[ 0.000000] Linux version 4.18.0rh8.10-debug (green@maintenance) (gcc version 8.5.0 20210514 (Red Hat 8.5.0-26) (GCC)) #2 SMP Mon Jul 14 01:24:22 EDT 2025 [ 0.000000] Command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' [ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 [ 0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. [ 0.000000] signal: max sigframe size: 1776 [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bffd9fff] usable [ 0.000000] BIOS-e820: [mem 0x00000000bffda000-0x00000000bfffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x0000000146dfffff] usable [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] SMBIOS 2.8 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.17.0-8.fc42 06/10/2025 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 351194894 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2421 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE22BD 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 00227D (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2331 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE23C1 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE23F9 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe22bd-0xbffe2330] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22bc] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2331-0xbffe23c0] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe23c1-0xbffe23f8] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe23f9-0xbffe2420] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001011] APIC: Switch to symmetric I/O mode setup [ 0.002351] x2apic enabled [ 0.003008] Switched APIC routing to physical x2apic. [ 0.004018] kvm-guest: setup PV IPIs [ 0.007442] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.008000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.008017] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009008] pid_max: default: 32768 minimum: 301 [ 0.010087] LSM: Security Framework initializing [ 0.010949] Yama: becoming mindful. [ 0.011019] SELinux: Initializing. [ 0.012055] *** VALIDATE selinux *** [ 0.019357] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.023438] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.024138] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.025055] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.026000] *** VALIDATE tmpfs *** [ 0.026000] *** VALIDATE proc *** [ 0.026163] *** VALIDATE cgroup *** [ 0.027007] *** VALIDATE cgroup2 *** [ 0.029185] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.030114] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.031003] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.032021] Spectre V2 : User space: Vulnerable [ 0.033003] Speculative Store Bypass: Vulnerable [ 0.036227] debug: unmapping init [mem 0xffffffff99c59000-0xffffffff99c60fff] [ 0.039000] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.039519] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.040022] ... version: 2 [ 0.040983] ... bit width: 48 [ 0.041012] ... generic registers: 4 [ 0.041688] ... value mask: 0000ffffffffffff [ 0.042017] ... max period: 00007fffffffffff [ 0.043011] ... fixed-purpose events: 3 [ 0.043862] ... event mask: 000000070000000f [ 0.044292] rcu: Hierarchical SRCU implementation. [ 0.046578] smp: Bringing up secondary CPUs ... [ 0.047644] x86: Booting SMP configuration: [ 0.048032] .... node #0, CPUs: #1 #2 #3 [ 0.053424] smp: Brought up 1 node, 4 CPUs [ 0.055012] smpboot: Max logical packages: 1 [ 0.056008] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.121435] node 0 deferred pages initialised in 63ms [ 0.125149] devtmpfs: initialized [ 0.126299] x86/mm: Memory block size: 128MB [ 0.128910] gcov: version magic: 0x41383552 [ 0.130399] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.131074] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.132311] pinctrl core: initialized pinctrl subsystem [ 0.133152] [ 0.134009] ************************************************************* [ 0.135008] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.136014] ** ** [ 0.137010] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.138011] ** ** [ 0.139009] ** This means that this kernel is built to expose internal ** [ 0.140011] ** IOMMU data structures, which may compromise security on ** [ 0.141009] ** your system. ** [ 0.142077] ** ** [ 0.143007] ** If you see this message and you are not debugging the ** [ 0.144009] ** kernel, report this immediately to your vendor! ** [ 0.145007] ** ** [ 0.146012] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.147012] ************************************************************* [ 0.148706] NET: Registered protocol family 16 [ 0.149400] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.150053] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.151049] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.152295] cpuidle: using governor menu [ 0.154382] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.156588] PCI: Using configuration type 1 for base access [ 0.158125] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.165180] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.166025] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.167122] cryptd: max_cpu_qlen set to 1000 [ 0.168245] ACPI: Added _OSI(Module Device) [ 0.169009] ACPI: Added _OSI(Processor Device) [ 0.170008] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.171008] ACPI: Added _OSI(Processor Aggregator Device) [ 0.174500] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.176478] ACPI: Interpreter enabled [ 0.177042] ACPI: PM: (supports S0 S3 S4 S5) [ 0.178006] ACPI: Using IOAPIC for interrupt routing [ 0.179094] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.180314] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.188206] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.189019] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.190008] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.191050] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.192875] acpiphp: Slot [2] registered [ 0.193102] acpiphp: Slot [5] registered [ 0.194069] acpiphp: Slot [6] registered [ 0.195098] acpiphp: Slot [3] registered [ 0.196057] acpiphp: Slot [4] registered [ 0.197048] acpiphp: Slot [7] registered [ 0.198078] acpiphp: Slot [8] registered [ 0.199075] acpiphp: Slot [9] registered [ 0.200126] acpiphp: Slot [10] registered [ 0.201099] acpiphp: Slot [11] registered [ 0.202070] acpiphp: Slot [12] registered [ 0.203140] acpiphp: Slot [13] registered [ 0.204061] acpiphp: Slot [14] registered [ 0.205065] acpiphp: Slot [15] registered [ 0.206098] acpiphp: Slot [16] registered [ 0.207068] acpiphp: Slot [17] registered [ 0.208083] acpiphp: Slot [18] registered [ 0.209076] acpiphp: Slot [19] registered [ 0.210074] acpiphp: Slot [20] registered [ 0.211063] acpiphp: Slot [21] registered [ 0.212065] acpiphp: Slot [22] registered [ 0.212956] acpiphp: Slot [23] registered [ 0.213074] acpiphp: Slot [24] registered [ 0.214069] acpiphp: Slot [25] registered [ 0.215094] acpiphp: Slot [26] registered [ 0.216073] acpiphp: Slot [27] registered [ 0.217076] acpiphp: Slot [28] registered [ 0.218092] acpiphp: Slot [29] registered [ 0.219072] acpiphp: Slot [30] registered [ 0.220063] acpiphp: Slot [31] registered [ 0.221120] PCI host bridge to bus 0000:00 [ 0.222014] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.223016] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.224016] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.225029] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.228026] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.230020] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.232180] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.235025] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.238355] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.246000] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.249452] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.251011] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.253014] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.255022] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.257537] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.259838] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.262041] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.266167] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.270012] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.280014] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.284006] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.292803] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.303012] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.308015] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.322020] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.330237] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.334014] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.348019] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.355947] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.362814] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.365303] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.367306] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.368191] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.370138] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.374025] iommu: Default domain type: Passthrough [ 0.375672] SCSI subsystem initialized [ 0.377136] ACPI: bus type USB registered [ 0.378000] usbcore: registered new interface driver usbfs [ 0.378000] usbcore: registered new interface driver hub [ 0.380055] usbcore: registered new device driver usb [ 0.381092] pps_core: LinuxPPS API ver. 1 registered [ 0.382005] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.385035] PTP clock support registered [ 0.387038] EDAC MC: Ver: 3.0.0 [ 0.388103] PCI: Using ACPI for IRQ routing [ 0.390346] NetLabel: Initializing [ 0.391010] NetLabel: domain hash size = 128 [ 0.393012] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.394070] NetLabel: unlabeled traffic allowed by default [ 0.396122] vgaarb: loaded [ 0.397253] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.398010] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.402194] clocksource: Switched to clocksource kvm-clock [ 0.490723] VFS: Disk quotas dquot_6.6.0 [ 0.492135] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.494242] *** VALIDATE ramfs *** [ 0.495032] *** VALIDATE hugetlbfs *** [ 0.496271] pnp: PnP ACPI init [ 0.498059] pnp: PnP ACPI: found 6 devices [ 0.511955] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.514828] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.516640] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.518913] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.521305] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.523215] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.525025] NET: Registered protocol family 2 [ 0.526530] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.530707] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.533665] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.538328] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.540640] TCP: Hash tables configured (established 65536 bind 65536) [ 0.542881] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.544448] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.546219] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.548796] NET: Registered protocol family 1 [ 0.550638] RPC: Registered named UNIX socket transport module. [ 0.551781] RPC: Registered udp transport module. [ 0.552610] RPC: Registered tcp transport module. [ 0.553714] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.555721] NET: Registered protocol family 44 [ 0.556636] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.557871] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.559135] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.560469] PCI: CLS 0 bytes, default 64 [ 0.561702] Unpacking initramfs... [ 2.473780] debug: unmapping init [mem 0xffff99f4bcc64000-0xffff99f4bffcffff] [ 2.480596] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.482542] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.486443] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 4.473351] Initialise system trusted keyrings [ 4.477124] Key type blacklist registered [ 4.479196] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 4.489124] zbud: loaded [ 4.492383] *** VALIDATE nfs *** [ 4.493493] *** VALIDATE nfs4 *** [ 4.494905] pstore: using deflate compression [ 4.498957] Platform Keyring initialized [ 4.640575] NET: Registered protocol family 38 [ 4.642752] Key type asymmetric registered [ 4.644414] Asymmetric key parser 'x509' registered [ 4.646503] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 4.651319] io scheduler mq-deadline registered [ 4.652913] io scheduler kyber registered [ 4.654773] io scheduler bfq registered [ 4.656795] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 4.659962] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 4.662928] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 4.665960] ACPI: Power Button [PWRF] [ 4.672557] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 4.681721] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 4.694255] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 4.724984] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 4.755934] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 4.764476] Non-volatile memory driver v1.3 [ 4.767484] Linux agpgart interface v0.103 [ 4.800812] virtio_blk virtio1: [vda] 134096 512-byte logical blocks (68.7 MB/65.5 MiB) [ 4.803839] vda: detected capacity change from 0 to 68657152 [ 4.821824] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 4.824507] vdb: detected capacity change from 0 to 1073741824 [ 4.832256] libphy: Fixed MDIO Bus: probed [ 4.839665] usbcore: registered new interface driver usbserial_generic [ 4.841913] usbserial: USB Serial support registered for generic [ 4.843987] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 4.849426] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 4.851337] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 4.853961] mousedev: PS/2 mouse device common for all mice [ 4.859888] rtc_cmos 00:05: RTC can wake from S4 [ 4.863895] rtc_cmos 00:05: registered as rtc0 [ 4.865887] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 4.868096] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 4.869119] intel_pstate: CPU model not supported [ 4.874455] hid: raw HID events driver (C) Jiri Kosina [ 4.877751] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 4.880602] usbcore: registered new interface driver usbhid [ 4.882167] usbhid: USB HID core driver [ 4.883524] drop_monitor: Initializing network drop monitor service [ 4.886610] Initializing XFRM netlink socket [ 4.888766] NET: Registered protocol family 10 [ 4.891582] Segment Routing with IPv6 [ 4.892852] NET: Registered protocol family 17 [ 4.895063] mpls_gso: MPLS GSO support [ 4.900622] RAS: Correctable Errors collector initialized. [ 4.902692] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 4.903579] AVX version of gcm_enc/dec engaged. [ 4.909286] AES CTR mode by8 optimization enabled [ 4.996590] sched_clock: Marking stable (4996523295, 0)->(5850220492, -853697197) [ 5.001741] registered taskstats version 1 [ 5.003771] Loading compiled-in X.509 certificates [ 5.009395] zswap: loaded using pool lzo/zbud [ 5.044257] Key type big_key registered [ 5.063517] Key type encrypted registered [ 5.067757] ima: No TPM chip found, activating TPM-bypass! [ 5.070236] ima: Allocated hash algorithm: sha1 [ 5.072708] ima: No architecture policies found [ 5.075222] evm: Initialising EVM extended attributes: [ 5.077229] evm: security.selinux [ 5.078632] evm: security.ima [ 5.079853] evm: security.capability [ 5.081540] evm: HMAC attrs: 0x1 [ 5.084767] rtc_cmos 00:05: setting system clock to 2026-01-16 06:55:28 UTC (1768546528) [ 5.092174] debug: unmapping init [mem 0xffffffff9ac03000-0xffffffff9adfffff] [ 5.097379] debug: unmapping init [mem 0xffffffff99982000-0xffffffff99c58fff] [ 5.109285] Write protecting the kernel read-only data: 28672k [ 5.113976] debug: unmapping init [mem 0xffffffff98003000-0xffffffff981fffff] [ 5.117798] debug: unmapping init [mem 0xffffffff98914000-0xffffffff989fffff] [ 5.172963] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 5.183123] systemd[1]: Detected virtualization kvm. [ 5.184777] systemd[1]: Detected architecture x86-64. [ 5.186580] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 5.225114] systemd[1]: No hostname configured. [ 5.226883] systemd[1]: Set hostname to . [ 5.229380] random: systemd: uninitialized urandom read (16 bytes read) [ 5.232572] systemd[1]: Initializing machine ID from random generator. [ 5.313193] random: ln: uninitialized urandom read (6 bytes read) [ 5.395434] random: systemd: uninitialized urandom read (16 bytes read) [ 5.445329] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ 5.459691] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. [ 5.466667] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on Journal Socket. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Swap. [ OK ] Started Memstrack Anylazing Service. [ OK ] Reached target Slices. [ OK ] Reached target Timers. Starting Apply Kernel Variables... [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Listening on udev Control Socket. [ OK ] Reached target Sockets. Starting Create Volatile Files and Directories... Starting Setup Virtual Console... Starting Journal Service... [ OK ] Reached target Paths. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Setup Virtual Console. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 7.707681] device-mapper: uevent: version 1.0.3 [ 7.718995] 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. [ 8.645833] random: fast init done Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting dracut initqueue hook... [ 10.578238] virtio_net virtio0 ens2: renamed from eth0 [ 11.124922] scsi host0: ata_piix [ 11.599934] scsi host1: ata_piix [ 11.603348] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 11.606908] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 17.635286] random: crng init done [ 17.637615] random: 7 urandom warning(s) missed due to ratelimiting [ 20.867681] dracut-initqueue[584]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 24.304950] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped target Timers. [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Slices. [ OK ] Stopped target Sockets. [ OK ] Stopped target System Initialization. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Apply Kernel Variables. Stopping udev Kernel Device Manager... [ OK ] Stopped target Swap. [ OK ] Stopped target Paths. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ 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 Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 28.290654] printk: systemd: 26 output lines suppressed due to ratelimiting [ 29.555729] SELinux: Disabled at runtime. [ 30.055591] 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) [ 30.062152] systemd[1]: Detected virtualization kvm. [ 30.063533] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 33.851689] systemd[1]: initrd-switch-root.service: Succeeded. [ 33.855812] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 33.950766] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 33.978279] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 33.989304] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 34.047071] systemd[1]: Starting Journal Service... Starting Journal Service... [ 34.086604] systemd[1]: Starting Create list of required static device nodes for the current kernel... Starting Create list of required st…ce nodes for the current kernel... Activating swap /dev/disk/by-label/SWAP... [ OK ] Listening on udev Control Socket. Mounting Huge Pages File System... [ OK ] Listening on udev Kernel Socket. [ 34.477207] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS Starting udev Coldplug all Devices... [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Listening on Process Core Dump Socket. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Reached target rpc_pipefs.target. Mounting POSIX Message Queue File System... [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ 35.162454] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ 35.176137] systemd[1]: Stopped target Initrd Root File System. [ OK ] Stopped target Initrd Root File System. [ 35.183329] systemd[1]: Listening on initctl Compatibility Named Pipe. [ OK ] Listening on initctl Compatibility Named Pipe. [ 35.193785] systemd[1]: Created slice User and Session Slice. [ OK ] Created slice User and Session Slice. [ 35.201673] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ 35.211137] systemd[1]: Created slice system-getty.slice. [ OK ] Created slice system-getty.slice. [ 35.227815] systemd[1]: Started Forward Password Requests to Wall Directory Watch. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ 35.242877] systemd[1]: Reached target Paths. [ OK ] Reached target Paths. [ 35.299703] systemd[1]: Listening on RPCbind Server Activation Socket. [ OK ] Listening on RPCbind Server Activation Socket. [ 35.349943] systemd[1]: Reached target RPC Port Mapper. [ OK ] Reached target RPC Port Mapper. Starting Remount Root and Kernel File Systems... Starting Apply Kernel Variables... Mounting Kernel Debug File System... [ OK ] Reached target Local Encrypted Volumes. [ OK ] Started Journal Service. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted Huge Pages File System. [ OK ] Mounted POSIX Message Queue File System. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted Kernel Debug File System. Starting Configure read-only root support... [ OK ] Reached target Swap. Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started udev Coldplug all Devices. [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... [ OK ] Mounted /mnt. [ 36.915565] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 39.514976] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 39.637970] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 40.389279] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 40.600219] EDAC sbridge: Ver: 1.1.2 [* ] A start job is running for Configur…-only root support (9s / no limit) [** ] A start job is running for Configur…only root support (10s / no limit) [*** ] A start job is running for Configur…only root support (10s / no limit) [ *** ] A start job is running for Configur…only root support (11s / no limit) [ *** ] A start job is running for Configur…only root support (12s / no limit) [ ***] A start job is running for Configur…only root support (12s / no limit)[ 46.770178] Key type dns_resolver registered [ **] A start job is running for Configur…only root support (13s / no limit) [ *] A start job is running for Configur…only root support (13s / no limit)[ 47.535648] NFS: Registering the id_resolver key type [ 47.539904] Key type id_resolver registered [ 47.543555] Key type id_legacy registered [ **] A start job is running for Configur…only root support (14s / no limit) [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... Starting Load/Save Random Seed... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Load/Save Random Seed. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started dnf makecache --timer. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started irqbalance daemon. Starting Restore /run/initramfs on shutdown... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Login Service... [ OK ] Reached target sshd-keygen.target. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting Dynamic System Tuning Daemon... Starting OpenSSH server daemon... Starting GSSAPI Proxy Daemon... Starting Network Manager Wait Online... Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ 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 Getty on tty1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Serial Getty on ttyS1. [ OK ] Reached target Login Prompts. [ OK ] Started Command Scheduler. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Crash recovery kernel arming... Starting System Logging Service... Starting Notify NFS peers of a restart... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg219-client login: [ 104.948919] libcfs: loading out-of-tree module taints kernel. [ 105.006215] Key type ._llcrypt registered [ 105.017585] Key type .llcrypt registered [ 105.366905] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 105.373815] alg: No test for adler32 (adler32-zlib) [ 106.449676] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 106.868319] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 108.567173] Key type lgssc registered [ 109.499303] Lustre: Echo OBD driver; http://www.lustre.org/ [ 212.282847] Lustre: Mounted lustre-client [ 215.585702] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 229.399267] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing check_logdir /tmp/testlogs/ [ 233.010986] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing yml_node [ 236.023209] Lustre: DEBUG MARKER: Client: 2.17.50.30 [ 237.559186] Lustre: DEBUG MARKER: MDS: 2.17.50.30 [ 238.047544] Lustre: lustre-OST0000-osc-ffff99f504d5e800: disconnect after 24s idle [ 239.434078] Lustre: DEBUG MARKER: OSS: 2.17.50.30 [ 240.344967] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Fri Jan 16 01:59:22 EST 2026 [ 251.394750] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 252.276668] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 253.471506] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 263.718955] LustreError: 8980:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 263.737400] Lustre: Unmounted lustre-client [ 350.208927] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 352.754525] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 364.183185] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 392.263893] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 02:01:54 (1768546914) [ 394.093316] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 395.441213] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 02:01:57 (1768546917) [ 418.115711] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 420.453858] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 431.857467] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 432.188803] Lustre: Mounted lustre-client [ 438.576772] LustreError: 14428:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff99f507c7d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 438.590160] LustreError: 14428:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 438.594202] LustreError: 14428:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 438.647184] Lustre: Unmounted lustre-client [ 460.313384] Key type lgssc unregistered [ 460.505223] LNet: 14835:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 460.511026] LNetError: 14835:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 460.523108] LNet: Removed LNI 192.168.202.19@tcp [ 460.965140] Key type .llcrypt unregistered [ 460.967402] Key type ._llcrypt unregistered [ 470.212546] Key type ._llcrypt registered [ 470.214691] Key type .llcrypt registered [ 471.360589] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 02:03:13 (1768546993) [ 472.165108] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 472.215070] alg: No test for adler32 (adler32-zlib) [ 473.252743] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 473.437823] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 475.095160] Key type lgssc registered [ 476.141327] Lustre: Echo OBD driver; http://www.lustre.org/ [ 526.888775] Lustre: Mounted lustre-client [ 611.051185] LustreError: 18171:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff99f507996800: lov tgt 1990 not cleaned! deathrow=0, lovrc=1 [ 611.062648] LustreError: 18171:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 611.095440] Lustre: Unmounted lustre-client [ 631.415603] Key type lgssc unregistered [ 631.599406] LNet: 18634:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 631.607219] LNetError: 18634:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 631.617520] LNet: Removed LNI 192.168.202.19@tcp [ 632.025516] Key type .llcrypt unregistered [ 632.027848] Key type ._llcrypt unregistered [ 638.604653] Key type ._llcrypt registered [ 638.606941] Key type .llcrypt registered [ 639.542461] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 02:06:01 (1768547161) [ 641.379098] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 641.388571] alg: No test for adler32 (adler32-zlib) [ 642.298329] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 642.455207] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 644.087179] Key type lgssc registered [ 644.711392] Lustre: Echo OBD driver; http://www.lustre.org/ [ 674.757592] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 676.781223] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 686.084096] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 692.715800] Lustre: Mounted lustre-client [ 701.707065] LustreError: 21739:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff99f50578d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 701.719221] LustreError: 21739:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 701.757222] Lustre: Unmounted lustre-client [ 730.614411] Key type lgssc unregistered [ 730.781509] LNet: 22142:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 730.789771] LNetError: 22142:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 730.807673] LNet: Removed LNI 192.168.202.19@tcp [ 731.154411] Key type .llcrypt unregistered [ 731.155934] Key type ._llcrypt unregistered [ 736.868580] Key type ._llcrypt registered [ 736.872177] Key type .llcrypt registered [ 737.710678] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 02:07:40 (1768547260) [ 738.941208] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 739.001276] alg: No test for adler32 (adler32-zlib) [ 739.930873] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 740.081624] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 741.687169] Key type lgssc registered [ 742.333925] Lustre: Echo OBD driver; http://www.lustre.org/ [ 771.441054] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 773.660696] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 785.232279] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 787.690926] Lustre: Mounted lustre-client [ 795.355809] LustreError: 24837:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff99f507c79800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 795.366691] LustreError: 24837:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 795.396268] Lustre: Unmounted lustre-client [ 824.388217] Key type lgssc unregistered [ 824.570307] LNet: 25239:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 824.573778] LNetError: 25239:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 824.586644] LNet: Removed LNI 192.168.202.19@tcp [ 824.995145] Key type .llcrypt unregistered [ 824.996641] Key type ._llcrypt unregistered [ 834.241212] Key type ._llcrypt registered [ 834.243013] Key type .llcrypt registered [ 835.135239] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 02:09:17 (1768547357) [ 836.647778] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 836.667079] alg: No test for adler32 (adler32-zlib) [ 837.606418] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 837.724768] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 839.343254] Key type lgssc registered [ 839.967952] Lustre: Echo OBD driver; http://www.lustre.org/ [ 859.346031] hrtimer: interrupt took 2769336 ns [ 908.914179] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 916.526353] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 945.575967] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 948.588656] Lustre: Mounted lustre-client [ 953.828797] Lustre: lustre-MDT0000-mdc-ffff99f507725800: Connection to lustre-MDT0000 (at 192.168.202.119@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 969.210611] Lustre: lustre-MDT0001-mdc-ffff99f507725800: Connection to lustre-MDT0001 (at 192.168.202.119@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 973.252484] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 980.447283] Lustre: 26193:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1768547487/real 1768547487] req@000000003034fea5 x1854456317614080/t0(0) o400->MGC192.168.202.119@tcp@192.168.202.119@tcp:26/25 lens 224/224 e 0 to 1 dl 1768547503 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 980.468306] LustreError: MGC192.168.202.119@tcp: Connection to MGS (at 192.168.202.119@tcp) was lost; in progress operations using this service will fail [ 983.557050] LustreError: 28423:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 983.632747] Lustre: Unmounted lustre-client [ 1002.760449] Key type lgssc unregistered [ 1002.987078] LNet: 28814:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1002.992217] LNetError: 28814:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1003.023658] LNet: Removed LNI 192.168.202.19@tcp [ 1003.844194] Key type .llcrypt unregistered [ 1003.849471] Key type ._llcrypt unregistered [ 1021.600521] Key type ._llcrypt registered [ 1021.602944] Key type .llcrypt registered [ 1023.005676] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 02:12:24 (1768547544) [ 1025.773236] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1025.935034] alg: No test for adler32 (adler32-zlib) [ 1027.149358] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 1027.399958] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 1029.208063] Key type lgssc registered [ 1030.456544] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1087.867651] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1092.575070] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1113.211645] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1115.980788] Lustre: Mounted lustre-client [ 1127.794418] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1138.100792] LustreError: 31940:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1138.196968] Lustre: Unmounted lustre-client [ 1170.113332] Key type lgssc unregistered [ 1170.447381] LNet: 32342:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1170.469762] LNetError: 32342:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1170.492678] LNet: Removed LNI 192.168.202.19@tcp [ 1171.241313] Key type .llcrypt unregistered [ 1171.242574] Key type ._llcrypt unregistered [ 1189.410674] Key type ._llcrypt registered [ 1189.415456] Key type .llcrypt registered [ 1191.116331] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 02:15:12 (1768547712) [ 1193.908431] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1193.960147] alg: No test for adler32 (adler32-zlib) [ 1195.124193] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 1195.365052] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 1197.063297] Key type lgssc registered [ 1198.390795] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1255.574505] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1260.162943] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1285.108679] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1286.855358] Lustre: Mounted lustre-client [ 1290.310905] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1294.378961] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 1306.682898] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2026-01-16 2h17m07s [ 1309.714965] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 1312.755937] Lustre: lustre-MDT0000-mdc-ffff99f51186e000: Connection to lustre-MDT0000 (at 192.168.202.119@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1320.905269] LustreError: 36047:0:(lmv_obd.c:1435:lmv_statfs()) lustre-MDT0000-mdc-ffff99f51186e000: can't stat MDS #0: rc = -19 [ 1322.848774] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 1335.263426] Lustre: 33298:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1768547842/real 1768547842] req@000000009ea9cbdf x1854456691957888/t0(0) o400->MGC192.168.202.119@tcp@192.168.202.119@tcp:26/25 lens 224/224 e 0 to 1 dl 1768547858 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1335.302118] LustreError: MGC192.168.202.119@tcp: Connection to MGS (at 192.168.202.119@tcp) was lost; in progress operations using this service will fail [ 1335.352508] Lustre: Evicted from MGS (at 192.168.202.119@tcp) after server handle changed from 0x3393449566fc1d60 to 0x3393449566fc2015 [ 1335.360649] Lustre: MGC192.168.202.119@tcp: Connection restored to 192.168.202.119@tcp (at 192.168.202.119@tcp) [ 1336.381735] LustreError: lustre-MDT0000-mdc-ffff99f51186e000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 1336.433781] Lustre: lustre-MDT0000-mdc-ffff99f51186e000: Connection restored to 192.168.202.119@tcp (at 192.168.202.119@tcp) [ 1342.128114] Lustre: DEBUG MARKER: Wait for df (36047) ... [ 1343.617967] Lustre: DEBUG MARKER: done [ 1346.511576] LustreError: 36662:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff99f51186e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1346.557419] LustreError: 36662:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1346.639828] Lustre: Unmounted lustre-client [ 1386.072801] Key type lgssc unregistered [ 1386.592260] LNet: 37066:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1386.621276] LNetError: 37066:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1387.687925] LNet: Removed LNI 192.168.202.19@tcp [ 1388.718198] Key type .llcrypt unregistered [ 1388.726685] Key type ._llcrypt unregistered [ 1426.270410] Key type ._llcrypt registered [ 1426.272414] Key type .llcrypt registered [ 1428.048324] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 02:19:09 (1768547949) [ 1432.085256] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1432.143617] alg: No test for adler32 (adler32-zlib) [ 1433.414914] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 1433.966031] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 1435.664276] Key type lgssc registered [ 1437.787666] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1502.179278] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1506.253238] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1530.652805] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1532.294534] Lustre: Mounted lustre-client [ 1538.237654] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2026-01-16 2h20m59s [ 1540.334262] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1549.110848] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 1552.573118] Lustre: DEBUG MARKER: Creating a test file and stat it [ 1572.831213] Lustre: 41133:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1768548076/real 1768548076] req@0000000053441d95 x1854456942565760/t0(0) o101->lustre-MDT0000-mdc-ffff99f5069c5000@192.168.202.119@tcp:12/10 lens 576/1152 e 0 to 1 dl 1768548096 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:1 [ 1572.876923] Lustre: lustre-MDT0000-mdc-ffff99f5069c5000: Connection to lustre-MDT0000 (at 192.168.202.119@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1572.931611] Lustre: lustre-MDT0000-mdc-ffff99f5069c5000: Connection restored to 192.168.202.119@tcp (at 192.168.202.119@tcp) [ 1595.251903] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 1600.818633] Lustre: DEBUG MARKER: done [ 1606.335676] LustreError: 41616:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff99f5069c5000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1606.383697] LustreError: 41616:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1606.475286] Lustre: Unmounted lustre-client [ 1637.764551] Key type lgssc unregistered [ 1638.163771] LNet: 42019:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1638.170241] LNetError: 42019:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1638.190410] LNet: Removed LNI 192.168.202.19@tcp [ 1639.389350] Key type .llcrypt unregistered [ 1639.391120] Key type ._llcrypt unregistered [ 1681.665662] Key type ._llcrypt registered [ 1681.675168] Key type .llcrypt registered [ 1683.288456] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 02:23:24 (1768548204) [ 1685.197518] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 1687.630340] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 02:23:29 (1768548209) [ 1692.434908] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1692.621187] alg: No test for adler32 (adler32-zlib) [ 1693.836418] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 1694.034359] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 1695.719154] Key type lgssc registered [ 1697.766367] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1732.119824] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 02:24:13 (1768548253) [ 1776.100681] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1781.153879] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1806.048655] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1809.040290] Lustre: Mounted lustre-client [ 1813.151418] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 1818.260981] LustreError: 46375:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff99f508167800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1818.296722] LustreError: 46375:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1818.381355] Lustre: Unmounted lustre-client [ 1837.877556] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 1893.652938] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1898.541400] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1899.484102] Lustre: Mounted lustre-client [ 1903.013621] LustreError: 47634:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff99f51874e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1903.070608] LustreError: 47634:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1903.074652] LustreError: 47634:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 1903.169874] Lustre: Unmounted lustre-client [ 1970.897637] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1976.037467] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1976.603121] Lustre: Mounted lustre-client [ 1980.789302] LustreError: 48759:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff99f508dd2000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1980.835118] LustreError: 48759:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1980.846161] LustreError: 48759:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 1980.928583] Lustre: Unmounted lustre-client [ 1999.647718] Lustre: DEBUG MARKER: files compared the same [ 2025.291936] Key type lgssc unregistered [ 2025.590339] LNet: 49464:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2025.597562] LNetError: 49464:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2025.630398] LNet: Removed LNI 192.168.202.19@tcp [ 2026.713977] Key type .llcrypt unregistered [ 2026.718734] Key type ._llcrypt unregistered [ 2049.121644] Key type ._llcrypt registered [ 2049.124509] Key type .llcrypt registered [ 2050.378376] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 02:29:32 (1768548572) [ 2053.115947] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2053.332220] alg: No test for adler32 (adler32-zlib) [ 2054.586707] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 2054.898519] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 2056.663190] Key type lgssc registered [ 2058.226673] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2115.571627] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2120.700700] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2142.522728] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2145.595292] Lustre: Mounted lustre-client [ 2152.993333] LustreError: 52564:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff99f5061c1000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2153.011979] LustreError: 52564:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2153.075207] Lustre: Unmounted lustre-client [ 2185.889578] Key type lgssc unregistered [ 2186.179558] LNet: 52968:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2186.193549] LNetError: 52968:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2187.243698] LNet: Removed LNI 192.168.202.19@tcp [ 2188.045678] Key type .llcrypt unregistered [ 2188.047460] Key type ._llcrypt unregistered [ 2207.039322] Key type ._llcrypt registered [ 2207.040922] Key type .llcrypt registered [ 2208.436611] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 02:32:10 (1768548730) [ 2211.403906] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2211.422286] alg: No test for adler32 (adler32-zlib) [ 2212.718588] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 2213.016938] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 2214.751287] Key type lgssc registered [ 2215.921122] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2302.730243] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2339.117355] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2343.523225] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2371.856432] Key type lgssc unregistered [ 2372.159643] LNet: 56370:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2372.163386] LNetError: 56370:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2372.170617] LNet: Removed LNI 192.168.202.19@tcp [ 2372.820417] Key type .llcrypt unregistered [ 2372.828094] Key type ._llcrypt unregistered [ 2390.073812] Key type ._llcrypt registered [ 2390.077622] Key type .llcrypt registered [ 2391.666988] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 02:35:13 (1768548913) [ 2395.719378] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2395.734225] alg: No test for adler32 (adler32-zlib) [ 2396.934394] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 2397.286512] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 2399.064057] Key type lgssc registered [ 2400.818728] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2489.916139] Lustre: Mounted lustre-client [ 2497.077613] LustreError: 58864:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff99f508163000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2497.110994] LustreError: 58864:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2497.169852] Lustre: Unmounted lustre-client [ 2539.597314] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 02:37:41 (1768549061) [ 2671.640813] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2692.240677] Lustre: Mounted lustre-client [ 2700.230237] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2700.238264] LustreError: 62840:0:(file.c:249:ll_close_inode_openhandle()) lustre-clilmv-ffff99f510d72000: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 2700.263446] Lustre: 57352:0:(llite_lib.c:4226:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.202.119@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 2710.587796] LustreError: 62840:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 2710.604435] LustreError: 62840:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 2710.705061] Lustre: Unmounted lustre-client [ 2768.723934] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 02:41:30 (1768549290) [ 2781.721892] Key type lgssc unregistered [ 2781.931721] LNet: 64054:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2781.937740] LNetError: 64054:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2781.961430] LNet: Removed LNI 192.168.202.19@tcp [ 2782.616870] Key type .llcrypt unregistered [ 2782.619498] Key type ._llcrypt unregistered [ 2797.693400] Key type ._llcrypt registered [ 2797.702899] Key type .llcrypt registered [ 2798.325248] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2798.341444] alg: No test for adler32 (adler32-zlib) [ 2799.570766] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 2799.832475] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 2801.514737] Key type lgssc registered [ 2802.989834] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2860.537453] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2864.954295] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2887.524140] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2889.345408] Lustre: Mounted lustre-client [ 2897.639055] LustreError: 66579:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff99f511868800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2897.669936] LustreError: 66579:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 2897.768817] Lustre: Unmounted lustre-client [ 2936.496600] Key type lgssc unregistered [ 2936.904685] LNet: 66982:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2936.914134] LNetError: 66982:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2936.936161] LNet: Removed LNI 192.168.202.19@tcp [ 2938.006700] Key type .llcrypt unregistered [ 2938.010462] Key type ._llcrypt unregistered [ 2949.061249] Key type ._llcrypt registered [ 2949.065915] Key type .llcrypt registered [ 2949.870057] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2949.905030] alg: No test for adler32 (adler32-zlib) [ 2951.049773] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 2951.354962] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 2953.087258] Key type lgssc registered [ 2954.703547] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3082.714467] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3088.318672] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3114.145045] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3125.353251] Lustre: Mounted lustre-client [ 3129.136052] LustreError: 69929:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff99f5069c1800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3129.174926] LustreError: 69929:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 3129.293456] Lustre: Unmounted lustre-client [ 3160.441753] Key type lgssc unregistered [ 3160.658929] LNet: 70332:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3160.666266] LNetError: 70332:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3160.700300] LNet: Removed LNI 192.168.202.19@tcp [ 3161.666895] Key type .llcrypt unregistered [ 3161.669934] Key type ._llcrypt unregistered [ 3177.577533] Key type ._llcrypt registered [ 3177.579835] Key type .llcrypt registered [ 3179.097965] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 02:48:20 (1768549700) [ 3181.863687] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3182.010577] alg: No test for adler32 (adler32-zlib) [ 3183.321157] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 3183.824636] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 3185.551233] Key type lgssc registered [ 3187.296116] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3247.842536] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3252.444618] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3276.441802] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3288.709429] Lustre: Mounted lustre-client [ 3295.919583] LustreError: 73458:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff99f508dd2800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3295.949991] LustreError: 73458:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 3296.014834] Lustre: Unmounted lustre-client [ 3296.718654] Lustre: Mounted lustre-client [ 3299.894136] LustreError: 73534:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff99f51186f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3299.931696] LustreError: 73534:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 3299.942482] LustreError: 73534:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 3300.033195] Lustre: Unmounted lustre-client [ 3331.161407] Key type lgssc unregistered [ 3331.809325] LNet: 73935:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3331.827277] LNetError: 73935:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3331.855692] LNet: Removed LNI 192.168.202.19@tcp [ 3332.852371] Key type .llcrypt unregistered [ 3332.854843] Key type ._llcrypt unregistered [ 3347.339117] Key type ._llcrypt registered [ 3347.384860] Key type .llcrypt registered [ 3347.986350] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3348.002280] alg: No test for adler32 (adler32-zlib) [ 3349.260183] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 3349.565582] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 3351.303254] Key type lgssc registered [ 3352.575232] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3411.653960] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3416.438333] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3443.707628] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3450.957737] Lustre: Mounted lustre-client [ 3456.878197] LustreError: 76478:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff99f507996800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3456.917892] LustreError: 76478:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 3457.025960] Lustre: Unmounted lustre-client [ 3489.805351] Key type lgssc unregistered [ 3490.369804] LNet: 76880:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3490.388325] LNetError: 76880:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3490.410764] LNet: Removed LNI 192.168.202.19@tcp [ 3491.464767] Key type .llcrypt unregistered [ 3491.468630] Key type ._llcrypt unregistered [ 3512.891534] Key type ._llcrypt registered [ 3512.896812] Key type .llcrypt registered [ 3514.416639] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 02:53:56 (1768550036) [ 3517.414030] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3517.619421] alg: No test for adler32 (adler32-zlib) [ 3518.721364] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 3519.084421] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 3520.919170] Key type lgssc registered [ 3522.681695] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3589.028840] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3595.535737] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3618.203106] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3630.679622] Lustre: Mounted lustre-client [ 3643.485162] Lustre: lustre: root_squash is set to 0:0 [ 3643.529113] Lustre: lustre: nosquash_nids is cleared [ 3651.803095] Lustre: lustre: root_squash is set to 500:500 [ 3664.499169] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.202.19@tcp 192.168.0.[2,10]@tcp [ 3677.778732] Lustre: lustre: nosquash_nids set to 192.168.202.19/24@tcp [ 3684.080760] LustreError: 80351:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff99f5077e1000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3684.107027] LustreError: 80351:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 3684.193249] Lustre: Unmounted lustre-client [ 3730.435554] Key type lgssc unregistered [ 3730.813666] LNet: 80757:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3730.827107] LNetError: 80757:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3730.843499] LNet: Removed LNI 192.168.202.19@tcp [ 3731.724958] Key type .llcrypt unregistered [ 3731.727712] Key type ._llcrypt unregistered [ 3750.747580] Key type ._llcrypt registered [ 3750.751657] Key type .llcrypt registered [ 3752.253756] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 02:57:54 (1768550274) [ 3754.970022] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 3756.660690] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 02:57:58 (1768550278) [ 3760.344472] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3760.372134] alg: No test for adler32 (adler32-zlib) [ 3761.645764] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 3761.902196] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 3763.551268] Key type lgssc registered [ 3764.668772] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3819.610695] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3824.010836] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3846.329942] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3847.846939] Lustre: lustre: root_squash is set to 500:500 [ 3847.905383] Lustre: lustre: nosquash_nids set to 192.168.202.19/24@tcp [ 3849.008942] Lustre: Mounted lustre-client [ 3856.472361] LustreError: 84228:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff99f504710000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3856.499666] LustreError: 84228:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 3856.551878] Lustre: Unmounted lustre-client [ 3887.354599] Key type lgssc unregistered [ 3887.624906] LNet: 84630:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3887.648571] LNetError: 84630:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3887.669672] LNet: Removed LNI 192.168.202.19@tcp [ 3888.494690] Key type .llcrypt unregistered [ 3888.499152] Key type ._llcrypt unregistered [ 3908.259800] Key type ._llcrypt registered [ 3908.261844] Key type .llcrypt registered [ 3910.355719] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 3912.755420] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 03:00:34 (1768550434) [ 3935.371414] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3935.472650] alg: No test for adler32 (adler32-zlib) [ 3936.645840] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 3936.907480] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 3938.584399] Key type lgssc registered [ 3940.141380] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4019.837876] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4023.766091] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4044.421705] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4110.116859] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4114.210626] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4134.338562] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4145.830900] Lustre: Mounted lustre-client [ 4178.625630] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff99f50578d800.ost_server_uuid 50 [ 4180.810803] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff99f50578d800.ost_server_uuid in FULL state after 0 sec [ 4181.562836] Lustre: Mounted lustre-client [ 4185.577054] LustreError: 91555:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff99f507990000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4185.599257] LustreError: 91555:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 4185.680765] Lustre: Unmounted lustre-client [ 4187.776662] LustreError: 91574:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff99f50578d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4187.800747] LustreError: 91574:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 4187.829353] LustreError: 91574:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 4187.836929] LustreError: 91574:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 4187.963184] Lustre: Unmounted lustre-client [ 4239.021571] Key type lgssc unregistered [ 4239.301647] LNet: 92134:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4239.306565] LNetError: 92134:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4239.322280] LNet: Removed LNI 192.168.202.19@tcp [ 4239.873576] Key type .llcrypt unregistered [ 4239.876722] Key type ._llcrypt unregistered [ 4274.224730] Key type ._llcrypt registered [ 4274.230313] Key type .llcrypt registered [ 4275.323133] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 03:06:37 (1768550797) [ 4295.577037] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4295.812063] alg: No test for adler32 (adler32-zlib) [ 4296.952205] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 4297.243552] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 4298.903167] Key type lgssc registered [ 4300.163407] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4386.240363] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4390.124965] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4412.211484] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4414.914381] Lustre: Mounted lustre-client [ 4436.447485] Lustre: 93498:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1768550943/real 1768550943] req@000000002d5db0a3 x1854459944637696/t0(0) o400->lustre-OST0000-osc-ffff99f50722d000@192.168.202.119@tcp:28/4 lens 224/224 e 0 to 1 dl 1768550959 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4436.514573] Lustre: lustre-OST0000-osc-ffff99f50722d000: Connection to lustre-OST0000 (at 192.168.202.119@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4441.248104] Lustre: 93498:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1768550948/real 1768550948] req@0000000025cb29f2 x1854459944638208/t0(0) o400->lustre-OST0000-osc-ffff99f50722d000@192.168.202.119@tcp:28/4 lens 224/224 e 0 to 1 dl 1768550964 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4445.664837] Lustre: 93497:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1768550953/real 1768550953] req@000000005b09663b x1854459944638720/t0(0) o400->lustre-OST0000-osc-ffff99f50722d000@192.168.202.119@tcp:28/4 lens 224/224 e 0 to 1 dl 1768550969 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4446.449366] Lustre: lustre-OST0000-osc-ffff99f50722d000: Connection restored to 192.168.202.119@tcp (at 192.168.202.119@tcp) [ 4450.783767] Lustre: 93498:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1768550958/real 1768550958] req@00000000c0593822 x1854459944639232/t0(0) o400->lustre-OST0000-osc-ffff99f50722d000@192.168.202.119@tcp:28/4 lens 224/224 e 0 to 1 dl 1768550974 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4450.840153] Lustre: lustre-MDT0000-mdc-ffff99f50722d000: Connection to lustre-MDT0000 (at 192.168.202.119@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4472.287885] Lustre: 93497:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1768550979/real 1768550979] req@000000009430dfc9 x1854459944641920/t0(0) o400->MGC192.168.202.119@tcp@192.168.202.119@tcp:26/25 lens 224/224 e 0 to 1 dl 1768550995 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4472.319334] LustreError: MGC192.168.202.119@tcp: Connection to MGS (at 192.168.202.119@tcp) was lost; in progress operations using this service will fail [ 4472.358368] Lustre: Evicted from MGS (at 192.168.202.119@tcp) after server handle changed from 0x4b7f5d4159b91350 to 0x4b7f5d4159b914df [ 4472.377347] Lustre: MGC192.168.202.119@tcp: Connection restored to 192.168.202.119@tcp (at 192.168.202.119@tcp) [ 4482.751139] Lustre: lustre-MDT0000-mdc-ffff99f50722d000: Connection restored to 192.168.202.119@tcp (at 192.168.202.119@tcp) [ 4489.511721] LustreError: 97319:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff99f50722d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4489.533540] LustreError: 97319:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4489.576960] Lustre: Unmounted lustre-client [ 4520.146288] Key type lgssc unregistered [ 4520.587269] LNet: 97720:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4520.608747] LNetError: 97720:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4520.638069] LNet: Removed LNI 192.168.202.19@tcp [ 4521.453939] Key type .llcrypt unregistered [ 4521.457981] Key type ._llcrypt unregistered [ 4540.761975] Key type ._llcrypt registered [ 4540.763465] Key type .llcrypt registered [ 4542.132249] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 03:11:04 (1768551064) [ 4558.387041] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4558.405654] alg: No test for adler32 (adler32-zlib) [ 4559.525470] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 4559.861370] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 4561.551199] Key type lgssc registered [ 4562.827447] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4604.128552] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4717.086487] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4721.902605] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4746.362870] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4748.308264] Lustre: Mounted lustre-client [ 4751.591773] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 4754.128520] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 03:14:35 (1768551275) [ 4993.738304] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5004.012839] LustreError: 108506:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5004.056148] Lustre: Unmounted lustre-client [ 5143.265767] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5147.855538] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5172.078690] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5219.895479] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 03:22:21 (1768551741) [ 5323.207703] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5327.270627] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5351.873831] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5352.553486] Lustre: Mounted lustre-client [ 5357.179648] LustreError: 115930:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff99f504764800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5357.203603] LustreError: 115930:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5357.208144] LustreError: 115930:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 5357.314252] Lustre: Unmounted lustre-client [ 5389.197558] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 03:25:11 (1768551911) [ 5485.404430] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5489.498476] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5512.089477] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5512.765992] Lustre: Mounted lustre-client [ 5518.147509] LustreError: 119983:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff99f508165000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5518.170781] LustreError: 119983:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5518.173781] LustreError: 119983:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 5518.304179] Lustre: Unmounted lustre-client [ 5546.379546] Key type lgssc unregistered [ 5546.767972] LNet: 120387:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5546.784264] LNetError: 120387:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5546.813942] LNet: Removed LNI 192.168.202.19@tcp [ 5547.454651] Key type .llcrypt unregistered [ 5547.467292] Key type ._llcrypt unregistered [ 5564.048694] Key type ._llcrypt registered [ 5564.050966] Key type .llcrypt registered [ 5565.559247] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 03:28:07 (1768552087) [ 5567.961141] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5568.020262] alg: No test for adler32 (adler32-zlib) [ 5569.143923] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 5569.362599] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 5571.031695] Key type lgssc registered [ 5572.118526] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5621.044603] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5625.250179] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5648.289357] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5650.063772] Lustre: Mounted lustre-client [ 5662.909668] LustreError: 123489:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff99f5077e6000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5662.936952] LustreError: 123489:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5662.997576] Lustre: Unmounted lustre-client [ 5691.397853] Key type lgssc unregistered [ 5691.684927] LNet: 123892:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5691.692962] LNetError: 123892:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5692.724582] LNet: Removed LNI 192.168.202.19@tcp [ 5693.409261] Key type .llcrypt unregistered [ 5693.415655] Key type ._llcrypt unregistered [ 5709.760527] Key type ._llcrypt registered [ 5709.763266] Key type .llcrypt registered [ 5711.503043] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 03:30:33 (1768552233) [ 5715.264369] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5715.311310] alg: No test for adler32 (adler32-zlib) [ 5716.601643] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 5716.885987] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 5718.591292] Key type lgssc registered [ 5720.933733] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5775.947798] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5779.712287] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5799.055181] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5800.865304] Lustre: Mounted lustre-client [ 5806.060125] Lustre: lustre-OST0000-osc-ffff99f510d70800: Connection to lustre-OST0000 (at 192.168.202.119@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5817.457744] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff99f510d70800.ost_server_uuid 50 [ 5819.196631] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 5830.361577] LustreError: 127436:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff99f510d70800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5830.370490] LustreError: 127436:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5830.450337] Lustre: Unmounted lustre-client [ 5858.049685] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 03:32:59 (1768552379) [ 5890.987593] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5895.231432] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5918.474667] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5945.555231] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 5946.754908] Lustre: Mounted lustre-client [ 5951.976672] Lustre: lustre-OST0000-osc-ffff99f51874d800: Connection to lustre-OST0000 (at 192.168.202.119@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5979.281470] LustreError: 130101:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff99f51874d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5979.304670] LustreError: 130101:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 5979.313804] LustreError: 130101:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 5979.383090] Lustre: Unmounted lustre-client [ 6032.517795] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 03:35:54 (1768552554) [ 6069.316740] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6074.214509] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6095.648680] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6117.560719] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 6118.412116] Lustre: Mounted lustre-client [ 6123.523954] Lustre: lustre-OST0000-osc-ffff99f51186d800: Connection to lustre-OST0000 (at 192.168.202.119@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6140.997096] LustreError: 132867:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff99f51186d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6141.016281] LustreError: 132867:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 6141.037353] LustreError: 132867:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 6141.043241] LustreError: 132867:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 6141.114560] Lustre: Unmounted lustre-client [ 6202.265769] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 03:38:43 (1768552723) [ 6306.952356] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6312.466812] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6340.077305] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6413.669189] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6418.863734] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6439.915118] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6481.176145] Lustre: Mounted lustre-client [ 6524.592811] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6528.354972] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 2 sec [ 6530.325614] LustreError: 139506:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff99f5077e6000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6530.341498] LustreError: 139506:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 6530.355442] LustreError: 139506:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6530.359652] LustreError: 139506:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 6530.426496] Lustre: Unmounted lustre-client [ 6564.376571] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 03:44:46 (1768553086) [ 6602.257840] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6606.032222] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6628.048799] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6664.158714] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 6704.715709] Lustre: Mounted lustre-client [ 6746.196143] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 6747.980561] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6769.512081] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 6779.842400] LustreError: 143102:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 6779.854229] LustreError: 143102:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 6779.939470] Lustre: Unmounted lustre-client [ 6838.328824] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 03:49:20 (1768553360) [ 6873.102790] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6877.097996] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6899.081611] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6900.104632] Lustre: Mounted lustre-client [ 6925.465370] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 6927.612560] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6942.451733] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff99f511869000.ost_server_uuid 50 [ 6944.569388] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff99f511869000.ost_server_uuid in IDLE state after 0 sec [ 6948.511284] LustreError: 146532:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff99f511869000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6948.520928] LustreError: 146532:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 6948.527563] LustreError: 146532:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 6948.575631] Lustre: Unmounted lustre-client [ 6948.931712] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 6948.938115] Lustre: Skipped 3 previous similar messages [ 6949.119705] Lustre: Mounted lustre-client [ 6950.369504] LustreError: 146595:0:(sec.c:410:import_sec_validate_get()) import 00000000746bd56b (NEW) with no sec [ 7012.110451] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 03:52:14 (1768553534) [ 7047.804944] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7052.165045] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7074.908323] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7097.191841] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 7097.822927] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 7098.016458] Lustre: Mounted lustre-client [ 7110.472746] LustreError: 149449:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff99f5070ac800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7110.489670] LustreError: 149449:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7110.537497] LustreError: 149449:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 7110.541900] LustreError: 149449:0:(obd_class.h:479:obd_check_dev()) Skipped 18 previous similar messages [ 7110.652096] Lustre: Unmounted lustre-client [ 7110.654252] Lustre: Skipped 1 previous similar message [ 7148.139850] Key type lgssc unregistered [ 7148.364879] LNet: 149898:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7148.374025] LNetError: 149898:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7148.399333] LNet: Removed LNI 192.168.202.19@tcp [ 7149.244874] Key type .llcrypt unregistered [ 7149.247437] Key type ._llcrypt unregistered [ 7167.442680] Key type ._llcrypt registered [ 7167.444290] Key type .llcrypt registered [ 7168.903549] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 03:54:50 (1768553690) [ 7171.127260] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7171.186751] alg: No test for adler32 (adler32-zlib) [ 7172.457837] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 7172.996655] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 7174.705074] Key type lgssc registered [ 7176.439941] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7228.669407] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7232.686346] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7257.151217] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7277.476267] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 7278.160250] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 7279.370877] Lustre: Mounted lustre-client [ 7286.804254] LustreError: lustre-MDT0001-mdc-ffff99f50578b000: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 7286.833789] Lustre: lustre-MDT0001-mdc-ffff99f50578b000: Connection restored to 192.168.202.119@tcp (at 192.168.202.119@tcp) [ 7291.657620] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7294.183280] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 7316.503869] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 7325.815603] LustreError: 154149:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff99f50578b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7325.826177] LustreError: 154149:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 7325.857513] Lustre: Unmounted lustre-client [ 7360.503758] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 03:58:01 (1768553881) [ 7460.327620] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7464.175113] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7485.474907] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7486.272281] Lustre: Mounted lustre-client [ 7513.365314] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 7515.258216] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7527.215120] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7537.552632] LustreError: 158886:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 7537.569084] LustreError: 158886:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 7537.702294] Lustre: Unmounted lustre-client [ 7573.211519] Key type lgssc unregistered [ 7573.448425] LNet: 159302:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7573.453738] LNetError: 159302:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7573.485437] LNet: Removed LNI 192.168.202.19@tcp [ 7574.179132] Key type .llcrypt unregistered [ 7574.181151] Key type ._llcrypt unregistered [ 7607.003975] Key type ._llcrypt registered [ 7607.006654] Key type .llcrypt registered [ 7608.233703] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 04:02:10 (1768554130) [ 7610.409715] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7610.429986] alg: No test for adler32 (adler32-zlib) [ 7611.510629] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 7611.688414] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 7613.327236] Key type lgssc registered [ 7614.404669] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7665.033785] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7668.663264] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7689.668794] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7691.437855] Lustre: Mounted lustre-client [ 7738.980788] LustreError: 162581:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff99f50615f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7739.004782] LustreError: 162581:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7739.147900] Lustre: Unmounted lustre-client [ 7783.401831] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7784.437872] Lustre: Mounted lustre-client [ 7822.021227] LustreError: 163240:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff99f51186d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7822.057804] LustreError: 163240:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7822.060904] LustreError: 163240:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 7822.099365] Lustre: Unmounted lustre-client [ 7850.732193] Key type lgssc unregistered [ 7850.995318] LNet: 163645:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7851.001528] LNetError: 163645:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7851.022485] LNet: Removed LNI 192.168.202.19@tcp [ 7851.596324] Key type .llcrypt unregistered [ 7851.598182] Key type ._llcrypt unregistered [ 7867.066709] Key type ._llcrypt registered [ 7867.069933] Key type .llcrypt registered [ 7868.216426] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 04:06:30 (1768554390) [ 7870.992648] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7871.020213] alg: No test for adler32 (adler32-zlib) [ 7872.390965] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 7872.634993] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 7874.367239] Key type lgssc registered [ 7875.833398] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7929.667656] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7934.088441] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7957.109241] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7960.103178] Lustre: Mounted lustre-client [ 7979.741673] LustreError: 166913:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff99f508162000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7979.780676] LustreError: 166913:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7979.859093] Lustre: Unmounted lustre-client [ 8016.293437] Key type lgssc unregistered [ 8016.566802] LNet: 167319:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8016.577605] LNetError: 167319:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8016.596206] LNet: Removed LNI 192.168.202.19@tcp [ 8017.394818] Key type .llcrypt unregistered [ 8017.400085] Key type ._llcrypt unregistered [ 8033.976300] Key type ._llcrypt registered [ 8033.981030] Key type .llcrypt registered [ 8034.488350] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8034.511516] alg: No test for adler32 (adler32-zlib) [ 8035.785062] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 8035.992179] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 8037.703207] Key type lgssc registered [ 8039.329309] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8099.179907] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8103.198107] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8128.025854] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8129.954779] Lustre: Mounted lustre-client [ 8139.102496] LustreError: 169894:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff99f507555800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8139.118840] LustreError: 169894:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 8139.189480] Lustre: Unmounted lustre-client [ 8176.698404] Key type lgssc unregistered [ 8176.954629] LNet: 170298:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8176.961220] LNetError: 170298:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8176.983447] LNet: Removed LNI 192.168.202.19@tcp [ 8177.515645] Key type .llcrypt unregistered [ 8177.517856] Key type ._llcrypt unregistered [ 8194.044131] Key type ._llcrypt registered [ 8194.046272] Key type .llcrypt registered [ 8195.244268] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 04:11:57 (1768554717) [ 8197.873261] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8197.898779] alg: No test for adler32 (adler32-zlib) [ 8198.920460] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 8199.066228] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 8200.711194] Key type lgssc registered [ 8202.010205] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8260.009497] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8264.292081] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8284.831827] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8287.610974] Lustre: Mounted lustre-client [ 8309.149326] LustreError: 173562:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff99f507557000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8309.184180] LustreError: 173562:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 8309.238151] Lustre: Unmounted lustre-client [ 8340.527157] Key type lgssc unregistered [ 8340.850609] LNet: 173965:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8340.857873] LNetError: 173965:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8340.874265] LNet: Removed LNI 192.168.202.19@tcp [ 8341.812768] Key type .llcrypt unregistered [ 8341.816210] Key type ._llcrypt unregistered [ 8356.199842] Key type ._llcrypt registered [ 8356.206054] Key type .llcrypt registered [ 8356.761290] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8356.789618] alg: No test for adler32 (adler32-zlib) [ 8358.052353] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 8358.447720] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 8360.169349] Key type lgssc registered [ 8361.770643] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8420.194927] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8424.237946] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8445.715733] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8448.578657] Lustre: Mounted lustre-client [ 8456.783705] LustreError: 176539:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff99f510370800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8456.809766] LustreError: 176539:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 8456.913271] Lustre: Unmounted lustre-client [ 8485.375747] Key type lgssc unregistered [ 8485.660129] LNet: 176941:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8485.670933] LNetError: 176941:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8485.686474] LNet: Removed LNI 192.168.202.19@tcp [ 8486.341680] Key type .llcrypt unregistered [ 8486.344660] Key type ._llcrypt unregistered [ 8501.892087] Key type ._llcrypt registered [ 8501.895319] Key type .llcrypt registered [ 8503.470724] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 04:17:05 (1768555025) [ 8527.008396] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8527.199290] alg: No test for adler32 (adler32-zlib) [ 8528.361876] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 8528.633917] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 8530.303513] Key type lgssc registered [ 8531.595457] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8626.392359] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8630.828420] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8655.440642] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8702.184777] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 04:20:23 (1768555223) [ 8734.925337] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8738.427249] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8760.659321] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8763.403943] Lustre: Mounted lustre-client [ 8768.906775] LustreError: 183466:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff99f518b9d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8768.948399] LustreError: 183466:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 8769.039152] Lustre: Unmounted lustre-client [ 8810.688970] Key type lgssc unregistered [ 8810.991666] LNet: 183869:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8810.996632] LNetError: 183869:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8811.016406] LNet: Removed LNI 192.168.202.19@tcp [ 8811.973468] Key type .llcrypt unregistered [ 8811.976172] Key type ._llcrypt unregistered [ 8830.136440] Key type ._llcrypt registered [ 8830.138343] Key type .llcrypt registered [ 8831.743740] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 8833.447161] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 04:22:35 (1768555355) [ 8854.945753] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8855.009975] alg: No test for adler32 (adler32-zlib) [ 8856.112268] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 8856.415992] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 8858.135127] Key type lgssc registered [ 8859.444295] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8960.701254] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8964.201721] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8990.408379] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid [ 9015.048203] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid [ 9017.121802] Lustre: Mounted lustre-client [ 9042.428420] LustreError: 189420:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff99f50578a800: lov tgt 1000 not cleaned! deathrow=0, lovrc=1 [ 9042.455808] LustreError: 189420:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 9042.610261] Lustre: Unmounted lustre-client [ 9184.286912] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9188.372897] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9215.487229] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9260.923800] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 04:29:42 (1768555782) [ 9262.405870] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 9264.307650] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 04:29:46 (1768555786) [ 9323.774287] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9327.282264] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9370.555268] Key type lgssc unregistered [ 9370.802544] LNet: 195609:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9370.808339] LNetError: 195609:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9370.826996] LNet: Removed LNI 192.168.202.19@tcp [ 9371.410651] Key type .llcrypt unregistered [ 9371.412902] Key type ._llcrypt unregistered [ 9386.978949] Key type ._llcrypt registered [ 9386.981929] Key type .llcrypt registered [ 9388.343750] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 04:31:50 (1768555910) [ 9442.789991] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9442.835387] alg: No test for adler32 (adler32-zlib) [ 9443.941819] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 9444.177734] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 9445.824436] Key type lgssc registered [ 9447.189391] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9495.539152] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9499.490743] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9531.858837] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9559.368533] Key type lgssc unregistered [ 9559.619613] LNet: 199250:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9559.630680] LNetError: 199250:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9559.645879] LNet: Removed LNI 192.168.202.19@tcp [ 9560.309669] Key type .llcrypt unregistered [ 9560.311586] Key type ._llcrypt unregistered [ 9577.163772] Key type ._llcrypt registered [ 9577.166778] Key type .llcrypt registered [ 9578.506523] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 04:35:00 (1768556100) [ 9581.491132] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9581.534581] alg: No test for adler32 (adler32-zlib) [ 9582.751336] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 9583.041960] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 9584.687402] Key type lgssc registered [ 9585.781109] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9637.090263] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9641.926420] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9667.964747] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9669.889323] Lustre: Mounted lustre-client [ 9680.381540] Lustre: lustre-MDT0000-mdc-ffff99f5069c1800: Connection to lustre-MDT0000 (at 192.168.202.119@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9690.616452] Lustre: lustre-MDT0001-mdc-ffff99f5069c1800: Connection to lustre-MDT0001 (at 192.168.202.119@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9700.646440] Lustre: 200237:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1768556208/real 1768556208] req@000000009d1620bf x1854465487486976/t0(0) o400->MGC192.168.202.119@tcp@192.168.202.119@tcp:26/25 lens 224/224 e 0 to 1 dl 1768556224 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 9700.701749] LustreError: MGC192.168.202.119@tcp: Connection to MGS (at 192.168.202.119@tcp) was lost; in progress operations using this service will fail [ 9726.314123] Lustre: Evicted from MGS (at 192.168.202.119@tcp) after server handle changed from 0x31d6859b7af4eb9e to 0x31d6859b7af50663 [ 9726.333688] Lustre: MGC192.168.202.119@tcp: Connection restored to 192.168.202.119@tcp (at 192.168.202.119@tcp) [ 9756.138515] LustreError: lustre-MDT0000-mdc-ffff99f5069c1800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 9756.178431] Lustre: lustre-MDT0000-mdc-ffff99f5069c1800: Connection restored to 192.168.202.119@tcp (at 192.168.202.119@tcp) [ 9760.577185] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9762.418494] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9766.777504] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9768.394908] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9771.164069] LustreError: 203668:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff99f5069c1800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9771.179141] LustreError: 203668:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 9771.249236] Lustre: Unmounted lustre-client [ 9800.436413] Key type lgssc unregistered [ 9800.837878] LNet: 204070:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9800.852787] LNetError: 204070:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9800.875528] LNet: Removed LNI 192.168.202.19@tcp [ 9801.975735] Key type .llcrypt unregistered [ 9801.978063] Key type ._llcrypt unregistered [ 9820.781838] Key type ._llcrypt registered [ 9820.783873] Key type .llcrypt registered [ 9822.086413] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 04:39:04 (1768556344) [ 9824.681286] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9824.776154] alg: No test for adler32 (adler32-zlib) [ 9825.866183] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 9826.107163] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [ 9827.800147] Key type lgssc registered [ 9829.191862] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9884.279879] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9889.270353] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9956.358880] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9961.298068] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9993.994651] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10017.071672] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [10057.097551] Key type lgssc unregistered [10057.344940] LNet: 209190:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10057.353872] LNetError: 209190:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10057.369903] LNet: Removed LNI 192.168.202.19@tcp [10058.014804] Key type .llcrypt unregistered [10058.021681] Key type ._llcrypt unregistered [10106.174065] Key type ._llcrypt registered [10106.180613] Key type .llcrypt registered [10107.738355] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 04:43:49 (1768556629) [10148.906644] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10148.917586] alg: No test for adler32 (adler32-zlib) [10150.004200] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [10150.233645] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [10151.864632] Key type lgssc registered [10153.116089] Lustre: Echo OBD driver; http://www.lustre.org/ [10234.871469] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10239.023342] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10265.006778] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10310.334149] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 04:47:11 (1768556831) [10319.352213] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 04:47:21 (1768556841) [10355.715901] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10360.548648] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10380.725126] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10383.344729] Lustre: Mounted lustre-client [10388.470777] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [10390.241437] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [10391.547588] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [10393.547426] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [10398.693716] Lustre: lustre-MDT0000-mdc-ffff99f5077e3000: Connection to lustre-MDT0000 (at 192.168.202.119@tcp) was lost; in progress operations using this service will wait for recovery to complete [10415.071782] Lustre: 211089:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1768556922/real 1768556922] req@0000000014839049 x1854466081957632/t0(0) o400->MGC192.168.202.119@tcp@192.168.202.119@tcp:26/25 lens 224/224 e 0 to 1 dl 1768556938 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [10415.162735] LustreError: MGC192.168.202.119@tcp: Connection to MGS (at 192.168.202.119@tcp) was lost; in progress operations using this service will fail [10425.335653] Lustre: Evicted from MGS (at 192.168.202.119@tcp) after server handle changed from 0xc6c105413862803b to 0xc6c10541386281ae [10425.360244] Lustre: MGC192.168.202.119@tcp: Connection restored to 192.168.202.119@tcp (at 192.168.202.119@tcp) [10432.698310] Lustre: lustre-MDT0000-mdc-ffff99f5077e3000: Connection restored to 192.168.202.119@tcp (at 192.168.202.119@tcp) [10442.403084] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10444.233271] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [10448.340494] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [10450.921819] Lustre: lustre-MDT0000-mdc-ffff99f5077e3000: Connection to lustre-MDT0000 (at 192.168.202.119@tcp) was lost; in progress operations using this service will wait for recovery to complete [10467.295222] Lustre: 211091:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1768556974/real 1768556974] req@00000000fd2da1fe x1854466081966848/t0(0) o400->MGC192.168.202.119@tcp@192.168.202.119@tcp:26/25 lens 224/224 e 0 to 1 dl 1768556990 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [10467.323327] LustreError: MGC192.168.202.119@tcp: Connection to MGS (at 192.168.202.119@tcp) was lost; in progress operations using this service will fail [10477.554777] Lustre: Evicted from MGS (at 192.168.202.119@tcp) after server handle changed from 0xc6c10541386281ae to 0xc6c10541386284b0 [10477.563862] Lustre: MGC192.168.202.119@tcp: Connection restored to 192.168.202.119@tcp (at 192.168.202.119@tcp) [10484.382481] Lustre: lustre-MDT0000-mdc-ffff99f5077e3000: Connection restored to 192.168.202.119@tcp (at 192.168.202.119@tcp) [10489.675702] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10491.172417] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [10494.743853] LustreError: 219356:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff99f5077e3000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10494.773595] LustreError: 219356:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [10494.836362] Lustre: Unmounted lustre-client [10557.323536] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10561.165822] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10580.947765] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10581.410932] Lustre: lustre-MDT0001-mdc-ffff99f507f75800: Connection to lustre-MDT0001 (at 192.168.202.119@tcp) was lost; in progress operations using this service will wait for recovery to complete [10581.454125] Lustre: lustre-MDT0001-mdc-ffff99f507f75800: Connection restored to 192.168.202.119@tcp (at 192.168.202.119@tcp) [10581.483855] Lustre: Mounted lustre-client [10582.990732] LustreError: 220977:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff99f507f75800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10583.016562] LustreError: 220977:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [10583.021438] LustreError: 220977:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [10583.093915] Lustre: Unmounted lustre-client [10605.392431] Key type lgssc unregistered [10605.740202] LNet: 221379:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10605.755055] LNetError: 221379:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10605.777093] LNet: Removed LNI 192.168.202.19@tcp [10606.533599] Key type .llcrypt unregistered [10606.537243] Key type ._llcrypt unregistered [10624.831778] Key type ._llcrypt registered [10624.834454] Key type .llcrypt registered [10626.000435] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 04:52:28 (1768557148) [10631.604341] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10631.659881] alg: No test for adler32 (adler32-zlib) [10632.885605] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [10633.249106] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [10634.951216] Key type lgssc registered [10636.738467] Lustre: Echo OBD driver; http://www.lustre.org/ [10682.796601] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10686.449409] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10706.171726] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10707.990155] Lustre: Mounted lustre-client [10712.936856] LustreError: 224550:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff99f510370000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10712.962045] LustreError: 224550:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [10713.050866] Lustre: Unmounted lustre-client [10785.299832] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10790.584606] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10810.716740] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10811.496913] Lustre: Mounted lustre-client [10821.241346] LustreError: 226157:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff99f504d58800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10821.288429] LustreError: 226157:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [10821.291255] LustreError: 226157:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [10821.378180] Lustre: Unmounted lustre-client [10890.225658] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10894.976851] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10919.258821] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10919.981057] Lustre: Mounted lustre-client [10922.168506] LustreError: 227780:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff99f511b31000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10922.202597] LustreError: 227780:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [10922.214813] LustreError: 227780:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [10922.304096] Lustre: Unmounted lustre-client [10948.758206] Key type lgssc unregistered [10948.981216] LNet: 228181:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10948.992618] LNetError: 228181:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10949.016851] LNet: Removed LNI 192.168.202.19@tcp [10949.583729] Key type .llcrypt unregistered [10949.589560] Key type ._llcrypt unregistered [10966.081861] Key type ._llcrypt registered [10966.084782] Key type .llcrypt registered [10967.197410] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 04:58:09 (1768557489) [10970.564112] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10970.724063] alg: No test for adler32 (adler32-zlib) [10971.723597] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [10971.883463] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [10973.511179] Key type lgssc registered [10974.878768] Lustre: Echo OBD driver; http://www.lustre.org/ [11025.829894] Key type lgssc unregistered [11026.080909] LNet: 230499:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11026.091943] LNetError: 230499:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11026.110899] LNet: Removed LNI 192.168.202.19@tcp [11026.716980] Key type .llcrypt unregistered [11026.718657] Key type ._llcrypt unregistered [11060.970791] Key type ._llcrypt registered [11060.973772] Key type .llcrypt registered [11061.613303] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11061.635493] alg: No test for adler32 (adler32-zlib) [11062.746072] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [11062.904094] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [11064.591555] Key type lgssc registered [11066.088481] Lustre: Echo OBD driver; http://www.lustre.org/ [11164.890974] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11170.235142] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11195.288884] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11241.759903] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [11244.034447] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 05:02:45 (1768557765) [11281.298448] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11285.414643] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11306.067117] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11326.935989] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [11328.922445] Lustre: Mounted lustre-client [11334.128292] Lustre: lustre-OST0001-osc-ffff99f5069c7800: Connection to lustre-OST0001 (at 192.168.202.119@tcp) was lost; in progress operations using this service will wait for recovery to complete [11342.185491] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [11352.442956] LustreError: 237033:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [11352.495341] Lustre: Unmounted lustre-client [11389.676216] Key type lgssc unregistered [11390.033414] LNet: 237438:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11390.046641] LNetError: 237438:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11390.070238] LNet: Removed LNI 192.168.202.19@tcp [11390.912267] Key type .llcrypt unregistered [11390.916802] Key type ._llcrypt unregistered [11427.770419] Key type ._llcrypt registered [11427.772544] Key type .llcrypt registered [11429.270794] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 05:05:51 (1768557951) [11444.492286] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11444.548892] alg: No test for adler32 (adler32-zlib) [11445.786862] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [11446.088949] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [11447.863233] Key type lgssc registered [11449.215332] Lustre: Echo OBD driver; http://www.lustre.org/ [11501.382666] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11506.294279] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11533.397610] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 05:07:34 (1768558054) [11568.602786] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11573.076371] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11592.985870] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11594.675415] Lustre: Mounted lustre-client [11601.741372] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [11610.670924] LustreError: 242469:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [11610.740733] Lustre: Unmounted lustre-client [11722.759730] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11726.652231] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11780.240075] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11780.961130] Lustre: Mounted lustre-client [11783.593386] LustreError: 244526:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff99f510376800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11783.616527] LustreError: 244526:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [11783.620241] LustreError: 244526:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [11783.672373] Lustre: Unmounted lustre-client [11810.416314] Key type lgssc unregistered [11810.719661] LNet: 244929:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11810.729142] LNetError: 244929:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11810.743368] LNet: Removed LNI 192.168.202.19@tcp [11811.436514] Key type .llcrypt unregistered [11811.438657] Key type ._llcrypt unregistered [11836.053416] Key type ._llcrypt registered [11836.098295] Key type .llcrypt registered [11836.396858] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11836.415351] alg: No test for adler32 (adler32-zlib) [11837.526806] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [11837.788956] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [11839.503482] Key type lgssc registered [11840.637884] Lustre: Echo OBD driver; http://www.lustre.org/ [11918.565641] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11922.992840] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11945.933581] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12001.305769] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [12003.226156] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 05:15:25 (1768558525) [12038.791326] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12044.104971] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12072.244544] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12077.773734] Lustre: Mounted lustre-client [12081.321426] LustreError: 250994:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff99f5042b3000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12081.370599] LustreError: 250994:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [12081.483390] Lustre: Unmounted lustre-client [12113.203559] Key type lgssc unregistered [12113.525113] LNet: 251398:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12113.530312] LNetError: 251398:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12113.551598] LNet: Removed LNI 192.168.202.19@tcp [12114.474794] Key type .llcrypt unregistered [12114.477840] Key type ._llcrypt unregistered [12136.061524] Key type ._llcrypt registered [12136.068403] Key type .llcrypt registered [12137.993294] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [12140.118060] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 05:17:41 (1768558661) [12150.215246] Key type .llcrypt unregistered [12150.218459] Key type ._llcrypt unregistered [12159.569182] Key type ._llcrypt registered [12159.571631] Key type .llcrypt registered [12160.098827] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12160.110229] alg: No test for adler32 (adler32-zlib) [12161.216929] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [12161.491635] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [12163.163314] Key type lgssc registered [12164.189345] Lustre: Echo OBD driver; http://www.lustre.org/ [12223.742506] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12240.677165] Lustre: Mounted lustre-client [12243.362965] LustreError: 254422:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff99f518b9a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12243.398225] LustreError: 254422:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [12243.486816] Lustre: Unmounted lustre-client [12272.379974] Key type lgssc unregistered [12272.609512] LNet: 254824:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12272.620704] LNetError: 254824:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12272.644435] LNet: Removed LNI 192.168.202.19@tcp [12273.384540] Key type .llcrypt unregistered [12273.388317] Key type ._llcrypt unregistered [12295.737667] Key type ._llcrypt registered [12295.747056] Key type .llcrypt registered [12297.499293] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 05:20:18 (1768558818) [12301.507438] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12301.547447] alg: No test for adler32 (adler32-zlib) [12302.720956] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [12303.080351] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [12304.808105] Key type lgssc registered [12306.899729] Lustre: Echo OBD driver; http://www.lustre.org/ [12398.489487] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12434.727236] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12440.254239] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12443.613330] Lustre: Mounted lustre-client [12446.743938] LustreError: 257882:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff99f5070ba000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12446.764705] LustreError: 257882:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [12446.829384] Lustre: Unmounted lustre-client [12475.594848] Key type lgssc unregistered [12475.873439] LNet: 258286:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12475.889301] LNetError: 258286:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12475.908117] LNet: Removed LNI 192.168.202.19@tcp [12476.554462] Key type .llcrypt unregistered [12476.557238] Key type ._llcrypt unregistered [12493.840775] Key type ._llcrypt registered [12493.842487] Key type .llcrypt registered [12495.210948] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 05:23:37 (1768559017) [12497.435474] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12497.444446] alg: No test for adler32 (adler32-zlib) [12498.496880] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [12498.774638] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [12500.608892] Key type lgssc registered [12502.225743] Lustre: Echo OBD driver; http://www.lustre.org/ [12563.200612] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12568.521497] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12591.906791] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12594.986932] Lustre: Mounted lustre-client [12600.300815] Lustre: lustre-MDT0000-mdc-ffff99f5042b2800: Connection to lustre-MDT0000 (at 192.168.202.119@tcp) was lost; in progress operations using this service will wait for recovery to complete [12606.557698] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [12606.588448] LustreError: 261366:0:(file.c:6123:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [12606.968812] LustreError: 261385:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff99f5042b2800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12606.992975] LustreError: MGC192.168.202.119@tcp: Connection to MGS (at 192.168.202.119@tcp) was lost; in progress operations using this service will fail [12607.001587] LustreError: 261385:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [12607.057424] Lustre: Unmounted lustre-client [12629.890882] Key type lgssc unregistered [12630.261840] LNet: 261778:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12630.269561] LNetError: 261778:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12630.293202] LNet: Removed LNI 192.168.202.19@tcp [12631.344858] Key type .llcrypt unregistered [12631.348346] Key type ._llcrypt unregistered [12652.856092] Key type ._llcrypt registered [12652.857491] Key type .llcrypt registered [12655.351733] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 05:26:15 (1768559175) [12658.898591] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12658.931665] alg: No test for adler32 (adler32-zlib) [12660.021285] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [12660.227521] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [12661.919191] Key type lgssc registered [12663.299376] Lustre: Echo OBD driver; http://www.lustre.org/ [12719.611733] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12724.496554] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12748.308535] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12751.198938] Lustre: Mounted lustre-client [12756.475503] Lustre: lustre-MDT0001-mdc-ffff99f507551800: Connection to lustre-MDT0001 (at 192.168.202.119@tcp) was lost; in progress operations using this service will wait for recovery to complete [12762.850692] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [12765.808064] LustreError: 264884:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff99f507551800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12765.845531] LustreError: 264884:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [12765.903295] Lustre: Unmounted lustre-client [12788.948650] Key type lgssc unregistered [12789.322146] LNet: 265278:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12789.335252] LNetError: 265278:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12789.352901] LNet: Removed LNI 192.168.202.19@tcp [12790.204098] Key type .llcrypt unregistered [12790.209193] Key type ._llcrypt unregistered [12808.673384] Key type ._llcrypt registered [12808.680207] Key type .llcrypt registered [12809.860202] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 05:28:51 (1768559331) [12831.122529] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12831.190097] alg: No test for adler32 (adler32-zlib) [12832.418319] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [12832.677664] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [12834.367495] Key type lgssc registered [12835.590310] Lustre: Echo OBD driver; http://www.lustre.org/ [12952.241936] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12954.024595] Lustre: Mounted lustre-client [13000.167388] Lustre: lustre-MDT0001-mdc-ffff99f507551000: Connection to lustre-MDT0001 (at 192.168.202.119@tcp) was lost; in progress operations using this service will wait for recovery to complete [13006.749546] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [13017.038545] LustreError: 270634:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [13017.091117] Lustre: Unmounted lustre-client [13045.416814] Key type lgssc unregistered [13045.696701] LNet: 271036:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13045.703788] LNetError: 271036:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [13045.723688] LNet: Removed LNI 192.168.202.19@tcp [13046.438568] Key type .llcrypt unregistered [13046.442281] Key type ._llcrypt unregistered [13063.630574] Key type ._llcrypt registered [13063.631950] Key type .llcrypt registered [13065.009936] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 05:33:06 (1768559586) [13067.633520] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [13069.461407] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 05:33:11 (1768559591) [13071.576978] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [13073.682307] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 05:33:15 (1768559595) [13075.490451] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [13078.559805] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 05:33:19 (1768559599) [13080.425346] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [13082.781351] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 05:33:24 (1768559604) [13085.336272] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [13087.777737] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 05:33:29 (1768559609) [13096.138275] Key type .llcrypt unregistered [13096.140755] Key type ._llcrypt unregistered [13103.321919] Key type ._llcrypt registered [13103.342861] Key type .llcrypt registered [13103.816559] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13103.833275] alg: No test for adler32 (adler32-zlib) [13105.053485] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [13105.291535] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [13107.087175] Key type lgssc registered [13108.061936] Lustre: Echo OBD driver; http://www.lustre.org/ [13192.990828] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [13198.709673] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [13224.123613] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [13226.088600] Lustre: Mounted lustre-client [13228.838780] LustreError: 276639:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff99f5042b0800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13228.867719] LustreError: 276639:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [13228.949049] Lustre: Unmounted lustre-client [13271.184812] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 05:36:32 (1768559792) [13309.705661] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [13314.098390] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [13346.800518] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [13353.010612] Lustre: Mounted lustre-client [13356.074290] LustreError: 278761:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff99f5077e7000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13356.109252] LustreError: 278761:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [13356.116198] LustreError: 278761:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [13356.178620] Lustre: Unmounted lustre-client [13404.945196] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 05:38:46 (1768559926) [13407.391850] LNet: 1 local NIs in recovery (showing 1): 192.168.202.19@tcp [14060.312466] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [14064.594816] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [14098.511257] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [14200.035464] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 05:52:02 (1768560722) [14212.626689] Key type lgssc unregistered [14213.014136] LNet: 284315:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14213.018985] LNetError: 284315:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [14213.036815] LNet: Removed LNI 192.168.202.19@tcp [14213.773567] Key type .llcrypt unregistered [14213.782913] Key type ._llcrypt unregistered [14350.364097] Key type ._llcrypt registered [14350.370790] Key type .llcrypt registered [14350.724469] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14350.736749] alg: No test for adler32 (adler32-zlib) [14351.751520] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [14351.951021] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [14353.639168] Key type lgssc registered [14354.972228] Lustre: Echo OBD driver; http://www.lustre.org/ [14449.097507] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [14453.621140] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [14478.117640] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [14523.198621] Key type lgssc unregistered [14523.477623] LNet: 287924:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14523.487311] LNetError: 287924:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [14523.498038] LNet: Removed LNI 192.168.202.19@tcp [14524.245626] Key type .llcrypt unregistered [14524.250694] Key type ._llcrypt unregistered [14562.430842] Key type ._llcrypt registered [14562.434382] Key type .llcrypt registered [14564.825653] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 05:58:05 (1768561085) [14588.988565] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14589.001586] alg: No test for adler32 (adler32-zlib) [14590.150389] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [14590.405067] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [14592.111202] Key type lgssc registered [14593.785514] Lustre: Echo OBD driver; http://www.lustre.org/ [14649.568181] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 05:59:31 (1768561171) [14661.743426] Key type lgssc unregistered [14662.038085] LNet: 291478:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14662.045192] LNetError: 291478:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [14662.059984] LNet: Removed LNI 192.168.202.19@tcp [14662.759593] Key type .llcrypt unregistered [14662.763563] Key type ._llcrypt unregistered [14675.173473] Key type ._llcrypt registered [14675.186231] Key type .llcrypt registered [14675.725449] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14675.754930] alg: No test for adler32 (adler32-zlib) [14676.950306] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [14677.216560] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [14678.911182] Key type lgssc registered [14680.000624] Lustre: Echo OBD driver; http://www.lustre.org/ [14730.752584] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [14734.632674] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [14756.481773] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [15054.937762] LNet: Added LNI 192.168.202.19@tcp50 [8/256/0/180] [15058.205530] LNet: Removed LNI 192.168.202.19@tcp [15060.470507] Lustre: 295798:0:(ldlm_lib.c:563:client_obd_setup()) lustre-MDT0000-mdc-ffff99f50560d800: config has no valid NIDs, force dynamic NIDs [15061.716956] Lustre: Mounted lustre-client [15061.792485] LustreError: 295814:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff99f50560d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [15061.822601] LustreError: 295814:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [15061.904104] Lustre: Unmounted lustre-client [15097.283395] Key type lgssc unregistered [15097.580404] LNet: 296657:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15097.586843] LNetError: 296657:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [15097.635861] LNet: Removed LNI 192.168.202.19@tcp50 [15098.126479] Key type .llcrypt unregistered [15098.132334] Key type ._llcrypt unregistered [15131.981892] Key type ._llcrypt registered [15131.992958] Key type .llcrypt registered [15133.652754] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 06:07:35 (1768561655) [15136.779928] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [15136.801423] alg: No test for adler32 (adler32-zlib) [15137.944460] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [15138.362238] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [15140.151587] Key type lgssc registered [15141.548849] Lustre: Echo OBD driver; http://www.lustre.org/ [15197.826483] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [15202.841541] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [15225.692296] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [15228.532602] Lustre: Mounted lustre-client [15296.814443] Lustre: Mounted fs15246-client [15313.660905] LustreError: 300804:0:(lov_obd.c:783:lov_cleanup()) fs15246-clilov-ffff99f507c7e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [15313.694980] LustreError: 300804:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [15313.765520] Lustre: Unmounted fs15246-client [15341.702509] LustreError: 301022:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff99f5069c0800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [15341.715987] LustreError: 301022:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [15341.720111] LustreError: 301022:0:(obd_class.h:479:obd_check_dev()) Skipped 3 previous similar messages [15341.781643] Lustre: Unmounted lustre-client [15371.538369] Key type lgssc unregistered [15371.952603] LNet: 301425:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15371.965706] LNetError: 301425:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [15372.001084] LNet: Removed LNI 192.168.202.19@tcp [15373.219271] Key type .llcrypt unregistered [15373.235163] Key type ._llcrypt unregistered [15387.808329] Key type ._llcrypt registered [15387.815086] Key type .llcrypt registered [15389.410665] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 06:11:51 (1768561911) [15392.170983] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [15392.395124] alg: No test for adler32 (adler32-zlib) [15393.542792] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [15393.782395] LNet: Added LNI 192.168.202.19@tcp [8/256/0/180] [15395.456735] Key type lgssc registered [15397.035887] Lustre: Echo OBD driver; http://www.lustre.org/ [15555.337481] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [15559.723564] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [15588.247740] Lustre: DEBUG MARKER: oleg219-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [15727.681427] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 15485 sec ========= 06:17:28 (1768562248) [15729.802841] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 06:17:31 (1768562251) === [15734.661658] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 06:17:36 (1768562256) === [15777.172875] Key type lgssc unregistered [15777.521639] LNet: 310608:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15777.531156] LNetError: 310608:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [15777.550594] LNet: Removed LNI 192.168.202.19@tcp [15778.378788] Key type .llcrypt unregistered [15778.383613] Key type ._llcrypt unregistered