[ 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 398035664 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 2560MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001009] APIC: Switch to symmetric I/O mode setup [ 0.002241] x2apic enabled [ 0.003004] Switched APIC routing to physical x2apic. [ 0.004006] kvm-guest: setup PV IPIs [ 0.006000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.006000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.006008] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.007004] pid_max: default: 32768 minimum: 301 [ 0.008080] LSM: Security Framework initializing [ 0.009024] Yama: becoming mindful. [ 0.009719] SELinux: Initializing. [ 0.010037] *** VALIDATE selinux *** [ 0.017410] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.020650] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.021086] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.022061] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.024008] *** VALIDATE tmpfs *** [ 0.024974] *** VALIDATE proc *** [ 0.025141] *** VALIDATE cgroup *** [ 0.026004] *** VALIDATE cgroup2 *** [ 0.027173] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.028094] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.029002] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.030016] Spectre V2 : User space: Vulnerable [ 0.031003] Speculative Store Bypass: Vulnerable [ 0.033554] debug: unmapping init [mem 0xffffffffb5c59000-0xffffffffb5c60fff] [ 0.035865] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.036421] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.037009] ... version: 2 [ 0.037836] ... bit width: 48 [ 0.038006] ... generic registers: 4 [ 0.038750] ... value mask: 0000ffffffffffff [ 0.039005] ... max period: 00007fffffffffff [ 0.040005] ... fixed-purpose events: 3 [ 0.040760] ... event mask: 000000070000000f [ 0.041140] rcu: Hierarchical SRCU implementation. [ 0.043088] smp: Bringing up secondary CPUs ... [ 0.044371] x86: Booting SMP configuration: [ 0.045014] .... node #0, CPUs: #1 #2 #3 [ 0.047636] smp: Brought up 1 node, 4 CPUs [ 0.048838] smpboot: Max logical packages: 1 [ 0.049006] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.225685] node 0 deferred pages initialised in 175ms [ 0.228232] devtmpfs: initialized [ 0.229162] x86/mm: Memory block size: 128MB [ 0.230941] gcov: version magic: 0x41383552 [ 0.233272] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.234063] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.235337] pinctrl core: initialized pinctrl subsystem [ 0.236119] [ 0.236433] ************************************************************* [ 0.238007] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.240007] ** ** [ 0.241016] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.242006] ** ** [ 0.244007] ** This means that this kernel is built to expose internal ** [ 0.245006] ** IOMMU data structures, which may compromise security on ** [ 0.247006] ** your system. ** [ 0.248006] ** ** [ 0.249006] ** If you see this message and you are not debugging the ** [ 0.251007] ** kernel, report this immediately to your vendor! ** [ 0.252005] ** ** [ 0.254011] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.255005] ************************************************************* [ 0.256655] NET: Registered protocol family 16 [ 0.258339] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.259031] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.261029] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.263435] cpuidle: using governor menu [ 0.264471] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.266293] PCI: Using configuration type 1 for base access [ 0.267084] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.273068] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.274026] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.276091] cryptd: max_cpu_qlen set to 1000 [ 0.278153] ACPI: Added _OSI(Module Device) [ 0.279007] ACPI: Added _OSI(Processor Device) [ 0.279858] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.281007] ACPI: Added _OSI(Processor Aggregator Device) [ 0.283682] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.286362] ACPI: Interpreter enabled [ 0.288036] ACPI: PM: (supports S0 S3 S4 S5) [ 0.288935] ACPI: Using IOAPIC for interrupt routing [ 0.289048] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.291224] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.299338] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.301017] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.302008] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.304039] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.306856] acpiphp: Slot [2] registered [ 0.308054] acpiphp: Slot [3] registered [ 0.308904] acpiphp: Slot [4] registered [ 0.310052] acpiphp: Slot [5] registered [ 0.310885] acpiphp: Slot [6] registered [ 0.311054] acpiphp: Slot [7] registered [ 0.311959] acpiphp: Slot [8] registered [ 0.313058] acpiphp: Slot [9] registered [ 0.313883] acpiphp: Slot [10] registered [ 0.315062] acpiphp: Slot [11] registered [ 0.316083] acpiphp: Slot [12] registered [ 0.318081] acpiphp: Slot [13] registered [ 0.320070] acpiphp: Slot [14] registered [ 0.322081] acpiphp: Slot [15] registered [ 0.323070] acpiphp: Slot [16] registered [ 0.324078] acpiphp: Slot [17] registered [ 0.326064] acpiphp: Slot [18] registered [ 0.327084] acpiphp: Slot [19] registered [ 0.328065] acpiphp: Slot [20] registered [ 0.330064] acpiphp: Slot [21] registered [ 0.331069] acpiphp: Slot [22] registered [ 0.332067] acpiphp: Slot [23] registered [ 0.334063] acpiphp: Slot [24] registered [ 0.335081] acpiphp: Slot [25] registered [ 0.337085] acpiphp: Slot [26] registered [ 0.338117] acpiphp: Slot [27] registered [ 0.340072] acpiphp: Slot [28] registered [ 0.341067] acpiphp: Slot [29] registered [ 0.343064] acpiphp: Slot [30] registered [ 0.344067] acpiphp: Slot [31] registered [ 0.346080] PCI host bridge to bus 0000:00 [ 0.348013] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.350010] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.352011] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.355009] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.357010] pci_bus 0000:00: root bus resource [mem 0x180000000-0x1ffffffff window] [ 0.360015] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.362170] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.366326] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.370376] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.378012] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.382049] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.385010] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.387011] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.390009] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.392332] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.395810] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.398027] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.401647] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.406010] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.415741] pci 0000:00:02.0: reg 0x20: [mem 0xfebf4000-0xfebf7fff 64bit pref] [ 0.420009] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.425487] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.430020] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.435013] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.447013] pci 0000:00:05.0: reg 0x20: [mem 0xfebf8000-0xfebfbfff 64bit pref] [ 0.452740] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.456015] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.462014] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.472015] pci 0000:00:06.0: reg 0x20: [mem 0xfebfc000-0xfebfffff 64bit pref] [ 0.480350] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.481234] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.483200] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.484238] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.486108] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.490064] iommu: Default domain type: Passthrough [ 0.491381] SCSI subsystem initialized [ 0.492067] ACPI: bus type USB registered [ 0.492973] usbcore: registered new interface driver usbfs [ 0.494036] usbcore: registered new interface driver hub [ 0.496038] usbcore: registered new device driver usb [ 0.497109] pps_core: LinuxPPS API ver. 1 registered [ 0.498006] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.499019] PTP clock support registered [ 0.501076] EDAC MC: Ver: 3.0.0 [ 0.502097] PCI: Using ACPI for IRQ routing [ 0.503512] NetLabel: Initializing [ 0.504007] NetLabel: domain hash size = 128 [ 0.504961] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.506039] NetLabel: unlabeled traffic allowed by default [ 0.508075] vgaarb: loaded [ 0.509181] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.510007] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.515009] clocksource: Switched to clocksource kvm-clock [ 0.597876] VFS: Disk quotas dquot_6.6.0 [ 0.601512] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.606829] *** VALIDATE ramfs *** [ 0.610218] *** VALIDATE hugetlbfs *** [ 0.614098] pnp: PnP ACPI init [ 0.622279] pnp: PnP ACPI: found 6 devices [ 0.642131] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.644031] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.645329] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.646517] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.647874] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.649207] pci_bus 0000:00: resource 8 [mem 0x180000000-0x1ffffffff window] [ 0.650650] NET: Registered protocol family 2 [ 0.652524] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.655664] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.657832] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.661512] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.663617] TCP: Hash tables configured (established 65536 bind 65536) [ 0.665470] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.667401] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.669268] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.671292] NET: Registered protocol family 1 [ 0.672924] RPC: Registered named UNIX socket transport module. [ 0.674237] RPC: Registered udp transport module. [ 0.675236] RPC: Registered tcp transport module. [ 0.676261] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.678271] NET: Registered protocol family 44 [ 0.679275] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.680637] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.683078] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.685640] PCI: CLS 0 bytes, default 64 [ 0.687520] Unpacking initramfs... [ 2.110904] debug: unmapping init [mem 0xffff95cb7cc64000-0xffff95cb7ffcffff] [ 2.115876] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.118392] software IO TLB: mapped [mem 0x00000000b8c64000-0x00000000bcc64000] (64MB) [ 2.121491] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.650635] Initialise system trusted keyrings [ 2.652383] Key type blacklist registered [ 2.658468] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.669762] zbud: loaded [ 2.673716] *** VALIDATE nfs *** [ 2.674704] *** VALIDATE nfs4 *** [ 2.677572] pstore: using deflate compression [ 2.685312] Platform Keyring initialized [ 2.810739] NET: Registered protocol family 38 [ 2.812518] Key type asymmetric registered [ 2.813701] Asymmetric key parser 'x509' registered [ 2.815086] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.818089] io scheduler mq-deadline registered [ 2.819430] io scheduler kyber registered [ 2.821029] io scheduler bfq registered [ 2.822851] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.826265] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.828518] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.832303] ACPI: Power Button [PWRF] [ 2.941756] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.058565] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.182528] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.216446] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.253209] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.260386] Non-volatile memory driver v1.3 [ 3.262897] Linux agpgart interface v0.103 [ 3.343552] virtio_blk virtio1: [vda] 132696 512-byte logical blocks (67.9 MB/64.8 MiB) [ 3.352658] vda: detected capacity change from 0 to 67940352 [ 3.372304] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.375873] vdb: detected capacity change from 0 to 1073741824 [ 3.382103] libphy: Fixed MDIO Bus: probed [ 3.394433] usbcore: registered new interface driver usbserial_generic [ 3.396792] usbserial: USB Serial support registered for generic [ 3.399138] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.404148] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.405536] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.407525] mousedev: PS/2 mouse device common for all mice [ 3.409860] rtc_cmos 00:05: RTC can wake from S4 [ 3.414038] rtc_cmos 00:05: registered as rtc0 [ 3.415606] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.418127] intel_pstate: CPU model not supported [ 3.421135] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.425338] hid: raw HID events driver (C) Jiri Kosina [ 3.429112] usbcore: registered new interface driver usbhid [ 3.432766] usbhid: USB HID core driver [ 3.438118] drop_monitor: Initializing network drop monitor service [ 3.440039] Initializing XFRM netlink socket [ 3.440632] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.446371] NET: Registered protocol family 10 [ 3.451705] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.460317] Segment Routing with IPv6 [ 3.461575] NET: Registered protocol family 17 [ 3.463948] mpls_gso: MPLS GSO support [ 3.469222] RAS: Correctable Errors collector initialized. [ 3.471112] AVX version of gcm_enc/dec engaged. [ 3.472420] AES CTR mode by8 optimization enabled [ 3.604873] sched_clock: Marking stable (3604847581, 0)->(4205420566, -600572985) [ 3.609283] registered taskstats version 1 [ 3.613868] Loading compiled-in X.509 certificates [ 3.619594] zswap: loaded using pool lzo/zbud [ 3.659811] Key type big_key registered [ 3.674689] Key type encrypted registered [ 3.677474] ima: No TPM chip found, activating TPM-bypass! [ 3.679539] ima: Allocated hash algorithm: sha1 [ 3.681191] ima: No architecture policies found [ 3.683095] evm: Initialising EVM extended attributes: [ 3.684845] evm: security.selinux [ 3.685913] evm: security.ima [ 3.687230] evm: security.capability [ 3.688533] evm: HMAC attrs: 0x1 [ 3.691617] rtc_cmos 00:05: setting system clock to 2025-07-16 17:41:46 UTC (1752687706) [ 3.698365] debug: unmapping init [mem 0xffffffffb6c03000-0xffffffffb6dfffff] [ 3.701504] debug: unmapping init [mem 0xffffffffb5982000-0xffffffffb5c58fff] [ 3.714090] Write protecting the kernel read-only data: 28672k [ 3.718428] debug: unmapping init [mem 0xffffffffb4003000-0xffffffffb41fffff] [ 3.721391] debug: unmapping init [mem 0xffffffffb4914000-0xffffffffb49fffff] [ 3.764906] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 3.773716] systemd[1]: Detected virtualization kvm. [ 3.775826] systemd[1]: Detected architecture x86-64. [ 3.777663] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.813163] systemd[1]: No hostname configured. [ 3.814681] systemd[1]: Set hostname to . [ 3.817220] random: systemd: uninitialized urandom read (16 bytes read) [ 3.819903] systemd[1]: Initializing machine ID from random generator. [ 3.891424] random: ln: uninitialized urandom read (6 bytes read) [ 3.982335] random: systemd: uninitialized urandom read (16 bytes read) [ 3.984641] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ 3.990731] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ 3.994963] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Listening on Journal Socket. [ OK ] Started Memstrack Anylazing Service. Starting Setup Virtual Console... Starting Create list of required st…ce nodes for the current kernel... Starting Apply Kernel Variables... [ OK ] Reached target Timers. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on udev Control Socket. [ OK ] Listening on Journal Socket (/dev/log). Starting Journal Service... [ OK ] Reached target Sockets. [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... [ OK ] Started Setup Virtual Console. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.970757] device-mapper: uevent: version 1.0.3 [ 4.972807] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target System Initialization. [ 6.418211] virtio_net virtio0 ens2: renamed from eth0 [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 6.878653] random: fast init done Starting dracut initqueue hook... [ 7.338836] scsi host0: ata_piix [ 7.346214] scsi host1: ata_piix [ 7.347760] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 7.358354] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 12.196857] random: crng init done [ 12.198058] random: 7 urandom warning(s) missed due to ratelimiting [ 13.998135] dracut-initqueue[587]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 15.309724] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Timers. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Sockets. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Swap. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped target Paths. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped target Slices. [ OK ] Stopped udev Kernel Device Manager. [ 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 Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 16.749891] printk: systemd: 26 output lines suppressed due to ratelimiting [ 17.135456] SELinux: Disabled at runtime. [ 17.211667] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 17.219780] systemd[1]: Detected virtualization kvm. [ 17.221522] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 18.019611] systemd[1]: initrd-switch-root.service: Succeeded. [ 18.022948] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 18.030360] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 18.044826] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 18.048343] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 18.062415] systemd[1]: Starting Journal Service... Starting Journal Service... [ 18.109114] systemd[1]: Stopped target Switch Root. [ OK ] Stopped target Switch Root. [ OK ] Reached target rpc_pipefs.target. Activating swap /dev/disk/by-label/SWAP... [ OK ] Created slice system-getty.slice. Starting Remount Root and Kernel File Systems... Starting Create [ 18.184647] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS list of required st…ce nodes for the current kernel... [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Stopped target Initrd File Systems. [ OK ] Reached target RPC Port Mapper. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. Mounting Huge Pages File System... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. Starting Apply Kernel Variables... Mounting POSIX Message Queue File System... [ OK ] Stopped target Initrd Root File System. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Listening on Process Core Dump Socket. Mounting Kernel Debug File System... [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Started Journal Service. [ OK ] Activated swap /dev/disk/by-label/SWAP. [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 ] Mounted Huge Pages File System. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Mounted Kernel Debug File System. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... [ OK ] Reached target Swap. Starting Flush Journal to Persistent Storage... [ OK ] Started udev Coldplug all Devices. [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... [ OK ] Mounted /mnt. [ 18.842347] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 20.097837] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 20.126164] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 20.385590] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 21.091690] EDAC sbridge: Ver: 1.1.2 [ 22.736925] hrtimer: interrupt took 2948563 ns [ 23.559440] Key type dns_resolver registered [ 24.328904] NFS: Registering the id_resolver key type [ 24.330820] Key type id_resolver registered [ 24.332298] Key type id_legacy registered [* ] A start job is running for Configur…-only root support (6s / no limit) [** ] A start job is running for Configur…-only root support (6s / 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 Update UTMP about System Boot/Shutdown... Starting RPC Bind... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started dnf makecache --timer. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. Starting Restore /run/initramfs on shutdown... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started irqbalance daemon. [ OK ] Reached target sshd-keygen.target. Starting Login Service... [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting OpenSSH server daemon... Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... Starting Network Manager Wait Online... Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Serial Getty on ttyS1. [ OK ] Reached target Login Prompts. [ OK ] Started Command Scheduler. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting System Logging Service... Starting Crash recovery kernel arming... Starting Notify NFS peers of a restart... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg442-client login: [ 53.513769] libcfs: loading out-of-tree module taints kernel. [ 53.538225] Key type ._llcrypt registered [ 53.549040] Key type .llcrypt registered [ 53.771576] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 53.776400] alg: No test for adler32 (adler32-zlib) [ 54.754925] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 54.999890] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 55.001254] LNet: Accept secure, port 988 [ 56.599148] Key type lgssc registered [ 57.065815] Lustre: Echo OBD driver; http://www.lustre.org/ [ 114.431137] Lustre: Mounted lustre-client [ 116.901194] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 127.502576] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing check_logdir /tmp/testlogs/ [ 129.071357] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing yml_node [ 130.818678] Lustre: DEBUG MARKER: Client: 2.16.57.1 [ 131.769528] Lustre: DEBUG MARKER: MDS: 2.16.57.1 [ 132.713817] Lustre: DEBUG MARKER: OSS: 2.16.57.1 [ 133.302142] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Wed Jul 16 13:43:55 EDT 2025 [ 139.270141] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 139.773577] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 140.255197] Lustre: lustre-OST0000-osc-ffff95cbc384a000: disconnect after 25s idle [ 140.262511] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 150.502176] LustreError: 8955:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 150.514068] Lustre: Unmounted lustre-client [ 196.572686] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 197.804413] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 205.339890] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 236.197926] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 13:45:38 (1752687938) [ 236.969456] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 237.506373] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 13:45:39 (1752687939) [ 247.438971] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 248.676864] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 254.692023] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 265.200253] Lustre: Mounted lustre-client [ 267.023450] LustreError: 14377:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff95cbc4fe3000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 267.031407] LustreError: 14377:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 267.033285] LustreError: 14377:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 267.056785] Lustre: Unmounted lustre-client [ 284.683108] Key type lgssc unregistered [ 284.815458] LNet: 14785:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 285.861628] LNet: Removed LNI 192.168.204.42@tcp [ 286.143395] Key type .llcrypt unregistered [ 286.144563] Key type ._llcrypt unregistered [ 292.809758] Key type ._llcrypt registered [ 292.810615] Key type .llcrypt registered [ 293.260768] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 13:46:35 (1752687995) [ 293.678730] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 293.726662] alg: No test for adler32 (adler32-zlib) [ 294.574470] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 294.651576] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 294.653847] LNet: Accept secure, port 988 [ 296.231132] Key type lgssc registered [ 296.556091] Lustre: Echo OBD driver; http://www.lustre.org/ [ 329.177252] Lustre: Mounted lustre-client [ 410.439320] LustreError: 18088:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff95cbc87a3000: lov tgt 1990 not cleaned! deathrow=0, lovrc=1 [ 410.456574] LustreError: 18088:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 410.508445] Lustre: Unmounted lustre-client [ 441.104404] Key type lgssc unregistered [ 441.342852] LNet: 18550:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 442.408554] LNet: Removed LNI 192.168.204.42@tcp [ 442.995258] Key type .llcrypt unregistered [ 442.997062] Key type ._llcrypt unregistered [ 449.900525] Key type ._llcrypt registered [ 449.902293] Key type .llcrypt registered [ 450.659248] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 13:49:12 (1752688152) [ 451.840175] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 451.884459] alg: No test for adler32 (adler32-zlib) [ 452.858473] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 453.018049] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 453.020693] LNet: Accept secure, port 988 [ 454.671149] Key type lgssc registered [ 455.338849] Lustre: Echo OBD driver; http://www.lustre.org/ [ 480.480655] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 482.398449] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 492.355235] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 500.233430] Lustre: Mounted lustre-client [ 505.610687] LustreError: 21651:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff95cbd8af4000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 505.622130] LustreError: 21651:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 505.655271] Lustre: Unmounted lustre-client [ 529.231096] Key type lgssc unregistered [ 529.366423] LNet: 22052:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 530.407090] LNet: Removed LNI 192.168.204.42@tcp [ 530.688727] Key type .llcrypt unregistered [ 530.690376] Key type ._llcrypt unregistered [ 535.923426] Key type ._llcrypt registered [ 535.924753] Key type .llcrypt registered [ 536.596982] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 13:50:38 (1752688238) [ 537.566335] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 537.637591] alg: No test for adler32 (adler32-zlib) [ 538.584661] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 538.715424] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 538.718447] LNet: Accept secure, port 988 [ 540.335197] Key type lgssc registered [ 540.976558] Lustre: Echo OBD driver; http://www.lustre.org/ [ 568.683412] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 570.967363] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 580.887871] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 583.260584] Lustre: Mounted lustre-client [ 589.974776] LustreError: 24742:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff95cbc53b3000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 589.989938] LustreError: 24742:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 590.032955] Lustre: Unmounted lustre-client [ 614.576721] Key type lgssc unregistered [ 614.793753] LNet: 25146:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 615.847650] LNet: Removed LNI 192.168.204.42@tcp [ 616.354403] Key type .llcrypt unregistered [ 616.360587] Key type ._llcrypt unregistered [ 624.724751] Key type ._llcrypt registered [ 624.726234] Key type .llcrypt registered [ 625.368774] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 13:52:07 (1752688327) [ 626.435471] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 626.475804] alg: No test for adler32 (adler32-zlib) [ 627.430764] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 627.592147] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 627.595219] LNet: Accept secure, port 988 [ 629.223778] Key type lgssc registered [ 629.809251] Lustre: Echo OBD driver; http://www.lustre.org/ [ 654.740789] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 656.749807] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 666.983356] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 674.797459] Lustre: Mounted lustre-client [ 679.907408] Lustre: lustre-MDT0000-mdc-ffff95cbc3f62000: Connection to lustre-MDT0000 (at 192.168.204.142@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 690.150921] Lustre: lustre-MDT0001-mdc-ffff95cbc3f62000: Connection to lustre-MDT0001 (at 192.168.204.142@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 700.383153] Lustre: 26103:0:(client.c:2453:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1752688387/real 1752688387] req@0000000030b204ef x1837826918324352/t0(0) o400->MGC192.168.204.142@tcp@192.168.204.142@tcp:26/25 lens 224/224 e 0 to 1 dl 1752688403 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 700.397264] LustreError: MGC192.168.204.142@tcp: Connection to MGS (at 192.168.204.142@tcp) was lost; in progress operations using this service will fail [ 701.224730] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 711.464153] LustreError: 28314:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 711.486371] Lustre: Unmounted lustre-client [ 718.116384] Key type lgssc unregistered [ 718.329911] LNet: 28702:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 719.398616] LNet: Removed LNI 192.168.204.42@tcp [ 719.776757] Key type .llcrypt unregistered [ 719.778611] Key type ._llcrypt unregistered [ 729.668829] Key type ._llcrypt registered [ 729.670409] Key type .llcrypt registered [ 730.530868] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 13:53:52 (1752688432) [ 732.193264] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 732.272673] alg: No test for adler32 (adler32-zlib) [ 733.277257] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 733.460959] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 733.466201] LNet: Accept secure, port 988 [ 735.087125] Key type lgssc registered [ 735.881314] Lustre: Echo OBD driver; http://www.lustre.org/ [ 765.761074] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 768.016164] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 778.328186] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 779.779923] Lustre: Mounted lustre-client [ 795.114432] Lustre: lustre-OST0000-osc-ffff95cbc4fe4000: Connection to lustre-OST0000 (at 192.168.204.142@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 807.208669] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 817.457504] LustreError: 31818:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 817.501784] Lustre: Unmounted lustre-client [ 836.429841] Key type lgssc unregistered [ 836.569632] LNet: 32218:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 837.610502] LNet: Removed LNI 192.168.204.42@tcp [ 837.931196] Key type .llcrypt unregistered [ 837.932946] Key type ._llcrypt unregistered [ 845.069570] Key type ._llcrypt registered [ 845.071182] Key type .llcrypt registered [ 845.655892] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 13:55:47 (1752688547) [ 846.504163] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 846.513229] alg: No test for adler32 (adler32-zlib) [ 847.424235] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 847.547441] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 847.550283] LNet: Accept secure, port 988 [ 849.151294] Key type lgssc registered [ 849.624597] Lustre: Echo OBD driver; http://www.lustre.org/ [ 871.566695] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 873.484084] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 882.480395] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 888.827633] Lustre: Mounted lustre-client [ 890.905280] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 892.362149] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 898.549872] LNetError: Refusing connection from 192.168.204.42 for 127.0.0.2@tcp: No matching NI [ 898.556921] LNetError: 33162:0:(socklnd_cb.c:1794:ksocknal_recv_hello()) Error -104 reading HELLO from 127.0.0.2 [ 898.560350] LNetError: Connection to 127.0.0.2@tcp at host 127.0.0.2:988 was reset: is it running a compatible version of Lustre and is 127.0.0.2@tcp one of its NIDs? [ 903.108380] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2025-07-1613h56m45s [ 904.122333] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 909.288057] Lustre: lustre-MDT0000-mdc-ffff95cbc65bf800: Connection to lustre-MDT0000 (at 192.168.204.142@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 909.308541] LNetError: Refusing connection from 192.168.204.42 for 127.0.0.2@tcp: No matching NI [ 909.315137] LNetError: 33163:0:(socklnd_cb.c:1794:ksocknal_recv_hello()) Error -104 reading HELLO from 127.0.0.2 [ 909.318996] LNetError: Connection to 127.0.0.2@tcp at host 127.0.0.2:988 was reset: is it running a compatible version of Lustre and is 127.0.0.2@tcp one of its NIDs? [ 912.039404] LustreError: 35913:0:(lmv_obd.c:1468:lmv_statfs()) lustre-MDT0000-mdc-ffff95cbc65bf800: can't stat MDS #0: rc = -19 [ 912.662883] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 914.399949] LNetError: Refusing connection from 192.168.204.42 for 127.0.0.2@tcp: No matching NI [ 914.405178] LNetError: 33164:0:(socklnd_cb.c:1794:ksocknal_recv_hello()) Error -104 reading HELLO from 127.0.0.2 [ 914.408771] LNetError: Connection to 127.0.0.2@tcp at host 127.0.0.2:988 was reset: is it running a compatible version of Lustre and is 127.0.0.2@tcp one of its NIDs? [ 918.952684] Lustre: DEBUG MARKER: Wait for df (35913) ... [ 919.505678] Lustre: DEBUG MARKER: done [ 919.819502] LustreError: 36518:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff95cbc65bf800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 919.821178] LustreError: MGC192.168.204.142@tcp: Connection to MGS (at 192.168.204.142@tcp) was lost; in progress operations using this service will fail [ 919.823597] LustreError: 36518:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 919.829160] Lustre: Evicted from MGS (at 192.168.204.142@tcp) after server handle changed from 0xc0343ed619b5137e to 0xc0343ed619b5172f [ 919.832104] Lustre: MGC192.168.204.142@tcp: Connection restored to (at 192.168.204.142@tcp) [ 919.843459] Lustre: Unmounted lustre-client [ 934.103702] Key type lgssc unregistered [ 934.242823] LNet: 36920:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 935.271371] LNet: Removed LNI 192.168.204.42@tcp [ 935.605224] Key type .llcrypt unregistered [ 935.607066] Key type ._llcrypt unregistered [ 955.106068] Key type ._llcrypt registered [ 955.107568] Key type .llcrypt registered [ 955.340412] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 955.347196] alg: No test for adler32 (adler32-zlib) [ 956.252817] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 956.363188] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 956.365883] LNet: Accept secure, port 988 [ 957.967259] Key type lgssc registered [ 958.475702] Lustre: Echo OBD driver; http://www.lustre.org/ [ 996.506576] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 998.054230] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1009.161440] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1035.104689] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 13:58:57 (1752688737) [ 1052.905427] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1055.433333] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1068.081985] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1069.555518] Lustre: Mounted lustre-client [ 1076.234607] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2025-07-1613h59m38s [ 1077.247995] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1080.061318] LNetError: Refusing connection from 192.168.204.42 for 127.0.0.2@tcp: No matching NI [ 1080.067753] LNetError: 37690:0:(socklnd_cb.c:1794:ksocknal_recv_hello()) Error -104 reading HELLO from 127.0.0.2 [ 1080.071936] LNetError: Connection to 127.0.0.2@tcp at host 127.0.0.2:988 was reset: is it running a compatible version of Lustre and is 127.0.0.2@tcp one of its NIDs? [ 1080.939575] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 1082.258515] Lustre: DEBUG MARKER: Creating a test file and stat it [ 1102.304379] Lustre: 43772:0:(client.c:2453:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1752688785/real 1752688785] req@00000000fc39f7db x1837827263305984/t0(0) o101->lustre-MDT0000-mdc-ffff95cbc87a3000@192.168.204.142@tcp:12/10 lens 576/1152 e 0 to 1 dl 1752688805 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:0 [ 1102.321827] Lustre: lustre-MDT0000-mdc-ffff95cbc87a3000: Connection to lustre-MDT0000 (at 192.168.204.142@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1102.347221] Lustre: lustre-MDT0000-mdc-ffff95cbc87a3000: Connection restored to (at 192.168.204.142@tcp) [ 1122.378277] Lustre: 43772:0:(client.c:2453:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1752688805/real 1752688805] req@00000000fc39f7db x1837827263305984/t0(0) o101->lustre-MDT0000-mdc-ffff95cbc87a3000@192.168.204.142@tcp:12/10 lens 576/1152 e 0 to 1 dl 1752688825 ref 2 fl Rpc:RXQ/602/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:0 [ 1122.390798] Lustre: lustre-MDT0000-mdc-ffff95cbc87a3000: Connection to lustre-MDT0000 (at 192.168.204.142@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1122.411944] Lustre: lustre-MDT0000-mdc-ffff95cbc87a3000: Connection restored to (at 192.168.204.142@tcp) [ 1123.274744] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 1124.718653] Lustre: DEBUG MARKER: done [ 1126.252967] LustreError: 44250:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff95cbc87a3000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1126.262251] LustreError: 44250:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1126.293291] Lustre: Unmounted lustre-client [ 1146.512768] Key type lgssc unregistered [ 1146.661430] LNet: 44651:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1147.685951] LNet: Removed LNI 192.168.204.42@tcp [ 1147.981490] Key type .llcrypt unregistered [ 1147.983227] Key type ._llcrypt unregistered [ 1169.927131] Key type ._llcrypt registered [ 1169.930433] Key type .llcrypt registered [ 1170.146215] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1170.158928] alg: No test for adler32 (adler32-zlib) [ 1171.134591] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 1171.307850] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 1171.311185] LNet: Accept secure, port 988 [ 1172.943195] Key type lgssc registered [ 1173.537311] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1215.094513] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1217.027946] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1228.631693] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1255.021237] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 14:02:36 (1752688956) [ 1255.845282] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 1256.803887] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 14:02:38 (1752688958) [ 1268.109919] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 14:02:50 (1752688970) [ 1281.180615] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1282.878080] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1290.905936] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1296.378169] Lustre: Mounted lustre-client [ 1298.471476] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 1299.921386] LustreError: 52483:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff95cbd80e5800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1299.935992] LustreError: 52483:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1299.966880] Lustre: Unmounted lustre-client [ 1310.754796] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 1328.156524] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1329.611238] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1329.837299] Lustre: Mounted lustre-client [ 1330.864872] LustreError: 53743:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff95cbc76af000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1330.877437] LustreError: 53743:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1330.880659] LustreError: 53743:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 1330.911266] Lustre: Unmounted lustre-client [ 1358.877764] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1360.314317] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1360.514594] Lustre: Mounted lustre-client [ 1361.602733] LustreError: 54862:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff95cbd8b65800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1361.615691] LustreError: 54862:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1361.618902] LustreError: 54862:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 1361.651679] Lustre: Unmounted lustre-client [ 1373.972883] Lustre: DEBUG MARKER: files compared the same [ 1389.244608] Key type lgssc unregistered [ 1389.371792] LNet: 55565:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1390.438202] LNet: Removed LNI 192.168.204.42@tcp [ 1390.744346] Key type .llcrypt unregistered [ 1390.746117] Key type ._llcrypt unregistered [ 1397.857712] Key type ._llcrypt registered [ 1397.859052] Key type .llcrypt registered [ 1398.441232] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 14:05:00 (1752689100) [ 1399.378232] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1399.388666] alg: No test for adler32 (adler32-zlib) [ 1400.278497] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 1400.382911] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 1400.385642] LNet: Accept secure, port 988 [ 1401.983188] Key type lgssc registered [ 1402.408695] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1421.241301] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1422.569272] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1428.716307] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1444.336135] Lustre: Mounted lustre-client [ 1445.998271] LustreError: 58646:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff95cbc77ad800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1446.004375] LustreError: 58646:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1446.028114] Lustre: Unmounted lustre-client [ 1464.152875] Key type lgssc unregistered [ 1464.289332] LNet: 59048:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1465.317529] LNet: Removed LNI 192.168.204.42@tcp [ 1465.583884] Key type .llcrypt unregistered [ 1465.585071] Key type ._llcrypt unregistered [ 1472.390916] Key type ._llcrypt registered [ 1472.392474] Key type .llcrypt registered [ 1472.983758] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 14:06:15 (1752689175) [ 1473.984358] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1473.998366] alg: No test for adler32 (adler32-zlib) [ 1474.886703] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 1474.980537] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 1474.982977] LNet: Accept secure, port 988 [ 1476.567166] Key type lgssc registered [ 1476.956501] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1544.391183] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1555.336952] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1556.710105] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1574.927141] Key type lgssc unregistered [ 1575.066540] LNet: 62432:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1575.077472] LNet: Removed LNI 192.168.204.42@tcp [ 1575.381865] Key type .llcrypt unregistered [ 1575.383307] Key type ._llcrypt unregistered [ 1582.329721] Key type ._llcrypt registered [ 1582.331024] Key type .llcrypt registered [ 1582.921710] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 14:08:05 (1752689285) [ 1584.194067] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1584.222066] alg: No test for adler32 (adler32-zlib) [ 1585.096356] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 1585.193767] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 1585.195920] LNet: Accept secure, port 988 [ 1586.791200] Key type lgssc registered [ 1587.214757] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1617.699685] Lustre: Mounted lustre-client [ 1623.549898] LustreError: 64909:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff95cbd815d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1623.562243] LustreError: 64909:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1623.596043] Lustre: Unmounted lustre-client [ 1646.342757] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 14:09:08 (1752689348) [ 1694.385109] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1700.757869] Lustre: Mounted lustre-client [ 1706.278954] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1706.282376] LustreError: 68856:0:(file.c:248:ll_close_inode_openhandle()) lustre-clilmv-ffff95cbc49a1000: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 1706.292204] Lustre: 63413:0:(llite_lib.c:4215:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.204.142@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 1716.588949] LustreError: 68856:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 1716.591564] LustreError: 68856:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 1716.615401] Lustre: Unmounted lustre-client [ 1752.840030] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 14:10:55 (1752689455) [ 1757.646628] Key type lgssc unregistered [ 1757.793791] LNet: 70064:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1758.821779] LNet: Removed LNI 192.168.204.42@tcp [ 1759.118112] Key type .llcrypt unregistered [ 1759.119795] Key type ._llcrypt unregistered [ 1764.750638] Key type ._llcrypt registered [ 1764.756304] Key type .llcrypt registered [ 1765.048215] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1765.056082] alg: No test for adler32 (adler32-zlib) [ 1765.956218] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 1766.052120] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 1766.054743] LNet: Accept secure, port 988 [ 1767.647176] Key type lgssc registered [ 1768.064340] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1788.092619] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1789.639848] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1796.351366] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1806.838130] Lustre: Mounted lustre-client [ 1808.746440] LustreError: 72578:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff95cbc40d6800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1808.755329] LustreError: 72578:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 1808.779201] Lustre: Unmounted lustre-client [ 1835.132628] Key type lgssc unregistered [ 1835.271738] LNet: 72981:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1836.326081] LNet: Removed LNI 192.168.204.42@tcp [ 1836.652869] Key type .llcrypt unregistered [ 1836.654537] Key type ._llcrypt unregistered [ 1842.023660] Key type ._llcrypt registered [ 1842.025749] Key type .llcrypt registered [ 1842.307621] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1842.323244] alg: No test for adler32 (adler32-zlib) [ 1843.214822] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 1843.319483] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 1843.322499] LNet: Accept secure, port 988 [ 1844.911315] Key type lgssc registered [ 1845.479432] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1898.651973] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1900.036150] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1907.300477] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1917.936296] Lustre: Mounted lustre-client [ 1918.433905] LustreError: 75920:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff95cbd80e4800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1918.442882] LustreError: 75920:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 1918.465129] Lustre: Unmounted lustre-client [ 1929.248373] Key type lgssc unregistered [ 1929.384712] LNet: 76320:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1930.407122] LNet: Removed LNI 192.168.204.42@tcp [ 1930.718802] Key type .llcrypt unregistered [ 1930.720419] Key type ._llcrypt unregistered [ 1937.769348] Key type ._llcrypt registered [ 1937.771082] Key type .llcrypt registered [ 1938.411107] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 14:14:00 (1752689640) [ 1939.337441] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1939.403135] alg: No test for adler32 (adler32-zlib) [ 1940.312671] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 1940.444572] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 1940.448202] LNet: Accept secure, port 988 [ 1942.081658] Key type lgssc registered [ 1942.611803] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1963.470692] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1964.970358] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1971.874859] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1982.469130] Lustre: Mounted lustre-client [ 1984.921757] LustreError: 79427:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff95cbc49a1800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1984.930389] LustreError: 79427:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 1984.958778] Lustre: Unmounted lustre-client [ 1985.124087] Lustre: Mounted lustre-client [ 1985.850605] LustreError: 79505:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff95cbc49a3000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1985.859854] LustreError: 79505:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 1985.863515] LustreError: 79505:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 1985.893224] Lustre: Unmounted lustre-client [ 2004.424267] Key type lgssc unregistered [ 2004.564749] LNet: 79906:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2005.605812] LNet: Removed LNI 192.168.204.42@tcp [ 2005.910346] Key type .llcrypt unregistered [ 2005.912063] Key type ._llcrypt unregistered [ 2011.800546] Key type ._llcrypt registered [ 2011.801981] Key type .llcrypt registered [ 2012.022685] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2012.030817] alg: No test for adler32 (adler32-zlib) [ 2012.930912] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 2013.036634] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 2013.039300] LNet: Accept secure, port 988 [ 2014.631300] Key type lgssc registered [ 2015.135119] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2036.246382] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2037.621173] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2044.791980] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2056.701242] Lustre: Mounted lustre-client [ 2058.680519] LustreError: 82439:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff95cbd80e7000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2058.690637] LustreError: 82439:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 2058.722187] Lustre: Unmounted lustre-client [ 2077.470291] Key type lgssc unregistered [ 2077.611875] LNet: 82841:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2078.629740] LNet: Removed LNI 192.168.204.42@tcp [ 2078.939303] Key type .llcrypt unregistered [ 2078.940547] Key type ._llcrypt unregistered [ 2085.720095] Key type ._llcrypt registered [ 2085.721587] Key type .llcrypt registered [ 2086.261986] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 14:16:28 (1752689788) [ 2087.133684] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2087.151538] alg: No test for adler32 (adler32-zlib) [ 2088.016149] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 2088.111764] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 2088.114384] LNet: Accept secure, port 988 [ 2089.703249] Key type lgssc registered [ 2090.185078] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2111.080300] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2112.754311] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2120.310210] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2130.959779] Lustre: Mounted lustre-client [ 2139.645734] Lustre: lustre: nosquash_nids is cleared [ 2139.661377] Lustre: lustre: root_squash is set to 500:500 [ 2146.547563] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.204.42@tcp 192.168.0.[2,10]@tcp [ 2155.515965] Lustre: lustre: nosquash_nids set to 192.168.204.42/24@tcp [ 2156.797418] LustreError: 86295:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff95cbc65b9000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2156.811372] LustreError: 86295:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 2156.838897] Lustre: Unmounted lustre-client [ 2166.970645] Key type lgssc unregistered [ 2167.128741] LNet: 86696:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2168.165946] LNet: Removed LNI 192.168.204.42@tcp [ 2168.455897] Key type .llcrypt unregistered [ 2168.457978] Key type ._llcrypt unregistered [ 2175.301682] Key type ._llcrypt registered [ 2175.303176] Key type .llcrypt registered [ 2175.849842] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 14:17:58 (1752689878) [ 2176.769423] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 2177.468789] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 14:17:59 (1752689879) [ 2178.456354] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2178.476535] alg: No test for adler32 (adler32-zlib) [ 2179.378276] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 2179.487611] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 2179.489948] LNet: Accept secure, port 988 [ 2181.095218] Key type lgssc registered [ 2181.584097] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2201.199607] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2202.657780] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2210.185947] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2210.400467] Lustre: lustre: root_squash is set to 500:500 [ 2210.415052] Lustre: lustre: nosquash_nids set to 192.168.204.42/24@tcp [ 2221.046684] Lustre: Mounted lustre-client [ 2223.666873] LustreError: 90153:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff95cbc8919800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2223.676494] LustreError: 90153:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 2223.703253] Lustre: Unmounted lustre-client [ 2242.333623] Key type lgssc unregistered [ 2242.492640] LNet: 90555:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2243.559487] LNet: Removed LNI 192.168.204.42@tcp [ 2243.881209] Key type .llcrypt unregistered [ 2243.882849] Key type ._llcrypt unregistered [ 2251.045037] Key type ._llcrypt registered [ 2251.046477] Key type .llcrypt registered [ 2251.596607] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 2252.203877] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 14:19:14 (1752689954) [ 2259.078295] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2259.107740] alg: No test for adler32 (adler32-zlib) [ 2260.046213] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 2260.160091] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 2260.162266] LNet: Accept secure, port 988 [ 2261.775323] Key type lgssc registered [ 2262.320287] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2297.307126] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2298.861151] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2307.681100] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2340.676453] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2341.911634] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2348.582978] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2362.452207] Lustre: Mounted lustre-client [ 2378.096475] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff95cbc5d4a000.ost_server_uuid 50 [ 2378.716307] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff95cbc5d4a000.ost_server_uuid in FULL state after 0 sec [ 2378.880233] Lustre: Mounted lustre-client [ 2379.948202] LustreError: 97438:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff95cbd11fa800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2379.958550] LustreError: 97438:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 2379.992135] Lustre: Unmounted lustre-client [ 2426.757270] Key type lgssc unregistered [ 2426.897947] LNet: 98011:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2427.942868] LNet: Removed LNI 192.168.204.42@tcp [ 2428.237042] Key type .llcrypt unregistered [ 2428.238387] Key type ._llcrypt unregistered [ 2447.004206] Key type ._llcrypt registered [ 2447.009378] Key type .llcrypt registered [ 2447.202669] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2447.209849] alg: No test for adler32 (adler32-zlib) [ 2448.121991] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 2448.250610] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 2448.253031] LNet: Accept secure, port 988 [ 2449.863191] Key type lgssc registered [ 2450.509280] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2485.915673] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2487.381738] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2496.479807] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2519.019969] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 14:23:41 (1752690221) [ 2551.589566] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2552.936537] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2560.559602] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2567.127788] Lustre: Mounted lustre-client [ 2582.499188] Lustre: lustre-OST0000-osc-ffff95cbd8b64800: Connection to lustre-OST0000 (at 192.168.204.142@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2587.618215] Lustre: lustre-MDT0000-mdc-ffff95cbd8b64800: Connection to lustre-MDT0000 (at 192.168.204.142@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2588.639229] Lustre: 98793:0:(client.c:2453:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1752690275/real 1752690275] req@00000000de80ed34 x1837828827780864/t0(0) o400->lustre-OST0000-osc-ffff95cbd8b64800@192.168.204.142@tcp:28/4 lens 224/224 e 0 to 1 dl 1752690291 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2593.759213] Lustre: 98793:0:(client.c:2453:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1752690280/real 1752690280] req@00000000facc5cf2 x1837828827781376/t0(0) o400->lustre-OST0000-osc-ffff95cbd8b64800@192.168.204.142@tcp:28/4 lens 224/224 e 0 to 1 dl 1752690296 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2597.860571] LustreError: MGC192.168.204.142@tcp: Connection to MGS (at 192.168.204.142@tcp) was lost; in progress operations using this service will fail [ 2597.867233] Lustre: 105920:0:(mgc_request.c:1876:mgc_process_log()) MGC192.168.204.142@tcp: IR log lustre-cliir failed, not fatal: rc = -5 [ 2597.871056] Lustre: Evicted from MGS (at 192.168.204.142@tcp) after server handle changed from 0x45770451a4f15587 to 0x45770451a4f1587b [ 2597.876988] Lustre: MGC192.168.204.142@tcp: Connection restored to 192.168.204.142@tcp (at 192.168.204.142@tcp) [ 2603.305375] Lustre: lustre-MDT0000-mdc-ffff95cbd8b64800: Connection restored to 192.168.204.142@tcp (at 192.168.204.142@tcp) [ 2603.704239] LustreError: 106641:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff95cbd8b64800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2603.712327] LustreError: 106641:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2603.737508] Lustre: Unmounted lustre-client [ 2622.260109] Key type lgssc unregistered [ 2622.403416] LNet: 107041:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2623.461865] LNet: Removed LNI 192.168.204.42@tcp [ 2623.759841] Key type .llcrypt unregistered [ 2623.761421] Key type ._llcrypt unregistered [ 2630.587143] Key type ._llcrypt registered [ 2630.588650] Key type .llcrypt registered [ 2631.137442] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 14:25:33 (1752690333) [ 2643.164026] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2643.176615] alg: No test for adler32 (adler32-zlib) [ 2644.050499] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 2644.150581] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 2644.153154] LNet: Accept secure, port 988 [ 2645.751182] Key type lgssc registered [ 2646.209090] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2662.075303] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2698.008631] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2699.389470] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2707.344800] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2709.569143] Lustre: Mounted lustre-client [ 2710.290901] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 2710.918890] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 14:26:53 (1752690413) [ 2785.037987] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2795.302106] LustreError: 117773:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2795.317810] Lustre: Unmounted lustre-client [ 2846.166847] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2847.404769] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2855.521532] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2881.550467] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 14:29:43 (1752690583) [ 2911.489958] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2912.661757] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2919.993677] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2920.138823] Lustre: Mounted lustre-client [ 2921.183084] LustreError: 125167:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff95cbc450d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2921.190072] LustreError: 125167:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2921.192335] LustreError: 125167:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 2921.215092] Lustre: Unmounted lustre-client [ 2939.500812] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 14:30:41 (1752690641) [ 2969.838225] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2971.033233] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2978.753587] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2978.958330] Lustre: Mounted lustre-client [ 2980.031695] LustreError: 129202:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff95cbd8636800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2980.039619] LustreError: 129202:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2980.041799] LustreError: 129202:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 2980.069271] Lustre: Unmounted lustre-client [ 2997.716651] Key type lgssc unregistered [ 2997.863556] LNet: 129609:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2998.885865] LNet: Removed LNI 192.168.204.42@tcp [ 2999.154638] Key type .llcrypt unregistered [ 2999.156064] Key type ._llcrypt unregistered [ 3005.581519] Key type ._llcrypt registered [ 3005.583013] Key type .llcrypt registered [ 3006.085187] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 14:31:48 (1752690708) [ 3006.857373] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3006.897816] alg: No test for adler32 (adler32-zlib) [ 3007.768124] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 3007.856819] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 3007.858952] LNet: Accept secure, port 988 [ 3009.447201] Key type lgssc registered [ 3009.846237] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3029.284693] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3030.690420] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3037.911902] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3048.440901] Lustre: Mounted lustre-client [ 3059.339977] LustreError: 132701:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff95cbc724e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3059.349089] LustreError: 132701:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3059.374518] Lustre: Unmounted lustre-client [ 3077.946865] Key type lgssc unregistered [ 3078.088555] LNet: 133103:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3079.142756] LNet: Removed LNI 192.168.204.42@tcp [ 3079.422023] Key type .llcrypt unregistered [ 3079.423532] Key type ._llcrypt unregistered [ 3086.137786] Key type ._llcrypt registered [ 3086.139318] Key type .llcrypt registered [ 3086.658994] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 14:33:08 (1752690788) [ 3087.573316] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3087.642784] alg: No test for adler32 (adler32-zlib) [ 3088.535557] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 3088.637410] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 3088.639734] LNet: Accept secure, port 988 [ 3090.239186] Key type lgssc registered [ 3090.637172] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3110.196905] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3111.716229] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3119.225874] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3129.851525] Lustre: Mounted lustre-client [ 3134.951678] Lustre: lustre-OST0000-osc-ffff95cbd8637000: Connection to lustre-OST0000 (at 192.168.204.142@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3140.606687] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff95cbd8637000.ost_server_uuid 50 [ 3141.227933] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 3150.596948] LustreError: 136629:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff95cbd8637000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3150.603179] LustreError: 136629:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3150.627322] Lustre: Unmounted lustre-client [ 3161.376385] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 14:34:23 (1752690863) [ 3171.226441] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3172.423828] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3178.474335] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3185.964444] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3186.167635] Lustre: Mounted lustre-client [ 3191.267768] Lustre: lustre-OST0000-osc-ffff95cbc46e1800: Connection to lustre-OST0000 (at 192.168.204.142@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3205.546299] LustreError: 139278:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff95cbc46e1800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3205.553779] LustreError: 139278:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 3205.556292] LustreError: 139278:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 3205.580325] Lustre: Unmounted lustre-client [ 3229.263431] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 14:35:31 (1752690931) [ 3239.209869] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3240.396564] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3246.520791] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3252.626856] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3252.803632] Lustre: Mounted lustre-client [ 3257.827318] Lustre: lustre-OST0000-osc-ffff95cbc87a4000: Connection to lustre-OST0000 (at 192.168.204.142@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3269.514859] LustreError: 142029:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff95cbc87a4000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3269.518401] LustreError: 142029:0:(lov_obd.c:784:lov_cleanup()) Skipped 1 previous similar message [ 3269.522633] LustreError: 142029:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 3269.524212] LustreError: 142029:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 3269.541112] Lustre: Unmounted lustre-client [ 3301.393464] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 14:36:43 (1752691003) [ 3331.723819] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3332.875924] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3340.201312] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3378.513391] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3379.813553] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3386.143577] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3407.922283] Lustre: Mounted lustre-client [ 3435.487865] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3436.009898] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 3436.299731] LustreError: 148607:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff95cbc7683800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3436.302759] LustreError: 148607:0:(lov_obd.c:784:lov_cleanup()) Skipped 1 previous similar message [ 3436.307081] LustreError: 148607:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3436.308737] LustreError: 148607:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 3436.326366] Lustre: Unmounted lustre-client [ 3446.786444] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 14:39:09 (1752691149) [ 3455.936760] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3457.101260] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3462.766315] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3483.793249] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3499.506568] Lustre: Mounted lustre-client [ 3527.107501] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3527.670326] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 3530.211349] Lustre: lustre-OST0001-osc-ffff95cbd811c800: Connection to lustre-OST0001 (at 192.168.204.142@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3535.481444] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3545.769272] LustreError: 152179:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 3545.772656] LustreError: 152179:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 3545.788204] Lustre: Unmounted lustre-client [ 3576.588081] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 14:41:18 (1752691278) [ 3587.494168] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3588.871841] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3595.521490] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3600.884050] Lustre: Mounted lustre-client [ 3608.619143] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3611.279488] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 2 sec [ 3615.453621] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff95cbc5d33800.ost_server_uuid 50 [ 3615.985205] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff95cbc5d33800.ost_server_uuid in FULL state after 0 sec [ 3616.671345] LustreError: 155615:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff95cbc5d33800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3616.677265] LustreError: 155615:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 3616.679057] LustreError: 155615:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 3616.704200] Lustre: Unmounted lustre-client [ 3616.800855] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 3616.803031] Lustre: Skipped 3 previous similar messages [ 3617.226630] LustreError: 155678:0:(sec.c:411:import_sec_validate_get()) import 0000000099e603fa (NEW) with no sec [ 3656.703643] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 14:42:39 (1752691359) [ 3667.270547] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3668.576716] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3674.806439] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3681.294405] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3681.447722] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 3686.898618] Lustre: Mounted lustre-client [ 3686.900245] Lustre: Skipped 1 previous similar message [ 3690.575327] LustreError: 158495:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff95cbc7687800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3690.579354] LustreError: 158495:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 3690.588612] LustreError: 158495:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 3690.591042] LustreError: 158495:0:(obd_class.h:479:obd_check_dev()) Skipped 18 previous similar messages [ 3690.620210] Lustre: Unmounted lustre-client [ 3690.621741] Lustre: Skipped 1 previous similar message [ 3711.389850] Key type lgssc unregistered [ 3711.513551] LNet: 158941:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3712.549591] LNet: Removed LNI 192.168.204.42@tcp [ 3712.801372] Key type .llcrypt unregistered [ 3712.802471] Key type ._llcrypt unregistered [ 3719.233709] Key type ._llcrypt registered [ 3719.234749] Key type .llcrypt registered [ 3719.688359] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 14:43:41 (1752691421) [ 3720.094745] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3720.101747] alg: No test for adler32 (adler32-zlib) [ 3720.962022] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 3721.044247] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 3721.045913] LNet: Accept secure, port 988 [ 3722.623153] Key type lgssc registered [ 3722.993404] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3741.931429] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3743.160185] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3749.552682] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3756.219956] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3756.371840] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 3761.650401] Lustre: Mounted lustre-client [ 3766.059777] LustreError: lustre-MDT0001-mdc-ffff95cbe035c000: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 3766.068667] Lustre: lustre-MDT0001-mdc-ffff95cbe035c000: Connection restored to (at 192.168.204.142@tcp) [ 3767.455329] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3767.992563] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 3775.333880] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 3781.586260] LustreError: 163140:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff95cbe035c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3781.591857] LustreError: 163140:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 3781.616643] Lustre: Unmounted lustre-client [ 3801.261932] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 14:45:03 (1752691503) [ 3833.218214] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3834.398417] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3842.124397] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3842.277417] Lustre: Mounted lustre-client [ 3852.247022] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3852.785915] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 3868.643296] Lustre: lustre-OST0001-osc-ffff95cbc3f62000: Connection to lustre-OST0001 (at 192.168.204.142@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3870.886033] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3881.128766] LustreError: 167861:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 3881.130979] LustreError: 167861:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 3881.147838] Lustre: Unmounted lustre-client [ 3905.009335] Key type lgssc unregistered [ 3905.141398] LNet: 168264:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3906.213856] LNet: Removed LNI 192.168.204.42@tcp [ 3906.465694] Key type .llcrypt unregistered [ 3906.467380] Key type ._llcrypt unregistered [ 3922.684276] Key type ._llcrypt registered [ 3922.685622] Key type .llcrypt registered [ 3922.901064] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3922.907359] alg: No test for adler32 (adler32-zlib) [ 3923.774785] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 3923.865878] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 3923.867370] LNet: Accept secure, port 988 [ 3925.455172] Key type lgssc registered [ 3925.823212] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3955.666056] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3956.935220] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3964.964272] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3986.466064] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 14:48:08 (1752691688) [ 3996.561129] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3997.879797] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4004.322163] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4015.088992] Lustre: Mounted lustre-client [ 4052.216464] LustreError: 174336:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff95cbc6fca800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4052.225383] LustreError: 174336:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4052.244034] Lustre: Unmounted lustre-client [ 4068.744375] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4068.931520] Lustre: Mounted lustre-client [ 4101.380449] LustreError: 174993:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff95cbc7414800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4101.389619] LustreError: 174993:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4101.391186] LustreError: 174993:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 4101.412664] Lustre: Unmounted lustre-client [ 4127.456169] Key type lgssc unregistered [ 4127.588388] LNet: 175400:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4128.613771] LNet: Removed LNI 192.168.204.42@tcp [ 4128.912957] Key type .llcrypt unregistered [ 4128.914889] Key type ._llcrypt unregistered [ 4135.357724] Key type ._llcrypt registered [ 4135.358922] Key type .llcrypt registered [ 4135.810936] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 14:50:38 (1752691838) [ 4136.657162] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4136.665226] alg: No test for adler32 (adler32-zlib) [ 4137.515979] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 4137.597428] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 4137.598793] LNet: Accept secure, port 988 [ 4139.183206] Key type lgssc registered [ 4139.592490] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4156.832250] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4158.029262] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4164.335164] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4179.951798] Lustre: Mounted lustre-client [ 4186.733363] LustreError: 178662:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff95cbc5d4f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4186.741176] LustreError: 178662:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4186.762802] Lustre: Unmounted lustre-client [ 4204.341159] Key type lgssc unregistered [ 4204.475718] LNet: 179065:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4205.542233] LNet: Removed LNI 192.168.204.42@tcp [ 4205.788201] Key type .llcrypt unregistered [ 4205.789898] Key type ._llcrypt unregistered [ 4211.278817] Key type ._llcrypt registered [ 4211.279792] Key type .llcrypt registered [ 4211.532942] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4211.543213] alg: No test for adler32 (adler32-zlib) [ 4212.414420] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 4212.506697] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 4212.509246] LNet: Accept secure, port 988 [ 4214.095198] Key type lgssc registered [ 4214.511614] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4231.937050] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4233.212934] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4239.172600] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4254.704202] Lustre: Mounted lustre-client [ 4257.621964] LustreError: 181624:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff95cbc5d4b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4257.628858] LustreError: 181624:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4257.650577] Lustre: Unmounted lustre-client [ 4283.444330] Key type lgssc unregistered [ 4283.568525] LNet: 182029:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4284.581471] LNet: Removed LNI 192.168.204.42@tcp [ 4284.850126] Key type .llcrypt unregistered [ 4284.851714] Key type ._llcrypt unregistered [ 4291.324149] Key type ._llcrypt registered [ 4291.325171] Key type .llcrypt registered [ 4291.796587] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 14:53:14 (1752691994) [ 4292.747612] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4292.759585] alg: No test for adler32 (adler32-zlib) [ 4293.626236] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 4293.722818] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 4293.724878] LNet: Accept secure, port 988 [ 4295.319174] Key type lgssc registered [ 4295.740778] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4313.441050] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4314.767591] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4320.959400] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4331.506558] Lustre: Mounted lustre-client [ 4338.259294] LustreError: 185281:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff95cbd811b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4338.265970] LustreError: 185281:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4338.288714] Lustre: Unmounted lustre-client [ 4355.602854] Key type lgssc unregistered [ 4355.729507] LNet: 185682:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4356.773484] LNet: Removed LNI 192.168.204.42@tcp [ 4357.054740] Key type .llcrypt unregistered [ 4357.056759] Key type ._llcrypt unregistered [ 4362.797095] Key type ._llcrypt registered [ 4362.798079] Key type .llcrypt registered [ 4362.986673] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4362.994894] alg: No test for adler32 (adler32-zlib) [ 4363.874116] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 4363.971276] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 4363.972887] LNet: Accept secure, port 988 [ 4365.567172] Key type lgssc registered [ 4365.978484] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4383.604668] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4384.927742] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4391.416912] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4402.159423] Lustre: Mounted lustre-client [ 4404.930930] LustreError: 188245:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff95cbd8b64800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4404.939120] LustreError: 188245:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4404.962987] Lustre: Unmounted lustre-client [ 4414.389078] Key type lgssc unregistered [ 4414.511562] LNet: 188646:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4415.525762] LNet: Removed LNI 192.168.204.42@tcp [ 4415.831164] Key type .llcrypt unregistered [ 4415.832764] Key type ._llcrypt unregistered [ 4422.412602] Key type ._llcrypt registered [ 4422.413633] Key type .llcrypt registered [ 4422.917891] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 14:55:25 (1752692125) [ 4430.028550] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4430.052344] alg: No test for adler32 (adler32-zlib) [ 4430.898096] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 4430.975983] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 4430.978308] LNet: Accept secure, port 988 [ 4432.567189] Key type lgssc registered [ 4432.954187] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4462.047828] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4463.309382] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4471.093709] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4500.946731] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 14:56:43 (1752692203) [ 4511.108971] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4512.282877] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4518.395854] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4534.256449] Lustre: Mounted lustre-client [ 4536.210382] LustreError: 195142:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff95cbc7447800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4536.218974] LustreError: 195142:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4536.245139] Lustre: Unmounted lustre-client [ 4562.544553] Key type lgssc unregistered [ 4562.661641] LNet: 195545:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4563.685899] LNet: Removed LNI 192.168.204.42@tcp [ 4563.950394] Key type .llcrypt unregistered [ 4563.952133] Key type ._llcrypt unregistered [ 4570.475673] Key type ._llcrypt registered [ 4570.477334] Key type .llcrypt registered [ 4570.953594] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 4571.512795] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 14:57:53 (1752692273) [ 4577.637234] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4577.644828] alg: No test for adler32 (adler32-zlib) [ 4578.496337] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 4578.579472] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 4578.581165] LNet: Accept secure, port 988 [ 4580.167159] Key type lgssc registered [ 4580.521117] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4613.666197] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4614.895695] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4622.399255] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid [ 4628.838596] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid [ 4630.014360] Lustre: Mounted lustre-client [ 4636.078318] LustreError: 201066:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff95cbd20cc000: lov tgt 1000 not cleaned! deathrow=0, lovrc=1 [ 4636.084977] LustreError: 201066:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4636.111647] Lustre: Unmounted lustre-client [ 4699.715409] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4701.022819] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4708.606654] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4730.244238] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 15:00:32 (1752692432) [ 4730.753480] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 4731.297696] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 15:00:33 (1752692433) [ 4749.749617] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4750.947481] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4773.373281] Key type lgssc unregistered [ 4773.512455] LNet: 207240:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4774.569595] LNet: Removed LNI 192.168.204.42@tcp [ 4774.832606] Key type .llcrypt unregistered [ 4774.833653] Key type ._llcrypt unregistered [ 4781.376830] Key type ._llcrypt registered [ 4781.378365] Key type .llcrypt registered [ 4781.901449] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 15:01:24 (1752692484) [ 4793.801424] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4793.827374] alg: No test for adler32 (adler32-zlib) [ 4794.687541] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 4794.780251] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 4794.782121] LNet: Accept secure, port 988 [ 4796.375323] Key type lgssc registered [ 4796.782306] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4810.963882] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4812.209269] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4818.179111] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4829.676942] Key type lgssc unregistered [ 4829.806472] LNet: 210858:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4829.812511] LNet: Removed LNI 192.168.204.42@tcp [ 4830.106450] Key type .llcrypt unregistered [ 4830.107552] Key type ._llcrypt unregistered [ 4836.683392] Key type ._llcrypt registered [ 4836.684388] Key type .llcrypt registered [ 4837.156677] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 15:02:19 (1752692539) [ 4838.171784] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4838.183529] alg: No test for adler32 (adler32-zlib) [ 4839.030127] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 4839.108119] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 4839.110876] LNet: Accept secure, port 988 [ 4840.695273] Key type lgssc registered [ 4841.223613] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4858.214519] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4859.407625] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4865.689369] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4876.273945] Lustre: Mounted lustre-client [ 4881.379719] Lustre: lustre-MDT0000-mdc-ffff95cbc8140000: Connection to lustre-MDT0000 (at 192.168.204.142@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4886.498351] Lustre: lustre-MDT0001-mdc-ffff95cbc8140000: Connection to lustre-MDT0001 (at 192.168.204.142@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4902.751137] Lustre: 211843:0:(client.c:2453:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1752692589/real 1752692589] req@0000000057576dd0 x1837831333953920/t0(0) o400->MGC192.168.204.142@tcp@192.168.204.142@tcp:26/25 lens 224/224 e 0 to 1 dl 1752692605 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4902.760458] LustreError: MGC192.168.204.142@tcp: Connection to MGS (at 192.168.204.142@tcp) was lost; in progress operations using this service will fail [ 4912.929677] Lustre: Evicted from MGS (at 192.168.204.142@tcp) after server handle changed from 0x8a0beb1d95c64151 to 0x8a0beb1d95c65c40 [ 4912.934476] Lustre: MGC192.168.204.142@tcp: Connection restored to 192.168.204.142@tcp (at 192.168.204.142@tcp) [ 4917.909765] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4926.245267] LustreError: lustre-MDT0000-mdc-ffff95cbc8140000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 4926.270160] Lustre: lustre-MDT0000-mdc-ffff95cbc8140000: Connection restored to 192.168.204.142@tcp (at 192.168.204.142@tcp) [ 4927.684815] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 9 sec [ 4928.934147] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4929.504089] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 4930.139968] LustreError: 215322:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff95cbc8140000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4930.145682] LustreError: 215322:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4930.169133] Lustre: Unmounted lustre-client [ 4964.390253] Key type lgssc unregistered [ 4964.508610] LNet: 215726:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4965.541463] LNet: Removed LNI 192.168.204.42@tcp [ 4965.803189] Key type .llcrypt unregistered [ 4965.804752] Key type ._llcrypt unregistered [ 4972.427691] Key type ._llcrypt registered [ 4972.429222] Key type .llcrypt registered [ 4972.932560] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 15:04:35 (1752692675) [ 4973.886855] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4973.896975] alg: No test for adler32 (adler32-zlib) [ 4974.766949] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 4974.865528] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 4974.867911] LNet: Accept secure, port 988 [ 4976.455203] Key type lgssc registered [ 4976.861414] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4993.391452] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4994.500357] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5015.191553] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5016.308255] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5025.576533] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5031.684906] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 5065.054566] Key type lgssc unregistered [ 5065.184468] LNet: 220821:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5065.193348] LNet: Removed LNI 192.168.204.42@tcp [ 5065.425429] Key type .llcrypt unregistered [ 5065.426484] Key type ._llcrypt unregistered [ 5082.065485] Key type ._llcrypt registered [ 5082.066570] Key type .llcrypt registered [ 5082.284464] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5082.290683] alg: No test for adler32 (adler32-zlib) [ 5083.149383] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 5083.238704] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 5083.240871] LNet: Accept secure, port 988 [ 5084.831166] Key type lgssc registered [ 5085.205127] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5114.270984] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5115.480323] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5122.781550] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5144.257595] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 15:07:26 (1752692846) [ 5181.011682] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5182.150491] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5189.506987] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5223.519312] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 15:08:45 (1752692925) [ 5226.015217] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 15:08:48 (1752692928) [ 5236.146577] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5237.283102] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5243.027086] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5258.747741] Lustre: Mounted lustre-client [ 5261.849434] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [ 5262.632614] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 5263.208029] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 5263.865528] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [ 5268.961641] Lustre: lustre-MDT0000-mdc-ffff95cbd0662000: Connection to lustre-MDT0000 (at 192.168.204.142@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5279.202332] LustreError: MGC192.168.204.142@tcp: Connection to MGS (at 192.168.204.142@tcp) was lost; in progress operations using this service will fail [ 5279.211162] Lustre: Evicted from MGS (at 192.168.204.142@tcp) after server handle changed from 0x76381d3bc3a7b67b to 0x76381d3bc3a7b8ea [ 5279.214922] Lustre: MGC192.168.204.142@tcp: Connection restored to 192.168.204.142@tcp (at 192.168.204.142@tcp) [ 5283.120255] Lustre: lustre-MDT0000-mdc-ffff95cbd0662000: Connection restored to 192.168.204.142@tcp (at 192.168.204.142@tcp) [ 5284.372552] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5284.918352] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 5286.816654] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [ 5289.441868] Lustre: lustre-MDT0000-mdc-ffff95cbd0662000: Connection to lustre-MDT0000 (at 192.168.204.142@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5304.802809] LustreError: MGC192.168.204.142@tcp: Connection to MGS (at 192.168.204.142@tcp) was lost; in progress operations using this service will fail [ 5304.810764] Lustre: Evicted from MGS (at 192.168.204.142@tcp) after server handle changed from 0x76381d3bc3a7b8ea to 0x76381d3bc3a7bbd7 [ 5304.814633] Lustre: MGC192.168.204.142@tcp: Connection restored to 192.168.204.142@tcp (at 192.168.204.142@tcp) [ 5307.368147] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5307.899409] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 5309.455123] LustreError: 234415:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff95cbd0662000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5309.460837] LustreError: 234415:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5309.478559] Lustre: Unmounted lustre-client [ 5342.055509] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5343.287177] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5349.123893] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5364.723769] Lustre: Mounted lustre-client [ 5365.049080] LustreError: 236033:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff95cbc5d30800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5365.056877] LustreError: 236033:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5365.059155] LustreError: 236033:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 5365.078118] Lustre: Unmounted lustre-client [ 5382.438269] Key type lgssc unregistered [ 5382.558584] LNet: 236435:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5383.589788] LNet: Removed LNI 192.168.204.42@tcp [ 5383.883957] Key type .llcrypt unregistered [ 5383.885042] Key type ._llcrypt unregistered [ 5390.315835] Key type ._llcrypt registered [ 5390.317280] Key type .llcrypt registered [ 5390.802339] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 15:11:33 (1752693093) [ 5392.147707] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5392.192252] alg: No test for adler32 (adler32-zlib) [ 5393.084469] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 5393.188894] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 5393.190480] LNet: Accept secure, port 988 [ 5394.767172] Key type lgssc registered [ 5395.185096] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5412.714671] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5414.018223] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5420.272274] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5435.890478] Lustre: Mounted lustre-client [ 5438.889354] LustreError: 239594:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff95cbd8636000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5438.897333] LustreError: 239594:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5438.920443] Lustre: Unmounted lustre-client [ 5471.185509] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5472.398159] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5478.362639] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5494.256341] Lustre: Mounted lustre-client [ 5500.363183] LustreError: 241192:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff95cbc46e5800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5500.368996] LustreError: 241192:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5500.370669] LustreError: 241192:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 5500.390931] Lustre: Unmounted lustre-client [ 5532.381725] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5533.614691] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5539.802464] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5550.579668] Lustre: Mounted lustre-client [ 5550.938847] LustreError: 242806:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff95cbc46e2000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5550.944884] LustreError: 242806:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5550.946487] LustreError: 242806:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 5550.965134] Lustre: Unmounted lustre-client [ 5568.451374] Key type lgssc unregistered [ 5568.593587] LNet: 243208:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5569.637946] LNet: Removed LNI 192.168.204.42@tcp [ 5569.897611] Key type .llcrypt unregistered [ 5569.898653] Key type ._llcrypt unregistered [ 5576.048180] Key type ._llcrypt registered [ 5576.049191] Key type .llcrypt registered [ 5576.524168] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 15:14:38 (1752693278) [ 5577.565380] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5577.573334] alg: No test for adler32 (adler32-zlib) [ 5578.443139] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 5578.532856] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 5578.534753] LNet: Accept secure, port 988 [ 5580.127170] Key type lgssc registered [ 5580.481865] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5596.180771] Key type lgssc unregistered [ 5596.310361] LNet: 245513:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5596.317362] LNet: Removed LNI 192.168.204.42@tcp [ 5596.573476] Key type .llcrypt unregistered [ 5596.574524] Key type ._llcrypt unregistered [ 5606.184080] Key type ._llcrypt registered [ 5606.185066] Key type .llcrypt registered [ 5606.330052] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5606.336851] alg: No test for adler32 (adler32-zlib) [ 5607.211064] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 5607.301470] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 5607.302995] LNet: Accept secure, port 988 [ 5608.895257] Key type lgssc registered [ 5609.517033] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5642.170839] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5644.099115] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5654.060173] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5676.460588] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [ 5677.048620] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 15:16:19 (1752693379) [ 5688.657927] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5690.083857] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5696.495678] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5703.823989] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 5704.994966] Lustre: Mounted lustre-client [ 5710.308150] Lustre: lustre-OST0001-osc-ffff95cbd811f800: Connection to lustre-OST0001 (at 192.168.204.142@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5712.658681] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5722.919720] LustreError: 251993:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 5722.936735] Lustre: Unmounted lustre-client [ 5747.331789] Key type lgssc unregistered [ 5747.461552] LNet: 252397:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5748.518833] LNet: Removed LNI 192.168.204.42@tcp [ 5748.853814] Key type .llcrypt unregistered [ 5748.855100] Key type ._llcrypt unregistered [ 5764.608139] Key type ._llcrypt registered [ 5764.609149] Key type .llcrypt registered [ 5764.740777] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5764.745871] alg: No test for adler32 (adler32-zlib) [ 5765.620756] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 5765.732268] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 5765.734325] LNet: Accept secure, port 988 [ 5767.327118] Key type lgssc registered [ 5767.785784] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5797.435511] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5798.667233] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5805.971647] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5827.115458] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 15:18:49 (1752693529) [ 5840.781130] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5842.083843] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5862.350886] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 15:19:24 (1752693564) [ 5873.519513] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5874.683706] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5881.639577] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5892.101877] Lustre: Mounted lustre-client [ 5900.776127] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 5905.566348] LustreError: 260242:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5905.588140] Lustre: Unmounted lustre-client [ 5953.527422] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5954.737064] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5989.798648] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6000.644374] Lustre: Mounted lustre-client [ 6001.151711] LustreError: 262339:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff95cbc6c9e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6001.158728] LustreError: 262339:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6001.160159] LustreError: 262339:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 6001.182176] Lustre: Unmounted lustre-client [ 6011.505462] Key type lgssc unregistered [ 6011.637451] LNet: 262739:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6012.709637] LNet: Removed LNI 192.168.204.42@tcp [ 6012.964574] Key type .llcrypt unregistered [ 6012.965625] Key type ._llcrypt unregistered [ 6023.398389] Key type ._llcrypt registered [ 6023.399671] Key type .llcrypt registered [ 6023.599695] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6023.605453] alg: No test for adler32 (adler32-zlib) [ 6024.450319] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 6024.527207] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 6024.530315] LNet: Accept secure, port 988 [ 6026.111159] Key type lgssc registered [ 6026.442526] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6054.854135] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6056.100573] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6063.527565] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6085.313833] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [ 6085.918962] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 15:23:08 (1752693788) [ 6095.093778] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6096.252565] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6101.977352] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6118.383419] Lustre: Mounted lustre-client [ 6119.240241] LustreError: 268774:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff95cbc7325000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6119.246175] LustreError: 268774:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6119.267452] Lustre: Unmounted lustre-client [ 6136.519116] Key type lgssc unregistered [ 6136.642613] LNet: 269176:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6137.701950] LNet: Removed LNI 192.168.204.42@tcp [ 6137.935643] Key type .llcrypt unregistered [ 6137.936701] Key type ._llcrypt unregistered [ 6143.892660] Key type ._llcrypt registered [ 6143.893646] Key type .llcrypt registered [ 6144.389119] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [ 6144.971547] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 15:24:07 (1752693847) [ 6147.767236] Key type .llcrypt unregistered [ 6147.768296] Key type ._llcrypt unregistered [ 6150.412922] Key type ._llcrypt registered [ 6150.414126] Key type .llcrypt registered [ 6150.720526] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6150.725726] alg: No test for adler32 (adler32-zlib) [ 6151.581881] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 6151.661181] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 6151.662691] LNet: Accept secure, port 988 [ 6153.247128] Key type lgssc registered [ 6153.585762] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6170.716426] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6175.921226] Lustre: Mounted lustre-client [ 6176.361322] LustreError: 272181:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff95cbc53b2000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6176.366670] LustreError: 272181:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6176.385410] Lustre: Unmounted lustre-client [ 6193.679928] Key type lgssc unregistered [ 6193.812490] LNet: 272584:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6194.854805] LNet: Removed LNI 192.168.204.42@tcp [ 6195.113243] Key type .llcrypt unregistered [ 6195.114330] Key type ._llcrypt unregistered [ 6201.528489] Key type ._llcrypt registered [ 6201.529400] Key type .llcrypt registered [ 6201.968816] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 15:25:04 (1752693904) [ 6202.837639] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6202.867578] alg: No test for adler32 (adler32-zlib) [ 6203.707819] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 6203.780287] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 6203.781834] LNet: Accept secure, port 988 [ 6205.359124] Key type lgssc registered [ 6205.710977] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6271.936665] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6282.285916] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6283.535734] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6284.715541] Lustre: Mounted lustre-client [ 6285.276620] LustreError: 275620:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff95cbc450c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6285.284388] LustreError: 275620:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6285.305943] Lustre: Unmounted lustre-client [ 6302.420144] Key type lgssc unregistered [ 6302.547760] LNet: 276024:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6303.590728] LNet: Removed LNI 192.168.204.42@tcp [ 6303.942735] Key type .llcrypt unregistered [ 6303.943957] Key type ._llcrypt unregistered [ 6310.524095] Key type ._llcrypt registered [ 6310.525030] Key type .llcrypt registered [ 6311.035188] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 15:26:53 (1752694013) [ 6311.726646] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6311.732767] alg: No test for adler32 (adler32-zlib) [ 6312.590232] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 6312.675242] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 6312.676783] LNet: Accept secure, port 988 [ 6314.255132] Key type lgssc registered [ 6314.572591] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6331.677269] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6332.906960] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6338.990637] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6354.414226] Lustre: Mounted lustre-client [ 6359.522656] Lustre: lustre-MDT0000-mdc-ffff95cbc53b2000: Connection to lustre-MDT0000 (at 192.168.204.142@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6361.882137] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 6361.889478] LustreError: 279082:0:(file.c:6198:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [ 6361.998642] LustreError: 279101:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff95cbc53b2000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6361.999354] LustreError: MGC192.168.204.142@tcp: Connection to MGS (at 192.168.204.142@tcp) was lost; in progress operations using this service will fail [ 6362.003752] LustreError: 279101:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6362.020571] Lustre: Unmounted lustre-client [ 6372.592208] Key type lgssc unregistered [ 6372.715401] LNet: 279495:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6373.733327] LNet: Removed LNI 192.168.204.42@tcp [ 6373.953339] Key type .llcrypt unregistered [ 6373.954384] Key type ._llcrypt unregistered [ 6380.294578] Key type ._llcrypt registered [ 6380.296304] Key type .llcrypt registered [ 6380.746456] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 15:28:03 (1752694083) [ 6381.505950] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6381.581214] alg: No test for adler32 (adler32-zlib) [ 6382.453803] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 6382.547681] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 6382.551174] LNet: Accept secure, port 988 [ 6384.143148] Key type lgssc registered [ 6384.526638] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6401.132514] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6402.178111] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6408.317066] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6418.927493] Lustre: Mounted lustre-client [ 6424.034516] Lustre: lustre-MDT0001-mdc-ffff95cbc53b1800: Connection to lustre-MDT0001 (at 192.168.204.142@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6434.788775] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 6435.100849] LustreError: 282584:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff95cbc53b1800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6435.107738] LustreError: 282584:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6435.125211] Lustre: Unmounted lustre-client [ 6445.907672] Key type lgssc unregistered [ 6446.011497] LNet: 282976:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6447.077832] LNet: Removed LNI 192.168.204.42@tcp [ 6447.390220] Key type .llcrypt unregistered [ 6447.391193] Key type ._llcrypt unregistered [ 6453.076408] Key type ._llcrypt registered [ 6453.077357] Key type .llcrypt registered [ 6453.650198] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 15:29:15 (1752694155) [ 6459.771426] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6459.817195] alg: No test for adler32 (adler32-zlib) [ 6460.660810] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 6460.731158] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 6460.732621] LNet: Accept secure, port 988 [ 6462.311193] Key type lgssc registered [ 6462.891342] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6499.278450] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6500.434080] Lustre: Mounted lustre-client [ 6513.125991] Lustre: lustre-MDT0001-mdc-ffff95cbc53b1000: Connection to lustre-MDT0001 (at 192.168.204.142@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6527.463446] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 6537.705112] LustreError: 288309:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 6537.719199] Lustre: Unmounted lustre-client [ 6554.517696] Key type lgssc unregistered [ 6554.628879] LNet: 288710:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6555.686200] LNet: Removed LNI 192.168.204.42@tcp [ 6555.929530] Key type .llcrypt unregistered [ 6555.930486] Key type ._llcrypt unregistered [ 6562.088853] Key type ._llcrypt registered [ 6562.090609] Key type .llcrypt registered [ 6562.563110] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 15:31:04 (1752694264) [ 6563.050134] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [ 6563.657271] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 15:31:05 (1752694265) [ 6564.166742] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [ 6564.730216] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 15:31:07 (1752694267) [ 6565.213041] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [ 6565.841224] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 15:31:08 (1752694268) [ 6566.345260] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [ 6566.915857] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 15:31:09 (1752694269) [ 6567.462698] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [ 6567.965345] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 15:31:10 (1752694270) [ 6570.805185] Key type .llcrypt unregistered [ 6570.806107] Key type ._llcrypt unregistered [ 6573.063153] Key type ._llcrypt registered [ 6573.068345] Key type .llcrypt registered [ 6573.273713] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6573.278735] alg: No test for adler32 (adler32-zlib) [ 6574.120835] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 6574.202476] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 6574.203865] LNet: Accept secure, port 988 [ 6575.783139] Key type lgssc registered [ 6576.124320] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6605.833355] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6607.172101] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6615.590756] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6622.167654] Lustre: Mounted lustre-client [ 6622.571722] LustreError: 294291:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff95cbc4006000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6622.577918] LustreError: 294291:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6622.603089] Lustre: Unmounted lustre-client [ 6644.635857] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 15:32:27 (1752694347) [ 6653.849428] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6654.946704] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6660.241930] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6660.391561] Lustre: Mounted lustre-client [ 6660.679881] LustreError: 296402:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff95cbc87a6800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6660.685674] LustreError: 296402:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6660.687287] LustreError: 296402:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 6660.705610] Lustre: Unmounted lustre-client [ 6678.559121] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 15:33:00 (1752694380) [ 6702.861521] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6703.878367] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6709.225015] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6743.425936] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 15:34:05 (1752694445) [ 6747.030273] Key type lgssc unregistered [ 6747.160537] LNet: 301840:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6792.229912] LNet: Removed LNI 192.168.204.42@tcp [ 6792.448444] Key type .llcrypt unregistered [ 6792.449457] Key type ._llcrypt unregistered [ 6926.061855] Key type ._llcrypt registered [ 6926.062756] Key type .llcrypt registered [ 6926.295266] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6926.299951] alg: No test for adler32 (adler32-zlib) [ 6927.149048] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 6927.231390] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 6927.232900] LNet: Accept secure, port 988 [ 6928.815120] Key type lgssc registered [ 6929.196980] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6936.797640] Lustre: DEBUG MARKER: SKIP: conf-sanity test_73c unable to configure net [ 6940.485861] Key type lgssc unregistered [ 6940.610490] LNet: 303524:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6940.617467] LNet: Removed LNI 192.168.204.42@tcp [ 6940.840359] Key type .llcrypt unregistered [ 6940.841274] Key type ._llcrypt unregistered [ 6944.185703] Key type ._llcrypt registered [ 6944.186636] Key type .llcrypt registered [ 6944.640864] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 15:37:26 (1752694646) [ 6950.972944] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6950.998418] alg: No test for adler32 (adler32-zlib) [ 6951.849541] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 6951.927366] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 6951.928766] LNet: Accept secure, port 988 [ 6953.511120] Key type lgssc registered [ 6953.868177] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6970.429733] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 15:37:52 (1752694672) [ 6982.918360] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6984.103135] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6990.964949] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6992.129810] Lustre: Mounted lustre-client [ 7011.458404] Lustre: Mounted fs15246-client [ 7015.843057] LustreError: 309521:0:(lov_obd.c:784:lov_cleanup()) fs15246-clilov-ffff95cbc87a1000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7015.850157] LustreError: 309521:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 7015.860468] Lustre: Unmounted fs15246-client [ 7027.885165] LustreError: 309738:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff95cbc4697000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7027.889733] LustreError: 309738:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7027.891365] LustreError: 309738:0:(obd_class.h:479:obd_check_dev()) Skipped 3 previous similar messages [ 7027.909883] Lustre: Unmounted lustre-client [ 7044.838642] Key type lgssc unregistered [ 7044.952517] LNet: 310139:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7045.989916] LNet: Removed LNI 192.168.204.42@tcp [ 7046.195214] Key type .llcrypt unregistered [ 7046.196277] Key type ._llcrypt unregistered [ 7050.290678] Key type ._llcrypt registered [ 7050.292345] Key type .llcrypt registered [ 7050.690950] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 15:39:13 (1752694753) [ 7051.202471] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7051.221439] alg: No test for adler32 (adler32-zlib) [ 7052.075348] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 7052.156373] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 7052.157909] LNet: Accept secure, port 988 [ 7053.735109] Key type lgssc registered [ 7054.112471] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7093.938242] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7095.054887] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7102.763455] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7148.362733] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 7014 sec ========== 15:40:50 (1752694850) [ 7149.081051] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 15:40:51 (1752694851) === [ 7150.540997] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 15:40:52 (1752694852) === [ 7166.626940] Key type lgssc unregistered [ 7166.766071] LNet: 319268:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7166.780182] LNet: Removed LNI 192.168.204.42@tcp [ 7167.148734] Key type .llcrypt unregistered [ 7167.150366] Key type ._llcrypt unregistered