[ 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 552800159 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2400.000 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f5b50-0x000f5b5f] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F5970 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE1BB7 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE1A53 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 001A13 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE1AC7 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE1B57 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE1B8F 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe1a53-0xbffe1ac6] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe1a52] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe1ac7-0xbffe1b56] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe1b57-0xbffe1b8e] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe1b8f-0xbffe1bb6] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001015] APIC: Switch to symmetric I/O mode setup [ 0.003267] x2apic enabled [ 0.004016] Switched APIC routing to physical x2apic. [ 0.005032] kvm-guest: setup PV IPIs [ 0.008329] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.009000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x22983777dd9, max_idle_ns: 440795300422 ns [ 0.009032] Calibrating delay loop (skipped) preset value.. 4800.00 BogoMIPS (lpj=2400000) [ 0.010023] pid_max: default: 32768 minimum: 301 [ 0.011203] LSM: Security Framework initializing [ 0.013085] Yama: becoming mindful. [ 0.014068] SELinux: Initializing. [ 0.015102] *** VALIDATE selinux *** [ 0.024744] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.030119] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.031187] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.033075] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.035090] *** VALIDATE tmpfs *** [ 0.037584] *** VALIDATE proc *** [ 0.038350] *** VALIDATE cgroup *** [ 0.039018] *** VALIDATE cgroup2 *** [ 0.040363] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.041000] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.041016] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.042060] Spectre V2 : User space: Vulnerable [ 0.043029] Speculative Store Bypass: Vulnerable [ 0.046587] debug: unmapping init [mem 0xffffffff9f259000-0xffffffff9f260fff] [ 0.049234] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.050903] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.051037] ... version: 2 [ 0.052023] ... bit width: 48 [ 0.053021] ... generic registers: 4 [ 0.054020] ... value mask: 0000ffffffffffff [ 0.055021] ... max period: 00007fffffffffff [ 0.056022] ... fixed-purpose events: 3 [ 0.057012] ... event mask: 000000070000000f [ 0.058325] rcu: Hierarchical SRCU implementation. [ 0.060569] smp: Bringing up secondary CPUs ... [ 0.061760] x86: Booting SMP configuration: [ 0.062045] .... node #0, CPUs: #1 #2 #3 [ 0.066482] smp: Brought up 1 node, 4 CPUs [ 0.068032] smpboot: Max logical packages: 1 [ 0.069018] smpboot: Total of 4 processors activated (19200.00 BogoMIPS) [ 0.246060] node 0 deferred pages initialised in 174ms [ 0.250167] devtmpfs: initialized [ 0.251282] x86/mm: Memory block size: 128MB [ 0.254137] gcov: version magic: 0x41383552 [ 0.260101] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.270000] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.278144] pinctrl core: initialized pinctrl subsystem [ 0.282329] [ 0.283015] ************************************************************* [ 0.287021] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.292031] ** ** [ 0.296026] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.297020] ** ** [ 0.301039] ** This means that this kernel is built to expose internal ** [ 0.306091] ** IOMMU data structures, which may compromise security on ** [ 0.308015] ** your system. ** [ 0.311026] ** ** [ 0.313018] ** If you see this message and you are not debugging the ** [ 0.315012] ** kernel, report this immediately to your vendor! ** [ 0.316016] ** ** [ 0.319046] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.321017] ************************************************************* [ 0.324708] NET: Registered protocol family 16 [ 0.326483] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.329137] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.331106] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.334154] cpuidle: using governor menu [ 0.336000] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.339702] PCI: Using configuration type 1 for base access [ 0.341171] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.349150] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.350062] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.352277] cryptd: max_cpu_qlen set to 1000 [ 0.354502] ACPI: Added _OSI(Module Device) [ 0.355000] ACPI: Added _OSI(Processor Device) [ 0.357545] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.359021] ACPI: Added _OSI(Processor Aggregator Device) [ 0.363770] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.370618] ACPI: Interpreter enabled [ 0.371000] ACPI: PM: (supports S0 S3 S4 S5) [ 0.372019] ACPI: Using IOAPIC for interrupt routing [ 0.373213] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.377472] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.386443] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.389064] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.392034] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.396121] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.402279] acpiphp: Slot [2] registered [ 0.403222] acpiphp: Slot [3] registered [ 0.405331] acpiphp: Slot [4] registered [ 0.407189] acpiphp: Slot [5] registered [ 0.408231] acpiphp: Slot [6] registered [ 0.410353] acpiphp: Slot [7] registered [ 0.412145] acpiphp: Slot [8] registered [ 0.413185] acpiphp: Slot [9] registered [ 0.415153] acpiphp: Slot [10] registered [ 0.416183] acpiphp: Slot [11] registered [ 0.417000] acpiphp: Slot [12] registered [ 0.417000] acpiphp: Slot [13] registered [ 0.419113] acpiphp: Slot [14] registered [ 0.420087] acpiphp: Slot [15] registered [ 0.422127] acpiphp: Slot [16] registered [ 0.423192] acpiphp: Slot [17] registered [ 0.425139] acpiphp: Slot [18] registered [ 0.426180] acpiphp: Slot [19] registered [ 0.428159] acpiphp: Slot [20] registered [ 0.430150] acpiphp: Slot [21] registered [ 0.431245] acpiphp: Slot [22] registered [ 0.433196] acpiphp: Slot [23] registered [ 0.435180] acpiphp: Slot [24] registered [ 0.436137] acpiphp: Slot [25] registered [ 0.438126] acpiphp: Slot [26] registered [ 0.439205] acpiphp: Slot [27] registered [ 0.441158] acpiphp: Slot [28] registered [ 0.442157] acpiphp: Slot [29] registered [ 0.444140] acpiphp: Slot [30] registered [ 0.445103] acpiphp: Slot [31] registered [ 0.447073] PCI host bridge to bus 0000:00 [ 0.448020] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.450027] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.452028] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.454040] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.457036] pci_bus 0000:00: root bus resource [mem 0x180000000-0x1ffffffff window] [ 0.459038] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.461245] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.464000] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.468000] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.475014] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.479066] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.481026] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.484044] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.487044] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.490657] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.494786] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.497074] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.499782] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.511023] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.520671] pci 0000:00:02.0: reg 0x20: [mem 0xfebf4000-0xfebf7fff 64bit pref] [ 0.524024] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.526000] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.533022] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.537017] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.548027] pci 0000:00:05.0: reg 0x20: [mem 0xfebf8000-0xfebfbfff 64bit pref] [ 0.557199] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.561019] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.568021] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.576019] pci 0000:00:06.0: reg 0x20: [mem 0xfebfc000-0xfebfffff 64bit pref] [ 0.585629] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.587400] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.588341] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.590332] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.592231] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.596272] iommu: Default domain type: Passthrough [ 0.603088] SCSI subsystem initialized [ 0.607187] ACPI: bus type USB registered [ 0.608000] usbcore: registered new interface driver usbfs [ 0.608000] usbcore: registered new interface driver hub [ 0.614135] usbcore: registered new device driver usb [ 0.615214] pps_core: LinuxPPS API ver. 1 registered [ 0.616000] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.617076] PTP clock support registered [ 0.620082] EDAC MC: Ver: 3.0.0 [ 0.624083] PCI: Using ACPI for IRQ routing [ 0.627261] NetLabel: Initializing [ 0.628018] NetLabel: domain hash size = 128 [ 0.630011] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.632139] NetLabel: unlabeled traffic allowed by default [ 0.634175] vgaarb: loaded [ 0.636611] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.639017] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.651623] clocksource: Switched to clocksource kvm-clock [ 0.818463] VFS: Disk quotas dquot_6.6.0 [ 0.819304] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.820789] *** VALIDATE ramfs *** [ 0.821358] *** VALIDATE hugetlbfs *** [ 0.822143] pnp: PnP ACPI init [ 0.825129] pnp: PnP ACPI: found 6 devices [ 0.861180] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.865109] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.868710] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.872053] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.875845] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.880891] pci_bus 0000:00: resource 8 [mem 0x180000000-0x1ffffffff window] [ 0.886365] NET: Registered protocol family 2 [ 0.891288] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.900230] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.906566] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.914256] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.920534] TCP: Hash tables configured (established 65536 bind 65536) [ 0.925974] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.933119] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.938711] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.945745] NET: Registered protocol family 1 [ 0.952209] RPC: Registered named UNIX socket transport module. [ 0.955335] RPC: Registered udp transport module. [ 0.957193] RPC: Registered tcp transport module. [ 0.959333] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.962128] NET: Registered protocol family 44 [ 0.964378] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.966804] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.969546] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.971594] PCI: CLS 0 bytes, default 64 [ 0.973909] Unpacking initramfs... [ 3.261959] debug: unmapping init [mem 0xffff8f8abcc64000-0xffff8f8abffcffff] [ 3.270696] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 3.275573] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 3.280203] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x22983777dd9, max_idle_ns: 440795300422 ns [ 3.933274] Initialise system trusted keyrings [ 3.934882] Key type blacklist registered [ 3.936569] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 3.945614] zbud: loaded [ 3.949101] *** VALIDATE nfs *** [ 3.950221] *** VALIDATE nfs4 *** [ 3.951746] pstore: using deflate compression [ 3.955768] Platform Keyring initialized [ 4.237075] NET: Registered protocol family 38 [ 4.241398] Key type asymmetric registered [ 4.245681] Asymmetric key parser 'x509' registered [ 4.250484] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 4.255926] io scheduler mq-deadline registered [ 4.261846] io scheduler kyber registered [ 4.270614] io scheduler bfq registered [ 4.282460] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 4.288599] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 4.291240] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 4.312647] ACPI: Power Button [PWRF] [ 4.484833] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 4.643085] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 4.805652] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 4.845106] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 4.899215] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 4.912553] Non-volatile memory driver v1.3 [ 4.914134] Linux agpgart interface v0.103 [ 4.960165] virtio_blk virtio1: [vda] 133248 512-byte logical blocks (68.2 MB/65.1 MiB) [ 4.963244] vda: detected capacity change from 0 to 68222976 [ 5.004616] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 5.007036] vdb: detected capacity change from 0 to 1073741824 [ 5.025645] libphy: Fixed MDIO Bus: probed [ 5.045436] usbcore: registered new interface driver usbserial_generic [ 5.052924] usbserial: USB Serial support registered for generic [ 5.058817] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 5.072980] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 5.075743] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 5.081815] mousedev: PS/2 mouse device common for all mice [ 5.091252] rtc_cmos 00:05: RTC can wake from S4 [ 5.095068] rtc_cmos 00:05: registered as rtc0 [ 5.103189] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 5.104490] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 5.109751] intel_pstate: CPU model not supported [ 5.117514] hid: raw HID events driver (C) Jiri Kosina [ 5.133835] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 5.146557] usbcore: registered new interface driver usbhid [ 5.156259] usbhid: USB HID core driver [ 5.159903] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 5.168731] drop_monitor: Initializing network drop monitor service [ 5.169936] Initializing XFRM netlink socket [ 5.170290] NET: Registered protocol family 10 [ 5.180440] Segment Routing with IPv6 [ 5.198606] NET: Registered protocol family 17 [ 5.201773] mpls_gso: MPLS GSO support [ 5.211950] RAS: Correctable Errors collector initialized. [ 5.214044] AVX version of gcm_enc/dec engaged. [ 5.217963] AES CTR mode by8 optimization enabled [ 5.368217] sched_clock: Marking stable (5368197340, 0)->(6416484657, -1048287317) [ 5.372637] registered taskstats version 1 [ 5.375763] Loading compiled-in X.509 certificates [ 5.379728] zswap: loaded using pool lzo/zbud [ 5.420279] Key type big_key registered [ 5.447149] Key type encrypted registered [ 5.448866] ima: No TPM chip found, activating TPM-bypass! [ 5.454246] ima: Allocated hash algorithm: sha1 [ 5.456378] ima: No architecture policies found [ 5.458130] evm: Initialising EVM extended attributes: [ 5.461076] evm: security.selinux [ 5.462836] evm: security.ima [ 5.463810] evm: security.capability [ 5.465601] evm: HMAC attrs: 0x1 [ 5.468392] rtc_cmos 00:05: setting system clock to 2025-10-10 19:42:29 UTC (1760125349) [ 5.476437] debug: unmapping init [mem 0xffffffffa0203000-0xffffffffa03fffff] [ 5.479729] debug: unmapping init [mem 0xffffffff9ef82000-0xffffffff9f258fff] [ 5.491084] Write protecting the kernel read-only data: 28672k [ 5.498473] debug: unmapping init [mem 0xffffffff9d603000-0xffffffff9d7fffff] [ 5.503756] debug: unmapping init [mem 0xffffffff9df14000-0xffffffff9dffffff] [ 5.558596] 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) [ 5.568276] systemd[1]: Detected virtualization kvm. [ 5.569966] systemd[1]: Detected architecture x86-64. [ 5.572794] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 5.620743] systemd[1]: No hostname configured. [ 5.625661] systemd[1]: Set hostname to . [ 5.627729] random: systemd: uninitialized urandom read (16 bytes read) [ 5.630185] systemd[1]: Initializing machine ID from random generator. [ 5.741046] random: ln: uninitialized urandom read (6 bytes read) [ 5.948598] random: systemd: uninitialized urandom read (16 bytes read) [ 5.953898] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ 5.974447] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ 5.990067] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Paths. [ OK ] Listening on udev Control Socket. [ OK ] Listening on Journal Socket. Starting Setup Virtual Console... [ OK ] Started Memstrack Anylazing Service. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Timers. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Local File Systems. [ OK ] Reached target Swap. [ OK ] Reached target Slices. Starting Create Volatile Files and Directories... Starting Apply Kernel Variables... [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Sockets. Starting Journal Service... [ OK ] Started Setup Virtual Console. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Apply Kernel Variables. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 7.849499] device-mapper: uevent: version 1.0.3 [ 7.856159] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 9.621122] virtio_net virtio0 ens2: renamed from eth0 [ 10.344563] scsi host0: ata_piix [ 10.747536] scsi host1: ata_piix [ 10.748884] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 10.751018] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 15.988061] random: crng init done [ 15.989363] random: 7 urandom warning(s) missed due to ratelimiting [ 18.676156] dracut-initqueue[584]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 21.120988] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target 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. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Timers. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ OK ] Stopped target Sockets. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Swap. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 23.587302] printk: systemd: 26 output lines suppressed due to ratelimiting [ 24.053162] SELinux: Disabled at runtime. [ 24.140407] 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) [ 24.153397] systemd[1]: Detected virtualization kvm. [ 24.155308] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 25.377814] systemd[1]: initrd-switch-root.service: Succeeded. [ 25.384060] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 25.389281] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 25.402705] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 25.409852] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 25.427896] systemd[1]: Starting Journal Service... Starting Journal Service... [ 25.457572] systemd[1]: Mounting Kernel Debug File System... Mounting Kernel Debug File System... [ OK ] Created slice system-getty.slice. Mounting Huge Pages File System... [ OK ] Listening on Process Core Dump Socket. [ OK ] Created slice system-serial\x2dgetty.slice. Starting Create list of required st…ce nodes for the current kernel... Starting Remount Root and Kernel File Systems... [ OK ] Listening on initctl Compatibility Named Pipe. Activating swap /dev/disk/by-label/SWAP... Starting Apply Kernel Variables... [ OK ] Created slice User and Session Slice. Mounting POSIX Message Queue File System... [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ 25.951889] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Listening on udev Control Socket. [ OK ] Reached target Slices. [ OK ] Listening on udev Kernel Socket. Starting udev Coldplug all Devices... [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. [ OK ] Stopped target Initrd Root File System. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Reached target rpc_pipefs.target. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Started Journal Service. [ OK ] Mounted Kernel Debug File System. [ OK ] Mounted Huge Pages 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 ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Reached target Swap. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started udev Coldplug all Devices. [ 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. [ 27.564501] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 28.959170] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 29.045037] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 30.302590] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 30.434526] EDAC sbridge: Ver: 1.1.2 [* ] A start job is running for Configur…-only root support (8s / no limit) [** ] A start job is running for Configur…-only root support (8s / no limit) [*** ] 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) [ *** ] A start job is running for Configur…only root support (10s / no limit) [ ***] A start job is running for Configur…only root support (10s / no limit) [ **] A start job is running for Configur…only root support (11s / no limit)[ 36.799110] Key type dns_resolver registered [ *] A start job is running for Configur…only root support (11s / no limit)[ 37.361711] NFS: Registering the id_resolver key type [ 37.364258] Key type id_resolver registered [ 37.366180] Key type id_legacy registered [ **] A start job is running for Configur…only root support (12s / 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 Create Volatile Files and Directories... Starting Load/Save Random Seed... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Load/Save Random Seed. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started dnf makecache --timer. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. Starting Login Service... [ OK ] Started irqbalance daemon. [ OK ] Reached target sshd-keygen.target. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Restore /run/initramfs on shutdown... [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... Starting OpenSSH server daemon... Starting Hostname Service... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started OpenSSH server daemon. [ OK ] Started Permit User Sessions. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Getty on tty1. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS0. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Notify NFS peers of a restart... Starting System Logging Service... Starting Crash recovery kernel arming... [ OK ] Started Notify NFS peers of a restart. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg412-client login: [ 89.457064] libcfs: loading out-of-tree module taints kernel. [ 89.596964] Key type ._llcrypt registered [ 89.598034] Key type .llcrypt registered [ 90.012726] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 90.022587] alg: No test for adler32 (adler32-zlib) [ 91.230056] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 91.732454] LNet: Added LNI 192.168.204.12@tcp [8/256/0/180] [ 93.407823] Key type lgssc registered [ 94.529471] Lustre: Echo OBD driver; http://www.lustre.org/ [ 206.388253] Lustre: Mounted lustre-client [ 209.955679] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 222.962422] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing check_logdir /tmp/testlogs/ [ 225.385851] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing yml_node [ 228.203367] Lustre: DEBUG MARKER: Client: 2.16.58.106 [ 230.099494] Lustre: DEBUG MARKER: MDS: 2.16.58.106 [ 231.884700] Lustre: DEBUG MARKER: OSS: 2.16.58.106 [ 231.910279] Lustre: lustre-OST0000-osc-ffff8f8b10cbf000: disconnect after 23s idle [ 233.000452] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Fri Oct 10 15:46:16 EDT 2025 [ 242.586566] Lustre: DEBUG MARKER: excepting tests: 102 106 115 32newtarball [ 243.426322] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 244.443727] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 254.696807] LustreError: 8978:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 254.721722] Lustre: Unmounted lustre-client [ 331.332794] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 333.589363] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 344.708489] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 378.131027] Lustre: DEBUG MARKER: == conf-sanity test 76a: set permanent params with lctl across mounts ========================================================== 15:48:41 (1760125721) [ 394.057605] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 396.075504] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 405.535922] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 411.171295] Lustre: Mounted lustre-client [ 419.797743] LustreError: 14213:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f8b098c8800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 419.823490] LustreError: 14213:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 419.828829] LustreError: 14213:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 419.878830] Lustre: Unmounted lustre-client [ 485.914234] Lustre: Mounted lustre-client [ 489.165157] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 502.208042] LustreError: 16768:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f8b098aa800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 502.226110] LustreError: 16768:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 502.229093] LustreError: 16768:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 502.278441] Lustre: Unmounted lustre-client [ 576.500654] Lustre: Mounted lustre-client [ 579.226324] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 586.993094] LustreError: 18994:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f8b0af18000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 586.997549] LustreError: 18994:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 587.004892] LustreError: 18994:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 587.007594] LustreError: 18994:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 587.036611] Lustre: Unmounted lustre-client [ 623.060831] Lustre: DEBUG MARKER: == conf-sanity test 76b: verify params log setup correctly ========================================================== 15:52:46 (1760125966) [ 668.669047] Lustre: Mounted lustre-client [ 671.607838] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 678.729158] LustreError: 22208:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f8b1134a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 678.735071] LustreError: 22208:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 678.742712] LustreError: 22208:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 678.745469] LustreError: 22208:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 678.779585] Lustre: Unmounted lustre-client [ 714.918472] Lustre: DEBUG MARKER: == conf-sanity test 76c: verify changelog_mask is applied with lctl set_param -P ========================================================== 15:54:18 (1760126058) [ 754.686709] Lustre: Mounted lustre-client [ 757.549262] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 770.020541] Lustre: lustre-MDT0000-mdc-ffff8f8b10571800: Connection to lustre-MDT0000 (at 192.168.204.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 775.140936] Lustre: lustre-MDT0001-mdc-ffff8f8b10571800: Connection to lustre-MDT0001 (at 192.168.204.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 780.256493] Lustre: lustre-OST0000-osc-ffff8f8b10571800: disconnect after 24s idle [ 780.262395] Lustre: Skipped 1 previous similar message [ 785.380808] LustreError: MGC192.168.204.112@tcp: Connection to MGS (at 192.168.204.112@tcp) was lost; in progress operations using this service will fail [ 785.395948] Lustre: Evicted from MGS (at 192.168.204.112@tcp) after server handle changed from 0x2f66a9991067c5e0 to 0x2f66a9991067c6d5 [ 785.401980] Lustre: MGC192.168.204.112@tcp: Connection restored to 192.168.204.112@tcp (at 192.168.204.112@tcp) [ 808.067351] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 810.979214] LustreError: lustre-MDT0000-mdc-ffff8f8b10571800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 810.987486] Lustre: lustre-MDT0000-mdc-ffff8f8b10571800: Connection restored to 192.168.204.112@tcp (at 192.168.204.112@tcp) [ 811.929970] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 3 sec [ 813.530287] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 816.268265] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 2 sec [ 817.035823] LustreError: 26543:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f8b10571800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 817.041538] LustreError: 26543:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 817.045565] LustreError: 26543:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 817.048210] LustreError: 26543:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 817.075749] Lustre: Unmounted lustre-client [ 852.759624] Lustre: DEBUG MARKER: == conf-sanity test 76d: verify llite.*.xattr_cache can be set by 'lctl set_param -P' correctly ========================================================== 15:56:36 (1760126196) [ 890.878898] Lustre: Mounted lustre-client [ 893.702356] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 903.355284] LustreError: 29605:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f8b0609b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 903.360380] LustreError: 29605:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 903.369838] LustreError: 29605:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 903.373675] LustreError: 29605:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 903.411825] Lustre: Unmounted lustre-client [ 940.825276] Lustre: DEBUG MARKER: == conf-sanity test 77: comma-separated MGS NIDs and failover node NIDs ========================================================== 15:58:04 (1760126284) [ 941.816761] Lustre: DEBUG MARKER: SKIP: conf-sanity test_77 mixed loopback and real device not working [ 942.571924] Lustre: DEBUG MARKER: == conf-sanity test 78: run resize2fs on MDT and OST filesystems ========================================================== 15:58:05 (1760126285) [ 965.425678] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 974.280883] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 974.507712] Lustre: Mounted lustre-client [ 974.509082] Lustre: Skipped 2 previous similar messages [ 975.121202] Lustre: DEBUG MARKER: create test files [ 986.296215] LustreError: 33236:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f8b098ac000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 986.301318] LustreError: 33236:0:(lov_obd.c:783:lov_cleanup()) Skipped 5 previous similar messages [ 986.334284] LustreError: 33236:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 986.336784] LustreError: 33236:0:(obd_class.h:479:obd_check_dev()) Skipped 27 previous similar messages [ 986.376286] Lustre: Unmounted lustre-client [ 986.377846] Lustre: Skipped 2 previous similar messages [ 997.423858] Key type lgssc unregistered [ 997.570397] LNet: 33586:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 997.573544] LNetError: 33586:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 997.582708] LNet: Removed LNI 192.168.204.12@tcp [ 997.923282] Key type .llcrypt unregistered [ 997.925241] Key type ._llcrypt unregistered [ 1007.223924] Key type ._llcrypt registered [ 1007.225426] Key type .llcrypt registered [ 1007.446914] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1007.453280] alg: No test for adler32 (adler32-zlib) [ 1008.335562] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 1008.437023] LNet: Added LNI 192.168.204.12@tcp [8/256/0/180] [ 1010.031717] Key type lgssc registered [ 1010.511108] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1024.631319] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1031.603897] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1032.828868] Lustre: Mounted lustre-client [ 1034.880343] Lustre: DEBUG MARKER: check files after expanding the MDT and OST filesystems [ 1036.999245] Lustre: DEBUG MARKER: create more files after expanding the MDT and OST filesystems [ 1038.395367] LustreError: 36046:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f8b10575800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1038.435757] LustreError: 36046:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 1038.474902] Lustre: Unmounted lustre-client [ 1048.962946] Key type lgssc unregistered [ 1049.101465] LNet: 36396:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1049.104804] LNetError: 36396:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1049.113384] LNet: Removed LNI 192.168.204.12@tcp [ 1049.432574] Key type .llcrypt unregistered [ 1049.434301] Key type ._llcrypt unregistered [ 1059.393630] Key type ._llcrypt registered [ 1059.395314] Key type .llcrypt registered [ 1059.566748] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1059.579974] alg: No test for adler32 (adler32-zlib) [ 1060.468426] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 1060.573701] LNet: Added LNI 192.168.204.12@tcp [8/256/0/180] [ 1062.175169] Key type lgssc registered [ 1062.673733] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1082.436495] hrtimer: interrupt took 4486308 ns [ 1090.328955] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1122.250448] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1124.088295] Lustre: Mounted lustre-client [ 1127.795786] Lustre: DEBUG MARKER: check files after shrinking the MDT and OST filesystems [ 1164.584808] LustreError: 38775:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f8b11b78000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1164.652576] LustreError: 38775:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 1164.730467] Lustre: Unmounted lustre-client [ 1181.152268] Key type lgssc unregistered [ 1181.377605] LNet: 39129:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1181.384083] LNetError: 39129:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1181.402614] LNet: Removed LNI 192.168.204.12@tcp [ 1181.946896] Key type .llcrypt unregistered [ 1181.949558] Key type ._llcrypt unregistered [ 1204.855534] Key type ._llcrypt registered [ 1204.859477] Key type .llcrypt registered [ 1205.220653] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1205.231381] alg: No test for adler32 (adler32-zlib) [ 1206.262169] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 1206.399698] LNet: Added LNI 192.168.204.12@tcp [8/256/0/180] [ 1208.063200] Key type lgssc registered [ 1208.957380] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1273.920557] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1276.830465] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1292.425024] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1336.185128] Lustre: DEBUG MARKER: == conf-sanity test 79: format MDT/OST without mgs option (should return errors) ========================================================== 16:04:38 (1760126678) [ 1464.778517] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1468.926689] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1492.246521] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1533.724562] Lustre: DEBUG MARKER: == conf-sanity test 80: mgc import reconnect race ======== 16:07:56 (1760126876) [ 1566.945825] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1571.209582] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1590.437782] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1643.091381] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 1695.259203] Lustre: DEBUG MARKER: == conf-sanity test 81: sparse OST indexing ============== 16:10:37 (1760127037) [ 1696.718773] Lustre: DEBUG MARKER: SKIP: conf-sanity test_81 needs >= 3 OSTs [ 1699.009572] Lustre: DEBUG MARKER: == conf-sanity test 82a: specify OSTs for file (succeed) or directory (succeed) ========================================================== 16:10:41 (1760127041) [ 1700.624629] Lustre: DEBUG MARKER: SKIP: conf-sanity test_82a needs >= 3 OSTs [ 1702.702338] Lustre: DEBUG MARKER: == conf-sanity test 82b: specify OSTs for file with --pool and --ost-list options ========================================================== 16:10:45 (1760127045) [ 1704.293648] Lustre: DEBUG MARKER: SKIP: conf-sanity test_82b needs >= 4 OSTs [ 1706.052514] Lustre: DEBUG MARKER: == conf-sanity test 83: ENOSPACE on OST doesn't cause message VFS: Busy inodes after unmount ... ========================================================== 16:10:48 (1760127048) [ 1707.934847] Lustre: DEBUG MARKER: mount the OST /dev/mapper/ost1_flakey as a ldiskfs filesystem [ 1715.787360] Lustre: DEBUG MARKER: run llverfs in partial mode on the OST ldiskfs /mnt/lustre-ost1 [ 1723.040597] Lustre: DEBUG MARKER: unmount the OST /dev/mapper/ost1_flakey [ 1842.867809] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1846.230503] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1864.485044] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1909.735296] Lustre: DEBUG MARKER: == conf-sanity test 84: check recovery_hard_time ========= 16:14:12 (1760127252) [ 1935.450185] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1938.456529] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1953.725918] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1985.646255] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 1998.419176] Lustre: Mounted lustre-client [ 2015.148464] Lustre: DEBUG MARKER: local REPLAY BARRIER on lustre-MDT0000 [ 2044.905790] Lustre: lustre-MDT0000-mdc-ffff8f8b0a75a800: Connection to lustre-MDT0000 (at 192.168.204.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2044.918592] Lustre: Skipped 1 previous similar message [ 2060.256833] Lustre: 39744:0:(client.c:2468:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1760127388/real 1760127388] req@0000000082eb56b9 x1845626457948544/t0(0) o400->MGC192.168.204.112@tcp@192.168.204.112@tcp:26/25 lens 224/224 e 0 to 1 dl 1760127404 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2060.278324] LustreError: MGC192.168.204.112@tcp: Connection to MGS (at 192.168.204.112@tcp) was lost; in progress operations using this service will fail [ 2071.611284] Lustre: Evicted from MGS (at 192.168.204.112@tcp) after server handle changed from 0x90850ac536e17f50 to 0x90850ac536e24119 [ 2071.617630] Lustre: MGC192.168.204.112@tcp: Connection restored to 192.168.204.112@tcp (at 192.168.204.112@tcp) [ 2073.438298] LustreError: 39741:0:(client.c:3408:ptlrpc_replay_interpret()) @@@ status 301, old was 0 req@0000000075070003 x1845626457305088/t8589934597(8589934597) o101->lustre-MDT0000-mdc-ffff8f8b0a75a800@192.168.204.112@tcp:12/10 lens 592/608 e 0 to 0 dl 1760127433 ref 2 fl Interpret:RQU/604/0 rc 301/301 job:'' uid:0 gid:0 projid:0 [ 2074.094655] LustreError: 39741:0:(client.c:3408:ptlrpc_replay_interpret()) @@@ status 301, old was 0 req@00000000f6f8436b x1845626457305728/t8589934599(8589934599) o101->lustre-MDT0000-mdc-ffff8f8b0a75a800@192.168.204.112@tcp:12/10 lens 592/608 e 0 to 0 dl 1760127433 ref 2 fl Interpret:RQU/604/0 rc 301/301 job:'' uid:0 gid:0 projid:0 [ 2075.487289] LustreError: 39741:0:(client.c:3408:ptlrpc_replay_interpret()) @@@ status 301, old was 0 req@0000000080ae3053 x1845626457307008/t8589934603(8589934603) o101->lustre-MDT0000-mdc-ffff8f8b0a75a800@192.168.204.112@tcp:12/10 lens 592/608 e 0 to 0 dl 1760127435 ref 2 fl Interpret:RQU/604/0 rc 301/301 job:'' uid:0 gid:0 projid:0 [ 2075.497521] LustreError: 39741:0:(client.c:3408:ptlrpc_replay_interpret()) Skipped 1 previous similar message [ 2078.147712] LustreError: 39741:0:(client.c:3408:ptlrpc_replay_interpret()) @@@ status 301, old was 0 req@000000003ace9976 x1845626457309568/t8589934611(8589934611) o101->lustre-MDT0000-mdc-ffff8f8b0a75a800@192.168.204.112@tcp:12/10 lens 592/608 e 0 to 0 dl 1760127437 ref 2 fl Interpret:RQU/604/0 rc 301/301 job:'' uid:0 gid:0 projid:0 [ 2078.195877] LustreError: 39741:0:(client.c:3408:ptlrpc_replay_interpret()) Skipped 3 previous similar messages [ 2082.657970] LustreError: 39741:0:(client.c:3408:ptlrpc_replay_interpret()) @@@ status 301, old was 0 req@00000000b4b36578 x1845626457314048/t8589934625(8589934625) o101->lustre-MDT0000-mdc-ffff8f8b0a75a800@192.168.204.112@tcp:12/10 lens 592/608 e 0 to 0 dl 1760127442 ref 2 fl Interpret:RQU/604/0 rc 301/301 job:'' uid:0 gid:0 projid:0 [ 2082.671223] LustreError: 39741:0:(client.c:3408:ptlrpc_replay_interpret()) Skipped 6 previous similar messages [ 2091.007451] LustreError: 39741:0:(client.c:3408:ptlrpc_replay_interpret()) @@@ status 301, old was 0 req@0000000039b36dbb x1845626457322368/t8589934651(8589934651) o101->lustre-MDT0000-mdc-ffff8f8b0a75a800@192.168.204.112@tcp:12/10 lens 592/608 e 0 to 0 dl 1760127450 ref 2 fl Interpret:RQU/604/0 rc 301/301 job:'' uid:0 gid:0 projid:0 [ 2091.056210] LustreError: 39741:0:(client.c:3408:ptlrpc_replay_interpret()) Skipped 12 previous similar messages [ 2105.573843] LustreError: 39741:0:(mdc_request.c:667:mdc_replay_open()) @@@ cannot properly replay without open data req@00000000dfd48011 x1845626457337088/t8589934697(8589934697) o101->lustre-MDT0000-mdc-ffff8f8b0a75a800@192.168.204.112@tcp:12/10 lens 592/608 e 0 to 0 dl 1760127465 ref 1 fl Interpret:RMQU/604/0 rc 301/301 job:'' uid:0 gid:0 projid:0 [ 2107.537566] LustreError: 39741:0:(client.c:3408:ptlrpc_replay_interpret()) @@@ status 301, old was 0 req@00000000f3e35571 x1845626457339008/t8589934703(8589934703) o101->lustre-MDT0000-mdc-ffff8f8b0a75a800@192.168.204.112@tcp:12/10 lens 592/608 e 0 to 0 dl 1760127467 ref 2 fl Interpret:RQU/604/0 rc 301/301 job:'' uid:0 gid:0 projid:0 [ 2107.553514] LustreError: 39741:0:(client.c:3408:ptlrpc_replay_interpret()) Skipped 25 previous similar messages [ 2130.648228] LustreError: lustre-MDT0000-mdc-ffff8f8b0a75a800: operation mds_close to node 192.168.204.112@tcp failed: rc = -107 [ 2130.728822] Lustre: lustre-MDT0000-mdc-ffff8f8b098c9000: Connection restored to 192.168.204.112@tcp (at 192.168.204.112@tcp) [ 2132.970030] LustreError: lustre-MDT0000-mdc-ffff8f8b0a75a800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 2133.142652] LustreError: 60602:0:(file.c:6101:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -5 [ 2133.569570] Lustre: lustre-MDT0000-mdc-ffff8f8b0a75a800: Connection restored to 192.168.204.112@tcp (at 192.168.204.112@tcp) [ 2139.165837] LustreError: 60703:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f8b0a75a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2139.173707] LustreError: 60703:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 2139.208763] Lustre: Unmounted lustre-client [ 2140.312230] LustreError: 60722:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f8b098c9000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2140.324816] LustreError: 60722:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 2140.343725] LustreError: 60722:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 2140.356269] LustreError: 60722:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 2140.415804] Lustre: Unmounted lustre-client [ 2187.044813] Lustre: DEBUG MARKER: == conf-sanity test 85: osd_ost init: fail ea_fid_set ==== 16:18:49 (1760127529) [ 2250.113175] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2260.299704] Lustre: DEBUG MARKER: == conf-sanity test 86: Replacing mkfs.lustre -G option == 16:20:02 (1760127602) [ 2293.730598] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2398.264385] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2402.010911] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2419.774514] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2463.154600] Lustre: DEBUG MARKER: == conf-sanity test 87: check if MDT inode can hold EAs with N stripes properly ========================================================== 16:23:25 (1760127805) [ 2466.395045] Key type lgssc unregistered [ 2466.715064] LNet: 67903:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2466.719698] LNetError: 67903:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2467.756872] LNet: Removed LNI 192.168.204.12@tcp [ 2468.611361] Key type .llcrypt unregistered [ 2468.619508] Key type ._llcrypt unregistered [ 2490.843188] Key type ._llcrypt registered [ 2490.845252] Key type .llcrypt registered [ 2491.282117] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2491.298120] alg: No test for adler32 (adler32-zlib) [ 2492.315487] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 2492.533152] LNet: Added LNI 192.168.204.12@tcp [8/256/0/180] [ 2494.207671] Key type lgssc registered [ 2495.289602] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2568.934362] Lustre: Mounted lustre-client [ 2569.579625] LustreError: 71139:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f8b0acfe000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2569.591419] LustreError: 71139:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2569.626303] Lustre: Unmounted lustre-client [ 2593.517471] Lustre: DEBUG MARKER: == conf-sanity test 88: check the default mount options can be overridden ========================================================== 16:25:36 (1760127936) [ 2615.268982] Lustre: DEBUG MARKER: == conf-sanity test 89: check tunefs --param and --erase-param{s} options ========================================================== 16:25:58 (1760127958) [ 2709.622383] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL failover.node=192.0.2.254@tcp0,mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2712.821668] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL failover.node=192.0.2.254@tcp0,mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2730.236580] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount (FULL|IDLE) failover.node=192.0.2.254@tcp0,osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2767.931226] Lustre: DEBUG MARKER: == conf-sanity test 90a: check max_mod_rpcs_in_flight is enforced ========================================================== 16:28:30 (1760128110) [ 2793.863406] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2797.000401] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2815.376508] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2821.716863] Lustre: Mounted lustre-client [ 2841.567228] Lustre: 79406:0:(client.c:2468:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1760128169/real 1760128169] req@00000000e389b775 x1845627805788288/t0(0) o36->lustre-MDT0000-mdc-ffff8f8b11cac000@192.168.204.112@tcp:12/10 lens 488/512 e 0 to 1 dl 1760128185 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2841.568531] Lustre: lustre-MDT0000-mdc-ffff8f8b11cac000: Connection to lustre-MDT0000 (at 192.168.204.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2841.633598] Lustre: 79406:0:(client.c:2468:ptlrpc_expire_one_request()) Skipped 3 previous similar messages [ 2841.686327] Lustre: lustre-MDT0000-mdc-ffff8f8b11cac000: Connection restored to 192.168.204.112@tcp (at 192.168.204.112@tcp) [ 2858.976021] Lustre: 79447:0:(client.c:2468:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1760128187/real 1760128187] req@0000000009180197 x1845627805798528/t0(0) o36->lustre-MDT0000-mdc-ffff8f8b11cac000@192.168.204.112@tcp:12/10 lens 488/512 e 0 to 1 dl 1760128203 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2858.977682] Lustre: lustre-MDT0000-mdc-ffff8f8b11cac000: Connection to lustre-MDT0000 (at 192.168.204.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2858.991400] Lustre: 79447:0:(client.c:2468:ptlrpc_expire_one_request()) Skipped 5 previous similar messages [ 2859.020579] Lustre: lustre-MDT0000-mdc-ffff8f8b11cac000: Connection restored to 192.168.204.112@tcp (at 192.168.204.112@tcp) [ 2860.385991] LustreError: 79490:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f8b11cac000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2860.395167] LustreError: 79490:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 2860.416921] LustreError: 79490:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2860.423832] LustreError: 79490:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 2860.499270] Lustre: Unmounted lustre-client [ 2890.730879] Key type lgssc unregistered [ 2890.969882] LNet: 79892:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2890.980621] LNetError: 79892:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2892.012477] LNet: Removed LNI 192.168.204.12@tcp [ 2892.557272] Key type .llcrypt unregistered [ 2892.561186] Key type ._llcrypt unregistered [ 2904.010642] Key type ._llcrypt registered [ 2904.013195] Key type .llcrypt registered [ 2905.141297] Lustre: DEBUG MARKER: == conf-sanity test 90b: check max_mod_rpcs_in_flight is enforced after update ========================================================== 16:30:47 (1760128247) [ 2907.117826] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2907.133733] alg: No test for adler32 (adler32-zlib) [ 2908.180567] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 2908.408693] LNet: Added LNI 192.168.204.12@tcp [8/256/0/180] [ 2910.071164] Key type lgssc registered [ 2911.156757] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2951.931643] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2954.950715] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2972.914505] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2978.870418] Lustre: Mounted lustre-client [ 3002.335832] Lustre: 83013:0:(client.c:2468:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1760128330/real 1760128330] req@0000000082eb56b9 x1845628241978496/t0(0) o36->lustre-MDT0000-mdc-ffff8f8b09999000@192.168.204.112@tcp:12/10 lens 488/512 e 0 to 1 dl 1760128346 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3002.349567] Lustre: lustre-MDT0000-mdc-ffff8f8b09999000: Connection to lustre-MDT0000 (at 192.168.204.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3002.374610] Lustre: lustre-MDT0000-mdc-ffff8f8b09999000: Connection restored to 192.168.204.112@tcp (at 192.168.204.112@tcp) [ 3020.767284] Lustre: 83060:0:(client.c:2468:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1760128348/real 1760128348] req@000000005918d4a6 x1845628241991168/t0(0) o36->lustre-MDT0001-mdc-ffff8f8b09999000@192.168.204.112@tcp:12/10 lens 488/512 e 0 to 1 dl 1760128364 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3020.767284] Lustre: 83059:0:(client.c:2468:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1760128348/real 1760128348] req@00000000bdaa9301 x1845628241992192/t0(0) o36->lustre-MDT0001-mdc-ffff8f8b09999000@192.168.204.112@tcp:12/10 lens 488/512 e 0 to 1 dl 1760128364 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3020.767398] Lustre: lustre-MDT0001-mdc-ffff8f8b09999000: Connection to lustre-MDT0001 (at 192.168.204.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3020.796053] Lustre: 83059:0:(client.c:2468:ptlrpc_expire_one_request()) Skipped 2 previous similar messages [ 3020.857556] Lustre: lustre-MDT0001-mdc-ffff8f8b09999000: Connection restored to 192.168.204.112@tcp (at 192.168.204.112@tcp) [ 3038.687166] Lustre: 83095:0:(client.c:2468:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1760128366/real 1760128366] req@000000009205060e x1845628241998848/t0(0) o36->lustre-MDT0001-mdc-ffff8f8b09999000@192.168.204.112@tcp:12/10 lens 488/512 e 0 to 1 dl 1760128382 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3038.688218] Lustre: lustre-MDT0001-mdc-ffff8f8b09999000: Connection to lustre-MDT0001 (at 192.168.204.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3038.706781] Lustre: 83095:0:(client.c:2468:ptlrpc_expire_one_request()) Skipped 3 previous similar messages [ 3038.744425] Lustre: lustre-MDT0001-mdc-ffff8f8b09999000: Connection restored to 192.168.204.112@tcp (at 192.168.204.112@tcp) [ 3052.758477] LustreError: 83152:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f8b09999000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3052.794934] LustreError: 83152:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3052.851832] Lustre: Unmounted lustre-client [ 3054.067210] Lustre: Mounted lustre-client [ 3072.479266] Lustre: 83250:0:(client.c:2468:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1760128400/real 1760128400] req@00000000e133f5f0 x1845628242041856/t0(0) o36->lustre-MDT0000-mdc-ffff8f8b048f3800@192.168.204.112@tcp:12/10 lens 504/512 e 0 to 1 dl 1760128416 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3072.479266] Lustre: 83271:0:(client.c:2468:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1760128400/real 1760128400] req@000000008ae677d4 x1845628242041600/t0(0) o36->lustre-MDT0000-mdc-ffff8f8b048f3800@192.168.204.112@tcp:12/10 lens 488/512 e 0 to 1 dl 1760128416 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3072.479288] Lustre: 83271:0:(client.c:2468:ptlrpc_expire_one_request()) Skipped 1 previous similar message [ 3072.479799] Lustre: lustre-MDT0000-mdc-ffff8f8b048f3800: Connection to lustre-MDT0000 (at 192.168.204.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3072.495473] Lustre: 83250:0:(client.c:2468:ptlrpc_expire_one_request()) Skipped 14 previous similar messages [ 3072.507538] Lustre: lustre-MDT0000-mdc-ffff8f8b048f3800: Connection restored to 192.168.204.112@tcp (at 192.168.204.112@tcp) [ 3089.887223] Lustre: 83314:0:(client.c:2468:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1760128417/real 1760128417] req@00000000ee63dda6 x1845628242058624/t0(0) o36->lustre-MDT0000-mdc-ffff8f8b048f3800@192.168.204.112@tcp:12/10 lens 488/512 e 0 to 1 dl 1760128433 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3089.887296] Lustre: lustre-MDT0000-mdc-ffff8f8b048f3800: Connection to lustre-MDT0000 (at 192.168.204.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3089.900198] Lustre: 83314:0:(client.c:2468:ptlrpc_expire_one_request()) Skipped 3 previous similar messages [ 3089.922921] Lustre: lustre-MDT0000-mdc-ffff8f8b048f3800: Connection restored to 192.168.204.112@tcp (at 192.168.204.112@tcp) [ 3106.506315] LustreError: 83367:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f8b048f3800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3106.538564] LustreError: 83367:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3106.546323] LustreError: 83367:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 3106.609661] Lustre: Unmounted lustre-client [ 3129.952930] Key type lgssc unregistered [ 3130.132982] LNet: 83772:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3130.139283] LNetError: 83772:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3130.159473] LNet: Removed LNI 192.168.204.12@tcp [ 3130.671926] Key type .llcrypt unregistered [ 3130.673376] Key type ._llcrypt unregistered [ 3139.849868] Key type ._llcrypt registered [ 3139.851385] Key type .llcrypt registered [ 3140.742779] Lustre: DEBUG MARKER: == conf-sanity test 90c: check max_mod_rpcs_in_flight update limits ========================================================== 16:34:43 (1760128483) [ 3142.334836] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3142.369671] alg: No test for adler32 (adler32-zlib) [ 3143.404516] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 3143.557330] LNet: Added LNI 192.168.204.12@tcp [8/256/0/180] [ 3145.183258] Key type lgssc registered [ 3145.946527] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3175.280201] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3177.235629] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3190.165804] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3191.731412] Lustre: Mounted lustre-client [ 3199.487181] LustreError: 86893:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f8b1182a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3199.511836] LustreError: 86893:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3199.566197] Lustre: Unmounted lustre-client [ 3200.693576] Lustre: Mounted lustre-client [ 3200.837273] LustreError: 86955:0:(genops.c:2257:obd_set_max_mod_rpcs_in_flight()) lustre-MDT0000-mdc-ffff8f8b0a42a800: can't set max_mod_rpcs_in_flight=9 higher than mdt.*.max_mod_rpcs_in_flight=8 returned by the MDT server at connection. [ 3203.147516] LustreError: 86990:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f8b0a42a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3203.171496] LustreError: 86990:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3203.176857] LustreError: 86990:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 3203.264479] Lustre: Unmounted lustre-client [ 3203.644937] Lustre: Mounted lustre-client [ 3206.526658] LustreError: 87101:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f8b0a42a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3206.542197] LustreError: 87101:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3206.545672] LustreError: 87101:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 3206.589872] Lustre: Unmounted lustre-client [ 3229.541238] Key type lgssc unregistered [ 3229.716074] LNet: 87504:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3229.724117] LNetError: 87504:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3229.744349] LNet: Removed LNI 192.168.204.12@tcp [ 3230.205890] Key type .llcrypt unregistered [ 3230.207728] Key type ._llcrypt unregistered [ 3238.837724] Key type ._llcrypt registered [ 3238.839137] Key type .llcrypt registered [ 3239.730311] Lustre: DEBUG MARKER: == conf-sanity test 90d: check one close RPC is allowed above max_mod_rpcs_in_flight ========================================================== 16:36:22 (1760128582) [ 3241.080860] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3241.093690] alg: No test for adler32 (adler32-zlib) [ 3242.068346] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 3242.227747] LNet: Added LNI 192.168.204.12@tcp [8/256/0/180] [ 3243.890722] Key type lgssc registered [ 3244.577341] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3270.563194] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3272.739855] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3282.871732] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3284.249281] Lustre: Mounted lustre-client [ 3312.607202] Lustre: 90612:0:(client.c:2468:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1760128640/real 1760128640] req@000000008f57cd1f x1845628592212480/t0(0) o36->lustre-MDT0000-mdc-ffff8f8b0a5a0000@192.168.204.112@tcp:12/10 lens 488/512 e 0 to 1 dl 1760128656 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3312.607202] Lustre: 90606:0:(client.c:2468:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1760128640/real 1760128640] req@0000000060e0b30e x1845628592211072/t0(0) o36->lustre-MDT0000-mdc-ffff8f8b0a5a0000@192.168.204.112@tcp:12/10 lens 488/512 e 0 to 1 dl 1760128656 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3312.607294] Lustre: lustre-MDT0000-mdc-ffff8f8b0a5a0000: Connection to lustre-MDT0000 (at 192.168.204.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3312.623753] Lustre: 90612:0:(client.c:2468:ptlrpc_expire_one_request()) Skipped 2 previous similar messages [ 3312.677571] Lustre: lustre-MDT0000-mdc-ffff8f8b0a5a0000: Connection restored to 192.168.204.112@tcp (at 192.168.204.112@tcp) [ 3313.729338] LustreError: 90654:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f8b0a5a0000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3313.751222] LustreError: 90654:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3313.787666] Lustre: Unmounted lustre-client [ 3336.443369] Key type lgssc unregistered [ 3336.655322] LNet: 91056:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3336.662101] LNetError: 91056:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3336.678800] LNet: Removed LNI 192.168.204.12@tcp [ 3337.154389] Key type .llcrypt unregistered [ 3337.156399] Key type ._llcrypt unregistered [ 3344.860045] Key type ._llcrypt registered [ 3344.861596] Key type .llcrypt registered [ 3345.645912] Lustre: DEBUG MARKER: == conf-sanity test 91: evict-by-nid support ============= 16:38:08 (1760128688) [ 3346.953600] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3347.025692] alg: No test for adler32 (adler32-zlib) [ 3347.984762] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 3348.109608] LNet: Added LNI 192.168.204.12@tcp [8/256/0/180] [ 3349.719343] Key type lgssc registered [ 3350.299910] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3379.820596] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3381.955698] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3393.413574] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3394.880127] Lustre: Mounted lustre-client [ 3402.852534] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3413.099155] LustreError: 94192:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3413.119831] Lustre: Unmounted lustre-client [ 3460.661986] Key type lgssc unregistered [ 3460.811232] LNet: 94669:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3460.815980] LNetError: 94669:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3460.829517] LNet: Removed LNI 192.168.204.12@tcp [ 3461.149967] Key type .llcrypt unregistered [ 3461.151545] Key type ._llcrypt unregistered [ 3468.273724] Key type ._llcrypt registered [ 3468.275891] Key type .llcrypt registered [ 3468.921369] Lustre: DEBUG MARKER: == conf-sanity test 92: ldev returns MGS NID correctly in command substitution ========================================================== 16:40:12 (1760128812) [ 3474.195169] Lustre: DEBUG MARKER: == conf-sanity test 93: register mulitple MDT at the same time ========================================================== 16:40:17 (1760128817) [ 3475.289385] Lustre: DEBUG MARKER: SKIP: conf-sanity test_93 needs >= 3 MDTs [ 3476.548569] Lustre: DEBUG MARKER: == conf-sanity test 94: ldev outputs correct labels for file system name query ========================================================== 16:40:19 (1760128819) [ 3481.521217] Lustre: DEBUG MARKER: == conf-sanity test 95: ldev should only allow one label filter ========================================================== 16:40:24 (1760128824) [ 3486.302584] Lustre: DEBUG MARKER: == conf-sanity test 96: ldev returns hostname and backend fs correctly in command sub ========================================================== 16:40:29 (1760128829) [ 3490.585346] Lustre: DEBUG MARKER: == conf-sanity test 97: ldev returns correct ouput when querying based on role ========================================================== 16:40:33 (1760128833) [ 3495.184199] Lustre: DEBUG MARKER: == conf-sanity test 98: Buffer-overflow check while parsing mount_opts ========================================================== 16:40:38 (1760128838) [ 3496.337619] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3496.351868] alg: No test for adler32 (adler32-zlib) [ 3497.280735] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 3497.417805] LNet: Added LNI 192.168.204.12@tcp [8/256/0/180] [ 3499.039172] Key type lgssc registered [ 3499.691915] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3526.505355] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3528.293481] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3538.194101] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3539.591627] Lustre: Mounted lustre-client [ 3551.263469] LustreError: 101989:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f8b11cae800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3551.276368] LustreError: 101989:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3551.318050] Lustre: Unmounted lustre-client [ 3571.230711] Key type lgssc unregistered [ 3571.369783] LNet: 102391:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3571.373436] LNetError: 102391:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3571.384797] LNet: Removed LNI 192.168.204.12@tcp [ 3571.669348] Key type .llcrypt unregistered [ 3571.670975] Key type ._llcrypt unregistered [ 3578.884371] Key type ._llcrypt registered [ 3578.886154] Key type .llcrypt registered [ 3579.607853] Lustre: DEBUG MARKER: == conf-sanity test 99: Adding meta_bg option ============ 16:42:02 (1760128922) [ 3580.799922] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3580.852259] alg: No test for adler32 (adler32-zlib) [ 3581.785745] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 3581.913334] LNet: Added LNI 192.168.204.12@tcp [8/256/0/180] [ 3583.527335] Key type lgssc registered [ 3584.066959] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3601.083376] Lustre: DEBUG MARKER: == conf-sanity test 100: check lshowmount lists MGS, MDT, OST and 0@lo ========================================================== 16:42:24 (1760128944) [ 3658.662959] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3660.828345] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3673.932671] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3675.244658] Lustre: Mounted lustre-client [ 3677.492779] LustreError: 108198:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f8b11e60800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3677.508486] LustreError: 108198:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3677.567931] Lustre: Unmounted lustre-client [ 3700.063297] Key type lgssc unregistered [ 3700.256805] LNet: 108600:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3700.264599] LNetError: 108600:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3700.291307] LNet: Removed LNI 192.168.204.12@tcp [ 3700.813824] Key type .llcrypt unregistered [ 3700.815368] Key type ._llcrypt unregistered [ 3710.510571] Key type ._llcrypt registered [ 3710.514938] Key type .llcrypt registered [ 3711.488678] Lustre: DEBUG MARKER: == conf-sanity test 101a: Race MDT->OST reconnection with create ========================================================== 16:44:14 (1760129054) [ 3713.002988] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3713.024849] alg: No test for adler32 (adler32-zlib) [ 3714.023465] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 3714.172727] LNet: Added LNI 192.168.204.12@tcp [8/256/0/180] [ 3715.799260] Key type lgssc registered [ 3716.567101] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3746.250065] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3748.338382] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3758.293814] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3760.660748] Lustre: Mounted lustre-client [ 4768.179762] LustreError: 120496:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f8b098c9000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4768.187497] LustreError: 120496:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4768.257131] Lustre: Unmounted lustre-client [ 4793.366879] Key type lgssc unregistered [ 4793.516572] LNet: 120901:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4793.520694] LNetError: 120901:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4793.531625] LNet: Removed LNI 192.168.204.12@tcp [ 4793.840647] Key type .llcrypt unregistered [ 4793.842907] Key type ._llcrypt unregistered [ 4801.114384] Key type ._llcrypt registered [ 4801.116164] Key type .llcrypt registered [ 4801.865621] Lustre: DEBUG MARKER: == conf-sanity test 101b: Race events DISCONNECT and ACTIVE in osp ========================================================== 17:02:25 (1760130145) [ 4803.173421] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4803.193467] alg: No test for adler32 (adler32-zlib) [ 4804.162737] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 4804.328189] LNet: Added LNI 192.168.204.12@tcp [8/256/0/180] [ 4805.943210] Key type lgssc registered [ 4806.522464] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4830.723116] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4832.578936] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4841.810200] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4848.128456] Lustre: Mounted lustre-client [ 4853.220410] Lustre: lustre-OST0000-osc-ffff8f8b10575000: Connection to lustre-OST0000 (at 192.168.204.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4892.488144] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4894.452339] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 1 sec [ 4896.359842] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8f8b10575000.ost_server_uuid 50 [ 4897.156871] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8f8b10575000.ost_server_uuid in FULL state after 0 sec [ 4897.825969] LustreError: 125048:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f8b10575000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4897.835926] LustreError: 125048:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4897.868167] Lustre: Unmounted lustre-client [ 4917.366474] Key type lgssc unregistered [ 4917.541739] LNet: 125450:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4917.544365] LNetError: 125450:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4917.556732] LNet: Removed LNI 192.168.204.12@tcp [ 4917.904788] Key type .llcrypt unregistered [ 4917.906932] Key type ._llcrypt unregistered [ 4924.658776] Key type ._llcrypt registered [ 4924.660536] Key type .llcrypt registered [ 4925.368178] Lustre: DEBUG MARKER: SKIP: conf-sanity test_102 skipping excluded test 102 [ 4926.267520] Lustre: DEBUG MARKER: == conf-sanity test 103: rename filesystem name ========== 17:04:29 (1760130269) [ 4929.686956] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4929.755768] alg: No test for adler32 (adler32-zlib) [ 4930.729177] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 4930.867617] LNet: Added LNI 192.168.204.12@tcp [8/256/0/180] [ 4932.479323] Key type lgssc registered [ 4933.154108] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4973.049492] Lustre: Mounted lustre-client [ 4975.787908] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 4992.133754] LustreError: 128931:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f8b04d48800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4992.149817] LustreError: 128931:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 4992.185256] Lustre: Unmounted lustre-client [ 5062.134961] Lustre: Mounted mylustre-client [ 5064.875350] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 5075.379991] LustreError: 131252:0:(lov_obd.c:783:lov_cleanup()) mylustre-clilov-ffff8f8b09848000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5075.385375] LustreError: 131252:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 5075.394272] LustreError: 131252:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 5075.397104] LustreError: 131252:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 5075.432213] Lustre: Unmounted mylustre-client [ 5142.517109] Lustre: Mounted tfs-client [ 5145.152336] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 5152.964957] LustreError: 133557:0:(lov_obd.c:783:lov_cleanup()) tfs-clilov-ffff8f8b0a734000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5152.970851] LustreError: 133557:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 5152.978695] LustreError: 133557:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 5152.981749] LustreError: 133557:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 5153.015052] Lustre: Unmounted tfs-client [ 5213.781159] Lustre: Mounted lustre-client [ 5216.163917] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 5224.750735] Lustre: DEBUG MARKER: == conf-sanity test 104a: Make sure user defined options are reflected in mount ========================================================== 17:09:28 (1760130568) [ 5225.456158] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5235.751795] LustreError: 136426:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 5235.754559] LustreError: 136426:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 5235.770407] Lustre: Unmounted lustre-client [ 5315.432207] Lustre: client wants to enable acl, but mdt not! [ 5315.445759] Lustre: Mounted lustre-client [ 5317.658483] Lustre: DEBUG MARKER: == conf-sanity test 104b: Mount uses last flock argument ========================================================== 17:11:01 (1760130661) [ 5317.788633] Lustre: client wants to enable acl, but mdt not! [ 5318.155838] LustreError: 139831:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f8b3bcdf000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5318.160220] LustreError: 139831:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 5318.165457] LustreError: 139831:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 5318.167382] LustreError: 139831:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 5318.190163] Lustre: Unmounted lustre-client [ 5321.297145] Lustre: DEBUG MARKER: == conf-sanity test 105: check file creation for ro and rw bind mnt pt ========================================================== 17:11:04 (1760130664) [ 5321.578981] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5321.581043] Lustre: Skipped 3 previous similar messages [ 5331.815637] LustreError: 140551:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 5331.818981] LustreError: 140551:0:(obd_class.h:479:obd_check_dev()) Skipped 23 previous similar messages [ 5331.838876] Lustre: Unmounted lustre-client [ 5331.840778] Lustre: Skipped 2 previous similar messages [ 5364.070790] Key type lgssc unregistered [ 5364.197627] LNet: 140958:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5364.200226] LNetError: 140958:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5364.215537] LNet: Removed LNI 192.168.204.12@tcp [ 5364.479204] Key type .llcrypt unregistered [ 5364.480342] Key type ._llcrypt unregistered [ 5378.629975] Key type ._llcrypt registered [ 5378.634047] Key type .llcrypt registered [ 5378.865666] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5378.871701] alg: No test for adler32 (adler32-zlib) [ 5379.734169] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 5379.840329] LNet: Added LNI 192.168.204.12@tcp [8/256/0/180] [ 5381.431182] Key type lgssc registered [ 5381.823967] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5409.335219] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5410.633959] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5418.923033] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5420.136065] Lustre: Mounted lustre-client [ 5420.262601] Lustre: Remounted lustre-client read-only [ 5420.653596] LustreError: 144698:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f8b1088c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5420.662343] LustreError: 144698:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5420.689245] Lustre: Unmounted lustre-client [ 5438.529675] Key type lgssc unregistered [ 5438.647909] LNet: 145100:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5438.651267] LNetError: 145100:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5438.661665] LNet: Removed LNI 192.168.204.12@tcp [ 5438.936919] Key type .llcrypt unregistered [ 5438.938461] Key type ._llcrypt unregistered [ 5444.781418] Key type ._llcrypt registered [ 5444.782848] Key type .llcrypt registered [ 5445.297481] Lustre: DEBUG MARKER: SKIP: conf-sanity test_106 skipping SLOW test 106 [ 5445.908659] Lustre: DEBUG MARKER: == conf-sanity test 107: Unknown config param should not fail target mounting ========================================================== 17:13:09 (1760130789) [ 5446.886479] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5446.904367] alg: No test for adler32 (adler32-zlib) [ 5447.786824] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 5447.875426] LNet: Added LNI 192.168.204.12@tcp [8/256/0/180] [ 5449.463166] Key type lgssc registered [ 5449.919789] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5468.054554] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5469.328613] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5475.977989] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5493.707514] Key type lgssc unregistered [ 5493.841319] LNet: 148630:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5493.843722] LNetError: 148630:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5493.853316] LNet: Removed LNI 192.168.204.12@tcp [ 5494.097466] Key type .llcrypt unregistered [ 5494.098515] Key type ._llcrypt unregistered [ 5497.915716] Key type ._llcrypt registered [ 5497.917375] Key type .llcrypt registered [ 5498.094575] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5498.109885] alg: No test for adler32 (adler32-zlib) [ 5498.964111] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 5499.040908] LNet: Added LNI 192.168.204.12@tcp [8/256/0/180] [ 5500.623211] Key type lgssc registered [ 5501.043636] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5561.802759] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5566.548173] Key type lgssc unregistered [ 5566.672409] LNet: 150567:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5566.674965] LNetError: 150567:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5566.683318] LNet: Removed LNI 192.168.204.12@tcp [ 5566.953437] Key type .llcrypt unregistered [ 5566.954427] Key type ._llcrypt unregistered [ 5572.162613] Key type ._llcrypt registered [ 5572.163938] Key type .llcrypt registered [ 5572.683644] Lustre: DEBUG MARKER: == conf-sanity test 108a: migrate from ldiskfs to ZFS ==== 17:15:16 (1760130916) [ 5573.210411] Lustre: DEBUG MARKER: SKIP: conf-sanity test_108a zfs only test [ 5573.788970] Lustre: DEBUG MARKER: == conf-sanity test 108b: migrate from ZFS to ldiskfs ==== 17:15:17 (1760130917) [ 5578.370499] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5578.386710] alg: No test for adler32 (adler32-zlib) [ 5579.266078] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 5579.357804] LNet: Added LNI 192.168.204.12@tcp [8/256/0/180] [ 5580.951239] Key type lgssc registered [ 5581.383033] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5616.598849] Lustre: Mounted lustre-client [ 5616.783460] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5616.787300] LustreError: 153139:0:(file.c:249:ll_close_inode_openhandle()) lustre-clilmv-ffff8f8b04aa4000: inode [0x200000bd1:0x2:0x0] mdc close failed: rc = -108 [ 5616.795224] Lustre: 151948:0:(llite_lib.c:4240:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.204.112@tcp:/lustre/fid: [0x200000bd1:0x2:0x0]/ may get corrupted (rc -108) [ 5616.799504] Lustre: 151946:0:(llite_lib.c:4240:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.204.112@tcp:/lustre/fid: [0x240000402:0x3:0x0]/ may get corrupted (rc -108) [ 5627.046204] LustreError: 153139:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 5627.062130] Lustre: Unmounted lustre-client [ 5664.283720] Lustre: DEBUG MARKER: == conf-sanity test 109a: test lctl clear_conf fsname ==== 17:16:47 (1760131007) [ 5698.481936] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5699.710422] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5707.280940] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5712.878957] Lustre: Mounted lustre-client [ 5771.594730] LustreError: 157622:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f8b0a733800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5771.598924] LustreError: 157622:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5771.601347] LustreError: 157622:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 5771.624146] Lustre: Unmounted lustre-client [ 5804.602279] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5805.837772] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5812.010524] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5827.571781] Lustre: Mounted lustre-client [ 5847.559313] LustreError: 159508:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f8b10765800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5847.565591] LustreError: 159508:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5847.567971] LustreError: 159508:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 5847.588385] Lustre: Unmounted lustre-client [ 5864.821328] Key type lgssc unregistered [ 5864.949509] LNet: 159908:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5864.951828] LNetError: 159908:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5864.961781] LNet: Removed LNI 192.168.204.12@tcp [ 5865.197268] Key type .llcrypt unregistered [ 5865.198218] Key type ._llcrypt unregistered [ 5870.488593] Key type ._llcrypt registered [ 5870.489648] Key type .llcrypt registered [ 5870.943815] Lustre: DEBUG MARKER: == conf-sanity test 109b: test lctl clear_conf one config ========================================================== 17:20:14 (1760131214) [ 5880.596229] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5880.601287] alg: No test for adler32 (adler32-zlib) [ 5881.452226] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 5881.530633] LNet: Added LNI 192.168.204.12@tcp [8/256/0/180] [ 5883.111169] Key type lgssc registered [ 5883.532453] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5913.094635] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5914.262465] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5921.434580] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5927.896285] Lustre: Mounted lustre-client [ 5995.383257] LustreError: 164818:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f8b11cad800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5995.387473] LustreError: 164818:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5995.407127] Lustre: Unmounted lustre-client [ 6037.604901] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6038.768982] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6044.467976] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6060.020480] Lustre: Mounted lustre-client [ 6077.172249] LustreError: 166707:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f8b1088e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6077.178955] LustreError: 166707:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6077.180893] LustreError: 166707:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 6077.205750] Lustre: Unmounted lustre-client [ 6102.848280] Key type lgssc unregistered [ 6102.965391] LNet: 167110:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6102.967640] LNetError: 167110:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6102.979435] LNet: Removed LNI 192.168.204.12@tcp [ 6103.217423] Key type .llcrypt unregistered [ 6103.218360] Key type ._llcrypt unregistered [ 6108.613226] Key type ._llcrypt registered [ 6108.614421] Key type .llcrypt registered [ 6109.136385] Lustre: DEBUG MARKER: == conf-sanity test 110: Adding large_dir with 3-level htree ========================================================== 17:24:12 (1760131452) [ 6113.667373] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6113.696128] alg: No test for adler32 (adler32-zlib) [ 6114.570765] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 6114.666397] LNet: Added LNI 192.168.204.12@tcp [8/256/0/180] [ 6116.255094] Key type lgssc registered [ 6116.655102] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6147.962266] Lustre: Mounted lustre-client [ 6149.515373] Lustre: Mounted lustre-client [ 6325.890561] LustreError: 171045:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f8b11da5800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6325.894817] LustreError: 171045:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 6325.911234] Lustre: Unmounted lustre-client [ 6367.683802] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6368.862463] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6376.124291] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6395.554077] Lustre: DEBUG MARKER: SKIP: conf-sanity test_111 skipping SLOW test 111 [ 6396.103700] Lustre: DEBUG MARKER: == conf-sanity test 112a: mount OST with no_create option ========================================================== 17:28:59 (1760131739) [ 6405.454340] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6406.552824] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6412.328637] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6424.047695] Lustre: Mounted lustre-client [ 6426.659677] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8f8b098c8800.ost_server_uuid 50 [ 6427.204609] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8f8b098c8800.ost_server_uuid in FULL state after 0 sec [ 6428.410470] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8f8b098c8800.ost_server_uuid 50 [ 6428.893548] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8f8b098c8800.ost_server_uuid in FULL state after 0 sec [ 6444.514885] Lustre: lustre-OST0001-osc-ffff8f8b098c8800: Connection to lustre-OST0001 (at 192.168.204.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6447.602295] LustreError: 177901:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f8b098c8800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6447.605924] LustreError: 177901:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 6447.611922] LustreError: 177901:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 6447.615025] LustreError: 177901:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 6447.646953] Lustre: Unmounted lustre-client [ 6447.648934] Lustre: Skipped 1 previous similar message [ 6464.974282] Key type lgssc unregistered [ 6465.094551] LNet: 178302:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6465.098581] LNetError: 178302:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6465.107852] LNet: Removed LNI 192.168.204.12@tcp [ 6465.369080] Key type .llcrypt unregistered [ 6465.370166] Key type ._llcrypt unregistered [ 6470.825595] Key type ._llcrypt registered [ 6470.826586] Key type .llcrypt registered [ 6471.264277] Lustre: DEBUG MARKER: == conf-sanity test 112b: mount MDT with no_create option ========================================================== 17:30:14 (1760131814) [ 6472.037747] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6472.060387] alg: No test for adler32 (adler32-zlib) [ 6472.922794] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 6473.006858] LNet: Added LNI 192.168.204.12@tcp [8/256/0/180] [ 6474.591114] Key type lgssc registered [ 6474.992250] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6487.613953] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6498.611013] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6504.708765] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 6511.088332] Lustre: Mounted lustre-client [ 6595.803683] LustreError: 181704:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f8b0a7c7800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6595.809781] LustreError: 181704:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 6595.831345] Lustre: Unmounted lustre-client [ 6613.530475] Key type lgssc unregistered [ 6613.671696] LNet: 182106:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6613.675497] LNetError: 182106:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6613.686352] LNet: Removed LNI 192.168.204.12@tcp [ 6613.972315] Key type .llcrypt unregistered [ 6613.973307] Key type ._llcrypt unregistered [ 6619.909496] Key type ._llcrypt registered [ 6619.911251] Key type .llcrypt registered [ 6620.409984] Lustre: DEBUG MARKER: == conf-sanity test 113: Shadow mountpoint correctly report ro/rw for mounts ========================================================== 17:32:43 (1760131963) [ 6629.825821] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6629.840721] alg: No test for adler32 (adler32-zlib) [ 6630.707295] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 6630.792623] LNet: Added LNI 192.168.204.12@tcp [8/256/0/180] [ 6632.383189] Key type lgssc registered [ 6632.807123] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6660.915039] Lustre: Mounted lustre-client [ 6663.298855] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 6675.023409] LustreError: 186025:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f8b04aa1800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6675.029826] LustreError: 186025:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 6675.055178] Lustre: Unmounted lustre-client [ 6733.995238] Lustre: Mounted lustre-client [ 6736.408722] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 6742.930066] LustreError: 188262:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f8b11d7d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6742.933128] LustreError: 188262:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 6742.937655] LustreError: 188262:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 6742.939339] LustreError: 188262:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 6742.961123] Lustre: Unmounted lustre-client [ 6774.899573] Lustre: DEBUG MARKER: SKIP: conf-sanity test_114 skipping SLOW test 114 [ 6775.462546] Lustre: DEBUG MARKER: SKIP: conf-sanity test_115 skipping excluded test 115 [ 6776.062810] Lustre: DEBUG MARKER: == conf-sanity test 116: big size MDT support ============ 17:35:19 (1760132119) [ 6836.710671] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6837.846397] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6844.858670] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6875.626943] Lustre: DEBUG MARKER: == conf-sanity test 117: lctl get_param return errors properly ========================================================== 17:36:59 (1760132219) [ 6885.671951] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6886.913357] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6893.088811] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6908.914251] Lustre: Mounted lustre-client [ 6911.344116] LustreError: 195370:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f8b098c8000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6911.347583] LustreError: 195370:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 6911.352157] LustreError: 195370:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6911.355102] LustreError: 195370:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 6911.377174] Lustre: Unmounted lustre-client [ 6937.048988] Key type lgssc unregistered [ 6937.182438] LNet: 195774:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6937.184525] LNetError: 195774:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6937.193428] LNet: Removed LNI 192.168.204.12@tcp [ 6937.474140] Key type .llcrypt unregistered [ 6937.475361] Key type ._llcrypt unregistered [ 6942.829659] Key type ._llcrypt registered [ 6942.830716] Key type .llcrypt registered [ 6943.303400] Lustre: DEBUG MARKER: == conf-sanity test 119: writeconf on slave mdt shouldn't duplicate mdc/osp and crash ========================================================== 17:38:06 (1760132286) [ 6945.106697] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6945.115156] alg: No test for adler32 (adler32-zlib) [ 6945.976422] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 6946.062699] LNet: Added LNI 192.168.204.12@tcp [8/256/0/180] [ 6947.655142] Key type lgssc registered [ 6948.050462] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6965.402709] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6966.636355] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6972.931180] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6983.662214] Lustre: Mounted lustre-client [ 6988.771313] Lustre: lustre-MDT0001-mdc-ffff8f8b04aa3000: Connection to lustre-MDT0001 (at 192.168.204.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7005.162395] LustreError: 199178:0:(obd_config.c:1997:class_config_llog_handler()) MGC192.168.204.112@tcp: cfg command failed: rc = -17 [ 7005.164846] Lustre: cmd=cf001 0:lustre-MDT0001-mdc 1:mdc 2:lustre-clilmv_UUID [ 7005.164846] [ 7005.166847] LustreError: 198780:0:(mgc_request.c:614:do_requeue()) failed processing log: -17 [ 7301.090151] Lustre: lustre-MDT0001-mdc-ffff8f8b04aa3000: Connection to lustre-MDT0001 (at 192.168.204.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7325.676696] LustreError: 201339:0:(obd_config.c:689:class_setup()) Device 4 already setup (type mdc) [ 7325.678906] LustreError: 201339:0:(obd_config.c:1997:class_config_llog_handler()) MGC192.168.204.112@tcp: cfg command failed: rc = -17 [ 7325.683227] Lustre: cmd=cf003 0:lustre-MDT0001-mdc 1:lustre-MDT0001_UUID 2:192.168.204.112@tcp [ 7325.683227] [ 7325.685652] LustreError: 198780:0:(mgc_request.c:614:do_requeue()) failed processing log: -17 [ 7623.650465] Lustre: lustre-MDT0001-mdc-ffff8f8b04aa3000: Connection to lustre-MDT0001 (at 192.168.204.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7641.066860] LustreError: 203522:0:(obd_config.c:1997:class_config_llog_handler()) MGC192.168.204.112@tcp: cfg command failed: rc = -17 [ 7641.071652] Lustre: cmd=cf014 0:lustre-clilmv 1:lustre-MDT0001_UUID 2:1 3:1 4:lustre-MDT0001-mdc_UUID [ 7641.071652] [ 7641.076287] LustreError: 198780:0:(mgc_request.c:614:do_requeue()) failed processing log: -17 [ 7935.733360] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7946.027583] LustreError: 205544:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7946.042709] Lustre: Unmounted lustre-client [ 7980.305806] Lustre: DEBUG MARKER: == conf-sanity test 120: cross-target rename should not create bad symlinks ========================================================== 17:55:23 (1760133323) [ 7992.168032] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7993.277554] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8000.618242] Lustre: DEBUG MARKER: oleg412-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8000.767089] Lustre: Mounted lustre-client [ 8002.555552] LustreError: 209467:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f8b11009000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8002.561551] LustreError: 209467:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 8002.564520] LustreError: 209467:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 8002.588298] Lustre: Unmounted lustre-client [ 8044.194170] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 7810 sec ========== 17:56:27 (1760133387) [ 8044.719917] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 17:56:28 (1760133388) === [ 8045.824739] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 17:56:29 (1760133389) === [ 8056.846587] Key type lgssc unregistered [ 8056.957546] LNet: 213304:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8056.960570] LNetError: 213304:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8056.968781] LNet: Removed LNI 192.168.204.12@tcp [ 8057.193039] Key type .llcrypt unregistered [ 8057.193978] Key type ._llcrypt unregistered