[ 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 432911424 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f5b50-0x000f5b5f] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F5970 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE1BB7 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE1A53 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 001A13 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE1AC7 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE1B57 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE1B8F 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe1a53-0xbffe1ac6] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe1a52] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe1ac7-0xbffe1b56] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe1b57-0xbffe1b8e] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe1b8f-0xbffe1bb6] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 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.001012] APIC: Switch to symmetric I/O mode setup [ 0.002000] x2apic enabled [ 0.002007] Switched APIC routing to physical x2apic. [ 0.003010] 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.006021] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.007009] pid_max: default: 32768 minimum: 301 [ 0.008140] LSM: Security Framework initializing [ 0.009033] Yama: becoming mindful. [ 0.010025] SELinux: Initializing. [ 0.012012] *** VALIDATE selinux *** [ 0.020099] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.025386] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.026142] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.027102] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.028153] *** VALIDATE tmpfs *** [ 0.030399] *** VALIDATE proc *** [ 0.031232] *** VALIDATE cgroup *** [ 0.032006] *** VALIDATE cgroup2 *** [ 0.033250] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.034147] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.035007] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.036033] Spectre V2 : User space: Vulnerable [ 0.037006] Speculative Store Bypass: Vulnerable [ 0.040000] debug: unmapping init [mem 0xffffffffa8e59000-0xffffffffa8e60fff] [ 0.041746] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.042633] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.043021] ... version: 2 [ 0.044007] ... bit width: 48 [ 0.045008] ... generic registers: 4 [ 0.046008] ... value mask: 0000ffffffffffff [ 0.047012] ... max period: 00007fffffffffff [ 0.048009] ... fixed-purpose events: 3 [ 0.049008] ... event mask: 000000070000000f [ 0.050260] rcu: Hierarchical SRCU implementation. [ 0.052342] smp: Bringing up secondary CPUs ... [ 0.053491] x86: Booting SMP configuration: [ 0.054013] .... node #0, CPUs: #1 #2 #3 [ 0.062120] smp: Brought up 1 node, 4 CPUs [ 0.064009] smpboot: Max logical packages: 1 [ 0.065008] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.215676] node 0 deferred pages initialised in 143ms [ 0.222461] devtmpfs: initialized [ 0.226492] x86/mm: Memory block size: 128MB [ 0.230000] gcov: version magic: 0x41383552 [ 0.234588] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.235113] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.236387] pinctrl core: initialized pinctrl subsystem [ 0.237239] [ 0.237640] ************************************************************* [ 0.238015] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.239025] ** ** [ 0.240021] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.241017] ** ** [ 0.242011] ** This means that this kernel is built to expose internal ** [ 0.243015] ** IOMMU data structures, which may compromise security on ** [ 0.244018] ** your system. ** [ 0.245018] ** ** [ 0.246020] ** If you see this message and you are not debugging the ** [ 0.247023] ** kernel, report this immediately to your vendor! ** [ 0.248028] ** ** [ 0.249025] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.250016] ************************************************************* [ 0.251916] NET: Registered protocol family 16 [ 0.252698] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.253094] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.254088] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.255666] cpuidle: using governor menu [ 0.257000] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.260841] PCI: Using configuration type 1 for base access [ 0.263166] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.277091] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.278000] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.283123] cryptd: max_cpu_qlen set to 1000 [ 0.288947] ACPI: Added _OSI(Module Device) [ 0.289023] ACPI: Added _OSI(Processor Device) [ 0.290015] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.291010] ACPI: Added _OSI(Processor Aggregator Device) [ 0.294974] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.298359] ACPI: Interpreter enabled [ 0.299053] ACPI: PM: (supports S0 S3 S4 S5) [ 0.300010] ACPI: Using IOAPIC for interrupt routing [ 0.301125] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.302402] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.312058] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.313038] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.314018] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.315100] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.317263] acpiphp: Slot [2] registered [ 0.318135] acpiphp: Slot [3] registered [ 0.319094] acpiphp: Slot [4] registered [ 0.320060] acpiphp: Slot [5] registered [ 0.321144] acpiphp: Slot [6] registered [ 0.322126] acpiphp: Slot [7] registered [ 0.323097] acpiphp: Slot [8] registered [ 0.324119] acpiphp: Slot [9] registered [ 0.325077] acpiphp: Slot [10] registered [ 0.326092] acpiphp: Slot [11] registered [ 0.327106] acpiphp: Slot [12] registered [ 0.328077] acpiphp: Slot [13] registered [ 0.329129] acpiphp: Slot [14] registered [ 0.330103] acpiphp: Slot [15] registered [ 0.331652] acpiphp: Slot [16] registered [ 0.332125] acpiphp: Slot [17] registered [ 0.334136] acpiphp: Slot [18] registered [ 0.336144] acpiphp: Slot [19] registered [ 0.337222] acpiphp: Slot [20] registered [ 0.339119] acpiphp: Slot [21] registered [ 0.341127] acpiphp: Slot [22] registered [ 0.342110] acpiphp: Slot [23] registered [ 0.344138] acpiphp: Slot [24] registered [ 0.346131] acpiphp: Slot [25] registered [ 0.347204] acpiphp: Slot [26] registered [ 0.349120] acpiphp: Slot [27] registered [ 0.351129] acpiphp: Slot [28] registered [ 0.354000] acpiphp: Slot [29] registered [ 0.355152] acpiphp: Slot [30] registered [ 0.357113] acpiphp: Slot [31] registered [ 0.358137] PCI host bridge to bus 0000:00 [ 0.359016] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.361020] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.363022] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.365022] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.370045] pci_bus 0000:00: root bus resource [mem 0x180000000-0x1ffffffff window] [ 0.372028] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.374218] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.382166] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.389171] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.398016] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.404185] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.407022] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.409016] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.412019] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.416501] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.420463] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.425051] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.429449] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.435036] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.451054] pci 0000:00:02.0: reg 0x20: [mem 0xfebf4000-0xfebf7fff 64bit pref] [ 0.459038] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.476123] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.484017] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.499050] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.522043] pci 0000:00:05.0: reg 0x20: [mem 0xfebf8000-0xfebfbfff 64bit pref] [ 0.533000] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.544036] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.552032] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.572019] pci 0000:00:06.0: reg 0x20: [mem 0xfebfc000-0xfebfffff 64bit pref] [ 0.585183] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.591528] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.598500] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.600528] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.603337] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.608246] iommu: Default domain type: Passthrough [ 0.611733] SCSI subsystem initialized [ 0.613397] ACPI: bus type USB registered [ 0.619253] usbcore: registered new interface driver usbfs [ 0.621150] usbcore: registered new interface driver hub [ 0.623216] usbcore: registered new device driver usb [ 0.626494] pps_core: LinuxPPS API ver. 1 registered [ 0.627015] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.631187] PTP clock support registered [ 0.635155] EDAC MC: Ver: 3.0.0 [ 0.647295] PCI: Using ACPI for IRQ routing [ 0.649000] NetLabel: Initializing [ 0.649000] NetLabel: domain hash size = 128 [ 0.649000] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.649000] NetLabel: unlabeled traffic allowed by default [ 0.653129] vgaarb: loaded [ 0.656272] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.658008] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.669559] clocksource: Switched to clocksource kvm-clock [ 0.800146] VFS: Disk quotas dquot_6.6.0 [ 0.801475] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.803723] *** VALIDATE ramfs *** [ 0.804798] *** VALIDATE hugetlbfs *** [ 0.806554] pnp: PnP ACPI init [ 0.808771] pnp: PnP ACPI: found 6 devices [ 0.828088] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.832297] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.834648] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.837054] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.839574] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.842050] pci_bus 0000:00: resource 8 [mem 0x180000000-0x1ffffffff window] [ 0.844901] NET: Registered protocol family 2 [ 0.847391] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.853093] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.856451] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.862472] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.865975] TCP: Hash tables configured (established 65536 bind 65536) [ 0.868542] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.871413] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.874105] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.877683] NET: Registered protocol family 1 [ 0.879829] RPC: Registered named UNIX socket transport module. [ 0.882846] RPC: Registered udp transport module. [ 0.884486] RPC: Registered tcp transport module. [ 0.886086] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.888988] NET: Registered protocol family 44 [ 0.890717] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.892476] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.894458] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.896442] PCI: CLS 0 bytes, default 64 [ 0.899751] Unpacking initramfs... [ 3.448701] debug: unmapping init [mem 0xffff8a617cc64000-0xffff8a617ffcffff] [ 3.454678] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 3.456763] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 3.459336] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 4.355725] Initialise system trusted keyrings [ 4.357131] Key type blacklist registered [ 4.358704] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 4.367475] zbud: loaded [ 4.370802] *** VALIDATE nfs *** [ 4.372801] *** VALIDATE nfs4 *** [ 4.374272] pstore: using deflate compression [ 4.377873] Platform Keyring initialized [ 4.532915] NET: Registered protocol family 38 [ 4.534220] Key type asymmetric registered [ 4.535505] Asymmetric key parser 'x509' registered [ 4.536830] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 4.546531] io scheduler mq-deadline registered [ 4.548283] io scheduler kyber registered [ 4.550227] io scheduler bfq registered [ 4.551803] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 4.555439] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 4.558337] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 4.560961] ACPI: Power Button [PWRF] [ 4.669845] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 4.799452] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 4.935362] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 4.973324] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 5.012694] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 5.019812] Non-volatile memory driver v1.3 [ 5.023171] Linux agpgart interface v0.103 [ 5.082719] virtio_blk virtio1: [vda] 134040 512-byte logical blocks (68.6 MB/65.4 MiB) [ 5.085837] vda: detected capacity change from 0 to 68628480 [ 5.138358] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 5.141549] vdb: detected capacity change from 0 to 1073741824 [ 5.152609] libphy: Fixed MDIO Bus: probed [ 5.166158] usbcore: registered new interface driver usbserial_generic [ 5.168460] usbserial: USB Serial support registered for generic [ 5.173828] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 5.178407] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 5.180180] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 5.186419] mousedev: PS/2 mouse device common for all mice [ 5.189758] rtc_cmos 00:05: RTC can wake from S4 [ 5.194137] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 5.196830] rtc_cmos 00:05: registered as rtc0 [ 5.207526] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 5.211804] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 5.218404] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 5.218478] intel_pstate: CPU model not supported [ 5.226829] hid: raw HID events driver (C) Jiri Kosina [ 5.240620] usbcore: registered new interface driver usbhid [ 5.242273] usbhid: USB HID core driver [ 5.243471] drop_monitor: Initializing network drop monitor service [ 5.246118] Initializing XFRM netlink socket [ 5.247819] NET: Registered protocol family 10 [ 5.256636] Segment Routing with IPv6 [ 5.257891] NET: Registered protocol family 17 [ 5.259776] mpls_gso: MPLS GSO support [ 5.265110] RAS: Correctable Errors collector initialized. [ 5.266692] AVX version of gcm_enc/dec engaged. [ 5.267958] AES CTR mode by8 optimization enabled [ 5.444371] sched_clock: Marking stable (5444338381, 0)->(6534176586, -1089838205) [ 5.451237] registered taskstats version 1 [ 5.453101] Loading compiled-in X.509 certificates [ 5.455228] zswap: loaded using pool lzo/zbud [ 5.495599] Key type big_key registered [ 5.526603] Key type encrypted registered [ 5.528187] ima: No TPM chip found, activating TPM-bypass! [ 5.532598] ima: Allocated hash algorithm: sha1 [ 5.535160] ima: No architecture policies found [ 5.539404] evm: Initialising EVM extended attributes: [ 5.541557] evm: security.selinux [ 5.543638] evm: security.ima [ 5.545562] evm: security.capability [ 5.551314] evm: HMAC attrs: 0x1 [ 5.554699] rtc_cmos 00:05: setting system clock to 2025-12-29 18:54:57 UTC (1767034497) [ 5.561259] debug: unmapping init [mem 0xffffffffa9e03000-0xffffffffa9ffffff] [ 5.573957] debug: unmapping init [mem 0xffffffffa8b82000-0xffffffffa8e58fff] [ 5.586064] Write protecting the kernel read-only data: 28672k [ 5.593287] debug: unmapping init [mem 0xffffffffa7203000-0xffffffffa73fffff] [ 5.597668] debug: unmapping init [mem 0xffffffffa7b14000-0xffffffffa7bfffff] [ 5.655315] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 5.666576] systemd[1]: Detected virtualization kvm. [ 5.668607] systemd[1]: Detected architecture x86-64. [ 5.670948] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 5.708894] systemd[1]: No hostname configured. [ 5.713237] systemd[1]: Set hostname to . [ 5.716169] random: systemd: uninitialized urandom read (16 bytes read) [ 5.720945] systemd[1]: Initializing machine ID from random generator. [ 5.883476] random: ln: uninitialized urandom read (6 bytes read) [ 6.150676] random: systemd: uninitialized urandom read (16 bytes read) [ 6.153881] systemd[1]: Reached target Timers. [ OK ] Reached target Timers. [ 6.160147] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ 6.164483] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ OK ] Reached target Slices. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Local File Systems. [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Swap. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Listening on Journal Socket. Starting Setup Virtual Console... Starting Create Volatile Files and Directories... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Started Memstrack Anylazing Service. [ OK ] Reached target Sockets. Starting Apply Kernel Variables... Starting Journal Service... [ OK ] Started Setup Virtual Console. [ OK ] Started Create Volatile Files and Directories. [ 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 Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 7.810636] device-mapper: uevent: version 1.0.3 [ 7.813710] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting dracut initqueue hook... [ 9.712585] virtio_net virtio0 ens2: renamed from eth0 [ 10.221543] scsi host0: ata_piix [ 10.309927] scsi host1: ata_piix [ 10.311836] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 10.316094] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 13.015549] random: fast init done [ 15.088249] random: crng init done [ 15.089354] random: 7 urandom warning(s) missed due to ratelimiting [ 17.324335] dracut-initqueue[585]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 19.225649] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped target Timers. Stopping Hardware RNG Entropy Gatherer Daemon... [ 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 Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Slices. [ OK ] Stopped target Sockets. [ OK ] Stopped target Paths. [ OK ] Stopped target System Initialization. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped target Swap. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 21.621653] printk: systemd: 26 output lines suppressed due to ratelimiting [ 22.186720] SELinux: Disabled at runtime. [ 22.261230] 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) [ 22.272211] systemd[1]: Detected virtualization kvm. [ 22.277205] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 23.752977] systemd[1]: initrd-switch-root.service: Succeeded. [ 23.766210] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 23.787689] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 23.791048] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 23.794281] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 23.826272] systemd[1]: Starting Journal Service... Starting Journal Service... [ 23.851228] systemd[1]: Starting Create list of required static device nodes for the current kernel... Starting Create list of required st…ce nodes for the current kernel... Mounting Huge Pages File System... [ OK ] Started Forward Password Requests to Wall Directory Watch. Activating swap /dev/disk/by-label/SWAP... [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Listening on udev Control Socket. [ OK ] Created slice system-serial\x2dgetty.slice. [ 24.069158] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Created slice User and Session Slice. [ OK ] Reached target rpc_pipefs.target. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Slices. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd Root File System. [ OK ] Stopped target Initrd File Systems. [ OK ] Listening on Process Core Dump Socket. [ OK ] Listening on initctl Compatibility Named Pipe. Starting udev Coldplug all Devices... [ OK ] Reached target RPC Port Mapper. Mounting POSIX Message Queue File System... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. Starting Remount Root and Kernel File Systems... Mounting Kernel Debug File System... [ OK ] Created slice system-getty.slice. Starting Apply Kernel Variables... [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Started Journal Service. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted Huge Pages File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted POSIX Message Queue File System. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted Kernel Debug File System. [ OK ] Started Apply Kernel Variables. Starting Configure read-only root support... [ OK ] Reached target Swap. Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started udev Coldplug all Devices. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... Starting udev Kernel Device Manager... [ OK ] Mounted /mnt. [ 25.310276] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 26.358429] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 26.478556] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 27.282848] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 27.335392] EDAC sbridge: Ver: 1.1.2 [ 29.595088] hrtimer: interrupt took 2223463 ns [ 30.893920] Key type dns_resolver registered [* ] A start job is running for Configur…-only root support (7s / no limit) [** ] A start job is running for Configur…-only root support (8s / no limit)[ 31.759650] NFS: Registering the id_resolver key type [ 31.761866] Key type id_resolver registered [ 31.766922] Key type id_legacy registered [*** ] A start job is running for Configur…-only root support (8s / no limit) [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Mark the need to relabel after reboot... Starting Rebuild Dynamic Linker Cache... Starting Create Volatile Files and Directories... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started dnf makecache --timer. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started irqbalance daemon. Starting Login Service... [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Reached target sshd-keygen.target. Starting Restore /run/initramfs on shutdown... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting OpenSSH server daemon... Starting Dynamic System Tuning Daemon... Starting GSSAPI Proxy Daemon... [ OK ] Started Login Service. 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 Command Scheduler. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS1. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Crash recovery kernel arming... Starting System Logging Service... Starting Notify NFS peers of a restart... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg429-client login: [ 67.014515] libcfs: loading out-of-tree module taints kernel. [ 67.087946] Key type ._llcrypt registered [ 67.089434] Key type .llcrypt registered [ 67.301386] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 67.305996] alg: No test for adler32 (adler32-zlib) [ 68.286668] Lustre: Lustre: Build Version: 2.17.0_RC3_1_ga0d6154 [ 68.540092] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 70.152184] Key type lgssc registered [ 70.713080] Lustre: Echo OBD driver; http://www.lustre.org/ [ 150.649278] Lustre: Mounted lustre-client [ 153.262679] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 166.801109] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing check_logdir /tmp/testlogs/ [ 169.147824] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing yml_node [ 171.489694] Lustre: DEBUG MARKER: Client: 2.17.0.RC3 [ 172.964330] Lustre: DEBUG MARKER: MDS: 2.17.0.RC3 [ 174.211927] Lustre: DEBUG MARKER: OSS: 2.17.0.RC3 [ 174.928904] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Mon Dec 29 13:57:46 EST 2025 [ 176.098522] Lustre: lustre-OST0000-osc-ffff8a61c71b2800: disconnect after 24s idle [ 182.574510] Lustre: DEBUG MARKER: excepting tests: 102 106 115 32newtarball [ 183.272538] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 183.971956] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 194.219080] LustreError: 8990:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 194.234760] Lustre: Unmounted lustre-client [ 252.653360] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 254.395291] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 264.326465] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 295.640915] Lustre: DEBUG MARKER: == conf-sanity test 76a: set permanent params with lctl across mounts ========================================================== 13:59:46 (1767034786) [ 309.617395] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 311.456584] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 320.661163] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 326.156129] Lustre: Mounted lustre-client [ 330.964072] LustreError: 14218:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a61c68cc800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 330.979360] LustreError: 14218:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 330.982400] LustreError: 14218:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 331.020501] Lustre: Unmounted lustre-client [ 397.069073] Lustre: Mounted lustre-client [ 400.645074] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 413.528298] LustreError: 16787:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a61c6878800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 413.548793] LustreError: 16787:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 413.552668] LustreError: 16787:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 413.603336] Lustre: Unmounted lustre-client [ 486.724152] Lustre: Mounted lustre-client [ 489.859346] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 498.497810] LustreError: 19016:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a61c6847800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 498.505950] LustreError: 19016:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 498.519061] LustreError: 19016:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 498.529938] LustreError: 19016:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 498.579111] Lustre: Unmounted lustre-client [ 537.319197] Lustre: DEBUG MARKER: == conf-sanity test 76b: verify params log setup correctly ========================================================== 14:03:48 (1767035028) [ 581.784427] Lustre: Mounted lustre-client [ 584.508867] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 591.818797] LustreError: 22232:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a61c357e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 591.823867] LustreError: 22232:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 591.831898] LustreError: 22232:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 591.833996] LustreError: 22232:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 591.868156] Lustre: Unmounted lustre-client [ 628.197139] Lustre: DEBUG MARKER: == conf-sanity test 76c: verify changelog_mask is applied with lctl set_param -P ========================================================== 14:05:19 (1767035119) [ 668.678783] Lustre: Mounted lustre-client [ 671.299684] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 684.012527] Lustre: lustre-MDT0000-mdc-ffff8a61dbba6800: Connection to lustre-MDT0000 (at 192.168.204.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 689.127183] Lustre: lustre-MDT0001-mdc-ffff8a61dbba6800: Connection to lustre-MDT0001 (at 192.168.204.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 694.242890] Lustre: lustre-OST0000-osc-ffff8a61dbba6800: disconnect after 24s idle [ 694.246486] Lustre: Skipped 1 previous similar message [ 699.364481] LustreError: MGC192.168.204.129@tcp: Connection to MGS (at 192.168.204.129@tcp) was lost; in progress operations using this service will fail [ 699.375958] Lustre: Evicted from MGS (at 192.168.204.129@tcp) after server handle changed from 0x96ff131175926c9d to 0x96ff131175926f0c [ 699.382255] Lustre: MGC192.168.204.129@tcp: Connection restored to 192.168.204.129@tcp (at 192.168.204.129@tcp) [ 707.898289] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 714.725509] LustreError: lustre-MDT0000-mdc-ffff8a61dbba6800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 714.733040] Lustre: lustre-MDT0000-mdc-ffff8a61dbba6800: Connection restored to 192.168.204.129@tcp (at 192.168.204.129@tcp) [ 715.774594] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 7 sec [ 717.184150] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 717.798593] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 718.598479] LustreError: 26591:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a61dbba6800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 718.603729] LustreError: 26591:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 718.606968] LustreError: 26591:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 718.609891] LustreError: 26591:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 718.639660] Lustre: Unmounted lustre-client [ 753.468858] Lustre: DEBUG MARKER: == conf-sanity test 76d: verify llite.*.xattr_cache can be set by 'lctl set_param -P' correctly ========================================================== 14:07:24 (1767035244) [ 793.617662] Lustre: Mounted lustre-client [ 796.458307] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 805.826157] LustreError: 29651:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a61c7068000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 805.831282] LustreError: 29651:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 805.837694] LustreError: 29651:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 805.839762] LustreError: 29651:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 805.865121] Lustre: Unmounted lustre-client [ 842.700635] Lustre: DEBUG MARKER: == conf-sanity test 77: comma-separated MGS NIDs and failover node NIDs ========================================================== 14:08:54 (1767035334) [ 843.618097] Lustre: DEBUG MARKER: SKIP: conf-sanity test_77 mixed loopback and real device not working [ 844.325377] Lustre: DEBUG MARKER: == conf-sanity test 78: run resize2fs on MDT and OST filesystems ========================================================== 14:08:55 (1767035335) [ 864.889973] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 873.031406] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 873.201802] Lustre: Mounted lustre-client [ 873.202811] Lustre: Skipped 2 previous similar messages [ 873.722362] Lustre: DEBUG MARKER: create test files [ 883.973489] LustreError: 33268:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a61c706a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 883.978817] LustreError: 33268:0:(lov_obd.c:783:lov_cleanup()) Skipped 5 previous similar messages [ 884.011968] LustreError: 33268:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 884.014816] LustreError: 33268:0:(obd_class.h:479:obd_check_dev()) Skipped 27 previous similar messages [ 884.052713] Lustre: Unmounted lustre-client [ 884.054161] Lustre: Skipped 2 previous similar messages [ 894.629729] Key type lgssc unregistered [ 894.770553] LNet: 33618:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 894.773951] LNetError: 33618:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 894.784144] LNet: Removed LNI 192.168.204.29@tcp [ 895.121183] Key type .llcrypt unregistered [ 895.122895] Key type ._llcrypt unregistered [ 904.092642] Key type ._llcrypt registered [ 904.094363] Key type .llcrypt registered [ 904.379636] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 904.385978] alg: No test for adler32 (adler32-zlib) [ 905.277188] Lustre: Lustre: Build Version: 2.17.0_RC3_1_ga0d6154 [ 905.374952] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 906.968157] Key type lgssc registered [ 907.441318] Lustre: Echo OBD driver; http://www.lustre.org/ [ 921.768338] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 928.378448] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 929.602105] Lustre: Mounted lustre-client [ 931.543571] Lustre: DEBUG MARKER: check files after expanding the MDT and OST filesystems [ 933.604345] Lustre: DEBUG MARKER: create more files after expanding the MDT and OST filesystems [ 934.986859] LustreError: 36080:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a61dbba1800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 935.029652] LustreError: 36080:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 935.068469] Lustre: Unmounted lustre-client [ 945.430047] Key type lgssc unregistered [ 945.562635] LNet: 36430:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 945.568315] LNetError: 36430:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 945.579748] LNet: Removed LNI 192.168.204.29@tcp [ 945.888134] Key type .llcrypt unregistered [ 945.889939] Key type ._llcrypt unregistered [ 955.301959] Key type ._llcrypt registered [ 955.303500] Key type .llcrypt registered [ 955.498728] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 955.504721] alg: No test for adler32 (adler32-zlib) [ 956.379967] Lustre: Lustre: Build Version: 2.17.0_RC3_1_ga0d6154 [ 956.467911] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 958.056129] Key type lgssc registered [ 958.459463] Lustre: Echo OBD driver; http://www.lustre.org/ [ 971.221644] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 977.500941] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 984.028570] Lustre: Mounted lustre-client [ 986.011577] Lustre: DEBUG MARKER: check files after shrinking the MDT and OST filesystems [ 987.826590] LustreError: 38770:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a61e0d49000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 987.855819] LustreError: 38770:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 987.894188] Lustre: Unmounted lustre-client [ 995.965370] Key type lgssc unregistered [ 996.088772] LNet: 39119:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 996.091875] LNetError: 39119:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 996.101393] LNet: Removed LNI 192.168.204.29@tcp [ 996.347910] Key type .llcrypt unregistered [ 996.349040] Key type ._llcrypt unregistered [ 1006.271163] Key type ._llcrypt registered [ 1006.275685] Key type .llcrypt registered [ 1006.555660] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1006.562185] alg: No test for adler32 (adler32-zlib) [ 1007.429767] Lustre: Lustre: Build Version: 2.17.0_RC3_1_ga0d6154 [ 1007.519627] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 1009.104153] Key type lgssc registered [ 1009.526931] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1040.931819] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1042.272628] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1050.066656] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1071.986838] Lustre: DEBUG MARKER: == conf-sanity test 79: format MDT/OST without mgs option (should return errors) ========================================================== 14:12:43 (1767035563) [ 1116.923079] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1118.283937] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1126.143783] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1148.188248] Lustre: DEBUG MARKER: == conf-sanity test 80: mgc import reconnect race ======== 14:13:59 (1767035639) [ 1158.947370] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1160.310673] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1166.982555] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1206.239706] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 1234.702770] Lustre: DEBUG MARKER: == conf-sanity test 81: sparse OST indexing ============== 14:15:26 (1767035726) [ 1235.263455] Lustre: DEBUG MARKER: SKIP: conf-sanity test_81 needs >= 3 OSTs [ 1235.829068] Lustre: DEBUG MARKER: == conf-sanity test 82a: specify OSTs for file (succeed) or directory (succeed) ========================================================== 14:15:27 (1767035727) [ 1236.348893] Lustre: DEBUG MARKER: SKIP: conf-sanity test_82a needs >= 3 OSTs [ 1236.925975] Lustre: DEBUG MARKER: == conf-sanity test 82b: specify OSTs for file with --pool and --ost-list options ========================================================== 14:15:28 (1767035728) [ 1237.461074] Lustre: DEBUG MARKER: SKIP: conf-sanity test_82b needs >= 4 OSTs [ 1238.011044] Lustre: DEBUG MARKER: == conf-sanity test 82c: specify sparse OSTs for setstripe (should not crash) ========================================================== 14:15:29 (1767035729) [ 1238.516556] Lustre: DEBUG MARKER: SKIP: conf-sanity test_82c needs >= 4 OSTs [ 1239.095129] Lustre: DEBUG MARKER: == conf-sanity test 83: ENOSPACE on OST doesn't cause message VFS: Busy inodes after unmount ... ========================================================== 14:15:30 (1767035730) [ 1239.620148] Lustre: DEBUG MARKER: mount the OST /dev/mapper/ost1_flakey as a ldiskfs filesystem [ 1242.226118] Lustre: DEBUG MARKER: run llverfs in partial mode on the OST ldiskfs /mnt/lustre-ost1 [ 1244.568364] Lustre: DEBUG MARKER: unmount the OST /dev/mapper/ost1_flakey [ 1284.993980] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1286.160852] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1293.440152] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1314.383357] Lustre: DEBUG MARKER: == conf-sanity test 84: check recovery_hard_time ========= 14:16:45 (1767035805) [ 1324.390138] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1325.527823] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1331.379053] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1351.785314] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 1359.933268] Lustre: Mounted lustre-client [ 1369.062378] Lustre: DEBUG MARKER: local REPLAY BARRIER on lustre-MDT0000 [ 1380.836601] Lustre: lustre-MDT0000-mdc-ffff8a61c4be3800: Connection to lustre-MDT0000 (at 192.168.204.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1396.195724] LustreError: MGC192.168.204.129@tcp: Connection to MGS (at 192.168.204.129@tcp) was lost; in progress operations using this service will fail [ 1396.202342] Lustre: Evicted from MGS (at 192.168.204.129@tcp) after server handle changed from 0xa174cb4fb433e3c5 to 0xa174cb4fb434a58e [ 1396.207803] Lustre: MGC192.168.204.129@tcp: Connection restored to 192.168.204.129@tcp (at 192.168.204.129@tcp) [ 1396.966747] LustreError: 39728:0:(client.c:3418:ptlrpc_replay_interpret()) @@@ status 301, old was 0 req@0000000091cfeacd x1852871015409920/t8589934597(8589934597) o101->lustre-MDT0000-mdc-ffff8a61c4be3800@192.168.204.129@tcp:12/10 lens 592/608 e 0 to 0 dl 1767035904 ref 2 fl Interpret:RQU/604/0 rc 301/301 job:'' uid:0 gid:0 projid:0 [ 1397.597645] LustreError: 39728:0:(client.c:3418:ptlrpc_replay_interpret()) @@@ status 301, old was 0 req@000000002b12c306 x1852871015410560/t8589934599(8589934599) o101->lustre-MDT0000-mdc-ffff8a61c4be3800@192.168.204.129@tcp:12/10 lens 592/608 e 0 to 0 dl 1767035905 ref 2 fl Interpret:RQU/604/0 rc 301/301 job:'' uid:0 gid:0 projid:0 [ 1398.869066] LustreError: 39728:0:(client.c:3418:ptlrpc_replay_interpret()) @@@ status 301, old was 0 req@00000000e2b1f29e x1852871015411840/t8589934603(8589934603) o101->lustre-MDT0000-mdc-ffff8a61c4be3800@192.168.204.129@tcp:12/10 lens 592/608 e 0 to 0 dl 1767035906 ref 2 fl Interpret:RQU/604/0 rc 301/301 job:'' uid:0 gid:0 projid:0 [ 1398.877140] LustreError: 39728:0:(client.c:3418:ptlrpc_replay_interpret()) Skipped 1 previous similar message [ 1401.404423] LustreError: 39728:0:(client.c:3418:ptlrpc_replay_interpret()) @@@ status 301, old was 0 req@00000000d6aa3d87 x1852871015414400/t8589934611(8589934611) o101->lustre-MDT0000-mdc-ffff8a61c4be3800@192.168.204.129@tcp:12/10 lens 592/608 e 0 to 0 dl 1767035909 ref 2 fl Interpret:RQU/604/0 rc 301/301 job:'' uid:0 gid:0 projid:0 [ 1401.412790] LustreError: 39728:0:(client.c:3418:ptlrpc_replay_interpret()) Skipped 3 previous similar messages [ 1405.795956] LustreError: 39728:0:(client.c:3418:ptlrpc_replay_interpret()) @@@ status 301, old was 0 req@00000000e0a78c38 x1852871015418880/t8589934625(8589934625) o101->lustre-MDT0000-mdc-ffff8a61c4be3800@192.168.204.129@tcp:12/10 lens 592/608 e 0 to 0 dl 1767035913 ref 2 fl Interpret:RQU/604/0 rc 301/301 job:'' uid:0 gid:0 projid:0 [ 1405.802584] LustreError: 39728:0:(client.c:3418:ptlrpc_replay_interpret()) Skipped 6 previous similar messages [ 1413.940997] LustreError: 39728:0:(client.c:3418:ptlrpc_replay_interpret()) @@@ status 301, old was 0 req@00000000a0998585 x1852871015427200/t8589934651(8589934651) o101->lustre-MDT0000-mdc-ffff8a61c4be3800@192.168.204.129@tcp:12/10 lens 592/608 e 0 to 0 dl 1767035921 ref 2 fl Interpret:RQU/604/0 rc 301/301 job:'' uid:0 gid:0 projid:0 [ 1413.949486] LustreError: 39728:0:(client.c:3418:ptlrpc_replay_interpret()) Skipped 12 previous similar messages [ 1430.189713] LustreError: 39728:0:(client.c:3418:ptlrpc_replay_interpret()) @@@ status 301, old was 0 req@00000000e616f3cd x1852871015443840/t8589934703(8589934703) o101->lustre-MDT0000-mdc-ffff8a61c4be3800@192.168.204.129@tcp:12/10 lens 592/608 e 0 to 0 dl 1767035937 ref 2 fl Interpret:RQU/604/0 rc 301/301 job:'' uid:0 gid:0 projid:0 [ 1430.200962] LustreError: 39728:0:(client.c:3418:ptlrpc_replay_interpret()) Skipped 25 previous similar messages [ 1455.791256] LustreError: lustre-MDT0000-mdc-ffff8a61c4be3800: operation ldlm_enqueue to node 192.168.204.129@tcp failed: rc = -107 [ 1455.820548] Lustre: lustre-MDT0000-mdc-ffff8a61c71b3000: Connection restored to 192.168.204.129@tcp (at 192.168.204.129@tcp) [ 1457.640613] LustreError: lustre-MDT0000-mdc-ffff8a61c4be3800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 1457.744189] LustreError: 60805:0:(file.c:6123:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -5 [ 1457.945427] Lustre: lustre-MDT0000-mdc-ffff8a61c4be3800: Connection restored to 192.168.204.129@tcp (at 192.168.204.129@tcp) [ 1460.039288] LustreError: 60909:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a61c4be3800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1460.046042] LustreError: 60909:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 1460.069608] Lustre: Unmounted lustre-client [ 1494.751952] Lustre: DEBUG MARKER: == conf-sanity test 85: osd_ost init: fail ea_fid_set ==== 14:19:46 (1767035986) [ 1547.161984] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1550.946100] Lustre: DEBUG MARKER: == conf-sanity test 86: Replacing mkfs.lustre -G option == 14:20:42 (1767036042) [ 1570.062273] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1611.945396] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1613.189581] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1621.380528] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1651.354479] Lustre: DEBUG MARKER: == conf-sanity test 87: check if MDT inode can hold EAs with N stripes properly ========================================================== 14:22:22 (1767036142) [ 1652.411735] Key type lgssc unregistered [ 1652.520538] LNet: 68086:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1652.524067] LNetError: 68086:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1652.532729] LNet: Removed LNI 192.168.204.29@tcp [ 1652.775637] Key type .llcrypt unregistered [ 1652.777024] Key type ._llcrypt unregistered [ 1662.296246] Key type ._llcrypt registered [ 1662.297369] Key type .llcrypt registered [ 1662.474452] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1662.485579] alg: No test for adler32 (adler32-zlib) [ 1663.348897] Lustre: Lustre: Build Version: 2.17.0_RC3_1_ga0d6154 [ 1663.441276] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 1665.032166] Key type lgssc registered [ 1665.444558] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1701.624973] Lustre: Mounted lustre-client [ 1701.902372] LustreError: 71311:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a61c79ff000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1701.909683] LustreError: 71311:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1701.926152] Lustre: Unmounted lustre-client [ 1716.512693] Lustre: DEBUG MARKER: == conf-sanity test 88: check the default mount options can be overridden ========================================================== 14:23:27 (1767036207) [ 1726.825592] Lustre: DEBUG MARKER: == conf-sanity test 89: check tunefs --param and --erase-param{s} options ========================================================== 14:23:38 (1767036218) [ 1764.748847] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL failover.node=192.0.2.254@tcp0,mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1765.913063] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL failover.node=192.0.2.254@tcp0,mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1773.346670] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) failover.node=192.0.2.254@tcp0,osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1794.597104] Lustre: DEBUG MARKER: == conf-sanity test 90a: check max_mod_rpcs_in_flight is enforced ========================================================== 14:24:46 (1767036286) [ 1803.996243] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1805.105882] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1810.956518] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1826.799759] Lustre: Mounted lustre-client [ 1844.192141] Lustre: 79541:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1767036320/real 1767036320] req@0000000092e10a88 x1852871703292416/t0(0) o36->lustre-MDT0000-mdc-ffff8a61c4814000@192.168.204.129@tcp:12/10 lens 488/512 e 0 to 1 dl 1767036336 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1844.192265] Lustre: lustre-MDT0000-mdc-ffff8a61c4814000: Connection to lustre-MDT0000 (at 192.168.204.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1844.198455] Lustre: 79541:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 3 previous similar messages [ 1844.208072] Lustre: lustre-MDT0000-mdc-ffff8a61c4814000: Connection restored to 192.168.204.129@tcp (at 192.168.204.129@tcp) [ 1860.064173] Lustre: 79584:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1767036336/real 1767036336] req@0000000071e79175 x1852871703301760/t0(0) o36->lustre-MDT0000-mdc-ffff8a61c4814000@192.168.204.129@tcp:12/10 lens 488/512 e 0 to 1 dl 1767036352 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1860.064353] Lustre: lustre-MDT0000-mdc-ffff8a61c4814000: Connection to lustre-MDT0000 (at 192.168.204.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1860.074422] Lustre: 79584:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 3 previous similar messages [ 1860.088913] Lustre: lustre-MDT0000-mdc-ffff8a61c4814000: Connection restored to 192.168.204.129@tcp (at 192.168.204.129@tcp) [ 1860.396984] LustreError: 79629:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a61c4814000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1860.400908] LustreError: 79629:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 1860.407730] LustreError: 79629:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1860.409335] LustreError: 79629:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 1860.431361] Lustre: Unmounted lustre-client [ 1878.169917] Key type lgssc unregistered [ 1878.288555] LNet: 80032:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1878.291857] LNetError: 80032:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1878.299763] LNet: Removed LNI 192.168.204.29@tcp [ 1878.563446] Key type .llcrypt unregistered [ 1878.564423] Key type ._llcrypt unregistered [ 1884.289166] Key type ._llcrypt registered [ 1884.290259] Key type .llcrypt registered [ 1884.821263] Lustre: DEBUG MARKER: == conf-sanity test 90b: check max_mod_rpcs_in_flight is enforced after update ========================================================== 14:26:16 (1767036376) [ 1885.864298] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1885.873082] alg: No test for adler32 (adler32-zlib) [ 1886.755437] Lustre: Lustre: Build Version: 2.17.0_RC3_1_ga0d6154 [ 1886.859629] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 1888.456219] Key type lgssc registered [ 1888.933959] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1906.207843] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1907.359466] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1913.207692] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1923.571325] Lustre: Mounted lustre-client [ 1944.544083] Lustre: 83140:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1767036420/real 1767036420] req@00000000af8855ce x1852871937106944/t0(0) o36->lustre-MDT0000-mdc-ffff8a61e0bad800@192.168.204.129@tcp:12/10 lens 488/512 e 0 to 1 dl 1767036436 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1944.550065] Lustre: lustre-MDT0000-mdc-ffff8a61e0bad800: Connection to lustre-MDT0000 (at 192.168.204.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1944.558428] Lustre: lustre-MDT0000-mdc-ffff8a61e0bad800: Connection restored to 192.168.204.129@tcp (at 192.168.204.129@tcp) [ 1961.440165] Lustre: 83186:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1767036437/real 1767036437] req@00000000b7a34304 x1852871937120128/t0(0) o36->lustre-MDT0001-mdc-ffff8a61e0bad800@192.168.204.129@tcp:12/10 lens 488/512 e 0 to 1 dl 1767036453 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1961.440504] Lustre: lustre-MDT0001-mdc-ffff8a61e0bad800: Connection to lustre-MDT0001 (at 192.168.204.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1961.447806] Lustre: 83186:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 3 previous similar messages [ 1961.458485] Lustre: lustre-MDT0001-mdc-ffff8a61e0bad800: Connection restored to 192.168.204.129@tcp (at 192.168.204.129@tcp) [ 1977.312174] Lustre: 83223:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1767036453/real 1767036453] req@0000000005c0664b x1852871937126656/t0(0) o36->lustre-MDT0001-mdc-ffff8a61e0bad800@192.168.204.129@tcp:12/10 lens 488/512 e 0 to 1 dl 1767036469 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1977.312174] Lustre: 83219:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1767036453/real 1767036453] req@00000000c5c0a263 x1852871937126144/t0(0) o36->lustre-MDT0001-mdc-ffff8a61e0bad800@192.168.204.129@tcp:12/10 lens 488/512 e 0 to 1 dl 1767036469 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1977.312198] Lustre: 83223:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 2 previous similar messages [ 1977.312291] Lustre: lustre-MDT0001-mdc-ffff8a61e0bad800: Connection to lustre-MDT0001 (at 192.168.204.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1977.322887] Lustre: 83219:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 3 previous similar messages [ 1977.324223] Lustre: lustre-MDT0001-mdc-ffff8a61e0bad800: Connection restored to 192.168.204.129@tcp (at 192.168.204.129@tcp) [ 1992.303517] LustreError: 83279:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a61e0bad800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1992.312934] LustreError: 83279:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1992.337540] Lustre: Unmounted lustre-client [ 1992.944535] Lustre: Mounted lustre-client [ 2009.056167] Lustre: 83374:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1767036485/real 1767036485] req@00000000256d3855 x1852871937165824/t0(0) o36->lustre-MDT0000-mdc-ffff8a61c750e000@192.168.204.129@tcp:12/10 lens 488/512 e 0 to 1 dl 1767036501 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2009.056279] Lustre: lustre-MDT0000-mdc-ffff8a61c750e000: Connection to lustre-MDT0000 (at 192.168.204.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2009.063427] Lustre: 83374:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 3 previous similar messages [ 2009.076300] Lustre: lustre-MDT0000-mdc-ffff8a61c750e000: Connection restored to 192.168.204.129@tcp (at 192.168.204.129@tcp) [ 2025.952180] Lustre: 83449:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1767036501/real 1767036501] req@00000000daa0093e x1852871937188224/t0(0) o36->lustre-MDT0000-mdc-ffff8a61c750e000@192.168.204.129@tcp:12/10 lens 488/512 e 0 to 1 dl 1767036517 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2025.952256] Lustre: lustre-MDT0000-mdc-ffff8a61c750e000: Connection to lustre-MDT0000 (at 192.168.204.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2025.959948] Lustre: 83449:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 3 previous similar messages [ 2025.970785] Lustre: lustre-MDT0000-mdc-ffff8a61c750e000: Connection restored to 192.168.204.129@tcp (at 192.168.204.129@tcp) [ 2027.047229] LustreError: 83502:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a61c750e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2027.056109] LustreError: 83502:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2027.057698] LustreError: 83502:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 2027.079510] Lustre: Unmounted lustre-client [ 2044.169710] Key type lgssc unregistered [ 2044.275481] LNet: 83902:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2044.277269] LNetError: 83902:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2044.288487] LNet: Removed LNI 192.168.204.29@tcp [ 2044.508091] Key type .llcrypt unregistered [ 2044.509665] Key type ._llcrypt unregistered [ 2049.304301] Key type ._llcrypt registered [ 2049.305236] Key type .llcrypt registered [ 2049.752847] Lustre: DEBUG MARKER: == conf-sanity test 90c: check max_mod_rpcs_in_flight update limits ========================================================== 14:29:01 (1767036541) [ 2050.412247] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2050.458073] alg: No test for adler32 (adler32-zlib) [ 2051.309542] Lustre: Lustre: Build Version: 2.17.0_RC3_1_ga0d6154 [ 2051.391106] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 2052.976095] Key type lgssc registered [ 2053.383025] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2069.839315] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2070.992196] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2076.760137] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2092.528340] Lustre: Mounted lustre-client [ 2094.991412] LustreError: 87019:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a61c750b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2094.998267] LustreError: 87019:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2095.019865] Lustre: Unmounted lustre-client [ 2095.461316] Lustre: Mounted lustre-client [ 2095.520824] LustreError: 87081:0:(genops.c:2257:obd_set_max_mod_rpcs_in_flight()) lustre-MDT0000-mdc-ffff8a61e0501800: can't set max_mod_rpcs_in_flight=9 higher than mdt.*.max_mod_rpcs_in_flight=8 returned by the MDT server at connection. [ 2096.395555] LustreError: 87115:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a61e0501800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2096.402600] LustreError: 87115:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2096.404210] LustreError: 87115:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 2096.424170] Lustre: Unmounted lustre-client [ 2096.538286] Lustre: Mounted lustre-client [ 2097.798817] LustreError: 87225:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a61e03d7800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2097.806072] LustreError: 87225:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2097.807638] LustreError: 87225:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 2097.830404] Lustre: Unmounted lustre-client [ 2115.013248] Key type lgssc unregistered [ 2115.124417] LNet: 87627:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2115.126947] LNetError: 87627:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2115.137165] LNet: Removed LNI 192.168.204.29@tcp [ 2115.358978] Key type .llcrypt unregistered [ 2115.360313] Key type ._llcrypt unregistered [ 2120.356421] Key type ._llcrypt registered [ 2120.357412] Key type .llcrypt registered [ 2120.883704] Lustre: DEBUG MARKER: == conf-sanity test 90d: check one close RPC is allowed above max_mod_rpcs_in_flight ========================================================== 14:30:12 (1767036612) [ 2121.703588] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2121.749568] alg: No test for adler32 (adler32-zlib) [ 2122.597274] Lustre: Lustre: Build Version: 2.17.0_RC3_1_ga0d6154 [ 2122.678917] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 2124.256169] Key type lgssc registered [ 2124.617294] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2141.311064] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2142.492444] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2148.306334] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2164.210652] Lustre: Mounted lustre-client [ 2182.624134] Lustre: 90723:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1767036658/real 1767036658] req@00000000faa2064e x1852872184577792/t0(0) o36->lustre-MDT0000-mdc-ffff8a61c750b800@192.168.204.129@tcp:12/10 lens 488/512 e 0 to 1 dl 1767036674 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2182.624264] Lustre: lustre-MDT0000-mdc-ffff8a61c750b800: Connection to lustre-MDT0000 (at 192.168.204.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2182.632503] Lustre: 90723:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 3 previous similar messages [ 2182.646975] Lustre: lustre-MDT0000-mdc-ffff8a61c750b800: Connection restored to 192.168.204.129@tcp (at 192.168.204.129@tcp) [ 2196.642053] LustreError: 90772:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a61c750b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2196.648363] LustreError: 90772:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2196.665253] Lustre: Unmounted lustre-client [ 2213.678280] Key type lgssc unregistered [ 2213.779443] LNet: 91176:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2213.782684] LNetError: 91176:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2213.791576] LNet: Removed LNI 192.168.204.29@tcp [ 2214.029920] Key type .llcrypt unregistered [ 2214.031035] Key type ._llcrypt unregistered [ 2219.072890] Key type ._llcrypt registered [ 2219.073971] Key type .llcrypt registered [ 2219.508222] Lustre: DEBUG MARKER: == conf-sanity test 91: evict-by-nid support ============= 14:31:50 (1767036710) [ 2220.254967] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2220.266932] alg: No test for adler32 (adler32-zlib) [ 2221.136791] Lustre: Lustre: Build Version: 2.17.0_RC3_1_ga0d6154 [ 2221.236967] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 2222.824121] Key type lgssc registered [ 2223.255316] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2239.943101] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2241.069781] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2246.741906] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2262.516327] Lustre: Mounted lustre-client [ 2264.234995] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2274.471893] LustreError: 94295:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2274.489667] Lustre: Unmounted lustre-client [ 2324.686031] Key type lgssc unregistered [ 2324.805466] LNet: 94773:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2324.807525] LNetError: 94773:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2324.819431] LNet: Removed LNI 192.168.204.29@tcp [ 2325.085396] Key type .llcrypt unregistered [ 2325.086330] Key type ._llcrypt unregistered [ 2330.424800] Key type ._llcrypt registered [ 2330.426249] Key type .llcrypt registered [ 2330.889769] Lustre: DEBUG MARKER: == conf-sanity test 92: ldev returns MGS NID correctly in command substitution ========================================================== 14:33:42 (1767036822) [ 2334.014994] Lustre: DEBUG MARKER: == conf-sanity test 93: register mulitple MDT at the same time ========================================================== 14:33:45 (1767036825) [ 2334.484337] Lustre: DEBUG MARKER: SKIP: conf-sanity test_93 needs >= 3 MDTs [ 2335.082426] Lustre: DEBUG MARKER: == conf-sanity test 94: ldev outputs correct labels for file system name query ========================================================== 14:33:46 (1767036826) [ 2337.524297] Lustre: DEBUG MARKER: == conf-sanity test 95: ldev should only allow one label filter ========================================================== 14:33:49 (1767036829) [ 2340.477776] Lustre: DEBUG MARKER: == conf-sanity test 96: ldev returns hostname and backend fs correctly in command sub ========================================================== 14:33:51 (1767036831) [ 2343.237935] Lustre: DEBUG MARKER: == conf-sanity test 97: ldev returns correct ouput when querying based on role ========================================================== 14:33:54 (1767036834) [ 2346.116562] Lustre: DEBUG MARKER: == conf-sanity test 98: Buffer-overflow check while parsing mount_opts ========================================================== 14:33:57 (1767036837) [ 2347.026870] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2347.038447] alg: No test for adler32 (adler32-zlib) [ 2347.901690] Lustre: Lustre: Build Version: 2.17.0_RC3_1_ga0d6154 [ 2347.977305] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 2349.552202] Key type lgssc registered [ 2349.963119] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2367.407681] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2368.659473] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2374.993167] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2385.392580] Lustre: Mounted lustre-client [ 2387.180524] LustreError: 102085:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a61c71b0000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2387.189573] LustreError: 102085:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2387.213190] Lustre: Unmounted lustre-client [ 2404.725716] Key type lgssc unregistered [ 2404.847446] LNet: 102488:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2404.850103] LNetError: 102488:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2404.859518] LNet: Removed LNI 192.168.204.29@tcp [ 2405.105767] Key type .llcrypt unregistered [ 2405.106907] Key type ._llcrypt unregistered [ 2410.552203] Key type ._llcrypt registered [ 2410.554312] Key type .llcrypt registered [ 2411.043461] Lustre: DEBUG MARKER: == conf-sanity test 99: Adding meta_bg option ============ 14:35:02 (1767036902) [ 2411.894735] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2411.908404] alg: No test for adler32 (adler32-zlib) [ 2412.775448] Lustre: Lustre: Build Version: 2.17.0_RC3_1_ga0d6154 [ 2412.863617] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 2414.448201] Key type lgssc registered [ 2414.860753] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2426.047368] Lustre: DEBUG MARKER: == conf-sanity test 100: check lshowmount lists MGS, MDT, OST and 0@lo ========================================================== 14:35:17 (1767036917) [ 2457.801391] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2459.032721] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2466.551828] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2472.923034] Lustre: Mounted lustre-client [ 2474.216955] LustreError: 108286:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a61c6847000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2474.225130] LustreError: 108286:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2474.249162] Lustre: Unmounted lustre-client [ 2491.483140] Key type lgssc unregistered [ 2491.608401] LNet: 108687:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2491.610683] LNetError: 108687:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2491.618377] LNet: Removed LNI 192.168.204.29@tcp [ 2491.871729] Key type .llcrypt unregistered [ 2491.872701] Key type ._llcrypt unregistered [ 2497.099877] Key type ._llcrypt registered [ 2497.100933] Key type .llcrypt registered [ 2497.605759] Lustre: DEBUG MARKER: == conf-sanity test 101a: Race MDT->OST reconnection with create ========================================================== 14:36:28 (1767036988) [ 2498.502382] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2498.563725] alg: No test for adler32 (adler32-zlib) [ 2499.438591] Lustre: Lustre: Build Version: 2.17.0_RC3_1_ga0d6154 [ 2499.524295] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 2501.104166] Key type lgssc registered [ 2501.572393] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2519.586836] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2520.860795] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2527.058485] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2537.458083] Lustre: Mounted lustre-client [ 2975.415816] LustreError: 120477:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a61e0dfc800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2975.419842] LustreError: 120477:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2975.453111] Lustre: Unmounted lustre-client [ 3001.005902] Key type lgssc unregistered [ 3001.117382] LNet: 120879:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3001.120441] LNetError: 120879:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3001.127733] LNet: Removed LNI 192.168.204.29@tcp [ 3001.376052] Key type .llcrypt unregistered [ 3001.377112] Key type ._llcrypt unregistered [ 3006.706667] Key type ._llcrypt registered [ 3006.708109] Key type .llcrypt registered [ 3007.185343] Lustre: DEBUG MARKER: == conf-sanity test 101b: Race events DISCONNECT and ACTIVE in osp ========================================================== 14:44:58 (1767037498) [ 3007.918759] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3007.968897] alg: No test for adler32 (adler32-zlib) [ 3008.845244] Lustre: Lustre: Build Version: 2.17.0_RC3_1_ga0d6154 [ 3008.936111] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 3010.520101] Key type lgssc registered [ 3010.922817] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3028.031412] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3029.199853] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3035.269850] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3045.886967] Lustre: Mounted lustre-client [ 3050.979593] Lustre: lustre-OST0000-osc-ffff8a61c783b000: Connection to lustre-OST0000 (at 192.168.204.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3083.639278] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3093.363436] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 9 sec [ 3094.608301] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8a61c783b000.ost_server_uuid 50 [ 3095.149727] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8a61c783b000.ost_server_uuid in FULL state after 0 sec [ 3095.511220] LustreError: 125087:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a61c783b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3095.518831] LustreError: 125087:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3095.538872] Lustre: Unmounted lustre-client [ 3112.864216] Key type lgssc unregistered [ 3112.985545] LNet: 125488:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3112.988398] LNetError: 125488:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3112.996258] LNet: Removed LNI 192.168.204.29@tcp [ 3113.214309] Key type .llcrypt unregistered [ 3113.215826] Key type ._llcrypt unregistered [ 3118.584945] Key type ._llcrypt registered [ 3118.586982] Key type .llcrypt registered [ 3119.108065] Lustre: DEBUG MARKER: SKIP: conf-sanity test_102 skipping excluded test 102 [ 3119.694649] Lustre: DEBUG MARKER: == conf-sanity test 103: rename filesystem name ========== 14:46:51 (1767037611) [ 3122.188651] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3122.207835] alg: No test for adler32 (adler32-zlib) [ 3123.080258] Lustre: Lustre: Build Version: 2.17.0_RC3_1_ga0d6154 [ 3123.166723] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 3124.752134] Key type lgssc registered [ 3125.159182] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3154.966980] Lustre: Mounted lustre-client [ 3157.295611] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 3171.542395] LustreError: 128966:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a61c783c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3171.548406] LustreError: 128966:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 3171.578117] Lustre: Unmounted lustre-client [ 3228.362150] Lustre: Mounted mylustre-client [ 3230.660842] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 3240.322237] LustreError: 131286:0:(lov_obd.c:783:lov_cleanup()) mylustre-clilov-ffff8a61c92b2800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3240.325069] LustreError: 131286:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 3240.329198] LustreError: 131286:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 3240.331372] LustreError: 131286:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 3240.358135] Lustre: Unmounted mylustre-client [ 3296.981767] Lustre: Mounted tfs-client [ 3299.320172] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 3306.638773] LustreError: 133583:0:(lov_obd.c:783:lov_cleanup()) tfs-clilov-ffff8a61c5c1b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3306.641873] LustreError: 133583:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 3306.645853] LustreError: 133583:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 3306.647616] LustreError: 133583:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 3306.668149] Lustre: Unmounted tfs-client [ 3364.321253] Lustre: Mounted lustre-client [ 3366.633726] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 3375.030970] Lustre: DEBUG MARKER: == conf-sanity test 104a: Make sure user defined options are reflected in mount ========================================================== 14:51:06 (1767037866) [ 3375.634302] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3385.901347] LustreError: 136448:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 3385.904869] LustreError: 136448:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 3385.923539] Lustre: Unmounted lustre-client [ 3455.054229] Lustre: client wants to enable acl, but mdt not! [ 3455.067917] Lustre: Mounted lustre-client [ 3457.394701] Lustre: DEBUG MARKER: == conf-sanity test 104b: Mount uses last flock argument ========================================================== 14:52:28 (1767037948) [ 3457.526386] Lustre: client wants to enable acl, but mdt not! [ 3457.911392] LustreError: 139853:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a61c62fe000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3457.915183] LustreError: 139853:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 3457.921071] LustreError: 139853:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 3457.923729] LustreError: 139853:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 3457.951243] Lustre: Unmounted lustre-client [ 3458.546925] Lustre: client wants to enable acl, but mdt not! [ 3458.549182] Lustre: Skipped 1 previous similar message [ 3461.129189] Lustre: DEBUG MARKER: == conf-sanity test 105: check file creation for ro and rw bind mnt pt ========================================================== 14:52:32 (1767037952) [ 3461.439190] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3461.442346] Lustre: Skipped 3 previous similar messages [ 3471.720920] LustreError: 140576:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 3471.723520] LustreError: 140576:0:(obd_class.h:479:obd_check_dev()) Skipped 23 previous similar messages [ 3471.741329] Lustre: Unmounted lustre-client [ 3471.743504] Lustre: Skipped 2 previous similar messages [ 3503.734311] Key type lgssc unregistered [ 3503.860551] LNet: 140980:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3503.863178] LNetError: 140980:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3503.870819] LNet: Removed LNI 192.168.204.29@tcp [ 3504.154140] Key type .llcrypt unregistered [ 3504.155205] Key type ._llcrypt unregistered [ 3526.164874] Key type ._llcrypt registered [ 3526.165946] Key type .llcrypt registered [ 3526.407422] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3526.412654] alg: No test for adler32 (adler32-zlib) [ 3527.278497] Lustre: Lustre: Build Version: 2.17.0_RC3_1_ga0d6154 [ 3527.368678] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 3528.960224] Key type lgssc registered [ 3529.416112] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3554.968879] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3556.229896] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3563.754313] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3564.923440] Lustre: Mounted lustre-client [ 3565.030357] Lustre: Remounted lustre-client read-only [ 3565.403534] LustreError: 144719:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a61c92b1000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3565.409796] LustreError: 144719:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3565.432141] Lustre: Unmounted lustre-client [ 3582.586174] Key type lgssc unregistered [ 3582.713430] LNet: 145122:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3582.715928] LNetError: 145122:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3582.722632] LNet: Removed LNI 192.168.204.29@tcp [ 3582.957947] Key type .llcrypt unregistered [ 3582.959163] Key type ._llcrypt unregistered [ 3588.154197] Key type ._llcrypt registered [ 3588.155132] Key type .llcrypt registered [ 3588.667757] Lustre: DEBUG MARKER: SKIP: conf-sanity test_106 skipping SLOW test 106 [ 3589.240723] Lustre: DEBUG MARKER: == conf-sanity test 107: Unknown config param should not fail target mounting ========================================================== 14:54:40 (1767038080) [ 3590.068617] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3590.080756] alg: No test for adler32 (adler32-zlib) [ 3590.950328] Lustre: Lustre: Build Version: 2.17.0_RC3_1_ga0d6154 [ 3591.034648] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 3592.624203] Key type lgssc registered [ 3593.062969] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3609.924966] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3611.100894] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3617.039490] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3634.563835] Key type lgssc unregistered [ 3634.684375] LNet: 148650:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3634.686508] LNetError: 148650:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3634.696227] LNet: Removed LNI 192.168.204.29@tcp [ 3634.930710] Key type .llcrypt unregistered [ 3634.932422] Key type ._llcrypt unregistered [ 3638.683227] Key type ._llcrypt registered [ 3638.684213] Key type .llcrypt registered [ 3638.908629] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3638.916623] alg: No test for adler32 (adler32-zlib) [ 3639.800920] Lustre: Lustre: Build Version: 2.17.0_RC3_1_ga0d6154 [ 3639.906582] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 3641.496145] Key type lgssc registered [ 3641.937735] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3702.003127] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3706.406387] Key type lgssc unregistered [ 3706.539385] LNet: 150588:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3706.541705] LNetError: 150588:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3706.554456] LNet: Removed LNI 192.168.204.29@tcp [ 3706.817550] Key type .llcrypt unregistered [ 3706.819439] Key type ._llcrypt unregistered [ 3711.970423] Key type ._llcrypt registered [ 3711.971442] Key type .llcrypt registered [ 3712.463629] Lustre: DEBUG MARKER: == conf-sanity test 108a: migrate from ldiskfs to ZFS ==== 14:56:43 (1767038203) [ 3712.970869] Lustre: DEBUG MARKER: SKIP: conf-sanity test_108a zfs only test [ 3713.558202] Lustre: DEBUG MARKER: == conf-sanity test 108b: migrate from ZFS to ldiskfs ==== 14:56:45 (1767038205) [ 3717.710453] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3717.763829] alg: No test for adler32 (adler32-zlib) [ 3718.640107] Lustre: Lustre: Build Version: 2.17.0_RC3_1_ga0d6154 [ 3718.730850] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 3720.320139] Key type lgssc registered [ 3720.720073] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3753.944792] Lustre: Mounted lustre-client [ 3754.088607] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3754.090917] LustreError: 153155:0:(file.c:249:ll_close_inode_openhandle()) lustre-clilmv-ffff8a61c4f1d000: inode [0x200000bd1:0x2:0x0] mdc close failed: rc = -108 [ 3754.097360] Lustre: 151968:0:(llite_lib.c:4226:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.204.129@tcp:/lustre/fid: [0x200000bd1:0x2:0x0]/ may get corrupted (rc -108) [ 3754.102426] Lustre: 151968:0:(llite_lib.c:4226:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.204.129@tcp:/lustre/fid: [0x240000402:0x3:0x0]/ may get corrupted (rc -108) [ 3764.391978] LustreError: 153155:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 3764.408203] Lustre: Unmounted lustre-client [ 3793.433547] Lustre: DEBUG MARKER: == conf-sanity test 109a: test lctl clear_conf fsname ==== 14:58:04 (1767038284) [ 3826.879980] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3828.087645] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3835.261646] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3840.495346] Lustre: Mounted lustre-client [ 3888.263704] LustreError: 157653:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a61e0bab000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3888.266887] LustreError: 157653:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3888.268317] LustreError: 157653:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 3888.287252] Lustre: Unmounted lustre-client [ 3920.418150] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3921.547401] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3927.497886] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3938.289245] Lustre: Mounted lustre-client [ 3959.259393] LustreError: 159534:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a61e044c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3959.263410] LustreError: 159534:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3959.265025] LustreError: 159534:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 3959.286245] Lustre: Unmounted lustre-client [ 3976.404845] Key type lgssc unregistered [ 3976.522392] LNet: 159947:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3976.524638] LNetError: 159947:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3976.534598] LNet: Removed LNI 192.168.204.29@tcp [ 3976.763384] Key type .llcrypt unregistered [ 3976.764356] Key type ._llcrypt unregistered [ 3981.851897] Key type ._llcrypt registered [ 3981.852964] Key type .llcrypt registered [ 3982.319129] Lustre: DEBUG MARKER: == conf-sanity test 109b: test lctl clear_conf one config ========================================================== 15:01:13 (1767038473) [ 3991.785299] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3991.813032] alg: No test for adler32 (adler32-zlib) [ 3992.671111] Lustre: Lustre: Build Version: 2.17.0_RC3_1_ga0d6154 [ 3992.755829] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 3994.344168] Key type lgssc registered [ 3994.704146] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4023.356212] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4024.505063] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4031.731943] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4038.106829] Lustre: Mounted lustre-client [ 4097.963607] LustreError: 164872:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a61c6840000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4097.966932] LustreError: 164872:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4097.985794] Lustre: Unmounted lustre-client [ 4138.941357] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4140.149610] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4146.006888] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4161.521816] Lustre: Mounted lustre-client [ 4177.393571] LustreError: 166757:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a61c92b5000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4177.398901] LustreError: 166757:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4177.400802] LustreError: 166757:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 4177.419578] Lustre: Unmounted lustre-client [ 4194.896887] Key type lgssc unregistered [ 4195.024524] LNet: 167158:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4195.027889] LNetError: 167158:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4195.037495] LNet: Removed LNI 192.168.204.29@tcp [ 4195.278572] Key type .llcrypt unregistered [ 4195.279593] Key type ._llcrypt unregistered [ 4200.857458] Key type ._llcrypt registered [ 4200.858437] Key type .llcrypt registered [ 4201.341922] Lustre: DEBUG MARKER: == conf-sanity test 110: Adding large_dir with 3-level htree ========================================================== 15:04:52 (1767038692) [ 4205.710923] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4205.717771] alg: No test for adler32 (adler32-zlib) [ 4206.589048] Lustre: Lustre: Build Version: 2.17.0_RC3_1_ga0d6154 [ 4206.671892] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 4208.256127] Key type lgssc registered [ 4208.662173] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4238.541817] Lustre: Mounted lustre-client [ 4240.067891] Lustre: Mounted lustre-client [ 4407.512342] LustreError: 171097:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a61d7b4c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4407.515768] LustreError: 171097:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 4407.532180] Lustre: Unmounted lustre-client [ 4448.448434] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4449.578121] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4456.989405] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4476.570385] Lustre: DEBUG MARKER: SKIP: conf-sanity test_111 skipping SLOW test 111 [ 4477.201344] Lustre: DEBUG MARKER: == conf-sanity test 112a: mount OST with no_create option ========================================================== 15:09:28 (1767038968) [ 4486.810570] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4487.973473] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4494.219285] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4501.677761] Lustre: Mounted lustre-client [ 4506.530087] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8a61e0a02000.ost_server_uuid 50 [ 4507.135896] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8a61e0a02000.ost_server_uuid in FULL state after 0 sec [ 4508.736950] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8a61e0a02000.ost_server_uuid 50 [ 4509.293647] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8a61e0a02000.ost_server_uuid in FULL state after 0 sec [ 4522.468835] Lustre: lustre-OST0001-osc-ffff8a61e0a02000: Connection to lustre-OST0001 (at 192.168.204.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4527.960893] LustreError: 177946:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a61e0a02000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4527.963768] LustreError: 177946:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 4527.966604] LustreError: 177946:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 4527.968365] LustreError: 177946:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 4527.991197] Lustre: Unmounted lustre-client [ 4527.992171] Lustre: Skipped 1 previous similar message [ 4545.207318] Key type lgssc unregistered [ 4545.336399] LNet: 178349:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4545.339049] LNetError: 178349:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4545.347553] LNet: Removed LNI 192.168.204.29@tcp [ 4545.581893] Key type .llcrypt unregistered [ 4545.583069] Key type ._llcrypt unregistered [ 4550.416204] Key type ._llcrypt registered [ 4550.417089] Key type .llcrypt registered [ 4550.851778] Lustre: DEBUG MARKER: == conf-sanity test 112b: mount MDT with no_create option ========================================================== 15:10:42 (1767039042) [ 4551.528193] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4551.578055] alg: No test for adler32 (adler32-zlib) [ 4552.441933] Lustre: Lustre: Build Version: 2.17.0_RC3_1_ga0d6154 [ 4552.534141] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 4554.120114] Key type lgssc registered [ 4554.509402] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4566.843696] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4577.101648] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4582.815883] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 4583.987218] Lustre: Mounted lustre-client [ 4668.122724] LustreError: 181746:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a61c5c19800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4668.126654] LustreError: 181746:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 4668.149166] Lustre: Unmounted lustre-client [ 4685.257153] Key type lgssc unregistered [ 4685.370561] LNet: 182149:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4685.373055] LNetError: 182149:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4685.379764] LNet: Removed LNI 192.168.204.29@tcp [ 4685.613567] Key type .llcrypt unregistered [ 4685.614673] Key type ._llcrypt unregistered [ 4690.836621] Key type ._llcrypt registered [ 4690.837545] Key type .llcrypt registered [ 4691.297783] Lustre: DEBUG MARKER: == conf-sanity test 113: Shadow mountpoint correctly report ro/rw for mounts ========================================================== 15:13:02 (1767039182) [ 4700.209565] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4700.220230] alg: No test for adler32 (adler32-zlib) [ 4701.090590] Lustre: Lustre: Build Version: 2.17.0_RC3_1_ga0d6154 [ 4701.182244] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 4702.768173] Key type lgssc registered [ 4703.178201] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4730.826048] Lustre: Mounted lustre-client [ 4733.132097] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 4748.525622] LustreError: 186096:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a61c71b4800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4748.531695] LustreError: 186096:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 4748.558772] Lustre: Unmounted lustre-client [ 4803.542336] Lustre: Mounted lustre-client [ 4805.848654] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 4812.368286] LustreError: 188332:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a61e0d89800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4812.371934] LustreError: 188332:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 4812.377107] LustreError: 188332:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 4812.378610] LustreError: 188332:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 4812.399873] Lustre: Unmounted lustre-client [ 4843.542575] Lustre: DEBUG MARKER: SKIP: conf-sanity test_114 skipping SLOW test 114 [ 4844.030183] Lustre: DEBUG MARKER: SKIP: conf-sanity test_115 skipping excluded test 115 [ 4844.587977] Lustre: DEBUG MARKER: == conf-sanity test 116: big size MDT support ============ 15:15:36 (1767039336) [ 4903.236555] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4904.599440] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4911.857015] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4935.534716] Lustre: DEBUG MARKER: == conf-sanity test 117: lctl get_param return errors properly ========================================================== 15:17:06 (1767039426) [ 4945.915255] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4947.053845] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4953.207069] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4968.943701] Lustre: Mounted lustre-client [ 4971.308882] LustreError: 195444:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a61c807a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4971.314335] LustreError: 195444:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 4971.318717] LustreError: 195444:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4971.320248] LustreError: 195444:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 4971.342978] Lustre: Unmounted lustre-client [ 4988.505205] Key type lgssc unregistered [ 4988.615487] LNet: 195845:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4988.621156] LNetError: 195845:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4988.632271] LNet: Removed LNI 192.168.204.29@tcp [ 4988.865116] Key type .llcrypt unregistered [ 4988.866085] Key type ._llcrypt unregistered [ 4993.848954] Key type ._llcrypt registered [ 4993.849940] Key type .llcrypt registered [ 4994.263805] Lustre: DEBUG MARKER: == conf-sanity test 119: writeconf on slave mdt shouldn't duplicate mdc/osp and crash ========================================================== 15:18:05 (1767039485) [ 4995.737539] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4995.765056] alg: No test for adler32 (adler32-zlib) [ 4996.621799] Lustre: Lustre: Build Version: 2.17.0_RC3_1_ga0d6154 [ 4996.697398] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 4998.272142] Key type lgssc registered [ 4998.634332] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5015.381185] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5016.497371] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5022.346534] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5023.500259] Lustre: Mounted lustre-client [ 5028.835739] Lustre: lustre-MDT0001-mdc-ffff8a61d7ddd800: Connection to lustre-MDT0001 (at 192.168.204.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5037.419280] LustreError: 199214:0:(obd_config.c:2001:class_config_llog_handler()) MGC192.168.204.129@tcp: cfg command failed: rc = -17 [ 5037.423629] Lustre: cmd=cf001 0:lustre-MDT0001-mdc 1:mdc 2:lustre-clilmv_UUID [ 5037.423629] [ 5037.426776] LustreError: 198850:0:(mgc_request.c:614:do_requeue()) failed processing log: -17 [ 5341.154690] Lustre: lustre-MDT0001-mdc-ffff8a61d7ddd800: Connection to lustre-MDT0001 (at 192.168.204.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5355.501038] LustreError: 201400:0:(obd_config.c:689:class_setup()) Device 4 already setup (type mdc) [ 5355.504266] LustreError: 201400:0:(obd_config.c:797:class_detach()) OBD device 4 still set up [ 5355.506670] LustreError: 201400:0:(obd_config.c:2001:class_config_llog_handler()) MGC192.168.204.129@tcp: cfg command failed: rc = -17 [ 5355.508854] Lustre: cmd=cf003 0:lustre-MDT0001-mdc 1:lustre-MDT0001_UUID 2:192.168.204.129@tcp [ 5355.508854] [ 5355.510984] LustreError: 198850:0:(mgc_request.c:614:do_requeue()) failed processing log: -17 [ 5653.475237] Lustre: lustre-MDT0001-mdc-ffff8a61d7ddd800: Connection to lustre-MDT0001 (at 192.168.204.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5680.108797] LustreError: 203591:0:(obd_config.c:2001:class_config_llog_handler()) MGC192.168.204.129@tcp: cfg command failed: rc = -17 [ 5680.111392] Lustre: cmd=cf014 0:lustre-clilmv 1:lustre-MDT0001_UUID 2:1 3:1 4:lustre-MDT0001-mdc_UUID [ 5680.111392] [ 5680.113800] LustreError: 198850:0:(mgc_request.c:614:do_requeue()) failed processing log: -17 [ 5974.499797] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5984.744928] LustreError: 205611:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5984.762046] Lustre: Unmounted lustre-client [ 6016.818302] Lustre: DEBUG MARKER: == conf-sanity test 120: cross-target rename should not create bad symlinks ========================================================== 15:35:08 (1767040508) [ 6028.505299] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6029.630518] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6036.841934] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6036.998828] Lustre: Mounted lustre-client [ 6038.737209] LustreError: 209532:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a61c6842000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6038.742486] LustreError: 209532:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6038.743955] LustreError: 209532:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 6038.764627] Lustre: Unmounted lustre-client [ 6080.291283] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 5905 sec ========== 15:36:11 (1767040571) [ 6080.827715] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 15:36:12 (1767040572) === [ 6081.966159] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 15:36:13 (1767040573) === [ 6093.241641] Key type lgssc unregistered [ 6093.367446] LNet: 213367:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6093.369828] LNetError: 213367:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6093.378835] LNet: Removed LNI 192.168.204.29@tcp [ 6093.623260] Key type .llcrypt unregistered [ 6093.625203] Key type ._llcrypt unregistered