[ 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-10.fc44 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 434644392 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 0x00000000BFFE2464 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE2300 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 0022C0 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2374 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE2404 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE243C 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe2300-0xbffe2373] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22ff] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2374-0xbffe2403] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe2404-0xbffe243b] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe243c-0xbffe2463] [ 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.003161] x2apic enabled [ 0.004011] Switched APIC routing to physical x2apic. [ 0.005011] kvm-guest: setup PV IPIs [ 0.008363] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.009000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.010023] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.011014] pid_max: default: 32768 minimum: 301 [ 0.012155] LSM: Security Framework initializing [ 0.013067] Yama: becoming mindful. [ 0.014049] SELinux: Initializing. [ 0.015082] *** VALIDATE selinux *** [ 0.023345] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.028587] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.029208] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.030098] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.031094] *** VALIDATE tmpfs *** [ 0.033424] *** VALIDATE proc *** [ 0.034295] *** VALIDATE cgroup *** [ 0.035010] *** VALIDATE cgroup2 *** [ 0.037166] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.038159] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.039006] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.040025] Spectre V2 : User space: Vulnerable [ 0.041007] Speculative Store Bypass: Vulnerable [ 0.043932] debug: unmapping init [mem 0xffffffff8cc59000-0xffffffff8cc60fff] [ 0.046139] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.047744] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.048022] ... version: 2 [ 0.049011] ... bit width: 48 [ 0.050010] ... generic registers: 4 [ 0.050931] ... value mask: 0000ffffffffffff [ 0.051016] ... max period: 00007fffffffffff [ 0.052017] ... fixed-purpose events: 3 [ 0.053015] ... event mask: 000000070000000f [ 0.054336] rcu: Hierarchical SRCU implementation. [ 0.056584] smp: Bringing up secondary CPUs ... [ 0.057570] x86: Booting SMP configuration: [ 0.058037] .... node #0, CPUs: #1 #2 #3 [ 0.061526] smp: Brought up 1 node, 4 CPUs [ 0.063019] smpboot: Max logical packages: 1 [ 0.064014] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.117020] node 0 deferred pages initialised in 50ms [ 0.120020] devtmpfs: initialized [ 0.121246] x86/mm: Memory block size: 128MB [ 0.124259] gcov: version magic: 0x41383552 [ 0.126118] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.127086] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.128277] pinctrl core: initialized pinctrl subsystem [ 0.129219] [ 0.129791] ************************************************************* [ 0.130013] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.131013] ** ** [ 0.132014] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.133016] ** ** [ 0.134013] ** This means that this kernel is built to expose internal ** [ 0.135010] ** IOMMU data structures, which may compromise security on ** [ 0.136014] ** your system. ** [ 0.137013] ** ** [ 0.138015] ** If you see this message and you are not debugging the ** [ 0.139017] ** kernel, report this immediately to your vendor! ** [ 0.140020] ** ** [ 0.141018] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.142019] ************************************************************* [ 0.143657] NET: Registered protocol family 16 [ 0.144447] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.145061] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.146078] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.147445] cpuidle: using governor menu [ 0.148318] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.151469] PCI: Using configuration type 1 for base access [ 0.153130] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.162053] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.163023] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.165022] cryptd: max_cpu_qlen set to 1000 [ 0.166378] ACPI: Added _OSI(Module Device) [ 0.169022] ACPI: Added _OSI(Processor Device) [ 0.171018] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.172013] ACPI: Added _OSI(Processor Aggregator Device) [ 0.177798] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.184322] ACPI: Interpreter enabled [ 0.186061] ACPI: PM: (supports S0 S3 S4 S5) [ 0.186985] ACPI: Using IOAPIC for interrupt routing [ 0.188095] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.190384] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.200205] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.203054] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.205019] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.207082] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.212568] acpiphp: Slot [2] registered [ 0.214130] acpiphp: Slot [5] registered [ 0.215118] acpiphp: Slot [6] registered [ 0.217128] acpiphp: Slot [3] registered [ 0.218134] acpiphp: Slot [4] registered [ 0.220116] acpiphp: Slot [7] registered [ 0.222119] acpiphp: Slot [8] registered [ 0.224132] acpiphp: Slot [9] registered [ 0.225093] acpiphp: Slot [10] registered [ 0.227121] acpiphp: Slot [11] registered [ 0.228075] acpiphp: Slot [12] registered [ 0.229035] acpiphp: Slot [13] registered [ 0.230091] acpiphp: Slot [14] registered [ 0.231126] acpiphp: Slot [15] registered [ 0.232158] acpiphp: Slot [16] registered [ 0.234107] acpiphp: Slot [17] registered [ 0.236113] acpiphp: Slot [18] registered [ 0.237115] acpiphp: Slot [19] registered [ 0.238097] acpiphp: Slot [20] registered [ 0.240123] acpiphp: Slot [21] registered [ 0.242131] acpiphp: Slot [22] registered [ 0.243088] acpiphp: Slot [23] registered [ 0.244069] acpiphp: Slot [24] registered [ 0.245083] acpiphp: Slot [25] registered [ 0.246080] acpiphp: Slot [26] registered [ 0.247079] acpiphp: Slot [27] registered [ 0.248088] acpiphp: Slot [28] registered [ 0.249060] acpiphp: Slot [29] registered [ 0.250111] acpiphp: Slot [30] registered [ 0.251047] acpiphp: Slot [31] registered [ 0.251925] PCI host bridge to bus 0000:00 [ 0.253015] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.254016] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.256026] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.258022] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.260023] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.262024] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.263177] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.265855] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.267964] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.273751] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.276462] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.278024] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.279017] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.282023] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.283531] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.285763] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.287036] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.290332] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.295014] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.304696] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.309014] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.313811] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.319021] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.324016] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.334044] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.342417] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.348021] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.355019] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.370020] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.379883] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.383938] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.385299] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.387259] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.389233] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.392225] iommu: Default domain type: Passthrough [ 0.394406] SCSI subsystem initialized [ 0.396172] ACPI: bus type USB registered [ 0.397116] usbcore: registered new interface driver usbfs [ 0.399063] usbcore: registered new interface driver hub [ 0.400090] usbcore: registered new device driver usb [ 0.402174] pps_core: LinuxPPS API ver. 1 registered [ 0.403009] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.405103] PTP clock support registered [ 0.407258] EDAC MC: Ver: 3.0.0 [ 0.408306] PCI: Using ACPI for IRQ routing [ 0.410652] NetLabel: Initializing [ 0.411006] NetLabel: domain hash size = 128 [ 0.413010] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.415098] NetLabel: unlabeled traffic allowed by default [ 0.418287] vgaarb: loaded [ 0.420360] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.422011] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.429350] clocksource: Switched to clocksource kvm-clock [ 0.535764] VFS: Disk quotas dquot_6.6.0 [ 0.537159] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.539235] *** VALIDATE ramfs *** [ 0.540268] *** VALIDATE hugetlbfs *** [ 0.541625] pnp: PnP ACPI init [ 0.543892] pnp: PnP ACPI: found 6 devices [ 0.561081] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.564419] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.566426] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.567849] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.569366] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.570830] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.572888] NET: Registered protocol family 2 [ 0.574687] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.578338] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.580907] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.584760] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.588838] TCP: Hash tables configured (established 65536 bind 65536) [ 0.591760] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.594905] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.597485] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.600281] NET: Registered protocol family 1 [ 0.603160] RPC: Registered named UNIX socket transport module. [ 0.605176] RPC: Registered udp transport module. [ 0.606777] RPC: Registered tcp transport module. [ 0.608560] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.610383] NET: Registered protocol family 44 [ 0.611844] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.613684] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.615982] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.618574] PCI: CLS 0 bytes, default 64 [ 0.620385] Unpacking initramfs... [ 2.078354] debug: unmapping init [mem 0xffff98e37cc64000-0xffff98e37ffcffff] [ 2.081668] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.083580] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.086153] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.636857] Initialise system trusted keyrings [ 2.638675] Key type blacklist registered [ 2.640644] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.650155] zbud: loaded [ 2.653737] *** VALIDATE nfs *** [ 2.655056] *** VALIDATE nfs4 *** [ 2.657102] pstore: using deflate compression [ 2.660893] Platform Keyring initialized [ 2.757691] NET: Registered protocol family 38 [ 2.760407] Key type asymmetric registered [ 2.761501] Asymmetric key parser 'x509' registered [ 2.763605] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.767184] io scheduler mq-deadline registered [ 2.768749] io scheduler kyber registered [ 2.769920] io scheduler bfq registered [ 2.771466] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.773839] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.776396] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.778334] ACPI: Power Button [PWRF] [ 2.781760] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.785983] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.798641] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.824184] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.853953] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.859340] Non-volatile memory driver v1.3 [ 2.861202] Linux agpgart interface v0.103 [ 2.897200] virtio_blk virtio1: [vda] 134872 512-byte logical blocks (69.1 MB/65.9 MiB) [ 2.900428] vda: detected capacity change from 0 to 69054464 [ 2.916821] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 2.919048] vdb: detected capacity change from 0 to 1073741824 [ 2.925633] libphy: Fixed MDIO Bus: probed [ 2.939617] usbcore: registered new interface driver usbserial_generic [ 2.942592] usbserial: USB Serial support registered for generic [ 2.945066] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 2.950838] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 2.953046] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 2.955784] mousedev: PS/2 mouse device common for all mice [ 2.957945] rtc_cmos 00:05: RTC can wake from S4 [ 2.960850] rtc_cmos 00:05: registered as rtc0 [ 2.961065] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 2.962964] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 2.969325] intel_pstate: CPU model not supported [ 2.973034] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 2.974636] hid: raw HID events driver (C) Jiri Kosina [ 2.978859] usbcore: registered new interface driver usbhid [ 2.981141] usbhid: USB HID core driver [ 2.983150] drop_monitor: Initializing network drop monitor service [ 2.986106] Initializing XFRM netlink socket [ 2.986191] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 2.988359] NET: Registered protocol family 10 [ 2.994277] Segment Routing with IPv6 [ 2.995620] NET: Registered protocol family 17 [ 2.998178] mpls_gso: MPLS GSO support [ 3.004504] RAS: Correctable Errors collector initialized. [ 3.007032] AVX version of gcm_enc/dec engaged. [ 3.009076] AES CTR mode by8 optimization enabled [ 3.083270] sched_clock: Marking stable (3083245838, 0)->(3927578054, -844332216) [ 3.085977] registered taskstats version 1 [ 3.087688] Loading compiled-in X.509 certificates [ 3.089024] zswap: loaded using pool lzo/zbud [ 3.111192] Key type big_key registered [ 3.123129] Key type encrypted registered [ 3.124717] ima: No TPM chip found, activating TPM-bypass! [ 3.126438] ima: Allocated hash algorithm: sha1 [ 3.128807] ima: No architecture policies found [ 3.131242] evm: Initialising EVM extended attributes: [ 3.133769] evm: security.selinux [ 3.135289] evm: security.ima [ 3.136717] evm: security.capability [ 3.138366] evm: HMAC attrs: 0x1 [ 3.141097] rtc_cmos 00:05: setting system clock to 2026-06-01 16:58:36 UTC (1780333116) [ 3.147730] debug: unmapping init [mem 0xffffffff8dc03000-0xffffffff8ddfffff] [ 3.150480] debug: unmapping init [mem 0xffffffff8c982000-0xffffffff8cc58fff] [ 3.159178] Write protecting the kernel read-only data: 28672k [ 3.163192] debug: unmapping init [mem 0xffffffff8b003000-0xffffffff8b1fffff] [ 3.166484] debug: unmapping init [mem 0xffffffff8b914000-0xffffffff8b9fffff] [ 3.198558] 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.207334] systemd[1]: Detected virtualization kvm. [ 3.208739] systemd[1]: Detected architecture x86-64. [ 3.209959] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.234876] systemd[1]: No hostname configured. [ 3.236154] systemd[1]: Set hostname to . [ 3.238308] random: systemd: uninitialized urandom read (16 bytes read) [ 3.240760] systemd[1]: Initializing machine ID from random generator. [ 3.368333] random: systemd: uninitialized urandom read (16 bytes read) [ 3.370743] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ 3.374570] random: systemd: uninitialized urandom read (16 bytes read) [ 3.376542] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ 3.379753] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local File Systems. [ OK ] Listening on Journal Socket. Starting Create Volatile Files and Directories... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on Journal Socket (/dev/log). Starting Journal Service... [ OK ] Reached target Paths. [ OK ] Reached target Sockets. [ OK ] Reached target Local Encrypted Volumes. Starting Apply Kernel Variables... [ OK ] Reached target Initrd Root Device. [ OK ] Reached target Timers. [ OK ] Started Memstrack Anylazing Service. Starting Setup Virtual Console... [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. [ OK ] Started Setup Virtual Console. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 3.901704] device-mapper: uevent: version 1.0.3 [ 3.903730] 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. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting dracut initqueue hook... [ 4.514700] virtio_net virtio0 ens2: renamed from eth0 [ 4.529353] random: fast init done [ 4.680814] scsi host0: ata_piix [ 4.737809] scsi host1: ata_piix [ 4.740784] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 4.745047] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 8.514373] dracut-initqueue[575]: RTNETLINK answers: File exists [ 9.643768] random: crng init done [ 9.645216] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 10.138930] 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 Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Slices. [ OK ] Stopped target Sockets. [ OK ] Stopped target Paths. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Swap. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 11.407702] printk: systemd: 26 output lines suppressed due to ratelimiting [ 11.699728] SELinux: Disabled at runtime. [ 11.768259] 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) [ 11.776092] systemd[1]: Detected virtualization kvm. [ 11.778014] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 12.309239] systemd[1]: initrd-switch-root.service: Succeeded. [ 12.311808] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 12.314922] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 12.317597] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 12.319736] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 12.328827] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.333380] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target rpc_pipefs.target. [ OK ] Created slice system-getty.slice. [ OK ] Started Forward Password Requests to Wall Directory Watch. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. Mounting POSIX Message Queue File System... [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... Starting Apply Kernel Variables... Mounting Kernel Debug File System... [ OK ] Listening on Process Core Dump Socket. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd Root File System. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. Activating swap /dev/disk/by-label/SWAP... [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. Starting Remount Root and Kernel File Systems... [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Stopped target Initrd File Systems. Starting Create list of required st…ce nodes for the current kernel... [ 12.525593] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS Mounting Huge Pages File System... [ OK ] Started Journal Service. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted Kernel Debug File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [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 ] Mounted Huge Pages File System. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... [ OK ] Reached target Swap. Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... [ OK ] Started udev Coldplug all Devices. [ OK ] Mounted /mnt. [ 12.880455] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 13.218763] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 13.256084] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 13.369646] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 13.391229] EDAC sbridge: Ver: 1.1.2 [ 14.648888] Key type dns_resolver registered [ 14.952330] NFS: Registering the id_resolver key type [ 14.954624] Key type id_resolver registered [ 14.956450] Key type id_legacy registered [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Mark the need to relabel after reboot... Starting Rebuild Dynamic Linker Cache... Starting Load/Save Random Seed... Starting Create Volatile Files and Directories... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Load/Save Random Seed. [ OK ] Started Create Volatile Files and Directories. Starting 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 Cleanup of Temporary Directories. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. [ OK ] Reached target Basic System. Starting Restore /run/initramfs on shutdown... Starting Login Service... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Reached target sshd-keygen.target. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Started irqbalance daemon. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting OpenSSH server daemon... Starting Dynamic System Tuning Daemon... Starting Hostname Service... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started OpenSSH server daemon. [ OK ] Started Permit User Sessions. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS0. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Notify NFS peers of a restart... Starting Crash recovery kernel arming... Starting System Logging Service... [ OK ] Started Notify NFS peers of a restart. [ 18.229766] hrtimer: interrupt took 15756408 ns [ OK ] Started System Logging Service. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg249-client login: [ 93.281038] libcfs: loading out-of-tree module taints kernel. [ 93.549573] Key type ._llcrypt registered [ 93.558579] Key type .llcrypt registered [ 94.873332] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 94.902205] alg: No test for adler32 (adler32-zlib) [ 96.753562] Lustre: Lustre: Build Version: 2.17.53_36_g8248a7d [ 98.111890] LNet: Added LNI 192.168.202.49@tcp [8/256/0/180] [ 99.935323] Key type lgssc registered [ 102.459230] Lustre: Echo OBD driver; http://www.lustre.org/ [ 309.865756] Lustre: Mounted lustre-client [ 314.843877] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 330.963838] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing check_logdir /tmp/testlogs/ [ 335.327591] Lustre: lustre-OST0000-osc-ffff98e3c3324000: disconnect after 23s idle [ 338.279750] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing yml_node [ 344.334843] Lustre: DEBUG MARKER: Client: 2.17.53.36 [ 347.902279] Lustre: DEBUG MARKER: MDS: 2.17.53.36 [ 351.028232] Lustre: DEBUG MARKER: OSS: 2.17.53.36 [ 353.216759] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Mon Jun 1 13:04:24 EDT 2026 [ 375.818949] Lustre: DEBUG MARKER: excepting tests: 102 106 115 32newtarball [ 378.106657] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 381.619809] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 392.000570] Lustre: Unmounted lustre-client [ 526.141551] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 531.828182] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 559.265090] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 601.985750] Lustre: DEBUG MARKER: == conf-sanity test 76a: set permanent params with lctl across mounts ========================================================== 13:08:33 (1780333713) [ 639.885858] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 645.380524] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 665.986708] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 666.708352] Lustre: Mounted lustre-client [ 683.275512] LustreError: 14272:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff98e3c85cb800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 683.414678] Lustre: Unmounted lustre-client [ 816.110989] Lustre: Mounted lustre-client [ 821.460354] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 835.372973] LustreError: 16827:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff98e3c5535000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 835.565969] Lustre: Unmounted lustre-client [ 969.840430] Lustre: Mounted lustre-client [ 976.365606] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 987.595674] LustreError: 19055:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff98e3c85cf800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 987.606089] LustreError: 19055:0:(lov_obd.c:792:lov_cleanup()) Skipped 1 previous similar message [ 987.707025] Lustre: Unmounted lustre-client [ 1038.515958] Lustre: DEBUG MARKER: == conf-sanity test 76b: verify params log setup correctly ========================================================== 13:15:50 (1780334150) [ 1149.219696] Lustre: Mounted lustre-client [ 1155.766664] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 1166.218615] LustreError: 22274:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff98e3c5534000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1166.229465] LustreError: 22274:0:(lov_obd.c:792:lov_cleanup()) Skipped 1 previous similar message [ 1166.385946] Lustre: Unmounted lustre-client [ 1219.186893] Lustre: DEBUG MARKER: == conf-sanity test 76c: verify changelog_mask is applied with lctl set_param -P ========================================================== 13:18:50 (1780334330) [ 1310.102259] Lustre: Mounted lustre-client [ 1315.807289] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 1325.577995] Lustre: lustre-MDT0000-mdc-ffff98e3d00eb000: Connection to lustre-MDT0000 (at 192.168.202.149@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1335.776545] Lustre: lustre-OST0000-osc-ffff98e3d00eb000: disconnect after 24s idle [ 1335.786966] Lustre: lustre-MDT0001-mdc-ffff98e3d00eb000: Connection to lustre-MDT0001 (at 192.168.202.149@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1335.790863] Lustre: Skipped 1 previous similar message [ 1347.039185] Lustre: 2406:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1780334444/real 1780334444] req@ffff98e3c553ca80 x1866814677107968/t0(0) o400->MGC192.168.202.149@tcp@192.168.202.149@tcp:26/25 lens 224/224 e 0 to 1 dl 1780334460 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'kworker.0' uid:0 gid:0 projid:4294967295 [ 1347.049149] LustreError: MGC192.168.202.149@tcp: Connection to MGS (at 192.168.202.149@tcp) was lost; in progress operations using this service will fail [ 1357.225615] Lustre: Evicted from MGS (at 192.168.202.149@tcp) after server handle changed from 0x23569228418768f2 to 0x23569228418769a8 [ 1357.237336] Lustre: MGC192.168.202.149@tcp: Connection restored to 192.168.202.149@tcp (at 192.168.202.149@tcp) [ 1359.223511] Lustre: 24827:0:(mgc_request.c:1899:mgc_process_log()) MGC192.168.202.149@tcp: IR log lustre-cliir failed, not fatal: rc = -2 [ 1381.875559] LustreError: lustre-MDT0000-mdc-ffff98e3d00eb000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 1381.905944] Lustre: lustre-MDT0000-mdc-ffff98e3d00eb000: Connection restored to 192.168.202.149@tcp (at 192.168.202.149@tcp) [ 1387.008110] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1388.794116] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 1394.031981] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1396.055549] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 1399.874964] LustreError: 26578:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff98e3d00eb000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1399.898541] LustreError: 26578:0:(lov_obd.c:792:lov_cleanup()) Skipped 1 previous similar message [ 1400.015824] Lustre: Unmounted lustre-client [ 1443.639367] Lustre: DEBUG MARKER: == conf-sanity test 76d: verify llite.*.xattr_cache can be set by 'lctl set_param -P' correctly ========================================================== 13:22:35 (1780334555) [ 1538.469526] Lustre: Mounted lustre-client [ 1545.055270] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 1555.427388] LustreError: 29637:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff98e3d0053800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1555.454545] LustreError: 29637:0:(lov_obd.c:792:lov_cleanup()) Skipped 1 previous similar message [ 1555.590047] Lustre: Unmounted lustre-client [ 1604.831761] Lustre: DEBUG MARKER: == conf-sanity test 77: comma-separated MGS NIDs and failover node NIDs ========================================================== 13:25:16 (1780334716) [ 1607.936626] Lustre: DEBUG MARKER: SKIP: conf-sanity test_77 mixed loopback and real device not working [ 1610.327556] Lustre: DEBUG MARKER: == conf-sanity test 78: run resize2fs on MDT and OST filesystems ========================================================== 13:25:21 (1780334721) [ 1676.894294] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1702.602638] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1703.698089] Lustre: Mounted lustre-client [ 1703.704047] Lustre: Skipped 2 previous similar messages [ 1705.744245] Lustre: DEBUG MARKER: create test files [ 1738.651583] LustreError: 33261:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff98e3d05bf000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1738.658392] LustreError: 33261:0:(lov_obd.c:792:lov_cleanup()) Skipped 5 previous similar messages [ 1738.941675] Lustre: Unmounted lustre-client [ 1738.948519] Lustre: Skipped 2 previous similar messages [ 1759.163228] Key type lgssc unregistered [ 1759.490077] LNet: 33615:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1759.496770] LNetError: 33615:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1760.563480] LNet: Removed LNI 192.168.202.49@tcp [ 1761.745189] Key type .llcrypt unregistered [ 1761.748749] Key type ._llcrypt unregistered [ 1795.779296] Key type ._llcrypt registered [ 1795.781913] Key type .llcrypt registered [ 1796.377890] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1796.393276] alg: No test for adler32 (adler32-zlib) [ 1797.812595] Lustre: Lustre: Build Version: 2.17.53_36_g8248a7d [ 1798.149026] LNet: Added LNI 192.168.202.49@tcp [8/256/0/180] [ 1799.904911] Key type lgssc registered [ 1801.725647] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1843.601843] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1867.500715] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1869.267319] Lustre: Mounted lustre-client [ 1873.463846] Lustre: DEBUG MARKER: check files after expanding the MDT and OST filesystems [ 1880.784561] Lustre: DEBUG MARKER: create more files after expanding the MDT and OST filesystems [ 1887.082861] LustreError: 36097:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff98e3c5531800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1887.449930] Lustre: Unmounted lustre-client [ 1908.736296] Key type lgssc unregistered [ 1909.113593] LNet: 36451:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1909.118142] LNetError: 36451:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1909.136918] LNet: Removed LNI 192.168.202.49@tcp [ 1910.184856] Key type .llcrypt unregistered [ 1910.186592] Key type ._llcrypt unregistered [ 1951.129496] Key type ._llcrypt registered [ 1951.134064] Key type .llcrypt registered [ 1951.896549] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1951.932467] alg: No test for adler32 (adler32-zlib) [ 1953.336693] Lustre: Lustre: Build Version: 2.17.53_36_g8248a7d [ 1953.562069] LNet: Added LNI 192.168.202.49@tcp [8/256/0/180] [ 1955.289329] Key type lgssc registered [ 1956.883370] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1993.373966] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2016.754895] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2018.596774] Lustre: Mounted lustre-client [ 2023.135751] Lustre: DEBUG MARKER: check files after shrinking the MDT and OST filesystems [ 2030.380567] LustreError: 38812:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff98e3c59e9800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2030.553630] Lustre: Unmounted lustre-client [ 2049.766528] Key type lgssc unregistered [ 2050.129669] LNet: 39163:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2050.143041] LNetError: 39163:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2050.161763] LNet: Removed LNI 192.168.202.49@tcp [ 2050.886479] Key type .llcrypt unregistered [ 2050.888717] Key type ._llcrypt unregistered [ 2081.676609] Key type ._llcrypt registered [ 2081.683576] Key type .llcrypt registered [ 2082.425343] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2082.438920] alg: No test for adler32 (adler32-zlib) [ 2083.859633] Lustre: Lustre: Build Version: 2.17.53_36_g8248a7d [ 2084.422258] LNet: Added LNI 192.168.202.49@tcp [8/256/0/180] [ 2086.240059] Key type lgssc registered [ 2087.924914] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2186.531933] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2191.911536] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2215.579697] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2261.924433] Lustre: DEBUG MARKER: == conf-sanity test 79: format MDT/OST without mgs option (should return errors) ========================================================== 13:36:13 (1780335373) [ 2406.357920] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2411.504946] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2433.582819] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2477.073931] Lustre: DEBUG MARKER: == conf-sanity test 80: mgc import reconnect race ======== 13:39:48 (1780335588) [ 2508.038515] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2511.580891] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2529.104891] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2591.360913] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 2636.658035] Lustre: DEBUG MARKER: == conf-sanity test 81: sparse OST indexing ============== 13:42:28 (1780335748) [ 2638.479342] Lustre: DEBUG MARKER: SKIP: conf-sanity test_81 needs >= 3 OSTs [ 2640.385647] Lustre: DEBUG MARKER: == conf-sanity test 82a: specify OSTs for file (succeed) or directory (succeed) ========================================================== 13:42:32 (1780335752) [ 2641.914933] Lustre: DEBUG MARKER: SKIP: conf-sanity test_82a needs >= 3 OSTs [ 2644.282502] Lustre: DEBUG MARKER: == conf-sanity test 82b: specify OSTs for file with --pool and --ost-list options ========================================================== 13:42:35 (1780335755) [ 2645.687727] Lustre: DEBUG MARKER: SKIP: conf-sanity test_82b needs >= 4 OSTs [ 2647.645995] Lustre: DEBUG MARKER: == conf-sanity test 82c: specify sparse OSTs for setstripe (should not crash) ========================================================== 13:42:39 (1780335759) [ 2649.221145] Lustre: DEBUG MARKER: SKIP: conf-sanity test_82c needs >= 4 OSTs [ 2651.633355] Lustre: DEBUG MARKER: == conf-sanity test 83: ENOSPACE on OST doesn't cause message VFS: Busy inodes after unmount ... ========================================================== 13:42:43 (1780335763) [ 2653.540461] Lustre: DEBUG MARKER: mount the OST /dev/mapper/ost1_flakey as a ldiskfs filesystem [ 2664.801747] Lustre: DEBUG MARKER: run llverfs in partial mode on the OST ldiskfs /mnt/lustre-ost1 [ 2675.834372] Lustre: DEBUG MARKER: unmount the OST /dev/mapper/ost1_flakey [ 2807.793951] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2812.953763] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2836.907542] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2892.466665] Lustre: DEBUG MARKER: == conf-sanity test 84: check recovery_hard_time ========= 13:46:44 (1780336004) [ 2929.996887] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2935.087068] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2955.459517] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2993.559943] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3006.684317] Lustre: Mounted lustre-client [ 3007.262334] Lustre: Mounted lustre-client [ 3028.354796] Lustre: DEBUG MARKER: local REPLAY BARRIER on lustre-MDT0000 [ 3063.782564] Lustre: lustre-MDT0000-mdc-ffff98e3d0069000: Connection to lustre-MDT0000 (at 192.168.202.149@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3079.967187] Lustre: 39778:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1780336177/real 1780336177] req@00000000b2bf7c0f x1866816760186880/t0(0) o400->MGC192.168.202.149@tcp@192.168.202.149@tcp:26/25 lens 224/224 e 0 to 1 dl 1780336193 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3079.988333] LustreError: MGC192.168.202.149@tcp: Connection to MGS (at 192.168.202.149@tcp) was lost; in progress operations using this service will fail [ 3090.419155] Lustre: Evicted from MGS (at 192.168.202.149@tcp) after server handle changed from 0x3de6a790b83435c to 0x3de6a790b84053a [ 3090.446562] Lustre: MGC192.168.202.149@tcp: Connection restored to 192.168.202.149@tcp (at 192.168.202.149@tcp) [ 3092.472326] LustreError: 39777:0:(client.c:3438:ptlrpc_replay_interpret()) @@@ status 301, old was 0 req@000000000143fb90 x1866816759543424/t8589934601(8589934601) o101->lustre-MDT0000-mdc-ffff98e3d0069000@192.168.202.149@tcp:12/10 lens 592/608 e 0 to 0 dl 1780336221 ref 2 fl Interpret:RQU/604/0 rc 301/301 job:'' uid:0 gid:0 projid:0 [ 3093.152706] LustreError: 39777:0:(client.c:3438:ptlrpc_replay_interpret()) @@@ status 301, old was 0 req@00000000a94ef85e x1866816759544064/t8589934603(8589934603) o101->lustre-MDT0000-mdc-ffff98e3d0069000@192.168.202.149@tcp:12/10 lens 592/608 e 0 to 0 dl 1780336222 ref 2 fl Interpret:RQU/604/0 rc 301/301 job:'' uid:0 gid:0 projid:0 [ 3094.600644] LustreError: 39777:0:(client.c:3438:ptlrpc_replay_interpret()) @@@ status 301, old was 0 req@000000003538623b x1866816759545344/t8589934607(8589934607) o101->lustre-MDT0000-mdc-ffff98e3d0069000@192.168.202.149@tcp:12/10 lens 592/608 e 0 to 0 dl 1780336223 ref 2 fl Interpret:RQU/604/0 rc 301/301 job:'' uid:0 gid:0 projid:0 [ 3094.647459] LustreError: 39777:0:(client.c:3438:ptlrpc_replay_interpret()) Skipped 1 previous similar message [ 3096.618547] LustreError: 39777:0:(client.c:3438:ptlrpc_replay_interpret()) @@@ status 301, old was 0 req@0000000048d87920 x1866816759547264/t8589934613(8589934613) o101->lustre-MDT0000-mdc-ffff98e3d0069000@192.168.202.149@tcp:12/10 lens 592/608 e 0 to 0 dl 1780336225 ref 2 fl Interpret:RQU/604/0 rc 301/301 job:'' uid:0 gid:0 projid:0 [ 3096.661358] LustreError: 39777:0:(client.c:3438:ptlrpc_replay_interpret()) Skipped 2 previous similar messages [ 3101.130328] LustreError: 39777:0:(client.c:3438:ptlrpc_replay_interpret()) @@@ status 301, old was 0 req@000000001e4c4efa x1866816759551744/t8589934627(8589934627) o101->lustre-MDT0000-mdc-ffff98e3d0069000@192.168.202.149@tcp:12/10 lens 592/608 e 0 to 0 dl 1780336230 ref 2 fl Interpret:RQU/604/0 rc 301/301 job:'' uid:0 gid:0 projid:0 [ 3101.149240] LustreError: 39777:0:(client.c:3438:ptlrpc_replay_interpret()) Skipped 6 previous similar messages [ 3109.486146] LustreError: 39777:0:(client.c:3438:ptlrpc_replay_interpret()) @@@ status 301, old was 0 req@00000000d46db130 x1866816759560832/t8589934653(8589934653) o101->lustre-MDT0000-mdc-ffff98e3d0069000@192.168.202.149@tcp:12/10 lens 592/608 e 0 to 0 dl 1780336238 ref 2 fl Interpret:RQU/604/0 rc 301/301 job:'' uid:0 gid:0 projid:0 [ 3109.529432] LustreError: 39777:0:(client.c:3438:ptlrpc_replay_interpret()) Skipped 12 previous similar messages [ 3125.499417] LustreError: 39777:0:(client.c:3438:ptlrpc_replay_interpret()) @@@ status 301, old was 0 req@00000000383cd769 x1866816759576832/t8589934703(8589934703) o101->lustre-MDT0000-mdc-ffff98e3d0069000@192.168.202.149@tcp:12/10 lens 592/608 e 0 to 0 dl 1780336254 ref 2 fl Interpret:RQU/604/0 rc 301/301 job:'' uid:0 gid:0 projid:0 [ 3125.550764] LustreError: 39777:0:(client.c:3438:ptlrpc_replay_interpret()) Skipped 24 previous similar messages [ 3150.314214] LustreError: lustre-MDT0000-mdc-ffff98e3d0069000: operation mds_close to node 192.168.202.149@tcp failed: rc = -107 [ 3150.454804] Lustre: lustre-MDT0000-mdc-ffff98e3c3c8b800: Connection restored to 192.168.202.149@tcp (at 192.168.202.149@tcp) [ 3153.901800] LustreError: lustre-MDT0000-mdc-ffff98e3d0069000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 3154.232175] LustreError: 61000:0:(file.c:6105:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -5 [ 3154.936102] Lustre: lustre-MDT0000-mdc-ffff98e3d0069000: Connection restored to 192.168.202.149@tcp (at 192.168.202.149@tcp) [ 3164.940588] LustreError: 61097:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff98e3d0069000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3165.027802] Lustre: Unmounted lustre-client [ 3167.063608] LustreError: 61118:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff98e3c3c8b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3167.078838] LustreError: 61118:0:(lov_obd.c:792:lov_cleanup()) Skipped 1 previous similar message [ 3167.181278] Lustre: Unmounted lustre-client [ 3210.838090] Lustre: DEBUG MARKER: == conf-sanity test 85: osd_ost init: fail ea_fid_set ==== 13:52:02 (1780336322) [ 3279.874174] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3293.828202] Lustre: DEBUG MARKER: == conf-sanity test 86: Replacing mkfs.lustre -G option == 13:53:25 (1780336405) [ 3334.329039] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3463.225910] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3467.770163] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3493.556159] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3547.052226] Lustre: DEBUG MARKER: == conf-sanity test 87: check if MDT inode can hold EAs with N stripes properly ========================================================== 13:57:38 (1780336658) [ 3550.585616] Key type lgssc unregistered [ 3550.809440] LNet: 68308:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3550.814123] LNetError: 68308:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3550.827973] LNet: Removed LNI 192.168.202.49@tcp [ 3551.654922] Key type .llcrypt unregistered [ 3551.660251] Key type ._llcrypt unregistered [ 3582.935265] Key type ._llcrypt registered [ 3582.948949] Key type .llcrypt registered [ 3583.442203] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3583.459659] alg: No test for adler32 (adler32-zlib) [ 3584.627541] Lustre: Lustre: Build Version: 2.17.53_36_g8248a7d [ 3584.849869] LNet: Added LNI 192.168.202.49@tcp [8/256/0/180] [ 3586.511212] Key type lgssc registered [ 3587.707867] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3708.786221] Lustre: Mounted lustre-client [ 3710.154249] LustreError: 71550:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff98e3c59eb800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3710.264786] Lustre: Unmounted lustre-client [ 3744.045531] Lustre: DEBUG MARKER: == conf-sanity test 88: check the default mount options can be overridden ========================================================== 14:00:55 (1780336855) [ 3777.999715] Lustre: DEBUG MARKER: == conf-sanity test 89: check tunefs --param and --erase-param{s} options ========================================================== 14:01:29 (1780336889) [ 3907.485798] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL failover.node=192.0.2.254@tcp0,mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3911.778686] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL failover.node=192.0.2.254@tcp0,mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3935.010444] Lustre: DEBUG MARKER: oleg249-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 [ 3984.386597] Lustre: DEBUG MARKER: == conf-sanity test 90a: check max_mod_rpcs_in_flight is enforced ========================================================== 14:04:55 (1780337095) [ 4020.009817] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4023.848266] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4044.164564] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4045.090606] Lustre: Mounted lustre-client [ 4066.271192] Lustre: 79831:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1780337163/real 1780337163] req@00000000ebb801b4 x1866818333469824/t0(0) o36->lustre-MDT0000-mdc-ffff98e3c798b800@192.168.202.149@tcp:12/10 lens 488/512 e 0 to 1 dl 1780337179 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4066.272141] Lustre: lustre-MDT0000-mdc-ffff98e3c798b800: Connection to lustre-MDT0000 (at 192.168.202.149@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4066.322155] Lustre: 79831:0:(client.c:2480:ptlrpc_expire_one_request()) Skipped 3 previous similar messages [ 4066.393080] Lustre: lustre-MDT0000-mdc-ffff98e3c798b800: Connection restored to 192.168.202.149@tcp (at 192.168.202.149@tcp) [ 4085.215270] Lustre: 79872:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1780337182/real 1780337182] req@00000000cfcc78a5 x1866818333478528/t0(0) o36->lustre-MDT0000-mdc-ffff98e3c798b800@192.168.202.149@tcp:12/10 lens 488/512 e 0 to 1 dl 1780337198 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4085.215992] Lustre: lustre-MDT0000-mdc-ffff98e3c798b800: Connection to lustre-MDT0000 (at 192.168.202.149@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4085.268321] Lustre: 79872:0:(client.c:2480:ptlrpc_expire_one_request()) Skipped 5 previous similar messages [ 4085.354942] Lustre: lustre-MDT0000-mdc-ffff98e3c798b800: Connection restored to 192.168.202.149@tcp (at 192.168.202.149@tcp) [ 4087.391799] LustreError: 79909:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff98e3c798b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4087.396948] LustreError: 79909:0:(lov_obd.c:792:lov_cleanup()) Skipped 1 previous similar message [ 4087.489816] Lustre: Unmounted lustre-client [ 4120.341284] Key type lgssc unregistered [ 4120.660264] LNet: 80316:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4120.668720] LNetError: 80316:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4120.689304] LNet: Removed LNI 192.168.202.49@tcp [ 4121.576885] Key type .llcrypt unregistered [ 4121.579256] Key type ._llcrypt unregistered [ 4140.016367] Key type ._llcrypt registered [ 4140.020412] Key type .llcrypt registered [ 4141.491722] Lustre: DEBUG MARKER: == conf-sanity test 90b: check max_mod_rpcs_in_flight is enforced after update ========================================================== 14:07:33 (1780337253) [ 4144.654603] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4144.695497] alg: No test for adler32 (adler32-zlib) [ 4145.857547] Lustre: Lustre: Build Version: 2.17.53_36_g8248a7d [ 4146.172479] LNet: Added LNI 192.168.202.49@tcp [8/256/0/180] [ 4147.887267] Key type lgssc registered [ 4149.366928] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4197.577624] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4202.110962] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4222.704817] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4224.475617] Lustre: Mounted lustre-client [ 4248.543389] Lustre: 83432:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1780337345/real 1780337345] req@00000000af309023 x1866818921702272/t0(0) o36->lustre-MDT0000-mdc-ffff98e3c8501800@192.168.202.149@tcp:12/10 lens 488/512 e 0 to 1 dl 1780337361 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4248.578735] Lustre: lustre-MDT0000-mdc-ffff98e3c8501800: Connection to lustre-MDT0000 (at 192.168.202.149@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4248.633770] Lustre: lustre-MDT0000-mdc-ffff98e3c8501800: Connection restored to 192.168.202.149@tcp (at 192.168.202.149@tcp) [ 4267.487191] Lustre: 83478:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1780337364/real 1780337364] req@000000000bf2cb68 x1866818921714560/t0(0) o36->lustre-MDT0001-mdc-ffff98e3c8501800@192.168.202.149@tcp:12/10 lens 488/512 e 0 to 1 dl 1780337380 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4267.488660] Lustre: lustre-MDT0001-mdc-ffff98e3c8501800: Connection to lustre-MDT0001 (at 192.168.202.149@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4267.501928] Lustre: 83478:0:(client.c:2480:ptlrpc_expire_one_request()) Skipped 3 previous similar messages [ 4267.556653] Lustre: lustre-MDT0001-mdc-ffff98e3c8501800: Connection restored to 192.168.202.149@tcp (at 192.168.202.149@tcp) [ 4285.407215] Lustre: 83509:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1780337382/real 1780337382] req@0000000058fb0e3d x1866818921721472/t0(0) o36->lustre-MDT0001-mdc-ffff98e3c8501800@192.168.202.149@tcp:12/10 lens 488/512 e 0 to 1 dl 1780337398 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4285.407570] Lustre: lustre-MDT0001-mdc-ffff98e3c8501800: Connection to lustre-MDT0001 (at 192.168.202.149@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4285.436919] Lustre: 83509:0:(client.c:2480:ptlrpc_expire_one_request()) Skipped 3 previous similar messages [ 4285.495471] Lustre: lustre-MDT0001-mdc-ffff98e3c8501800: Connection restored to 192.168.202.149@tcp (at 192.168.202.149@tcp) [ 4289.067213] LustreError: 83566:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff98e3c8501800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4289.138440] Lustre: Unmounted lustre-client [ 4290.786912] Lustre: Mounted lustre-client [ 4309.471437] Lustre: 83658:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1780337406/real 1780337406] req@00000000f4eaf5be x1866818921757696/t0(0) o36->lustre-MDT0000-mdc-ffff98e3c7652000@192.168.202.149@tcp:12/10 lens 488/512 e 0 to 1 dl 1780337422 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4309.471546] Lustre: lustre-MDT0000-mdc-ffff98e3c7652000: Connection to lustre-MDT0000 (at 192.168.202.149@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4309.505634] Lustre: 83658:0:(client.c:2480:ptlrpc_expire_one_request()) Skipped 4 previous similar messages [ 4309.595352] Lustre: lustre-MDT0000-mdc-ffff98e3c7652000: Connection restored to 192.168.202.149@tcp (at 192.168.202.149@tcp) [ 4326.879284] Lustre: 83721:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1780337424/real 1780337424] req@00000000290193d3 x1866818921777408/t0(0) o36->lustre-MDT0000-mdc-ffff98e3c7652000@192.168.202.149@tcp:12/10 lens 488/512 e 0 to 1 dl 1780337440 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4326.880631] Lustre: lustre-MDT0000-mdc-ffff98e3c7652000: Connection to lustre-MDT0000 (at 192.168.202.149@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4326.945655] Lustre: 83721:0:(client.c:2480:ptlrpc_expire_one_request()) Skipped 14 previous similar messages [ 4327.030524] Lustre: lustre-MDT0000-mdc-ffff98e3c7652000: Connection restored to 192.168.202.149@tcp (at 192.168.202.149@tcp) [ 4344.436116] LustreError: 83773:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff98e3c7652000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4344.566473] Lustre: Unmounted lustre-client [ 4376.124571] Key type lgssc unregistered [ 4376.396175] LNet: 84180:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4376.407848] LNetError: 84180:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4376.426938] LNet: Removed LNI 192.168.202.49@tcp [ 4377.757263] Key type .llcrypt unregistered [ 4377.761513] Key type ._llcrypt unregistered [ 4396.844414] Key type ._llcrypt registered [ 4396.846641] Key type .llcrypt registered [ 4398.674551] Lustre: DEBUG MARKER: == conf-sanity test 90c: check max_mod_rpcs_in_flight update limits ========================================================== 14:11:50 (1780337510) [ 4401.366147] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4401.620269] alg: No test for adler32 (adler32-zlib) [ 4402.863733] Lustre: Lustre: Build Version: 2.17.53_36_g8248a7d [ 4403.288434] LNet: Added LNI 192.168.202.49@tcp [8/256/0/180] [ 4405.071265] Key type lgssc registered [ 4406.875614] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4458.209850] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4462.391586] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4482.160919] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4484.738819] Lustre: Mounted lustre-client [ 4490.593229] LustreError: 87307:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff98e3c6da1000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4490.736177] Lustre: Unmounted lustre-client [ 4492.063539] Lustre: Mounted lustre-client [ 4492.215957] LustreError: 87364:0:(genops.c:2263:obd_set_max_mod_rpcs_in_flight()) lustre-MDT0000-mdc-ffff98e3c8507000: 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. [ 4496.103859] LustreError: 87399:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff98e3c8507000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4496.196390] Lustre: Unmounted lustre-client [ 4496.587256] Lustre: Mounted lustre-client [ 4502.014554] LustreError: 87507:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff98e3c798b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4502.099991] Lustre: Unmounted lustre-client [ 4540.240078] Key type lgssc unregistered [ 4540.577628] LNet: 87912:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4540.591831] LNetError: 87912:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4540.613558] LNet: Removed LNI 192.168.202.49@tcp [ 4541.826041] Key type .llcrypt unregistered [ 4541.843160] Key type ._llcrypt unregistered [ 4559.847492] Key type ._llcrypt registered [ 4559.850613] Key type .llcrypt registered [ 4561.482949] Lustre: DEBUG MARKER: == conf-sanity test 90d: check one close RPC is allowed above max_mod_rpcs_in_flight ========================================================== 14:14:32 (1780337672) [ 4564.038297] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4564.070396] alg: No test for adler32 (adler32-zlib) [ 4565.236086] Lustre: Lustre: Build Version: 2.17.53_36_g8248a7d [ 4565.666851] LNet: Added LNI 192.168.202.49@tcp [8/256/0/180] [ 4567.407246] Key type lgssc registered [ 4568.989720] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4618.962829] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4623.207669] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4643.169190] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4645.956287] Lustre: Mounted lustre-client [ 4665.823226] Lustre: 91025:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1780337763/real 1780337763] req@00000000421655b6 x1866819361061760/t0(0) o36->lustre-MDT0000-mdc-ffff98e3c3322000@192.168.202.149@tcp:12/10 lens 488/512 e 0 to 1 dl 1780337779 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4665.825017] Lustre: lustre-MDT0000-mdc-ffff98e3c3322000: Connection to lustre-MDT0000 (at 192.168.202.149@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4665.839196] Lustre: 91025:0:(client.c:2480:ptlrpc_expire_one_request()) Skipped 3 previous similar messages [ 4665.884410] Lustre: lustre-MDT0000-mdc-ffff98e3c3322000: Connection restored to 192.168.202.149@tcp (at 192.168.202.149@tcp) [ 4668.044293] LustreError: 91068:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff98e3c3322000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4668.195245] Lustre: Unmounted lustre-client [ 4698.111614] Key type lgssc unregistered [ 4698.487768] LNet: 91472:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4698.496372] LNetError: 91472:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4698.510871] LNet: Removed LNI 192.168.202.49@tcp [ 4699.342749] Key type .llcrypt unregistered [ 4699.344281] Key type ._llcrypt unregistered [ 4718.999033] Key type ._llcrypt registered [ 4719.001058] Key type .llcrypt registered [ 4720.402980] Lustre: DEBUG MARKER: == conf-sanity test 91: evict-by-nid support ============= 14:17:12 (1780337832) [ 4723.187250] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4723.197833] alg: No test for adler32 (adler32-zlib) [ 4724.282672] Lustre: Lustre: Build Version: 2.17.53_36_g8248a7d [ 4724.505785] LNet: Added LNI 192.168.202.49@tcp [8/256/0/180] [ 4726.199305] Key type lgssc registered [ 4727.661620] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4784.219727] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4790.532476] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4813.708771] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4826.204526] Lustre: Mounted lustre-client [ 4831.760869] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4842.125327] Lustre: Unmounted lustre-client [ 4911.331244] Key type lgssc unregistered [ 4911.686792] LNet: 95093:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4911.696104] LNetError: 95093:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4911.720687] LNet: Removed LNI 192.168.202.49@tcp [ 4912.341542] Key type .llcrypt unregistered [ 4912.347198] Key type ._llcrypt unregistered [ 4930.972651] Key type ._llcrypt registered [ 4930.977199] Key type .llcrypt registered [ 4933.166831] Lustre: DEBUG MARKER: == conf-sanity test 92: ldev returns MGS NID correctly in command substitution ========================================================== 14:20:44 (1780338044) [ 4945.842302] Lustre: DEBUG MARKER: == conf-sanity test 93: register mulitple MDT at the same time ========================================================== 14:20:57 (1780338057) [ 4947.802697] Lustre: DEBUG MARKER: SKIP: conf-sanity test_93 needs >= 3 MDTs [ 4950.006806] Lustre: DEBUG MARKER: == conf-sanity test 94: ldev outputs correct labels for file system name query ========================================================== 14:21:01 (1780338061) [ 4960.984981] Lustre: DEBUG MARKER: == conf-sanity test 95: ldev should only allow one label filter ========================================================== 14:21:12 (1780338072) [ 4971.163662] Lustre: DEBUG MARKER: == conf-sanity test 96: ldev returns hostname and backend fs correctly in command sub ========================================================== 14:21:22 (1780338082) [ 4982.392984] Lustre: DEBUG MARKER: == conf-sanity test 97: ldev returns correct ouput when querying based on role ========================================================== 14:21:33 (1780338093) [ 4992.456706] Lustre: DEBUG MARKER: == conf-sanity test 98: Buffer-overflow check while parsing mount_opts ========================================================== 14:21:44 (1780338104) [ 4996.401129] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4996.422644] alg: No test for adler32 (adler32-zlib) [ 4997.657305] Lustre: Lustre: Build Version: 2.17.53_36_g8248a7d [ 4997.910967] LNet: Added LNI 192.168.202.49@tcp [8/256/0/180] [ 4999.679211] Key type lgssc registered [ 5001.386481] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5057.110476] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5062.454837] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5086.409502] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5089.389231] Lustre: Mounted lustre-client [ 5093.740959] LustreError: 102431:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff98e3c92fe000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5093.901484] Lustre: Unmounted lustre-client [ 5122.867194] Key type lgssc unregistered [ 5123.139827] LNet: 102835:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5123.153488] LNetError: 102835:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5123.183338] LNet: Removed LNI 192.168.202.49@tcp [ 5123.901847] Key type .llcrypt unregistered [ 5123.904402] Key type ._llcrypt unregistered [ 5140.930826] Key type ._llcrypt registered [ 5140.932916] Key type .llcrypt registered [ 5142.736253] Lustre: DEBUG MARKER: == conf-sanity test 99: Adding meta_bg option ============ 14:24:14 (1780338254) [ 5146.978453] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5146.995457] alg: No test for adler32 (adler32-zlib) [ 5148.273934] Lustre: Lustre: Build Version: 2.17.53_36_g8248a7d [ 5148.670305] LNet: Added LNI 192.168.202.49@tcp [8/256/0/180] [ 5150.415146] Key type lgssc registered [ 5151.738771] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5190.329514] Lustre: DEBUG MARKER: == conf-sanity test 100: check lshowmount lists MGS, MDT, OST and 0@lo ========================================================== 14:25:01 (1780338301) [ 5291.622345] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5295.946530] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5317.477927] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5319.250326] Lustre: Mounted lustre-client [ 5325.219371] LustreError: 108660:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff98e3c34a3000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5325.308792] Lustre: Unmounted lustre-client [ 5352.714828] Key type lgssc unregistered [ 5353.027649] LNet: 109063:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5353.036959] LNetError: 109063:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5353.064388] LNet: Removed LNI 192.168.202.49@tcp [ 5353.801150] Key type .llcrypt unregistered [ 5353.804949] Key type ._llcrypt unregistered [ 5369.761884] Key type ._llcrypt registered [ 5369.766514] Key type .llcrypt registered [ 5371.731892] Lustre: DEBUG MARKER: == conf-sanity test 101a: Race MDT->OST reconnection with create ========================================================== 14:28:02 (1780338482) [ 5375.741481] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5375.802066] alg: No test for adler32 (adler32-zlib) [ 5377.311333] Lustre: Lustre: Build Version: 2.17.53_36_g8248a7d [ 5377.813809] LNet: Added LNI 192.168.202.49@tcp [8/256/0/180] [ 5379.631855] Key type lgssc registered [ 5381.088932] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5433.288508] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5437.701948] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5460.464549] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5462.053332] Lustre: Mounted lustre-client [ 5703.084617] LustreError: 114359:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff98e3c34a3800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5703.162474] Lustre: Unmounted lustre-client [ 5730.442956] Key type lgssc unregistered [ 5730.731693] LNet: 114760:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5730.738556] LNetError: 114760:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5730.770875] LNet: Removed LNI 192.168.202.49@tcp [ 5731.377711] Key type .llcrypt unregistered [ 5731.379092] Key type ._llcrypt unregistered [ 5747.417896] Key type ._llcrypt registered [ 5747.424919] Key type .llcrypt registered [ 5749.211697] Lustre: DEBUG MARKER: == conf-sanity test 101b: Race events DISCONNECT and ACTIVE in osp ========================================================== 14:34:20 (1780338860) [ 5752.248608] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5752.300949] alg: No test for adler32 (adler32-zlib) [ 5753.445425] Lustre: Lustre: Build Version: 2.17.53_36_g8248a7d [ 5753.731503] LNet: Added LNI 192.168.202.49@tcp [8/256/0/180] [ 5755.527226] Key type lgssc registered [ 5757.164875] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5810.022682] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5814.201753] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5837.261572] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5840.470086] Lustre: Mounted lustre-client [ 5850.602784] Lustre: lustre-OST0000-osc-ffff98e3c3323000: Connection to lustre-OST0000 (at 192.168.202.149@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5905.258338] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5908.640843] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 5915.562129] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff98e3c3323000.ost_server_uuid 50 [ 5918.412927] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff98e3c3323000.ost_server_uuid in IDLE state after 0 sec [ 5922.107633] LustreError: 118913:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff98e3c3323000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5922.191860] Lustre: Unmounted lustre-client [ 5952.650359] Key type lgssc unregistered [ 5952.967417] LNet: 119317:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5952.975643] LNetError: 119317:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5952.989365] LNet: Removed LNI 192.168.202.49@tcp [ 5953.628105] Key type .llcrypt unregistered [ 5953.631150] Key type ._llcrypt unregistered [ 5970.703698] Key type ._llcrypt registered [ 5970.706664] Key type .llcrypt registered [ 5972.502070] Lustre: DEBUG MARKER: SKIP: conf-sanity test_102 skipping excluded test 102 [ 5974.593269] Lustre: DEBUG MARKER: == conf-sanity test 103: rename filesystem name ========== 14:38:06 (1780339086) [ 5983.031056] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5983.062147] alg: No test for adler32 (adler32-zlib) [ 5984.320992] Lustre: Lustre: Build Version: 2.17.53_36_g8248a7d [ 5984.548774] LNet: Added LNI 192.168.202.49@tcp [8/256/0/180] [ 5986.175181] Key type lgssc registered [ 5987.348490] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6082.630096] Lustre: Mounted lustre-client [ 6089.716681] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 6108.129473] Lustre: lustre-OST0001-osc-ffff98e3ca01b800: disconnect after 23s idle [ 6115.855923] LustreError: 122803:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff98e3ca01b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6115.936176] Lustre: Unmounted lustre-client [ 6250.802580] Lustre: Mounted mylustre-client [ 6256.420434] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 6270.458569] LustreError: 125130:0:(lov_obd.c:792:lov_cleanup()) mylustre-clilov-ffff98e3c8502000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6270.476381] LustreError: 125130:0:(lov_obd.c:792:lov_cleanup()) Skipped 1 previous similar message [ 6270.649568] Lustre: Unmounted mylustre-client [ 6416.170103] Lustre: Mounted tfs-client [ 6425.641798] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 6438.234742] LustreError: 127438:0:(lov_obd.c:792:lov_cleanup()) tfs-clilov-ffff98e3c7988000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6438.248956] LustreError: 127438:0:(lov_obd.c:792:lov_cleanup()) Skipped 1 previous similar message [ 6438.349756] Lustre: Unmounted tfs-client [ 6569.159708] Lustre: Mounted lustre-client [ 6574.847098] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 6589.867829] Lustre: DEBUG MARKER: == conf-sanity test 104a: Make sure user defined options are reflected in mount ========================================================== 14:48:21 (1780339701) [ 6593.180922] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 6603.566226] Lustre: Unmounted lustre-client [ 6783.973669] Lustre: client wants to enable acl, but mdt not! [ 6784.028639] Lustre: Mounted lustre-client [ 6793.009583] Lustre: DEBUG MARKER: == conf-sanity test 104b: Mount uses last flock argument ========================================================== 14:51:44 (1780339904) [ 6793.490108] Lustre: client wants to enable acl, but mdt not! [ 6793.516659] Lustre: Mounted lustre-client [ 6796.092216] LustreError: 133719:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff98e3ca133000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6796.107249] LustreError: 133719:0:(lov_obd.c:792:lov_cleanup()) Skipped 1 previous similar message [ 6796.211289] Lustre: Unmounted lustre-client [ 6796.585053] Lustre: client wants to enable acl, but mdt not! [ 6799.221749] Lustre: client wants to enable acl, but mdt not! [ 6809.409403] LustreError: 134250:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff98e3d3f25000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6809.428750] LustreError: 134250:0:(lov_obd.c:792:lov_cleanup()) Skipped 3 previous similar messages [ 6809.546436] Lustre: Unmounted lustre-client [ 6809.554264] Lustre: Skipped 1 previous similar message [ 6812.020152] Lustre: DEBUG MARKER: == conf-sanity test 105: check file creation for ro and rw bind mnt pt ========================================================== 14:52:03 (1780339923) [ 6815.330843] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 6815.336418] Lustre: Skipped 3 previous similar messages [ 6825.684038] Lustre: Unmounted lustre-client [ 6869.742354] Key type lgssc unregistered [ 6870.073587] LNet: 134829:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6870.081775] LNetError: 134829:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6870.117131] LNet: Removed LNI 192.168.202.49@tcp [ 6871.244189] Key type .llcrypt unregistered [ 6871.245981] Key type ._llcrypt unregistered [ 6899.327700] Key type ._llcrypt registered [ 6899.329791] Key type .llcrypt registered [ 6899.882116] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6899.896968] alg: No test for adler32 (adler32-zlib) [ 6901.327382] Lustre: Lustre: Build Version: 2.17.53_36_g8248a7d [ 6901.622903] LNet: Added LNI 192.168.202.49@tcp [8/256/0/180] [ 6903.316585] Key type lgssc registered [ 6904.648416] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6990.640608] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6995.293941] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7020.372634] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7022.195449] Lustre: Mounted lustre-client [ 7022.677548] Lustre: Remounted lustre-client read-only [ 7025.295899] LustreError: 138585:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff98e3c8505800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7025.405318] Lustre: Unmounted lustre-client [ 7064.290726] Key type lgssc unregistered [ 7064.623044] LNet: 138990:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7064.637800] LNetError: 138990:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7065.701813] LNet: Removed LNI 192.168.202.49@tcp [ 7066.663529] Key type .llcrypt unregistered [ 7066.665608] Key type ._llcrypt unregistered [ 7084.439842] Key type ._llcrypt registered [ 7084.447487] Key type .llcrypt registered [ 7086.380203] Lustre: DEBUG MARKER: SKIP: conf-sanity test_106 skipping SLOW test 106 [ 7088.874275] Lustre: DEBUG MARKER: == conf-sanity test 107: Unknown config param should not fail target mounting ========================================================== 14:56:40 (1780340200) [ 7092.584096] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7092.604351] alg: No test for adler32 (adler32-zlib) [ 7094.185905] Lustre: Lustre: Build Version: 2.17.53_36_g8248a7d [ 7094.714226] LNet: Added LNI 192.168.202.49@tcp [8/256/0/180] [ 7096.575611] Key type lgssc registered [ 7099.066646] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7172.238453] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7179.196576] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7202.747530] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7236.265441] Key type lgssc unregistered [ 7236.618946] LNet: 142547:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7236.629193] LNetError: 142547:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7236.657969] LNet: Removed LNI 192.168.202.49@tcp [ 7237.653672] Key type .llcrypt unregistered [ 7237.657277] Key type ._llcrypt unregistered [ 7251.189189] Key type ._llcrypt registered [ 7251.193688] Key type .llcrypt registered [ 7251.889892] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7251.904549] alg: No test for adler32 (adler32-zlib) [ 7253.270626] Lustre: Lustre: Build Version: 2.17.53_36_g8248a7d [ 7253.609784] LNet: Added LNI 192.168.202.49@tcp [8/256/0/180] [ 7255.384089] Key type lgssc registered [ 7257.386766] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7349.750979] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7366.196070] Key type lgssc unregistered [ 7366.646554] LNet: 144513:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7366.663029] LNetError: 144513:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7366.715826] LNet: Removed LNI 192.168.202.49@tcp [ 7368.076293] Key type .llcrypt unregistered [ 7368.081551] Key type ._llcrypt unregistered [ 7390.310953] Key type ._llcrypt registered [ 7390.313498] Key type .llcrypt registered [ 7392.671386] Lustre: DEBUG MARKER: == conf-sanity test 108a: migrate from ldiskfs to ZFS ==== 15:01:43 (1780340503) [ 7394.531279] Lustre: DEBUG MARKER: SKIP: conf-sanity test_108a zfs only test [ 7397.080427] Lustre: DEBUG MARKER: == conf-sanity test 108b: migrate from ZFS to ldiskfs ==== 15:01:48 (1780340508) [ 7411.629789] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7411.653779] alg: No test for adler32 (adler32-zlib) [ 7412.862555] Lustre: Lustre: Build Version: 2.17.53_36_g8248a7d [ 7413.285981] LNet: Added LNI 192.168.202.49@tcp [8/256/0/180] [ 7415.063254] Key type lgssc registered [ 7416.884655] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7509.271532] Lustre: Mounted lustre-client [ 7510.663830] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7510.683516] LustreError: 147097:0:(file.c:252:ll_close_inode_openhandle()) lustre-clilmv-ffff98e3c6ded800: inode [0x200000bd2:0x2:0x0] mdc close failed: rc = -108 [ 7510.739989] Lustre: 145903:0:(llite_lib.c:4192:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.202.149@tcp:/lustre/fid: [0x240000402:0x3:0x0]/ may get corrupted (rc -108) [ 7510.745159] Lustre: 145900:0:(llite_lib.c:4192:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.202.149@tcp:/lustre/fid: [0x200000bd2:0x2:0x0]/ may get corrupted (rc -108) [ 7521.181128] Lustre: Unmounted lustre-client [ 7581.946786] Lustre: DEBUG MARKER: == conf-sanity test 109a: test lctl clear_conf fsname ==== 15:04:52 (1780340692) [ 7716.341314] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7721.183497] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7749.320029] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7750.306089] Lustre: Mounted lustre-client [ 7829.862902] LustreError: 151583:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff98e3c34a7000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7829.954739] Lustre: Unmounted lustre-client [ 7943.860136] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7949.820621] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7976.197840] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7982.667753] Lustre: Mounted lustre-client [ 8013.329757] LustreError: 153476:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff98e3d00eb000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8013.460675] Lustre: Unmounted lustre-client [ 8047.394879] Key type lgssc unregistered [ 8047.778700] LNet: 153881:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8047.795031] LNetError: 153881:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8047.817205] LNet: Removed LNI 192.168.202.49@tcp [ 8049.020986] Key type .llcrypt unregistered [ 8049.022890] Key type ._llcrypt unregistered [ 8071.775134] Key type ._llcrypt registered [ 8071.784472] Key type .llcrypt registered [ 8073.287117] Lustre: DEBUG MARKER: == conf-sanity test 109b: test lctl clear_conf one config ========================================================== 15:13:04 (1780341184) [ 8107.207385] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8107.262116] alg: No test for adler32 (adler32-zlib) [ 8108.404395] Lustre: Lustre: Build Version: 2.17.53_36_g8248a7d [ 8108.731371] LNet: Added LNI 192.168.202.49@tcp [8/256/0/180] [ 8110.455306] Key type lgssc registered [ 8112.519854] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8193.387458] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8197.949426] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8218.376680] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8220.114257] Lustre: Mounted lustre-client [ 8279.282876] LustreError: 158755:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff98e3d2352000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8279.355603] Lustre: Unmounted lustre-client [ 8352.376538] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8356.025730] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8373.317217] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8378.918865] Lustre: Mounted lustre-client [ 8403.093669] LustreError: 160646:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff98e3c798c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8403.160084] Lustre: Unmounted lustre-client [ 8446.737396] Key type lgssc unregistered [ 8447.002683] LNet: 161052:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8447.014603] LNetError: 161052:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8447.030546] LNet: Removed LNI 192.168.202.49@tcp [ 8447.819808] Key type .llcrypt unregistered [ 8447.821572] Key type ._llcrypt unregistered [ 8463.261099] Key type ._llcrypt registered [ 8463.262961] Key type .llcrypt registered [ 8464.378238] Lustre: DEBUG MARKER: == conf-sanity test 110: Adding large_dir with 3-level htree ========================================================== 15:19:36 (1780341576) [ 8476.121870] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8476.154414] alg: No test for adler32 (adler32-zlib) [ 8477.261872] Lustre: Lustre: Build Version: 2.17.53_36_g8248a7d [ 8477.538967] LNet: Added LNI 192.168.202.49@tcp [8/256/0/180] [ 8479.223248] Key type lgssc registered [ 8480.545949] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8559.395035] Lustre: Mounted lustre-client [ 8562.453473] Lustre: Mounted lustre-client [ 9198.422038] LustreError: 165070:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff98e3d3f20000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9198.474696] Lustre: Unmounted lustre-client [ 9199.148179] LustreError: 165083:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff98e3d06f1800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9199.170257] Lustre: Unmounted lustre-client [ 9269.050666] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9271.310899] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9284.690497] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9311.090682] Lustre: DEBUG MARKER: SKIP: conf-sanity test_111 skipping SLOW test 111 [ 9312.372935] Lustre: DEBUG MARKER: == conf-sanity test 112a: mount OST with no_create option ========================================================== 15:33:44 (1780342424) [ 9331.556397] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9334.275957] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9346.369282] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9358.083676] Lustre: Mounted lustre-client [ 9362.419514] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff98e3c3c8c800.ost_server_uuid 50 [ 9363.232413] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff98e3c3c8c800.ost_server_uuid in FULL state after 0 sec [ 9365.636701] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff98e3c3c8c800.ost_server_uuid 50 [ 9366.625215] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff98e3c3c8c800.ost_server_uuid in FULL state after 0 sec [ 9383.908096] Lustre: lustre-OST0001-osc-ffff98e3c3c8c800: Connection to lustre-OST0001 (at 192.168.202.149@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9395.974168] LustreError: 171923:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff98e3c3c8c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9396.029067] Lustre: Unmounted lustre-client [ 9417.099017] Key type lgssc unregistered [ 9417.247867] LNet: 172325:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9417.253753] LNetError: 172325:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9417.269371] LNet: Removed LNI 192.168.202.49@tcp [ 9417.616212] Key type .llcrypt unregistered [ 9417.617677] Key type ._llcrypt unregistered [ 9426.197626] Key type ._llcrypt registered [ 9426.199202] Key type .llcrypt registered [ 9427.021929] Lustre: DEBUG MARKER: == conf-sanity test 112b: mount MDT with no_create option ========================================================== 15:35:39 (1780342539) [ 9428.281273] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9428.294591] alg: No test for adler32 (adler32-zlib) [ 9429.226333] Lustre: Lustre: Build Version: 2.17.53_36_g8248a7d [ 9429.366241] LNet: Added LNI 192.168.202.49@tcp [8/256/0/180] [ 9431.007497] Key type lgssc registered [ 9431.911456] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9450.426550] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9468.676021] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9478.786608] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 9480.085752] Lustre: Mounted lustre-client [ 9576.604980] LustreError: 175727:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff98e3c554c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9576.649133] Lustre: Unmounted lustre-client [ 9604.807972] Key type lgssc unregistered [ 9604.958981] LNet: 176131:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9604.963366] LNetError: 176131:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9604.978992] LNet: Removed LNI 192.168.202.49@tcp [ 9605.303630] Key type .llcrypt unregistered [ 9605.305820] Key type ._llcrypt unregistered [ 9612.671532] Key type ._llcrypt registered [ 9612.672738] Key type .llcrypt registered [ 9613.440996] Lustre: DEBUG MARKER: == conf-sanity test 113: Shadow mountpoint correctly report ro/rw for mounts ========================================================== 15:38:45 (1780342725) [ 9627.628879] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9627.658455] alg: No test for adler32 (adler32-zlib) [ 9628.566683] Lustre: Lustre: Build Version: 2.17.53_36_g8248a7d [ 9628.676534] LNet: Added LNI 192.168.202.49@tcp [8/256/0/180] [ 9630.279198] Key type lgssc registered [ 9630.871851] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9674.249649] Lustre: Mounted lustre-client [ 9677.260588] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 9688.663795] LustreError: 180061:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff98e3c5531000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9688.713880] Lustre: Unmounted lustre-client [ 9773.204355] Lustre: Mounted lustre-client [ 9779.971062] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 9786.204630] LustreError: 182287:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff98e3c79cb800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9786.212829] LustreError: 182287:0:(lov_obd.c:792:lov_cleanup()) Skipped 1 previous similar message [ 9786.329977] Lustre: Unmounted lustre-client [ 9831.340956] Lustre: DEBUG MARKER: SKIP: conf-sanity test_114 skipping SLOW test 114 [ 9832.330334] Lustre: DEBUG MARKER: SKIP: conf-sanity test_115 skipping excluded test 115 [ 9833.422453] Lustre: DEBUG MARKER: == conf-sanity test 116: big size MDT support ============ 15:42:25 (1780342945) [ 9987.781648] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9991.042772] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10006.062205] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10042.938545] Lustre: DEBUG MARKER: == conf-sanity test 117: lctl get_param return errors properly ========================================================== 15:45:55 (1780343155) [10066.417413] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10069.296791] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10086.137502] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10097.205208] Lustre: Mounted lustre-client [10103.156316] LustreError: 189421:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff98e3c45f9800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10103.164785] LustreError: 189421:0:(lov_obd.c:792:lov_cleanup()) Skipped 1 previous similar message [10103.278828] Lustre: Unmounted lustre-client [10127.368386] Key type lgssc unregistered [10127.606529] LNet: 189824:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10127.617494] LNetError: 189824:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [10127.660703] LNet: Removed LNI 192.168.202.49@tcp [10128.118404] Key type .llcrypt unregistered [10128.122189] Key type ._llcrypt unregistered [10140.045570] Key type ._llcrypt registered [10140.048266] Key type .llcrypt registered [10141.142944] Lustre: DEBUG MARKER: == conf-sanity test 119: writeconf on slave mdt shouldn't duplicate mdc/osp and crash ========================================================== 15:47:33 (1780343253) [10145.277387] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10145.303719] alg: No test for adler32 (adler32-zlib) [10146.334994] Lustre: Lustre: Build Version: 2.17.53_36_g8248a7d [10146.576066] LNet: Added LNI 192.168.202.49@tcp [8/256/0/180] [10148.279205] Key type lgssc registered [10149.250873] Lustre: Echo OBD driver; http://www.lustre.org/ [10182.118685] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10185.038847] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10199.189662] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10200.574642] Lustre: Mounted lustre-client [10205.670122] Lustre: lustre-MDT0001-mdc-ffff98e3c45f8000: Connection to lustre-MDT0001 (at 192.168.202.149@tcp) was lost; in progress operations using this service will wait for recovery to complete [10227.215951] LustreError: 193204:0:(obd_config.c:2103:class_config_llog_handler()) MGC192.168.202.149@tcp: cfg command failed: rc = -17 [10227.230657] Lustre: cmd=cf001 0:lustre-MDT0001-mdc 1:mdc 2:lustre-clilmv_UUID [10227.230657] [10227.237548] LustreError: 192843:0:(mgc_request.c:614:do_requeue()) failed processing log: -17 [10528.237345] Lustre: lustre-MDT0001-mdc-ffff98e3c45f8000: Connection to lustre-MDT0001 (at 192.168.202.149@tcp) was lost; in progress operations using this service will wait for recovery to complete [10546.748410] LustreError: 194952:0:(obd_config.c:761:class_setup()) mdc: device 4 already setup: rc = -17 [10546.758298] LustreError: 194952:0:(obd_config.c:866:class_detach()) OBD device 4 still set up [10546.772758] LustreError: 194952:0:(obd_config.c:2103:class_config_llog_handler()) MGC192.168.202.149@tcp: cfg command failed: rc = -17 [10546.785222] Lustre: cmd=cf003 0:lustre-MDT0001-mdc 1:lustre-MDT0001_UUID 2:192.168.202.149@tcp [10546.785222] [10546.792871] LustreError: 192843:0:(mgc_request.c:614:do_requeue()) failed processing log: -17 [10850.791698] Lustre: lustre-MDT0001-mdc-ffff98e3c45f8000: Connection to lustre-MDT0001 (at 192.168.202.149@tcp) was lost; in progress operations using this service will wait for recovery to complete [10875.910252] LustreError: 196717:0:(obd_config.c:2103:class_config_llog_handler()) MGC192.168.202.149@tcp: cfg command failed: rc = -17 [10875.915600] Lustre: cmd=cf014 0:lustre-clilmv 1:lustre-MDT0001_UUID 2:1 3:1 4:lustre-MDT0001-mdc_UUID [10875.915600] [10875.921675] LustreError: 192843:0:(mgc_request.c:614:do_requeue()) failed processing log: -17 [11175.993819] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [11186.300254] Lustre: Unmounted lustre-client [11227.974031] Lustre: DEBUG MARKER: == conf-sanity test 120: cross-target rename should not create bad symlinks ========================================================== 16:05:40 (1780344340) [11243.317331] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11244.819092] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11253.520340] Lustre: DEBUG MARKER: oleg249-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11253.737828] Lustre: Mounted lustre-client [11255.710631] LustreError: 202479:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff98e3c5537000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11255.745237] Lustre: Unmounted lustre-client [11303.798628] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 10950 sec ========= 16:06:56 (1780344416) [11304.428683] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 16:06:57 (1780344417) === [11305.741311] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 16:06:58 (1780344418) === [11318.771395] Key type lgssc unregistered [11318.903413] LNet: 206319:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11318.906604] LNetError: 206319:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [11318.915700] LNet: Removed LNI 192.168.202.49@tcp [11319.224634] Key type .llcrypt unregistered [11319.226163] Key type ._llcrypt unregistered