[ 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 571548873 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, 524584K 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.001011] APIC: Switch to symmetric I/O mode setup [ 0.002322] x2apic enabled [ 0.003006] Switched APIC routing to physical x2apic. [ 0.004018] kvm-guest: setup PV IPIs [ 0.007000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.007000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.007019] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.008011] pid_max: default: 32768 minimum: 301 [ 0.009128] LSM: Security Framework initializing [ 0.010041] Yama: becoming mindful. [ 0.011029] SELinux: Initializing. [ 0.012054] *** VALIDATE selinux *** [ 0.020737] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.024645] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.026063] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.027100] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.028111] *** VALIDATE tmpfs *** [ 0.029412] *** VALIDATE proc *** [ 0.031109] *** VALIDATE cgroup *** [ 0.032006] *** VALIDATE cgroup2 *** [ 0.033237] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.034140] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.035006] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.036033] Spectre V2 : User space: Vulnerable [ 0.037008] Speculative Store Bypass: Vulnerable [ 0.039727] debug: unmapping init [mem 0xffffffff90a59000-0xffffffff90a60fff] [ 0.042000] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.042779] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.043021] ... version: 2 [ 0.044010] ... bit width: 48 [ 0.045007] ... generic registers: 4 [ 0.046009] ... value mask: 0000ffffffffffff [ 0.047010] ... max period: 00007fffffffffff [ 0.048010] ... fixed-purpose events: 3 [ 0.049009] ... event mask: 000000070000000f [ 0.050275] rcu: Hierarchical SRCU implementation. [ 0.052468] smp: Bringing up secondary CPUs ... [ 0.053573] x86: Booting SMP configuration: [ 0.054022] .... node #0, CPUs: #1 #2 #3 [ 0.062344] smp: Brought up 1 node, 4 CPUs [ 0.064009] smpboot: Max logical packages: 1 [ 0.065011] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.113738] node 0 deferred pages initialised in 46ms [ 0.115319] devtmpfs: initialized [ 0.116292] x86/mm: Memory block size: 128MB [ 0.119537] gcov: version magic: 0x41383552 [ 0.121373] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.122171] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.123302] pinctrl core: initialized pinctrl subsystem [ 0.124198] [ 0.124766] ************************************************************* [ 0.125091] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.126081] ** ** [ 0.127011] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.128014] ** ** [ 0.129010] ** This means that this kernel is built to expose internal ** [ 0.130010] ** IOMMU data structures, which may compromise security on ** [ 0.131015] ** your system. ** [ 0.132014] ** ** [ 0.133013] ** If you see this message and you are not debugging the ** [ 0.134015] ** kernel, report this immediately to your vendor! ** [ 0.135014] ** ** [ 0.136013] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.137014] ************************************************************* [ 0.138704] NET: Registered protocol family 16 [ 0.139553] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.140068] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.141057] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.142622] cpuidle: using governor menu [ 0.145146] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.148606] PCI: Using configuration type 1 for base access [ 0.151137] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.162117] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.164022] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.168186] cryptd: max_cpu_qlen set to 1000 [ 0.171618] ACPI: Added _OSI(Module Device) [ 0.172011] ACPI: Added _OSI(Processor Device) [ 0.173011] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.174013] ACPI: Added _OSI(Processor Aggregator Device) [ 0.178773] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.182378] ACPI: Interpreter enabled [ 0.183052] ACPI: PM: (supports S0 S3 S4 S5) [ 0.184098] ACPI: Using IOAPIC for interrupt routing [ 0.185089] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.186449] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.198214] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.199038] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.200027] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.201079] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.202889] acpiphp: Slot [2] registered [ 0.203000] acpiphp: Slot [5] registered [ 0.206104] acpiphp: Slot [6] registered [ 0.208148] acpiphp: Slot [3] registered [ 0.209175] acpiphp: Slot [4] registered [ 0.211121] acpiphp: Slot [7] registered [ 0.213087] acpiphp: Slot [8] registered [ 0.214213] acpiphp: Slot [9] registered [ 0.216180] acpiphp: Slot [10] registered [ 0.218094] acpiphp: Slot [11] registered [ 0.220101] acpiphp: Slot [12] registered [ 0.221070] acpiphp: Slot [13] registered [ 0.223104] acpiphp: Slot [14] registered [ 0.224102] acpiphp: Slot [15] registered [ 0.226120] acpiphp: Slot [16] registered [ 0.228089] acpiphp: Slot [17] registered [ 0.230092] acpiphp: Slot [18] registered [ 0.232119] acpiphp: Slot [19] registered [ 0.233195] acpiphp: Slot [20] registered [ 0.235121] acpiphp: Slot [21] registered [ 0.237120] acpiphp: Slot [22] registered [ 0.239126] acpiphp: Slot [23] registered [ 0.241169] acpiphp: Slot [24] registered [ 0.243095] acpiphp: Slot [25] registered [ 0.245106] acpiphp: Slot [26] registered [ 0.246096] acpiphp: Slot [27] registered [ 0.248131] acpiphp: Slot [28] registered [ 0.250097] acpiphp: Slot [29] registered [ 0.252115] acpiphp: Slot [30] registered [ 0.253177] acpiphp: Slot [31] registered [ 0.255080] PCI host bridge to bus 0000:00 [ 0.257018] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.260051] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.263022] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.266021] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.269020] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.272023] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.274297] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.278173] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.281588] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.290024] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.296014] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.299019] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.302017] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.305015] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.307697] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.310904] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.314045] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.316673] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.321011] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.331782] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.336018] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.342000] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.349011] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.359017] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.368015] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.372377] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.376047] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.380013] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.388017] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.399378] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.401452] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.405376] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.407387] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.409286] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.412108] iommu: Default domain type: Passthrough [ 0.414481] SCSI subsystem initialized [ 0.415103] ACPI: bus type USB registered [ 0.416165] usbcore: registered new interface driver usbfs [ 0.418085] usbcore: registered new interface driver hub [ 0.420092] usbcore: registered new device driver usb [ 0.422175] pps_core: LinuxPPS API ver. 1 registered [ 0.423007] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.426057] PTP clock support registered [ 0.429057] EDAC MC: Ver: 3.0.0 [ 0.430516] PCI: Using ACPI for IRQ routing [ 0.432728] NetLabel: Initializing [ 0.434009] NetLabel: domain hash size = 128 [ 0.435011] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.438083] NetLabel: unlabeled traffic allowed by default [ 0.441625] vgaarb: loaded [ 0.444403] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.446013] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.453371] clocksource: Switched to clocksource kvm-clock [ 0.566559] VFS: Disk quotas dquot_6.6.0 [ 0.567858] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.570579] *** VALIDATE ramfs *** [ 0.571984] *** VALIDATE hugetlbfs *** [ 0.573323] pnp: PnP ACPI init [ 0.575525] pnp: PnP ACPI: found 6 devices [ 0.593032] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.596251] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.598581] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.601203] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.604252] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.623691] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.626396] NET: Registered protocol family 2 [ 0.629665] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.635880] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.639973] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.644743] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.648574] TCP: Hash tables configured (established 65536 bind 65536) [ 0.652137] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.656306] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.660442] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.664644] NET: Registered protocol family 1 [ 0.667431] RPC: Registered named UNIX socket transport module. [ 0.670149] RPC: Registered udp transport module. [ 0.672106] RPC: Registered tcp transport module. [ 0.673878] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.676019] NET: Registered protocol family 44 [ 0.677281] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.679508] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.681740] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.684132] PCI: CLS 0 bytes, default 64 [ 0.685913] Unpacking initramfs... [ 2.231764] debug: unmapping init [mem 0xffff91c9fcc64000-0xffff91c9fffcffff] [ 2.235748] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.237468] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.239810] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.867481] Initialise system trusted keyrings [ 2.868889] Key type blacklist registered [ 2.880714] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.920577] zbud: loaded [ 2.924250] *** VALIDATE nfs *** [ 2.925809] *** VALIDATE nfs4 *** [ 2.927842] pstore: using deflate compression [ 2.932152] Platform Keyring initialized [ 3.057533] NET: Registered protocol family 38 [ 3.060153] Key type asymmetric registered [ 3.061601] Asymmetric key parser 'x509' registered [ 3.120491] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 3.122931] io scheduler mq-deadline registered [ 3.124280] io scheduler kyber registered [ 3.126106] io scheduler bfq registered [ 3.130876] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 3.134316] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 3.137685] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 3.140929] ACPI: Power Button [PWRF] [ 3.147208] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.154319] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.181566] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.212403] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.243200] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.248829] Non-volatile memory driver v1.3 [ 3.250739] Linux agpgart interface v0.103 [ 3.280162] virtio_blk virtio1: [vda] 134864 512-byte logical blocks (69.1 MB/65.9 MiB) [ 3.282490] vda: detected capacity change from 0 to 69050368 [ 3.316524] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.319543] vdb: detected capacity change from 0 to 1073741824 [ 3.326969] libphy: Fixed MDIO Bus: probed [ 3.332481] usbcore: registered new interface driver usbserial_generic [ 3.335291] usbserial: USB Serial support registered for generic [ 3.337488] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.341981] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.343600] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.345907] mousedev: PS/2 mouse device common for all mice [ 3.351469] rtc_cmos 00:05: RTC can wake from S4 [ 3.355324] rtc_cmos 00:05: registered as rtc0 [ 3.356909] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.360489] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.363198] intel_pstate: CPU model not supported [ 3.369639] hid: raw HID events driver (C) Jiri Kosina [ 3.373383] usbcore: registered new interface driver usbhid [ 3.376656] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.379852] usbhid: USB HID core driver [ 3.380120] drop_monitor: Initializing network drop monitor service [ 3.381581] Initializing XFRM netlink socket [ 3.391587] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.400362] NET: Registered protocol family 10 [ 3.410608] Segment Routing with IPv6 [ 3.411880] NET: Registered protocol family 17 [ 3.413886] mpls_gso: MPLS GSO support [ 3.418694] RAS: Correctable Errors collector initialized. [ 3.419854] AVX version of gcm_enc/dec engaged. [ 3.421417] AES CTR mode by8 optimization enabled [ 3.478665] sched_clock: Marking stable (3478649470, 0)->(4357868478, -879219008) [ 3.480754] registered taskstats version 1 [ 3.482157] Loading compiled-in X.509 certificates [ 3.483297] zswap: loaded using pool lzo/zbud [ 3.501275] Key type big_key registered [ 3.510359] Key type encrypted registered [ 3.511557] ima: No TPM chip found, activating TPM-bypass! [ 3.513095] ima: Allocated hash algorithm: sha1 [ 3.514031] ima: No architecture policies found [ 3.514849] evm: Initialising EVM extended attributes: [ 3.515763] evm: security.selinux [ 3.516396] evm: security.ima [ 3.517545] evm: security.capability [ 3.518577] evm: HMAC attrs: 0x1 [ 3.520110] rtc_cmos 00:05: setting system clock to 2026-06-01 15:13:35 UTC (1780326815) [ 3.525288] debug: unmapping init [mem 0xffffffff91a03000-0xffffffff91bfffff] [ 3.527851] debug: unmapping init [mem 0xffffffff90782000-0xffffffff90a58fff] [ 3.535438] Write protecting the kernel read-only data: 28672k [ 3.538050] debug: unmapping init [mem 0xffffffff8ee03000-0xffffffff8effffff] [ 3.541164] debug: unmapping init [mem 0xffffffff8f714000-0xffffffff8f7fffff] [ 3.573786] 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.581529] systemd[1]: Detected virtualization kvm. [ 3.583507] systemd[1]: Detected architecture x86-64. [ 3.585604] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.614235] systemd[1]: No hostname configured. [ 3.617255] systemd[1]: Set hostname to . [ 3.619575] random: systemd: uninitialized urandom read (16 bytes read) [ 3.621989] systemd[1]: Initializing machine ID from random generator. [ 3.853774] random: systemd: uninitialized urandom read (16 bytes read) [ 3.857832] systemd[1]: Reached target Timers. [ OK ] Reached target Timers. [ 3.865721] random: systemd: uninitialized urandom read (16 bytes read) [ 3.893586] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ 3.904200] systemd[1]: Reached target Local Encrypted Volumes. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Listening on Journal Socket. Starting Apply Kernel Variables... Starting Setup Virtual Console... [ OK ] Reached target Local File Systems. [ OK ] Reached target Swap. Starting Create Volatile Files and Directories... [ OK ] Reached target Initrd Root Device. [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Started Memstrack Anylazing Service. [ OK ] Listening on udev Control Socket. Starting Journal Service... [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Sockets. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Slices. [ OK ] Started Apply Kernel Variables. [ OK ] Started Setup Virtual Console. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Create list of required sta…vice nodes for the current kernel. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.849194] device-mapper: uevent: version 1.0.3 [ 4.851650] 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. [ 5.950193] virtio_net virtio0 ens2: renamed from eth0 Starting dracut initqueue hook... [ 7.433331] scsi host0: ata_piix [ 7.666242] scsi host1: ata_piix [ 7.669994] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 7.675960] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 11.053232] random: crng init done [ 11.054697] random: 7 urandom warning(s) missed due to ratelimiting [ 14.353418] dracut-initqueue[586]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 15.573409] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Initrd Default Target. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Timers. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ OK ] Stopped target Sockets. [ OK ] Stopped target System Initialization. [ 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 Swap. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped target Local File Systems. [ OK ] Stopped Apply Kernel Variables. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 17.791178] printk: systemd: 26 output lines suppressed due to ratelimiting [ 18.155648] SELinux: Disabled at runtime. [ 18.216833] 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) [ 18.225849] systemd[1]: Detected virtualization kvm. [ 18.227511] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 18.924761] systemd[1]: initrd-switch-root.service: Succeeded. [ 18.928504] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 18.935201] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 18.940401] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 18.945699] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 18.954925] systemd[1]: Starting Journal Service... Starting Journal Service... [ 18.967030] systemd[1]: Starting Remount Root and Kernel File Systems... Starting Remount Root and Kernel File Systems... [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Listening on Process Core Dump Socket. Activating swap /dev/disk/by-label/SWAP... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target RPC Port Mapper. [ OK ] Reached target Paths. [ 19.165416] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS Mounting Huge Pages File System... Mounting Kernel Debug File System... [ OK ] Created slice system-sshd\x2dkeygen.slice. Starting Apply Kernel Variables... [ OK ] Created slice system-getty.slice. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Reached target rpc_pipefs.target. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd Root File System. [ OK ] Stopped target Initrd File Systems. Mounting POSIX Message Queue File System... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Started Journal Service. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted Huge Pages File System. [ OK ] Mounted Kernel Debug File System. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Reached target Swap. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started udev Coldplug all Devices. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... Starting udev Kernel Device Manager... [ 20.103858] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /mnt. [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 21.508277] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 21.554460] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 22.284951] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 23.335546] EDAC sbridge: Ver: 1.1.2 [ 25.052118] Key type dns_resolver registered [ 25.484868] NFS: Registering the id_resolver key type [ 25.487400] Key type id_resolver registered [ 25.488942] Key type id_legacy registered [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started dnf makecache --timer. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started irqbalance daemon. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Restore /run/initramfs on shutdown... [ OK ] Reached target sshd-keygen.target. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... Starting Login Service... [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Timers. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting OpenSSH server daemon... Starting Dynamic System Tuning Daemon... Starting GSSAPI Proxy Daemon... Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ OK ] Started Login 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 Permit User Sessions. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Getty on tty1. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Notify NFS peers of a restart... Starting System Logging Service... Starting Crash recovery kernel arming... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg234-client login: [ 66.636688] hrtimer: interrupt took 3134674 ns [ 84.689522] libcfs: loading out-of-tree module taints kernel. [ 84.806734] Key type ._llcrypt registered [ 84.808348] Key type .llcrypt registered [ 85.202722] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 85.215440] alg: No test for adler32 (adler32-zlib) [ 86.492530] Lustre: Lustre: Build Version: 2.17.53_33_g3f95abe [ 87.218535] LNet: Added LNI 192.168.202.34@tcp [8/256/0/180] [ 88.943156] Key type lgssc registered [ 90.411642] Lustre: Echo OBD driver; http://www.lustre.org/ [ 218.846285] Lustre: Mounted lustre-client [ 223.607595] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 234.759186] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing check_logdir /tmp/testlogs/ [ 238.602772] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing yml_node [ 241.837465] Lustre: DEBUG MARKER: Client: 2.17.53.33 [ 243.847393] Lustre: DEBUG MARKER: MDS: 2.17.53.33 [ 244.703502] Lustre: lustre-OST0000-osc-ffff91ca468a9000: disconnect after 23s idle [ 245.760286] Lustre: DEBUG MARKER: OSS: 2.17.53.33 [ 247.293341] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Mon Jun 1 11:17:38 EDT 2026 [ 261.157798] Lustre: DEBUG MARKER: excepting tests: 102 106 115 32newtarball [ 262.659950] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 264.714082] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 275.029601] Lustre: Unmounted lustre-client [ 362.636868] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 366.143615] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 382.981971] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 416.190702] Lustre: DEBUG MARKER: == conf-sanity test 76a: set permanent params with lctl across mounts ========================================================== 11:20:27 (1780327227) [ 443.823952] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 447.073809] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 463.884495] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 469.550152] Lustre: Mounted lustre-client [ 476.065403] LustreError: 14219:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff91ca438fb000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 476.126279] Lustre: Unmounted lustre-client [ 568.327698] Lustre: Mounted lustre-client [ 572.649416] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 583.829114] LustreError: 16770:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff91ca50cc0000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 583.917262] Lustre: Unmounted lustre-client [ 675.873868] Lustre: Mounted lustre-client [ 679.647362] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 684.182683] LustreError: 18990:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff91ca5134a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 684.191126] LustreError: 18990:0:(lov_obd.c:792:lov_cleanup()) Skipped 1 previous similar message [ 684.256982] Lustre: Unmounted lustre-client [ 723.953369] Lustre: DEBUG MARKER: == conf-sanity test 76b: verify params log setup correctly ========================================================== 11:25:35 (1780327535) [ 781.170879] Lustre: Mounted lustre-client [ 784.724925] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 788.744515] LustreError: 22197:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff91ca468ad800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 788.750313] LustreError: 22197:0:(lov_obd.c:792:lov_cleanup()) Skipped 1 previous similar message [ 788.823129] Lustre: Unmounted lustre-client [ 829.503985] Lustre: DEBUG MARKER: == conf-sanity test 76c: verify changelog_mask is applied with lctl set_param -P ========================================================== 11:27:20 (1780327640) [ 871.111022] Lustre: Mounted lustre-client [ 874.587960] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 881.637597] Lustre: lustre-MDT0000-mdc-ffff91ca47c29800: Connection to lustre-MDT0000 (at 192.168.202.134@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 891.877920] Lustre: lustre-MDT0001-mdc-ffff91ca47c29800: Connection to lustre-MDT0001 (at 192.168.202.134@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 896.993339] Lustre: lustre-OST0000-osc-ffff91ca47c29800: disconnect after 24s idle [ 896.997800] Lustre: Skipped 1 previous similar message [ 900.069390] LustreError: MGC192.168.202.134@tcp: Connection to MGS (at 192.168.202.134@tcp) was lost; in progress operations using this service will fail [ 900.083705] Lustre: Evicted from MGS (at 192.168.202.134@tcp) after server handle changed from 0xcfa0d7d100e5ab2c to 0xcfa0d7d100e5abe2 [ 900.093172] Lustre: MGC192.168.202.134@tcp: Connection restored to 192.168.202.134@tcp (at 192.168.202.134@tcp) [ 901.867678] Lustre: 24742:0:(mgc_request.c:1899:mgc_process_log()) MGC192.168.202.134@tcp: IR log lustre-cliir failed, not fatal: rc = -2 [ 924.593966] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 926.710674] LustreError: lustre-MDT0000-mdc-ffff91ca47c29800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 926.719956] Lustre: lustre-MDT0000-mdc-ffff91ca47c29800: Connection restored to 192.168.202.134@tcp (at 192.168.202.134@tcp) [ 927.553490] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 2 sec [ 929.777541] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 932.820351] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 2 sec [ 934.067531] LustreError: 26521:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff91ca47c29800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 934.072731] LustreError: 26521:0:(lov_obd.c:792:lov_cleanup()) Skipped 1 previous similar message [ 934.116293] Lustre: Unmounted lustre-client [ 973.195337] Lustre: DEBUG MARKER: == conf-sanity test 76d: verify llite.*.xattr_cache can be set by 'lctl set_param -P' correctly ========================================================== 11:29:44 (1780327784) [ 1015.431612] Lustre: Mounted lustre-client [ 1019.283078] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 1027.288939] LustreError: 29571:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff91ca438ff000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1027.300473] LustreError: 29571:0:(lov_obd.c:792:lov_cleanup()) Skipped 1 previous similar message [ 1027.404893] Lustre: Unmounted lustre-client [ 1087.146803] Lustre: DEBUG MARKER: == conf-sanity test 77: comma-separated MGS NIDs and failover node NIDs ========================================================== 11:31:37 (1780327897) [ 1089.937138] Lustre: DEBUG MARKER: SKIP: conf-sanity test_77 mixed loopback and real device not working [ 1091.762968] Lustre: DEBUG MARKER: == conf-sanity test 78: run resize2fs on MDT and OST filesystems ========================================================== 11:31:42 (1780327902) [ 1150.421625] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1171.969594] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1172.621892] Lustre: Mounted lustre-client [ 1172.623090] Lustre: Skipped 2 previous similar messages [ 1174.349509] Lustre: DEBUG MARKER: create test files [ 1201.634947] LustreError: 33191:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff91ca5448d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1201.643795] LustreError: 33191:0:(lov_obd.c:792:lov_cleanup()) Skipped 5 previous similar messages [ 1201.984817] Lustre: Unmounted lustre-client [ 1201.990053] Lustre: Skipped 2 previous similar messages [ 1220.856683] Key type lgssc unregistered [ 1221.117680] LNet: 33543:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1221.121704] LNetError: 33543:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1221.135420] LNet: Removed LNI 192.168.202.34@tcp [ 1221.705526] Key type .llcrypt unregistered [ 1221.708146] Key type ._llcrypt unregistered [ 1245.083856] Key type ._llcrypt registered [ 1245.087513] Key type .llcrypt registered [ 1245.579471] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1245.614489] alg: No test for adler32 (adler32-zlib) [ 1246.798581] Lustre: Lustre: Build Version: 2.17.53_33_g3f95abe [ 1247.075078] LNet: Added LNI 192.168.202.34@tcp [8/256/0/180] [ 1248.799223] Key type lgssc registered [ 1250.438438] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1284.884365] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1304.329271] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1305.909930] Lustre: Mounted lustre-client [ 1309.148565] Lustre: DEBUG MARKER: check files after expanding the MDT and OST filesystems [ 1314.801957] Lustre: DEBUG MARKER: create more files after expanding the MDT and OST filesystems [ 1318.278545] LustreError: 36024:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff91ca468ab000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1318.436928] Lustre: Unmounted lustre-client [ 1330.425205] Key type lgssc unregistered [ 1330.763563] LNet: 36374:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1330.773038] LNetError: 36374:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1331.815417] LNet: Removed LNI 192.168.202.34@tcp [ 1332.601811] Key type .llcrypt unregistered [ 1332.603428] Key type ._llcrypt unregistered [ 1357.203716] Key type ._llcrypt registered [ 1357.206106] Key type .llcrypt registered [ 1357.633388] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1357.642932] alg: No test for adler32 (adler32-zlib) [ 1359.017835] Lustre: Lustre: Build Version: 2.17.53_33_g3f95abe [ 1359.282118] LNet: Added LNI 192.168.202.34@tcp [8/256/0/180] [ 1360.985279] Key type lgssc registered [ 1363.333920] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1403.959796] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1431.910326] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1434.787252] Lustre: Mounted lustre-client [ 1438.828297] Lustre: DEBUG MARKER: check files after shrinking the MDT and OST filesystems [ 1476.477762] LustreError: 38744:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff91ca45b6b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1476.766144] Lustre: Unmounted lustre-client [ 1497.276668] Key type lgssc unregistered [ 1497.658542] LNet: 39101:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1497.664887] LNetError: 39101:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1497.685666] LNet: Removed LNI 192.168.202.34@tcp [ 1498.307505] Key type .llcrypt unregistered [ 1498.319255] Key type ._llcrypt unregistered [ 1529.529285] Key type ._llcrypt registered [ 1529.538102] Key type .llcrypt registered [ 1529.967636] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1529.978306] alg: No test for adler32 (adler32-zlib) [ 1531.062972] Lustre: Lustre: Build Version: 2.17.53_33_g3f95abe [ 1531.250420] LNet: Added LNI 192.168.202.34@tcp [8/256/0/180] [ 1532.887191] Key type lgssc registered [ 1534.382763] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1617.079435] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1621.691854] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1644.298370] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1690.293747] Lustre: DEBUG MARKER: == conf-sanity test 79: format MDT/OST without mgs option (should return errors) ========================================================== 11:41:40 (1780328500) [ 1829.054843] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1833.379439] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1852.612900] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1889.293198] Lustre: DEBUG MARKER: == conf-sanity test 80: mgc import reconnect race ======== 11:45:00 (1780328700) [ 1915.567305] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1918.999345] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1938.663834] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1993.603959] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 2032.146057] Lustre: DEBUG MARKER: == conf-sanity test 81: sparse OST indexing ============== 11:47:23 (1780328843) [ 2033.220114] Lustre: DEBUG MARKER: SKIP: conf-sanity test_81 needs >= 3 OSTs [ 2034.837545] Lustre: DEBUG MARKER: == conf-sanity test 82a: specify OSTs for file (succeed) or directory (succeed) ========================================================== 11:47:25 (1780328845) [ 2036.272779] Lustre: DEBUG MARKER: SKIP: conf-sanity test_82a needs >= 3 OSTs [ 2037.722738] Lustre: DEBUG MARKER: == conf-sanity test 82b: specify OSTs for file with --pool and --ost-list options ========================================================== 11:47:28 (1780328848) [ 2039.128454] Lustre: DEBUG MARKER: SKIP: conf-sanity test_82b needs >= 4 OSTs [ 2040.741520] Lustre: DEBUG MARKER: == conf-sanity test 82c: specify sparse OSTs for setstripe (should not crash) ========================================================== 11:47:31 (1780328851) [ 2042.265717] Lustre: DEBUG MARKER: SKIP: conf-sanity test_82c needs >= 4 OSTs [ 2043.531238] Lustre: DEBUG MARKER: == conf-sanity test 83: ENOSPACE on OST doesn't cause message VFS: Busy inodes after unmount ... ========================================================== 11:47:34 (1780328854) [ 2044.788882] Lustre: DEBUG MARKER: mount the OST /dev/mapper/ost1_flakey as a ldiskfs filesystem [ 2051.893896] Lustre: DEBUG MARKER: run llverfs in partial mode on the OST ldiskfs /mnt/lustre-ost1 [ 2057.840157] Lustre: DEBUG MARKER: unmount the OST /dev/mapper/ost1_flakey [ 2161.326627] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2164.058660] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2181.234835] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2215.145534] Lustre: DEBUG MARKER: == conf-sanity test 84: check recovery_hard_time ========= 11:50:26 (1780329026) [ 2233.766443] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2235.894947] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2246.254360] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2263.671532] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 2269.848207] Lustre: Mounted lustre-client [ 2282.164384] Lustre: DEBUG MARKER: local REPLAY BARRIER on lustre-MDT0000 [ 2300.927880] Lustre: lustre-MDT0000-mdc-ffff91ca45b97000: Connection to lustre-MDT0000 (at 192.168.202.134@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2316.192077] Lustre: 39717:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1780329112/real 1780329112] req@00000000d23f35ea x1866809573243904/t0(0) o400->MGC192.168.202.134@tcp@192.168.202.134@tcp:26/25 lens 224/224 e 0 to 1 dl 1780329128 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2316.217121] LustreError: MGC192.168.202.134@tcp: Connection to MGS (at 192.168.202.134@tcp) was lost; in progress operations using this service will fail [ 2316.240303] Lustre: Evicted from MGS (at 192.168.202.134@tcp) after server handle changed from 0x795d6ca967065847 to 0x795d6ca967071a25 [ 2316.247546] Lustre: MGC192.168.202.134@tcp: Connection restored to 192.168.202.134@tcp (at 192.168.202.134@tcp) [ 2320.727906] LustreError: 39714:0:(client.c:3438:ptlrpc_replay_interpret()) @@@ status 301, old was 0 req@00000000554ae5a1 x1866809572601216/t8589934601(8589934601) o101->lustre-MDT0000-mdc-ffff91ca45b97000@192.168.202.134@tcp:12/10 lens 592/608 e 0 to 0 dl 1780329148 ref 2 fl Interpret:RQU/604/0 rc 301/301 job:'' uid:0 gid:0 projid:0 [ 2321.372370] LustreError: 39714:0:(client.c:3438:ptlrpc_replay_interpret()) @@@ status 301, old was 0 req@00000000ff2f5417 x1866809572601856/t8589934603(8589934603) o101->lustre-MDT0000-mdc-ffff91ca45b97000@192.168.202.134@tcp:12/10 lens 592/608 e 0 to 0 dl 1780329149 ref 2 fl Interpret:RQU/604/0 rc 301/301 job:'' uid:0 gid:0 projid:0 [ 2322.674028] LustreError: 39714:0:(client.c:3438:ptlrpc_replay_interpret()) @@@ status 301, old was 0 req@000000003df57c88 x1866809572603136/t8589934607(8589934607) o101->lustre-MDT0000-mdc-ffff91ca45b97000@192.168.202.134@tcp:12/10 lens 592/608 e 0 to 0 dl 1780329150 ref 2 fl Interpret:RQU/604/0 rc 301/301 job:'' uid:0 gid:0 projid:0 [ 2322.685512] LustreError: 39714:0:(client.c:3438:ptlrpc_replay_interpret()) Skipped 1 previous similar message [ 2325.233129] LustreError: 39714:0:(client.c:3438:ptlrpc_replay_interpret()) @@@ status 301, old was 0 req@00000000509daba3 x1866809572605696/t8589934615(8589934615) o101->lustre-MDT0000-mdc-ffff91ca45b97000@192.168.202.134@tcp:12/10 lens 592/608 e 0 to 0 dl 1780329152 ref 2 fl Interpret:RQU/604/0 rc 301/301 job:'' uid:0 gid:0 projid:0 [ 2325.252994] LustreError: 39714:0:(client.c:3438:ptlrpc_replay_interpret()) Skipped 3 previous similar messages [ 2329.673980] LustreError: 39714:0:(client.c:3438:ptlrpc_replay_interpret()) @@@ status 301, old was 0 req@0000000077c14646 x1866809572610176/t8589934629(8589934629) o101->lustre-MDT0000-mdc-ffff91ca45b97000@192.168.202.134@tcp:12/10 lens 592/608 e 0 to 0 dl 1780329157 ref 2 fl Interpret:RQU/604/0 rc 301/301 job:'' uid:0 gid:0 projid:0 [ 2329.704797] LustreError: 39714:0:(client.c:3438:ptlrpc_replay_interpret()) Skipped 6 previous similar messages [ 2337.862055] LustreError: 39714:0:(client.c:3438:ptlrpc_replay_interpret()) @@@ status 301, old was 0 req@00000000df9c65a7 x1866809572618496/t8589934655(8589934655) o101->lustre-MDT0000-mdc-ffff91ca45b97000@192.168.202.134@tcp:12/10 lens 592/608 e 0 to 0 dl 1780329165 ref 2 fl Interpret:RQU/604/0 rc 301/301 job:'' uid:0 gid:0 projid:0 [ 2337.874767] LustreError: 39714:0:(client.c:3438:ptlrpc_replay_interpret()) Skipped 12 previous similar messages [ 2354.230786] LustreError: 39714:0:(client.c:3438:ptlrpc_replay_interpret()) @@@ status 301, old was 0 req@0000000034f02e59 x1866809572635136/t8589934707(8589934707) o101->lustre-MDT0000-mdc-ffff91ca45b97000@192.168.202.134@tcp:12/10 lens 592/608 e 0 to 0 dl 1780329181 ref 2 fl Interpret:RQU/604/0 rc 301/301 job:'' uid:0 gid:0 projid:0 [ 2354.246044] LustreError: 39714:0:(client.c:3438:ptlrpc_replay_interpret()) Skipped 25 previous similar messages [ 2375.987867] LustreError: lustre-MDT0000-mdc-ffff91ca45b97000: operation mds_close to node 192.168.202.134@tcp failed: rc = -107 [ 2376.060348] Lustre: lustre-MDT0000-mdc-ffff91ca47c2c000: Connection restored to 192.168.202.134@tcp (at 192.168.202.134@tcp) [ 2381.802279] LustreError: lustre-MDT0000-mdc-ffff91ca45b97000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 2381.908541] LustreError: 60893:0:(file.c:6102:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -5 [ 2382.327475] Lustre: lustre-MDT0000-mdc-ffff91ca45b97000: Connection restored to 192.168.202.134@tcp (at 192.168.202.134@tcp) [ 2386.073727] LustreError: 60993:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff91ca45b97000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2386.134888] Lustre: Unmounted lustre-client [ 2387.155165] LustreError: 61012:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff91ca47c2c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2387.172233] LustreError: 61012:0:(lov_obd.c:792:lov_cleanup()) Skipped 1 previous similar message [ 2387.256282] Lustre: Unmounted lustre-client [ 2418.501227] Lustre: DEBUG MARKER: == conf-sanity test 85: osd_ost init: fail ea_fid_set ==== 11:53:49 (1780329229) [ 2475.789797] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2482.002264] Lustre: DEBUG MARKER: == conf-sanity test 86: Replacing mkfs.lustre -G option == 11:54:53 (1780329293) [ 2506.411914] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2561.794315] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2563.149396] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2571.764221] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2592.539895] Lustre: DEBUG MARKER: == conf-sanity test 87: check if MDT inode can hold EAs with N stripes properly ========================================================== 11:56:43 (1780329403) [ 2593.875041] Key type lgssc unregistered [ 2594.050510] LNet: 68173:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2594.056861] LNetError: 68173:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2594.065373] LNet: Removed LNI 192.168.202.34@tcp [ 2594.366483] Key type .llcrypt unregistered [ 2594.367583] Key type ._llcrypt unregistered [ 2609.454130] Key type ._llcrypt registered [ 2609.456263] Key type .llcrypt registered [ 2609.761814] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2609.776820] alg: No test for adler32 (adler32-zlib) [ 2610.706714] Lustre: Lustre: Build Version: 2.17.53_33_g3f95abe [ 2610.852327] LNet: Added LNI 192.168.202.34@tcp [8/256/0/180] [ 2612.471182] Key type lgssc registered [ 2613.189456] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2667.426206] Lustre: Mounted lustre-client [ 2667.900807] LustreError: 71406:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff91ca42d14800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2667.931123] Lustre: Unmounted lustre-client [ 2688.252383] Lustre: DEBUG MARKER: == conf-sanity test 88: check the default mount options can be overridden ========================================================== 11:58:19 (1780329499) [ 2705.765254] Lustre: DEBUG MARKER: == conf-sanity test 89: check tunefs --param and --erase-param{s} options ========================================================== 11:58:36 (1780329516) [ 2763.239765] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL failover.node=192.0.2.254@tcp0,mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2765.010857] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL failover.node=192.0.2.254@tcp0,mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2775.000221] Lustre: DEBUG MARKER: oleg234-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 [ 2799.870675] Lustre: DEBUG MARKER: == conf-sanity test 90a: check max_mod_rpcs_in_flight is enforced ========================================================== 12:00:11 (1780329611) [ 2812.103783] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2813.510145] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2821.266568] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2831.867289] Lustre: Mounted lustre-client [ 2850.783155] Lustre: 79643:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1780329646/real 1780329646] req@000000007d8300ae x1866810704029440/t0(0) o36->lustre-MDT0000-mdc-ffff91ca47c21800@192.168.202.134@tcp:12/10 lens 488/512 e 0 to 1 dl 1780329662 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2850.783155] Lustre: 79647:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1780329646/real 1780329646] req@000000002db9cdc3 x1866810704030848/t0(0) o36->lustre-MDT0000-mdc-ffff91ca47c21800@192.168.202.134@tcp:12/10 lens 488/512 e 0 to 1 dl 1780329662 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2850.783171] Lustre: 79643:0:(client.c:2480:ptlrpc_expire_one_request()) Skipped 2 previous similar messages [ 2850.783250] Lustre: lustre-MDT0000-mdc-ffff91ca47c21800: Connection to lustre-MDT0000 (at 192.168.202.134@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2850.792334] Lustre: lustre-MDT0000-mdc-ffff91ca47c21800: Connection restored to 192.168.202.134@tcp (at 192.168.202.134@tcp) [ 2850.793843] Lustre: 79647:0:(client.c:2480:ptlrpc_expire_one_request()) Skipped 4 previous similar messages [ 2867.679235] Lustre: 79697:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1780329663/real 1780329663] req@000000003a704767 x1866810704037888/t0(0) o36->lustre-MDT0000-mdc-ffff91ca47c21800@192.168.202.134@tcp:12/10 lens 488/512 e 0 to 1 dl 1780329679 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2867.679334] Lustre: lustre-MDT0000-mdc-ffff91ca47c21800: Connection to lustre-MDT0000 (at 192.168.202.134@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2867.915903] Lustre: 79697:0:(client.c:2480:ptlrpc_expire_one_request()) Skipped 3 previous similar messages [ 2867.948735] Lustre: lustre-MDT0000-mdc-ffff91ca47c21800: Connection restored to 192.168.202.134@tcp (at 192.168.202.134@tcp) [ 2869.379580] LustreError: 79735:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff91ca47c21800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2869.387668] LustreError: 79735:0:(lov_obd.c:792:lov_cleanup()) Skipped 1 previous similar message [ 2869.473629] Lustre: Unmounted lustre-client [ 2917.403635] Key type lgssc unregistered [ 2918.349595] LNet: 80141:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2918.370061] LNetError: 80141:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2918.431747] LNet: Removed LNI 192.168.202.34@tcp [ 2921.328128] Key type .llcrypt unregistered [ 2921.334640] Key type ._llcrypt unregistered [ 2951.198407] Key type ._llcrypt registered [ 2951.201347] Key type .llcrypt registered [ 2953.161538] Lustre: DEBUG MARKER: == conf-sanity test 90b: check max_mod_rpcs_in_flight is enforced after update ========================================================== 12:02:42 (1780329762) [ 2957.660094] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2957.689030] alg: No test for adler32 (adler32-zlib) [ 2958.952848] Lustre: Lustre: Build Version: 2.17.53_33_g3f95abe [ 2959.299481] LNet: Added LNI 192.168.202.34@tcp [8/256/0/180] [ 2961.015192] Key type lgssc registered [ 2963.196889] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3029.504546] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3034.141597] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3055.427592] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3057.320047] Lustre: Mounted lustre-client [ 3080.160532] Lustre: 83262:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1780329876/real 1780329876] req@000000003c86892c x1866811069965184/t0(0) o36->lustre-MDT0000-mdc-ffff91ca50cc5000@192.168.202.134@tcp:12/10 lens 488/512 e 0 to 1 dl 1780329892 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3080.199886] Lustre: lustre-MDT0000-mdc-ffff91ca50cc5000: Connection to lustre-MDT0000 (at 192.168.202.134@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3080.243152] Lustre: lustre-MDT0000-mdc-ffff91ca50cc5000: Connection restored to 192.168.202.134@tcp (at 192.168.202.134@tcp) [ 3098.079194] Lustre: 83309:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1780329894/real 1780329894] req@0000000060435e7d x1866811069978752/t0(0) o36->lustre-MDT0001-mdc-ffff91ca50cc5000@192.168.202.134@tcp:12/10 lens 488/512 e 0 to 1 dl 1780329910 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3098.081077] Lustre: lustre-MDT0001-mdc-ffff91ca50cc5000: Connection to lustre-MDT0001 (at 192.168.202.134@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3098.116983] Lustre: 83309:0:(client.c:2480:ptlrpc_expire_one_request()) Skipped 3 previous similar messages [ 3098.184731] Lustre: lustre-MDT0001-mdc-ffff91ca50cc5000: Connection restored to 192.168.202.134@tcp (at 192.168.202.134@tcp) [ 3115.999487] Lustre: 83340:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1780329911/real 1780329911] req@00000000d0faea77 x1866811069985024/t0(0) o36->lustre-MDT0001-mdc-ffff91ca50cc5000@192.168.202.134@tcp:12/10 lens 488/512 e 0 to 1 dl 1780329927 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3116.001061] Lustre: lustre-MDT0001-mdc-ffff91ca50cc5000: Connection to lustre-MDT0001 (at 192.168.202.134@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3116.036106] Lustre: 83340:0:(client.c:2480:ptlrpc_expire_one_request()) Skipped 3 previous similar messages [ 3116.083074] Lustre: lustre-MDT0001-mdc-ffff91ca50cc5000: Connection restored to 192.168.202.134@tcp (at 192.168.202.134@tcp) [ 3119.426525] LustreError: 83395:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff91ca50cc5000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3119.523220] Lustre: Unmounted lustre-client [ 3120.878802] Lustre: Mounted lustre-client [ 3138.528069] Lustre: 83503:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1780329934/real 1780329934] req@00000000608c4d37 x1866811070026880/t0(0) o36->lustre-MDT0000-mdc-ffff91ca502c4000@192.168.202.134@tcp:12/10 lens 504/512 e 0 to 1 dl 1780329950 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3138.529498] Lustre: lustre-MDT0000-mdc-ffff91ca502c4000: Connection to lustre-MDT0000 (at 192.168.202.134@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3138.551397] Lustre: 83503:0:(client.c:2480:ptlrpc_expire_one_request()) Skipped 2 previous similar messages [ 3138.603561] Lustre: lustre-MDT0000-mdc-ffff91ca502c4000: Connection restored to 192.168.202.134@tcp (at 192.168.202.134@tcp) [ 3156.447282] Lustre: 83539:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1780329952/real 1780329952] req@0000000056075b33 x1866811070040832/t0(0) o36->lustre-MDT0000-mdc-ffff91ca502c4000@192.168.202.134@tcp:12/10 lens 488/512 e 0 to 1 dl 1780329968 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3156.448755] Lustre: lustre-MDT0000-mdc-ffff91ca502c4000: Connection to lustre-MDT0000 (at 192.168.202.134@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3156.494483] Lustre: 83539:0:(client.c:2480:ptlrpc_expire_one_request()) Skipped 3 previous similar messages [ 3156.551345] Lustre: lustre-MDT0000-mdc-ffff91ca502c4000: Connection restored to 192.168.202.134@tcp (at 192.168.202.134@tcp) [ 3170.490349] LustreError: 83609:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff91ca502c4000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3170.612247] Lustre: Unmounted lustre-client [ 3200.477796] Key type lgssc unregistered [ 3200.847747] LNet: 84014:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3200.858708] LNetError: 84014:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3200.903687] LNet: Removed LNI 192.168.202.34@tcp [ 3202.044291] Key type .llcrypt unregistered [ 3202.047903] Key type ._llcrypt unregistered [ 3221.347157] Key type ._llcrypt registered [ 3221.349783] Key type .llcrypt registered [ 3222.751369] Lustre: DEBUG MARKER: == conf-sanity test 90c: check max_mod_rpcs_in_flight update limits ========================================================== 12:07:13 (1780330033) [ 3226.486190] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3226.514821] alg: No test for adler32 (adler32-zlib) [ 3227.867813] Lustre: Lustre: Build Version: 2.17.53_33_g3f95abe [ 3228.343882] LNet: Added LNI 192.168.202.34@tcp [8/256/0/180] [ 3230.103168] Key type lgssc registered [ 3232.231622] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3295.155161] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3299.518418] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3330.932232] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3333.804650] Lustre: Mounted lustre-client [ 3341.866705] LustreError: 87144:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff91ca47be6800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3341.946335] Lustre: Unmounted lustre-client [ 3343.176564] Lustre: Mounted lustre-client [ 3343.334443] LustreError: 87201:0:(genops.c:2263:obd_set_max_mod_rpcs_in_flight()) lustre-MDT0000-mdc-ffff91ca42d13000: 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. [ 3348.737976] LustreError: 87236:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff91ca42d13000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3348.820031] Lustre: Unmounted lustre-client [ 3349.506187] Lustre: Mounted lustre-client [ 3355.683480] LustreError: 87341:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff91ca45b6d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3355.733604] Lustre: Unmounted lustre-client [ 3384.682787] Key type lgssc unregistered [ 3384.961661] LNet: 87744:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3384.972749] LNetError: 87744:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3385.010671] LNet: Removed LNI 192.168.202.34@tcp [ 3385.809958] Key type .llcrypt unregistered [ 3385.813484] Key type ._llcrypt unregistered [ 3402.020898] Key type ._llcrypt registered [ 3402.022501] Key type .llcrypt registered [ 3403.271567] Lustre: DEBUG MARKER: == conf-sanity test 90d: check one close RPC is allowed above max_mod_rpcs_in_flight ========================================================== 12:10:13 (1780330213) [ 3405.871367] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3405.917954] alg: No test for adler32 (adler32-zlib) [ 3407.095521] Lustre: Lustre: Build Version: 2.17.53_33_g3f95abe [ 3407.350880] LNet: Added LNI 192.168.202.34@tcp [8/256/0/180] [ 3409.063253] Key type lgssc registered [ 3410.539842] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3457.365464] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3460.840878] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3477.497305] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3484.267506] Lustre: Mounted lustre-client [ 3503.071226] Lustre: 90853:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1780330299/real 1780330299] req@00000000b374e126 x1866811539735808/t0(0) o36->lustre-MDT0000-mdc-ffff91ca473ab000@192.168.202.134@tcp:12/10 lens 488/512 e 0 to 1 dl 1780330315 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3503.074513] Lustre: lustre-MDT0000-mdc-ffff91ca473ab000: Connection to lustre-MDT0000 (at 192.168.202.134@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3503.092950] Lustre: 90853:0:(client.c:2480:ptlrpc_expire_one_request()) Skipped 3 previous similar messages [ 3503.143576] Lustre: lustre-MDT0000-mdc-ffff91ca473ab000: Connection restored to 192.168.202.134@tcp (at 192.168.202.134@tcp) [ 3504.882990] LustreError: 90894:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff91ca473ab000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3504.997163] Lustre: Unmounted lustre-client [ 3525.710718] Key type lgssc unregistered [ 3525.898864] LNet: 91296:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3525.905284] LNetError: 91296:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3525.924695] LNet: Removed LNI 192.168.202.34@tcp [ 3526.411236] Key type .llcrypt unregistered [ 3526.415281] Key type ._llcrypt unregistered [ 3540.157321] Key type ._llcrypt registered [ 3540.159742] Key type .llcrypt registered [ 3541.475164] Lustre: DEBUG MARKER: == conf-sanity test 91: evict-by-nid support ============= 12:12:31 (1780330351) [ 3543.930935] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3543.968794] alg: No test for adler32 (adler32-zlib) [ 3545.245134] Lustre: Lustre: Build Version: 2.17.53_33_g3f95abe [ 3545.722605] LNet: Added LNI 192.168.202.34@tcp [8/256/0/180] [ 3547.433088] Key type lgssc registered [ 3549.071862] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3591.172671] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3594.037599] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3608.113879] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3619.871423] Lustre: Mounted lustre-client [ 3622.957703] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3633.295222] Lustre: Unmounted lustre-client [ 3680.175686] Key type lgssc unregistered [ 3680.318631] LNet: 94903:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3680.324919] LNetError: 94903:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3680.335676] LNet: Removed LNI 192.168.202.34@tcp [ 3680.649915] Key type .llcrypt unregistered [ 3680.651492] Key type ._llcrypt unregistered [ 3688.157586] Key type ._llcrypt registered [ 3688.159144] Key type .llcrypt registered [ 3688.989804] Lustre: DEBUG MARKER: == conf-sanity test 92: ldev returns MGS NID correctly in command substitution ========================================================== 12:15:00 (1780330500) [ 3694.063424] Lustre: DEBUG MARKER: == conf-sanity test 93: register mulitple MDT at the same time ========================================================== 12:15:05 (1780330505) [ 3694.963620] Lustre: DEBUG MARKER: SKIP: conf-sanity test_93 needs >= 3 MDTs [ 3695.917966] Lustre: DEBUG MARKER: == conf-sanity test 94: ldev outputs correct labels for file system name query ========================================================== 12:15:07 (1780330507) [ 3700.328039] Lustre: DEBUG MARKER: == conf-sanity test 95: ldev should only allow one label filter ========================================================== 12:15:11 (1780330511) [ 3705.366428] Lustre: DEBUG MARKER: == conf-sanity test 96: ldev returns hostname and backend fs correctly in command sub ========================================================== 12:15:16 (1780330516) [ 3709.457475] Lustre: DEBUG MARKER: == conf-sanity test 97: ldev returns correct ouput when querying based on role ========================================================== 12:15:20 (1780330520) [ 3713.484907] Lustre: DEBUG MARKER: == conf-sanity test 98: Buffer-overflow check while parsing mount_opts ========================================================== 12:15:24 (1780330524) [ 3714.584875] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3714.594645] alg: No test for adler32 (adler32-zlib) [ 3715.479407] Lustre: Lustre: Build Version: 2.17.53_33_g3f95abe [ 3715.583979] LNet: Added LNI 192.168.202.34@tcp [8/256/0/180] [ 3717.199171] Key type lgssc registered [ 3717.888935] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3743.081267] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3746.425599] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3769.689239] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3772.892142] Lustre: Mounted lustre-client [ 3776.523452] LustreError: 102226:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff91ca52fda000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3776.584547] Lustre: Unmounted lustre-client [ 3809.338767] Key type lgssc unregistered [ 3809.843954] LNet: 102630:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3809.868512] LNetError: 102630:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3809.923960] LNet: Removed LNI 192.168.202.34@tcp [ 3811.123693] Key type .llcrypt unregistered [ 3811.127544] Key type ._llcrypt unregistered [ 3833.597803] Key type ._llcrypt registered [ 3833.599823] Key type .llcrypt registered [ 3835.298617] Lustre: DEBUG MARKER: == conf-sanity test 99: Adding meta_bg option ============ 12:17:25 (1780330645) [ 3838.247888] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3838.273291] alg: No test for adler32 (adler32-zlib) [ 3839.493676] Lustre: Lustre: Build Version: 2.17.53_33_g3f95abe [ 3839.859232] LNet: Added LNI 192.168.202.34@tcp [8/256/0/180] [ 3841.624366] Key type lgssc registered [ 3843.452856] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3878.956455] Lustre: DEBUG MARKER: == conf-sanity test 100: check lshowmount lists MGS, MDT, OST and 0@lo ========================================================== 12:18:08 (1780330688) [ 3989.063486] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3994.010589] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4019.727856] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4021.294353] Lustre: Mounted lustre-client [ 4027.115165] LustreError: 108450:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff91ca5448e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4027.237794] Lustre: Unmounted lustre-client [ 4056.953436] Key type lgssc unregistered [ 4057.140980] LNet: 108853:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4057.145494] LNetError: 108853:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4057.155783] LNet: Removed LNI 192.168.202.34@tcp [ 4058.037953] Key type .llcrypt unregistered [ 4058.047222] Key type ._llcrypt unregistered [ 4077.380637] Key type ._llcrypt registered [ 4077.385193] Key type .llcrypt registered [ 4079.074547] Lustre: DEBUG MARKER: == conf-sanity test 101a: Race MDT->OST reconnection with create ========================================================== 12:21:29 (1780330889) [ 4081.876386] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4081.996647] alg: No test for adler32 (adler32-zlib) [ 4083.444931] Lustre: Lustre: Build Version: 2.17.53_33_g3f95abe [ 4083.878919] LNet: Added LNI 192.168.202.34@tcp [8/256/0/180] [ 4085.527204] Key type lgssc registered [ 4087.923079] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4150.659969] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4155.421139] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4177.476076] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4179.405565] Lustre: Mounted lustre-client [ 6289.698724] LustreError: 120913:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff91ca42d12800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6289.791138] Lustre: Unmounted lustre-client [ 6323.849247] Key type lgssc unregistered [ 6324.024928] LNet: 121319:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6324.029801] LNetError: 121319:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6325.095122] LNet: Removed LNI 192.168.202.34@tcp [ 6325.694363] Key type .llcrypt unregistered [ 6325.696430] Key type ._llcrypt unregistered [ 6347.329733] Key type ._llcrypt registered [ 6347.331602] Key type .llcrypt registered [ 6348.902811] Lustre: DEBUG MARKER: == conf-sanity test 101b: Race events DISCONNECT and ACTIVE in osp ========================================================== 12:59:19 (1780333159) [ 6351.841883] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6351.858667] alg: No test for adler32 (adler32-zlib) [ 6352.945370] Lustre: Lustre: Build Version: 2.17.53_33_g3f95abe [ 6353.118564] LNet: Added LNI 192.168.202.34@tcp [8/256/0/180] [ 6354.775183] Key type lgssc registered [ 6356.215266] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6420.223470] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6424.279075] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6445.367262] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6448.533158] Lustre: Mounted lustre-client [ 6463.980886] Lustre: lustre-OST0000-osc-ffff91ca468af800: Connection to lustre-OST0000 (at 192.168.202.134@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6517.988060] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6519.814658] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6524.898551] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff91ca468af800.ost_server_uuid 50 [ 6526.806924] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff91ca468af800.ost_server_uuid in FULL state after 0 sec [ 6530.067223] LustreError: 125475:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff91ca468af800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6530.185353] Lustre: Unmounted lustre-client [ 6561.426047] Key type lgssc unregistered [ 6561.833428] LNet: 125879:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6561.864450] LNetError: 125879:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6562.928574] LNet: Removed LNI 192.168.202.34@tcp [ 6563.969586] Key type .llcrypt unregistered [ 6563.972294] Key type ._llcrypt unregistered [ 6584.113830] Key type ._llcrypt registered [ 6584.127988] Key type .llcrypt registered [ 6585.684701] Lustre: DEBUG MARKER: SKIP: conf-sanity test_102 skipping excluded test 102 [ 6588.021652] Lustre: DEBUG MARKER: == conf-sanity test 103: rename filesystem name ========== 13:03:18 (1780333398) [ 6598.825733] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6598.932204] alg: No test for adler32 (adler32-zlib) [ 6600.267938] Lustre: Lustre: Build Version: 2.17.53_33_g3f95abe [ 6600.809278] LNet: Added LNI 192.168.202.34@tcp [8/256/0/180] [ 6602.727306] Key type lgssc registered [ 6604.234993] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6703.810863] Lustre: Mounted lustre-client [ 6709.901872] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 6729.707221] LustreError: 129363:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff91ca51348800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6729.710611] Lustre: lustre-OST0001-osc-ffff91ca51348800: disconnect after 23s idle [ 6729.836053] Lustre: Unmounted lustre-client [ 6870.843213] Lustre: Mounted mylustre-client [ 6878.443667] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 6894.570327] LustreError: 131690:0:(lov_obd.c:792:lov_cleanup()) mylustre-clilov-ffff91ca6f1d9000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6894.579114] LustreError: 131690:0:(lov_obd.c:792:lov_cleanup()) Skipped 1 previous similar message [ 6894.724410] Lustre: Unmounted mylustre-client [ 7028.525863] Lustre: Mounted tfs-client [ 7034.632183] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 7045.671808] LustreError: 134003:0:(lov_obd.c:792:lov_cleanup()) tfs-clilov-ffff91ca45b90800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7045.679923] LustreError: 134003:0:(lov_obd.c:792:lov_cleanup()) Skipped 1 previous similar message [ 7045.757407] Lustre: Unmounted tfs-client [ 7168.899955] Lustre: Mounted lustre-client [ 7173.816578] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 7184.167789] Lustre: DEBUG MARKER: == conf-sanity test 104a: Make sure user defined options are reflected in mount ========================================================== 13:13:14 (1780333994) [ 7186.857507] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7197.161324] Lustre: Unmounted lustre-client [ 7368.890271] Lustre: client wants to enable acl, but mdt not! [ 7368.934047] Lustre: Mounted lustre-client [ 7376.711732] Lustre: DEBUG MARKER: == conf-sanity test 104b: Mount uses last flock argument ========================================================== 13:16:27 (1780334187) [ 7377.156229] Lustre: client wants to enable acl, but mdt not! [ 7377.178155] Lustre: Mounted lustre-client [ 7379.623530] LustreError: 140283:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff91ca452df000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7379.637311] LustreError: 140283:0:(lov_obd.c:792:lov_cleanup()) Skipped 1 previous similar message [ 7379.722383] Lustre: Unmounted lustre-client [ 7380.175156] Lustre: client wants to enable acl, but mdt not! [ 7382.900882] Lustre: client wants to enable acl, but mdt not! [ 7389.631529] LustreError: 140819:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff91ca6f1de000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7389.648223] LustreError: 140819:0:(lov_obd.c:792:lov_cleanup()) Skipped 3 previous similar messages [ 7389.705601] Lustre: Unmounted lustre-client [ 7389.708632] Lustre: Skipped 1 previous similar message [ 7391.284320] Lustre: DEBUG MARKER: == conf-sanity test 105: check file creation for ro and rw bind mnt pt ========================================================== 13:16:41 (1780334201) [ 7392.876807] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7392.879948] Lustre: Skipped 3 previous similar messages [ 7440.179335] Key type lgssc unregistered [ 7440.513503] LNet: 141397:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7440.525939] LNetError: 141397:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7441.574917] LNet: Removed LNI 192.168.202.34@tcp [ 7442.701681] Key type .llcrypt unregistered [ 7442.708072] Key type ._llcrypt unregistered [ 7470.595664] Key type ._llcrypt registered [ 7470.600894] Key type .llcrypt registered [ 7471.391616] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7471.410582] alg: No test for adler32 (adler32-zlib) [ 7472.599316] Lustre: Lustre: Build Version: 2.17.53_33_g3f95abe [ 7473.056531] LNet: Added LNI 192.168.202.34@tcp [8/256/0/180] [ 7474.847305] Key type lgssc registered [ 7476.702981] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7551.398807] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7555.463036] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7578.786844] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7580.292522] Lustre: Mounted lustre-client [ 7580.724603] Lustre: Remounted lustre-client read-only [ 7582.465553] LustreError: 145151:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff91ca79ef3000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7582.578219] Lustre: Unmounted lustre-client [ 7611.390683] Key type lgssc unregistered [ 7611.680554] LNet: 145554:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7611.690592] LNetError: 145554:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7611.720101] LNet: Removed LNI 192.168.202.34@tcp [ 7612.466280] Key type .llcrypt unregistered [ 7612.468678] Key type ._llcrypt unregistered [ 7629.648884] Key type ._llcrypt registered [ 7629.654447] Key type .llcrypt registered [ 7631.347036] Lustre: DEBUG MARKER: SKIP: conf-sanity test_106 skipping SLOW test 106 [ 7632.740568] Lustre: DEBUG MARKER: == conf-sanity test 107: Unknown config param should not fail target mounting ========================================================== 13:20:43 (1780334443) [ 7635.156153] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7635.173926] alg: No test for adler32 (adler32-zlib) [ 7636.307118] Lustre: Lustre: Build Version: 2.17.53_33_g3f95abe [ 7636.582210] LNet: Added LNI 192.168.202.34@tcp [8/256/0/180] [ 7638.337392] Key type lgssc registered [ 7639.207675] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7695.731551] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7700.494908] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7726.524930] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7757.508771] Key type lgssc unregistered [ 7757.757943] LNet: 149107:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7757.766289] LNetError: 149107:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7757.785595] LNet: Removed LNI 192.168.202.34@tcp [ 7758.750511] Key type .llcrypt unregistered [ 7758.753559] Key type ._llcrypt unregistered [ 7771.126921] Key type ._llcrypt registered [ 7771.139021] Key type .llcrypt registered [ 7771.713809] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7771.721855] alg: No test for adler32 (adler32-zlib) [ 7773.173979] Lustre: Lustre: Build Version: 2.17.53_33_g3f95abe [ 7773.384798] LNet: Added LNI 192.168.202.34@tcp [8/256/0/180] [ 7775.127265] Key type lgssc registered [ 7776.821307] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7871.823483] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7886.465800] Key type lgssc unregistered [ 7886.810685] LNet: 151061:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7886.821777] LNetError: 151061:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7886.843605] LNet: Removed LNI 192.168.202.34@tcp [ 7887.516757] Key type .llcrypt unregistered [ 7887.518072] Key type ._llcrypt unregistered [ 7907.924436] Key type ._llcrypt registered [ 7907.927194] Key type .llcrypt registered [ 7909.787509] Lustre: DEBUG MARKER: == conf-sanity test 108a: migrate from ldiskfs to ZFS ==== 13:25:19 (1780334719) [ 7911.982148] Lustre: DEBUG MARKER: SKIP: conf-sanity test_108a zfs only test [ 7914.426235] Lustre: DEBUG MARKER: == conf-sanity test 108b: migrate from ZFS to ldiskfs ==== 13:25:24 (1780334724) [ 7930.576407] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7930.592539] alg: No test for adler32 (adler32-zlib) [ 7931.976519] Lustre: Lustre: Build Version: 2.17.53_33_g3f95abe [ 7932.479799] LNet: Added LNI 192.168.202.34@tcp [8/256/0/180] [ 7934.399206] Key type lgssc registered [ 7936.477429] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8019.481927] Lustre: Mounted lustre-client [ 8020.704728] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 8020.722557] LustreError: 153640:0:(file.c:252:ll_close_inode_openhandle()) lustre-clilmv-ffff91ca45b96800: inode [0x200000bd2:0x2:0x0] mdc close failed: rc = -108 [ 8020.755544] Lustre: 152449:0:(llite_lib.c:4192:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.202.134@tcp:/lustre/fid: [0x240000402:0x3:0x0]/ may get corrupted (rc -108) [ 8020.760490] Lustre: 152446:0:(llite_lib.c:4192:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.202.134@tcp:/lustre/fid: [0x200000bd2:0x2:0x0]/ may get corrupted (rc -108) [ 8031.123745] Lustre: Unmounted lustre-client [ 8072.740578] Lustre: DEBUG MARKER: == conf-sanity test 109a: test lctl clear_conf fsname ==== 13:28:02 (1780334882) [ 8199.815848] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8205.108562] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8232.070262] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8233.031671] Lustre: Mounted lustre-client [ 8305.333113] LustreError: 158110:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff91ca47be0800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8305.446541] Lustre: Unmounted lustre-client [ 8394.849266] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8399.386534] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8422.448302] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8423.089490] Lustre: Mounted lustre-client [ 8451.363149] LustreError: 160004:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff91ca583be800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8451.416518] Lustre: Unmounted lustre-client [ 8483.502170] Key type lgssc unregistered [ 8483.872817] LNet: 160407:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8483.890591] LNetError: 160407:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8484.907639] LNet: Removed LNI 192.168.202.34@tcp [ 8486.014654] Key type .llcrypt unregistered [ 8486.017715] Key type ._llcrypt unregistered [ 8504.001969] Key type ._llcrypt registered [ 8504.004550] Key type .llcrypt registered [ 8505.489818] Lustre: DEBUG MARKER: == conf-sanity test 109b: test lctl clear_conf one config ========================================================== 13:35:15 (1780335315) [ 8539.068987] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8539.102428] alg: No test for adler32 (adler32-zlib) [ 8540.212418] Lustre: Lustre: Build Version: 2.17.53_33_g3f95abe [ 8540.368990] LNet: Added LNI 192.168.202.34@tcp [8/256/0/180] [ 8542.039177] Key type lgssc registered [ 8543.914282] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8630.207418] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8634.566856] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8658.252861] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8661.183326] Lustre: Mounted lustre-client [ 8730.864198] LustreError: 165303:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff91ca468ad800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8730.914379] Lustre: Unmounted lustre-client [ 8809.802667] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8812.823494] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8830.012606] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8830.615315] Lustre: Mounted lustre-client [ 8846.503468] LustreError: 167190:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff91ca583cd800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8846.567615] Lustre: Unmounted lustre-client [ 8875.135944] Key type lgssc unregistered [ 8875.444240] LNet: 167593:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8875.459854] LNetError: 167593:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8875.494771] LNet: Removed LNI 192.168.202.34@tcp [ 8876.606505] Key type .llcrypt unregistered [ 8876.615392] Key type ._llcrypt unregistered [ 8898.185224] Key type ._llcrypt registered [ 8898.186411] Key type .llcrypt registered [ 8899.335589] Lustre: DEBUG MARKER: == conf-sanity test 110: Adding large_dir with 3-level htree ========================================================== 13:41:50 (1780335710) [ 8914.003124] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8914.012497] alg: No test for adler32 (adler32-zlib) [ 8915.153448] Lustre: Lustre: Build Version: 2.17.53_33_g3f95abe [ 8915.515903] LNet: Added LNI 192.168.202.34@tcp [8/256/0/180] [ 8917.263182] Key type lgssc registered [ 8919.126579] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9010.077511] Lustre: Mounted lustre-client [ 9013.364031] Lustre: Mounted lustre-client [ 9797.924436] LustreError: 171637:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff91ca509d7800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9797.971080] Lustre: Unmounted lustre-client [ 9800.875583] LustreError: 171651:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff91ca583cf800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9800.964334] Lustre: Unmounted lustre-client [ 9927.392624] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9931.329687] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9954.932913] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10002.288737] Lustre: DEBUG MARKER: SKIP: conf-sanity test_111 skipping SLOW test 111 [10004.826732] Lustre: DEBUG MARKER: == conf-sanity test 112a: mount OST with no_create option ========================================================== 14:00:14 (1780336814) [10040.095151] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10043.198257] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10061.308297] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10081.380622] Lustre: Mounted lustre-client [10090.465577] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff91ca45b95000.ost_server_uuid 50 [10092.249654] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff91ca45b95000.ost_server_uuid in FULL state after 0 sec [10096.431752] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff91ca45b95000.ost_server_uuid 50 [10097.912406] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff91ca45b95000.ost_server_uuid in FULL state after 0 sec [10117.113517] Lustre: lustre-OST0001-osc-ffff91ca45b95000: Connection to lustre-OST0001 (at 192.168.202.134@tcp) was lost; in progress operations using this service will wait for recovery to complete [10132.611056] LustreError: 178524:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff91ca45b95000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10132.727875] Lustre: Unmounted lustre-client [10160.837298] Key type lgssc unregistered [10161.137311] LNet: 178927:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10161.146477] LNetError: 178927:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [10161.164381] LNet: Removed LNI 192.168.202.34@tcp [10161.898098] Key type .llcrypt unregistered [10161.900352] Key type ._llcrypt unregistered [10182.654617] Key type ._llcrypt registered [10182.656483] Key type .llcrypt registered [10184.360707] Lustre: DEBUG MARKER: == conf-sanity test 112b: mount MDT with no_create option ========================================================== 14:03:14 (1780336994) [10187.136856] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10187.153235] alg: No test for adler32 (adler32-zlib) [10188.345784] Lustre: Lustre: Build Version: 2.17.53_33_g3f95abe [10188.668869] LNet: Added LNI 192.168.202.34@tcp [8/256/0/180] [10190.383269] Key type lgssc registered [10192.039638] Lustre: Echo OBD driver; http://www.lustre.org/ [10229.481821] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10265.876287] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10289.663407] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [10292.636501] Lustre: Mounted lustre-client [10411.591867] LustreError: 182347:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff91ca42d12000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10411.666919] Lustre: Unmounted lustre-client [10457.651880] Key type lgssc unregistered [10457.909139] LNet: 182753:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10457.920316] LNetError: 182753:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [10457.943523] LNet: Removed LNI 192.168.202.34@tcp [10458.656452] Key type .llcrypt unregistered [10458.658397] Key type ._llcrypt unregistered [10476.384825] Key type ._llcrypt registered [10476.387444] Key type .llcrypt registered [10477.460763] Lustre: DEBUG MARKER: == conf-sanity test 113: Shadow mountpoint correctly report ro/rw for mounts ========================================================== 14:08:08 (1780337288) [10499.578738] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10499.605508] alg: No test for adler32 (adler32-zlib) [10500.716213] Lustre: Lustre: Build Version: 2.17.53_33_g3f95abe [10500.878074] LNet: Added LNI 192.168.202.34@tcp [8/256/0/180] [10502.591228] Key type lgssc registered [10503.615837] Lustre: Echo OBD driver; http://www.lustre.org/ [10590.030492] Lustre: Mounted lustre-client [10595.666694] Lustre: DEBUG MARKER: Using TIMEOUT=20 [10612.926786] LustreError: 186673:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff91ca452dd800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10613.063941] Lustre: Unmounted lustre-client [10749.938220] Lustre: Mounted lustre-client [10755.948320] Lustre: DEBUG MARKER: Using TIMEOUT=20 [10762.430959] LustreError: 188906:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff91ca6f1da000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10762.442433] LustreError: 188906:0:(lov_obd.c:792:lov_cleanup()) Skipped 1 previous similar message [10762.543454] Lustre: Unmounted lustre-client [10796.862577] Lustre: DEBUG MARKER: SKIP: conf-sanity test_114 skipping SLOW test 114 [10798.563327] Lustre: DEBUG MARKER: SKIP: conf-sanity test_115 skipping excluded test 115 [10800.036516] Lustre: DEBUG MARKER: == conf-sanity test 116: big size MDT support ============ 14:13:30 (1780337610) [11037.234432] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11040.813945] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11064.668047] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11131.829646] Lustre: DEBUG MARKER: == conf-sanity test 117: lctl get_param return errors properly ========================================================== 14:19:02 (1780337942) [11160.938214] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11165.548448] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11186.313899] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11187.055374] Lustre: Mounted lustre-client [11193.527453] LustreError: 196050:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff91ca45b6a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11193.535280] LustreError: 196050:0:(lov_obd.c:792:lov_cleanup()) Skipped 1 previous similar message [11193.625550] Lustre: Unmounted lustre-client [11222.516403] Key type lgssc unregistered [11222.827812] LNet: 196454:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11222.831858] LNetError: 196454:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [11223.846243] LNet: Removed LNI 192.168.202.34@tcp [11224.741959] Key type .llcrypt unregistered [11224.747175] Key type ._llcrypt unregistered [11245.646626] Key type ._llcrypt registered [11245.652647] Key type .llcrypt registered [11247.447438] Lustre: DEBUG MARKER: == conf-sanity test 119: writeconf on slave mdt shouldn't duplicate mdc/osp and crash ========================================================== 14:20:57 (1780338057) [11253.814296] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11253.857287] alg: No test for adler32 (adler32-zlib) [11255.074664] Lustre: Lustre: Build Version: 2.17.53_33_g3f95abe [11255.403563] LNet: Added LNI 192.168.202.34@tcp [8/256/0/180] [11257.167239] Key type lgssc registered [11258.827803] Lustre: Echo OBD driver; http://www.lustre.org/ [11317.383585] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11321.476925] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11342.463235] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11344.347313] Lustre: Mounted lustre-client [11354.604394] Lustre: lustre-MDT0001-mdc-ffff91ca468aa000: Connection to lustre-MDT0001 (at 192.168.202.134@tcp) was lost; in progress operations using this service will wait for recovery to complete [11375.605048] LustreError: 199810:0:(obd_config.c:2103:class_config_llog_handler()) MGC192.168.202.134@tcp: cfg command failed: rc = -17 [11375.613328] Lustre: cmd=cf001 0:lustre-MDT0001-mdc 1:mdc 2:lustre-clilmv_UUID [11375.613328] [11375.618678] LustreError: 199477:0:(mgc_request.c:614:do_requeue()) failed processing log: -17 [11682.292058] Lustre: lustre-MDT0001-mdc-ffff91ca468aa000: Connection to lustre-MDT0001 (at 192.168.202.134@tcp) was lost; in progress operations using this service will wait for recovery to complete [11708.436801] LustreError: 201336:0:(obd_config.c:761:class_setup()) mdc: device 4 already setup: rc = -17 [11708.442579] LustreError: 201336:0:(obd_config.c:866:class_detach()) OBD device 4 still set up [11708.447299] LustreError: 201336:0:(obd_config.c:2103:class_config_llog_handler()) MGC192.168.202.134@tcp: cfg command failed: rc = -17 [11708.459888] Lustre: cmd=cf003 0:lustre-MDT0001-mdc 1:lustre-MDT0001_UUID 2:192.168.202.134@tcp [11708.459888] [11708.466094] LustreError: 199477:0:(mgc_request.c:614:do_requeue()) failed processing log: -17 [12015.086247] Lustre: lustre-MDT0001-mdc-ffff91ca468aa000: Connection to lustre-MDT0001 (at 192.168.202.134@tcp) was lost; in progress operations using this service will wait for recovery to complete [12037.162367] LustreError: 202841:0:(obd_config.c:2103:class_config_llog_handler()) MGC192.168.202.134@tcp: cfg command failed: rc = -17 [12037.175491] Lustre: cmd=cf014 0:lustre-clilmv 1:lustre-MDT0001_UUID 2:1 3:1 4:lustre-MDT0001-mdc_UUID [12037.175491] [12037.194207] LustreError: 199477:0:(mgc_request.c:614:do_requeue()) failed processing log: -17 [12339.941942] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [12350.335212] Lustre: Unmounted lustre-client [12448.038476] Lustre: DEBUG MARKER: == conf-sanity test 120: cross-target rename should not create bad symlinks ========================================================== 14:40:58 (1780339258) [12489.798500] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12493.872734] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12518.677856] Lustre: DEBUG MARKER: oleg234-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12519.541989] Lustre: Mounted lustre-client [12524.580141] LustreError: 208119:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff91ca47be7800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12524.672648] Lustre: Unmounted lustre-client [12631.476772] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 12382 sec ========= 14:44:01 (1780339441) [12633.537628] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 14:44:03 (1780339443) === [12637.622555] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 14:44:07 (1780339447) === [12677.860208] Key type lgssc unregistered [12678.208197] LNet: 211972:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12678.221882] LNetError: 211972:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [12678.256178] LNet: Removed LNI 192.168.202.34@tcp [12679.270497] Key type .llcrypt unregistered [12679.277952] Key type ._llcrypt unregistered