[ 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 596801310 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.001017] APIC: Switch to symmetric I/O mode setup [ 0.002302] x2apic enabled [ 0.003007] Switched APIC routing to physical x2apic. [ 0.004016] kvm-guest: setup PV IPIs [ 0.006993] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.007000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.007023] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.008015] pid_max: default: 32768 minimum: 301 [ 0.009125] LSM: Security Framework initializing [ 0.010039] Yama: becoming mindful. [ 0.011028] SELinux: Initializing. [ 0.012055] *** VALIDATE selinux *** [ 0.020401] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.025359] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.026122] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.027085] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029041] *** VALIDATE tmpfs *** [ 0.030266] *** VALIDATE proc *** [ 0.031191] *** VALIDATE cgroup *** [ 0.032005] *** VALIDATE cgroup2 *** [ 0.033143] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.034183] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.035005] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.036025] Spectre V2 : User space: Vulnerable [ 0.037007] Speculative Store Bypass: Vulnerable [ 0.040437] debug: unmapping init [mem 0xffffffff9a459000-0xffffffff9a460fff] [ 0.042337] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.043643] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.044021] ... version: 2 [ 0.044984] ... bit width: 48 [ 0.045011] ... generic registers: 4 [ 0.045963] ... value mask: 0000ffffffffffff [ 0.046013] ... max period: 00007fffffffffff [ 0.047008] ... fixed-purpose events: 3 [ 0.048010] ... event mask: 000000070000000f [ 0.049818] rcu: Hierarchical SRCU implementation. [ 0.051951] smp: Bringing up secondary CPUs ... [ 0.052664] x86: Booting SMP configuration: [ 0.053093] .... node #0, CPUs: #1 #2 #3 [ 0.062181] smp: Brought up 1 node, 4 CPUs [ 0.064011] smpboot: Max logical packages: 1 [ 0.065049] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.106279] node 0 deferred pages initialised in 39ms [ 0.109249] devtmpfs: initialized [ 0.110262] x86/mm: Memory block size: 128MB [ 0.113204] gcov: version magic: 0x41383552 [ 0.115404] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.116158] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.117231] pinctrl core: initialized pinctrl subsystem [ 0.118157] [ 0.118797] ************************************************************* [ 0.119018] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.120023] ** ** [ 0.121015] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.122014] ** ** [ 0.123014] ** This means that this kernel is built to expose internal ** [ 0.124012] ** IOMMU data structures, which may compromise security on ** [ 0.125938] ** your system. ** [ 0.126012] ** ** [ 0.127026] ** If you see this message and you are not debugging the ** [ 0.128013] ** kernel, report this immediately to your vendor! ** [ 0.129011] ** ** [ 0.130010] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.131012] ************************************************************* [ 0.132817] NET: Registered protocol family 16 [ 0.133582] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.134067] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.135049] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.136618] cpuidle: using governor menu [ 0.138967] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.141516] PCI: Using configuration type 1 for base access [ 0.145402] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.154157] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.155018] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.158264] cryptd: max_cpu_qlen set to 1000 [ 0.160275] ACPI: Added _OSI(Module Device) [ 0.161013] ACPI: Added _OSI(Processor Device) [ 0.163010] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.164015] ACPI: Added _OSI(Processor Aggregator Device) [ 0.170236] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.176896] ACPI: Interpreter enabled [ 0.177065] ACPI: PM: (supports S0 S3 S4 S5) [ 0.178010] ACPI: Using IOAPIC for interrupt routing [ 0.179115] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.180615] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.195109] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.196120] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.197018] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.198198] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.200642] acpiphp: Slot [2] registered [ 0.201092] acpiphp: Slot [5] registered [ 0.202393] acpiphp: Slot [6] registered [ 0.203093] acpiphp: Slot [3] registered [ 0.204097] acpiphp: Slot [4] registered [ 0.205236] acpiphp: Slot [7] registered [ 0.206119] acpiphp: Slot [8] registered [ 0.207084] acpiphp: Slot [9] registered [ 0.208124] acpiphp: Slot [10] registered [ 0.209234] acpiphp: Slot [11] registered [ 0.210112] acpiphp: Slot [12] registered [ 0.211107] acpiphp: Slot [13] registered [ 0.212105] acpiphp: Slot [14] registered [ 0.213127] acpiphp: Slot [15] registered [ 0.214352] acpiphp: Slot [16] registered [ 0.215170] acpiphp: Slot [17] registered [ 0.216216] acpiphp: Slot [18] registered [ 0.217194] acpiphp: Slot [19] registered [ 0.218289] acpiphp: Slot [20] registered [ 0.219121] acpiphp: Slot [21] registered [ 0.220089] acpiphp: Slot [22] registered [ 0.221176] acpiphp: Slot [23] registered [ 0.222091] acpiphp: Slot [24] registered [ 0.223195] acpiphp: Slot [25] registered [ 0.224142] acpiphp: Slot [26] registered [ 0.225066] acpiphp: Slot [27] registered [ 0.226080] acpiphp: Slot [28] registered [ 0.227071] acpiphp: Slot [29] registered [ 0.228084] acpiphp: Slot [30] registered [ 0.229193] acpiphp: Slot [31] registered [ 0.230049] PCI host bridge to bus 0000:00 [ 0.231016] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.232017] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.233018] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.234015] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.235015] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.236030] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.237266] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.240108] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.243403] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.251013] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.254044] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.257014] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.259011] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.261010] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.262841] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.266171] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.269044] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.271973] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.277014] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.288015] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.293018] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.299963] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.319039] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.329024] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.347017] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.364089] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.377014] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.389017] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.433018] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.450050] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.452362] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.456624] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.458336] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.461178] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.466092] iommu: Default domain type: Passthrough [ 0.468371] SCSI subsystem initialized [ 0.469112] ACPI: bus type USB registered [ 0.471073] usbcore: registered new interface driver usbfs [ 0.473260] usbcore: registered new interface driver hub [ 0.474070] usbcore: registered new device driver usb [ 0.476245] pps_core: LinuxPPS API ver. 1 registered [ 0.478014] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.481075] PTP clock support registered [ 0.482352] EDAC MC: Ver: 3.0.0 [ 0.484808] PCI: Using ACPI for IRQ routing [ 0.485000] NetLabel: Initializing [ 0.485000] NetLabel: domain hash size = 128 [ 0.485000] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.487030] NetLabel: unlabeled traffic allowed by default [ 0.490013] vgaarb: loaded [ 0.491756] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.493008] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.500263] clocksource: Switched to clocksource kvm-clock [ 0.648760] VFS: Disk quotas dquot_6.6.0 [ 0.652729] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.659361] *** VALIDATE ramfs *** [ 0.660520] *** VALIDATE hugetlbfs *** [ 0.661816] pnp: PnP ACPI init [ 0.664482] pnp: PnP ACPI: found 6 devices [ 0.688961] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.691976] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.694177] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.696219] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.698710] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.700762] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.703780] NET: Registered protocol family 2 [ 0.706378] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.711530] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.715804] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.724761] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.728405] TCP: Hash tables configured (established 65536 bind 65536) [ 0.730902] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.734569] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.737158] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.740385] NET: Registered protocol family 1 [ 0.743038] RPC: Registered named UNIX socket transport module. [ 0.745039] RPC: Registered udp transport module. [ 0.747481] RPC: Registered tcp transport module. [ 0.749309] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.750649] NET: Registered protocol family 44 [ 0.751874] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.753225] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.754585] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.756316] PCI: CLS 0 bytes, default 64 [ 0.757547] Unpacking initramfs... [ 2.425385] debug: unmapping init [mem 0xffff8975fcc64000-0xffff8975fffcffff] [ 2.430610] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.434660] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.441752] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.994616] Initialise system trusted keyrings [ 2.996077] Key type blacklist registered [ 2.997883] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 3.008201] zbud: loaded [ 3.011277] *** VALIDATE nfs *** [ 3.012219] *** VALIDATE nfs4 *** [ 3.013678] pstore: using deflate compression [ 3.017571] Platform Keyring initialized [ 3.135661] NET: Registered protocol family 38 [ 3.137773] Key type asymmetric registered [ 3.139212] Asymmetric key parser 'x509' registered [ 3.141593] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 3.145608] io scheduler mq-deadline registered [ 3.147379] io scheduler kyber registered [ 3.149166] io scheduler bfq registered [ 3.150952] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 3.155780] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 3.158763] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 3.161763] ACPI: Power Button [PWRF] [ 3.168621] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.176305] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.191205] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.220961] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.251254] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.256200] Non-volatile memory driver v1.3 [ 3.257875] Linux agpgart interface v0.103 [ 3.291433] virtio_blk virtio1: [vda] 134032 512-byte logical blocks (68.6 MB/65.4 MiB) [ 3.294589] vda: detected capacity change from 0 to 68624384 [ 3.328754] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.332503] vdb: detected capacity change from 0 to 1073741824 [ 3.342626] libphy: Fixed MDIO Bus: probed [ 3.351776] usbcore: registered new interface driver usbserial_generic [ 3.354511] usbserial: USB Serial support registered for generic [ 3.357337] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.361775] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.363750] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.366374] mousedev: PS/2 mouse device common for all mice [ 3.369415] rtc_cmos 00:05: RTC can wake from S4 [ 3.372379] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.373278] rtc_cmos 00:05: registered as rtc0 [ 3.378761] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.558472] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.558513] intel_pstate: CPU model not supported [ 3.559621] hpet1: lost 11 rtc interrupts [ 3.573363] hid: raw HID events driver (C) Jiri Kosina [ 3.584144] usbcore: registered new interface driver usbhid [ 3.585988] usbhid: USB HID core driver [ 3.587491] drop_monitor: Initializing network drop monitor service [ 3.590378] Initializing XFRM netlink socket [ 3.592197] NET: Registered protocol family 10 [ 3.595657] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.600532] Segment Routing with IPv6 [ 3.602064] NET: Registered protocol family 17 [ 3.604153] mpls_gso: MPLS GSO support [ 3.608583] RAS: Correctable Errors collector initialized. [ 3.610260] AVX version of gcm_enc/dec engaged. [ 3.611741] AES CTR mode by8 optimization enabled [ 3.704983] sched_clock: Marking stable (3704905980, 0)->(4870023324, -1165117344) [ 3.708900] registered taskstats version 1 [ 3.711926] Loading compiled-in X.509 certificates [ 3.714763] zswap: loaded using pool lzo/zbud [ 3.787767] Key type big_key registered [ 3.802476] Key type encrypted registered [ 3.803905] ima: No TPM chip found, activating TPM-bypass! [ 3.806675] ima: Allocated hash algorithm: sha1 [ 3.808691] ima: No architecture policies found [ 3.810219] evm: Initialising EVM extended attributes: [ 3.812193] evm: security.selinux [ 3.813327] evm: security.ima [ 3.813921] evm: security.capability [ 3.814953] evm: HMAC attrs: 0x1 [ 3.816860] rtc_cmos 00:05: setting system clock to 2026-01-02 00:49:24 UTC (1767314964) [ 3.822369] debug: unmapping init [mem 0xffffffff9b403000-0xffffffff9b5fffff] [ 3.824339] debug: unmapping init [mem 0xffffffff9a182000-0xffffffff9a458fff] [ 3.837431] Write protecting the kernel read-only data: 28672k [ 3.840379] debug: unmapping init [mem 0xffffffff98803000-0xffffffff989fffff] [ 3.842982] debug: unmapping init [mem 0xffffffff99114000-0xffffffff991fffff] [ 3.876864] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 3.886545] systemd[1]: Detected virtualization kvm. [ 3.889186] systemd[1]: Detected architecture x86-64. [ 3.891524] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.924108] systemd[1]: No hostname configured. [ 3.940321] systemd[1]: Set hostname to . [ 3.942402] random: systemd: uninitialized urandom read (16 bytes read) [ 3.945118] systemd[1]: Initializing machine ID from random generator. [ 4.017407] random: ln: uninitialized urandom read (6 bytes read) [ 4.137735] random: systemd: uninitialized urandom read (16 bytes read) [ 4.144310] systemd[1]: Reached target Timers. [ OK ] Reached target Timers. [ 4.153918] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ 4.158893] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Swap. [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on udev Control Socket. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Local File Systems. [ OK ] Listening on Journal Socket. Starting Journal Service... Starting Setup Virtual Console... Starting Create list of required st…ce nodes for the current kernel... Starting Apply Kernel Variables... [ OK ] Reached target Sockets. Starting Create Volatile Files and Directories... [ OK ] Reached target Slices. [ OK ] Started Memstrack Anylazing Service. [ OK ] Started Setup Virtual Console. [ 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. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 6.777472] device-mapper: uevent: version 1.0.3 [ 6.779786] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 10.233746] virtio_net virtio0 ens2: renamed from eth0 [ 10.553163] scsi host0: ata_piix [ 10.751662] scsi host1: ata_piix [ 15.738349] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 15.743571] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 17.716655] random: crng init done [ 17.743155] random: 7 urandom warning(s) missed due to ratelimiting [ 20.730036] dracut-initqueue[585]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 25.001728] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped target Timers. [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped dracut initqueue hook. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ OK ] Stopped target System Initialization. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Swap. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped target Local File Systems. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. [ OK ] Stopped Apply Kernel Variables. Stopping udev Kernel Device Manager... [ OK ] Stopped target Sockets. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 29.078501] printk: systemd: 26 output lines suppressed due to ratelimiting [ 30.023942] SELinux: Disabled at runtime. [ 30.137233] 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.149802] systemd[1]: Detected virtualization kvm. [ 30.152706] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 32.015550] systemd[1]: initrd-switch-root.service: Succeeded. [ 32.018784] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 32.026667] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 32.034338] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 32.042767] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 32.055343] systemd[1]: Starting Journal Service... Starting Journal Service... [ 32.063336] systemd[1]: Started Forward Password Requests to Wall Directory Watch. [ OK ] Started Forward Password Requests to Wall Directory Watch. Starting Remount Root and Kernel File Systems... Mounting Kernel Debug File System... [ OK ] Stopped target Switch Root. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Reached target Paths. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Stopped target Initrd File Systems. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Listening on udev Control Socket. [ OK ] Reached target rpc_pipefs.target. [ OK ] Listening on Process Core Dump Socket. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Stopped target Initrd Root File System. Starting Create list of required st…ce nodes for the current kernel... Activating swap /dev/disk/by-label/SWAP... [ OK ] Listening on initctl Compatibility Named Pipe. [ 33.223545] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS Mounting POSIX Message Queue File System... Starting Apply Kernel Variables... [ OK ] Listening on udev Kernel Socket. Starting udev Coldplug all Devices... [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Created slice system-getty.slice. Mounting Huge Pages File System... [ OK ] Started Journal Service. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted Kernel Debug File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted Huge Pages File System. [ OK ] Reached target Swap. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started udev Coldplug all Devices. [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... Starting udev Kernel Device Manager... [ OK ] Mounted /mnt. [ 36.379327] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 40.054140] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 40.171464] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 41.095529] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 41.309027] EDAC sbridge: Ver: 1.1.2 [* ] 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) [ *** ] 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) [ ***] A start job is running for Configur…only root support (14s / no limit) [ **] A start job is running for Configur…only root support (14s / no limit)[ 46.894423] Key type dns_resolver registered [ *] A start job is running for Configur…only root support (15s / no limit) [ **] A start job is running for Configur…only root support (15s / no limit)[ 47.944775] NFS: Registering the id_resolver key type [ 47.951901] Key type id_resolver registered [ 47.953366] Key type id_legacy registered [ ***] A start job is running for Configur…only root support (16s / no limit) [ *** ] A start job is running for Configur…only root support (16s / 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 Daily Cleanup of Temporary Directories. [ OK ] Started dnf makecache --timer. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. Starting Restore /run/initramfs on shutdown... [ OK ] Started irqbalance daemon. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Reached target sshd-keygen.target. Starting Login Service... [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Timers. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting OpenSSH server daemon... Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... Starting Network Manager Wait Online... Starting Hostname Service... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Login Service. [ OK ] Started Permit User Sessions. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Reached target Login Prompts. [ OK ] Started OpenSSH server daemon. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Notify NFS peers of a restart... Starting Crash recovery kernel arming... Starting System Logging Service... [ OK ] Started Notify NFS peers of a restart. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg352-client login: [ 92.339081] hrtimer: interrupt took 5900455 ns [ 125.307113] libcfs: loading out-of-tree module taints kernel. [ 125.798280] Key type ._llcrypt registered [ 125.805731] Key type .llcrypt registered [ 126.217373] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 126.236842] alg: No test for adler32 (adler32-zlib) [ 127.693489] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [ 128.589363] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 130.344503] Key type lgssc registered [ 132.074866] Lustre: Echo OBD driver; http://www.lustre.org/ [ 485.701345] Lustre: Mounted lustre-client [ 491.122454] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 509.780255] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing check_logdir /tmp/testlogs/ [ 511.484082] Lustre: lustre-OST0000-osc-ffff8976498dc800: disconnect after 24s idle [ 515.232115] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing yml_node [ 524.202470] Lustre: DEBUG MARKER: Client: 2.17.0.RC4 [ 526.901645] Lustre: DEBUG MARKER: MDS: 2.17.0.RC4 [ 529.670787] Lustre: DEBUG MARKER: OSS: 2.17.0.RC4 [ 531.438796] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Thu Jan 1 19:58:10 EST 2026 [ 554.767449] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 556.867839] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 559.869969] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 570.178138] LustreError: 11075:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 570.222890] Lustre: Unmounted lustre-client [ 829.513310] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 833.123094] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 862.367572] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 901.338874] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 20:04:20 (1767315860) [ 905.020556] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 906.475716] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 20:04:25 (1767315865) [ 945.043807] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 949.176019] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 975.291236] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 976.227236] Lustre: Mounted lustre-client [ 984.888313] LustreError: 18006:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89764853b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 984.908270] LustreError: 18006:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 984.913399] LustreError: 18006:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 984.990989] Lustre: Unmounted lustre-client [ 1017.962279] Key type lgssc unregistered [ 1018.183348] LNet: 18463:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1018.193280] LNetError: 18463:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1019.261703] LNet: Removed LNI 192.168.203.52@tcp [ 1020.088144] Key type .llcrypt unregistered [ 1020.092656] Key type ._llcrypt unregistered [ 1034.242870] Key type ._llcrypt registered [ 1034.244842] Key type .llcrypt registered [ 1035.411813] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 20:06:34 (1767315994) [ 1042.181973] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1042.270087] alg: No test for adler32 (adler32-zlib) [ 1043.494312] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [ 1043.709871] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 1045.440160] Key type lgssc registered [ 1046.570256] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1186.031955] Lustre: Mounted lustre-client [ 1272.947466] LustreError: 22601:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89764684c800: lov tgt 1990 not cleaned! deathrow=0, lovrc=1 [ 1272.972383] LustreError: 22601:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 1273.010058] Lustre: Unmounted lustre-client [ 1303.469355] Key type lgssc unregistered [ 1303.772095] LNet: 23094:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1303.786440] LNetError: 23094:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1303.801332] LNet: Removed LNI 192.168.203.52@tcp [ 1304.473872] Key type .llcrypt unregistered [ 1304.476527] Key type ._llcrypt unregistered [ 1313.702534] Key type ._llcrypt registered [ 1313.705529] Key type .llcrypt registered [ 1314.800145] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 20:11:14 (1767316274) [ 1320.928324] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1320.973519] alg: No test for adler32 (adler32-zlib) [ 1322.184890] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [ 1322.543570] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 1324.240174] Key type lgssc registered [ 1325.833433] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1433.484964] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1438.641178] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1463.135449] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1480.251652] Lustre: Mounted lustre-client [ 1492.652941] LustreError: 26925:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8976489d1800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1492.683427] LustreError: 26925:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1492.733514] Lustre: Unmounted lustre-client [ 1523.301187] Key type lgssc unregistered [ 1523.523871] LNet: 27376:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1523.535039] LNetError: 27376:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1523.553807] LNet: Removed LNI 192.168.203.52@tcp [ 1524.153232] Key type .llcrypt unregistered [ 1524.155536] Key type ._llcrypt unregistered [ 1535.149322] Key type ._llcrypt registered [ 1535.151511] Key type .llcrypt registered [ 1536.422331] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 20:14:55 (1767316495) [ 1542.870051] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1543.037032] alg: No test for adler32 (adler32-zlib) [ 1544.281658] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [ 1544.614303] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 1546.424639] Key type lgssc registered [ 1547.699356] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1654.820291] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1658.776677] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1684.467392] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1687.273426] Lustre: Mounted lustre-client [ 1697.460426] LustreError: 30780:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff897644912800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1697.499091] LustreError: 30780:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1697.590504] Lustre: Unmounted lustre-client [ 1730.824320] Key type lgssc unregistered [ 1731.059651] LNet: 31237:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1731.070955] LNetError: 31237:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1731.094636] LNet: Removed LNI 192.168.203.52@tcp [ 1731.863640] Key type .llcrypt unregistered [ 1731.869348] Key type ._llcrypt unregistered [ 1746.526754] Key type ._llcrypt registered [ 1746.529535] Key type .llcrypt registered [ 1747.898427] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 20:18:27 (1767316707) [ 1754.729471] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1754.743538] alg: No test for adler32 (adler32-zlib) [ 1755.941190] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [ 1756.212236] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 1757.928210] Key type lgssc registered [ 1759.257431] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1872.655902] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1877.798726] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1903.516683] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1915.456687] Lustre: Mounted lustre-client [ 1925.622209] Lustre: lustre-MDT0000-mdc-ffff89764a551800: Connection to lustre-MDT0000 (at 192.168.203.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1935.847303] Lustre: lustre-MDT0001-mdc-ffff89764a551800: Connection to lustre-MDT0001 (at 192.168.203.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1940.541707] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1947.104257] Lustre: 32237:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1767316891/real 1767316891] req@00000000c992d8d7 x1853165892803328/t0(0) o400->MGC192.168.203.152@tcp@192.168.203.152@tcp:26/25 lens 224/224 e 0 to 1 dl 1767316907 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1947.132506] LustreError: MGC192.168.203.152@tcp: Connection to MGS (at 192.168.203.152@tcp) was lost; in progress operations using this service will fail [ 1950.844933] LustreError: 35148:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1950.913614] Lustre: Unmounted lustre-client [ 1972.954817] Key type lgssc unregistered [ 1973.160393] LNet: 35589:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1973.171799] LNetError: 35589:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1973.189268] LNet: Removed LNI 192.168.203.52@tcp [ 1973.854750] Key type .llcrypt unregistered [ 1973.856575] Key type ._llcrypt unregistered [ 1987.356501] Key type ._llcrypt registered [ 1987.358063] Key type .llcrypt registered [ 1988.637550] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 20:22:27 (1767316947) [ 1994.108850] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1994.149169] alg: No test for adler32 (adler32-zlib) [ 1995.281489] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [ 1995.532640] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 1997.288168] Key type lgssc registered [ 1998.630155] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2104.356225] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2108.128213] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2132.833803] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2135.637812] Lustre: Mounted lustre-client [ 2145.791891] Lustre: lustre-OST0000-osc-ffff89764a552800: Connection to lustre-OST0000 (at 192.168.203.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2151.128608] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2161.403946] LustreError: 39417:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2161.445042] Lustre: Unmounted lustre-client [ 2193.402157] Key type lgssc unregistered [ 2193.700167] LNet: 39867:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2193.705299] LNetError: 39867:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2193.721188] LNet: Removed LNI 192.168.203.52@tcp [ 2194.496107] Key type .llcrypt unregistered [ 2194.499461] Key type ._llcrypt unregistered [ 2208.778103] Key type ._llcrypt registered [ 2208.781696] Key type .llcrypt registered [ 2210.319594] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 20:26:09 (1767317169) [ 2216.453730] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2216.507790] alg: No test for adler32 (adler32-zlib) [ 2217.498201] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [ 2217.841632] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 2219.584170] Key type lgssc registered [ 2220.831955] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2325.405917] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2328.802638] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2350.711588] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2352.291747] Lustre: Mounted lustre-client [ 2356.100414] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 2360.392471] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 2371.822571] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2026-01-0120h28m51s [ 2373.844957] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 2378.226719] Lustre: lustre-MDT0000-mdc-ffff8976464ea800: Connection to lustre-MDT0000 (at 192.168.203.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2393.399507] LustreError: 44287:0:(lmv_obd.c:1435:lmv_statfs()) lustre-MDT0000-mdc-ffff8976464ea800: can't stat MDS #0: rc = -19 [ 2394.539922] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 2407.929399] LustreError: MGC192.168.203.152@tcp: Connection to MGS (at 192.168.203.152@tcp) was lost; in progress operations using this service will fail [ 2407.973490] Lustre: Evicted from MGS (at 192.168.203.152@tcp) after server handle changed from 0x5a7c322b8a2d678f to 0x5a7c322b8a2d6a28 [ 2407.988613] Lustre: MGC192.168.203.152@tcp: Connection restored to 192.168.203.152@tcp (at 192.168.203.152@tcp) [ 2408.997873] LustreError: lustre-MDT0000-mdc-ffff8976464ea800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 2409.035520] Lustre: lustre-MDT0000-mdc-ffff8976464ea800: Connection restored to 192.168.203.152@tcp (at 192.168.203.152@tcp) [ 2416.164900] Lustre: DEBUG MARKER: Wait for df (44287) ... [ 2417.607265] Lustre: DEBUG MARKER: done [ 2419.923886] LustreError: 44941:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8976464ea800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2419.931798] LustreError: 44941:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2419.997031] Lustre: Unmounted lustre-client [ 2452.320420] Key type lgssc unregistered [ 2452.665723] LNet: 45392:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2452.680401] LNetError: 45392:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2453.737449] LNet: Removed LNI 192.168.203.52@tcp [ 2454.634408] Key type .llcrypt unregistered [ 2454.643894] Key type ._llcrypt unregistered [ 2523.612241] Key type ._llcrypt registered [ 2523.617230] Key type .llcrypt registered [ 2524.184953] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2524.212761] alg: No test for adler32 (adler32-zlib) [ 2525.337358] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [ 2525.628636] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 2527.280455] Key type lgssc registered [ 2528.604756] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2693.830620] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2696.982424] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2722.442286] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2761.588025] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 20:35:21 (1767317721) [ 2798.255978] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2802.992555] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2825.784375] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2828.574962] Lustre: Mounted lustre-client [ 2836.148760] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2026-01-0120h36m35s [ 2837.575743] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 2844.967444] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 2847.311621] Lustre: DEBUG MARKER: Creating a test file and stat it [ 2867.680259] Lustre: 53721:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1767317808/real 1767317808] req@00000000c7d6e022 x1853166700206976/t0(0) o101->lustre-MDT0000-mdc-ffff8976493a5800@192.168.203.152@tcp:12/10 lens 576/1152 e 0 to 1 dl 1767317828 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:0 [ 2867.714293] Lustre: lustre-MDT0000-mdc-ffff8976493a5800: Connection to lustre-MDT0000 (at 192.168.203.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2867.762187] Lustre: lustre-MDT0000-mdc-ffff8976493a5800: Connection restored to 192.168.203.152@tcp (at 192.168.203.152@tcp) [ 2889.138902] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 2892.288637] Lustre: DEBUG MARKER: done [ 2896.346653] LustreError: 54199:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8976493a5800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2896.366465] LustreError: 54199:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2896.429159] Lustre: Unmounted lustre-client [ 2925.413272] Key type lgssc unregistered [ 2925.652630] LNet: 54651:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2925.662400] LNetError: 54651:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2925.686572] LNet: Removed LNI 192.168.203.52@tcp [ 2926.585330] Key type .llcrypt unregistered [ 2926.590409] Key type ._llcrypt unregistered [ 2997.247216] Key type ._llcrypt registered [ 2997.252470] Key type .llcrypt registered [ 2997.761721] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2997.772344] alg: No test for adler32 (adler32-zlib) [ 2998.910527] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [ 2999.150645] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 3000.800330] Key type lgssc registered [ 3002.192240] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3187.445774] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3191.403400] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3219.600281] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3274.608205] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 20:43:53 (1767318233) [ 3276.483111] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 3278.320524] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 20:43:57 (1767318237) [ 3370.161804] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 20:45:29 (1767318329) [ 3407.867339] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3411.540891] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3433.530495] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3435.393780] Lustre: Mounted lustre-client [ 3439.023338] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 3441.901171] LustreError: 64555:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89764bd85800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3441.931737] LustreError: 64555:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3442.017759] Lustre: Unmounted lustre-client [ 3462.228665] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 3512.254146] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3516.454188] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3517.125620] Lustre: Mounted lustre-client [ 3520.935180] LustreError: 65938:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8976498d8000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3520.955857] LustreError: 65938:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3520.960769] LustreError: 65938:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 3521.009545] Lustre: Unmounted lustre-client [ 3585.504596] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3590.132584] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3590.845549] Lustre: Mounted lustre-client [ 3595.307158] LustreError: 67183:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff897647f6c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3595.366363] LustreError: 67183:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3595.377696] LustreError: 67183:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 3595.541118] Lustre: Unmounted lustre-client [ 3623.025625] Lustre: DEBUG MARKER: files compared the same [ 3656.590570] Key type lgssc unregistered [ 3656.868515] LNet: 67978:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3656.888711] LNetError: 67978:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3656.927793] LNet: Removed LNI 192.168.203.52@tcp [ 3658.402588] Key type .llcrypt unregistered [ 3658.405527] Key type ._llcrypt unregistered [ 3672.080252] Key type ._llcrypt registered [ 3672.084312] Key type .llcrypt registered [ 3673.419780] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 20:50:32 (1767318632) [ 3680.021584] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3680.065557] alg: No test for adler32 (adler32-zlib) [ 3681.233149] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [ 3681.584448] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 3683.328160] Key type lgssc registered [ 3685.369449] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3796.872459] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3801.278147] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3823.326282] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3826.168098] Lustre: Mounted lustre-client [ 3838.400957] LustreError: 71785:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff897644073800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3838.417900] LustreError: 71785:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3838.457197] Lustre: Unmounted lustre-client [ 3875.662743] Key type lgssc unregistered [ 3876.018696] LNet: 72238:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3876.027532] LNetError: 72238:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3876.069370] LNet: Removed LNI 192.168.203.52@tcp [ 3877.072076] Key type .llcrypt unregistered [ 3877.074632] Key type ._llcrypt unregistered [ 3894.432983] Key type ._llcrypt registered [ 3894.435317] Key type .llcrypt registered [ 3895.770406] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 20:54:15 (1767318855) [ 3901.758598] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3901.823727] alg: No test for adler32 (adler32-zlib) [ 3903.037635] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [ 3903.311045] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 3905.008200] Key type lgssc registered [ 3906.418771] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4050.992646] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4099.883955] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4103.981110] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4136.832141] Key type lgssc unregistered [ 4137.226891] LNet: 76410:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4137.234338] LNetError: 76410:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4137.247120] LNet: Removed LNI 192.168.203.52@tcp [ 4138.005701] Key type .llcrypt unregistered [ 4138.007334] Key type ._llcrypt unregistered [ 4153.192987] Key type ._llcrypt registered [ 4153.198133] Key type .llcrypt registered [ 4154.584843] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 20:58:33 (1767319113) [ 4165.305562] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4165.338846] alg: No test for adler32 (adler32-zlib) [ 4166.420973] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [ 4166.665740] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 4168.368358] Key type lgssc registered [ 4169.745515] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4310.426874] Lustre: Mounted lustre-client [ 4317.579344] LustreError: 79701:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff897649fe9800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4317.603798] LustreError: 79701:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4317.662777] Lustre: Unmounted lustre-client [ 4358.256491] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 21:01:57 (1767319317) [ 4624.203845] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4650.048850] Lustre: Mounted lustre-client [ 4658.507702] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4658.526522] LustreError: 85101:0:(file.c:249:ll_close_inode_openhandle()) lustre-clilmv-ffff8976498df800: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 4658.548959] Lustre: 77472:0:(llite_lib.c:4226:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.203.152@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 4668.904315] LustreError: 85101:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 4668.921637] LustreError: 85101:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 4669.000409] Lustre: Unmounted lustre-client [ 4730.432642] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 21:08:09 (1767319689) [ 4748.886704] Key type lgssc unregistered [ 4749.204305] LNet: 86406:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4749.212440] LNetError: 86406:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4750.247773] LNet: Removed LNI 192.168.203.52@tcp [ 4751.170898] Key type .llcrypt unregistered [ 4751.176533] Key type ._llcrypt unregistered [ 4769.135746] Key type ._llcrypt registered [ 4769.156472] Key type .llcrypt registered [ 4769.409363] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4769.429641] alg: No test for adler32 (adler32-zlib) [ 4770.468043] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [ 4770.721816] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 4772.400248] Key type lgssc registered [ 4773.637276] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4887.132849] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4891.216232] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4916.073594] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4917.719808] Lustre: Mounted lustre-client [ 4925.082546] LustreError: 89639:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8976446ba800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4925.116290] LustreError: 89639:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 4925.191333] Lustre: Unmounted lustre-client [ 4956.536245] Key type lgssc unregistered [ 4956.809507] LNet: 90092:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4956.813554] LNetError: 90092:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4957.864544] LNet: Removed LNI 192.168.203.52@tcp [ 4958.478872] Key type .llcrypt unregistered [ 4958.481681] Key type ._llcrypt unregistered [ 4971.229669] Key type ._llcrypt registered [ 4971.245773] Key type .llcrypt registered [ 4971.949769] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4971.970853] alg: No test for adler32 (adler32-zlib) [ 4973.029549] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [ 4973.226294] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 4974.891087] Key type lgssc registered [ 4976.227156] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5159.646418] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5164.440226] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5188.845987] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5190.488409] Lustre: Mounted lustre-client [ 5198.361900] LustreError: 93829:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff897649cac000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5198.381955] LustreError: 93829:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 5198.454581] Lustre: Unmounted lustre-client [ 5241.947555] Key type lgssc unregistered [ 5242.417283] LNet: 94282:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5242.438639] LNetError: 94282:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5242.476184] LNet: Removed LNI 192.168.203.52@tcp [ 5243.470600] Key type .llcrypt unregistered [ 5243.479822] Key type ._llcrypt unregistered [ 5261.722764] Key type ._llcrypt registered [ 5261.727837] Key type .llcrypt registered [ 5263.714513] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 21:17:02 (1767320222) [ 5270.124495] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5270.171284] alg: No test for adler32 (adler32-zlib) [ 5271.331391] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [ 5271.633235] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 5273.296777] Key type lgssc registered [ 5275.267418] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5392.437841] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5395.988972] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5420.133557] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5435.953297] Lustre: Mounted lustre-client [ 5443.002386] LustreError: 98117:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8976498df800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5443.033522] LustreError: 98117:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 5443.123199] Lustre: Unmounted lustre-client [ 5443.896995] Lustre: Mounted lustre-client [ 5447.991383] LustreError: 98196:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8976489d5000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5448.021681] LustreError: 98196:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 5448.028026] LustreError: 98196:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 5448.069039] Lustre: Unmounted lustre-client [ 5483.761861] Key type lgssc unregistered [ 5483.979245] LNet: 98650:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5483.984051] LNetError: 98650:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5485.033065] LNet: Removed LNI 192.168.203.52@tcp [ 5485.662109] Key type .llcrypt unregistered [ 5485.666309] Key type ._llcrypt unregistered [ 5501.486379] Key type ._llcrypt registered [ 5501.505048] Key type .llcrypt registered [ 5501.936554] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5501.965616] alg: No test for adler32 (adler32-zlib) [ 5503.167631] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [ 5503.475482] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 5505.168226] Key type lgssc registered [ 5506.309436] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5614.104558] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5617.963641] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5640.874508] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5643.752311] Lustre: Mounted lustre-client [ 5651.916479] LustreError: 101901:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8976489d5800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5651.933374] LustreError: 101901:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 5651.986435] Lustre: Unmounted lustre-client [ 5685.885797] Key type lgssc unregistered [ 5686.210873] LNet: 102354:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5686.220601] LNetError: 102354:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5687.275129] LNet: Removed LNI 192.168.203.52@tcp [ 5687.954549] Key type .llcrypt unregistered [ 5687.956448] Key type ._llcrypt unregistered [ 5701.932265] Key type ._llcrypt registered [ 5701.934240] Key type .llcrypt registered [ 5703.119127] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 21:24:22 (1767320662) [ 5709.668962] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5709.804100] alg: No test for adler32 (adler32-zlib) [ 5710.886218] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [ 5711.118824] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 5712.784355] Key type lgssc registered [ 5714.151249] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5816.676211] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5821.475071] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5843.154828] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5844.859958] Lustre: Mounted lustre-client [ 5860.976825] Lustre: lustre: root_squash is set to 0:0 [ 5869.600263] Lustre: lustre: nosquash_nids is cleared [ 5869.630081] Lustre: lustre: root_squash is set to 500:500 [ 5877.247186] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.203.52@tcp 192.168.0.[2,10]@tcp [ 5885.658992] Lustre: lustre: nosquash_nids set to 192.168.203.52/24@tcp [ 5888.882397] LustreError: 106500:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8976467dc800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5888.903768] LustreError: 106500:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 5888.972273] Lustre: Unmounted lustre-client [ 5918.227446] Key type lgssc unregistered [ 5918.584916] LNet: 106951:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5918.597934] LNetError: 106951:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5918.611784] LNet: Removed LNI 192.168.203.52@tcp [ 5919.361543] Key type .llcrypt unregistered [ 5919.363577] Key type ._llcrypt unregistered [ 5938.528742] Key type ._llcrypt registered [ 5938.532020] Key type .llcrypt registered [ 5939.712912] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 21:28:19 (1767320899) [ 5942.846942] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 5944.539303] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 21:28:23 (1767320903) [ 5950.213961] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5950.250274] alg: No test for adler32 (adler32-zlib) [ 5951.351379] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [ 5951.682698] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 5953.432364] Key type lgssc registered [ 5955.149873] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6052.316175] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6055.760191] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6080.007218] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6081.924870] Lustre: lustre: root_squash is set to 500:500 [ 6081.987903] Lustre: lustre: nosquash_nids set to 192.168.203.52/24@tcp [ 6083.103871] Lustre: Mounted lustre-client [ 6091.089624] LustreError: 111140:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89764a551800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6091.110059] LustreError: 111140:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 6091.158787] Lustre: Unmounted lustre-client [ 6125.912581] Key type lgssc unregistered [ 6126.170716] LNet: 111592:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6126.176731] LNetError: 111592:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6127.209173] LNet: Removed LNI 192.168.203.52@tcp [ 6128.176353] Key type .llcrypt unregistered [ 6128.178291] Key type ._llcrypt unregistered [ 6145.914260] Key type ._llcrypt registered [ 6145.916646] Key type .llcrypt registered [ 6147.354532] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 6150.059483] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 21:31:48 (1767321108) [ 6187.537479] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6187.574198] alg: No test for adler32 (adler32-zlib) [ 6188.685319] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [ 6188.956801] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 6190.632146] Key type lgssc registered [ 6191.579694] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6361.429352] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6364.778053] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6390.409363] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6470.221894] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6475.197519] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6498.867338] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6509.435804] Lustre: Mounted lustre-client [ 6546.846787] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8976467db000.ost_server_uuid 50 [ 6548.441023] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8976467db000.ost_server_uuid in IDLE state after 0 sec [ 6549.039977] Lustre: Mounted lustre-client [ 6552.154953] LustreError: 119980:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89764c213000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6552.181037] LustreError: 119980:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 6552.255241] Lustre: Unmounted lustre-client [ 6553.725577] LustreError: 120000:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8976467db000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6553.733934] LustreError: 120000:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 6553.754611] LustreError: 120000:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 6553.761465] LustreError: 120000:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 6553.827105] Lustre: Unmounted lustre-client [ 6604.397483] Key type lgssc unregistered [ 6604.689059] LNet: 120623:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6604.697053] LNetError: 120623:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6604.709183] LNet: Removed LNI 192.168.203.52@tcp [ 6605.218181] Key type .llcrypt unregistered [ 6605.220956] Key type ._llcrypt unregistered [ 6672.454793] Key type ._llcrypt registered [ 6672.463184] Key type .llcrypt registered [ 6672.824787] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6672.834705] alg: No test for adler32 (adler32-zlib) [ 6673.939932] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [ 6674.126090] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 6675.776422] Key type lgssc registered [ 6677.043322] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6847.005436] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6850.533826] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6874.777573] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6915.271438] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 21:44:34 (1767321874) [ 7113.330739] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7117.198303] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7144.875801] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7146.365732] Lustre: Mounted lustre-client [ 7167.779340] Lustre: 121700:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1767322112/real 1767322112] req@00000000012e0ca3 x1853171049698432/t0(0) o400->lustre-OST0000-osc-ffff897649caf000@192.168.203.152@tcp:28/4 lens 224/224 e 0 to 1 dl 1767322128 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 7167.810111] Lustre: lustre-OST0000-osc-ffff897649caf000: Connection to lustre-OST0000 (at 192.168.203.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7172.961152] Lustre: 121698:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1767322117/real 1767322117] req@00000000b2bfb192 x1853171049698944/t0(0) o400->lustre-OST0000-osc-ffff897649caf000@192.168.203.152@tcp:28/4 lens 224/224 e 0 to 1 dl 1767322133 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 7178.208522] Lustre: 121701:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1767322122/real 1767322122] req@000000004c0f0e97 x1853171049699456/t0(0) o400->lustre-OST0000-osc-ffff897649caf000@192.168.203.152@tcp:28/4 lens 224/224 e 0 to 1 dl 1767322138 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 7179.162247] Lustre: lustre-OST0000-osc-ffff897649caf000: Connection restored to 192.168.203.152@tcp (at 192.168.203.152@tcp) [ 7183.136223] Lustre: 121698:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1767322127/real 1767322127] req@000000009cbd3482 x1853171049699968/t0(0) o400->lustre-OST0000-osc-ffff897649caf000@192.168.203.152@tcp:28/4 lens 224/224 e 0 to 1 dl 1767322143 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 7188.468936] Lustre: lustre-MDT0000-mdc-ffff897649caf000: Connection to lustre-MDT0000 (at 192.168.203.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7204.833142] Lustre: 121701:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1767322149/real 1767322149] req@000000005bbf5872 x1853171049702528/t0(0) o400->MGC192.168.203.152@tcp@192.168.203.152@tcp:26/25 lens 224/224 e 0 to 1 dl 1767322165 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 7204.870316] LustreError: MGC192.168.203.152@tcp: Connection to MGS (at 192.168.203.152@tcp) was lost; in progress operations using this service will fail [ 7215.093987] Lustre: Evicted from MGS (at 192.168.203.152@tcp) after server handle changed from 0x1ad698e6d3adee30 to 0x1ad698e6d3adefbf [ 7215.113113] Lustre: MGC192.168.203.152@tcp: Connection restored to 192.168.203.152@tcp (at 192.168.203.152@tcp) [ 7234.228576] Lustre: lustre-MDT0000-mdc-ffff897649caf000: Connection restored to 192.168.203.152@tcp (at 192.168.203.152@tcp) [ 7237.504590] LustreError: 132147:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff897649caf000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7237.522076] LustreError: 132147:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7237.553201] Lustre: Unmounted lustre-client [ 7264.872251] Key type lgssc unregistered [ 7265.123508] LNet: 132599:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7265.130575] LNetError: 132599:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7265.151438] LNet: Removed LNI 192.168.203.52@tcp [ 7266.284054] Key type .llcrypt unregistered [ 7266.286986] Key type ._llcrypt unregistered [ 7284.629965] Key type ._llcrypt registered [ 7284.632959] Key type .llcrypt registered [ 7286.069479] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 21:50:45 (1767322245) [ 7316.387344] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7316.450578] alg: No test for adler32 (adler32-zlib) [ 7317.511737] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [ 7317.700403] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 7319.360171] Key type lgssc registered [ 7320.555140] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7404.560782] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7593.185908] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7595.757641] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7619.650598] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7621.309769] Lustre: Mounted lustre-client [ 7622.851630] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 7624.434390] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 21:56:23 (1767322583) [ 7797.714762] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7807.987478] LustreError: 145532:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7808.056283] Lustre: Unmounted lustre-client [ 7979.936310] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7982.688699] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8002.958385] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8042.729335] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 22:03:22 (1767323002) [ 8186.218221] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8189.136861] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8208.613782] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8209.134884] Lustre: Mounted lustre-client [ 8212.106344] LustreError: 155524:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8976442c0800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8212.118810] LustreError: 155524:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 8212.120589] LustreError: 155524:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 8212.180285] Lustre: Unmounted lustre-client [ 8238.971476] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 22:06:38 (1767323198) [ 8374.301699] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8377.286318] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8396.346967] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8396.718742] Lustre: Mounted lustre-client [ 8399.111704] LustreError: 160860:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8976498db800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8399.129081] LustreError: 160860:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 8399.132606] LustreError: 160860:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 8399.176287] Lustre: Unmounted lustre-client [ 8428.603238] Key type lgssc unregistered [ 8428.803986] LNet: 161312:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8428.810731] LNetError: 161312:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8428.823711] LNet: Removed LNI 192.168.203.52@tcp [ 8429.274374] Key type .llcrypt unregistered [ 8429.276231] Key type ._llcrypt unregistered [ 8438.304330] Key type ._llcrypt registered [ 8438.305630] Key type .llcrypt registered [ 8439.114566] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 22:09:58 (1767323398) [ 8442.743655] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8442.772588] alg: No test for adler32 (adler32-zlib) [ 8443.724357] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [ 8443.891183] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 8445.504165] Key type lgssc registered [ 8446.191247] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8511.362064] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8513.570166] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8526.675585] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8529.083996] Lustre: Mounted lustre-client [ 8544.610584] LustreError: 165119:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89764673c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8544.622119] LustreError: 165119:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 8544.655948] Lustre: Unmounted lustre-client [ 8577.022452] Key type lgssc unregistered [ 8577.201086] LNet: 165573:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8577.205222] LNetError: 165573:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8577.215422] LNet: Removed LNI 192.168.203.52@tcp [ 8577.589510] Key type .llcrypt unregistered [ 8577.591389] Key type ._llcrypt unregistered [ 8585.736750] Key type ._llcrypt registered [ 8585.738291] Key type .llcrypt registered [ 8586.490320] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 22:12:26 (1767323546) [ 8590.197086] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8590.263063] alg: No test for adler32 (adler32-zlib) [ 8591.228468] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [ 8591.367570] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 8592.992588] Key type lgssc registered [ 8593.639647] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8651.694822] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8653.663081] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8666.384831] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8668.719064] Lustre: Mounted lustre-client [ 8678.889638] Lustre: lustre-OST0000-osc-ffff8976464fe000: Connection to lustre-OST0000 (at 192.168.203.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8683.717528] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff8976464fe000.ost_server_uuid 50 [ 8684.625282] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8976464fe000.ost_server_uuid in DISCONN state after 0 sec [ 8685.513682] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 8695.172872] LustreError: 169949:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8976464fe000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8695.180916] LustreError: 169949:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 8695.211151] Lustre: Unmounted lustre-client [ 8712.524868] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 22:14:32 (1767323672) [ 8730.812051] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8732.435663] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8743.261424] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8757.782968] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 8758.066352] Lustre: Mounted lustre-client [ 8763.365646] Lustre: lustre-OST0000-osc-ffff897649caa800: Connection to lustre-OST0000 (at 192.168.203.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8779.358087] LustreError: 172800:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff897649caa800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8779.369359] LustreError: 172800:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 8779.373359] LustreError: 172800:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 8779.399103] Lustre: Unmounted lustre-client [ 8818.923279] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 22:16:18 (1767323778) [ 8836.835651] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8838.495904] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8848.706161] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8857.971862] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 8858.251970] Lustre: Mounted lustre-client [ 8863.717988] Lustre: lustre-OST0000-osc-ffff89764673f000: Connection to lustre-OST0000 (at 192.168.203.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8875.697199] LustreError: 175773:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89764673f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8875.703293] LustreError: 175773:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 8875.710265] LustreError: 175773:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 8875.712545] LustreError: 175773:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 8875.751772] Lustre: Unmounted lustre-client [ 8904.710952] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 22:17:44 (1767323864) [ 9002.539074] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9004.225038] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9017.000225] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9053.305820] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9055.081055] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9065.900178] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9086.563491] Lustre: Mounted lustre-client [ 9118.043610] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9132.284509] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 13 sec [ 9132.961212] LustreError: 183991:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89764673a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9132.967884] LustreError: 183991:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 9132.976231] LustreError: 183991:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 9132.979594] LustreError: 183991:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 9133.009595] Lustre: Unmounted lustre-client [ 9154.025240] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 22:21:54 (1767324114) [ 9170.697697] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9172.228449] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9181.808417] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9204.158502] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 9217.200351] Lustre: Mounted lustre-client [ 9248.366940] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 9249.198880] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 9253.354114] Lustre: lustre-OST0001-osc-ffff89764bd82000: Connection to lustre-OST0001 (at 192.168.203.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9257.569340] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 9267.817829] LustreError: 187807:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 9267.820996] LustreError: 187807:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 9267.844735] Lustre: Unmounted lustre-client [ 9312.787917] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 22:24:32 (1767324272) [ 9327.816104] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9329.444239] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9338.616895] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9343.995913] Lustre: Mounted lustre-client [ 9355.336107] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 9356.965321] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 1 sec [ 9363.795669] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8976489d7800.ost_server_uuid 50 [ 9364.555054] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8976489d7800.ost_server_uuid in FULL state after 0 sec [ 9365.454430] LustreError: 191450:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8976489d7800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9365.464048] LustreError: 191450:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 9365.467843] LustreError: 191450:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 9365.499107] Lustre: Unmounted lustre-client [ 9365.632324] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 9365.636030] Lustre: Skipped 3 previous similar messages [ 9365.690553] Lustre: Mounted lustre-client [ 9366.110618] LustreError: 191513:0:(sec.c:410:import_sec_validate_get()) import 0000000024758598 (NEW) with no sec [ 9412.684755] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 22:26:12 (1767324372) [ 9431.153631] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9432.812599] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9444.418241] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9454.766230] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 9454.947297] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 9460.222574] Lustre: Mounted lustre-client [ 9464.204191] LustreError: 194556:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8976442c0800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9464.209417] LustreError: 194556:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9464.218183] LustreError: 194556:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 9464.220591] LustreError: 194556:0:(obd_class.h:479:obd_check_dev()) Skipped 18 previous similar messages [ 9464.257386] Lustre: Unmounted lustre-client [ 9464.259055] Lustre: Skipped 1 previous similar message [ 9492.654549] Key type lgssc unregistered [ 9492.770442] LNet: 195052:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9492.773552] LNetError: 195052:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9492.783738] LNet: Removed LNI 192.168.203.52@tcp [ 9493.042855] Key type .llcrypt unregistered [ 9493.044207] Key type ._llcrypt unregistered [ 9498.893483] Key type ._llcrypt registered [ 9498.894915] Key type .llcrypt registered [ 9499.471929] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 22:27:39 (1767324459) [ 9499.788265] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9499.828036] alg: No test for adler32 (adler32-zlib) [ 9500.714267] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [ 9500.813202] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 9502.400160] Key type lgssc registered [ 9502.821970] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9549.016721] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9550.432762] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9559.338608] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9568.649255] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 9569.864428] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 9570.933269] Lustre: Mounted lustre-client [ 9575.477436] LustreError: lustre-MDT0001-mdc-ffff8976442c6800: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 9575.489556] Lustre: lustre-MDT0001-mdc-ffff8976442c6800: Connection restored to 192.168.203.152@tcp (at 192.168.203.152@tcp) [ 9577.113711] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9577.850060] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9590.258835] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 9591.866930] LustreError: 200005:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8976442c6800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9591.881436] LustreError: 200005:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 9591.917937] Lustre: Unmounted lustre-client [ 9614.247750] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 22:29:34 (1767324574) [ 9704.332246] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9705.859646] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9717.740358] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9723.380220] Lustre: Mounted lustre-client [ 9738.985685] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 9739.805596] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 9744.868834] Lustre: lustre-OST0001-osc-ffff89764853a800: Connection to lustre-OST0001 (at 192.168.203.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9751.884351] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 9762.153778] LustreError: 206112:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 9762.157285] LustreError: 206112:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 9762.177195] Lustre: Unmounted lustre-client [ 9788.564394] Key type lgssc unregistered [ 9788.681559] LNet: 206566:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9788.684757] LNetError: 206566:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9788.692886] LNet: Removed LNI 192.168.203.52@tcp [ 9788.977675] Key type .llcrypt unregistered [ 9788.979535] Key type ._llcrypt unregistered [ 9814.737743] Key type ._llcrypt registered [ 9814.742559] Key type .llcrypt registered [ 9814.934531] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9814.941736] alg: No test for adler32 (adler32-zlib) [ 9815.803174] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [ 9815.896425] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [ 9817.480155] Key type lgssc registered [ 9817.876228] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9886.854665] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9888.078448] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9898.459848] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9921.067351] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 22:34:41 (1767324881) [ 9934.495247] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9935.885508] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9943.690386] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9954.294719] Lustre: Mounted lustre-client [ 9991.953829] LustreError: 214107:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff897649cab000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9991.963355] LustreError: 214107:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 9991.982936] Lustre: Unmounted lustre-client [10009.996258] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10010.177819] Lustre: Mounted lustre-client [10042.119411] LustreError: 214805:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff897644073000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10042.126715] LustreError: 214805:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [10042.128731] LustreError: 214805:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [10042.149119] Lustre: Unmounted lustre-client [10060.893063] Key type lgssc unregistered [10061.019376] LNet: 215262:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10061.022259] LNetError: 215262:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10061.038847] LNet: Removed LNI 192.168.203.52@tcp [10061.280445] Key type .llcrypt unregistered [10061.282082] Key type ._llcrypt unregistered [10066.384942] Key type ._llcrypt registered [10066.386056] Key type .llcrypt registered [10066.855346] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 22:37:07 (1767325027) [10068.783757] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10068.796411] alg: No test for adler32 (adler32-zlib) [10069.660264] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [10069.748353] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [10071.336181] Key type lgssc registered [10071.756100] Lustre: Echo OBD driver; http://www.lustre.org/ [10108.372543] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10109.551701] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10117.095628] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10127.858242] Lustre: Mounted lustre-client [10134.839492] LustreError: 219215:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8976498dc000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10134.848270] LustreError: 219215:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [10134.869148] Lustre: Unmounted lustre-client [10153.937577] Key type lgssc unregistered [10154.063614] LNet: 219665:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10154.066564] LNetError: 219665:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10154.075080] LNet: Removed LNI 192.168.203.52@tcp [10154.319252] Key type .llcrypt unregistered [10154.320297] Key type ._llcrypt unregistered [10159.598177] Key type ._llcrypt registered [10159.599470] Key type .llcrypt registered [10159.821331] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10159.829315] alg: No test for adler32 (adler32-zlib) [10160.690837] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [10160.771430] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [10162.360200] Key type lgssc registered [10162.793626] Lustre: Echo OBD driver; http://www.lustre.org/ [10199.543183] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10200.787247] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10207.910143] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10218.480489] Lustre: Mounted lustre-client [10221.280829] LustreError: 222929:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8976448d3800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10221.288462] LustreError: 222929:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [10221.309160] Lustre: Unmounted lustre-client [10239.986703] Key type lgssc unregistered [10240.102406] LNet: 223381:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10240.104639] LNetError: 223381:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10240.114628] LNet: Removed LNI 192.168.203.52@tcp [10240.328680] Key type .llcrypt unregistered [10240.329760] Key type ._llcrypt unregistered [10245.393812] Key type ._llcrypt registered [10245.394843] Key type .llcrypt registered [10245.848257] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 22:40:06 (1767325206) [10247.749197] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10247.762175] alg: No test for adler32 (adler32-zlib) [10248.619938] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [10248.700960] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [10250.280198] Key type lgssc registered [10250.657137] Lustre: Echo OBD driver; http://www.lustre.org/ [10286.246245] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10287.471161] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10294.588530] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10305.004871] Lustre: Mounted lustre-client [10311.639646] LustreError: 227331:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff897646848800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10311.646840] LustreError: 227331:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [10311.664394] Lustre: Unmounted lustre-client [10326.285273] Key type lgssc unregistered [10326.407756] LNet: 227782:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10326.410645] LNetError: 227782:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10326.421663] LNet: Removed LNI 192.168.203.52@tcp [10326.660890] Key type .llcrypt unregistered [10326.662247] Key type ._llcrypt unregistered [10331.669766] Key type ._llcrypt registered [10331.672182] Key type .llcrypt registered [10331.842165] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10331.846971] alg: No test for adler32 (adler32-zlib) [10332.703085] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [10332.782239] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [10334.360145] Key type lgssc registered [10334.725707] Lustre: Echo OBD driver; http://www.lustre.org/ [10370.328992] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10371.465271] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10378.548818] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10388.981271] Lustre: Mounted lustre-client [10391.862811] LustreError: 231046:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89764bd84800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10391.870815] LustreError: 231046:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [10391.891850] Lustre: Unmounted lustre-client [10418.429839] Key type lgssc unregistered [10418.562683] LNet: 231499:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10418.566066] LNetError: 231499:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10418.573871] LNet: Removed LNI 192.168.203.52@tcp [10418.820821] Key type .llcrypt unregistered [10418.821695] Key type ._llcrypt unregistered [10424.207424] Key type ._llcrypt registered [10424.208385] Key type .llcrypt registered [10424.672510] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 22:43:04 (1767325384) [10439.488131] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10439.525084] alg: No test for adler32 (adler32-zlib) [10440.387260] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [10440.470580] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [10442.056126] Key type lgssc registered [10442.470712] Lustre: Echo OBD driver; http://www.lustre.org/ [10502.303954] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10503.492077] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10513.089177] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10535.272194] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 22:44:55 (1767325495) [10547.960970] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10549.179476] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10556.626219] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10567.160677] Lustre: Mounted lustre-client [10569.009481] LustreError: 239422:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff897645c62800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10569.018321] LustreError: 239422:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [10569.037064] Lustre: Unmounted lustre-client [10587.643308] Key type lgssc unregistered [10587.755485] LNet: 239873:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10587.758256] LNetError: 239873:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10587.767747] LNet: Removed LNI 192.168.203.52@tcp [10587.992873] Key type .llcrypt unregistered [10587.994216] Key type ._llcrypt unregistered [10593.021414] Key type ._llcrypt registered [10593.022622] Key type .llcrypt registered [10593.446760] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [10593.986242] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 22:45:54 (1767325554) [10606.388216] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10606.396449] alg: No test for adler32 (adler32-zlib) [10607.244595] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [10607.324883] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [10608.904179] Key type lgssc registered [10609.282239] Lustre: Echo OBD driver; http://www.lustre.org/ [10673.058215] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10674.189448] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10683.572268] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid [10691.905111] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid [10693.066537] Lustre: Mounted lustre-client [10699.054854] LustreError: 246762:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89764a557800: lov tgt 1000 not cleaned! deathrow=0, lovrc=1 [10699.062575] LustreError: 246762:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [10699.088193] Lustre: Unmounted lustre-client [10798.718226] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10799.838488] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10809.185520] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10838.972778] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 22:49:59 (1767325799) [10839.426132] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [10839.975805] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 22:50:00 (1767325800) [10861.706027] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10862.805826] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10887.310083] Key type lgssc unregistered [10887.421485] LNet: 254545:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10887.424490] LNetError: 254545:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10887.433556] LNet: Removed LNI 192.168.203.52@tcp [10887.663095] Key type .llcrypt unregistered [10887.664163] Key type ._llcrypt unregistered [10892.586049] Key type ._llcrypt registered [10892.586773] Key type .llcrypt registered [10893.017514] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 22:50:53 (1767325853) [10907.562315] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10907.598888] alg: No test for adler32 (adler32-zlib) [10908.454661] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [10908.540914] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [10910.120249] Key type lgssc registered [10910.504132] Lustre: Echo OBD driver; http://www.lustre.org/ [10942.118025] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10943.214718] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10950.002418] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10968.530575] Key type lgssc unregistered [10968.638262] LNet: 258993:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10968.640532] LNetError: 258993:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10968.648230] LNet: Removed LNI 192.168.203.52@tcp [10968.881842] Key type .llcrypt unregistered [10968.884288] Key type ._llcrypt unregistered [10973.828585] Key type ._llcrypt registered [10973.830078] Key type .llcrypt registered [10974.288294] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 22:52:14 (1767325934) [10977.841424] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10977.881124] alg: No test for adler32 (adler32-zlib) [10978.733039] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [10978.811055] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [10980.392124] Key type lgssc registered [10980.791888] Lustre: Echo OBD driver; http://www.lustre.org/ [11014.813703] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11015.955908] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11022.642880] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11033.072566] Lustre: Mounted lustre-client [11038.178270] Lustre: lustre-MDT0000-mdc-ffff8976498db000: Connection to lustre-MDT0000 (at 192.168.203.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [11043.298449] Lustre: lustre-MDT0001-mdc-ffff8976498db000: Connection to lustre-MDT0001 (at 192.168.203.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [11058.656147] Lustre: 260075:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1767326003/real 1767326003] req@00000000bbf37a94 x1853175563895040/t0(0) o400->MGC192.168.203.152@tcp@192.168.203.152@tcp:26/25 lens 224/224 e 0 to 1 dl 1767326019 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [11058.658043] LustreError: MGC192.168.203.152@tcp: Connection to MGS (at 192.168.203.152@tcp) was lost; in progress operations using this service will fail [11058.668144] Lustre: Evicted from MGS (at 192.168.203.152@tcp) after server handle changed from 0x2f6b41de3ec5e349 to 0x2f6b41de3ec5fca9 [11058.671662] Lustre: MGC192.168.203.152@tcp: Connection restored to 192.168.203.152@tcp (at 192.168.203.152@tcp) [11076.648490] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11079.140162] LustreError: lustre-MDT0000-mdc-ffff8976498db000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [11079.157034] Lustre: lustre-MDT0000-mdc-ffff8976498db000: Connection restored to 192.168.203.152@tcp (at 192.168.203.152@tcp) [11080.247830] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 3 sec [11081.394686] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11081.950642] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [11082.562815] LustreError: 264299:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8976498db000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11082.568742] LustreError: 264299:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [11082.589129] Lustre: Unmounted lustre-client [11101.219779] Key type lgssc unregistered [11101.326543] LNet: 264750:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11101.329222] LNetError: 264750:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11101.338620] LNet: Removed LNI 192.168.203.52@tcp [11101.559335] Key type .llcrypt unregistered [11101.560495] Key type ._llcrypt unregistered [11106.741760] Key type ._llcrypt registered [11106.742833] Key type .llcrypt registered [11107.215737] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 22:54:27 (1767326067) [11110.005373] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11110.050718] alg: No test for adler32 (adler32-zlib) [11110.925530] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [11111.013655] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [11112.600163] Key type lgssc registered [11112.986429] Lustre: Echo OBD driver; http://www.lustre.org/ [11148.820437] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11150.000309] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11176.352597] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11177.549266] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11189.057326] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11197.578755] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [11217.989858] Key type lgssc unregistered [11218.114458] LNet: 270885:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11218.116551] LNetError: 270885:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11218.123328] LNet: Removed LNI 192.168.203.52@tcp [11218.350371] Key type .llcrypt unregistered [11218.352131] Key type ._llcrypt unregistered [11242.003789] Key type ._llcrypt registered [11242.004896] Key type .llcrypt registered [11242.227546] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11242.232676] alg: No test for adler32 (adler32-zlib) [11243.103220] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [11243.201037] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [11244.784118] Key type lgssc registered [11245.213391] Lustre: Echo OBD driver; http://www.lustre.org/ [11304.636380] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11305.753914] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11315.219417] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11338.985876] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 22:58:19 (1767326299) [11415.133189] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11416.277702] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11425.563465] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11447.556630] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 23:00:07 (1767326407) [11450.213734] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 23:00:10 (1767326410) [11463.550224] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11464.718217] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11471.713495] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11482.096365] Lustre: Mounted lustre-client [11485.292822] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [11486.074877] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [11486.638784] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [11487.194147] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [11492.322901] Lustre: lustre-MDT0000-mdc-ffff897644a51800: Connection to lustre-MDT0000 (at 192.168.203.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [11507.683467] LustreError: MGC192.168.203.152@tcp: Connection to MGS (at 192.168.203.152@tcp) was lost; in progress operations using this service will fail [11507.690673] Lustre: Evicted from MGS (at 192.168.203.152@tcp) after server handle changed from 0x364ab728e1bfec5a to 0x364ab728e1bfeec2 [11507.693507] Lustre: MGC192.168.203.152@tcp: Connection restored to 192.168.203.152@tcp (at 192.168.203.152@tcp) [11510.409073] Lustre: lustre-MDT0000-mdc-ffff897644a51800: Connection restored to 192.168.203.152@tcp (at 192.168.203.152@tcp) [11511.612712] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11512.149036] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [11514.002376] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [11517.923083] Lustre: lustre-MDT0000-mdc-ffff897644a51800: Connection to lustre-MDT0000 (at 192.168.203.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [11533.283383] LustreError: MGC192.168.203.152@tcp: Connection to MGS (at 192.168.203.152@tcp) was lost; in progress operations using this service will fail [11533.290732] Lustre: Evicted from MGS (at 192.168.203.152@tcp) after server handle changed from 0x364ab728e1bfeec2 to 0x364ab728e1bff0d6 [11533.294419] Lustre: MGC192.168.203.152@tcp: Connection restored to 192.168.203.152@tcp (at 192.168.203.152@tcp) [11546.245428] Lustre: lustre-MDT0000-mdc-ffff897644a51800: Connection restored to 192.168.203.152@tcp (at 192.168.203.152@tcp) [11547.441383] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11547.997489] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [11549.540315] LustreError: 287649:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff897644a51800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11549.547043] LustreError: 287649:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [11549.567153] Lustre: Unmounted lustre-client [11587.895163] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11589.059170] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11596.096240] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11606.514556] Lustre: Mounted lustre-client [11606.853938] LustreError: 289488:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8976498de000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11606.860919] LustreError: 289488:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [11606.863156] LustreError: 289488:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [11606.883125] Lustre: Unmounted lustre-client [11625.990497] Key type lgssc unregistered [11626.111594] LNet: 289940:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11626.113964] LNetError: 289940:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11626.122420] LNet: Removed LNI 192.168.203.52@tcp [11626.386405] Key type .llcrypt unregistered [11626.387633] Key type ._llcrypt unregistered [11631.580099] Key type ._llcrypt registered [11631.581112] Key type .llcrypt registered [11632.071641] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 23:03:12 (1767326592) [11635.306286] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11635.315167] alg: No test for adler32 (adler32-zlib) [11636.178829] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [11636.270658] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [11637.856179] Key type lgssc registered [11638.262211] Lustre: Echo OBD driver; http://www.lustre.org/ [11673.999886] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11675.186902] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11682.203319] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11692.527952] Lustre: Mounted lustre-client [11695.996821] LustreError: 293833:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff897644a54000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11696.005759] LustreError: 293833:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [11696.031135] Lustre: Unmounted lustre-client [11734.391611] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11735.529382] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11742.592218] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11752.959713] Lustre: Mounted lustre-client [11758.984850] LustreError: 295633:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff897648bad800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11758.991133] LustreError: 295633:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [11758.992664] LustreError: 295633:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [11759.012185] Lustre: Unmounted lustre-client [11792.135259] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11793.363792] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11800.301721] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11810.803678] Lustre: Mounted lustre-client [11811.138425] LustreError: 297467:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff897650134000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11811.145274] LustreError: 297467:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [11811.147405] LustreError: 297467:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [11811.170148] Lustre: Unmounted lustre-client [11838.115464] Key type lgssc unregistered [11838.250475] LNet: 297919:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11838.252992] LNetError: 297919:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11838.261373] LNet: Removed LNI 192.168.203.52@tcp [11838.527266] Key type .llcrypt unregistered [11838.528516] Key type ._llcrypt unregistered [11843.612917] Key type ._llcrypt registered [11843.614429] Key type .llcrypt registered [11844.109454] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 23:06:44 (1767326804) [11846.988092] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11847.003551] alg: No test for adler32 (adler32-zlib) [11847.858363] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [11847.943966] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [11849.536130] Key type lgssc registered [11849.904786] Lustre: Echo OBD driver; http://www.lustre.org/ [11884.425637] Key type lgssc unregistered [11884.536469] LNet: 300958:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11884.538917] LNetError: 300958:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11884.548423] LNet: Removed LNI 192.168.203.52@tcp [11884.813420] Key type .llcrypt unregistered [11884.814542] Key type ._llcrypt unregistered [11900.628162] Key type ._llcrypt registered [11900.629750] Key type .llcrypt registered [11900.826375] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11900.834960] alg: No test for adler32 (adler32-zlib) [11901.688384] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [11901.772022] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [11903.352154] Key type lgssc registered [11903.719884] Lustre: Echo OBD driver; http://www.lustre.org/ [11962.010705] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11963.412250] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11973.109307] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11990.701414] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [11991.226787] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 23:09:11 (1767326951) [12002.959572] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12004.121566] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12011.134190] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12020.455585] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [12021.624920] Lustre: Mounted lustre-client [12026.852782] Lustre: lustre-OST0001-osc-ffff897649cab000: Connection to lustre-OST0001 (at 192.168.203.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [12029.252356] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [12039.528471] LustreError: 308895:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [12039.543111] Lustre: Unmounted lustre-client [12072.563856] Key type lgssc unregistered [12072.669681] LNet: 309351:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12072.672642] LNetError: 309351:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12072.684156] LNet: Removed LNI 192.168.203.52@tcp [12072.911757] Key type .llcrypt unregistered [12072.912948] Key type ._llcrypt unregistered [12095.623918] Key type ._llcrypt registered [12095.625411] Key type .llcrypt registered [12095.848748] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12095.858077] alg: No test for adler32 (adler32-zlib) [12096.724424] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [12096.817909] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [12098.400125] Key type lgssc registered [12098.856620] Lustre: Echo OBD driver; http://www.lustre.org/ [12155.297811] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12156.514587] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12165.914250] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12187.525623] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 23:12:27 (1767327147) [12203.524214] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12204.659072] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12224.588417] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 23:13:04 (1767327184) [12235.901249] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12237.064249] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12243.758975] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12254.190810] Lustre: Mounted lustre-client [12262.378805] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [12266.709677] LustreError: 318794:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [12266.727148] Lustre: Unmounted lustre-client [12322.379286] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12323.587695] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12355.798890] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12366.323485] Lustre: Mounted lustre-client [12366.713979] LustreError: 321147:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff897649cad800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12366.720557] LustreError: 321147:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [12366.722537] LustreError: 321147:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [12366.746246] Lustre: Unmounted lustre-client [12377.861883] Key type lgssc unregistered [12377.991634] LNet: 321596:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12377.994546] LNetError: 321596:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12378.003963] LNet: Removed LNI 192.168.203.52@tcp [12378.300530] Key type .llcrypt unregistered [12378.301643] Key type ._llcrypt unregistered [12393.888968] Key type ._llcrypt registered [12393.895103] Key type .llcrypt registered [12394.133502] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12394.139185] alg: No test for adler32 (adler32-zlib) [12395.015838] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [12395.100358] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [12396.688214] Key type lgssc registered [12397.024561] Lustre: Echo OBD driver; http://www.lustre.org/ [12453.651684] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12454.706446] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12463.556526] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12484.690012] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [12485.234258] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 23:17:25 (1767327445) [12498.082490] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12499.241414] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12505.819662] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12517.359384] Lustre: Mounted lustre-client [12518.143086] LustreError: 329054:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff897644076000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12518.148681] LustreError: 329054:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [12518.166099] Lustre: Unmounted lustre-client [12536.416405] Key type lgssc unregistered [12536.535656] LNet: 329506:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12536.538906] LNetError: 329506:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12536.548845] LNet: Removed LNI 192.168.203.52@tcp [12536.779554] Key type .llcrypt unregistered [12536.780547] Key type ._llcrypt unregistered [12542.052813] Key type ._llcrypt registered [12542.053893] Key type .llcrypt registered [12542.509185] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [12543.037313] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 23:18:23 (1767327503) [12547.500321] Key type .llcrypt unregistered [12547.501238] Key type ._llcrypt unregistered [12551.320977] Key type ._llcrypt registered [12551.322416] Key type .llcrypt registered [12551.562554] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12551.568733] alg: No test for adler32 (adler32-zlib) [12552.432033] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [12552.522400] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [12554.104182] Key type lgssc registered [12554.537583] Lustre: Echo OBD driver; http://www.lustre.org/ [12589.414703] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12600.302982] Lustre: Mounted lustre-client [12600.713273] LustreError: 333263:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff897649caf000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12600.720175] LustreError: 333263:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [12600.738887] Lustre: Unmounted lustre-client [12618.837748] Key type lgssc unregistered [12618.950576] LNet: 333714:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12618.953292] LNetError: 333714:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12618.962708] LNet: Removed LNI 192.168.203.52@tcp [12619.177970] Key type .llcrypt unregistered [12619.178962] Key type ._llcrypt unregistered [12623.834757] Key type ._llcrypt registered [12623.835640] Key type .llcrypt registered [12624.273034] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 23:19:44 (1767327584) [12626.090693] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12626.097806] alg: No test for adler32 (adler32-zlib) [12626.948448] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [12627.027966] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [12628.608144] Key type lgssc registered [12628.952225] Lustre: Echo OBD driver; http://www.lustre.org/ [12704.105964] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12716.020102] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12717.163432] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12718.349382] Lustre: Mounted lustre-client [12718.826975] LustreError: 337452:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff897649ca6000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12718.835284] LustreError: 337452:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [12718.855747] Lustre: Unmounted lustre-client [12737.247730] Key type lgssc unregistered [12737.362382] LNet: 337903:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12737.364476] LNetError: 337903:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12737.371691] LNet: Removed LNI 192.168.203.52@tcp [12737.572767] Key type .llcrypt unregistered [12737.573716] Key type ._llcrypt unregistered [12742.561307] Key type ._llcrypt registered [12742.562443] Key type .llcrypt registered [12743.030948] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 23:21:43 (1767327703) [12745.098779] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12745.105591] alg: No test for adler32 (adler32-zlib) [12745.968084] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [12746.055641] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [12747.640127] Key type lgssc registered [12747.989964] Lustre: Echo OBD driver; http://www.lustre.org/ [12792.192516] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12793.537414] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12800.369240] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12810.735166] Lustre: Mounted lustre-client [12815.842834] Lustre: lustre-MDT0000-mdc-ffff897644a54000: Connection to lustre-MDT0000 (at 192.168.203.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [12818.595418] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [12818.603173] LustreError: 341676:0:(file.c:6123:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [12818.699332] LustreError: 341695:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff897644a54000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12818.702664] LustreError: MGC192.168.203.152@tcp: Connection to MGS (at 192.168.203.152@tcp) was lost; in progress operations using this service will fail [12818.704707] LustreError: 341695:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [12818.722254] Lustre: Unmounted lustre-client [12830.684708] Key type lgssc unregistered [12830.807306] LNet: 342136:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12830.810950] LNetError: 342136:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12830.820433] LNet: Removed LNI 192.168.203.52@tcp [12831.043278] Key type .llcrypt unregistered [12831.044278] Key type ._llcrypt unregistered [12836.901707] Key type ._llcrypt registered [12836.902679] Key type .llcrypt registered [12837.424629] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 23:23:17 (1767327797) [12839.760239] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12839.815135] alg: No test for adler32 (adler32-zlib) [12840.664466] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [12840.752061] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [12842.336172] Key type lgssc registered [12842.721062] Lustre: Echo OBD driver; http://www.lustre.org/ [12877.204897] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12878.588202] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12886.361501] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12896.751489] Lustre: Mounted lustre-client [12901.860616] Lustre: lustre-MDT0001-mdc-ffff89764a554000: Connection to lustre-MDT0001 (at 192.168.203.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [12904.725950] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [12905.064382] LustreError: 345934:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89764a554000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12905.071138] LustreError: 345934:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [12905.092696] Lustre: Unmounted lustre-client [12917.030370] Key type lgssc unregistered [12917.138591] LNet: 346377:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12917.141974] LNetError: 346377:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12917.152500] LNet: Removed LNI 192.168.203.52@tcp [12917.396641] Key type .llcrypt unregistered [12917.397682] Key type ._llcrypt unregistered [12922.369390] Key type ._llcrypt registered [12922.370371] Key type .llcrypt registered [12922.799486] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 23:24:43 (1767327883) [12935.178305] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12935.228711] alg: No test for adler32 (adler32-zlib) [12936.094899] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [12936.179690] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [12937.776210] Key type lgssc registered [12938.163394] Lustre: Echo OBD driver; http://www.lustre.org/ [13027.289258] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [13033.944250] Lustre: Mounted lustre-client [13050.852114] Lustre: lustre-MDT0001-mdc-ffff89764bd80800: Connection to lustre-MDT0001 (at 192.168.203.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [13057.929186] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [13068.201726] LustreError: 353665:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [13068.215178] Lustre: Unmounted lustre-client [13086.923289] Key type lgssc unregistered [13087.029691] LNet: 354114:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13087.032283] LNetError: 354114:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [13087.039426] LNet: Removed LNI 192.168.203.52@tcp [13087.259467] Key type .llcrypt unregistered [13087.260468] Key type ._llcrypt unregistered [13092.163118] Key type ._llcrypt registered [13092.164029] Key type .llcrypt registered [13092.593311] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 23:27:32 (1767328052) [13093.915872] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [13094.465344] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 23:27:34 (1767328054) [13095.802332] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [13096.339661] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 23:27:36 (1767328056) [13097.614965] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [13098.095616] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 23:27:38 (1767328058) [13099.387978] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [13099.877962] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 23:27:40 (1767328060) [13101.172440] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [13101.709713] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 23:27:41 (1767328061) [13106.234359] Key type .llcrypt unregistered [13106.235588] Key type ._llcrypt unregistered [13108.444376] Key type ._llcrypt registered [13108.450915] Key type .llcrypt registered [13108.662586] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13108.667708] alg: No test for adler32 (adler32-zlib) [13109.518572] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [13109.600479] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [13111.192149] Key type lgssc registered [13111.607487] Lustre: Echo OBD driver; http://www.lustre.org/ [13158.589686] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [13159.685071] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [13167.541747] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [13168.695698] Lustre: Mounted lustre-client [13169.139692] LustreError: 360758:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff897644075000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13169.146572] LustreError: 360758:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [13169.168600] Lustre: Unmounted lustre-client [13192.193120] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 23:29:12 (1767328152) [13204.911631] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [13206.123732] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [13213.265815] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [13223.921868] Lustre: Mounted lustre-client [13224.211859] LustreError: 363044:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89764bd81800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13224.216992] LustreError: 363044:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [13224.218659] LustreError: 363044:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [13224.237111] Lustre: Unmounted lustre-client [13243.555209] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 23:30:03 (1767328203) [13270.368361] LNet: 1 local NIs in recovery (showing 1): 192.168.203.52@tcp [13273.801658] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [13275.055731] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [13282.270760] Lustre: DEBUG MARKER: oleg352-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [13349.338637] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 23:31:49 (1767328309) [13354.174664] Key type lgssc unregistered [13354.276466] LNet: 369774:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13354.278656] LNetError: 369774:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [13354.286853] LNet: Removed LNI 192.168.203.52@tcp [13354.484204] Key type .llcrypt unregistered [13354.485177] Key type ._llcrypt unregistered [13472.699765] Key type ._llcrypt registered [13472.706636] Key type .llcrypt registered [13472.915762] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13472.944647] alg: No test for adler32 (adler32-zlib) [13473.805504] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [13473.888373] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [13475.472138] Key type lgssc registered [13475.809337] Lustre: Echo OBD driver; http://www.lustre.org/ [13517.727453] Lustre: DEBUG MARKER: conf-sanity test_73c: @@@@@@ FAIL: unable to start ost1 [13523.839636] Key type lgssc unregistered [13523.963347] LNet: 373028:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13523.965454] LNetError: 373028:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [13523.972464] LNet: Removed LNI 192.168.203.52@tcp [13524.204298] Key type .llcrypt unregistered [13524.205319] Key type ._llcrypt unregistered [13527.601514] Key type ._llcrypt registered [13527.603079] Key type .llcrypt registered [13528.044551] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 23:34:48 (1767328488) [13536.963573] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13536.969691] alg: No test for adler32 (adler32-zlib) [13537.814769] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [13537.893613] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [13539.472130] Key type lgssc registered [13539.825258] Lustre: Echo OBD driver; http://www.lustre.org/ [13579.671610] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 23:35:39 (1767328539) [13584.902894] Key type lgssc unregistered [13585.025690] LNet: 377570:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13585.027766] LNetError: 377570:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [13585.035912] LNet: Removed LNI 192.168.203.52@tcp [13585.265337] Key type .llcrypt unregistered [13585.266462] Key type ._llcrypt unregistered [13588.810796] Key type ._llcrypt registered [13588.811868] Key type .llcrypt registered [13589.047096] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13589.064805] alg: No test for adler32 (adler32-zlib) [13589.919130] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [13590.008914] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [13591.592182] Key type lgssc registered [13592.001102] Lustre: Echo OBD driver; http://www.lustre.org/ [13701.078623] LNet: Added LNI 192.168.203.52@tcp50 [8/256/0/180] [13701.888588] LNet: Removed LNI 192.168.203.52@tcp [13707.296292] LustreError: 381454:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.203.152@tcp50: failed processing log, type 1: rc = -110 [13715.424275] LustreError: 381468:0:(mgc_request.c:614:do_requeue()) failed processing log: -110 [13864.928321] LustreError: MGC192.168.203.152@tcp50: Confguration from log lustre-client failed from MGS -110. Communication error between node & MGS, a bad configuration, or other errors. See syslog for more info [13864.932338] Lustre: Unmounted lustre-client [13864.933606] LustreError: 381454:0:(obd_class.h:479:obd_check_dev()) Device 0 not setup [13864.939128] LustreError: 381454:0:(super25.c:199:lustre_fill_super()) llite: Unable to mount : rc = -110 [13865.426182] Lustre: DEBUG MARKER: conf-sanity test_73e: @@@@@@ FAIL: Mount fails on 192.168.203.152@tcp50 [13871.605267] Key type lgssc unregistered [13871.731118] LNet: 382360:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13871.733413] LNetError: 382360:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [13871.740334] LNet: Removed LNI 192.168.203.52@tcp50 [13871.912579] Key type .llcrypt unregistered [13871.913562] Key type ._llcrypt unregistered [13875.309134] Key type ._llcrypt registered [13875.311072] Key type .llcrypt registered [13875.757482] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 23:40:35 (1767328835) [13877.515453] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13877.531477] alg: No test for adler32 (adler32-zlib) [13878.372312] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [13878.447200] LNet: Added LNI 192.168.203.52@tcp [8/256/0/180] [13880.024111] Key type lgssc registered [13880.387980] Lustre: Echo OBD driver; http://www.lustre.org/ [13905.241303] Lustre: DEBUG MARKER: conf-sanity test_74: @@@@@@ FAIL: MDT start failed [13906.878761] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 23:41:07 (1767328867) [13931.098351] Lustre: DEBUG MARKER: conf-sanity test_75: @@@@@@ FAIL: add mds1 failed for default params [13976.043669] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 13444 sec ========= 23:42:16 (1767328936) [13976.532808] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 23:42:16 (1767328936) === [13977.514810] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 23:42:17 (1767328937) === [13985.496495] Key type lgssc unregistered [13985.610314] LNet: 391105:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13985.612551] LNetError: 391105:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [13985.619395] LNet: Removed LNI 192.168.203.52@tcp [13985.828382] Key type .llcrypt unregistered [13985.829435] Key type ._llcrypt unregistered