[ 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.16.2-1.fc38 04/01/2014 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 442089813 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 0x000f5b50-0x000f5b5f] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F5970 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE1BB7 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE1A53 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 001A13 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE1AC7 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE1B57 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE1B8F 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe1a53-0xbffe1ac6] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe1a52] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe1ac7-0xbffe1b56] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe1b57-0xbffe1b8e] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe1b8f-0xbffe1bb6] [ 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: 2895288K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001011] APIC: Switch to symmetric I/O mode setup [ 0.002388] x2apic enabled [ 0.003012] Switched APIC routing to physical x2apic. [ 0.004020] kvm-guest: setup PV IPIs [ 0.007787] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.008000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.008025] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009013] pid_max: default: 32768 minimum: 301 [ 0.011058] LSM: Security Framework initializing [ 0.012036] Yama: becoming mindful. [ 0.013030] SELinux: Initializing. [ 0.014042] *** VALIDATE selinux *** [ 0.021618] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.026693] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.027248] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.028172] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029118] *** VALIDATE tmpfs *** [ 0.030480] *** VALIDATE proc *** [ 0.032162] *** VALIDATE cgroup *** [ 0.033016] *** VALIDATE cgroup2 *** [ 0.035259] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.037008] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.038012] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.039024] Spectre V2 : User space: Vulnerable [ 0.040004] Speculative Store Bypass: Vulnerable [ 0.043196] debug: unmapping init [mem 0xffffffffbdc59000-0xffffffffbdc60fff] [ 0.045313] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.046557] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.047015] ... version: 2 [ 0.047959] ... bit width: 48 [ 0.048010] ... generic registers: 4 [ 0.048978] ... value mask: 0000ffffffffffff [ 0.049010] ... max period: 00007fffffffffff [ 0.050008] ... fixed-purpose events: 3 [ 0.050963] ... event mask: 000000070000000f [ 0.051251] rcu: Hierarchical SRCU implementation. [ 0.053322] smp: Bringing up secondary CPUs ... [ 0.054440] x86: Booting SMP configuration: [ 0.055014] .... node #0, CPUs: #1 #2 #3 [ 0.058764] smp: Brought up 1 node, 4 CPUs [ 0.060008] smpboot: Max logical packages: 1 [ 0.061011] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.172605] node 0 deferred pages initialised in 110ms [ 0.175220] devtmpfs: initialized [ 0.176175] x86/mm: Memory block size: 128MB [ 0.178552] gcov: version magic: 0x41383552 [ 0.180424] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.181089] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.182299] pinctrl core: initialized pinctrl subsystem [ 0.183223] [ 0.183805] ************************************************************* [ 0.184014] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.185010] ** ** [ 0.186009] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.187009] ** ** [ 0.188014] ** This means that this kernel is built to expose internal ** [ 0.189018] ** IOMMU data structures, which may compromise security on ** [ 0.190018] ** your system. ** [ 0.191031] ** ** [ 0.192016] ** If you see this message and you are not debugging the ** [ 0.193016] ** kernel, report this immediately to your vendor! ** [ 0.194014] ** ** [ 0.195020] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.196020] ************************************************************* [ 0.198182] NET: Registered protocol family 16 [ 0.199529] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.200056] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.201060] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.203083] cpuidle: using governor menu [ 0.206922] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.210508] PCI: Using configuration type 1 for base access [ 0.213179] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.224135] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.225041] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.227071] cryptd: max_cpu_qlen set to 1000 [ 0.230265] ACPI: Added _OSI(Module Device) [ 0.231017] ACPI: Added _OSI(Processor Device) [ 0.232013] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.235033] ACPI: Added _OSI(Processor Aggregator Device) [ 0.239736] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.245611] ACPI: Interpreter enabled [ 0.247071] ACPI: PM: (supports S0 S3 S4 S5) [ 0.248010] ACPI: Using IOAPIC for interrupt routing [ 0.250229] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.257119] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.267000] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.269047] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.271021] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.274107] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.278315] acpiphp: Slot [2] registered [ 0.279151] acpiphp: Slot [3] registered [ 0.281073] acpiphp: Slot [4] registered [ 0.282106] acpiphp: Slot [5] registered [ 0.283115] acpiphp: Slot [6] registered [ 0.285133] acpiphp: Slot [7] registered [ 0.286078] acpiphp: Slot [8] registered [ 0.287239] acpiphp: Slot [9] registered [ 0.289108] acpiphp: Slot [10] registered [ 0.290109] acpiphp: Slot [11] registered [ 0.292139] acpiphp: Slot [12] registered [ 0.293093] acpiphp: Slot [13] registered [ 0.294096] acpiphp: Slot [14] registered [ 0.295113] acpiphp: Slot [15] registered [ 0.297089] acpiphp: Slot [16] registered [ 0.298079] acpiphp: Slot [17] registered [ 0.299301] acpiphp: Slot [18] registered [ 0.301111] acpiphp: Slot [19] registered [ 0.302084] acpiphp: Slot [20] registered [ 0.304148] acpiphp: Slot [21] registered [ 0.308218] acpiphp: Slot [22] registered [ 0.309149] acpiphp: Slot [23] registered [ 0.310155] acpiphp: Slot [24] registered [ 0.312353] acpiphp: Slot [25] registered [ 0.313110] acpiphp: Slot [26] registered [ 0.315165] acpiphp: Slot [27] registered [ 0.316123] acpiphp: Slot [28] registered [ 0.317088] acpiphp: Slot [29] registered [ 0.318178] acpiphp: Slot [30] registered [ 0.320083] acpiphp: Slot [31] registered [ 0.321000] PCI host bridge to bus 0000:00 [ 0.321000] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.322021] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.324022] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.328038] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.330028] pci_bus 0000:00: root bus resource [mem 0x180000000-0x1ffffffff window] [ 0.333034] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.335212] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.339000] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.342404] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.349019] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.350072] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.352023] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.355021] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.357022] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.360759] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.363860] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.366054] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.369702] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.372025] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.380023] pci 0000:00:02.0: reg 0x20: [mem 0xfebf4000-0xfebf7fff 64bit pref] [ 0.384022] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.389139] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.395021] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.399018] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.417032] pci 0000:00:05.0: reg 0x20: [mem 0xfebf8000-0xfebfbfff 64bit pref] [ 0.425000] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.430026] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.437030] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.454040] pci 0000:00:06.0: reg 0x20: [mem 0xfebfc000-0xfebfffff 64bit pref] [ 0.463582] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.466873] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.469387] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.473500] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.474000] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.479128] iommu: Default domain type: Passthrough [ 0.480497] SCSI subsystem initialized [ 0.482152] ACPI: bus type USB registered [ 0.483107] usbcore: registered new interface driver usbfs [ 0.485080] usbcore: registered new interface driver hub [ 0.487102] usbcore: registered new device driver usb [ 0.488195] pps_core: LinuxPPS API ver. 1 registered [ 0.490010] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.492072] PTP clock support registered [ 0.494839] EDAC MC: Ver: 3.0.0 [ 0.497165] PCI: Using ACPI for IRQ routing [ 0.499935] NetLabel: Initializing [ 0.501009] NetLabel: domain hash size = 128 [ 0.502007] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.504092] NetLabel: unlabeled traffic allowed by default [ 0.506145] vgaarb: loaded [ 0.508135] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.509008] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.520250] clocksource: Switched to clocksource kvm-clock [ 0.642547] VFS: Disk quotas dquot_6.6.0 [ 0.644204] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.647418] *** VALIDATE ramfs *** [ 0.648689] *** VALIDATE hugetlbfs *** [ 0.650222] pnp: PnP ACPI init [ 0.653153] pnp: PnP ACPI: found 6 devices [ 0.670314] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.673153] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.674902] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.676844] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.679072] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.681065] pci_bus 0000:00: resource 8 [mem 0x180000000-0x1ffffffff window] [ 0.683377] NET: Registered protocol family 2 [ 0.685701] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.690147] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.693264] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.699368] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.702543] TCP: Hash tables configured (established 65536 bind 65536) [ 0.705632] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.708680] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.711621] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.714800] NET: Registered protocol family 1 [ 0.717343] RPC: Registered named UNIX socket transport module. [ 0.719541] RPC: Registered udp transport module. [ 0.721360] RPC: Registered tcp transport module. [ 0.723543] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.727257] NET: Registered protocol family 44 [ 0.732619] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.735404] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.737531] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.740694] PCI: CLS 0 bytes, default 64 [ 0.742944] Unpacking initramfs... [ 2.532793] debug: unmapping init [mem 0xffff9fc0fcc64000-0xffff9fc0fffcffff] [ 2.537356] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.540706] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.547040] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 3.252043] Initialise system trusted keyrings [ 3.254386] Key type blacklist registered [ 3.256783] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 3.266813] zbud: loaded [ 3.272078] *** VALIDATE nfs *** [ 3.276446] *** VALIDATE nfs4 *** [ 3.281056] pstore: using deflate compression [ 3.292984] Platform Keyring initialized [ 3.426669] NET: Registered protocol family 38 [ 3.428900] Key type asymmetric registered [ 3.430473] Asymmetric key parser 'x509' registered [ 3.433036] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 3.438099] io scheduler mq-deadline registered [ 3.440043] io scheduler kyber registered [ 3.441679] io scheduler bfq registered [ 3.443786] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 3.446589] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 3.448964] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 3.451437] ACPI: Power Button [PWRF] [ 3.573866] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.667164] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.761189] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.789600] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.819274] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.825250] Non-volatile memory driver v1.3 [ 3.826590] Linux agpgart interface v0.103 [ 3.855675] virtio_blk virtio1: [vda] 133912 512-byte logical blocks (68.6 MB/65.4 MiB) [ 3.858352] vda: detected capacity change from 0 to 68562944 [ 3.879604] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.882415] vdb: detected capacity change from 0 to 1073741824 [ 3.899499] libphy: Fixed MDIO Bus: probed [ 3.906756] usbcore: registered new interface driver usbserial_generic [ 3.909472] usbserial: USB Serial support registered for generic [ 3.914067] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.931850] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.933619] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.936961] mousedev: PS/2 mouse device common for all mice [ 3.940299] rtc_cmos 00:05: RTC can wake from S4 [ 3.946898] rtc_cmos 00:05: registered as rtc0 [ 3.949551] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.949910] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.961912] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.962630] intel_pstate: CPU model not supported [ 3.976226] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.984388] hid: raw HID events driver (C) Jiri Kosina [ 3.986364] usbcore: registered new interface driver usbhid [ 3.989519] usbhid: USB HID core driver [ 3.991790] drop_monitor: Initializing network drop monitor service [ 3.995234] Initializing XFRM netlink socket [ 3.997371] NET: Registered protocol family 10 [ 4.000828] Segment Routing with IPv6 [ 4.003035] NET: Registered protocol family 17 [ 4.005424] mpls_gso: MPLS GSO support [ 4.012396] RAS: Correctable Errors collector initialized. [ 4.015303] AVX version of gcm_enc/dec engaged. [ 4.017255] AES CTR mode by8 optimization enabled [ 4.129574] sched_clock: Marking stable (4129506107, 0)->(5022569324, -893063217) [ 4.142679] registered taskstats version 1 [ 4.147451] Loading compiled-in X.509 certificates [ 4.149580] zswap: loaded using pool lzo/zbud [ 4.197406] Key type big_key registered [ 4.230916] Key type encrypted registered [ 4.233591] ima: No TPM chip found, activating TPM-bypass! [ 4.237643] ima: Allocated hash algorithm: sha1 [ 4.240300] ima: No architecture policies found [ 4.243511] evm: Initialising EVM extended attributes: [ 4.246734] evm: security.selinux [ 4.248282] evm: security.ima [ 4.249707] evm: security.capability [ 4.251313] evm: HMAC attrs: 0x1 [ 4.255253] rtc_cmos 00:05: setting system clock to 2025-10-24 05:19:27 UTC (1761283167) [ 4.264300] debug: unmapping init [mem 0xffffffffbec03000-0xffffffffbedfffff] [ 4.267839] debug: unmapping init [mem 0xffffffffbd982000-0xffffffffbdc58fff] [ 4.276059] Write protecting the kernel read-only data: 28672k [ 4.290313] debug: unmapping init [mem 0xffffffffbc003000-0xffffffffbc1fffff] [ 4.293193] debug: unmapping init [mem 0xffffffffbc914000-0xffffffffbc9fffff] [ 4.334952] 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) [ 4.345518] systemd[1]: Detected virtualization kvm. [ 4.347765] systemd[1]: Detected architecture x86-64. [ 4.350412] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 4.393701] systemd[1]: No hostname configured. [ 4.396591] systemd[1]: Set hostname to . [ 4.399327] random: systemd: uninitialized urandom read (16 bytes read) [ 4.402375] systemd[1]: Initializing machine ID from random generator. [ 4.533027] random: ln: uninitialized urandom read (6 bytes read) [ 4.703897] random: systemd: uninitialized urandom read (16 bytes read) [ 4.707826] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ 4.717374] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ 4.724556] 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 ] Reached target Local File Systems. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Timers. [ OK ] Listening on Journal Socket. [ OK ] Reached target Sockets. Starting Create list of required st…ce nodes for the current kernel... Starting Setup Virtual Console... Starting Create Volatile Files and Directories... Starting Journal Service... [ OK ] Started Memstrack Anylazing Service. Starting Apply Kernel Variables... [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Setup Virtual Console. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Apply Kernel Variables. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 5.792182] device-mapper: uevent: version 1.0.3 [ 5.794796] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting dracut initqueue hook... [ 7.184792] virtio_net virtio0 ens2: renamed from eth0 [ 7.678432] scsi host0: ata_piix [ 7.779588] scsi host1: ata_piix [ 7.788286] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 7.792681] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 12.770022] random: crng init done [ 12.776150] random: 7 urandom warning(s) missed due to ratelimiting [ 14.368722] dracut-initqueue[594]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 15.470564] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped dracut pre-pivot and cleanup hook. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Timers. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Paths. [ OK ] Stopped target Slices. [ OK ] Stopped target Sockets. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped target Swap. [ 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 Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 17.284742] printk: systemd: 26 output lines suppressed due to ratelimiting [ 17.716984] SELinux: Disabled at runtime. [ 17.798695] 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) [ 17.818614] systemd[1]: Detected virtualization kvm. [ 17.822853] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 18.834979] systemd[1]: initrd-switch-root.service: Succeeded. [ 18.839354] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 18.849179] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 18.865240] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 18.874777] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 18.969548] systemd[1]: Starting Journal Service... Starting Journal Service... [ 18.983609] systemd[1]: Mounting POSIX Message Queue File System... Mounting POSIX Message Queue File System... [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. Starting Apply Kernel Variables... [ OK ] Listening on udev Control Socket. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Reached target rpc_pipefs.target. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. [ OK ] Stopped target Initrd Root File System. [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on initctl Compatibility Named Pipe. Mounting Huge Pages File System... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Listening on Process Core Dump Socket. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Created slice system-getty.slice. Starting Remount Root and Kernel File Systems... Activating swap /dev/disk/by-label/SWAP... Starting udev Coldplug all Devices... [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. Starting Create list of required st…ce nodes for the current kernel... [ 19.306988] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS Mounting Kernel Debug File System... [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Started Journal Service. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted Huge Pages File System. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted Kernel Debug 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 Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... [ OK ] Started udev Coldplug all Devices. [ OK ] Mounted /mnt. [ 20.080727] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 20.933547] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 21.046206] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 21.383073] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 21.555466] EDAC sbridge: Ver: 1.1.2 [ 24.316777] Key type dns_resolver registered [ 24.998922] NFS: Registering the id_resolver key type [ 25.001497] Key type id_resolver registered [ 25.003829] Key type id_legacy registered [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting Update UTMP about System Boot/Shutdown... Starting RPC Bind... [ 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 ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Started dnf makecache --timer. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Basic System. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Reached target sshd-keygen.target. Starting Restore /run/initramfs on shutdown... [ OK ] Started irqbalance daemon. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Login Service... [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... Starting OpenSSH server 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 OpenSSH server daemon. [ OK ] Started Permit User Sessions. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Command Scheduler. [ OK ] Started Getty on tty1. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Notify NFS peers of a restart... Starting System Logging Service... Starting Crash recovery kernel arming... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. [ OK ] Started Authorization Manager. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg233-client login: [ 40.840020] hrtimer: interrupt took 3009892 ns [ 60.903180] libcfs: loading out-of-tree module taints kernel. [ 60.975238] Key type ._llcrypt registered [ 60.976768] Key type .llcrypt registered [ 61.209131] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 61.215935] alg: No test for adler32 (adler32-zlib) [ 62.198192] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 62.487873] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 64.095195] Key type lgssc registered [ 64.808553] Lustre: Echo OBD driver; http://www.lustre.org/ [ 132.161908] Lustre: Mounted lustre-client [ 134.781605] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 146.633646] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing check_logdir /tmp/testlogs/ [ 148.225440] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing yml_node [ 149.932765] Lustre: DEBUG MARKER: Client: 2.16.59.47 [ 150.821368] Lustre: DEBUG MARKER: MDS: 2.16.59.47 [ 151.757235] Lustre: DEBUG MARKER: OSS: 2.16.59.47 [ 152.380557] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Fri Oct 24 01:21:55 EDT 2025 [ 157.663230] Lustre: lustre-OST0000-osc-ffff9fc1467c5000: disconnect after 24s idle [ 158.756275] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 159.311476] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 160.058365] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 170.343993] LustreError: 8980:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 170.366088] Lustre: Unmounted lustre-client [ 231.914305] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 233.168422] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 241.291406] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 262.579652] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 01:23:45 (1761283425) [ 263.623158] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 264.325579] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 01:23:46 (1761283426) [ 276.356559] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 277.769074] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 285.386226] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 295.923680] Lustre: Mounted lustre-client [ 297.794450] LustreError: 14404:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9fc16072e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 297.805475] LustreError: 14404:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 297.807996] LustreError: 14404:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 297.834148] Lustre: Unmounted lustre-client [ 316.219838] Key type lgssc unregistered [ 316.352455] LNet: 14811:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 316.356375] LNetError: 14811:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 316.369083] LNet: Removed LNI 192.168.202.33@tcp [ 316.681469] Key type .llcrypt unregistered [ 316.683185] Key type ._llcrypt unregistered [ 322.994953] Key type ._llcrypt registered [ 322.996399] Key type .llcrypt registered [ 323.557746] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 01:24:46 (1761283486) [ 324.224063] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 324.231283] alg: No test for adler32 (adler32-zlib) [ 325.147984] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 325.259841] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 326.871205] Key type lgssc registered [ 327.371399] Lustre: Echo OBD driver; http://www.lustre.org/ [ 360.948797] Lustre: Mounted lustre-client [ 442.016629] LustreError: 18153:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9fc148b58800: lov tgt 1990 not cleaned! deathrow=0, lovrc=1 [ 442.020381] LustreError: 18153:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 442.031119] Lustre: Unmounted lustre-client [ 462.191577] Key type lgssc unregistered [ 462.328611] LNet: 18617:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 462.333357] LNetError: 18617:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 462.347868] LNet: Removed LNI 192.168.202.33@tcp [ 462.682593] Key type .llcrypt unregistered [ 462.684370] Key type ._llcrypt unregistered [ 466.472865] Key type ._llcrypt registered [ 466.474703] Key type .llcrypt registered [ 467.056996] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 01:27:09 (1761283629) [ 468.080287] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 468.092381] alg: No test for adler32 (adler32-zlib) [ 468.989523] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 469.097676] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 470.711138] Key type lgssc registered [ 471.251342] Lustre: Echo OBD driver; http://www.lustre.org/ [ 489.988157] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 491.292769] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 497.419882] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 507.892534] Lustre: Mounted lustre-client [ 511.684418] LustreError: 21714:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9fc1467ef800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 511.692943] LustreError: 21714:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 511.719919] Lustre: Unmounted lustre-client [ 529.106193] Key type lgssc unregistered [ 529.236122] LNet: 22117:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 529.240275] LNetError: 22117:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 529.249722] LNet: Removed LNI 192.168.202.33@tcp [ 529.487842] Key type .llcrypt unregistered [ 529.489467] Key type ._llcrypt unregistered [ 532.907806] Key type ._llcrypt registered [ 532.909067] Key type .llcrypt registered [ 533.348493] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 01:28:16 (1761283696) [ 534.100142] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 534.117702] alg: No test for adler32 (adler32-zlib) [ 534.973125] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 535.045840] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 536.631181] Key type lgssc registered [ 537.036442] Lustre: Echo OBD driver; http://www.lustre.org/ [ 554.126889] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 555.342347] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 561.212608] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 577.005149] Lustre: Mounted lustre-client [ 579.816306] LustreError: 24805:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9fc143971800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 579.824056] LustreError: 24805:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 579.843154] Lustre: Unmounted lustre-client [ 597.573102] Key type lgssc unregistered [ 597.707662] LNet: 25208:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 597.710399] LNetError: 25208:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 597.718763] LNet: Removed LNI 192.168.202.33@tcp [ 597.939377] Key type .llcrypt unregistered [ 597.940199] Key type ._llcrypt unregistered [ 602.842699] Key type ._llcrypt registered [ 602.843977] Key type .llcrypt registered [ 603.289138] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 01:29:26 (1761283766) [ 603.948701] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 603.961589] alg: No test for adler32 (adler32-zlib) [ 604.801254] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 604.871420] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 606.463133] Key type lgssc registered [ 606.852081] Lustre: Echo OBD driver; http://www.lustre.org/ [ 623.959843] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 625.178631] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 631.280875] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 642.033306] Lustre: Mounted lustre-client [ 647.139171] Lustre: lustre-MDT0000-mdc-ffff9fc14711e800: Connection to lustre-MDT0000 (at 192.168.202.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 652.258382] Lustre: lustre-MDT0001-mdc-ffff9fc14711e800: Connection to lustre-MDT0001 (at 192.168.202.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 658.610342] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 668.511121] Lustre: 26160:0:(client.c:2468:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761283815/real 1761283815] req@00000000fe135815 x1846839888320512/t0(0) o400->MGC192.168.202.133@tcp@192.168.202.133@tcp:26/25 lens 224/224 e 0 to 1 dl 1761283831 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 668.519373] LustreError: MGC192.168.202.133@tcp: Connection to MGS (at 192.168.202.133@tcp) was lost; in progress operations using this service will fail [ 668.903754] LustreError: 28370:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 668.917100] Lustre: Unmounted lustre-client [ 679.409788] Key type lgssc unregistered [ 679.529538] LNet: 28761:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 679.532073] LNetError: 28761:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 679.539412] LNet: Removed LNI 192.168.202.33@tcp [ 679.784757] Key type .llcrypt unregistered [ 679.785828] Key type ._llcrypt unregistered [ 684.858696] Key type ._llcrypt registered [ 684.860145] Key type .llcrypt registered [ 685.326080] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 01:30:48 (1761283848) [ 686.062049] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 686.068908] alg: No test for adler32 (adler32-zlib) [ 686.932473] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 687.014815] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 688.599175] Key type lgssc registered [ 689.002664] Lustre: Echo OBD driver; http://www.lustre.org/ [ 706.035391] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 707.183591] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 712.994536] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 728.559202] Lustre: Mounted lustre-client [ 733.666965] Lustre: lustre-OST0000-osc-ffff9fc14455f000: Connection to lustre-OST0000 (at 192.168.202.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 737.484359] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 747.752190] LustreError: 31872:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 747.766342] Lustre: Unmounted lustre-client [ 764.932748] Key type lgssc unregistered [ 765.044317] LNet: 32272:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 765.047415] LNetError: 32272:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 765.057367] LNet: Removed LNI 192.168.202.33@tcp [ 765.286720] Key type .llcrypt unregistered [ 765.288415] Key type ._llcrypt unregistered [ 770.443360] Key type ._llcrypt registered [ 770.444766] Key type .llcrypt registered [ 770.913832] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 01:32:13 (1761283933) [ 771.585961] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 771.642184] alg: No test for adler32 (adler32-zlib) [ 772.495058] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 772.580442] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 774.175143] Key type lgssc registered [ 774.540332] Lustre: Echo OBD driver; http://www.lustre.org/ [ 791.221228] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 792.368216] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 798.074637] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 799.223810] Lustre: Mounted lustre-client [ 801.056219] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 802.234750] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 812.748523] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2025-10-24 1h32m55s [ 813.471714] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 814.562279] Lustre: lustre-MDT0000-mdc-ffff9fc1479e1000: Connection to lustre-MDT0000 (at 192.168.202.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 821.070151] LustreError: 35963:0:(lmv_obd.c:1435:lmv_statfs()) lustre-MDT0000-mdc-ffff9fc1479e1000: can't stat MDS #0: rc = -19 [ 821.573505] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 825.761313] LustreError: MGC192.168.202.133@tcp: Connection to MGS (at 192.168.202.133@tcp) was lost; in progress operations using this service will fail [ 825.767583] Lustre: Evicted from MGS (at 192.168.202.133@tcp) after server handle changed from 0x32014b3deee4dbb1 to 0x32014b3deee4de66 [ 825.771956] Lustre: MGC192.168.202.133@tcp: Connection restored to 192.168.202.133@tcp (at 192.168.202.133@tcp) [ 826.770487] Lustre: DEBUG MARKER: Wait for df (35963) ... [ 826.785991] LustreError: lustre-MDT0000-mdc-ffff9fc1479e1000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 826.793734] Lustre: lustre-MDT0000-mdc-ffff9fc1479e1000: Connection restored to 192.168.202.133@tcp (at 192.168.202.133@tcp) [ 827.311557] Lustre: DEBUG MARKER: done [ 827.597643] LustreError: 36571:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9fc1479e1000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 827.600131] LustreError: 36571:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 827.616252] Lustre: Unmounted lustre-client [ 842.636527] Key type lgssc unregistered [ 842.748515] LNet: 36972:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 842.751897] LNetError: 36972:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 842.760605] LNet: Removed LNI 192.168.202.33@tcp [ 842.978568] Key type .llcrypt unregistered [ 842.979362] Key type ._llcrypt unregistered [ 857.575360] Key type ._llcrypt registered [ 857.577159] Key type .llcrypt registered [ 857.784727] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 857.789836] alg: No test for adler32 (adler32-zlib) [ 858.643942] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 858.727804] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 860.311149] Key type lgssc registered [ 860.690024] Lustre: Echo OBD driver; http://www.lustre.org/ [ 889.949385] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 891.115912] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 898.256170] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 928.143555] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 01:34:50 (1761284090) [ 938.394050] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 939.649199] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 946.102446] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 956.913156] Lustre: Mounted lustre-client [ 959.378603] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2025-10-24 1h35m22s [ 959.946745] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 962.349121] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 963.227428] Lustre: DEBUG MARKER: Creating a test file and stat it [ 983.519116] Lustre: 43820:0:(client.c:2468:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761284126/real 1761284126] req@00000000b5d213c4 x1846840153609728/t0(0) o101->lustre-MDT0000-mdc-ffff9fc1457ee000@192.168.202.133@tcp:12/10 lens 576/1152 e 0 to 1 dl 1761284146 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:0 [ 983.526416] Lustre: lustre-MDT0000-mdc-ffff9fc1457ee000: Connection to lustre-MDT0000 (at 192.168.202.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 983.537322] Lustre: lustre-MDT0000-mdc-ffff9fc1457ee000: Connection restored to 192.168.202.133@tcp (at 192.168.202.133@tcp) [ 1003.848262] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 1004.985395] Lustre: DEBUG MARKER: done [ 1005.956995] LustreError: 44301:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9fc1457ee000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1005.964688] LustreError: 44301:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1005.986266] Lustre: Unmounted lustre-client [ 1023.506811] Key type lgssc unregistered [ 1023.631819] LNet: 44703:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1023.635386] LNetError: 44703:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1023.645780] LNet: Removed LNI 192.168.202.33@tcp [ 1023.898269] Key type .llcrypt unregistered [ 1023.899340] Key type ._llcrypt unregistered [ 1038.720854] Key type ._llcrypt registered [ 1038.722425] Key type .llcrypt registered [ 1038.957075] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1038.963056] alg: No test for adler32 (adler32-zlib) [ 1039.821112] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 1039.909223] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 1041.503209] Key type lgssc registered [ 1041.900074] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1069.967215] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1071.119846] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1078.284618] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1100.712902] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 01:37:43 (1761284263) [ 1101.250025] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 1101.852280] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 01:37:44 (1761284264) [ 1110.519208] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 01:37:53 (1761284273) [ 1120.785649] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1121.985515] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1128.099237] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1138.674922] Lustre: Mounted lustre-client [ 1140.826593] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 1142.740503] LustreError: 52516:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9fc1467c5800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1142.765833] LustreError: 52516:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1142.841295] Lustre: Unmounted lustre-client [ 1151.835952] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 1165.762967] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1166.977467] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1172.467580] Lustre: Mounted lustre-client [ 1173.371840] LustreError: 53770:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9fc145def000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1173.381251] LustreError: 53770:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1173.383547] LustreError: 53770:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 1173.408451] Lustre: Unmounted lustre-client [ 1200.116967] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1201.361315] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1201.551136] Lustre: Mounted lustre-client [ 1202.414411] LustreError: 54885:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9fc148b5c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1202.425233] LustreError: 54885:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1202.427710] LustreError: 54885:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 1202.456827] Lustre: Unmounted lustre-client [ 1214.076142] Lustre: DEBUG MARKER: files compared the same [ 1228.645463] Key type lgssc unregistered [ 1228.767386] LNet: 55590:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1228.770147] LNetError: 55590:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1228.776777] LNet: Removed LNI 192.168.202.33@tcp [ 1229.029338] Key type .llcrypt unregistered [ 1229.030390] Key type ._llcrypt unregistered [ 1234.068457] Key type ._llcrypt registered [ 1234.069643] Key type .llcrypt registered [ 1234.543216] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 01:39:57 (1761284397) [ 1235.352492] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1235.362951] alg: No test for adler32 (adler32-zlib) [ 1236.239099] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 1236.338846] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 1237.935173] Key type lgssc registered [ 1238.372793] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1257.005330] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1258.310984] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1264.539112] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1274.864646] Lustre: Mounted lustre-client [ 1276.558241] LustreError: 58672:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9fc143977000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1276.566781] LustreError: 58672:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1276.592118] Lustre: Unmounted lustre-client [ 1286.127931] Key type lgssc unregistered [ 1286.244492] LNet: 59073:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1286.246206] LNetError: 59073:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1286.253645] LNet: Removed LNI 192.168.202.33@tcp [ 1286.496761] Key type .llcrypt unregistered [ 1286.497943] Key type ._llcrypt unregistered [ 1291.405313] Key type ._llcrypt registered [ 1291.406173] Key type .llcrypt registered [ 1291.863822] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 01:40:54 (1761284454) [ 1292.639654] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1292.646946] alg: No test for adler32 (adler32-zlib) [ 1293.517559] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 1293.608271] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 1295.191180] Key type lgssc registered [ 1295.627433] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1358.215736] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1383.820540] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1387.483857] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1417.184554] Key type lgssc unregistered [ 1417.505687] LNet: 62460:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1417.515683] LNetError: 62460:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1417.543672] LNet: Removed LNI 192.168.202.33@tcp [ 1418.350530] Key type .llcrypt unregistered [ 1418.353440] Key type ._llcrypt unregistered [ 1431.347509] Key type ._llcrypt registered [ 1431.348983] Key type .llcrypt registered [ 1432.362821] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 01:43:14 (1761284594) [ 1434.503510] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1434.518564] alg: No test for adler32 (adler32-zlib) [ 1435.496379] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 1435.645943] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 1437.287382] Key type lgssc registered [ 1438.002080] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1480.590555] Lustre: Mounted lustre-client [ 1486.492357] LustreError: 64941:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9fc143977800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1486.506850] LustreError: 64941:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1486.533366] Lustre: Unmounted lustre-client [ 1514.643443] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 01:44:36 (1761284676) [ 1618.007799] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1642.594797] Lustre: Mounted lustre-client [ 1649.282710] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1649.288572] LustreError: 68900:0:(file.c:249:ll_close_inode_openhandle()) lustre-clilmv-ffff9fc148b5a800: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 1649.306746] Lustre: 63440:0:(llite_lib.c:4238:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.202.133@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 1659.592136] LustreError: 68900:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 1659.607661] LustreError: 68900:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 1659.664776] Lustre: Unmounted lustre-client [ 1705.140161] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 01:47:46 (1761284866) [ 1717.182368] Key type lgssc unregistered [ 1717.520655] LNet: 70121:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1717.536185] LNetError: 70121:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1717.580341] LNet: Removed LNI 192.168.202.33@tcp [ 1718.496505] Key type .llcrypt unregistered [ 1718.500432] Key type ._llcrypt unregistered [ 1730.388670] Key type ._llcrypt registered [ 1730.394695] Key type .llcrypt registered [ 1730.738757] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1730.754426] alg: No test for adler32 (adler32-zlib) [ 1731.881291] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 1732.164834] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 1733.919486] Key type lgssc registered [ 1735.408327] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1785.140583] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1789.543107] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1806.680565] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1808.509628] Lustre: Mounted lustre-client [ 1812.146239] LustreError: 72641:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9fc1467eb000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1812.172792] LustreError: 72641:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 1812.235645] Lustre: Unmounted lustre-client [ 1839.158825] Key type lgssc unregistered [ 1839.462940] LNet: 73044:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1839.470726] LNetError: 73044:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1839.496394] LNet: Removed LNI 192.168.202.33@tcp [ 1840.316668] Key type .llcrypt unregistered [ 1840.329677] Key type ._llcrypt unregistered [ 1850.712947] Key type ._llcrypt registered [ 1850.733478] Key type .llcrypt registered [ 1851.344972] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1851.371196] alg: No test for adler32 (adler32-zlib) [ 1852.481388] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 1852.793720] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 1854.511426] Key type lgssc registered [ 1855.942577] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1963.864800] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1967.943254] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1989.464982] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1991.574343] Lustre: Mounted lustre-client [ 1994.491886] LustreError: 75985:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9fc14447c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1994.541141] LustreError: 75985:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 1994.706259] Lustre: Unmounted lustre-client [ 2031.396904] Key type lgssc unregistered [ 2031.751325] LNet: 76390:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2031.767635] LNetError: 76390:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2032.807569] LNet: Removed LNI 192.168.202.33@tcp [ 2033.681303] Key type .llcrypt unregistered [ 2033.700630] Key type ._llcrypt unregistered [ 2050.649496] Key type ._llcrypt registered [ 2050.650933] Key type .llcrypt registered [ 2052.294850] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 01:53:33 (1761285213) [ 2054.718522] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2054.899084] alg: No test for adler32 (adler32-zlib) [ 2056.118906] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 2056.496632] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 2058.232183] Key type lgssc registered [ 2059.982136] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2108.289657] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2112.142395] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2130.943194] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2132.551684] Lustre: Mounted lustre-client [ 2137.702031] LustreError: 79515:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9fc1457ec800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2137.737380] LustreError: 79515:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 2137.877525] Lustre: Unmounted lustre-client [ 2138.586092] Lustre: Mounted lustre-client [ 2141.114221] LustreError: 79595:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9fc143977000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2141.131930] LustreError: 79595:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 2141.136882] LustreError: 79595:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 2141.204098] Lustre: Unmounted lustre-client [ 2169.224962] Key type lgssc unregistered [ 2169.562880] LNet: 79999:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2169.575684] LNetError: 79999:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2169.611795] LNet: Removed LNI 192.168.202.33@tcp [ 2170.324119] Key type .llcrypt unregistered [ 2170.326658] Key type ._llcrypt unregistered [ 2182.773356] Key type ._llcrypt registered [ 2182.783283] Key type .llcrypt registered [ 2183.413514] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2183.459807] alg: No test for adler32 (adler32-zlib) [ 2184.692320] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 2185.104852] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 2186.879245] Key type lgssc registered [ 2188.455246] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2240.560518] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2244.665610] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2267.319451] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2269.285612] Lustre: Mounted lustre-client [ 2273.484549] LustreError: 82541:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9fc145ded000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2273.536931] LustreError: 82541:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 2273.643780] Lustre: Unmounted lustre-client [ 2303.883826] Key type lgssc unregistered [ 2304.229834] LNet: 82945:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2304.244801] LNetError: 82945:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2304.266662] LNet: Removed LNI 192.168.202.33@tcp [ 2305.115739] Key type .llcrypt unregistered [ 2305.132420] Key type ._llcrypt unregistered [ 2322.537489] Key type ._llcrypt registered [ 2322.568388] Key type .llcrypt registered [ 2323.945914] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 01:58:05 (1761285485) [ 2327.055036] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2327.146461] alg: No test for adler32 (adler32-zlib) [ 2328.295547] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 2328.623885] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 2330.319234] Key type lgssc registered [ 2331.872358] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2380.545885] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2384.369659] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2403.250900] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2405.957647] Lustre: Mounted lustre-client [ 2411.858619] Lustre: lustre: root_squash is set to 0:0 [ 2421.284671] Lustre: lustre: nosquash_nids is cleared [ 2421.317642] Lustre: lustre: root_squash is set to 500:500 [ 2431.043325] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.202.33@tcp 192.168.0.[2,10]@tcp [ 2440.362732] Lustre: lustre: nosquash_nids set to 192.168.202.33/24@tcp [ 2442.944190] LustreError: 86376:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9fc143671000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2442.972316] LustreError: 86376:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 2443.105553] Lustre: Unmounted lustre-client [ 2468.176785] Key type lgssc unregistered [ 2468.522429] LNet: 86777:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2468.540751] LNetError: 86777:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2468.558767] LNet: Removed LNI 192.168.202.33@tcp [ 2469.453477] Key type .llcrypt unregistered [ 2469.455096] Key type ._llcrypt unregistered [ 2486.026583] Key type ._llcrypt registered [ 2486.028105] Key type .llcrypt registered [ 2487.644449] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 02:00:49 (1761285649) [ 2490.537829] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 2492.429607] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 02:00:54 (1761285654) [ 2495.172719] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2495.236294] alg: No test for adler32 (adler32-zlib) [ 2496.328698] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 2496.570433] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 2498.223278] Key type lgssc registered [ 2499.695188] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2550.347941] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2554.618522] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2575.926916] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2576.492247] Lustre: lustre: root_squash is set to 500:500 [ 2576.551996] Lustre: lustre: nosquash_nids set to 192.168.202.33/24@tcp [ 2577.645723] Lustre: Mounted lustre-client [ 2583.274931] LustreError: 90260:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9fc148f94800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2583.320876] LustreError: 90260:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 2583.370410] Lustre: Unmounted lustre-client [ 2611.626647] Key type lgssc unregistered [ 2611.963508] LNet: 90662:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2611.980790] LNetError: 90662:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2613.032763] LNet: Removed LNI 192.168.202.33@tcp [ 2613.850971] Key type .llcrypt unregistered [ 2613.863116] Key type ._llcrypt unregistered [ 2629.541561] Key type ._llcrypt registered [ 2629.544670] Key type .llcrypt registered [ 2631.000164] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 2632.926079] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 02:03:14 (1761285794) [ 2651.530567] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2651.559481] alg: No test for adler32 (adler32-zlib) [ 2652.653908] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 2652.927424] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 2654.615239] Key type lgssc registered [ 2656.058711] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2740.247447] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2743.988308] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2763.667584] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2830.887866] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2835.426174] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2856.372037] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2868.099587] Lustre: Mounted lustre-client [ 2894.979547] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9fc152d1f800.ost_server_uuid 50 [ 2896.557362] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9fc152d1f800.ost_server_uuid in FULL state after 0 sec [ 2896.970996] Lustre: Mounted lustre-client [ 2899.662626] LustreError: 97564:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9fc158120800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2899.686854] LustreError: 97564:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 2899.777182] Lustre: Unmounted lustre-client [ 2900.666657] LustreError: 97583:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9fc152d1f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2900.673375] LustreError: 97583:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 2900.687691] LustreError: 97583:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 2900.690000] LustreError: 97583:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 2900.744030] Lustre: Unmounted lustre-client [ 2944.088596] Key type lgssc unregistered [ 2944.336548] LNet: 98138:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2944.347616] LNetError: 98138:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2944.363158] LNet: Removed LNI 192.168.202.33@tcp [ 2944.939747] Key type .llcrypt unregistered [ 2944.943414] Key type ._llcrypt unregistered [ 2981.942875] Key type ._llcrypt registered [ 2981.945189] Key type .llcrypt registered [ 2982.569296] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2982.599445] alg: No test for adler32 (adler32-zlib) [ 2983.734126] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 2984.042066] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 2985.735161] Key type lgssc registered [ 2986.825498] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3049.225247] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3051.929419] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3067.655049] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3102.074549] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 02:11:04 (1761286264) [ 3174.653046] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3177.740711] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3195.455406] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3197.165801] Lustre: Mounted lustre-client [ 3217.905217] Lustre: 98924:0:(client.c:2468:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761286365/real 1761286365] req@00000000ea667feb x1846842382881920/t0(0) o400->lustre-OST0000-osc-ffff9fc1457e8000@192.168.202.133@tcp:28/4 lens 224/224 e 0 to 1 dl 1761286381 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3217.952870] Lustre: lustre-OST0000-osc-ffff9fc1457e8000: Connection to lustre-OST0000 (at 192.168.202.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3223.011696] Lustre: 98925:0:(client.c:2468:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761286370/real 1761286370] req@000000007dd5b157 x1846842382882432/t0(0) o400->lustre-OST0000-osc-ffff9fc1457e8000@192.168.202.133@tcp:28/4 lens 224/224 e 0 to 1 dl 1761286386 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3228.136709] Lustre: lustre-MDT0000-mdc-ffff9fc1457e8000: Connection to lustre-MDT0000 (at 192.168.202.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3228.185348] Lustre: lustre-OST0000-osc-ffff9fc1457e8000: Connection restored to 192.168.202.133@tcp (at 192.168.202.133@tcp) [ 3229.151241] Lustre: 98923:0:(client.c:2468:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761286376/real 1761286376] req@0000000040f4041e x1846842382882944/t0(0) o400->lustre-OST0000-osc-ffff9fc1457e8000@192.168.202.133@tcp:28/4 lens 224/224 e 0 to 1 dl 1761286392 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3234.335222] Lustre: 98923:0:(client.c:2468:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761286381/real 1761286381] req@000000001aa4ebee x1846842382883456/t0(0) o400->lustre-OST0000-osc-ffff9fc1457e8000@192.168.202.133@tcp:28/4 lens 224/224 e 0 to 1 dl 1761286397 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3244.511172] Lustre: 98923:0:(client.c:2468:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761286391/real 1761286391] req@000000006d557727 x1846842382884224/t0(0) o400->MGC192.168.202.133@tcp@192.168.202.133@tcp:26/25 lens 224/224 e 0 to 1 dl 1761286407 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3244.527332] LustreError: MGC192.168.202.133@tcp: Connection to MGS (at 192.168.202.133@tcp) was lost; in progress operations using this service will fail [ 3244.539706] Lustre: 106059:0:(mgc_request.c:1910:mgc_process_log()) MGC192.168.202.133@tcp: IR log lustre-cliir failed, not fatal: rc = -5 [ 3250.186149] Lustre: lustre-MDT0000-mdc-ffff9fc1457e8000: Connection restored to 192.168.202.133@tcp (at 192.168.202.133@tcp) [ 3252.572161] LustreError: 106783:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9fc1457e8000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3252.589392] LustreError: 106783:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3252.664165] Lustre: Unmounted lustre-client [ 3254.698854] Lustre: Evicted from MGS (at 192.168.202.133@tcp) after server handle changed from 0xd51dabaaeaa66a9a to 0xd51dabaaeaa66ead [ 3254.713400] Lustre: MGC192.168.202.133@tcp: Connection restored to 192.168.202.133@tcp (at 192.168.202.133@tcp) [ 3254.734162] LustreError: 106783:0:(obd_class.h:479:obd_check_dev()) Device 0 not setup [ 3254.737818] LustreError: 106783:0:(obd_class.h:479:obd_check_dev()) Skipped 5 previous similar messages [ 3287.414877] Key type lgssc unregistered [ 3287.716594] LNet: 107187:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3287.731748] LNetError: 107187:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3287.771394] LNet: Removed LNI 192.168.202.33@tcp [ 3288.465451] Key type .llcrypt unregistered [ 3288.469711] Key type ._llcrypt unregistered [ 3302.495386] Key type ._llcrypt registered [ 3302.497690] Key type .llcrypt registered [ 3303.763936] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 02:14:25 (1761286465) [ 3317.683406] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3317.852220] alg: No test for adler32 (adler32-zlib) [ 3319.018326] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 3319.259412] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 3320.903149] Key type lgssc registered [ 3321.883512] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3352.307887] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3427.827970] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3430.677439] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3446.626281] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3448.251748] Lustre: Mounted lustre-client [ 3449.757748] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 3450.984903] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 02:16:53 (1761286613) [ 3651.349581] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3661.622510] LustreError: 117948:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3661.649614] Lustre: Unmounted lustre-client [ 3774.355857] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3777.792721] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3794.394682] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3838.506767] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 02:23:20 (1761287000) [ 3914.384576] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3916.984688] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3931.479102] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3932.043760] Lustre: Mounted lustre-client [ 3934.905430] LustreError: 125363:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9fc144328800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3934.931642] LustreError: 125363:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3934.942764] LustreError: 125363:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 3935.019242] Lustre: Unmounted lustre-client [ 3966.299549] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 02:25:28 (1761287128) [ 4033.704237] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4036.863760] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4055.889259] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4056.482674] Lustre: Mounted lustre-client [ 4060.224116] LustreError: 129408:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9fc1457e8000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4060.250269] LustreError: 129408:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4060.252994] LustreError: 129408:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 4060.332056] Lustre: Unmounted lustre-client [ 4090.304648] Key type lgssc unregistered [ 4090.528224] LNet: 129809:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4090.532473] LNetError: 129809:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4090.554901] LNet: Removed LNI 192.168.202.33@tcp [ 4091.140801] Key type .llcrypt unregistered [ 4091.148799] Key type ._llcrypt unregistered [ 4104.492689] Key type ._llcrypt registered [ 4104.493759] Key type .llcrypt registered [ 4105.668376] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 02:27:47 (1761287267) [ 4107.779919] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4107.806917] alg: No test for adler32 (adler32-zlib) [ 4108.832812] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 4109.054773] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 4110.705744] Key type lgssc registered [ 4111.866122] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4157.030807] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4160.545237] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4176.029243] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4183.091554] Lustre: Mounted lustre-client [ 4195.336456] LustreError: 132914:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9fc144403000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4195.356690] LustreError: 132914:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4195.425065] Lustre: Unmounted lustre-client [ 4230.246914] Key type lgssc unregistered [ 4230.442801] LNet: 133317:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4230.446408] LNetError: 133317:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4230.465366] LNet: Removed LNI 192.168.202.33@tcp [ 4231.108334] Key type .llcrypt unregistered [ 4231.113604] Key type ._llcrypt unregistered [ 4246.826620] Key type ._llcrypt registered [ 4246.828362] Key type .llcrypt registered [ 4247.974243] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 02:30:09 (1761287409) [ 4250.217018] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4250.230767] alg: No test for adler32 (adler32-zlib) [ 4251.281488] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 4251.450912] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 4253.111317] Key type lgssc registered [ 4254.358848] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4299.224862] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4303.404953] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4320.345459] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4323.099752] Lustre: Mounted lustre-client [ 4328.420560] Lustre: lustre-OST0000-osc-ffff9fc1457e9800: Connection to lustre-OST0000 (at 192.168.202.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4337.453772] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff9fc1457e9800.ost_server_uuid 50 [ 4338.790798] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 4349.018800] LustreError: 136852:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9fc1457e9800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4349.025633] LustreError: 136852:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4349.064463] Lustre: Unmounted lustre-client [ 4368.407711] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 02:32:10 (1761287530) [ 4398.348282] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4401.022137] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4415.856886] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4433.207710] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 4433.754165] Lustre: Mounted lustre-client [ 4439.034319] Lustre: lustre-OST0000-osc-ffff9fc160668800: Connection to lustre-OST0000 (at 192.168.202.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4460.212081] LustreError: 139506:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9fc160668800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4460.233198] LustreError: 139506:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 4460.239688] LustreError: 139506:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 4460.366515] Lustre: Unmounted lustre-client [ 4503.285930] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 02:34:25 (1761287665) [ 4530.259490] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4534.416526] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4555.718394] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4575.848001] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 4576.542826] Lustre: Mounted lustre-client [ 4581.866689] Lustre: lustre-OST0000-osc-ffff9fc16066b800: Connection to lustre-OST0000 (at 192.168.202.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4597.259556] LustreError: 142266:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9fc16066b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4597.263199] LustreError: 142266:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 4597.281553] LustreError: 142266:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 4597.287752] LustreError: 142266:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 4597.353741] Lustre: Unmounted lustre-client [ 4645.188489] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 02:36:46 (1761287806) [ 4739.960682] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4744.017822] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4764.935243] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4834.977833] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4838.704144] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4856.817968] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4891.507369] Lustre: Mounted lustre-client [ 4929.034590] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4938.206510] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 7 sec [ 4939.604576] LustreError: 148928:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9fc1467ef800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4939.618497] LustreError: 148928:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 4939.629930] LustreError: 148928:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4939.635255] LustreError: 148928:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 4939.706067] Lustre: Unmounted lustre-client [ 4968.078128] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 02:42:10 (1761288130) [ 4994.236135] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4997.292925] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5012.803834] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5041.139449] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 5060.254334] Lustre: Mounted lustre-client [ 5094.133793] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 5095.024041] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 5096.425941] Lustre: lustre-OST0001-osc-ffff9fc151228800: Connection to lustre-OST0001 (at 192.168.202.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5112.307467] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5122.608240] LustreError: 152520:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 5122.613893] LustreError: 152520:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 5122.643205] Lustre: Unmounted lustre-client [ 5158.195366] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 02:45:20 (1761288320) [ 5171.727558] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5173.521649] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5182.715632] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5182.973945] Lustre: Mounted lustre-client [ 5192.929419] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 5193.647876] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 5200.948365] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9fc1467e9800.ost_server_uuid 50 [ 5201.801547] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9fc1467e9800.ost_server_uuid in FULL state after 0 sec [ 5202.945365] LustreError: 155935:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9fc1467e9800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5202.958673] LustreError: 155935:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 5202.962732] LustreError: 155935:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 5203.011208] Lustre: Unmounted lustre-client [ 5203.236818] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 5203.238972] Lustre: Skipped 3 previous similar messages [ 5203.303346] Lustre: Mounted lustre-client [ 5203.955141] LustreError: 155998:0:(sec.c:410:import_sec_validate_get()) import 000000002acf412f (NEW) with no sec [ 5266.887758] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 02:47:08 (1761288428) [ 5294.570197] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5297.689030] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5312.189704] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5327.942674] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 5328.274569] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 5333.553447] Lustre: Mounted lustre-client [ 5340.077609] LustreError: 158820:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9fc145d0b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5340.103409] LustreError: 158820:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 5340.127707] LustreError: 158820:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 5340.130079] LustreError: 158820:0:(obd_class.h:479:obd_check_dev()) Skipped 18 previous similar messages [ 5340.182365] Lustre: Unmounted lustre-client [ 5340.184038] Lustre: Skipped 1 previous similar message [ 5371.094561] Key type lgssc unregistered [ 5371.376705] LNet: 159265:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5371.382848] LNetError: 159265:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5371.403443] LNet: Removed LNI 192.168.202.33@tcp [ 5372.086799] Key type .llcrypt unregistered [ 5372.088431] Key type ._llcrypt unregistered [ 5386.108591] Key type ._llcrypt registered [ 5386.110245] Key type .llcrypt registered [ 5387.338489] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 02:49:09 (1761288549) [ 5388.375211] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5388.447286] alg: No test for adler32 (adler32-zlib) [ 5389.532234] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 5389.735855] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 5391.423455] Key type lgssc registered [ 5392.681579] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5434.761935] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5437.419417] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5451.131972] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5465.986331] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 5467.461473] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 5468.686442] Lustre: Mounted lustre-client [ 5473.241856] LustreError: lustre-MDT0001-mdc-ffff9fc144405000: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 5473.265109] Lustre: lustre-MDT0001-mdc-ffff9fc144405000: Connection restored to 192.168.202.133@tcp (at 192.168.202.133@tcp) [ 5476.585295] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5478.433899] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 5498.909183] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 5502.924851] LustreError: 163487:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9fc144405000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5502.952263] LustreError: 163487:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 5502.995298] Lustre: Unmounted lustre-client [ 5523.761429] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 02:51:25 (1761288685) [ 5594.548881] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5596.879271] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5609.322434] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5609.664151] Lustre: Mounted lustre-client [ 5622.613038] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 5623.403975] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 5632.997688] Lustre: lustre-OST0001-osc-ffff9fc148b5a800: Connection to lustre-OST0001 (at 192.168.202.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5639.496736] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5649.775511] LustreError: 168214:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 5649.780451] LustreError: 168214:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 5649.802375] Lustre: Unmounted lustre-client [ 5675.635404] Key type lgssc unregistered [ 5675.775603] LNet: 168618:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5675.779289] LNetError: 168618:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5675.788404] LNet: Removed LNI 192.168.202.33@tcp [ 5676.117922] Key type .llcrypt unregistered [ 5676.119583] Key type ._llcrypt unregistered [ 5696.692742] Key type ._llcrypt registered [ 5696.693904] Key type .llcrypt registered [ 5696.992249] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5697.000820] alg: No test for adler32 (adler32-zlib) [ 5697.935582] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 5698.051177] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 5699.663240] Key type lgssc registered [ 5700.253393] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5744.895440] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5746.541613] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5756.415063] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5782.369555] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 02:55:44 (1761288944) [ 5795.700810] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5797.553498] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5806.399074] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5811.725333] Lustre: Mounted lustre-client [ 5850.460378] LustreError: 174685:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9fc1467eb000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5850.472757] LustreError: 174685:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5850.502582] Lustre: Unmounted lustre-client [ 5870.584337] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5870.856479] Lustre: Mounted lustre-client [ 5903.782950] LustreError: 175340:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9fc15821f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5903.797563] LustreError: 175340:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5903.801068] LustreError: 175340:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 5903.838050] Lustre: Unmounted lustre-client [ 5924.529426] Key type lgssc unregistered [ 5924.693106] LNet: 175747:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5924.699655] LNetError: 175747:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5924.710824] LNet: Removed LNI 192.168.202.33@tcp [ 5925.105472] Key type .llcrypt unregistered [ 5925.107097] Key type ._llcrypt unregistered [ 5932.821357] Key type ._llcrypt registered [ 5932.822724] Key type .llcrypt registered [ 5933.512126] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 02:58:16 (1761289096) [ 5934.518759] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5934.560262] alg: No test for adler32 (adler32-zlib) [ 5935.497324] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 5935.617770] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 5937.231266] Key type lgssc registered [ 5937.943103] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5965.087554] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5967.281028] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5976.832809] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5979.138792] Lustre: Mounted lustre-client [ 5997.642119] LustreError: 178999:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9fc148b5e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5997.659545] LustreError: 178999:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5997.701545] Lustre: Unmounted lustre-client [ 6018.410141] Key type lgssc unregistered [ 6018.550225] LNet: 179401:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6018.558019] LNetError: 179401:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6018.568563] LNet: Removed LNI 192.168.202.33@tcp [ 6018.949643] Key type .llcrypt unregistered [ 6018.951277] Key type ._llcrypt unregistered [ 6024.435145] Key type ._llcrypt registered [ 6024.436595] Key type .llcrypt registered [ 6024.643942] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6024.651818] alg: No test for adler32 (adler32-zlib) [ 6025.652962] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 6025.812335] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 6027.441300] Key type lgssc registered [ 6028.299654] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6053.299540] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6055.082696] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6063.981122] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6069.756335] Lustre: Mounted lustre-client [ 6073.073300] LustreError: 181967:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9fc145338000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6073.084461] LustreError: 181967:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6073.111875] Lustre: Unmounted lustre-client [ 6091.803107] Key type lgssc unregistered [ 6091.942195] LNet: 182367:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6091.945730] LNetError: 182367:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6091.960615] LNet: Removed LNI 192.168.202.33@tcp [ 6092.258997] Key type .llcrypt unregistered [ 6092.260449] Key type ._llcrypt unregistered [ 6098.951375] Key type ._llcrypt registered [ 6098.952349] Key type .llcrypt registered [ 6099.579447] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 03:01:02 (1761289262) [ 6100.660184] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6100.669664] alg: No test for adler32 (adler32-zlib) [ 6101.549357] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 6101.665532] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 6103.271236] Key type lgssc registered [ 6103.803316] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6126.802728] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6128.396403] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6136.451549] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6141.947974] Lustre: Mounted lustre-client [ 6150.980696] LustreError: 185629:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9fc143974000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6150.994684] LustreError: 185629:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6151.024925] Lustre: Unmounted lustre-client [ 6170.492517] Key type lgssc unregistered [ 6170.632742] LNet: 186031:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6170.636507] LNetError: 186031:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6170.646172] LNet: Removed LNI 192.168.202.33@tcp [ 6170.919384] Key type .llcrypt unregistered [ 6170.920683] Key type ._llcrypt unregistered [ 6176.433763] Key type ._llcrypt registered [ 6176.435366] Key type .llcrypt registered [ 6176.687158] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6176.694126] alg: No test for adler32 (adler32-zlib) [ 6177.616857] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 6177.746970] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 6179.383243] Key type lgssc registered [ 6180.031941] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6204.656261] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6206.270416] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6215.009673] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6221.799780] Lustre: Mounted lustre-client [ 6228.879260] LustreError: 188599:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9fc148b5e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6228.889581] LustreError: 188599:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6228.922858] Lustre: Unmounted lustre-client [ 6252.072636] Key type lgssc unregistered [ 6252.221080] LNet: 189001:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6252.225092] LNetError: 189001:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6252.236740] LNet: Removed LNI 192.168.202.33@tcp [ 6252.533420] Key type .llcrypt unregistered [ 6252.535661] Key type ._llcrypt unregistered [ 6259.150569] Key type ._llcrypt registered [ 6259.151874] Key type .llcrypt registered [ 6259.841373] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 03:03:42 (1761289422) [ 6269.436809] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6269.495543] alg: No test for adler32 (adler32-zlib) [ 6270.408659] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 6270.547916] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 6272.175448] Key type lgssc registered [ 6272.762415] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6310.709934] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6312.379824] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6321.617107] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6346.456861] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 03:05:09 (1761289509) [ 6359.910428] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6361.512148] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6369.163474] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6379.523487] Lustre: Mounted lustre-client [ 6381.690237] LustreError: 195502:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9fc1607ed800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6381.704170] LustreError: 195502:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6381.732217] Lustre: Unmounted lustre-client [ 6400.211928] Key type lgssc unregistered [ 6400.349534] LNet: 195903:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6400.352726] LNetError: 195903:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6400.361068] LNet: Removed LNI 192.168.202.33@tcp [ 6400.641616] Key type .llcrypt unregistered [ 6400.643311] Key type ._llcrypt unregistered [ 6406.974200] Key type ._llcrypt registered [ 6406.975878] Key type .llcrypt registered [ 6407.582370] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 6408.275086] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 03:06:10 (1761289570) [ 6415.008681] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6415.033809] alg: No test for adler32 (adler32-zlib) [ 6415.897397] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 6415.986668] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 6417.583189] Key type lgssc registered [ 6418.038048] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6456.019143] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6457.579703] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6466.459044] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid [ 6473.794577] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid [ 6475.012702] Lustre: Mounted lustre-client [ 6482.495120] LustreError: 201435:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9fc143671000: lov tgt 1000 not cleaned! deathrow=0, lovrc=1 [ 6482.504402] LustreError: 201435:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6482.538224] Lustre: Unmounted lustre-client [ 6551.330412] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6552.850736] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6561.113853] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6601.851503] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 03:09:23 (1761289763) [ 6603.536930] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 6605.720953] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 03:09:27 (1761289767) [ 6651.779397] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6654.312416] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6675.475114] Key type lgssc unregistered [ 6675.666908] LNet: 207605:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6675.674073] LNetError: 207605:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6675.687536] LNet: Removed LNI 192.168.202.33@tcp [ 6676.287733] Key type .llcrypt unregistered [ 6676.293249] Key type ._llcrypt unregistered [ 6684.859501] Key type ._llcrypt registered [ 6684.860627] Key type .llcrypt registered [ 6685.813452] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 03:10:48 (1761289848) [ 6706.102816] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6706.142269] alg: No test for adler32 (adler32-zlib) [ 6707.150199] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 6707.322561] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 6708.951180] Key type lgssc registered [ 6709.872672] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6733.316568] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6735.086970] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6744.783381] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6766.992822] Key type lgssc unregistered [ 6767.168313] LNet: 211230:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6767.173952] LNetError: 211230:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6767.188952] LNet: Removed LNI 192.168.202.33@tcp [ 6767.792707] Key type .llcrypt unregistered [ 6767.794449] Key type ._llcrypt unregistered [ 6777.106981] Key type ._llcrypt registered [ 6777.108481] Key type .llcrypt registered [ 6778.058427] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 03:12:20 (1761289940) [ 6780.030389] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6780.080626] alg: No test for adler32 (adler32-zlib) [ 6781.106813] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 6781.254537] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 6782.910303] Key type lgssc registered [ 6783.909468] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6817.207039] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6819.471520] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6832.310570] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6834.935108] Lustre: Mounted lustre-client [ 6845.421062] Lustre: lustre-MDT0000-mdc-ffff9fc14718a800: Connection to lustre-MDT0000 (at 192.168.202.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6850.544754] Lustre: lustre-MDT0001-mdc-ffff9fc14718a800: Connection to lustre-MDT0001 (at 192.168.202.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6865.888441] Lustre: 212215:0:(client.c:2468:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761290013/real 1761290013] req@00000000e29f4407 x1846846364401920/t0(0) o400->MGC192.168.202.133@tcp@192.168.202.133@tcp:26/25 lens 224/224 e 0 to 1 dl 1761290029 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 6865.914884] LustreError: MGC192.168.202.133@tcp: Connection to MGS (at 192.168.202.133@tcp) was lost; in progress operations using this service will fail [ 6876.201391] Lustre: Evicted from MGS (at 192.168.202.133@tcp) after server handle changed from 0x6a546a4ecc8f0e40 to 0x6a546a4ecc8f2928 [ 6876.206845] Lustre: MGC192.168.202.133@tcp: Connection restored to 192.168.202.133@tcp (at 192.168.202.133@tcp) [ 6887.399073] LustreError: lustre-MDT0000-mdc-ffff9fc14718a800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 6887.433248] Lustre: lustre-MDT0000-mdc-ffff9fc14718a800: Connection restored to 192.168.202.133@tcp (at 192.168.202.133@tcp) [ 6887.614229] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6888.592355] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 6890.871398] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6891.871643] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 6893.354360] LustreError: 215633:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9fc14718a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6893.366758] LustreError: 215633:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6893.431863] Lustre: Unmounted lustre-client [ 6924.391804] Key type lgssc unregistered [ 6924.582775] LNet: 216036:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6924.593554] LNetError: 216036:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6924.608628] LNet: Removed LNI 192.168.202.33@tcp [ 6925.168167] Key type .llcrypt unregistered [ 6925.171104] Key type ._llcrypt unregistered [ 6934.509798] Key type ._llcrypt registered [ 6934.511247] Key type .llcrypt registered [ 6935.357581] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 03:14:57 (1761290097) [ 6936.855743] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6936.936218] alg: No test for adler32 (adler32-zlib) [ 6937.852586] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 6938.012569] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 6939.648275] Key type lgssc registered [ 6940.419795] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6968.510443] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6970.381280] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7001.292705] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7003.537203] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7021.635202] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7034.407894] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 7066.161267] Key type lgssc unregistered [ 7066.393817] LNet: 221138:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7066.397650] LNetError: 221138:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7066.411260] LNet: Removed LNI 192.168.202.33@tcp [ 7066.884766] Key type .llcrypt unregistered [ 7066.886639] Key type ._llcrypt unregistered [ 7091.023067] Key type ._llcrypt registered [ 7091.024699] Key type .llcrypt registered [ 7091.268657] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7091.279655] alg: No test for adler32 (adler32-zlib) [ 7092.250867] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 7092.411343] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 7094.047217] Key type lgssc registered [ 7095.002934] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7144.327516] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7146.236458] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7160.125438] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7198.226349] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 03:19:20 (1761290360) [ 7269.548793] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7272.120777] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7284.497592] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7313.787066] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 03:21:16 (1761290476) [ 7318.393919] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 03:21:20 (1761290480) [ 7339.112403] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7341.181297] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7352.348314] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7353.779834] Lustre: Mounted lustre-client [ 7361.266879] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [ 7362.164853] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 7363.157331] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 7364.087427] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [ 7369.191673] Lustre: lustre-MDT0000-mdc-ffff9fc143970000: Connection to lustre-MDT0000 (at 192.168.202.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7384.559379] LustreError: MGC192.168.202.133@tcp: Connection to MGS (at 192.168.202.133@tcp) was lost; in progress operations using this service will fail [ 7384.577981] Lustre: Evicted from MGS (at 192.168.202.133@tcp) after server handle changed from 0xcb8344cbf78f9f3f to 0xcb8344cbf78fa1a7 [ 7384.591626] Lustre: MGC192.168.202.133@tcp: Connection restored to 192.168.202.133@tcp (at 192.168.202.133@tcp) [ 7388.694267] Lustre: lustre-MDT0000-mdc-ffff9fc143970000: Connection restored to 192.168.202.133@tcp (at 192.168.202.133@tcp) [ 7391.680497] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7392.982639] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 7396.017846] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [ 7399.913165] Lustre: lustre-MDT0000-mdc-ffff9fc143970000: Connection to lustre-MDT0000 (at 192.168.202.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7415.278607] LustreError: MGC192.168.202.133@tcp: Connection to MGS (at 192.168.202.133@tcp) was lost; in progress operations using this service will fail [ 7415.302260] Lustre: Evicted from MGS (at 192.168.202.133@tcp) after server handle changed from 0xcb8344cbf78fa1a7 to 0xcb8344cbf78fa4a9 [ 7415.307664] Lustre: MGC192.168.202.133@tcp: Connection restored to 192.168.202.133@tcp (at 192.168.202.133@tcp) [ 7419.411893] Lustre: lustre-MDT0000-mdc-ffff9fc143970000: Connection restored to 192.168.202.133@tcp (at 192.168.202.133@tcp) [ 7421.994762] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7422.885848] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 7425.274240] LustreError: 234783:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9fc143970000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7425.287779] LustreError: 234783:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7425.319581] Lustre: Unmounted lustre-client [ 7467.316433] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7469.052950] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7478.362220] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7483.915328] Lustre: Mounted lustre-client [ 7484.483478] LustreError: 236405:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9fc1467ed800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7484.496791] LustreError: 236405:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7484.499802] LustreError: 236405:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 7484.529726] Lustre: Unmounted lustre-client [ 7495.883145] Key type lgssc unregistered [ 7496.012771] LNet: 236804:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7496.016586] LNetError: 236804:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7496.025957] LNet: Removed LNI 192.168.202.33@tcp [ 7496.347939] Key type .llcrypt unregistered [ 7496.349691] Key type ._llcrypt unregistered [ 7503.900611] Key type ._llcrypt registered [ 7503.902204] Key type .llcrypt registered [ 7504.739659] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 03:24:27 (1761290667) [ 7507.086502] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7507.098338] alg: No test for adler32 (adler32-zlib) [ 7508.060390] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 7508.208262] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 7509.823240] Key type lgssc registered [ 7510.489355] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7533.955731] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7535.847449] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7544.411213] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7550.957070] Lustre: Mounted lustre-client [ 7558.108661] LustreError: 239968:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9fc147189800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7558.122682] LustreError: 239968:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7558.157863] Lustre: Unmounted lustre-client [ 7596.672460] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7598.523556] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7608.333345] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7613.970546] Lustre: Mounted lustre-client [ 7620.502898] LustreError: 241564:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9fc14718a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7620.512137] LustreError: 241564:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7620.514591] LustreError: 241564:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 7620.543202] Lustre: Unmounted lustre-client [ 7658.513360] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7660.149551] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7669.347910] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7674.885895] Lustre: Mounted lustre-client [ 7675.455469] LustreError: 243178:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9fc14439b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7675.470716] LustreError: 243178:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7675.474746] LustreError: 243178:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 7675.505302] Lustre: Unmounted lustre-client [ 7703.119981] Key type lgssc unregistered [ 7703.269747] LNet: 243581:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7703.273825] LNetError: 243581:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7703.288307] LNet: Removed LNI 192.168.202.33@tcp [ 7703.604771] Key type .llcrypt unregistered [ 7703.607156] Key type ._llcrypt unregistered [ 7711.458136] Key type ._llcrypt registered [ 7711.459670] Key type .llcrypt registered [ 7712.202351] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 03:27:54 (1761290874) [ 7713.957449] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7713.967040] alg: No test for adler32 (adler32-zlib) [ 7714.867225] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 7714.987178] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 7716.591229] Key type lgssc registered [ 7717.144489] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7737.493391] Key type lgssc unregistered [ 7737.643615] LNet: 245890:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7737.647299] LNetError: 245890:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7737.655781] LNet: Removed LNI 192.168.202.33@tcp [ 7737.965635] Key type .llcrypt unregistered [ 7737.967196] Key type ._llcrypt unregistered [ 7751.415663] Key type ._llcrypt registered [ 7751.417306] Key type .llcrypt registered [ 7751.638331] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7751.645913] alg: No test for adler32 (adler32-zlib) [ 7752.552909] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 7752.673464] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 7754.287222] Key type lgssc registered [ 7754.872519] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7831.280170] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7839.055759] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7860.878084] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7895.367088] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [ 7896.524435] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 03:30:58 (1761291058) [ 7920.432497] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7923.439987] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7939.981691] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7960.487305] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 7961.698652] Lustre: Mounted lustre-client [ 7966.699230] Lustre: lustre-OST0001-osc-ffff9fc143971800: Connection to lustre-OST0001 (at 192.168.202.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7972.711847] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7982.960152] LustreError: 252400:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 7983.012623] Lustre: Unmounted lustre-client [ 8015.181375] Key type lgssc unregistered [ 8015.449693] LNet: 252804:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8015.463914] LNetError: 252804:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8015.490715] LNet: Removed LNI 192.168.202.33@tcp [ 8016.052177] Key type .llcrypt unregistered [ 8016.053557] Key type ._llcrypt unregistered [ 8052.735232] Key type ._llcrypt registered [ 8052.757148] Key type .llcrypt registered [ 8053.004417] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8053.018987] alg: No test for adler32 (adler32-zlib) [ 8054.103534] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 8054.329812] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 8055.983164] Key type lgssc registered [ 8057.109513] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8133.408970] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8136.620101] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8156.023995] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8192.075679] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 03:35:54 (1761291354) [ 8220.116313] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8223.178841] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8250.560162] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 03:36:51 (1761291411) [ 8287.470471] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8291.739338] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8320.986913] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8323.172252] Lustre: Mounted lustre-client [ 8336.435071] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 8341.549328] LustreError: 260656:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 8341.636165] Lustre: Unmounted lustre-client [ 8440.367493] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8443.287155] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8489.261499] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8495.148304] Lustre: Mounted lustre-client [ 8496.251539] LustreError: 262720:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9fc1500cd000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8496.273775] LustreError: 262720:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 8496.276665] LustreError: 262720:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 8496.343115] Lustre: Unmounted lustre-client [ 8521.006674] Key type lgssc unregistered [ 8521.266759] LNet: 263124:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8521.280934] LNetError: 263124:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8521.304884] LNet: Removed LNI 192.168.202.33@tcp [ 8521.880482] Key type .llcrypt unregistered [ 8521.882854] Key type ._llcrypt unregistered [ 8545.789423] Key type ._llcrypt registered [ 8545.811147] Key type .llcrypt registered [ 8546.162217] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8546.223135] alg: No test for adler32 (adler32-zlib) [ 8547.623335] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 8548.151521] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 8550.007210] Key type lgssc registered [ 8552.800923] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8631.007805] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8634.334794] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8652.371716] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8690.550438] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [ 8692.356552] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 03:44:14 (1761291854) [ 8720.969782] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8724.387960] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8743.430037] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8747.288562] Lustre: Mounted lustre-client [ 8749.009073] LustreError: 269182:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9fc1467eb000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8749.021121] LustreError: 269182:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 8749.073885] Lustre: Unmounted lustre-client [ 8775.979676] Key type lgssc unregistered [ 8776.294967] LNet: 269584:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8776.301612] LNetError: 269584:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8776.322197] LNet: Removed LNI 192.168.202.33@tcp [ 8776.886771] Key type .llcrypt unregistered [ 8776.888380] Key type ._llcrypt unregistered [ 8789.575665] Key type ._llcrypt registered [ 8789.578147] Key type .llcrypt registered [ 8790.568857] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [ 8792.047760] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 03:45:54 (1761291954) [ 8800.501598] Key type .llcrypt unregistered [ 8800.503105] Key type ._llcrypt unregistered [ 8808.126726] Key type ._llcrypt registered [ 8808.135582] Key type .llcrypt registered [ 8808.517185] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8808.533835] alg: No test for adler32 (adler32-zlib) [ 8809.664258] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 8809.881092] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 8811.535212] Key type lgssc registered [ 8812.790266] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8857.392027] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8873.031602] Lustre: Mounted lustre-client [ 8874.957489] LustreError: 272605:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9fc148b5d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8874.975927] LustreError: 272605:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 8875.024249] Lustre: Unmounted lustre-client [ 8911.909446] Key type lgssc unregistered [ 8912.168900] LNet: 273009:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8912.176375] LNetError: 273009:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8912.204965] LNet: Removed LNI 192.168.202.33@tcp [ 8912.807111] Key type .llcrypt unregistered [ 8912.809313] Key type ._llcrypt unregistered [ 8928.632617] Key type ._llcrypt registered [ 8928.633942] Key type .llcrypt registered [ 8929.914426] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 03:48:11 (1761292091) [ 8931.979669] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8931.993885] alg: No test for adler32 (adler32-zlib) [ 8933.068964] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 8933.254892] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 8934.927159] Key type lgssc registered [ 8936.064772] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9011.694390] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9035.351354] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9038.591920] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9041.234744] Lustre: Mounted lustre-client [ 9043.077788] LustreError: 276060:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9fc143672800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9043.096477] LustreError: 276060:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 9043.139529] Lustre: Unmounted lustre-client [ 9078.433855] Key type lgssc unregistered [ 9078.712447] LNet: 276464:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9078.727488] LNetError: 276464:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9078.747659] LNet: Removed LNI 192.168.202.33@tcp [ 9079.434234] Key type .llcrypt unregistered [ 9079.446740] Key type ._llcrypt unregistered [ 9094.582340] Key type ._llcrypt registered [ 9094.587806] Key type .llcrypt registered [ 9095.926305] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 03:50:57 (1761292257) [ 9097.742290] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9097.761811] alg: No test for adler32 (adler32-zlib) [ 9098.828118] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 9099.056641] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 9100.735664] Key type lgssc registered [ 9101.656524] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9142.232624] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9145.563574] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9162.910692] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9169.424813] Lustre: Mounted lustre-client [ 9174.510600] Lustre: lustre-MDT0000-mdc-ffff9fc1457ea000: Connection to lustre-MDT0000 (at 192.168.202.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9178.686297] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 9178.713898] LustreError: 279535:0:(file.c:6108:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [ 9178.972054] LustreError: 279554:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9fc1457ea000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9178.976751] LustreError: MGC192.168.202.133@tcp: Connection to MGS (at 192.168.202.133@tcp) was lost; in progress operations using this service will fail [ 9179.005750] LustreError: 279554:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 9179.054123] Lustre: Unmounted lustre-client [ 9198.325637] Key type lgssc unregistered [ 9198.544823] LNet: 279946:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9198.555318] LNetError: 279946:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9198.579731] LNet: Removed LNI 192.168.202.33@tcp [ 9199.151505] Key type .llcrypt unregistered [ 9199.154438] Key type ._llcrypt unregistered [ 9214.734249] Key type ._llcrypt registered [ 9214.736136] Key type .llcrypt registered [ 9216.002300] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 03:52:57 (1761292377) [ 9218.662464] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9218.705663] alg: No test for adler32 (adler32-zlib) [ 9219.938454] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 9220.212232] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 9221.903206] Key type lgssc registered [ 9223.075636] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9264.566589] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9267.003477] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9282.638902] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9293.860899] Lustre: Mounted lustre-client [ 9298.925912] Lustre: lustre-MDT0001-mdc-ffff9fc148b58000: Connection to lustre-MDT0001 (at 192.168.202.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9304.096054] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 9305.972975] LustreError: 283046:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9fc148b58000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9305.991996] LustreError: 283046:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 9306.047360] Lustre: Unmounted lustre-client [ 9325.653405] Key type lgssc unregistered [ 9325.968786] LNet: 283438:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9325.975368] LNetError: 283438:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9325.998386] LNet: Removed LNI 192.168.202.33@tcp [ 9326.729079] Key type .llcrypt unregistered [ 9326.746101] Key type ._llcrypt unregistered [ 9343.753453] Key type ._llcrypt registered [ 9343.754991] Key type .llcrypt registered [ 9345.125106] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 03:55:06 (1761292506) [ 9361.509493] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9361.525936] alg: No test for adler32 (adler32-zlib) [ 9362.638127] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 9362.931294] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 9364.735168] Key type lgssc registered [ 9365.879736] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9466.574937] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9469.180895] Lustre: Mounted lustre-client [ 9504.244812] Lustre: lustre-MDT0001-mdc-ffff9fc143677800: Connection to lustre-MDT0001 (at 192.168.202.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9514.684272] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 9524.983426] LustreError: 288793:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 9525.016723] Lustre: Unmounted lustre-client [ 9551.251954] Key type lgssc unregistered [ 9551.492285] LNet: 289194:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9551.501177] LNetError: 289194:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9551.518211] LNet: Removed LNI 192.168.202.33@tcp [ 9552.227493] Key type .llcrypt unregistered [ 9552.230213] Key type ._llcrypt unregistered [ 9566.479589] Key type ._llcrypt registered [ 9566.481715] Key type .llcrypt registered [ 9567.785655] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 03:58:49 (1761292729) [ 9569.004378] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [ 9570.851267] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 03:58:52 (1761292732) [ 9572.415668] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [ 9574.437830] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 03:58:56 (1761292736) [ 9576.472161] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [ 9578.670525] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 03:59:00 (1761292740) [ 9580.651192] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [ 9582.300553] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 03:59:04 (1761292744) [ 9584.029416] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [ 9585.708509] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 03:59:07 (1761292747) [ 9595.285487] Key type .llcrypt unregistered [ 9595.293496] Key type ._llcrypt unregistered [ 9602.151823] Key type ._llcrypt registered [ 9602.153527] Key type .llcrypt registered [ 9602.621732] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9602.656961] alg: No test for adler32 (adler32-zlib) [ 9603.762172] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 9603.953971] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 9605.663567] Key type lgssc registered [ 9606.908279] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9670.121994] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9674.885412] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9693.817940] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9695.577953] Lustre: Mounted lustre-client [ 9697.587263] LustreError: 294784:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9fc151169800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9697.616660] LustreError: 294784:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 9697.696201] Lustre: Unmounted lustre-client [ 9732.512427] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 04:01:34 (1761292894) [ 9758.148162] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9761.128896] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9775.482677] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9786.384824] Lustre: Mounted lustre-client [ 9787.551611] LustreError: 296910:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9fc14873c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9787.575595] LustreError: 296910:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 9787.581430] LustreError: 296910:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 9787.639740] Lustre: Unmounted lustre-client [ 9813.334942] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 04:02:55 (1761292975) [ 9881.522548] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9884.954057] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9900.887157] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9984.380552] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 04:05:46 (1761293146) [ 9996.516975] Key type lgssc unregistered [ 9996.785539] LNet: 302363:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9996.796860] LNetError: 302363:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9996.819833] LNet: Removed LNI 192.168.202.33@tcp [ 9997.468281] Key type .llcrypt unregistered [ 9997.471623] Key type ._llcrypt unregistered [10038.058380] Key type ._llcrypt registered [10038.065509] Key type .llcrypt registered [10038.371642] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10038.389953] alg: No test for adler32 (adler32-zlib) [10039.374832] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [10039.554073] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [10041.223220] Key type lgssc registered [10042.282307] Lustre: Echo OBD driver; http://www.lustre.org/ [10059.127204] Lustre: DEBUG MARKER: SKIP: conf-sanity test_73c unable to configure net [10070.613333] Key type lgssc unregistered [10070.857478] LNet: 304035:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10070.866482] LNetError: 304035:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10070.876950] LNet: Removed LNI 192.168.202.33@tcp [10071.622700] Key type .llcrypt unregistered [10071.626111] Key type ._llcrypt unregistered [10080.853027] Key type ._llcrypt registered [10080.854644] Key type .llcrypt registered [10082.133409] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 04:07:23 (1761293243) [10101.197341] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10101.205803] alg: No test for adler32 (adler32-zlib) [10102.360435] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [10102.619902] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [10104.367167] Key type lgssc registered [10105.633536] Lustre: Echo OBD driver; http://www.lustre.org/ [10154.309762] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 04:08:36 (1761293316) [10165.529652] Key type lgssc unregistered [10165.781903] LNet: 307588:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10165.797951] LNetError: 307588:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10165.821397] LNet: Removed LNI 192.168.202.33@tcp [10166.437247] Key type .llcrypt unregistered [10166.438851] Key type ._llcrypt unregistered [10174.600083] Key type ._llcrypt registered [10174.601829] Key type .llcrypt registered [10174.930113] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10174.941992] alg: No test for adler32 (adler32-zlib) [10175.952514] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [10176.167202] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [10177.855885] Key type lgssc registered [10178.798340] Lustre: Echo OBD driver; http://www.lustre.org/ [10220.363524] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10223.304626] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10239.540401] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10246.489125] LNet: Added LNI 192.168.202.33@tcp42 [8/256/0/180] [10248.308144] LNet: Removed LNI 192.168.202.33@tcp [10250.070577] Lustre: 310482:0:(ldlm_lib.c:563:client_obd_setup()) lustre-MDT0000-mdc-ffff9fc16072f800: config has no valid NIDs, force dynamic NIDs [10251.222619] Lustre: Mounted lustre-client [10251.292300] LustreError: 310497:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9fc16072f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10251.309304] LustreError: 310497:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [10251.370886] Lustre: Unmounted lustre-client [10277.900783] Key type lgssc unregistered [10278.138682] LNet: 311334:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10278.143722] LNetError: 311334:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10278.156138] LNet: Removed LNI 192.168.202.33@tcp42 [10278.545193] Key type .llcrypt unregistered [10278.547066] Key type ._llcrypt unregistered [10285.130674] Key type ._llcrypt registered [10285.132056] Key type .llcrypt registered [10286.063780] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 04:10:48 (1761293448) [10287.622548] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10287.683960] alg: No test for adler32 (adler32-zlib) [10288.674359] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [10288.826164] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [10290.448102] Key type lgssc registered [10291.202914] Lustre: Echo OBD driver; http://www.lustre.org/ [10318.733205] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10321.200202] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10333.468983] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10335.988101] Lustre: Mounted lustre-client [10378.262411] Lustre: Mounted fs15246-client [10386.945212] LustreError: 315462:0:(lov_obd.c:783:lov_cleanup()) fs15246-clilov-ffff9fc1457ed800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10386.957977] LustreError: 315462:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [10386.983719] Lustre: Unmounted fs15246-client [10405.823302] LustreError: 315679:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9fc152d1c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10405.836080] LustreError: 315679:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [10405.839432] LustreError: 315679:0:(obd_class.h:479:obd_check_dev()) Skipped 3 previous similar messages [10405.890157] Lustre: Unmounted lustre-client [10435.635082] Key type lgssc unregistered [10435.820908] LNet: 316081:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10435.825564] LNetError: 316081:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10435.846138] LNet: Removed LNI 192.168.202.33@tcp [10436.213595] Key type .llcrypt unregistered [10436.215158] Key type ._llcrypt unregistered [10442.592222] Key type ._llcrypt registered [10442.594138] Key type .llcrypt registered [10443.451973] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 04:13:25 (1761293605) [10444.434325] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10444.444701] alg: No test for adler32 (adler32-zlib) [10445.398229] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [10445.536435] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [10447.175174] Key type lgssc registered [10448.045279] Lustre: Echo OBD driver; http://www.lustre.org/ [10525.456221] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10527.426047] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10539.336771] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10602.506540] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 10450 sec ========= 04:16:05 (1761293765) [10603.252838] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 04:16:05 (1761293765) === [10604.745737] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 04:16:07 (1761293767) === [10620.354081] Key type lgssc unregistered [10620.479759] LNet: 325227:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10620.483432] LNetError: 325227:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10620.495618] LNet: Removed LNI 192.168.202.33@tcp [10620.778995] Key type .llcrypt unregistered [10620.780696] Key type ._llcrypt unregistered