[ 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 512931217 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: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524584K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001017] APIC: Switch to symmetric I/O mode setup [ 0.002360] x2apic enabled [ 0.003012] Switched APIC routing to physical x2apic. [ 0.004000] kvm-guest: setup PV IPIs [ 0.005000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.005000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.005020] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.006016] pid_max: default: 32768 minimum: 301 [ 0.008148] LSM: Security Framework initializing [ 0.009068] Yama: becoming mindful. [ 0.010066] SELinux: Initializing. [ 0.011178] *** VALIDATE selinux *** [ 0.025362] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.035286] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.037238] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.038244] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.039148] *** VALIDATE tmpfs *** [ 0.042461] *** VALIDATE proc *** [ 0.043349] *** VALIDATE cgroup *** [ 0.044013] *** VALIDATE cgroup2 *** [ 0.045328] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.046163] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.047016] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.048037] Spectre V2 : User space: Vulnerable [ 0.049023] Speculative Store Bypass: Vulnerable [ 0.053039] debug: unmapping init [mem 0xffffffffac659000-0xffffffffac660fff] [ 0.055019] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.056854] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.057027] ... version: 2 [ 0.058019] ... bit width: 48 [ 0.059011] ... generic registers: 4 [ 0.060015] ... value mask: 0000ffffffffffff [ 0.061020] ... max period: 00007fffffffffff [ 0.062021] ... fixed-purpose events: 3 [ 0.063018] ... event mask: 000000070000000f [ 0.064587] rcu: Hierarchical SRCU implementation. [ 0.066624] smp: Bringing up secondary CPUs ... [ 0.068392] x86: Booting SMP configuration: [ 0.069038] .... node #0, CPUs: #1 #2 #3 [ 0.089133] smp: Brought up 1 node, 4 CPUs [ 0.091013] smpboot: Max logical packages: 1 [ 0.092015] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.127713] node 0 deferred pages initialised in 28ms [ 0.144644] devtmpfs: initialized [ 0.145500] x86/mm: Memory block size: 128MB [ 0.148259] gcov: version magic: 0x41383552 [ 0.150302] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.151090] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.152382] pinctrl core: initialized pinctrl subsystem [ 0.153594] [ 0.153896] ************************************************************* [ 0.156013] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.158008] ** ** [ 0.160011] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.162011] ** ** [ 0.164010] ** This means that this kernel is built to expose internal ** [ 0.166016] ** IOMMU data structures, which may compromise security on ** [ 0.167007] ** your system. ** [ 0.169009] ** ** [ 0.170011] ** If you see this message and you are not debugging the ** [ 0.172016] ** kernel, report this immediately to your vendor! ** [ 0.174012] ** ** [ 0.177020] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.179014] ************************************************************* [ 0.182888] NET: Registered protocol family 16 [ 0.184497] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.186055] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.188050] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.192089] cpuidle: using governor menu [ 0.195000] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.198816] PCI: Using configuration type 1 for base access [ 0.199212] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.212060] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.214027] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.217048] cryptd: max_cpu_qlen set to 1000 [ 0.223324] ACPI: Added _OSI(Module Device) [ 0.231033] ACPI: Added _OSI(Processor Device) [ 0.236000] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.241266] ACPI: Added _OSI(Processor Aggregator Device) [ 0.253100] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.263524] ACPI: Interpreter enabled [ 0.266066] ACPI: PM: (supports S0 S3 S4 S5) [ 0.268013] ACPI: Using IOAPIC for interrupt routing [ 0.279117] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.286466] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.298316] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.300032] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.303018] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.307078] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.313311] acpiphp: Slot [2] registered [ 0.316111] acpiphp: Slot [3] registered [ 0.317000] acpiphp: Slot [4] registered [ 0.318130] acpiphp: Slot [5] registered [ 0.319085] acpiphp: Slot [6] registered [ 0.321074] acpiphp: Slot [7] registered [ 0.322093] acpiphp: Slot [8] registered [ 0.323149] acpiphp: Slot [9] registered [ 0.324058] acpiphp: Slot [10] registered [ 0.326055] acpiphp: Slot [11] registered [ 0.327053] acpiphp: Slot [12] registered [ 0.328071] acpiphp: Slot [13] registered [ 0.331082] acpiphp: Slot [14] registered [ 0.333128] acpiphp: Slot [15] registered [ 0.335071] acpiphp: Slot [16] registered [ 0.337559] acpiphp: Slot [17] registered [ 0.340078] acpiphp: Slot [18] registered [ 0.341116] acpiphp: Slot [19] registered [ 0.343138] acpiphp: Slot [20] registered [ 0.345145] acpiphp: Slot [21] registered [ 0.348082] acpiphp: Slot [22] registered [ 0.350077] acpiphp: Slot [23] registered [ 0.352075] acpiphp: Slot [24] registered [ 0.354072] acpiphp: Slot [25] registered [ 0.357115] acpiphp: Slot [26] registered [ 0.359087] acpiphp: Slot [27] registered [ 0.361115] acpiphp: Slot [28] registered [ 0.363076] acpiphp: Slot [29] registered [ 0.365070] acpiphp: Slot [30] registered [ 0.366067] acpiphp: Slot [31] registered [ 0.368083] PCI host bridge to bus 0000:00 [ 0.369027] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.371020] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.374018] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.378021] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.380016] pci_bus 0000:00: root bus resource [mem 0x180000000-0x1ffffffff window] [ 0.383022] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.386170] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.393818] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.406770] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.419597] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.427487] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.435026] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.451019] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.462019] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.471462] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.474842] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.487066] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.490193] pci 0000:00:01.3: quirk_piix4_acpi+0x0/0x1e0 took 15625 usecs [ 0.492838] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.501035] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.523000] pci 0000:00:02.0: reg 0x20: [mem 0xfebf4000-0xfebf7fff 64bit pref] [ 0.524000] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.529420] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.541027] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.544000] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.561021] pci 0000:00:05.0: reg 0x20: [mem 0xfebf8000-0xfebfbfff 64bit pref] [ 0.578485] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.602014] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.622018] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.649015] pci 0000:00:06.0: reg 0x20: [mem 0xfebfc000-0xfebfffff 64bit pref] [ 0.662438] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.665504] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.669380] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.671518] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.673187] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.677027] iommu: Default domain type: Passthrough [ 0.678000] SCSI subsystem initialized [ 0.678000] ACPI: bus type USB registered [ 0.678000] usbcore: registered new interface driver usbfs [ 0.678082] usbcore: registered new interface driver hub [ 0.679000] usbcore: registered new device driver usb [ 0.679000] pps_core: LinuxPPS API ver. 1 registered [ 0.679000] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.680069] PTP clock support registered [ 0.683092] EDAC MC: Ver: 3.0.0 [ 0.687144] PCI: Using ACPI for IRQ routing [ 0.694736] NetLabel: Initializing [ 0.700011] NetLabel: domain hash size = 128 [ 0.708012] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.714074] NetLabel: unlabeled traffic allowed by default [ 0.719234] vgaarb: loaded [ 0.722914] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.728011] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.741777] clocksource: Switched to clocksource kvm-clock [ 1.183848] VFS: Disk quotas dquot_6.6.0 [ 1.191381] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 1.197956] *** VALIDATE ramfs *** [ 1.199948] *** VALIDATE hugetlbfs *** [ 1.201506] pnp: PnP ACPI init [ 1.203988] pnp: PnP ACPI: found 6 devices [ 1.241668] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 1.245081] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 1.255698] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 1.264334] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 1.269741] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 1.272920] pci_bus 0000:00: resource 8 [mem 0x180000000-0x1ffffffff window] [ 1.276270] NET: Registered protocol family 2 [ 1.279475] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 1.297495] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 1.314369] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 1.326803] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 1.330393] TCP: Hash tables configured (established 65536 bind 65536) [ 1.335641] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 1.342154] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 1.348881] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 1.355144] NET: Registered protocol family 1 [ 1.362584] RPC: Registered named UNIX socket transport module. [ 1.364775] RPC: Registered udp transport module. [ 1.366314] RPC: Registered tcp transport module. [ 1.370593] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 1.373616] NET: Registered protocol family 44 [ 1.375663] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 1.378271] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 1.381166] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 1.383192] PCI: CLS 0 bytes, default 64 [ 1.384553] Unpacking initramfs... [ 7.389319] debug: unmapping init [mem 0xffffa08ffcc64000-0xffffa08ffffcffff] [ 7.411105] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 7.456886] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 7.462261] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 10.233284] Initialise system trusted keyrings [ 10.235312] Key type blacklist registered [ 10.271690] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 10.322936] zbud: loaded [ 10.336725] *** VALIDATE nfs *** [ 10.344313] *** VALIDATE nfs4 *** [ 10.347908] pstore: using deflate compression [ 10.361343] Platform Keyring initialized [ 10.747760] NET: Registered protocol family 38 [ 10.751716] Key type asymmetric registered [ 10.761326] Asymmetric key parser 'x509' registered [ 10.780975] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 10.796100] io scheduler mq-deadline registered [ 10.802850] io scheduler kyber registered [ 10.819038] io scheduler bfq registered [ 10.821954] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 10.833417] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 10.836854] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 10.839919] ACPI: Power Button [PWRF] [ 11.218961] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 11.752697] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 12.433986] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 12.529837] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 12.636634] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 12.658592] Non-volatile memory driver v1.3 [ 12.661771] Linux agpgart interface v0.103 [ 12.862020] hrtimer: interrupt took 8011293 ns [ 13.050576] virtio_blk virtio1: [vda] 133840 512-byte logical blocks (68.5 MB/65.4 MiB) [ 13.072784] vda: detected capacity change from 0 to 68526080 [ 13.161277] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 13.173905] vdb: detected capacity change from 0 to 1073741824 [ 13.219235] libphy: Fixed MDIO Bus: probed [ 13.254347] usbcore: registered new interface driver usbserial_generic [ 13.256962] usbserial: USB Serial support registered for generic [ 13.285126] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 13.308173] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 13.321207] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 13.338088] mousedev: PS/2 mouse device common for all mice [ 13.353243] rtc_cmos 00:05: RTC can wake from S4 [ 13.370137] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 13.393874] rtc_cmos 00:05: registered as rtc0 [ 13.436639] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 13.449249] intel_pstate: CPU model not supported [ 13.454175] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 13.496945] hid: raw HID events driver (C) Jiri Kosina [ 13.531716] usbcore: registered new interface driver usbhid [ 13.543841] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 13.548808] usbhid: USB HID core driver [ 13.549255] drop_monitor: Initializing network drop monitor service [ 13.549419] Initializing XFRM netlink socket [ 13.550528] NET: Registered protocol family 10 [ 13.561951] Segment Routing with IPv6 [ 13.631069] NET: Registered protocol family 17 [ 13.637804] mpls_gso: MPLS GSO support [ 13.659214] RAS: Correctable Errors collector initialized. [ 13.667846] AVX version of gcm_enc/dec engaged. [ 13.676375] AES CTR mode by8 optimization enabled [ 14.249790] sched_clock: Marking stable (14249744755, 0)->(16101689664, -1851944909) [ 14.266410] registered taskstats version 1 [ 14.276641] Loading compiled-in X.509 certificates [ 14.278749] zswap: loaded using pool lzo/zbud [ 14.396396] Key type big_key registered [ 14.424072] Key type encrypted registered [ 14.426670] ima: No TPM chip found, activating TPM-bypass! [ 14.432348] ima: Allocated hash algorithm: sha1 [ 14.439080] ima: No architecture policies found [ 14.444904] evm: Initialising EVM extended attributes: [ 14.456919] evm: security.selinux [ 14.462152] evm: security.ima [ 14.463495] evm: security.capability [ 14.464758] evm: HMAC attrs: 0x1 [ 14.474617] rtc_cmos 00:05: setting system clock to 2025-11-17 17:46:42 UTC (1763401602) [ 14.494473] debug: unmapping init [mem 0xffffffffad603000-0xffffffffad7fffff] [ 14.512717] debug: unmapping init [mem 0xffffffffac382000-0xffffffffac658fff] [ 14.549337] Write protecting the kernel read-only data: 28672k [ 14.565544] debug: unmapping init [mem 0xffffffffaaa03000-0xffffffffaabfffff] [ 14.582064] debug: unmapping init [mem 0xffffffffab314000-0xffffffffab3fffff] [ 14.790654] 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) [ 14.846310] systemd[1]: Detected virtualization kvm. [ 14.858388] systemd[1]: Detected architecture x86-64. [ 14.866644] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 14.957600] systemd[1]: No hostname configured. [ 14.960357] systemd[1]: Set hostname to . [ 14.962283] random: systemd: uninitialized urandom read (16 bytes read) [ 14.985410] systemd[1]: Initializing machine ID from random generator. [ 15.475754] random: systemd: uninitialized urandom read (16 bytes read) [ 15.477895] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ 15.500519] random: systemd: uninitialized urandom read (16 bytes read) [ 15.504857] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ 15.512515] systemd[1]: Reached target Timers. [ OK ] Reached target Timers. [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on udev Control Socket. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Swap. [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Local File Systems. [ OK ] Reached target Paths. [ OK ] Listening on Journal Socket. Starting Setup Virtual Console... [ OK ] Reached target Sockets. Starting Create list of required st…ce nodes for the current kernel... Starting Journal Service... Starting Apply Kernel Variables... Starting Create Volatile Files and Directories... [ OK ] Started Memstrack Anylazing Service. [ OK ] Started Setup Virtual Console. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create Volatile Files and Directories. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 18.069538] device-mapper: uevent: version 1.0.3 [ 18.078251] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon.[ 21.858958] random: fast init done [ 21.924499] virtio_net virtio0 ens2: renamed from eth0 [ 22.354175] scsi host0: ata_piix [ 22.587038] scsi host1: ata_piix [ 22.588382] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 22.615257] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 28.049827] random: crng init done [ 28.071140] random: 7 urandom warning(s) missed due to ratelimiting [ 31.372513] dracut-initqueue[584]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 34.896850] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped target Timers. [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. Stopping Hardware RNG Entropy Gatherer Daemon... [ 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 Sockets. [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ OK ] Stopped target System Initialization. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Swap. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped udev Kernel Device Manager. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 40.412481] printk: systemd: 26 output lines suppressed due to ratelimiting [ 41.906469] SELinux: Disabled at runtime. [ 42.112738] 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) [ 42.164974] systemd[1]: Detected virtualization kvm. [ 42.166950] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 45.523239] systemd[1]: initrd-switch-root.service: Succeeded. [ 45.557586] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 45.606188] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 45.609456] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 45.637062] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 45.705735] systemd[1]: Starting Journal Service... Starting Journal Service... [ 45.787697] systemd[1]: Starting Remount Root and Kernel File Systems... Starting Remount Root and Kernel File Systems... [ OK ] Listening on udev Kernel Socket. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. [ OK ] Reached target rpc_pipefs.target. Activating swap /dev/disk/by-label/SWAP... [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ 46.566470] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Listening on Process Core Dump Socket. Mounting POSIX Message Queue File System... [ OK ] Created slice system-getty.slice. [ OK ] Listening on udev Control Socket. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Stopped target Initrd Root File System. Starting Apply Kernel Variables... [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on initctl Compatibility Named Pipe. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ 47.212022] systemd[1]: Mounting Kernel Debug File System... Mounting Kernel Debug File System... [ 47.231349] systemd[1]: Created slice system-sshd\x2dkeygen.slice. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ 47.277431] systemd[1]: Mounting Huge Pages File System... Mounting Huge Pages File System... [ 47.327592] systemd[1]: Starting udev Coldplug all Devices... Starting udev Coldplug all Devices... [ 47.373365] systemd[1]: sysroot-var-lib-nfs-rpc_pipefs.mount: Succeeded. [ 47.376921] systemd[1]: sysroot.mount: Succeeded. [ 47.379545] systemd[1]: Started Journal Service. [ OK ] Started Journal Service. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted Kernel Debug File System. [ OK ] Mounted Huge Pages File System. [ OK ] Reached target Swap. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... [ OK ] Mounted /mnt. [ OK ] Started udev Coldplug all Devices. [ 49.062226] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 51.371123] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 51.459920] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 53.006627] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 53.466317] EDAC sbridge: Ver: 1.1.2 [* ] A start job is running for Configur…only root support (10s / no limit) [** ] A start job is running for Configur…only root support (10s / no limit) [*** ] A start job is running for Configur…only root support (11s / no limit) [ *** ] A start job is running for Configur…only root support (11s / no limit) [ *** ] A start job is running for Configur…only root support (12s / no limit) [ ***] A start job is running for Configur…only root support (13s / no limit) [ **] A start job is running for Configur…only root support (13s / no limit)[ 58.723264] Key type dns_resolver registered [ *] A start job is running for Configur…only root support (14s / no limit) [ **] A start job is running for Configur…only root support (14s / no limit)[ 59.842690] NFS: Registering the id_resolver key type [ 59.856728] Key type id_resolver registered [ 59.860874] Key type id_legacy registered [ ***] A start job is running for Configur…only root support (14s / no limit) [ *** ] A start job is running for Configur…only root support (15s / no limit) [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Load/Save Random Seed... Starting Create Volatile Files and Directories... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Load/Save Random Seed. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started RPC Bind. [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started dnf makecache --timer. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Login Service... [ OK ] Reached target sshd-keygen.target. [ OK ] Started irqbalance daemon. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... Starting Restore /run/initramfs on shutdown... [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting OpenSSH server daemon... Starting Dynamic System Tuning Daemon... Starting Network Manager Wait Online... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started OpenSSH server daemon. [ OK ] Started Permit User Sessions. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS0. [ OK ] Reached target Login Prompts. Starting Hostname Service... [ OK ] Started Login Service. [ 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 System Logging Service... Starting Notify NFS peers of a restart... Starting Crash recovery kernel arming... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg330-client login: [ 115.443161] libcfs: loading out-of-tree module taints kernel. [ 115.509320] Key type ._llcrypt registered [ 115.516349] Key type .llcrypt registered [ 116.178479] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 116.186799] alg: No test for adler32 (adler32-zlib) [ 117.451206] Lustre: Lustre: Build Version: 2.16.61_42_g7dd025e [ 118.087076] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 119.815173] Key type lgssc registered [ 121.456838] Lustre: Echo OBD driver; http://www.lustre.org/ [ 258.530985] Lustre: Mounted lustre-client [ 263.035422] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 278.324915] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing check_logdir /tmp/testlogs/ [ 282.656509] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing yml_node [ 284.127243] Lustre: lustre-OST0000-osc-ffffa090431b6800: disconnect after 24s idle [ 287.362392] Lustre: DEBUG MARKER: Client: 2.16.61.42 [ 289.621680] Lustre: DEBUG MARKER: MDS: 2.16.61.42 [ 291.954403] Lustre: DEBUG MARKER: OSS: 2.16.61.42 [ 293.551266] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Mon Nov 17 12:51:20 EST 2025 [ 308.895713] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 310.493849] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 312.413604] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 322.757588] LustreError: 8977:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 322.818357] Lustre: Unmounted lustre-client [ 430.893714] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 434.113285] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 453.385732] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 486.167909] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 12:54:33 (1763402073) [ 488.202379] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 489.671547] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 12:54:36 (1763402076) [ 517.456900] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 520.753381] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 538.569247] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 544.320833] Lustre: Mounted lustre-client [ 548.236981] LustreError: 14420:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0904acb1800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 548.263845] LustreError: 14420:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 548.266995] LustreError: 14420:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 548.332872] Lustre: Unmounted lustre-client [ 574.837712] Key type lgssc unregistered [ 575.070318] LNet: 14828:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 575.079361] LNetError: 14828:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 576.101083] LNet: Removed LNI 192.168.203.30@tcp [ 576.944351] Key type .llcrypt unregistered [ 576.947808] Key type ._llcrypt unregistered [ 590.011344] Key type ._llcrypt registered [ 590.014128] Key type .llcrypt registered [ 591.200779] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 12:56:17 (1763402177) [ 592.509472] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 592.521261] alg: No test for adler32 (adler32-zlib) [ 593.559959] Lustre: Lustre: Build Version: 2.16.61_42_g7dd025e [ 593.847702] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 595.567171] Key type lgssc registered [ 596.498472] Lustre: Echo OBD driver; http://www.lustre.org/ [ 665.662718] Lustre: Mounted lustre-client [ 752.761536] LustreError: 18145:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa09047829800: lov tgt 1990 not cleaned! deathrow=0, lovrc=1 [ 752.783548] LustreError: 18145:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 752.804725] Lustre: Unmounted lustre-client [ 779.974680] Key type lgssc unregistered [ 780.308947] LNet: 18608:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 780.324522] LNetError: 18608:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 781.351831] LNet: Removed LNI 192.168.203.30@tcp [ 782.023626] Key type .llcrypt unregistered [ 782.029684] Key type ._llcrypt unregistered [ 789.785490] Key type ._llcrypt registered [ 789.787201] Key type .llcrypt registered [ 791.011305] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 12:59:37 (1763402377) [ 792.795737] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 792.809988] alg: No test for adler32 (adler32-zlib) [ 793.810336] Lustre: Lustre: Build Version: 2.16.61_42_g7dd025e [ 793.994147] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 795.647838] Key type lgssc registered [ 796.629660] Lustre: Echo OBD driver; http://www.lustre.org/ [ 833.087474] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 835.841681] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 850.512699] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 861.757154] Lustre: Mounted lustre-client [ 870.489863] LustreError: 21712:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0904abc4800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 870.510136] LustreError: 21712:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 870.564054] Lustre: Unmounted lustre-client [ 894.966597] Key type lgssc unregistered [ 895.191149] LNet: 22126:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 895.200921] LNetError: 22126:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 896.231733] LNet: Removed LNI 192.168.203.30@tcp [ 896.791659] Key type .llcrypt unregistered [ 896.799454] Key type ._llcrypt unregistered [ 905.900546] Key type ._llcrypt registered [ 905.902298] Key type .llcrypt registered [ 907.115978] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 13:01:33 (1763402493) [ 909.370676] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 909.446659] alg: No test for adler32 (adler32-zlib) [ 910.538634] Lustre: Lustre: Build Version: 2.16.61_42_g7dd025e [ 910.783342] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 912.431192] Key type lgssc registered [ 913.540488] Lustre: Echo OBD driver; http://www.lustre.org/ [ 953.845964] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 956.977712] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 973.822698] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 984.626245] Lustre: Mounted lustre-client [ 989.619130] LustreError: 24824:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa09043b39000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 989.652949] LustreError: 24824:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 989.711771] Lustre: Unmounted lustre-client [ 1016.489427] Key type lgssc unregistered [ 1016.745955] LNet: 25225:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1016.755439] LNetError: 25225:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1016.765991] LNet: Removed LNI 192.168.203.30@tcp [ 1017.361982] Key type .llcrypt unregistered [ 1017.363783] Key type ._llcrypt unregistered [ 1029.375611] Key type ._llcrypt registered [ 1029.377358] Key type .llcrypt registered [ 1030.332064] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 13:03:37 (1763402617) [ 1031.812208] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1031.868720] alg: No test for adler32 (adler32-zlib) [ 1032.886917] Lustre: Lustre: Build Version: 2.16.61_42_g7dd025e [ 1033.065349] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 1034.711186] Key type lgssc registered [ 1035.551118] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1070.251199] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1073.027703] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1087.995798] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1090.564598] Lustre: Mounted lustre-client [ 1095.652978] Lustre: lustre-MDT0000-mdc-ffffa0904acb0000: Connection to lustre-MDT0000 (at 192.168.203.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1100.772620] Lustre: lustre-MDT0001-mdc-ffffa0904acb0000: Connection to lustre-MDT0001 (at 192.168.203.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1106.902877] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1116.127141] Lustre: 26180:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763402688/real 1763402688] req@000000006e559d8e x1849061666723328/t0(0) o400->MGC192.168.203.130@tcp@192.168.203.130@tcp:26/25 lens 224/224 e 0 to 1 dl 1763402704 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1116.152285] LustreError: MGC192.168.203.130@tcp: Connection to MGS (at 192.168.203.130@tcp) was lost; in progress operations using this service will fail [ 1117.177514] LustreError: 28397:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1117.202589] Lustre: Unmounted lustre-client [ 1133.259913] Key type lgssc unregistered [ 1133.509684] LNet: 28787:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1133.529934] LNetError: 28787:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1133.553850] LNet: Removed LNI 192.168.203.30@tcp [ 1134.280242] Key type .llcrypt unregistered [ 1134.293927] Key type ._llcrypt unregistered [ 1145.837307] Key type ._llcrypt registered [ 1145.838942] Key type .llcrypt registered [ 1146.814728] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 13:05:33 (1763402733) [ 1148.504600] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1148.635694] alg: No test for adler32 (adler32-zlib) [ 1149.644684] Lustre: Lustre: Build Version: 2.16.61_42_g7dd025e [ 1149.927970] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 1151.607209] Key type lgssc registered [ 1152.695754] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1188.464367] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1191.001558] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1205.316128] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1206.836289] Lustre: Mounted lustre-client [ 1211.881453] Lustre: lustre-OST0000-osc-ffffa090475c1800: Connection to lustre-OST0000 (at 192.168.203.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1217.850809] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1228.171028] LustreError: 31903:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1228.238416] Lustre: Unmounted lustre-client [ 1253.649595] Key type lgssc unregistered [ 1253.876254] LNet: 32303:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1253.885469] LNetError: 32303:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1253.897499] LNet: Removed LNI 192.168.203.30@tcp [ 1254.524201] Key type .llcrypt unregistered [ 1254.528682] Key type ._llcrypt unregistered [ 1267.225934] Key type ._llcrypt registered [ 1267.227420] Key type .llcrypt registered [ 1268.196796] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 13:07:35 (1763402855) [ 1269.762418] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1269.877849] alg: No test for adler32 (adler32-zlib) [ 1270.907883] Lustre: Lustre: Build Version: 2.16.61_42_g7dd025e [ 1271.110566] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 1272.772765] Key type lgssc registered [ 1273.906505] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1305.539364] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1307.859189] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1321.222513] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1322.959275] Lustre: Mounted lustre-client [ 1329.247470] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1331.815369] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 1343.089241] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2025-11-1713h08m49s [ 1344.524512] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 1348.587237] Lustre: lustre-MDT0000-mdc-ffffa0904a35c000: Connection to lustre-MDT0000 (at 192.168.203.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1353.557571] LustreError: 36011:0:(lmv_obd.c:1435:lmv_statfs()) lustre-MDT0000-mdc-ffffa0904a35c000: can't stat MDS #0: rc = -19 [ 1354.707106] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 1359.858527] LustreError: MGC192.168.203.130@tcp: Connection to MGS (at 192.168.203.130@tcp) was lost; in progress operations using this service will fail [ 1359.869486] Lustre: Evicted from MGS (at 192.168.203.130@tcp) after server handle changed from 0xe3a5ada25337ebcc to 0xe3a5ada25337ed00 [ 1359.886870] Lustre: MGC192.168.203.130@tcp: Connection restored to 192.168.203.130@tcp (at 192.168.203.130@tcp) [ 1360.947119] LustreError: lustre-MDT0000-mdc-ffffa0904a35c000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 1360.978765] Lustre: lustre-MDT0000-mdc-ffffa0904a35c000: Connection restored to 192.168.203.130@tcp (at 192.168.203.130@tcp) [ 1364.917195] Lustre: DEBUG MARKER: Wait for df (36011) ... [ 1365.967326] Lustre: DEBUG MARKER: done [ 1366.956743] LustreError: 36620:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0904a35c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1366.962290] LustreError: 36620:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1367.000715] Lustre: Unmounted lustre-client [ 1396.781883] Key type lgssc unregistered [ 1396.928142] LNet: 37023:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1396.933741] LNetError: 37023:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1396.950737] LNet: Removed LNI 192.168.203.30@tcp [ 1397.371045] Key type .llcrypt unregistered [ 1397.373239] Key type ._llcrypt unregistered [ 1425.166326] Key type ._llcrypt registered [ 1425.168743] Key type .llcrypt registered [ 1425.434274] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1425.449048] alg: No test for adler32 (adler32-zlib) [ 1426.476160] Lustre: Lustre: Build Version: 2.16.61_42_g7dd025e [ 1426.659911] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 1428.304095] Key type lgssc registered [ 1429.254475] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1492.822281] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1495.795384] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1512.896962] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1558.731200] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 13:12:25 (1763403145) [ 1587.163931] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1590.727701] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1608.806581] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1614.879662] Lustre: Mounted lustre-client [ 1618.783540] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2025-11-1713h13m25s [ 1620.170036] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1626.074764] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 1628.137107] Lustre: DEBUG MARKER: Creating a test file and stat it [ 1648.607316] Lustre: 43892:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763403216/real 1763403216] req@00000000c0ae10df x1849062078813696/t0(0) o101->lustre-MDT0000-mdc-ffffa0904acb6800@192.168.203.130@tcp:12/10 lens 576/1152 e 0 to 1 dl 1763403236 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:0 [ 1648.630787] Lustre: lustre-MDT0000-mdc-ffffa0904acb6800: Connection to lustre-MDT0000 (at 192.168.203.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1648.687538] Lustre: lustre-MDT0000-mdc-ffffa0904acb6800: Connection restored to 192.168.203.130@tcp (at 192.168.203.130@tcp) [ 1669.670843] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 1671.975343] Lustre: DEBUG MARKER: done [ 1674.460897] LustreError: 44374:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0904acb6800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1674.474265] LustreError: 44374:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1674.528564] Lustre: Unmounted lustre-client [ 1699.039284] Key type lgssc unregistered [ 1699.261647] LNet: 44776:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1699.279486] LNetError: 44776:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1699.300253] LNet: Removed LNI 192.168.203.30@tcp [ 1699.860403] Key type .llcrypt unregistered [ 1699.862278] Key type ._llcrypt unregistered [ 1733.818894] Key type ._llcrypt registered [ 1733.827062] Key type .llcrypt registered [ 1734.262282] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1734.300207] alg: No test for adler32 (adler32-zlib) [ 1735.420494] Lustre: Lustre: Build Version: 2.16.61_42_g7dd025e [ 1735.656876] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 1737.335227] Key type lgssc registered [ 1738.374586] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1804.865756] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1807.958615] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1823.344284] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1856.604312] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 13:17:23 (1763403443) [ 1858.077443] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 1859.517093] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 13:17:26 (1763403446) [ 1880.911579] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 13:17:47 (1763403467) [ 1903.323725] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1906.023558] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1920.726645] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1922.418580] Lustre: Mounted lustre-client [ 1925.831665] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 1928.552444] LustreError: 52612:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0904abcf000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1928.574151] LustreError: 52612:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1928.632380] Lustre: Unmounted lustre-client [ 1938.986569] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 1980.139703] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1983.448257] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1983.991148] Lustre: Mounted lustre-client [ 1987.029250] LustreError: 53873:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa09042c57800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1987.059417] LustreError: 53873:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1987.066578] LustreError: 53873:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 1987.116455] Lustre: Unmounted lustre-client [ 2038.711357] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2041.944244] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2042.543772] Lustre: Mounted lustre-client [ 2045.003266] LustreError: 54994:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa090475c5000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2045.028522] LustreError: 54994:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2045.031673] LustreError: 54994:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 2045.096607] Lustre: Unmounted lustre-client [ 2061.318774] Lustre: DEBUG MARKER: files compared the same [ 2083.244521] Key type lgssc unregistered [ 2083.527937] LNet: 55698:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2083.538501] LNetError: 55698:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2083.571606] LNet: Removed LNI 192.168.203.30@tcp [ 2084.229932] Key type .llcrypt unregistered [ 2084.231470] Key type ._llcrypt unregistered [ 2096.177441] Key type ._llcrypt registered [ 2096.181973] Key type .llcrypt registered [ 2097.222862] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 13:21:24 (1763403684) [ 2099.434293] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2099.442769] alg: No test for adler32 (adler32-zlib) [ 2100.490846] Lustre: Lustre: Build Version: 2.16.61_42_g7dd025e [ 2100.648855] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 2102.287204] Key type lgssc registered [ 2103.166809] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2142.919513] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2146.144737] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2161.106909] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2171.944331] Lustre: Mounted lustre-client [ 2175.302569] LustreError: 58792:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa09045aea800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2175.327877] LustreError: 58792:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2175.406087] Lustre: Unmounted lustre-client [ 2201.281436] Key type lgssc unregistered [ 2201.503915] LNet: 59196:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2201.512488] LNetError: 59196:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2201.534845] LNet: Removed LNI 192.168.203.30@tcp [ 2202.241103] Key type .llcrypt unregistered [ 2202.242904] Key type ._llcrypt unregistered [ 2213.837976] Key type ._llcrypt registered [ 2213.839351] Key type .llcrypt registered [ 2215.133251] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 13:23:21 (1763403801) [ 2217.245987] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2217.280517] alg: No test for adler32 (adler32-zlib) [ 2218.414374] Lustre: Lustre: Build Version: 2.16.61_42_g7dd025e [ 2218.660523] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 2220.335610] Key type lgssc registered [ 2221.451405] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2296.820233] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2323.250744] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2326.215557] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2349.440377] Key type lgssc unregistered [ 2349.669205] LNet: 62591:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2349.680186] LNetError: 62591:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2349.699676] LNet: Removed LNI 192.168.203.30@tcp [ 2350.181707] Key type .llcrypt unregistered [ 2350.183535] Key type ._llcrypt unregistered [ 2360.853295] Key type ._llcrypt registered [ 2360.855591] Key type .llcrypt registered [ 2361.844336] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 13:25:48 (1763403948) [ 2364.168546] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2364.200100] alg: No test for adler32 (adler32-zlib) [ 2365.240460] Lustre: Lustre: Build Version: 2.16.61_42_g7dd025e [ 2365.479399] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 2367.151943] Key type lgssc registered [ 2368.137257] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2421.660107] Lustre: Mounted lustre-client [ 2428.210031] LustreError: 65077:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0904abc3800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2428.236399] LustreError: 65077:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2428.320796] Lustre: Unmounted lustre-client [ 2466.854879] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 13:27:33 (1763404053) [ 2562.460170] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2576.513982] Lustre: Mounted lustre-client [ 2582.872273] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2582.881924] LustreError: 69044:0:(file.c:249:ll_close_inode_openhandle()) lustre-clilmv-ffffa09044530000: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 2582.917635] Lustre: 63573:0:(llite_lib.c:4226:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.203.130@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 2593.207144] LustreError: 69044:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 2593.218906] LustreError: 69044:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 2593.260600] Lustre: Unmounted lustre-client [ 2634.498506] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 13:30:21 (1763404221) [ 2644.266823] Key type lgssc unregistered [ 2644.490349] LNet: 70253:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2644.500350] LNetError: 70253:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2644.526754] LNet: Removed LNI 192.168.203.30@tcp [ 2644.974548] Key type .llcrypt unregistered [ 2644.976690] Key type ._llcrypt unregistered [ 2653.459983] Key type ._llcrypt registered [ 2653.462693] Key type .llcrypt registered [ 2653.843062] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2653.852748] alg: No test for adler32 (adler32-zlib) [ 2654.916334] Lustre: Lustre: Build Version: 2.16.61_42_g7dd025e [ 2655.122671] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 2656.783484] Key type lgssc registered [ 2657.796209] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2701.999240] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2705.714662] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2723.834919] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2725.495709] Lustre: Mounted lustre-client [ 2729.742136] LustreError: 72774:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa09044523800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2729.770620] LustreError: 72774:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 2729.868227] Lustre: Unmounted lustre-client [ 2756.013262] Key type lgssc unregistered [ 2756.273957] LNet: 73177:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2756.288217] LNetError: 73177:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2756.304292] LNet: Removed LNI 192.168.203.30@tcp [ 2756.919215] Key type .llcrypt unregistered [ 2756.920869] Key type ._llcrypt unregistered [ 2766.926342] Key type ._llcrypt registered [ 2766.927833] Key type .llcrypt registered [ 2767.429321] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2767.442567] alg: No test for adler32 (adler32-zlib) [ 2768.648061] Lustre: Lustre: Build Version: 2.16.61_42_g7dd025e [ 2768.894804] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 2770.591183] Key type lgssc registered [ 2771.759487] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2865.908737] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2869.358916] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2888.112580] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2893.919629] Lustre: Mounted lustre-client [ 2895.371144] LustreError: 76119:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa09060fa1000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2895.414694] LustreError: 76119:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 2895.467657] Lustre: Unmounted lustre-client [ 2931.830413] Key type lgssc unregistered [ 2932.060977] LNet: 76523:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2932.076607] LNetError: 76523:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2932.089682] LNet: Removed LNI 192.168.203.30@tcp [ 2932.662458] Key type .llcrypt unregistered [ 2932.667526] Key type ._llcrypt unregistered [ 2946.261804] Key type ._llcrypt registered [ 2946.263457] Key type .llcrypt registered [ 2947.389488] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 13:35:34 (1763404534) [ 2949.201482] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2949.222987] alg: No test for adler32 (adler32-zlib) [ 2950.209243] Lustre: Lustre: Build Version: 2.16.61_42_g7dd025e [ 2950.400306] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 2952.103155] Key type lgssc registered [ 2953.139482] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2992.352995] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2995.512074] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3013.702850] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3019.833063] Lustre: Mounted lustre-client [ 3024.039529] LustreError: 79643:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0904894f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3024.068934] LustreError: 79643:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 3024.125042] Lustre: Unmounted lustre-client [ 3024.669880] Lustre: Mounted lustre-client [ 3026.778881] LustreError: 79723:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0904abc5000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3026.797532] LustreError: 79723:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 3026.802915] LustreError: 79723:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 3026.867416] Lustre: Unmounted lustre-client [ 3052.035256] Key type lgssc unregistered [ 3052.253920] LNet: 80125:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3052.262962] LNetError: 80125:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3052.275232] LNet: Removed LNI 192.168.203.30@tcp [ 3052.778739] Key type .llcrypt unregistered [ 3052.781522] Key type ._llcrypt unregistered [ 3063.537278] Key type ._llcrypt registered [ 3063.540121] Key type .llcrypt registered [ 3063.982548] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3063.997222] alg: No test for adler32 (adler32-zlib) [ 3065.025192] Lustre: Lustre: Build Version: 2.16.61_42_g7dd025e [ 3065.187868] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 3066.831201] Key type lgssc registered [ 3067.950262] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3106.781722] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3109.792831] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3124.660482] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3135.518351] Lustre: Mounted lustre-client [ 3138.932891] LustreError: 82671:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0904bbc0800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3138.982666] LustreError: 82671:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 3139.042841] Lustre: Unmounted lustre-client [ 3164.415792] Key type lgssc unregistered [ 3164.635556] LNet: 83071:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3164.643212] LNetError: 83071:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3164.664260] LNet: Removed LNI 192.168.203.30@tcp [ 3165.202243] Key type .llcrypt unregistered [ 3165.206059] Key type ._llcrypt unregistered [ 3175.252520] Key type ._llcrypt registered [ 3175.254181] Key type .llcrypt registered [ 3176.255417] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 13:39:23 (1763404763) [ 3178.035201] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3178.054847] alg: No test for adler32 (adler32-zlib) [ 3179.091719] Lustre: Lustre: Build Version: 2.16.61_42_g7dd025e [ 3179.291544] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 3180.943143] Key type lgssc registered [ 3181.982322] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3223.622372] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3227.016663] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3244.077408] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3251.244300] Lustre: Mounted lustre-client [ 3260.958448] Lustre: lustre: root_squash is set to 0:0 [ 3260.972987] Lustre: lustre: nosquash_nids is cleared [ 3269.155364] Lustre: lustre: root_squash is set to 500:500 [ 3276.366821] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.203.30@tcp 192.168.0.[2,10]@tcp [ 3281.522833] Lustre: lustre: nosquash_nids set to 192.168.203.30/24@tcp [ 3284.063153] LustreError: 86525:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0904abc1800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3284.093116] LustreError: 86525:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 3284.145067] Lustre: Unmounted lustre-client [ 3308.930561] Key type lgssc unregistered [ 3309.172455] LNet: 86926:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3309.179131] LNetError: 86926:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3309.195367] LNet: Removed LNI 192.168.203.30@tcp [ 3309.736251] Key type .llcrypt unregistered [ 3309.737988] Key type ._llcrypt unregistered [ 3321.774069] Key type ._llcrypt registered [ 3321.781167] Key type .llcrypt registered [ 3322.816456] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 13:41:49 (1763404909) [ 3325.215748] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 3326.850807] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 13:41:53 (1763404913) [ 3329.015150] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3329.140993] alg: No test for adler32 (adler32-zlib) [ 3330.147979] Lustre: Lustre: Build Version: 2.16.61_42_g7dd025e [ 3330.352395] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 3331.999303] Key type lgssc registered [ 3332.942090] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3374.170143] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3377.272193] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3392.054845] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3392.570062] Lustre: lustre: root_squash is set to 500:500 [ 3392.589462] Lustre: lustre: nosquash_nids set to 192.168.203.30/24@tcp [ 3402.799976] Lustre: Mounted lustre-client [ 3408.036057] LustreError: 90394:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0904acb4800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3408.075462] LustreError: 90394:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 3408.143991] Lustre: Unmounted lustre-client [ 3431.627923] Key type lgssc unregistered [ 3431.867895] LNet: 90796:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3431.871768] LNetError: 90796:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3431.881214] LNet: Removed LNI 192.168.203.30@tcp [ 3432.332833] Key type .llcrypt unregistered [ 3432.335975] Key type ._llcrypt unregistered [ 3443.154848] Key type ._llcrypt registered [ 3443.158846] Key type .llcrypt registered [ 3444.308654] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 3445.604927] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 13:43:52 (1763405032) [ 3459.934352] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3459.951694] alg: No test for adler32 (adler32-zlib) [ 3460.995761] Lustre: Lustre: Build Version: 2.16.61_42_g7dd025e [ 3461.206263] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 3462.879183] Key type lgssc registered [ 3463.949319] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3522.962662] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3525.199970] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3539.341432] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3586.578369] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3589.091689] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3601.939591] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3609.148497] Lustre: Mounted lustre-client [ 3632.010100] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffffa0904abc4000.ost_server_uuid 50 [ 3632.966203] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffffa0904abc4000.ost_server_uuid in FULL state after 0 sec [ 3633.270749] Lustre: Mounted lustre-client [ 3635.477830] LustreError: 97686:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0904782e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3635.506983] LustreError: 97686:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 3635.605089] Lustre: Unmounted lustre-client [ 3637.002549] LustreError: 97705:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0904abc4000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3637.012631] LustreError: 97705:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 3637.039782] LustreError: 97705:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 3637.042846] LustreError: 97705:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 3637.118355] Lustre: Unmounted lustre-client [ 3678.226600] Key type lgssc unregistered [ 3678.433993] LNet: 98257:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3678.445369] LNetError: 98257:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3678.480612] LNet: Removed LNI 192.168.203.30@tcp [ 3679.056742] Key type .llcrypt unregistered [ 3679.060107] Key type ._llcrypt unregistered [ 3713.395207] Key type ._llcrypt registered [ 3713.397676] Key type .llcrypt registered [ 3713.587271] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3713.606785] alg: No test for adler32 (adler32-zlib) [ 3714.678566] Lustre: Lustre: Build Version: 2.16.61_42_g7dd025e [ 3714.931873] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 3716.607172] Key type lgssc registered [ 3717.997608] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3780.716589] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3783.871972] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3800.357601] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3842.584612] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 13:50:29 (1763405429) [ 3916.975471] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3920.601808] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3937.292836] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3938.881655] Lustre: Mounted lustre-client [ 3959.199158] Lustre: 99045:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763405531/real 1763405531] req@00000000f58c130c x1849064477955200/t0(0) o400->lustre-OST0000-osc-ffffa09047644000@192.168.203.130@tcp:28/4 lens 224/224 e 0 to 1 dl 1763405547 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3959.269718] Lustre: lustre-OST0000-osc-ffffa09047644000: Connection to lustre-OST0000 (at 192.168.203.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3965.407169] Lustre: 99045:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763405537/real 1763405537] req@00000000be6c26c4 x1849064477955712/t0(0) o400->lustre-OST0000-osc-ffffa09047644000@192.168.203.130@tcp:28/4 lens 224/224 e 0 to 1 dl 1763405553 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3969.540561] Lustre: lustre-OST0000-osc-ffffa09047644000: Connection restored to 192.168.203.130@tcp (at 192.168.203.130@tcp) [ 3970.531527] Lustre: 99043:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763405542/real 1763405542] req@00000000d579ee57 x1849064477956224/t0(0) o400->lustre-OST0000-osc-ffffa09047644000@192.168.203.130@tcp:28/4 lens 224/224 e 0 to 1 dl 1763405558 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3974.640461] Lustre: lustre-MDT0000-mdc-ffffa09047644000: Connection to lustre-MDT0000 (at 192.168.203.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3989.983161] Lustre: 99045:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763405562/real 1763405562] req@000000009b2dbba8 x1849064477958272/t0(0) o400->MGC192.168.203.130@tcp@192.168.203.130@tcp:26/25 lens 224/224 e 0 to 1 dl 1763405578 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3990.016100] LustreError: MGC192.168.203.130@tcp: Connection to MGS (at 192.168.203.130@tcp) was lost; in progress operations using this service will fail [ 3990.082238] Lustre: Evicted from MGS (at 192.168.203.130@tcp) after server handle changed from 0x2c1c93e20f34f736 to 0x2c1c93e20f34f9b3 [ 3990.102104] Lustre: MGC192.168.203.130@tcp: Connection restored to 192.168.203.130@tcp (at 192.168.203.130@tcp) [ 3995.534602] Lustre: lustre-MDT0000-mdc-ffffa09047644000: Connection restored to 192.168.203.130@tcp (at 192.168.203.130@tcp) [ 3999.253901] LustreError: 106906:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa09047644000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3999.288247] LustreError: 106906:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3999.330542] Lustre: Unmounted lustre-client [ 4030.849348] Key type lgssc unregistered [ 4031.099930] LNet: 107312:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4031.112175] LNetError: 107312:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4031.141633] LNet: Removed LNI 192.168.203.30@tcp [ 4031.705676] Key type .llcrypt unregistered [ 4031.709625] Key type ._llcrypt unregistered [ 4043.041271] Key type ._llcrypt registered [ 4043.042547] Key type .llcrypt registered [ 4044.178110] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 13:53:50 (1763405630) [ 4057.864609] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4057.919520] alg: No test for adler32 (adler32-zlib) [ 4058.958646] Lustre: Lustre: Build Version: 2.16.61_42_g7dd025e [ 4059.130625] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 4060.751166] Key type lgssc registered [ 4061.860764] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4091.594787] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4163.601742] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4166.245626] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4182.647246] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4185.320144] Lustre: Mounted lustre-client [ 4186.940895] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 4188.274181] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 13:56:15 (1763405775) [ 4368.196867] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4378.480643] LustreError: 118070:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4378.508133] Lustre: Unmounted lustre-client [ 4478.740475] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4481.567433] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4499.795323] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4545.465034] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 14:02:12 (1763406132) [ 4621.382824] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4624.770921] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4644.440399] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4645.150761] Lustre: Mounted lustre-client [ 4648.908101] LustreError: 125493:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0904b5cc800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4648.940377] LustreError: 125493:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4648.942521] LustreError: 125493:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 4649.040769] Lustre: Unmounted lustre-client [ 4677.200218] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 14:04:23 (1763406263) [ 4756.156145] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4759.961899] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4778.599258] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4779.055591] Lustre: Mounted lustre-client [ 4782.151344] LustreError: 129540:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0904777a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4782.182980] LustreError: 129540:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4782.186649] LustreError: 129540:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 4782.258642] Lustre: Unmounted lustre-client [ 4818.821269] Key type lgssc unregistered [ 4819.093667] LNet: 129944:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4819.106923] LNetError: 129944:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4819.119237] LNet: Removed LNI 192.168.203.30@tcp [ 4819.841082] Key type .llcrypt unregistered [ 4819.842781] Key type ._llcrypt unregistered [ 4835.792182] Key type ._llcrypt registered [ 4835.793648] Key type .llcrypt registered [ 4836.925357] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 14:07:03 (1763406423) [ 4838.994894] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4839.028628] alg: No test for adler32 (adler32-zlib) [ 4840.179143] Lustre: Lustre: Build Version: 2.16.61_42_g7dd025e [ 4840.371604] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 4842.047180] Key type lgssc registered [ 4843.196892] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4880.577955] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4884.181610] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4900.623549] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4911.663230] Lustre: Mounted lustre-client [ 4924.298792] LustreError: 133043:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa09042c50800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4924.322247] LustreError: 133043:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4924.402986] Lustre: Unmounted lustre-client [ 4949.438798] Key type lgssc unregistered [ 4949.730876] LNet: 133445:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4949.739530] LNetError: 133445:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4949.756874] LNet: Removed LNI 192.168.203.30@tcp [ 4950.322484] Key type .llcrypt unregistered [ 4950.327275] Key type ._llcrypt unregistered [ 4963.099722] Key type ._llcrypt registered [ 4963.101982] Key type .llcrypt registered [ 4964.241935] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 14:09:11 (1763406551) [ 4966.180715] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4966.196462] alg: No test for adler32 (adler32-zlib) [ 4967.214753] Lustre: Lustre: Build Version: 2.16.61_42_g7dd025e [ 4967.399764] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 4969.058429] Key type lgssc registered [ 4970.133404] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5013.674564] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5017.005465] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5032.434758] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5039.136483] Lustre: Mounted lustre-client [ 5044.211813] Lustre: lustre-OST0000-osc-ffffa090475c3800: Connection to lustre-OST0000 (at 192.168.203.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5054.284422] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffffa090475c3800.ost_server_uuid 50 [ 5055.726940] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 5066.334387] LustreError: 136980:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa090475c3800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5066.357282] LustreError: 136980:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5066.436086] Lustre: Unmounted lustre-client [ 5086.362706] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 14:11:13 (1763406673) [ 5113.931380] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5117.208612] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5133.092460] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5150.656776] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 5151.256533] Lustre: Mounted lustre-client [ 5156.343527] Lustre: lustre-OST0000-osc-ffffa0904abcd800: Connection to lustre-OST0000 (at 192.168.203.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5176.934954] LustreError: 139637:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0904abcd800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5176.954824] LustreError: 139637:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 5176.961114] LustreError: 139637:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 5177.041112] Lustre: Unmounted lustre-client [ 5220.680092] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 14:13:26 (1763406806) [ 5253.312411] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5256.673040] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5273.902375] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5292.880517] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 5293.622762] Lustre: Mounted lustre-client [ 5298.675144] Lustre: lustre-OST0000-osc-ffffa090494f8800: Connection to lustre-OST0000 (at 192.168.203.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5314.258952] LustreError: 142398:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa090494f8800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5314.280571] LustreError: 142398:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 5314.306214] LustreError: 142398:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 5314.309181] LustreError: 142398:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 5314.402537] Lustre: Unmounted lustre-client [ 5360.432901] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 14:15:47 (1763406947) [ 5435.368920] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5438.599209] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5460.115606] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5517.112854] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5519.997846] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5534.390795] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5557.373061] Lustre: Mounted lustre-client [ 5594.211944] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5604.204936] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 8 sec [ 5606.158240] LustreError: 149053:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0904acb1000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5606.166302] LustreError: 149053:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 5606.184585] LustreError: 149053:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5606.187748] LustreError: 149053:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 5606.242797] Lustre: Unmounted lustre-client [ 5636.887802] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 14:20:23 (1763407223) [ 5663.172177] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5666.882374] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5684.727953] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5713.334928] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 5743.771547] Lustre: Mounted lustre-client [ 5785.567418] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 5787.445805] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 5790.181384] Lustre: lustre-OST0001-osc-ffffa0904abc1000: Connection to lustre-OST0001 (at 192.168.203.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5798.742962] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5809.070893] LustreError: 152638:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 5809.079033] LustreError: 152638:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 5809.103549] Lustre: Unmounted lustre-client [ 5862.888754] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 14:24:09 (1763407449) [ 5893.879153] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5897.568730] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5916.511552] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5917.117817] Lustre: Mounted lustre-client [ 5934.974458] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 5936.206326] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 5946.175786] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffffa09049bc2800.ost_server_uuid 50 [ 5947.611125] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffffa09049bc2800.ost_server_uuid in FULL state after 0 sec [ 5949.894977] LustreError: 156066:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa09049bc2800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5949.919345] LustreError: 156066:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 5949.921750] LustreError: 156066:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 5949.998233] Lustre: Unmounted lustre-client [ 5950.278854] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 5950.283575] Lustre: Skipped 3 previous similar messages [ 5950.471228] Lustre: Mounted lustre-client [ 5951.341648] LustreError: 156130:0:(sec.c:410:import_sec_validate_get()) import 000000009c0d99ea (NEW) with no sec [ 6003.354126] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 14:26:29 (1763407589) [ 6035.792563] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6039.469903] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6058.749681] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6079.693693] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 6080.165207] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 6080.372280] Lustre: Mounted lustre-client [ 6094.691134] LustreError: 158996:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0904b5c9000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6094.707342] LustreError: 158996:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6094.737898] LustreError: 158996:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 6094.741277] LustreError: 158996:0:(obd_class.h:479:obd_check_dev()) Skipped 18 previous similar messages [ 6094.811582] Lustre: Unmounted lustre-client [ 6094.824525] Lustre: Skipped 1 previous similar message [ 6128.178188] Key type lgssc unregistered [ 6128.441279] LNet: 159443:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6128.445235] LNetError: 159443:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6129.510711] LNet: Removed LNI 192.168.203.30@tcp [ 6130.118418] Key type .llcrypt unregistered [ 6130.120043] Key type ._llcrypt unregistered [ 6144.015836] Key type ._llcrypt registered [ 6144.021276] Key type .llcrypt registered [ 6145.300903] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 14:28:51 (1763407731) [ 6146.322087] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6146.349373] alg: No test for adler32 (adler32-zlib) [ 6147.621515] Lustre: Lustre: Build Version: 2.16.61_42_g7dd025e [ 6147.999333] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 6149.711238] Key type lgssc registered [ 6151.200235] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6204.365816] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6209.345058] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6231.230112] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6250.432663] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 6252.212499] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 6253.443385] Lustre: Mounted lustre-client [ 6259.258653] LustreError: lustre-MDT0001-mdc-ffffa0904abc9000: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 6259.317441] Lustre: lustre-MDT0001-mdc-ffffa0904abc9000: Connection restored to 192.168.203.130@tcp (at 192.168.203.130@tcp) [ 6264.450127] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6266.583773] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 6285.836701] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 6294.718425] LustreError: 163668:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0904abc9000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6294.732168] LustreError: 163668:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 6294.799361] Lustre: Unmounted lustre-client [ 6324.936706] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 14:31:51 (1763407911) [ 6418.632500] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6421.866678] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6442.536244] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6443.302854] Lustre: Mounted lustre-client [ 6468.069458] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 6469.963342] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6481.995678] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 6492.289071] LustreError: 168407:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 6492.305076] LustreError: 168407:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 6492.416668] Lustre: Unmounted lustre-client [ 6527.226867] Key type lgssc unregistered [ 6527.525569] LNet: 168810:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6527.535605] LNetError: 168810:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6527.567512] LNet: Removed LNI 192.168.203.30@tcp [ 6528.468432] Key type .llcrypt unregistered [ 6528.471165] Key type ._llcrypt unregistered [ 6577.123153] Key type ._llcrypt registered [ 6577.130445] Key type .llcrypt registered [ 6577.715683] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6577.729347] alg: No test for adler32 (adler32-zlib) [ 6578.764550] Lustre: Lustre: Build Version: 2.16.61_42_g7dd025e [ 6578.943330] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 6580.599381] Key type lgssc registered [ 6582.045571] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6663.197412] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6667.001973] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6685.856670] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6724.676903] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 14:38:30 (1763408310) [ 6752.674987] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6756.093571] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6772.500691] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6779.464265] Lustre: Mounted lustre-client [ 6824.705984] LustreError: 174895:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0904abca000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6824.756750] LustreError: 174895:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6824.841646] Lustre: Unmounted lustre-client [ 6861.698280] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6862.431868] Lustre: Mounted lustre-client [ 6898.896157] LustreError: 175554:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0904acb1000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6898.941145] LustreError: 175554:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6898.944300] LustreError: 175554:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 6899.036762] Lustre: Unmounted lustre-client [ 6926.734250] Key type lgssc unregistered [ 6927.016275] LNet: 175958:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6927.029139] LNetError: 175958:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6927.053336] LNet: Removed LNI 192.168.203.30@tcp [ 6927.631363] Key type .llcrypt unregistered [ 6927.634889] Key type ._llcrypt unregistered [ 6942.811203] Key type ._llcrypt registered [ 6942.812864] Key type .llcrypt registered [ 6944.174425] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 14:42:10 (1763408530) [ 6946.892928] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6946.914554] alg: No test for adler32 (adler32-zlib) [ 6947.961880] Lustre: Lustre: Build Version: 2.16.61_42_g7dd025e [ 6948.335799] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 6950.055265] Key type lgssc registered [ 6951.127488] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6995.671457] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6999.101988] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7019.962438] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7022.939756] Lustre: Mounted lustre-client [ 7042.139034] LustreError: 179218:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0904acb0000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7042.171801] LustreError: 179218:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7042.210960] Lustre: Unmounted lustre-client [ 7070.260764] Key type lgssc unregistered [ 7070.514818] LNet: 179620:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7070.534328] LNetError: 179620:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7071.591216] LNet: Removed LNI 192.168.203.30@tcp [ 7072.234682] Key type .llcrypt unregistered [ 7072.244384] Key type ._llcrypt unregistered [ 7084.406834] Key type ._llcrypt registered [ 7084.408425] Key type .llcrypt registered [ 7084.778411] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7084.788559] alg: No test for adler32 (adler32-zlib) [ 7085.780172] Lustre: Lustre: Build Version: 2.16.61_42_g7dd025e [ 7085.986682] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 7087.679826] Key type lgssc registered [ 7088.670972] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7130.913950] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7134.025386] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7152.430324] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7158.796928] Lustre: Mounted lustre-client [ 7165.778710] LustreError: 182196:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa09047646800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7165.812578] LustreError: 182196:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7165.874400] Lustre: Unmounted lustre-client [ 7194.015831] Key type lgssc unregistered [ 7194.235448] LNet: 182599:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7194.247140] LNetError: 182599:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7194.266459] LNet: Removed LNI 192.168.203.30@tcp [ 7194.844101] Key type .llcrypt unregistered [ 7194.850424] Key type ._llcrypt unregistered [ 7212.076436] Key type ._llcrypt registered [ 7212.087461] Key type .llcrypt registered [ 7213.465520] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 14:46:39 (1763408799) [ 7216.794926] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7216.851771] alg: No test for adler32 (adler32-zlib) [ 7218.000939] Lustre: Lustre: Build Version: 2.16.61_42_g7dd025e [ 7218.440895] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 7220.271231] Key type lgssc registered [ 7221.949493] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7268.989909] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7272.678123] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7294.592932] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7297.512057] Lustre: Mounted lustre-client [ 7315.084805] LustreError: 185867:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0904acb5800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7315.108513] LustreError: 185867:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7315.154716] Lustre: Unmounted lustre-client [ 7342.129662] Key type lgssc unregistered [ 7342.412023] LNet: 186269:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7342.430653] LNetError: 186269:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7342.464119] LNet: Removed LNI 192.168.203.30@tcp [ 7343.254372] Key type .llcrypt unregistered [ 7343.255991] Key type ._llcrypt unregistered [ 7355.952192] Key type ._llcrypt registered [ 7355.953812] Key type .llcrypt registered [ 7356.536908] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7356.557306] alg: No test for adler32 (adler32-zlib) [ 7357.713818] Lustre: Lustre: Build Version: 2.16.61_42_g7dd025e [ 7358.052682] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 7359.735178] Key type lgssc registered [ 7361.342690] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7404.796061] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7407.964599] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7424.222252] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7430.169696] Lustre: Mounted lustre-client [ 7436.386650] LustreError: 188845:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0904782d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7436.406921] LustreError: 188845:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7436.461066] Lustre: Unmounted lustre-client [ 7462.924912] Key type lgssc unregistered [ 7463.193619] LNet: 189248:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7463.204331] LNetError: 189248:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7463.239443] LNet: Removed LNI 192.168.203.30@tcp [ 7463.958030] Key type .llcrypt unregistered [ 7463.963291] Key type ._llcrypt unregistered [ 7481.072737] Key type ._llcrypt registered [ 7481.074241] Key type .llcrypt registered [ 7482.419649] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 14:51:08 (1763409068) [ 7501.556510] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7501.648499] alg: No test for adler32 (adler32-zlib) [ 7502.685851] Lustre: Lustre: Build Version: 2.16.61_42_g7dd025e [ 7502.881718] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 7504.535235] Key type lgssc registered [ 7505.823713] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7572.475864] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7576.260289] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7593.648801] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7633.075618] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 14:53:39 (1763409219) [ 7659.288213] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7662.660645] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7678.099923] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7689.280799] Lustre: Mounted lustre-client [ 7693.265375] LustreError: 195771:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa09044530800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7693.299268] LustreError: 195771:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7693.354874] Lustre: Unmounted lustre-client [ 7719.537388] Key type lgssc unregistered [ 7719.774245] LNet: 196173:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7719.780464] LNetError: 196173:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7719.803612] LNet: Removed LNI 192.168.203.30@tcp [ 7720.317955] Key type .llcrypt unregistered [ 7720.320487] Key type ._llcrypt unregistered [ 7733.847368] Key type ._llcrypt registered [ 7733.852679] Key type .llcrypt registered [ 7735.097922] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 7736.654727] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 14:55:23 (1763409323) [ 7751.793037] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7751.813798] alg: No test for adler32 (adler32-zlib) [ 7752.856935] Lustre: Lustre: Build Version: 2.16.61_42_g7dd025e [ 7753.022427] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 7754.671649] Key type lgssc registered [ 7755.983414] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7836.365641] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7839.656398] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7860.423338] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid [ 7876.634451] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid [ 7878.219558] Lustre: Mounted lustre-client [ 7895.158308] LustreError: 201713:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa090459fe800: lov tgt 1000 not cleaned! deathrow=0, lovrc=1 [ 7895.202952] LustreError: 201713:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7895.273808] Lustre: Unmounted lustre-client [ 8000.841127] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8003.683921] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8020.894762] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8058.466595] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 15:00:45 (1763409645) [ 8059.448061] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 8060.860870] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 15:00:47 (1763409647) [ 8106.356184] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8109.255716] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8134.337242] Key type lgssc unregistered [ 8134.630121] LNet: 207900:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8134.636605] LNetError: 207900:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8134.654741] LNet: Removed LNI 192.168.203.30@tcp [ 8135.152514] Key type .llcrypt unregistered [ 8135.154081] Key type ._llcrypt unregistered [ 8148.615318] Key type ._llcrypt registered [ 8148.616716] Key type .llcrypt registered [ 8149.762509] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 15:02:16 (1763409736) [ 8172.459829] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8172.473771] alg: No test for adler32 (adler32-zlib) [ 8173.551341] Lustre: Lustre: Build Version: 2.16.61_42_g7dd025e [ 8173.747767] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 8175.439138] Key type lgssc registered [ 8176.491446] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8208.661382] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8211.197573] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8224.989987] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8250.125438] Key type lgssc unregistered [ 8250.320796] LNet: 211529:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8250.328848] LNetError: 211529:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8250.354440] LNet: Removed LNI 192.168.203.30@tcp [ 8250.891712] Key type .llcrypt unregistered [ 8250.898147] Key type ._llcrypt unregistered [ 8263.866222] Key type ._llcrypt registered [ 8263.873370] Key type .llcrypt registered [ 8265.233898] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 15:04:11 (1763409851) [ 8267.314070] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8267.329957] alg: No test for adler32 (adler32-zlib) [ 8268.330936] Lustre: Lustre: Build Version: 2.16.61_42_g7dd025e [ 8268.555326] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 8270.255599] Key type lgssc registered [ 8271.351433] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8310.421255] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8313.466636] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8329.370486] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8340.522615] Lustre: Mounted lustre-client [ 8345.573224] Lustre: lustre-MDT0000-mdc-ffffa0904abc3800: Connection to lustre-MDT0000 (at 192.168.203.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8355.823334] Lustre: lustre-MDT0001-mdc-ffffa0904abc3800: Connection to lustre-MDT0001 (at 192.168.203.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8372.191153] Lustre: 212515:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763409943/real 1763409943] req@000000003bb3b96c x1849069253247616/t0(0) o400->MGC192.168.203.130@tcp@192.168.203.130@tcp:26/25 lens 224/224 e 0 to 1 dl 1763409959 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 8372.203660] LustreError: MGC192.168.203.130@tcp: Connection to MGS (at 192.168.203.130@tcp) was lost; in progress operations using this service will fail [ 8372.222844] Lustre: Evicted from MGS (at 192.168.203.130@tcp) after server handle changed from 0x9437414f600a2403 to 0x9437414f600a3d47 [ 8372.238208] Lustre: MGC192.168.203.130@tcp: Connection restored to 192.168.203.130@tcp (at 192.168.203.130@tcp) [ 8400.877363] LustreError: lustre-MDT0000-mdc-ffffa0904abc3800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 8400.922081] Lustre: lustre-MDT0000-mdc-ffffa0904abc3800: Connection restored to 192.168.203.130@tcp (at 192.168.203.130@tcp) [ 8401.451279] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8402.906913] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 8405.547715] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8406.922508] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 8408.972495] LustreError: 215942:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0904abc3800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8408.993841] LustreError: 215942:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 8409.043135] Lustre: Unmounted lustre-client [ 8432.958596] Key type lgssc unregistered [ 8433.116579] LNet: 216344:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8433.121065] LNetError: 216344:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8433.142228] LNet: Removed LNI 192.168.203.30@tcp [ 8433.654653] Key type .llcrypt unregistered [ 8433.657803] Key type ._llcrypt unregistered [ 8444.803363] Key type ._llcrypt registered [ 8444.806420] Key type .llcrypt registered [ 8445.872279] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 15:07:12 (1763410032) [ 8447.253229] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8447.274054] alg: No test for adler32 (adler32-zlib) [ 8448.284942] Lustre: Lustre: Build Version: 2.16.61_42_g7dd025e [ 8448.472650] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 8450.103170] Key type lgssc registered [ 8451.122473] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8491.462115] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8495.073460] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8537.442687] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8540.658962] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8564.942615] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8579.433237] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 8621.742968] Key type lgssc unregistered [ 8621.963974] LNet: 221454:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8621.972111] LNetError: 221454:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8621.988547] LNet: Removed LNI 192.168.203.30@tcp [ 8622.532583] Key type .llcrypt unregistered [ 8622.534213] Key type ._llcrypt unregistered [ 8656.476480] Key type ._llcrypt registered [ 8656.481312] Key type .llcrypt registered [ 8656.750319] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8656.759585] alg: No test for adler32 (adler32-zlib) [ 8657.795713] Lustre: Lustre: Build Version: 2.16.61_42_g7dd025e [ 8657.989350] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 8659.641151] Key type lgssc registered [ 8660.629277] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8727.235515] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8730.139259] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8746.553850] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8783.026206] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 15:12:49 (1763410369) [ 8880.220076] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8883.386131] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8901.615946] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8938.982994] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 15:15:25 (1763410525) [ 8946.558456] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 15:15:32 (1763410532) [ 8977.080774] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8980.199207] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8996.394844] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9003.556234] Lustre: Mounted lustre-client [ 9008.847152] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [ 9010.775295] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 9012.608971] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 9014.033242] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [ 9018.860833] Lustre: lustre-MDT0000-mdc-ffffa09046a8c800: Connection to lustre-MDT0000 (at 192.168.203.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9034.207213] Lustre: 222239:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763410606/real 1763410606] req@000000002702927c x1849069662119936/t0(0) o400->MGC192.168.203.130@tcp@192.168.203.130@tcp:26/25 lens 224/224 e 0 to 1 dl 1763410622 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 9034.247996] LustreError: MGC192.168.203.130@tcp: Connection to MGS (at 192.168.203.130@tcp) was lost; in progress operations using this service will fail [ 9044.468870] Lustre: Evicted from MGS (at 192.168.203.130@tcp) after server handle changed from 0xc206a1d5c879150b to 0xc206a1d5c8791773 [ 9044.481841] Lustre: MGC192.168.203.130@tcp: Connection restored to 192.168.203.130@tcp (at 192.168.203.130@tcp) [ 9048.437915] Lustre: lustre-MDT0000-mdc-ffffa09046a8c800: Connection restored to 192.168.203.130@tcp (at 192.168.203.130@tcp) [ 9053.208560] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9054.735891] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9057.888547] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [ 9059.814846] Lustre: lustre-MDT0000-mdc-ffffa09046a8c800: Connection to lustre-MDT0000 (at 192.168.203.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9075.169675] Lustre: 222238:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763410647/real 1763410647] req@00000000b30f5fc5 x1849069662127744/t0(0) o400->MGC192.168.203.130@tcp@192.168.203.130@tcp:26/25 lens 224/224 e 0 to 1 dl 1763410663 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 9075.194129] LustreError: MGC192.168.203.130@tcp: Connection to MGS (at 192.168.203.130@tcp) was lost; in progress operations using this service will fail [ 9083.240754] Lustre: lustre-MDT0000-mdc-ffffa09046a8c800: Connection restored to 192.168.203.130@tcp (at 192.168.203.130@tcp) [ 9085.481526] Lustre: Evicted from MGS (at 192.168.203.130@tcp) after server handle changed from 0xc206a1d5c8791773 to 0xc206a1d5c8791b6a [ 9085.507504] Lustre: MGC192.168.203.130@tcp: Connection restored to 192.168.203.130@tcp (at 192.168.203.130@tcp) [ 9089.353604] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9090.811298] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9093.966677] LustreError: 235085:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa09046a8c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9093.985116] LustreError: 235085:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 9094.056554] Lustre: Unmounted lustre-client [ 9157.887637] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9161.640206] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9178.921294] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9184.797526] Lustre: Mounted lustre-client [ 9186.023717] LustreError: 236710:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0904ad18800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9186.042820] LustreError: 236710:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 9186.045737] LustreError: 236710:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 9186.132159] Lustre: Unmounted lustre-client [ 9219.929667] Key type lgssc unregistered [ 9220.180101] LNet: 237112:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9220.193993] LNetError: 237112:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9220.221341] LNet: Removed LNI 192.168.203.30@tcp [ 9220.899484] Key type .llcrypt unregistered [ 9220.901046] Key type ._llcrypt unregistered [ 9237.428277] Key type ._llcrypt registered [ 9237.442171] Key type .llcrypt registered [ 9238.928254] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 15:20:25 (1763410825) [ 9242.834149] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9242.855734] alg: No test for adler32 (adler32-zlib) [ 9243.910832] Lustre: Lustre: Build Version: 2.16.61_42_g7dd025e [ 9244.095237] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 9245.735180] Key type lgssc registered [ 9246.819291] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9289.825895] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9294.280656] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9313.975405] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9316.709640] Lustre: Mounted lustre-client [ 9322.144832] LustreError: 240284:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa090458a6800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9322.161854] LustreError: 240284:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 9322.215972] Lustre: Unmounted lustre-client [ 9387.267146] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9391.166719] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9411.608927] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9412.454184] Lustre: Mounted lustre-client [ 9421.124042] LustreError: 241887:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa09042c52800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9421.161641] LustreError: 241887:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 9421.170632] LustreError: 241887:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 9421.262956] Lustre: Unmounted lustre-client [ 9489.653096] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9492.408178] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9508.280364] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9514.029266] Lustre: Mounted lustre-client [ 9515.125797] LustreError: 243510:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa09045b35000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9515.153487] LustreError: 243510:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 9515.160518] LustreError: 243510:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 9515.221290] Lustre: Unmounted lustre-client [ 9542.044142] Key type lgssc unregistered [ 9542.355479] LNet: 243911:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9542.368760] LNetError: 243911:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9543.411900] LNet: Removed LNI 192.168.203.30@tcp [ 9544.288855] Key type .llcrypt unregistered [ 9544.296685] Key type ._llcrypt unregistered [ 9558.775326] Key type ._llcrypt registered [ 9558.778485] Key type .llcrypt registered [ 9559.974387] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 15:25:46 (1763411146) [ 9562.887650] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9562.894084] alg: No test for adler32 (adler32-zlib) [ 9563.977870] Lustre: Lustre: Build Version: 2.16.61_42_g7dd025e [ 9564.181386] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 9565.815788] Key type lgssc registered [ 9567.007828] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9608.331487] Key type lgssc unregistered [ 9608.628676] LNet: 246242:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9608.638686] LNetError: 246242:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9608.651555] LNet: Removed LNI 192.168.203.30@tcp [ 9609.405742] Key type .llcrypt unregistered [ 9609.423827] Key type ._llcrypt unregistered [ 9635.229474] Key type ._llcrypt registered [ 9635.231241] Key type .llcrypt registered [ 9635.393539] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9635.400907] alg: No test for adler32 (adler32-zlib) [ 9636.416795] Lustre: Lustre: Build Version: 2.16.61_42_g7dd025e [ 9636.648950] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 9638.319172] Key type lgssc registered [ 9639.811072] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9720.160626] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9724.283994] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9745.112397] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9789.630616] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [ 9791.854643] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 15:29:38 (1763411378) [ 9822.811848] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9826.397611] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9844.698435] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9863.628737] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 9865.459722] Lustre: Mounted lustre-client [ 9870.829092] Lustre: lustre-OST0001-osc-ffffa09042c54000: Connection to lustre-OST0001 (at 192.168.203.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9877.207190] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 9887.487172] LustreError: 252756:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 9887.549779] Lustre: Unmounted lustre-client [ 9929.601780] Key type lgssc unregistered [ 9929.838949] LNet: 253160:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9929.846607] LNetError: 253160:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9929.866211] LNet: Removed LNI 192.168.203.30@tcp [ 9930.489066] Key type .llcrypt unregistered [ 9930.490851] Key type ._llcrypt unregistered [ 9967.467434] Key type ._llcrypt registered [ 9967.469197] Key type .llcrypt registered [ 9967.930082] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9967.953071] alg: No test for adler32 (adler32-zlib) [ 9968.976565] Lustre: Lustre: Build Version: 2.16.61_42_g7dd025e [ 9969.290471] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 9970.975214] Key type lgssc registered [ 9972.305490] Lustre: Echo OBD driver; http://www.lustre.org/ [10040.252446] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10043.536407] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10064.824378] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10110.915642] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 15:34:57 (1763411697) [10149.113479] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10152.555238] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10172.013653] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 15:35:58 (1763411758) [10199.314754] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10202.515916] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10219.751744] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10225.733630] Lustre: Mounted lustre-client [10237.453729] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [10241.669879] LustreError: 260999:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [10241.714898] Lustre: Unmounted lustre-client [10333.611930] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10337.801472] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10383.921612] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10384.395628] Lustre: Mounted lustre-client [10386.075207] LustreError: 263049:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa09044532000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10386.124790] LustreError: 263049:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [10386.128233] LustreError: 263049:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [10386.233334] Lustre: Unmounted lustre-client [10411.781713] Key type lgssc unregistered [10412.009985] LNet: 263451:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10412.017448] LNetError: 263451:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10412.028669] LNet: Removed LNI 192.168.203.30@tcp [10412.645594] Key type .llcrypt unregistered [10412.654156] Key type ._llcrypt unregistered [10437.189379] Key type ._llcrypt registered [10437.191414] Key type .llcrypt registered [10437.609892] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10437.624281] alg: No test for adler32 (adler32-zlib) [10438.649921] Lustre: Lustre: Build Version: 2.16.61_42_g7dd025e [10438.889466] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [10440.576916] Key type lgssc registered [10441.839844] Lustre: Echo OBD driver; http://www.lustre.org/ [10513.946906] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10517.638773] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10537.931674] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10575.343259] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [10576.931271] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 15:42:43 (1763412163) [10605.941365] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10609.608054] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10625.721818] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10629.434035] Lustre: Mounted lustre-client [10631.543069] LustreError: 269505:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa09044533000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10631.588748] LustreError: 269505:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [10631.669865] Lustre: Unmounted lustre-client [10657.325871] Key type lgssc unregistered [10657.543294] LNet: 269907:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10657.555985] LNetError: 269907:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10658.599379] LNet: Removed LNI 192.168.203.30@tcp [10659.285918] Key type .llcrypt unregistered [10659.296663] Key type ._llcrypt unregistered [10671.793417] Key type ._llcrypt registered [10671.795408] Key type .llcrypt registered [10672.979327] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [10674.867362] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 15:44:21 (1763412261) [10682.462105] Key type .llcrypt unregistered [10682.464901] Key type ._llcrypt unregistered [10689.257545] Key type ._llcrypt registered [10689.260979] Key type .llcrypt registered [10689.620323] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10689.636592] alg: No test for adler32 (adler32-zlib) [10690.719920] Lustre: Lustre: Build Version: 2.16.61_42_g7dd025e [10690.997603] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [10692.647172] Key type lgssc registered [10693.821454] Lustre: Echo OBD driver; http://www.lustre.org/ [10736.805304] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10754.067223] Lustre: Mounted lustre-client [10755.510696] LustreError: 272920:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa09043479000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10755.526279] LustreError: 272920:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [10755.588280] Lustre: Unmounted lustre-client [10779.961874] Key type lgssc unregistered [10780.158803] LNet: 273322:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10780.176368] LNetError: 273322:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10780.194164] LNet: Removed LNI 192.168.203.30@tcp [10780.850669] Key type .llcrypt unregistered [10780.858213] Key type ._llcrypt unregistered [10792.533984] Key type ._llcrypt registered [10792.535581] Key type .llcrypt registered [10793.736900] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 15:46:20 (1763412380) [10795.538700] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10795.551355] alg: No test for adler32 (adler32-zlib) [10796.594511] Lustre: Lustre: Build Version: 2.16.61_42_g7dd025e [10796.745471] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [10798.399406] Key type lgssc registered [10799.681271] Lustre: Echo OBD driver; http://www.lustre.org/ [10873.498252] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10894.695274] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10897.005496] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10899.660518] Lustre: Mounted lustre-client [10901.310419] LustreError: 276370:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa09044851000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10901.321950] LustreError: 276370:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [10901.379062] Lustre: Unmounted lustre-client [10923.919619] Key type lgssc unregistered [10924.073522] LNet: 276771:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10924.078792] LNetError: 276771:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10925.095778] LNet: Removed LNI 192.168.203.30@tcp [10925.554197] Key type .llcrypt unregistered [10925.556639] Key type ._llcrypt unregistered [10936.181268] Key type ._llcrypt registered [10936.182902] Key type .llcrypt registered [10937.216719] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 15:48:44 (1763412524) [10938.776687] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10938.825285] alg: No test for adler32 (adler32-zlib) [10939.890332] Lustre: Lustre: Build Version: 2.16.61_42_g7dd025e [10940.068749] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [10941.727173] Key type lgssc registered [10942.704348] Lustre: Echo OBD driver; http://www.lustre.org/ [10981.384915] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10984.449300] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10999.142740] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11010.099520] Lustre: Mounted lustre-client [11015.145091] Lustre: lustre-MDT0000-mdc-ffffa0904abc5000: Connection to lustre-MDT0000 (at 192.168.203.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [11020.114182] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [11020.137639] LustreError: 279838:0:(file.c:6099:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [11020.394145] LustreError: 279857:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0904abc5000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11020.404674] LustreError: MGC192.168.203.130@tcp: Connection to MGS (at 192.168.203.130@tcp) was lost; in progress operations using this service will fail [11020.413217] LustreError: 279857:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [11020.447988] Lustre: Unmounted lustre-client [11037.873919] Key type lgssc unregistered [11038.119898] LNet: 280251:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11038.130380] LNetError: 280251:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11038.166662] LNet: Removed LNI 192.168.203.30@tcp [11038.782258] Key type .llcrypt unregistered [11038.784099] Key type ._llcrypt unregistered [11051.104593] Key type ._llcrypt registered [11051.106407] Key type .llcrypt registered [11052.247487] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 15:50:38 (1763412638) [11053.962830] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11054.001948] alg: No test for adler32 (adler32-zlib) [11055.046377] Lustre: Lustre: Build Version: 2.16.61_42_g7dd025e [11055.238389] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [11056.927461] Key type lgssc registered [11057.902877] Lustre: Echo OBD driver; http://www.lustre.org/ [11096.427422] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11099.474558] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11113.592770] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11124.250443] Lustre: Mounted lustre-client [11129.323210] Lustre: lustre-MDT0001-mdc-ffffa09044536000: Connection to lustre-MDT0001 (at 192.168.203.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [11133.934249] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [11135.151980] LustreError: 283346:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa09044536000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11135.176818] LustreError: 283346:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [11135.228218] Lustre: Unmounted lustre-client [11153.041218] Key type lgssc unregistered [11153.295312] LNet: 283738:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11153.301946] LNetError: 283738:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11153.320490] LNet: Removed LNI 192.168.203.30@tcp [11154.084943] Key type .llcrypt unregistered [11154.091101] Key type ._llcrypt unregistered [11166.174956] Key type ._llcrypt registered [11166.177653] Key type .llcrypt registered [11167.102224] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 15:52:34 (1763412754) [11179.790024] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11179.803819] alg: No test for adler32 (adler32-zlib) [11180.762761] Lustre: Lustre: Build Version: 2.16.61_42_g7dd025e [11180.994246] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [11182.663342] Key type lgssc registered [11183.585164] Lustre: Echo OBD driver; http://www.lustre.org/ [11273.446323] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11275.065955] Lustre: Mounted lustre-client [11308.525848] Lustre: lustre-MDT0001-mdc-ffffa0904b163800: Connection to lustre-MDT0001 (at 192.168.203.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [11317.223322] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [11327.472316] LustreError: 289100:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [11327.511452] Lustre: Unmounted lustre-client [11370.280757] Key type lgssc unregistered [11370.540404] LNet: 289504:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11370.548264] LNetError: 289504:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11371.557575] LNet: Removed LNI 192.168.203.30@tcp [11372.139472] Key type .llcrypt unregistered [11372.141153] Key type ._llcrypt unregistered [11385.165626] Key type ._llcrypt registered [11385.167438] Key type .llcrypt registered [11386.229462] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 15:56:13 (1763412973) [11387.855110] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [11389.617281] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 15:56:16 (1763412976) [11391.260234] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [11392.896386] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 15:56:19 (1763412979) [11394.478123] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [11396.046483] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 15:56:22 (1763412982) [11397.637522] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [11399.302987] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 15:56:26 (1763412986) [11400.814967] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [11402.282581] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 15:56:29 (1763412989) [11409.369353] Key type .llcrypt unregistered [11409.370964] Key type ._llcrypt unregistered [11414.570097] Key type ._llcrypt registered [11414.571764] Key type .llcrypt registered [11414.887415] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11414.908570] alg: No test for adler32 (adler32-zlib) [11416.023978] Lustre: Lustre: Build Version: 2.16.61_42_g7dd025e [11416.205108] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [11417.863240] Key type lgssc registered [11419.341639] Lustre: Echo OBD driver; http://www.lustre.org/ [11480.092288] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11482.949485] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11500.530270] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11502.270970] Lustre: Mounted lustre-client [11504.316191] LustreError: 295100:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa09042a26800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11504.334827] LustreError: 295100:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [11504.394962] Lustre: Unmounted lustre-client [11536.954707] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 15:58:43 (1763413123) [11565.875915] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11569.185569] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11584.939426] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11595.801333] Lustre: Mounted lustre-client [11596.732071] LustreError: 297223:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa090431b3000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11596.762654] LustreError: 297223:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [11596.767748] LustreError: 297223:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [11596.832825] Lustre: Unmounted lustre-client [11624.536283] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 16:00:11 (1763413211) [11641.504771] LNet: 1 local NIs in recovery (showing 1): 192.168.203.30@tcp [11694.609763] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11697.486855] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11712.170290] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11791.628075] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 16:02:58 (1763413378) [11800.343162] Key type lgssc unregistered [11800.526080] LNet: 302687:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11800.543723] LNetError: 302687:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11800.563927] LNet: Removed LNI 192.168.203.30@tcp [11801.144741] Key type .llcrypt unregistered [11801.146765] Key type ._llcrypt unregistered [11851.960764] Key type ._llcrypt registered [11851.964260] Key type .llcrypt registered [11852.242327] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11852.268394] alg: No test for adler32 (adler32-zlib) [11853.344398] Lustre: Lustre: Build Version: 2.16.61_42_g7dd025e [11853.543615] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [11855.215177] Key type lgssc registered [11856.204432] Lustre: Echo OBD driver; http://www.lustre.org/ [11930.093703] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11933.301517] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11951.796320] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11981.250880] Key type lgssc unregistered [11981.493976] LNet: 306282:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11981.511290] LNetError: 306282:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11981.541935] LNet: Removed LNI 192.168.203.30@tcp [11982.173534] Key type .llcrypt unregistered [11982.175182] Key type ._llcrypt unregistered [12005.173881] Key type ._llcrypt registered [12005.175543] Key type .llcrypt registered [12006.227921] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 16:06:33 (1763413593) [12021.902189] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12021.921366] alg: No test for adler32 (adler32-zlib) [12022.895477] Lustre: Lustre: Build Version: 2.16.61_42_g7dd025e [12023.162494] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [12024.823126] Key type lgssc registered [12025.731411] Lustre: Echo OBD driver; http://www.lustre.org/ [12073.997717] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 16:07:40 (1763413660) [12085.675299] Key type lgssc unregistered [12085.895750] LNet: 309832:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12085.900630] LNetError: 309832:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12085.925820] LNet: Removed LNI 192.168.203.30@tcp [12086.450556] Key type .llcrypt unregistered [12086.453835] Key type ._llcrypt unregistered [12094.590982] Key type ._llcrypt registered [12094.592684] Key type .llcrypt registered [12094.963176] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12094.974726] alg: No test for adler32 (adler32-zlib) [12096.098544] Lustre: Lustre: Build Version: 2.16.61_42_g7dd025e [12096.376482] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [12098.057400] Key type lgssc registered [12099.070533] Lustre: Echo OBD driver; http://www.lustre.org/ [12144.734251] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12147.771663] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12166.185488] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12408.817159] LNet: Added LNI 192.168.203.30@tcp50 [8/256/0/180] [12411.271732] LNet: Removed LNI 192.168.203.30@tcp [12413.403221] Lustre: 314142:0:(ldlm_lib.c:563:client_obd_setup()) lustre-MDT0000-mdc-ffffa0904b4f8000: config has no valid NIDs, force dynamic NIDs [12414.618236] Lustre: Mounted lustre-client [12414.664847] LustreError: 314158:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0904b4f8000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12414.689504] LustreError: 314158:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [12414.741386] Lustre: Unmounted lustre-client [12444.659948] Key type lgssc unregistered [12444.997442] LNet: 314997:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12445.009384] LNetError: 314997:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12445.026747] LNet: Removed LNI 192.168.203.30@tcp50 [12445.500651] Key type .llcrypt unregistered [12445.506477] Key type ._llcrypt unregistered [12477.255874] Key type ._llcrypt registered [12477.259528] Key type .llcrypt registered [12478.532156] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 16:14:25 (1763414065) [12480.532287] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12480.591995] alg: No test for adler32 (adler32-zlib) [12481.517799] Lustre: Lustre: Build Version: 2.16.61_42_g7dd025e [12481.668934] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [12483.319275] Key type lgssc registered [12484.608106] Lustre: Echo OBD driver; http://www.lustre.org/ [12532.131885] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12535.884063] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12552.409634] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12558.376833] Lustre: Mounted lustre-client [12612.813221] Lustre: Mounted fs15246-client [12627.156173] LustreError: 319141:0:(lov_obd.c:783:lov_cleanup()) fs15246-clilov-ffffa0904abca800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12627.203542] LustreError: 319141:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [12627.262704] Lustre: Unmounted fs15246-client [12648.886218] LustreError: 319358:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa09044533800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12648.900535] LustreError: 319358:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [12648.903558] LustreError: 319358:0:(obd_class.h:479:obd_check_dev()) Skipped 3 previous similar messages [12648.944745] Lustre: Unmounted lustre-client [12681.222268] Key type lgssc unregistered [12681.466671] LNet: 319760:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12681.474502] LNetError: 319760:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12681.499824] LNet: Removed LNI 192.168.203.30@tcp [12682.315167] Key type .llcrypt unregistered [12682.317107] Key type ._llcrypt unregistered [12691.849263] Key type ._llcrypt registered [12691.852433] Key type .llcrypt registered [12693.222548] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 16:17:59 (1763414279) [12694.688982] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12694.701648] alg: No test for adler32 (adler32-zlib) [12695.680522] Lustre: Lustre: Build Version: 2.16.61_42_g7dd025e [12695.869353] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [12697.576190] Key type lgssc registered [12698.571418] Lustre: Echo OBD driver; http://www.lustre.org/ [12820.068904] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12823.301398] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12840.684409] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12927.887667] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 12633 sec ========= 16:21:54 (1763414514) [12929.189882] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 16:21:56 (1763414516) === [12932.004526] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 16:21:58 (1763414518) === [12960.545759] Key type lgssc unregistered [12960.821132] LNet: 328926:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12960.835985] LNetError: 328926:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12960.858527] LNet: Removed LNI 192.168.203.30@tcp [12961.473643] Key type .llcrypt unregistered [12961.475410] Key type ._llcrypt unregistered