[ 0.000000] Linux version 4.18.0rh8.10-debug (green@maintenance) (gcc version 8.5.0 20210514 (Red Hat 8.5.0-26) (GCC)) #2 SMP Mon Jul 14 01:24:22 EDT 2025 [ 0.000000] Command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' [ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 [ 0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. [ 0.000000] signal: max sigframe size: 1776 [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bffd9fff] usable [ 0.000000] BIOS-e820: [mem 0x00000000bffda000-0x00000000bfffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x0000000146dfffff] usable [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] SMBIOS 2.8 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.17.0-8.fc42 06/10/2025 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 456685642 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2421 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE22BD 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 00227D (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2331 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE23C1 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE23F9 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe22bd-0xbffe2330] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22bc] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2331-0xbffe23c0] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe23c1-0xbffe23f8] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe23f9-0xbffe2420] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001013] APIC: Switch to symmetric I/O mode setup [ 0.003167] x2apic enabled [ 0.004010] Switched APIC routing to physical x2apic. [ 0.005017] kvm-guest: setup PV IPIs [ 0.008000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.008000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.008043] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009018] pid_max: default: 32768 minimum: 301 [ 0.010114] LSM: Security Framework initializing [ 0.011049] Yama: becoming mindful. [ 0.012064] SELinux: Initializing. [ 0.013084] *** VALIDATE selinux *** [ 0.022298] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.027226] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.028171] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029110] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.030119] *** VALIDATE tmpfs *** [ 0.031450] *** VALIDATE proc *** [ 0.032267] *** VALIDATE cgroup *** [ 0.033012] *** VALIDATE cgroup2 *** [ 0.034247] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.035150] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.036010] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.037033] Spectre V2 : User space: Vulnerable [ 0.038010] Speculative Store Bypass: Vulnerable [ 0.042507] debug: unmapping init [mem 0xffffffff9e059000-0xffffffff9e060fff] [ 0.046355] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.047962] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.049037] ... version: 2 [ 0.050022] ... bit width: 48 [ 0.051022] ... generic registers: 4 [ 0.052020] ... value mask: 0000ffffffffffff [ 0.053026] ... max period: 00007fffffffffff [ 0.054022] ... fixed-purpose events: 3 [ 0.055019] ... event mask: 000000070000000f [ 0.057331] rcu: Hierarchical SRCU implementation. [ 0.059947] smp: Bringing up secondary CPUs ... [ 0.060798] x86: Booting SMP configuration: [ 0.061036] .... node #0, CPUs: #1 #2 #3 [ 0.065104] smp: Brought up 1 node, 4 CPUs [ 0.067031] smpboot: Max logical packages: 1 [ 0.068020] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.227268] node 0 deferred pages initialised in 157ms [ 0.234143] devtmpfs: initialized [ 0.235416] x86/mm: Memory block size: 128MB [ 0.238076] gcov: version magic: 0x41383552 [ 0.241385] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.246162] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.249570] pinctrl core: initialized pinctrl subsystem [ 0.251332] [ 0.251878] ************************************************************* [ 0.254019] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.256016] ** ** [ 0.258022] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.261022] ** ** [ 0.264019] ** This means that this kernel is built to expose internal ** [ 0.266024] ** IOMMU data structures, which may compromise security on ** [ 0.268024] ** your system. ** [ 0.272029] ** ** [ 0.275020] ** If you see this message and you are not debugging the ** [ 0.278033] ** kernel, report this immediately to your vendor! ** [ 0.281022] ** ** [ 0.283019] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.286019] ************************************************************* [ 0.288757] NET: Registered protocol family 16 [ 0.291455] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.294074] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.297074] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.300524] cpuidle: using governor menu [ 0.303226] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.306481] PCI: Using configuration type 1 for base access [ 0.308352] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.321155] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.324024] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.328144] cryptd: max_cpu_qlen set to 1000 [ 0.332416] ACPI: Added _OSI(Module Device) [ 0.335029] ACPI: Added _OSI(Processor Device) [ 0.337026] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.339030] ACPI: Added _OSI(Processor Aggregator Device) [ 0.347794] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.359921] ACPI: Interpreter enabled [ 0.363153] ACPI: PM: (supports S0 S3 S4 S5) [ 0.366025] ACPI: Using IOAPIC for interrupt routing [ 0.369159] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.374755] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.390994] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.394061] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.396026] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.400120] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.405528] acpiphp: Slot [2] registered [ 0.407185] acpiphp: Slot [5] registered [ 0.408170] acpiphp: Slot [6] registered [ 0.410172] acpiphp: Slot [3] registered [ 0.411174] acpiphp: Slot [4] registered [ 0.413117] acpiphp: Slot [7] registered [ 0.414168] acpiphp: Slot [8] registered [ 0.416154] acpiphp: Slot [9] registered [ 0.417205] acpiphp: Slot [10] registered [ 0.419157] acpiphp: Slot [11] registered [ 0.420144] acpiphp: Slot [12] registered [ 0.422139] acpiphp: Slot [13] registered [ 0.424174] acpiphp: Slot [14] registered [ 0.425168] acpiphp: Slot [15] registered [ 0.427168] acpiphp: Slot [16] registered [ 0.429145] acpiphp: Slot [17] registered [ 0.431167] acpiphp: Slot [18] registered [ 0.432159] acpiphp: Slot [19] registered [ 0.434193] acpiphp: Slot [20] registered [ 0.437165] acpiphp: Slot [21] registered [ 0.439183] acpiphp: Slot [22] registered [ 0.441155] acpiphp: Slot [23] registered [ 0.443157] acpiphp: Slot [24] registered [ 0.444197] acpiphp: Slot [25] registered [ 0.446161] acpiphp: Slot [26] registered [ 0.447179] acpiphp: Slot [27] registered [ 0.449174] acpiphp: Slot [28] registered [ 0.451185] acpiphp: Slot [29] registered [ 0.453145] acpiphp: Slot [30] registered [ 0.454137] acpiphp: Slot [31] registered [ 0.456090] PCI host bridge to bus 0000:00 [ 0.458033] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.461022] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.463043] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.467040] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.469030] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.471083] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.473199] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.475177] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.478000] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.485020] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.489051] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.491020] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.493016] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.494015] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.497185] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.498829] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.501058] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.503106] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.507000] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.517022] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.522019] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.526857] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.539016] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.545016] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.562030] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.573720] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.588025] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.601037] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.619029] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.634372] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.637388] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.638403] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.639385] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.640223] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.646039] iommu: Default domain type: Passthrough [ 0.648413] SCSI subsystem initialized [ 0.650186] ACPI: bus type USB registered [ 0.653134] usbcore: registered new interface driver usbfs [ 0.655095] usbcore: registered new interface driver hub [ 0.658102] usbcore: registered new device driver usb [ 0.660173] pps_core: LinuxPPS API ver. 1 registered [ 0.663014] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.666097] PTP clock support registered [ 0.669084] EDAC MC: Ver: 3.0.0 [ 0.671209] PCI: Using ACPI for IRQ routing [ 0.673760] NetLabel: Initializing [ 0.676013] NetLabel: domain hash size = 128 [ 0.678011] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.681080] NetLabel: unlabeled traffic allowed by default [ 0.683145] vgaarb: loaded [ 0.685267] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.688016] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.697000] clocksource: Switched to clocksource kvm-clock [ 0.811695] VFS: Disk quotas dquot_6.6.0 [ 0.813650] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.816575] *** VALIDATE ramfs *** [ 0.817967] *** VALIDATE hugetlbfs *** [ 0.819858] pnp: PnP ACPI init [ 0.822428] pnp: PnP ACPI: found 6 devices [ 0.841055] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.844652] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.847128] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.849726] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.852489] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.855263] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.858411] NET: Registered protocol family 2 [ 0.861315] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.866788] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.871558] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.877467] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.882187] TCP: Hash tables configured (established 65536 bind 65536) [ 0.885419] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.889243] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.892371] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.895777] NET: Registered protocol family 1 [ 0.898451] RPC: Registered named UNIX socket transport module. [ 0.900172] RPC: Registered udp transport module. [ 0.901842] RPC: Registered tcp transport module. [ 0.903604] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.906116] NET: Registered protocol family 44 [ 0.907275] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.909962] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.912900] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.915744] PCI: CLS 0 bytes, default 64 [ 0.918461] Unpacking initramfs... [ 2.375821] debug: unmapping init [mem 0xffff8e187cc64000-0xffff8e187ffcffff] [ 2.381344] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.385092] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.389806] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.901628] Initialise system trusted keyrings [ 2.903662] Key type blacklist registered [ 2.905669] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.922001] zbud: loaded [ 2.925839] *** VALIDATE nfs *** [ 2.927869] *** VALIDATE nfs4 *** [ 2.930333] pstore: using deflate compression [ 2.935273] Platform Keyring initialized [ 3.051649] NET: Registered protocol family 38 [ 3.054629] Key type asymmetric registered [ 3.057260] Asymmetric key parser 'x509' registered [ 3.060238] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 3.065238] io scheduler mq-deadline registered [ 3.068411] io scheduler kyber registered [ 3.070990] io scheduler bfq registered [ 3.073788] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 3.078508] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 3.082983] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 3.087425] ACPI: Power Button [PWRF] [ 3.093778] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.102644] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.115159] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.146406] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.175261] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.180625] Non-volatile memory driver v1.3 [ 3.183468] Linux agpgart interface v0.103 [ 3.217097] virtio_blk virtio1: [vda] 133944 512-byte logical blocks (68.6 MB/65.4 MiB) [ 3.222051] vda: detected capacity change from 0 to 68579328 [ 3.242189] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.246166] vdb: detected capacity change from 0 to 1073741824 [ 3.252866] libphy: Fixed MDIO Bus: probed [ 3.259575] usbcore: registered new interface driver usbserial_generic [ 3.262528] usbserial: USB Serial support registered for generic [ 3.265323] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.278041] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.281399] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.289825] mousedev: PS/2 mouse device common for all mice [ 3.292874] rtc_cmos 00:05: RTC can wake from S4 [ 3.295740] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.299407] rtc_cmos 00:05: registered as rtc0 [ 3.303080] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.304566] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.310301] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.315041] intel_pstate: CPU model not supported [ 3.323759] hid: raw HID events driver (C) Jiri Kosina [ 3.325899] usbcore: registered new interface driver usbhid [ 3.327652] usbhid: USB HID core driver [ 3.328951] drop_monitor: Initializing network drop monitor service [ 3.331014] Initializing XFRM netlink socket [ 3.332601] NET: Registered protocol family 10 [ 3.337484] Segment Routing with IPv6 [ 3.339802] NET: Registered protocol family 17 [ 3.342796] mpls_gso: MPLS GSO support [ 3.348259] RAS: Correctable Errors collector initialized. [ 3.351231] AVX version of gcm_enc/dec engaged. [ 3.353887] AES CTR mode by8 optimization enabled [ 3.435957] sched_clock: Marking stable (3435914691, 0)->(4406357350, -970442659) [ 3.441919] registered taskstats version 1 [ 3.445089] Loading compiled-in X.509 certificates [ 3.446866] zswap: loaded using pool lzo/zbud [ 3.477055] Key type big_key registered [ 3.491128] Key type encrypted registered [ 3.492635] ima: No TPM chip found, activating TPM-bypass! [ 3.494562] ima: Allocated hash algorithm: sha1 [ 3.496370] ima: No architecture policies found [ 3.498353] evm: Initialising EVM extended attributes: [ 3.500358] evm: security.selinux [ 3.501463] evm: security.ima [ 3.502557] evm: security.capability [ 3.503714] evm: HMAC attrs: 0x1 [ 3.505925] rtc_cmos 00:05: setting system clock to 2026-04-09 19:30:36 UTC (1775763036) [ 3.512332] debug: unmapping init [mem 0xffffffff9f003000-0xffffffff9f1fffff] [ 3.515770] debug: unmapping init [mem 0xffffffff9dd82000-0xffffffff9e058fff] [ 3.523153] Write protecting the kernel read-only data: 28672k [ 3.526928] debug: unmapping init [mem 0xffffffff9c403000-0xffffffff9c5fffff] [ 3.529918] debug: unmapping init [mem 0xffffffff9cd14000-0xffffffff9cdfffff] [ 3.567375] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 3.575676] systemd[1]: Detected virtualization kvm. [ 3.577904] systemd[1]: Detected architecture x86-64. [ 3.580371] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.604082] systemd[1]: No hostname configured. [ 3.605639] systemd[1]: Set hostname to . [ 3.607973] random: systemd: uninitialized urandom read (16 bytes read) [ 3.610694] systemd[1]: Initializing machine ID from random generator. [ 3.677893] random: ln: uninitialized urandom read (6 bytes read) [ 3.765633] random: systemd: uninitialized urandom read (16 bytes read) [ 3.768460] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ 3.774361] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ 3.780507] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Listening on udev Control Socket. [ OK ] Reached target Swap. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on Journal Socket. [ OK ] Started Memstrack Anylazing Service. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Sockets. Starting Apply Kernel Variables... [ OK ] Reached target Timers. [ OK ] Reached target Paths. Starting Setup Virtual Console... [ OK ] Reached target Slices. Starting Journal Service... [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. [ OK ] Started Setup Virtual Console. [ OK ] Started Create Volatile Files and Directories. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.430699] device-mapper: uevent: version 1.0.3 [ 4.433683] 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. [ 5.267389] random: fast init done [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting dracut initqueue hook... [ 5.397132] virtio_net virtio0 ens2: renamed from eth0 [ 5.524630] scsi host0: ata_piix [ 5.543555] scsi host1: ata_piix [ 5.544735] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.547229] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 10.136333] dracut-initqueue[584]: RTNETLINK answers: File exists [ 10.255945] random: crng init done [ 10.257141] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 11.575273] 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... Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Timers. [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Sockets. [ OK ] Stopped target Slices. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Swap. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Paths. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 14.269700] printk: systemd: 26 output lines suppressed due to ratelimiting [ 14.574581] SELinux: Disabled at runtime. [ 14.635636] 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) [ 14.644815] systemd[1]: Detected virtualization kvm. [ 14.646854] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 15.446306] systemd[1]: initrd-switch-root.service: Succeeded. [ 15.450187] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 15.461467] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 15.466387] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 15.470206] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 15.483510] systemd[1]: Starting Journal Service... Starting Journal Service... [ 15.489532] systemd[1]: Listening on initctl Compatibility Named Pipe. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Listening on Process Core Dump Socket. [ OK ] Started Forward Password Requests to Wall Directory Watch. Mounting POSIX Message Queue File System... [ OK ] Created slice system-getty.slice. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Created slice system-sshd\x2dkeygen.slice. Starting Remount Root and Kernel File Systems... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target rpc_pipefs.target. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd Root File System. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Listening on udev Control Socket. Activating swap /dev/disk/by-label/SWAP... [ OK ] Listening on udev Kernel Socket. Starting udev Coldplug all Devices... [ OK [0[ 15.970627] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS m] Created slice system-serial\x2dgetty.slice. Mounting Kernel Debug File System... [ OK ] Stopped target Initrd File Systems. Starting Apply Kernel Variables... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. Mounting Huge Pages File System... [ OK ] Started Journal Service. [ OK ] Mounted POSIX Message Queue File System. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted Kernel Debug File System. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted Huge Pages File System. [ OK ] Reached target Swap. Starting Create Static Device Nodes in /dev... Starting Configure read-only root support... 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. [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... Starting udev Kernel Device Manager... [ OK ] Mounted /mnt. [ 17.617418] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 18.160686] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 18.258795] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 18.729394] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 18.769256] EDAC sbridge: Ver: 1.1.2 [ 21.264306] Key type dns_resolver registered [ 21.681178] NFS: Registering the id_resolver key type [ 21.683160] Key type id_resolver registered [ 21.684786] Key type id_legacy registered [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting 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 ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Restore /run/initramfs on shutdown... Starting Login Service... [ OK ] Reached target sshd-keygen.target. [ OK ] Started irqbalance daemon. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting OpenSSH server daemon... Starting Dynamic System Tuning Daemon... Starting GSSAPI Proxy Daemon... [ OK ] Started OpenSSH server daemon. [ OK ] Started GSSAPI Proxy Daemon. Starting Hostname Service... [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Getty on tty1. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Crash recovery kernel arming... Starting System Logging Service... Starting Notify NFS peers of a restart... [ OK ] Started Notify NFS peers of a restart. Starting Authorization Manager... [ OK ] Started System Logging Service. [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. [ OK ] Started Authorization Manager. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg658-client login: [ 73.680074] libcfs: loading out-of-tree module taints kernel. [ 73.827801] Key type ._llcrypt registered [ 73.831597] Key type .llcrypt registered [ 74.997624] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 75.019109] alg: No test for adler32 (adler32-zlib) [ 76.489847] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [ 77.626250] LNet: Added LNI 192.168.206.58@tcp [8/256/0/180] [ 79.391242] Key type lgssc registered [ 81.644565] Lustre: Echo OBD driver; http://www.lustre.org/ [ 106.987584] hrtimer: interrupt took 10428815 ns [ 257.450110] Lustre: Mounted lustre-client [ 263.383046] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 283.118152] Lustre: lustre-OST0000-osc-ffff8e18c6a80000: disconnect after 23s idle [ 283.563314] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing check_logdir /tmp/testlogs/ [ 289.250941] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing yml_node [ 293.992525] Lustre: DEBUG MARKER: Client: 2.17.51.77 [ 296.754848] Lustre: DEBUG MARKER: MDS: 2.17.51.77 [ 299.327889] Lustre: DEBUG MARKER: OSS: 2.17.51.77 [ 301.331259] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Thu Apr 9 15:35:32 EDT 2026 [ 321.683273] Lustre: DEBUG MARKER: excepting tests: 102 106 115 32newtarball [ 323.446322] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 326.547968] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 336.942886] Lustre: Unmounted lustre-client [ 464.087225] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 469.069676] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 493.868237] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 534.183194] Lustre: DEBUG MARKER: == conf-sanity test 76a: set permanent params with lctl across mounts ========================================================== 15:39:25 (1775763565) [ 566.034388] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 570.796550] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 592.064698] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 592.886667] Lustre: Mounted lustre-client [ 600.183470] LustreError: 14240:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8e18c8795800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 600.327192] Lustre: Unmounted lustre-client [ 716.735973] Lustre: Mounted lustre-client [ 721.644698] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 740.301651] LustreError: 16802:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8e18c5f2e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 740.441485] Lustre: Unmounted lustre-client [ 854.403448] Lustre: Mounted lustre-client [ 859.327715] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 872.513938] LustreError: 19038:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8e18c5bad000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 872.521786] LustreError: 19038:0:(lov_obd.c:786:lov_cleanup()) Skipped 1 previous similar message [ 872.640026] Lustre: Unmounted lustre-client [ 916.044296] Lustre: DEBUG MARKER: == conf-sanity test 76b: verify params log setup correctly ========================================================== 15:45:47 (1775763947) [ 1019.039730] Lustre: Mounted lustre-client [ 1024.759650] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 1037.235638] LustreError: 22266:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8e18c4c63800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1037.249897] LustreError: 22266:0:(lov_obd.c:786:lov_cleanup()) Skipped 1 previous similar message [ 1037.398726] Lustre: Unmounted lustre-client [ 1087.773633] Lustre: DEBUG MARKER: == conf-sanity test 76c: verify changelog_mask is applied with lctl set_param -P ========================================================== 15:48:39 (1775764119) [ 1165.106338] Lustre: Mounted lustre-client [ 1170.316184] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 1185.775715] Lustre: lustre-MDT0000-mdc-ffff8e18c5687000: Connection to lustre-MDT0000 (at 192.168.206.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1190.884627] Lustre: lustre-OST0000-osc-ffff8e18c5687000: disconnect after 23s idle [ 1190.893617] Lustre: Skipped 1 previous similar message [ 1190.903645] Lustre: lustre-MDT0001-mdc-ffff8e18c5687000: Connection to lustre-MDT0001 (at 192.168.206.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1206.112869] Lustre: 2410:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1775764223/real 1775764223] req@ffff8e18c3f7d180 x1862022578882688/t0(0) o400->MGC192.168.206.158@tcp@192.168.206.158@tcp:26/25 lens 224/224 e 0 to 1 dl 1775764239 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'kworker.0' uid:0 gid:0 projid:4294967295 [ 1206.166854] LustreError: MGC192.168.206.158@tcp: Connection to MGS (at 192.168.206.158@tcp) was lost; in progress operations using this service will fail [ 1216.497211] Lustre: Evicted from MGS (at 192.168.206.158@tcp) after server handle changed from 0x402fea012b3ec6f8 to 0x402fea012b3ec7f4 [ 1216.520308] Lustre: MGC192.168.206.158@tcp: Connection restored to 192.168.206.158@tcp (at 192.168.206.158@tcp) [ 1236.985073] LustreError: lustre-MDT0000-mdc-ffff8e18c5687000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 1237.004207] Lustre: lustre-MDT0000-mdc-ffff8e18c5687000: Connection restored to 192.168.206.158@tcp (at 192.168.206.158@tcp) [ 1240.402687] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1241.880050] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 1245.580798] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1247.040488] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 1249.260375] LustreError: 26577:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8e18c5687000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1249.267272] LustreError: 26577:0:(lov_obd.c:786:lov_cleanup()) Skipped 1 previous similar message [ 1249.327416] Lustre: Unmounted lustre-client [ 1289.929736] Lustre: DEBUG MARKER: == conf-sanity test 76d: verify llite.*.xattr_cache can be set by 'lctl set_param -P' correctly ========================================================== 15:52:01 (1775764321) [ 1376.252575] Lustre: Mounted lustre-client [ 1381.007409] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 1395.279518] LustreError: 29650:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8e18c7ce4800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1395.301774] LustreError: 29650:0:(lov_obd.c:786:lov_cleanup()) Skipped 1 previous similar message [ 1395.417115] Lustre: Unmounted lustre-client [ 1435.849668] Lustre: DEBUG MARKER: == conf-sanity test 77: comma-separated MGS NIDs and failover node NIDs ========================================================== 15:54:27 (1775764467) [ 1438.189572] Lustre: DEBUG MARKER: SKIP: conf-sanity test_77 mixed loopback and real device not working [ 1439.969103] Lustre: DEBUG MARKER: == conf-sanity test 78: run resize2fs on MDT and OST filesystems ========================================================== 15:54:31 (1775764471) [ 1494.290617] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1515.265290] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1515.888992] Lustre: Mounted lustre-client [ 1515.892847] Lustre: Skipped 2 previous similar messages [ 1517.793821] Lustre: DEBUG MARKER: create test files [ 1543.582926] LustreError: 33284:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8e18c5f2b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1543.590431] LustreError: 33284:0:(lov_obd.c:786:lov_cleanup()) Skipped 5 previous similar messages [ 1543.902243] Lustre: Unmounted lustre-client [ 1543.905866] Lustre: Skipped 2 previous similar messages [ 1561.454920] Key type lgssc unregistered [ 1561.766526] LNet: 33635:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1561.774748] LNetError: 33635:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1561.801913] LNet: Removed LNI 192.168.206.58@tcp [ 1562.594354] Key type .llcrypt unregistered [ 1562.601269] Key type ._llcrypt unregistered [ 1585.154815] Key type ._llcrypt registered [ 1585.156870] Key type .llcrypt registered [ 1585.501193] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1585.513837] alg: No test for adler32 (adler32-zlib) [ 1586.594944] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [ 1586.790951] LNet: Added LNI 192.168.206.58@tcp [8/256/0/180] [ 1588.440765] Key type lgssc registered [ 1589.860711] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1622.422926] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1642.418066] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1644.172185] Lustre: Mounted lustre-client [ 1647.943344] Lustre: DEBUG MARKER: check files after expanding the MDT and OST filesystems [ 1653.164967] Lustre: DEBUG MARKER: create more files after expanding the MDT and OST filesystems [ 1657.399699] LustreError: 36114:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8e18c529d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1657.577598] Lustre: Unmounted lustre-client [ 1679.357509] Key type lgssc unregistered [ 1679.545872] LNet: 36466:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1679.558635] LNetError: 36466:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1679.583413] LNet: Removed LNI 192.168.206.58@tcp [ 1680.400190] Key type .llcrypt unregistered [ 1680.402318] Key type ._llcrypt unregistered [ 1705.841716] Key type ._llcrypt registered [ 1705.846794] Key type .llcrypt registered [ 1706.246088] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1706.262224] alg: No test for adler32 (adler32-zlib) [ 1707.322832] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [ 1707.588596] LNet: Added LNI 192.168.206.58@tcp [8/256/0/180] [ 1709.343280] Key type lgssc registered [ 1710.460892] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1745.917906] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1764.305777] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1767.371775] Lustre: Mounted lustre-client [ 1771.729314] Lustre: DEBUG MARKER: check files after shrinking the MDT and OST filesystems [ 1778.449555] LustreError: 38832:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8e18d08ea800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1778.599250] Lustre: Unmounted lustre-client [ 1795.881333] Key type lgssc unregistered [ 1796.334674] LNet: 39182:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1796.359925] LNetError: 39182:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1796.398444] LNet: Removed LNI 192.168.206.58@tcp [ 1797.483151] Key type .llcrypt unregistered [ 1797.485380] Key type ._llcrypt unregistered [ 1827.892617] Key type ._llcrypt registered [ 1827.894645] Key type .llcrypt registered [ 1828.688343] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1828.722442] alg: No test for adler32 (adler32-zlib) [ 1829.885876] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [ 1830.157192] LNet: Added LNI 192.168.206.58@tcp [8/256/0/180] [ 1831.847285] Key type lgssc registered [ 1833.311721] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1914.669520] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1919.499786] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1941.574922] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1992.534991] Lustre: DEBUG MARKER: == conf-sanity test 79: format MDT/OST without mgs option (should return errors) ========================================================== 16:03:44 (1775765024) [ 2126.931570] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2130.721936] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2155.136671] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2195.478306] Lustre: DEBUG MARKER: == conf-sanity test 80: mgc import reconnect race ======== 16:07:07 (1775765227) [ 2226.929663] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2230.783069] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2249.862832] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2308.285770] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 2365.001941] Lustre: DEBUG MARKER: == conf-sanity test 81: sparse OST indexing ============== 16:09:56 (1775765396) [ 2367.275541] Lustre: DEBUG MARKER: SKIP: conf-sanity test_81 needs >= 3 OSTs [ 2369.500577] Lustre: DEBUG MARKER: == conf-sanity test 82a: specify OSTs for file (succeed) or directory (succeed) ========================================================== 16:10:00 (1775765400) [ 2371.902641] Lustre: DEBUG MARKER: SKIP: conf-sanity test_82a needs >= 3 OSTs [ 2374.130396] Lustre: DEBUG MARKER: == conf-sanity test 82b: specify OSTs for file with --pool and --ost-list options ========================================================== 16:10:05 (1775765405) [ 2376.016310] Lustre: DEBUG MARKER: SKIP: conf-sanity test_82b needs >= 4 OSTs [ 2378.225625] Lustre: DEBUG MARKER: == conf-sanity test 82c: specify sparse OSTs for setstripe (should not crash) ========================================================== 16:10:09 (1775765409) [ 2380.368877] Lustre: DEBUG MARKER: SKIP: conf-sanity test_82c needs >= 4 OSTs [ 2382.558869] Lustre: DEBUG MARKER: == conf-sanity test 83: ENOSPACE on OST doesn't cause message VFS: Busy inodes after unmount ... ========================================================== 16:10:13 (1775765413) [ 2384.284728] Lustre: DEBUG MARKER: mount the OST /dev/mapper/ost1_flakey as a ldiskfs filesystem [ 2392.709198] Lustre: DEBUG MARKER: run llverfs in partial mode on the OST ldiskfs /mnt/lustre-ost1 [ 2400.412714] Lustre: DEBUG MARKER: unmount the OST /dev/mapper/ost1_flakey [ 2532.038802] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2535.488424] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2558.513692] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2609.686543] Lustre: DEBUG MARKER: == conf-sanity test 84: check recovery_hard_time ========= 16:14:01 (1775765641) [ 2642.224167] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2646.253165] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2665.291836] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2696.569829] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 2708.288532] Lustre: Mounted lustre-client [ 2708.879194] Lustre: Mounted lustre-client [ 2727.421515] Lustre: DEBUG MARKER: local REPLAY BARRIER on lustre-MDT0000 [ 2760.177818] Lustre: lustre-MDT0000-mdc-ffff8e18c529a000: Connection to lustre-MDT0000 (at 192.168.206.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2776.543272] Lustre: 39810:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1775765793/real 1775765793] req@0000000075735165 x1862024417641600/t0(0) o400->MGC192.168.206.158@tcp@192.168.206.158@tcp:26/25 lens 224/224 e 0 to 1 dl 1775765809 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2776.561709] LustreError: MGC192.168.206.158@tcp: Connection to MGS (at 192.168.206.158@tcp) was lost; in progress operations using this service will fail [ 2786.796834] Lustre: Evicted from MGS (at 192.168.206.158@tcp) after server handle changed from 0x689c7627e5992a to 0x689c7627e65b08 [ 2786.806310] Lustre: MGC192.168.206.158@tcp: Connection restored to 192.168.206.158@tcp (at 192.168.206.158@tcp) [ 2792.120247] LustreError: 39809:0:(client.c:3419:ptlrpc_replay_interpret()) @@@ status 301, old was 0 req@00000000ac2ed2ce x1862024416998656/t8589934597(8589934597) o101->lustre-MDT0000-mdc-ffff8e18c529a000@192.168.206.158@tcp:12/10 lens 592/608 e 0 to 0 dl 1775765841 ref 2 fl Interpret:RQU/604/0 rc 301/301 job:'' uid:0 gid:0 projid:0 [ 2792.805882] LustreError: 39809:0:(client.c:3419:ptlrpc_replay_interpret()) @@@ status 301, old was 0 req@00000000fca144c0 x1862024416999296/t8589934599(8589934599) o101->lustre-MDT0000-mdc-ffff8e18c529a000@192.168.206.158@tcp:12/10 lens 592/608 e 0 to 0 dl 1775765841 ref 2 fl Interpret:RQU/604/0 rc 301/301 job:'' uid:0 gid:0 projid:0 [ 2794.260140] LustreError: 39809:0:(client.c:3419:ptlrpc_replay_interpret()) @@@ status 301, old was 0 req@00000000374d6891 x1862024417000576/t8589934603(8589934603) o101->lustre-MDT0000-mdc-ffff8e18c529a000@192.168.206.158@tcp:12/10 lens 592/608 e 0 to 0 dl 1775765842 ref 2 fl Interpret:RQU/604/0 rc 301/301 job:'' uid:0 gid:0 projid:0 [ 2794.290297] LustreError: 39809:0:(client.c:3419:ptlrpc_replay_interpret()) Skipped 1 previous similar message [ 2796.857503] LustreError: 39809:0:(client.c:3419:ptlrpc_replay_interpret()) @@@ status 301, old was 0 req@00000000440fcefa x1862024417003136/t8589934611(8589934611) o101->lustre-MDT0000-mdc-ffff8e18c529a000@192.168.206.158@tcp:12/10 lens 592/608 e 0 to 0 dl 1775765845 ref 2 fl Interpret:RQU/604/0 rc 301/301 job:'' uid:0 gid:0 projid:0 [ 2796.897899] LustreError: 39809:0:(client.c:3419:ptlrpc_replay_interpret()) Skipped 3 previous similar messages [ 2801.400621] LustreError: 39809:0:(client.c:3419:ptlrpc_replay_interpret()) @@@ status 301, old was 0 req@000000009c5d0439 x1862024417007616/t8589934625(8589934625) o101->lustre-MDT0000-mdc-ffff8e18c529a000@192.168.206.158@tcp:12/10 lens 592/608 e 0 to 0 dl 1775765850 ref 2 fl Interpret:RQU/604/0 rc 301/301 job:'' uid:0 gid:0 projid:0 [ 2801.447593] LustreError: 39809:0:(client.c:3419:ptlrpc_replay_interpret()) Skipped 6 previous similar messages [ 2809.768430] LustreError: 39809:0:(client.c:3419:ptlrpc_replay_interpret()) @@@ status 301, old was 0 req@0000000076840faf x1862024417015936/t8589934651(8589934651) o101->lustre-MDT0000-mdc-ffff8e18c529a000@192.168.206.158@tcp:12/10 lens 592/608 e 0 to 0 dl 1775765858 ref 2 fl Interpret:RQU/604/0 rc 301/301 job:'' uid:0 gid:0 projid:0 [ 2809.801591] LustreError: 39809:0:(client.c:3419:ptlrpc_replay_interpret()) Skipped 12 previous similar messages [ 2813.548816] LustreError: 39809:0:(mdc_request.c:668:mdc_replay_open()) @@@ cannot properly replay without open data req@0000000097899776 x1862024417019776/t8589934663(8589934663) o101->lustre-MDT0000-mdc-ffff8e18c529a000@192.168.206.158@tcp:12/10 lens 592/608 e 0 to 0 dl 1775765862 ref 1 fl Interpret:RMQU/604/0 rc 301/301 job:'' uid:0 gid:0 projid:0 [ 2826.295170] LustreError: 39809:0:(client.c:3419:ptlrpc_replay_interpret()) @@@ status 301, old was 0 req@00000000bf51413b x1862024417032576/t8589934703(8589934703) o101->lustre-MDT0000-mdc-ffff8e18c529a000@192.168.206.158@tcp:12/10 lens 592/608 e 0 to 0 dl 1775765874 ref 2 fl Interpret:RQU/604/0 rc 301/301 job:'' uid:0 gid:0 projid:0 [ 2826.348710] LustreError: 39809:0:(client.c:3419:ptlrpc_replay_interpret()) Skipped 25 previous similar messages [ 2851.343324] LustreError: lustre-MDT0000-mdc-ffff8e18c529a000: operation mds_close to node 192.168.206.158@tcp failed: rc = -107 [ 2851.464871] Lustre: lustre-MDT0000-mdc-ffff8e18c8797000: Connection restored to 192.168.206.158@tcp (at 192.168.206.158@tcp) [ 2852.329890] LustreError: lustre-MDT0000-mdc-ffff8e18c529a000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 2852.615654] LustreError: 61084:0:(file.c:6097:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -5 [ 2853.408502] Lustre: lustre-MDT0000-mdc-ffff8e18c529a000: Connection restored to 192.168.206.158@tcp (at 192.168.206.158@tcp) [ 2861.915763] LustreError: 61178:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8e18c529a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2862.006634] Lustre: Unmounted lustre-client [ 2864.207746] LustreError: 61198:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8e18c8797000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2864.218439] LustreError: 61198:0:(lov_obd.c:786:lov_cleanup()) Skipped 1 previous similar message [ 2864.283477] Lustre: Unmounted lustre-client [ 2906.241492] Lustre: DEBUG MARKER: == conf-sanity test 85: osd_ost init: fail ea_fid_set ==== 16:18:57 (1775765937) [ 2972.012753] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2984.920747] Lustre: DEBUG MARKER: == conf-sanity test 86: Replacing mkfs.lustre -G option == 16:20:15 (1775766015) [ 3026.275902] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3148.180976] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3153.569708] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3179.348882] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3223.935523] Lustre: DEBUG MARKER: == conf-sanity test 87: check if MDT inode can hold EAs with N stripes properly ========================================================== 16:24:15 (1775766255) [ 3227.852441] Key type lgssc unregistered [ 3228.198838] LNet: 68381:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3228.205726] LNetError: 68381:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3228.223606] LNet: Removed LNI 192.168.206.58@tcp [ 3229.347143] Key type .llcrypt unregistered [ 3229.350411] Key type ._llcrypt unregistered [ 3259.267405] Key type ._llcrypt registered [ 3259.270213] Key type .llcrypt registered [ 3259.634851] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3259.647189] alg: No test for adler32 (adler32-zlib) [ 3260.775671] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [ 3261.070609] LNet: Added LNI 192.168.206.58@tcp [8/256/0/180] [ 3262.751363] Key type lgssc registered [ 3264.265813] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3392.602489] Lustre: Mounted lustre-client [ 3394.041645] LustreError: 71633:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8e18c4c68800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3394.178519] Lustre: Unmounted lustre-client [ 3431.746498] Lustre: DEBUG MARKER: == conf-sanity test 88: check the default mount options can be overridden ========================================================== 16:27:43 (1775766463) [ 3474.537528] Lustre: DEBUG MARKER: == conf-sanity test 89: check tunefs --param and --erase-param{s} options ========================================================== 16:28:25 (1775766505) [ 3604.038269] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL failover.node=192.0.2.254@tcp0,mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3608.795267] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL failover.node=192.0.2.254@tcp0,mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3629.112508] Lustre: DEBUG MARKER: oleg658-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 [ 3681.390706] Lustre: DEBUG MARKER: == conf-sanity test 90a: check max_mod_rpcs_in_flight is enforced ========================================================== 16:31:52 (1775766712) [ 3718.798277] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3724.575920] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3744.927047] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3746.044807] Lustre: Mounted lustre-client [ 3769.823342] Lustre: 79914:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1775766786/real 1775766786] req@00000000db9fa832 x1862025917525632/t0(0) o36->lustre-MDT0000-mdc-ffff8e18c5f2e000@192.168.206.158@tcp:12/10 lens 488/512 e 0 to 1 dl 1775766802 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3769.824146] Lustre: lustre-MDT0000-mdc-ffff8e18c5f2e000: Connection to lustre-MDT0000 (at 192.168.206.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3769.856095] Lustre: 79914:0:(client.c:2479:ptlrpc_expire_one_request()) Skipped 3 previous similar messages [ 3769.939826] Lustre: lustre-MDT0000-mdc-ffff8e18c5f2e000: Connection restored to 192.168.206.158@tcp (at 192.168.206.158@tcp) [ 3787.743243] Lustre: 79949:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1775766804/real 1775766804] req@0000000007bed405 x1862025917532032/t0(0) o36->lustre-MDT0000-mdc-ffff8e18c5f2e000@192.168.206.158@tcp:12/10 lens 488/512 e 0 to 1 dl 1775766820 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3787.743493] Lustre: lustre-MDT0000-mdc-ffff8e18c5f2e000: Connection to lustre-MDT0000 (at 192.168.206.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3787.796290] Lustre: 79949:0:(client.c:2479:ptlrpc_expire_one_request()) Skipped 5 previous similar messages [ 3787.846165] Lustre: lustre-MDT0000-mdc-ffff8e18c5f2e000: Connection restored to 192.168.206.158@tcp (at 192.168.206.158@tcp) [ 3790.957847] LustreError: 79992:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8e18c5f2e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3790.977696] LustreError: 79992:0:(lov_obd.c:786:lov_cleanup()) Skipped 1 previous similar message [ 3791.176612] Lustre: Unmounted lustre-client [ 3824.268465] Key type lgssc unregistered [ 3824.688978] LNet: 80396:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3824.703853] LNetError: 80396:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3824.758404] LNet: Removed LNI 192.168.206.58@tcp [ 3825.834536] Key type .llcrypt unregistered [ 3825.841942] Key type ._llcrypt unregistered [ 3844.580545] Key type ._llcrypt registered [ 3844.582970] Key type .llcrypt registered [ 3846.357096] Lustre: DEBUG MARKER: == conf-sanity test 90b: check max_mod_rpcs_in_flight is enforced after update ========================================================== 16:34:37 (1775766877) [ 3849.562498] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3849.615363] alg: No test for adler32 (adler32-zlib) [ 3850.745350] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [ 3851.003438] LNet: Added LNI 192.168.206.58@tcp [8/256/0/180] [ 3852.735565] Key type lgssc registered [ 3854.501761] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3908.002975] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3912.397525] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3932.723607] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3935.615849] Lustre: Mounted lustre-client [ 3959.775639] Lustre: 83516:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1775766976/real 1775766976] req@0000000075735165 x1862026535117824/t0(0) o36->lustre-MDT0000-mdc-ffff8e18c575e800@192.168.206.158@tcp:12/10 lens 488/512 e 0 to 1 dl 1775766992 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3959.813634] Lustre: lustre-MDT0000-mdc-ffff8e18c575e800: Connection to lustre-MDT0000 (at 192.168.206.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3959.909572] Lustre: lustre-MDT0000-mdc-ffff8e18c575e800: Connection restored to 192.168.206.158@tcp (at 192.168.206.158@tcp) [ 3978.720652] Lustre: 83563:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1775766995/real 1775766995] req@0000000075735165 x1862026535131136/t0(0) o36->lustre-MDT0001-mdc-ffff8e18c575e800@192.168.206.158@tcp:12/10 lens 488/512 e 0 to 1 dl 1775767011 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3978.724354] Lustre: lustre-MDT0001-mdc-ffff8e18c575e800: Connection to lustre-MDT0001 (at 192.168.206.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3978.771680] Lustre: 83563:0:(client.c:2479:ptlrpc_expire_one_request()) Skipped 3 previous similar messages [ 3978.815837] Lustre: lustre-MDT0001-mdc-ffff8e18c575e800: Connection restored to 192.168.206.158@tcp (at 192.168.206.158@tcp) [ 3997.663211] Lustre: 83598:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1775767014/real 1775767014] req@00000000d0efa8d8 x1862026535136384/t0(0) o36->lustre-MDT0001-mdc-ffff8e18c575e800@192.168.206.158@tcp:12/10 lens 488/512 e 0 to 1 dl 1775767030 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3997.664031] Lustre: lustre-MDT0001-mdc-ffff8e18c575e800: Connection to lustre-MDT0001 (at 192.168.206.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3997.723836] Lustre: 83598:0:(client.c:2479:ptlrpc_expire_one_request()) Skipped 3 previous similar messages [ 3997.812199] Lustre: lustre-MDT0001-mdc-ffff8e18c575e800: Connection restored to 192.168.206.158@tcp (at 192.168.206.158@tcp) [ 4003.387230] LustreError: 83648:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8e18c575e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4003.517638] Lustre: Unmounted lustre-client [ 4005.045362] Lustre: Mounted lustre-client [ 4024.287302] Lustre: 83737:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1775767041/real 1775767041] req@00000000245a98da x1862026535171584/t0(0) o36->lustre-MDT0000-mdc-ffff8e18c5686000@192.168.206.158@tcp:12/10 lens 488/512 e 0 to 1 dl 1775767057 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4024.288878] Lustre: lustre-MDT0000-mdc-ffff8e18c5686000: Connection to lustre-MDT0000 (at 192.168.206.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4024.338606] Lustre: 83737:0:(client.c:2479:ptlrpc_expire_one_request()) Skipped 2 previous similar messages [ 4024.396264] Lustre: lustre-MDT0000-mdc-ffff8e18c5686000: Connection restored to 192.168.206.158@tcp (at 192.168.206.158@tcp) [ 4042.719252] Lustre: 83789:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1775767059/real 1775767059] req@00000000c70fc10d x1862026535188480/t0(0) o36->lustre-MDT0000-mdc-ffff8e18c5686000@192.168.206.158@tcp:12/10 lens 488/512 e 0 to 1 dl 1775767075 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4042.719756] Lustre: lustre-MDT0000-mdc-ffff8e18c5686000: Connection to lustre-MDT0000 (at 192.168.206.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4042.757068] Lustre: 83789:0:(client.c:2479:ptlrpc_expire_one_request()) Skipped 3 previous similar messages [ 4042.836354] Lustre: lustre-MDT0000-mdc-ffff8e18c5686000: Connection restored to 192.168.206.158@tcp (at 192.168.206.158@tcp) [ 4049.104684] LustreError: 83855:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8e18c5686000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4049.249047] Lustre: Unmounted lustre-client [ 4072.242991] Key type lgssc unregistered [ 4072.560946] LNet: 84259:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4072.569316] LNetError: 84259:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4072.588692] LNet: Removed LNI 192.168.206.58@tcp [ 4073.383913] Key type .llcrypt unregistered [ 4073.386798] Key type ._llcrypt unregistered [ 4087.993313] Key type ._llcrypt registered [ 4087.999696] Key type .llcrypt registered [ 4089.699993] Lustre: DEBUG MARKER: == conf-sanity test 90c: check max_mod_rpcs_in_flight update limits ========================================================== 16:38:40 (1775767120) [ 4092.657370] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4092.676840] alg: No test for adler32 (adler32-zlib) [ 4093.893495] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [ 4094.177959] LNet: Added LNI 192.168.206.58@tcp [8/256/0/180] [ 4095.943242] Key type lgssc registered [ 4097.414252] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4149.134419] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4153.747558] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4178.652687] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4181.479278] Lustre: Mounted lustre-client [ 4189.022630] LustreError: 87392:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8e18d29e7000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4189.108167] Lustre: Unmounted lustre-client [ 4190.804919] Lustre: Mounted lustre-client [ 4190.978875] LustreError: 87452:0:(genops.c:2261:obd_set_max_mod_rpcs_in_flight()) lustre-MDT0000-mdc-ffff8e18c4a0d000: 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. [ 4196.286394] LustreError: 87487:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8e18c4a0d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4196.367044] Lustre: Unmounted lustre-client [ 4196.936837] Lustre: Mounted lustre-client [ 4201.854093] LustreError: 87600:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8e18c7ce6800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4201.960590] Lustre: Unmounted lustre-client [ 4248.322672] Key type lgssc unregistered [ 4248.562093] LNet: 88006:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4248.579848] LNetError: 88006:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4248.597361] LNet: Removed LNI 192.168.206.58@tcp [ 4249.452346] Key type .llcrypt unregistered [ 4249.458480] Key type ._llcrypt unregistered [ 4268.003058] Key type ._llcrypt registered [ 4268.005590] Key type .llcrypt registered [ 4269.657976] Lustre: DEBUG MARKER: == conf-sanity test 90d: check one close RPC is allowed above max_mod_rpcs_in_flight ========================================================== 16:41:40 (1775767300) [ 4272.447840] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4272.474228] alg: No test for adler32 (adler32-zlib) [ 4273.528552] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [ 4273.756442] LNet: Added LNI 192.168.206.58@tcp [8/256/0/180] [ 4275.431175] Key type lgssc registered [ 4276.926070] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4325.956625] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4329.628471] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4348.897699] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4350.856788] Lustre: Mounted lustre-client [ 4371.423187] Lustre: 91121:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1775767388/real 1775767388] req@00000000d6daa559 x1862026978673664/t0(0) o36->lustre-MDT0000-mdc-ffff8e18c8791000@192.168.206.158@tcp:12/10 lens 488/512 e 0 to 1 dl 1775767404 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4371.423187] Lustre: 91119:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1775767388/real 1775767388] req@00000000372dbe87 x1862026978673408/t0(0) o36->lustre-MDT0000-mdc-ffff8e18c8791000@192.168.206.158@tcp:12/10 lens 488/512 e 0 to 1 dl 1775767404 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4371.423307] Lustre: lustre-MDT0000-mdc-ffff8e18c8791000: Connection to lustre-MDT0000 (at 192.168.206.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4371.465592] Lustre: 91121:0:(client.c:2479:ptlrpc_expire_one_request()) Skipped 1 previous similar message [ 4371.544752] Lustre: lustre-MDT0000-mdc-ffff8e18c8791000: Connection restored to 192.168.206.158@tcp (at 192.168.206.158@tcp) [ 4374.252962] LustreError: 91165:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8e18c8791000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4374.365751] Lustre: Unmounted lustre-client [ 4405.371975] Key type lgssc unregistered [ 4405.800372] LNet: 91570:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4405.829584] LNetError: 91570:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4405.851160] LNet: Removed LNI 192.168.206.58@tcp [ 4406.773050] Key type .llcrypt unregistered [ 4406.776081] Key type ._llcrypt unregistered [ 4423.049728] Key type ._llcrypt registered [ 4423.052945] Key type .llcrypt registered [ 4424.551912] Lustre: DEBUG MARKER: == conf-sanity test 91: evict-by-nid support ============= 16:44:15 (1775767455) [ 4427.123487] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4427.157721] alg: No test for adler32 (adler32-zlib) [ 4428.424683] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [ 4428.885818] LNet: Added LNI 192.168.206.58@tcp [8/256/0/180] [ 4430.743218] Key type lgssc registered [ 4432.749643] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4485.868745] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4489.905578] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4512.822954] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4514.840167] Lustre: Mounted lustre-client [ 4521.359145] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4531.746611] Lustre: Unmounted lustre-client [ 4590.312483] Key type lgssc unregistered [ 4590.721768] LNet: 95189:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4590.730442] LNetError: 95189:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4590.743319] LNet: Removed LNI 192.168.206.58@tcp [ 4591.553116] Key type .llcrypt unregistered [ 4591.562423] Key type ._llcrypt unregistered [ 4609.394775] Key type ._llcrypt registered [ 4609.397376] Key type .llcrypt registered [ 4610.934831] Lustre: DEBUG MARKER: == conf-sanity test 92: ldev returns MGS NID correctly in command substitution ========================================================== 16:47:22 (1775767642) [ 4621.896631] Lustre: DEBUG MARKER: == conf-sanity test 93: register mulitple MDT at the same time ========================================================== 16:47:33 (1775767653) [ 4623.521176] Lustre: DEBUG MARKER: SKIP: conf-sanity test_93 needs >= 3 MDTs [ 4625.718075] Lustre: DEBUG MARKER: == conf-sanity test 94: ldev outputs correct labels for file system name query ========================================================== 16:47:36 (1775767656) [ 4634.965052] Lustre: DEBUG MARKER: == conf-sanity test 95: ldev should only allow one label filter ========================================================== 16:47:46 (1775767666) [ 4646.218195] Lustre: DEBUG MARKER: == conf-sanity test 96: ldev returns hostname and backend fs correctly in command sub ========================================================== 16:47:57 (1775767677) [ 4656.879641] Lustre: DEBUG MARKER: == conf-sanity test 97: ldev returns correct ouput when querying based on role ========================================================== 16:48:08 (1775767688) [ 4667.562717] Lustre: DEBUG MARKER: == conf-sanity test 98: Buffer-overflow check while parsing mount_opts ========================================================== 16:48:18 (1775767698) [ 4670.417304] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4670.464660] alg: No test for adler32 (adler32-zlib) [ 4671.690376] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [ 4671.968391] LNet: Added LNI 192.168.206.58@tcp [8/256/0/180] [ 4673.711316] Key type lgssc registered [ 4675.657368] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4734.341229] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4739.320923] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4762.079071] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4765.087275] Lustre: Mounted lustre-client [ 4770.448692] LustreError: 102527:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8e18c5c42800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4770.624195] Lustre: Unmounted lustre-client [ 4800.480752] Key type lgssc unregistered [ 4800.860574] LNet: 102931:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4800.872190] LNetError: 102931:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4801.904355] LNet: Removed LNI 192.168.206.58@tcp [ 4802.871501] Key type .llcrypt unregistered [ 4802.873652] Key type ._llcrypt unregistered [ 4824.019489] Key type ._llcrypt registered [ 4824.022340] Key type .llcrypt registered [ 4825.867252] Lustre: DEBUG MARKER: == conf-sanity test 99: Adding meta_bg option ============ 16:50:56 (1775767856) [ 4828.887751] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4828.925353] alg: No test for adler32 (adler32-zlib) [ 4830.193951] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [ 4830.402038] LNet: Added LNI 192.168.206.58@tcp [8/256/0/180] [ 4832.128679] Key type lgssc registered [ 4834.087353] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4870.625873] Lustre: DEBUG MARKER: == conf-sanity test 100: check lshowmount lists MGS, MDT, OST and 0@lo ========================================================== 16:51:42 (1775767902) [ 4980.485163] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4985.084566] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5008.529612] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5011.424991] Lustre: Mounted lustre-client [ 5017.882898] LustreError: 108766:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8e18c8967000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5017.968351] Lustre: Unmounted lustre-client [ 5047.079294] Key type lgssc unregistered [ 5047.340993] LNet: 109169:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5047.350473] LNetError: 109169:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5047.374516] LNet: Removed LNI 192.168.206.58@tcp [ 5048.112371] Key type .llcrypt unregistered [ 5048.114615] Key type ._llcrypt unregistered [ 5065.098479] Key type ._llcrypt registered [ 5065.100872] Key type .llcrypt registered [ 5066.746841] Lustre: DEBUG MARKER: == conf-sanity test 101a: Race MDT->OST reconnection with create ========================================================== 16:54:57 (1775768097) [ 5069.682500] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5069.713083] alg: No test for adler32 (adler32-zlib) [ 5070.870605] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [ 5071.095061] LNet: Added LNI 192.168.206.58@tcp [8/256/0/180] [ 5072.752219] Key type lgssc registered [ 5074.790839] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5135.824580] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5140.359850] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5160.894406] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5163.978491] Lustre: Mounted lustre-client [ 7456.631605] LustreError: 121267:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8e18c5c47800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7456.753578] Lustre: Unmounted lustre-client [ 7493.393032] Key type lgssc unregistered [ 7493.770218] LNet: 121674:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7493.789510] LNetError: 121674:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7493.811268] LNet: Removed LNI 192.168.206.58@tcp [ 7494.835641] Key type .llcrypt unregistered [ 7494.850129] Key type ._llcrypt unregistered [ 7512.765314] Key type ._llcrypt registered [ 7512.768481] Key type .llcrypt registered [ 7514.215351] Lustre: DEBUG MARKER: == conf-sanity test 101b: Race events DISCONNECT and ACTIVE in osp ========================================================== 17:35:45 (1775770545) [ 7516.789188] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7516.818599] alg: No test for adler32 (adler32-zlib) [ 7518.084376] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [ 7518.458849] LNet: Added LNI 192.168.206.58@tcp [8/256/0/180] [ 7520.168241] Key type lgssc registered [ 7521.328784] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7571.328136] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7575.373757] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7596.963364] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7598.754906] Lustre: Mounted lustre-client [ 7609.323724] Lustre: lustre-OST0000-osc-ffff8e18c413c000: Connection to lustre-OST0000 (at 192.168.206.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7658.628419] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7660.366683] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7665.076963] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8e18c413c000.ost_server_uuid 50 [ 7667.319818] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8e18c413c000.ost_server_uuid in FULL state after 0 sec [ 7670.126547] LustreError: 125830:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8e18c413c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7670.252538] Lustre: Unmounted lustre-client [ 7698.614521] Key type lgssc unregistered [ 7698.875695] LNet: 126234:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7698.881224] LNetError: 126234:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7698.893718] LNet: Removed LNI 192.168.206.58@tcp [ 7699.648145] Key type .llcrypt unregistered [ 7699.651851] Key type ._llcrypt unregistered [ 7716.098457] Key type ._llcrypt registered [ 7716.100823] Key type .llcrypt registered [ 7718.171049] Lustre: DEBUG MARKER: SKIP: conf-sanity test_102 skipping excluded test 102 [ 7720.316274] Lustre: DEBUG MARKER: == conf-sanity test 103: rename filesystem name ========== 17:39:11 (1775770751) [ 7730.372365] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7730.411701] alg: No test for adler32 (adler32-zlib) [ 7731.613928] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [ 7731.983783] LNet: Added LNI 192.168.206.58@tcp [8/256/0/180] [ 7733.759228] Key type lgssc registered [ 7736.057787] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7822.361077] Lustre: Mounted lustre-client [ 7828.301996] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 7847.909502] Lustre: lustre-OST0000-osc-ffff8e18d174e000: disconnect after 22s idle [ 7854.017255] LustreError: 129729:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8e18d174e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7854.136028] Lustre: Unmounted lustre-client [ 7979.701073] Lustre: Mounted mylustre-client [ 7986.297920] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 8004.245344] LustreError: 132059:0:(lov_obd.c:786:lov_cleanup()) mylustre-clilov-ffff8e18c53ab000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8004.257287] LustreError: 132059:0:(lov_obd.c:786:lov_cleanup()) Skipped 1 previous similar message [ 8004.398985] Lustre: Unmounted mylustre-client [ 8124.624523] Lustre: Mounted tfs-client [ 8131.096615] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 8145.884693] LustreError: 134375:0:(lov_obd.c:786:lov_cleanup()) tfs-clilov-ffff8e18d3631800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8145.900507] LustreError: 134375:0:(lov_obd.c:786:lov_cleanup()) Skipped 1 previous similar message [ 8145.985016] Lustre: Unmounted tfs-client [ 8267.659770] Lustre: Mounted lustre-client [ 8273.837849] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 8290.437523] Lustre: DEBUG MARKER: == conf-sanity test 104a: Make sure user defined options are reflected in mount ========================================================== 17:48:41 (1775771321) [ 8293.343470] Lustre: lustre-OST0000-osc-ffff8e18c529b800: disconnect after 23s idle [ 8293.857901] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 8304.290512] Lustre: Unmounted lustre-client [ 8461.303492] Lustre: client wants to enable acl, but mdt not! [ 8461.359930] Lustre: Mounted lustre-client [ 8470.441961] Lustre: DEBUG MARKER: == conf-sanity test 104b: Mount uses last flock argument ========================================================== 17:51:41 (1775771501) [ 8471.173928] Lustre: client wants to enable acl, but mdt not! [ 8471.221111] Lustre: Mounted lustre-client [ 8473.809988] LustreError: 140671:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8e18d174c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8473.814403] LustreError: 140671:0:(lov_obd.c:786:lov_cleanup()) Skipped 1 previous similar message [ 8473.943487] Lustre: Unmounted lustre-client [ 8474.578172] Lustre: client wants to enable acl, but mdt not! [ 8477.644131] Lustre: client wants to enable acl, but mdt not! [ 8488.193146] LustreError: 141221:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8e18c717a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8488.221473] LustreError: 141221:0:(lov_obd.c:786:lov_cleanup()) Skipped 3 previous similar messages [ 8488.347103] Lustre: Unmounted lustre-client [ 8488.356831] Lustre: Skipped 1 previous similar message [ 8490.409722] Lustre: DEBUG MARKER: == conf-sanity test 105: check file creation for ro and rw bind mnt pt ========================================================== 17:52:01 (1775771521) [ 8493.085953] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 8493.092157] Lustre: Skipped 3 previous similar messages [ 8549.256084] Key type lgssc unregistered [ 8549.585347] LNet: 141800:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8549.598566] LNetError: 141800:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8549.612362] LNet: Removed LNI 192.168.206.58@tcp [ 8550.341897] Key type .llcrypt unregistered [ 8550.349547] Key type ._llcrypt unregistered [ 8571.686181] Key type ._llcrypt registered [ 8571.695328] Key type .llcrypt registered [ 8572.320568] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8572.343823] alg: No test for adler32 (adler32-zlib) [ 8573.516891] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [ 8573.819923] LNet: Added LNI 192.168.206.58@tcp [8/256/0/180] [ 8575.663235] Key type lgssc registered [ 8577.391405] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8659.257913] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8663.811053] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8687.265816] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8690.148416] Lustre: Mounted lustre-client [ 8690.578241] Lustre: Remounted lustre-client read-only [ 8692.709876] LustreError: 145552:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8e18d2477800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8692.843263] Lustre: Unmounted lustre-client [ 8724.945533] Key type lgssc unregistered [ 8725.314881] LNet: 145957:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8725.327507] LNetError: 145957:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8725.378484] LNet: Removed LNI 192.168.206.58@tcp [ 8726.432537] Key type .llcrypt unregistered [ 8726.440483] Key type ._llcrypt unregistered [ 8746.187784] Key type ._llcrypt registered [ 8746.191777] Key type .llcrypt registered [ 8747.970870] Lustre: DEBUG MARKER: SKIP: conf-sanity test_106 skipping SLOW test 106 [ 8750.419809] Lustre: DEBUG MARKER: == conf-sanity test 107: Unknown config param should not fail target mounting ========================================================== 17:56:21 (1775771781) [ 8754.781286] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8754.866032] alg: No test for adler32 (adler32-zlib) [ 8756.233083] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [ 8756.496290] LNet: Added LNI 192.168.206.58@tcp [8/256/0/180] [ 8758.175248] Key type lgssc registered [ 8759.489322] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8816.601729] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8821.348851] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8843.341556] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8874.672196] Key type lgssc unregistered [ 8874.886380] LNet: 149507:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8874.894810] LNetError: 149507:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8874.929661] LNet: Removed LNI 192.168.206.58@tcp [ 8875.706505] Key type .llcrypt unregistered [ 8875.708588] Key type ._llcrypt unregistered [ 8889.145597] Key type ._llcrypt registered [ 8889.187165] Key type .llcrypt registered [ 8889.944132] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8889.980683] alg: No test for adler32 (adler32-zlib) [ 8891.226927] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [ 8891.568411] LNet: Added LNI 192.168.206.58@tcp [8/256/0/180] [ 8893.304905] Key type lgssc registered [ 8895.152412] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8992.431583] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9006.891680] Key type lgssc unregistered [ 9007.223587] LNet: 151458:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9007.231300] LNetError: 151458:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9007.243912] LNet: Removed LNI 192.168.206.58@tcp [ 9008.168672] Key type .llcrypt unregistered [ 9008.170439] Key type ._llcrypt unregistered [ 9026.742243] Key type ._llcrypt registered [ 9026.744404] Key type .llcrypt registered [ 9028.783029] Lustre: DEBUG MARKER: == conf-sanity test 108a: migrate from ldiskfs to ZFS ==== 18:00:59 (1775772059) [ 9031.684965] Lustre: DEBUG MARKER: SKIP: conf-sanity test_108a zfs only test [ 9034.245596] Lustre: DEBUG MARKER: == conf-sanity test 108b: migrate from ZFS to ldiskfs ==== 18:01:05 (1775772065) [ 9051.135595] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9051.152454] alg: No test for adler32 (adler32-zlib) [ 9052.325952] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [ 9052.717650] LNet: Added LNI 192.168.206.58@tcp [8/256/0/180] [ 9054.511460] Key type lgssc registered [ 9056.209313] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9141.061246] Lustre: Mounted lustre-client [ 9142.055839] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 9142.088611] LustreError: 154055:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff8e18d158f800: inode [0x200000bd1:0x2:0x0] mdc close failed: rc = -108 [ 9142.121443] Lustre: 152856:0:(llite_lib.c:4149:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.206.158@tcp:/lustre/fid: [0x200000bd1:0x2:0x0]/ may get corrupted (rc -108) [ 9142.141844] Lustre: 152856:0:(llite_lib.c:4149:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.206.158@tcp:/lustre/fid: [0x240000402:0x3:0x0]/ may get corrupted (rc -108) [ 9152.594127] Lustre: Unmounted lustre-client [ 9193.386764] Lustre: DEBUG MARKER: == conf-sanity test 109a: test lctl clear_conf fsname ==== 18:03:44 (1775772224) [ 9313.164336] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9317.846661] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9343.204683] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9344.034194] Lustre: Mounted lustre-client [ 9404.410838] LustreError: 158533:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8e18c4b69000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9404.493276] Lustre: Unmounted lustre-client [ 9502.825741] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9508.453140] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9536.189115] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9542.229182] Lustre: Mounted lustre-client [ 9570.074796] LustreError: 160432:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8e18c4c65000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9570.213045] Lustre: Unmounted lustre-client [ 9607.876981] Key type lgssc unregistered [ 9608.182521] LNet: 160836:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9608.192632] LNetError: 160836:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9608.204607] LNet: Removed LNI 192.168.206.58@tcp [ 9609.083881] Key type .llcrypt unregistered [ 9609.090492] Key type ._llcrypt unregistered [ 9628.092839] Key type ._llcrypt registered [ 9628.098628] Key type .llcrypt registered [ 9630.064505] Lustre: DEBUG MARKER: == conf-sanity test 109b: test lctl clear_conf one config ========================================================== 18:11:00 (1775772660) [ 9663.709051] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9663.748620] alg: No test for adler32 (adler32-zlib) [ 9664.951975] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [ 9665.214335] LNet: Added LNI 192.168.206.58@tcp [8/256/0/180] [ 9667.015359] Key type lgssc registered [ 9668.088235] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9756.770468] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9761.779764] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9785.597549] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9787.474247] Lustre: Mounted lustre-client [ 9857.443402] LustreError: 165749:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8e18d0281800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9857.519520] Lustre: Unmounted lustre-client [ 9937.512550] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9941.903502] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9960.692453] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9961.341839] Lustre: Mounted lustre-client [ 9987.049231] LustreError: 167642:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8e18c7ce6000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9987.158235] Lustre: Unmounted lustre-client [10020.088185] Key type lgssc unregistered [10020.649110] LNet: 168047:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10020.670073] LNetError: 168047:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10020.702107] LNet: Removed LNI 192.168.206.58@tcp [10021.883288] Key type .llcrypt unregistered [10021.898063] Key type ._llcrypt unregistered [10040.954551] Key type ._llcrypt registered [10040.956176] Key type .llcrypt registered [10042.995502] Lustre: DEBUG MARKER: == conf-sanity test 110: Adding large_dir with 3-level htree ========================================================== 18:17:53 (1775773073) [10061.554904] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10061.575719] alg: No test for adler32 (adler32-zlib) [10063.022937] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [10063.355427] LNet: Added LNI 192.168.206.58@tcp [8/256/0/180] [10065.219152] Key type lgssc registered [10067.417214] Lustre: Echo OBD driver; http://www.lustre.org/ [10155.033995] Lustre: Mounted lustre-client [10157.550812] Lustre: Mounted lustre-client [11185.506582] LustreError: 172135:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8e18c7ce3000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11185.545801] Lustre: Unmounted lustre-client [11188.116786] LustreError: 172149:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8e18c53a8800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11188.175819] Lustre: Unmounted lustre-client [11310.274801] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11315.869818] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11337.070336] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11391.820214] Lustre: DEBUG MARKER: SKIP: conf-sanity test_111 skipping SLOW test 111 [11393.838994] Lustre: DEBUG MARKER: == conf-sanity test 112a: mount OST with no_create option ========================================================== 18:40:25 (1775774425) [11430.397150] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11434.379569] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11450.665224] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11473.921473] Lustre: Mounted lustre-client [11482.203030] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8e18c53a8000.ost_server_uuid 50 [11484.533790] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8e18c53a8000.ost_server_uuid in FULL state after 0 sec [11488.882691] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8e18c53a8000.ost_server_uuid 50 [11490.557136] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8e18c53a8000.ost_server_uuid in FULL state after 0 sec [11509.749927] Lustre: lustre-OST0001-osc-ffff8e18c53a8000: Connection to lustre-OST0001 (at 192.168.206.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [11519.081650] LustreError: 179013:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8e18c53a8000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11519.235216] Lustre: Unmounted lustre-client [11553.475541] Key type lgssc unregistered [11553.815423] LNet: 179419:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11553.830703] LNetError: 179419:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11553.862558] LNet: Removed LNI 192.168.206.58@tcp [11554.940346] Key type .llcrypt unregistered [11554.943547] Key type ._llcrypt unregistered [11577.954234] Key type ._llcrypt registered [11577.957308] Key type .llcrypt registered [11580.601430] Lustre: DEBUG MARKER: == conf-sanity test 112b: mount MDT with no_create option ========================================================== 18:43:30 (1775774610) [11584.258385] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11584.316250] alg: No test for adler32 (adler32-zlib) [11585.838714] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [11586.283102] LNet: Added LNI 192.168.206.58@tcp [8/256/0/180] [11588.135228] Key type lgssc registered [11590.071944] Lustre: Echo OBD driver; http://www.lustre.org/ [11622.900720] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11663.272188] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11687.339572] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [11689.564212] Lustre: Mounted lustre-client [11819.027314] LustreError: 182843:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8e18f1515800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11819.092292] Lustre: Unmounted lustre-client [11847.409419] Key type lgssc unregistered [11847.744171] LNet: 183246:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11847.750062] LNetError: 183246:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11847.780395] LNet: Removed LNI 192.168.206.58@tcp [11848.739316] Key type .llcrypt unregistered [11848.743868] Key type ._llcrypt unregistered [11868.446768] Key type ._llcrypt registered [11868.452241] Key type .llcrypt registered [11870.282320] Lustre: DEBUG MARKER: == conf-sanity test 113: Shadow mountpoint correctly report ro/rw for mounts ========================================================== 18:48:21 (1775774901) [11895.470454] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11895.486797] alg: No test for adler32 (adler32-zlib) [11896.586898] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [11896.883742] LNet: Added LNI 192.168.206.58@tcp [8/256/0/180] [11898.591637] Key type lgssc registered [11900.177497] Lustre: Echo OBD driver; http://www.lustre.org/ [11982.573378] Lustre: Mounted lustre-client [11988.755596] Lustre: DEBUG MARKER: Using TIMEOUT=20 [12008.416561] Lustre: lustre-OST0000-osc-ffff8e18c6e4b800: disconnect after 23s idle [12012.433456] LustreError: 187176:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8e18c6e4b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12012.552172] Lustre: Unmounted lustre-client [12131.530611] Lustre: Mounted lustre-client [12137.650357] Lustre: DEBUG MARKER: Using TIMEOUT=20 [12149.458302] LustreError: 189415:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8e18c7ce0000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12149.471606] LustreError: 189415:0:(lov_obd.c:786:lov_cleanup()) Skipped 1 previous similar message [12149.614018] Lustre: Unmounted lustre-client [12198.528732] Lustre: DEBUG MARKER: SKIP: conf-sanity test_114 skipping SLOW test 114 [12201.117288] Lustre: DEBUG MARKER: SKIP: conf-sanity test_115 skipping excluded test 115 [12204.112882] Lustre: DEBUG MARKER: == conf-sanity test 116: big size MDT support ============ 18:53:54 (1775775234) [12441.207530] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12445.548739] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12466.962807] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12527.214851] Lustre: DEBUG MARKER: == conf-sanity test 117: lctl get_param return errors properly ========================================================== 18:59:18 (1775775558) [12560.839569] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12564.783942] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12584.227690] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12584.816801] Lustre: Mounted lustre-client [12590.241501] LustreError: 196561:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8e18c5687000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12590.253011] LustreError: 196561:0:(lov_obd.c:786:lov_cleanup()) Skipped 1 previous similar message [12590.376476] Lustre: Unmounted lustre-client [12617.365847] Key type lgssc unregistered [12617.688929] LNet: 196965:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12617.706464] LNetError: 196965:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12617.744665] LNet: Removed LNI 192.168.206.58@tcp [12618.445336] Key type .llcrypt unregistered [12618.455989] Key type ._llcrypt unregistered [12637.852406] Key type ._llcrypt registered [12637.856619] Key type .llcrypt registered [12639.456770] Lustre: DEBUG MARKER: == conf-sanity test 119: writeconf on slave mdt shouldn't duplicate mdc/osp and crash ========================================================== 19:01:10 (1775775670) [12646.097472] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12646.110193] alg: No test for adler32 (adler32-zlib) [12647.185125] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [12647.525095] LNet: Added LNI 192.168.206.58@tcp [8/256/0/180] [12649.239129] Key type lgssc registered [12650.744293] Lustre: Echo OBD driver; http://www.lustre.org/ [12701.835423] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12706.304341] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12730.340796] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12733.403547] Lustre: Mounted lustre-client [12743.653067] Lustre: lustre-MDT0001-mdc-ffff8e18c8961000: Connection to lustre-MDT0001 (at 192.168.206.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [12769.320565] LustreError: 200366:0:(obd_config.c:2101:class_config_llog_handler()) MGC192.168.206.158@tcp: cfg command failed: rc = -17 [12769.329883] Lustre: cmd=cf001 0:lustre-MDT0001-mdc 1:mdc 2:lustre-clilmv_UUID [12769.329883] [12769.349166] LustreError: 199998:0:(mgc_request.c:614:do_requeue()) failed processing log: -17 [13076.470260] Lustre: lustre-MDT0001-mdc-ffff8e18c8961000: Connection to lustre-MDT0001 (at 192.168.206.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [13105.720673] LustreError: 201780:0:(obd_config.c:760:class_setup()) Device 4 already setup (type mdc) [13105.727962] LustreError: 201780:0:(obd_config.c:864:class_detach()) OBD device 4 still set up [13105.734585] LustreError: 201780:0:(obd_config.c:2101:class_config_llog_handler()) MGC192.168.206.158@tcp: cfg command failed: rc = -17 [13105.743680] Lustre: cmd=cf003 0:lustre-MDT0001-mdc 1:lustre-MDT0001_UUID 2:192.168.206.158@tcp [13105.743680] [13105.754993] LustreError: 199998:0:(mgc_request.c:614:do_requeue()) failed processing log: -17 [13414.374974] Lustre: lustre-MDT0001-mdc-ffff8e18c8961000: Connection to lustre-MDT0001 (at 192.168.206.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [13442.597053] LustreError: 203347:0:(obd_config.c:2101:class_config_llog_handler()) MGC192.168.206.158@tcp: cfg command failed: rc = -17 [13442.603769] Lustre: cmd=cf014 0:lustre-clilmv 1:lustre-MDT0001_UUID 2:1 3:1 4:lustre-MDT0001-mdc_UUID [13442.603769] [13442.614839] LustreError: 199998:0:(mgc_request.c:614:do_requeue()) failed processing log: -17 [13747.589839] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [13757.897629] Lustre: Unmounted lustre-client [13827.028759] Lustre: DEBUG MARKER: == conf-sanity test 120: cross-target rename should not create bad symlinks ========================================================== 19:20:58 (1775776858) [13860.775983] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [13864.124633] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [13881.050114] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [13881.830268] Lustre: Mounted lustre-client [13887.920950] LustreError: 208745:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8e18c5687000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13888.011658] Lustre: Unmounted lustre-client [13980.449960] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 13677 sec ========= 19:23:32 (1775777012) [13981.994996] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 19:23:33 (1775777013) === [13984.812767] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 19:23:36 (1775777016) === [14017.464339] Key type lgssc unregistered [14017.768421] LNet: 212590:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14017.774418] LNetError: 212590:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14017.788337] LNet: Removed LNI 192.168.206.58@tcp [14018.398933] Key type .llcrypt unregistered [14018.400685] Key type ._llcrypt unregistered