[ 0.000000] Linux version 4.18.0rh8.10-debug (green@maintenance) (gcc version 8.5.0 20210514 (Red Hat 8.5.0-26) (GCC)) #2 SMP Mon Jul 14 01:24:22 EDT 2025 [ 0.000000] Command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' [ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 [ 0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. [ 0.000000] signal: max sigframe size: 1776 [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bffd9fff] usable [ 0.000000] BIOS-e820: [mem 0x00000000bffda000-0x00000000bfffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x0000000146dfffff] usable [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] SMBIOS 2.8 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.17.0-8.fc42 06/10/2025 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 707031402 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2421 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE22BD 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 00227D (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2331 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE23C1 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE23F9 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe22bd-0xbffe2330] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22bc] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2331-0xbffe23c0] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe23c1-0xbffe23f8] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe23f9-0xbffe2420] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001011] APIC: Switch to symmetric I/O mode setup [ 0.002409] x2apic enabled [ 0.003008] Switched APIC routing to physical x2apic. [ 0.005005] kvm-guest: setup PV IPIs [ 0.008642] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.009000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.009023] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.011007] pid_max: default: 32768 minimum: 301 [ 0.012145] LSM: Security Framework initializing [ 0.013075] Yama: becoming mindful. [ 0.014044] SELinux: Initializing. [ 0.015075] *** VALIDATE selinux *** [ 0.027345] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.033413] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.034148] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.036047] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.038122] *** VALIDATE tmpfs *** [ 0.040501] *** VALIDATE proc *** [ 0.041248] *** VALIDATE cgroup *** [ 0.042009] *** VALIDATE cgroup2 *** [ 0.043289] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.044161] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.045009] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.048217] Spectre V2 : User space: Vulnerable [ 0.049010] Speculative Store Bypass: Vulnerable [ 0.052637] debug: unmapping init [mem 0xffffffffb9a59000-0xffffffffb9a60fff] [ 0.054233] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.055958] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.057029] ... version: 2 [ 0.058013] ... bit width: 48 [ 0.059014] ... generic registers: 4 [ 0.060025] ... value mask: 0000ffffffffffff [ 0.061011] ... max period: 00007fffffffffff [ 0.062013] ... fixed-purpose events: 3 [ 0.063011] ... event mask: 000000070000000f [ 0.064315] rcu: Hierarchical SRCU implementation. [ 0.066512] smp: Bringing up secondary CPUs ... [ 0.067809] x86: Booting SMP configuration: [ 0.068027] .... node #0, CPUs: #1 #2 #3 [ 0.076374] smp: Brought up 1 node, 4 CPUs [ 0.078019] smpboot: Max logical packages: 1 [ 0.079021] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.177370] node 0 deferred pages initialised in 96ms [ 0.181114] devtmpfs: initialized [ 0.182540] x86/mm: Memory block size: 128MB [ 0.186781] gcov: version magic: 0x41383552 [ 0.190672] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.194245] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.198602] pinctrl core: initialized pinctrl subsystem [ 0.200724] [ 0.202016] ************************************************************* [ 0.204023] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.206019] ** ** [ 0.208029] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.212031] ** ** [ 0.214019] ** This means that this kernel is built to expose internal ** [ 0.217025] ** IOMMU data structures, which may compromise security on ** [ 0.220026] ** your system. ** [ 0.222021] ** ** [ 0.225050] ** If you see this message and you are not debugging the ** [ 0.228014] ** kernel, report this immediately to your vendor! ** [ 0.231027] ** ** [ 0.234021] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.238022] ************************************************************* [ 0.241744] NET: Registered protocol family 16 [ 0.243518] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.246054] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.248056] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.251100] cpuidle: using governor menu [ 0.252865] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.254491] PCI: Using configuration type 1 for base access [ 0.256126] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.263207] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.265022] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.269066] cryptd: max_cpu_qlen set to 1000 [ 0.271213] ACPI: Added _OSI(Module Device) [ 0.272009] ACPI: Added _OSI(Processor Device) [ 0.273013] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.273998] ACPI: Added _OSI(Processor Aggregator Device) [ 0.277769] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.283361] ACPI: Interpreter enabled [ 0.284047] ACPI: PM: (supports S0 S3 S4 S5) [ 0.285005] ACPI: Using IOAPIC for interrupt routing [ 0.286008] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.288294] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.299957] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.302212] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.305020] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.309091] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.313593] acpiphp: Slot [2] registered [ 0.315100] acpiphp: Slot [5] registered [ 0.317131] acpiphp: Slot [6] registered [ 0.318108] acpiphp: Slot [3] registered [ 0.319098] acpiphp: Slot [4] registered [ 0.321114] acpiphp: Slot [7] registered [ 0.323284] acpiphp: Slot [8] registered [ 0.324212] acpiphp: Slot [9] registered [ 0.326107] acpiphp: Slot [10] registered [ 0.327112] acpiphp: Slot [11] registered [ 0.329054] acpiphp: Slot [12] registered [ 0.330076] acpiphp: Slot [13] registered [ 0.331104] acpiphp: Slot [14] registered [ 0.332115] acpiphp: Slot [15] registered [ 0.333052] acpiphp: Slot [16] registered [ 0.335066] acpiphp: Slot [17] registered [ 0.336305] acpiphp: Slot [18] registered [ 0.337069] acpiphp: Slot [19] registered [ 0.337881] acpiphp: Slot [20] registered [ 0.339094] acpiphp: Slot [21] registered [ 0.341053] acpiphp: Slot [22] registered [ 0.341786] acpiphp: Slot [23] registered [ 0.342062] acpiphp: Slot [24] registered [ 0.342829] acpiphp: Slot [25] registered [ 0.344080] acpiphp: Slot [26] registered [ 0.345083] acpiphp: Slot [27] registered [ 0.346105] acpiphp: Slot [28] registered [ 0.347071] acpiphp: Slot [29] registered [ 0.349059] acpiphp: Slot [30] registered [ 0.350069] acpiphp: Slot [31] registered [ 0.351070] PCI host bridge to bus 0000:00 [ 0.351919] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.353020] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.355023] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.358020] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.361019] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.363017] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.364164] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.365783] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.368098] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.375015] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.378273] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.380018] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.382018] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.385018] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.389525] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.391721] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.394045] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.396623] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.401030] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.413018] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.418961] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.423749] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.430018] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.436014] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.448023] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.455290] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.462017] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.474030] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.496017] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.509123] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.512586] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.515587] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.518577] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.520345] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.525132] iommu: Default domain type: Passthrough [ 0.526000] SCSI subsystem initialized [ 0.527569] ACPI: bus type USB registered [ 0.529119] usbcore: registered new interface driver usbfs [ 0.532085] usbcore: registered new interface driver hub [ 0.533085] usbcore: registered new device driver usb [ 0.535387] pps_core: LinuxPPS API ver. 1 registered [ 0.538014] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.542050] PTP clock support registered [ 0.544268] EDAC MC: Ver: 3.0.0 [ 0.547102] PCI: Using ACPI for IRQ routing [ 0.548962] NetLabel: Initializing [ 0.551010] NetLabel: domain hash size = 128 [ 0.553009] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.555088] NetLabel: unlabeled traffic allowed by default [ 0.558311] vgaarb: loaded [ 0.559838] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.564016] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.574585] clocksource: Switched to clocksource kvm-clock [ 0.705260] VFS: Disk quotas dquot_6.6.0 [ 0.707086] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.710444] *** VALIDATE ramfs *** [ 0.712458] *** VALIDATE hugetlbfs *** [ 0.714843] pnp: PnP ACPI init [ 0.717380] pnp: PnP ACPI: found 6 devices [ 0.734853] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.738493] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.741026] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.743106] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.745683] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.748299] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.751733] NET: Registered protocol family 2 [ 0.753824] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.759127] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.763471] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.770364] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.774108] TCP: Hash tables configured (established 65536 bind 65536) [ 0.777298] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.781650] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.784459] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.787451] NET: Registered protocol family 1 [ 0.790217] RPC: Registered named UNIX socket transport module. [ 0.792482] RPC: Registered udp transport module. [ 0.794904] RPC: Registered tcp transport module. [ 0.796462] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.799298] NET: Registered protocol family 44 [ 0.800793] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.803045] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.805142] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.807537] PCI: CLS 0 bytes, default 64 [ 0.809455] Unpacking initramfs... [ 2.498868] debug: unmapping init [mem 0xffff9a7c3cc64000-0xffff9a7c3ffcffff] [ 2.503675] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.506402] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.509525] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 3.114970] Initialise system trusted keyrings [ 3.117034] Key type blacklist registered [ 3.119411] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 3.129969] zbud: loaded [ 3.133422] *** VALIDATE nfs *** [ 3.134699] *** VALIDATE nfs4 *** [ 3.135792] pstore: using deflate compression [ 3.139737] Platform Keyring initialized [ 3.259916] NET: Registered protocol family 38 [ 3.261507] Key type asymmetric registered [ 3.263425] Asymmetric key parser 'x509' registered [ 3.265112] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 3.267643] io scheduler mq-deadline registered [ 3.268955] io scheduler kyber registered [ 3.270501] io scheduler bfq registered [ 3.272086] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 3.274946] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 3.276954] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 3.279336] ACPI: Power Button [PWRF] [ 3.285527] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.292769] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.303643] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.339162] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.381509] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.386749] Non-volatile memory driver v1.3 [ 3.388051] Linux agpgart interface v0.103 [ 3.422220] virtio_blk virtio1: [vda] 134608 512-byte logical blocks (68.9 MB/65.7 MiB) [ 3.425795] vda: detected capacity change from 0 to 68919296 [ 3.446550] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.451522] vdb: detected capacity change from 0 to 1073741824 [ 3.460165] libphy: Fixed MDIO Bus: probed [ 3.473322] usbcore: registered new interface driver usbserial_generic [ 3.475691] usbserial: USB Serial support registered for generic [ 3.478569] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.482519] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.484133] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.487149] mousedev: PS/2 mouse device common for all mice [ 3.491068] rtc_cmos 00:05: RTC can wake from S4 [ 3.496454] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.496601] rtc_cmos 00:05: registered as rtc0 [ 3.505336] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.508939] intel_pstate: CPU model not supported [ 3.512907] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.516389] hid: raw HID events driver (C) Jiri Kosina [ 3.519408] usbcore: registered new interface driver usbhid [ 3.521800] usbhid: USB HID core driver [ 3.521985] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.523752] drop_monitor: Initializing network drop monitor service [ 3.532030] Initializing XFRM netlink socket [ 3.534206] NET: Registered protocol family 10 [ 3.536719] Segment Routing with IPv6 [ 3.538547] NET: Registered protocol family 17 [ 3.540993] mpls_gso: MPLS GSO support [ 3.547901] RAS: Correctable Errors collector initialized. [ 3.551339] AVX version of gcm_enc/dec engaged. [ 3.553267] AES CTR mode by8 optimization enabled [ 3.649711] sched_clock: Marking stable (3649688817, 0)->(4793291089, -1143602272) [ 3.654533] registered taskstats version 1 [ 3.657120] Loading compiled-in X.509 certificates [ 3.659241] zswap: loaded using pool lzo/zbud [ 3.688128] Key type big_key registered [ 3.706227] Key type encrypted registered [ 3.708580] ima: No TPM chip found, activating TPM-bypass! [ 3.710838] ima: Allocated hash algorithm: sha1 [ 3.712987] ima: No architecture policies found [ 3.715465] evm: Initialising EVM extended attributes: [ 3.717542] evm: security.selinux [ 3.718419] evm: security.ima [ 3.719169] evm: security.capability [ 3.720138] evm: HMAC attrs: 0x1 [ 3.723268] rtc_cmos 00:05: setting system clock to 2026-04-29 20:17:53 UTC (1777493873) [ 3.729146] debug: unmapping init [mem 0xffffffffbaa03000-0xffffffffbabfffff] [ 3.732305] debug: unmapping init [mem 0xffffffffb9782000-0xffffffffb9a58fff] [ 3.742097] Write protecting the kernel read-only data: 28672k [ 3.745733] debug: unmapping init [mem 0xffffffffb7e03000-0xffffffffb7ffffff] [ 3.748100] debug: unmapping init [mem 0xffffffffb8714000-0xffffffffb87fffff] [ 3.777523] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 3.789766] systemd[1]: Detected virtualization kvm. [ 3.791552] systemd[1]: Detected architecture x86-64. [ 3.793486] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.817932] systemd[1]: No hostname configured. [ 3.819976] systemd[1]: Set hostname to . [ 3.823095] random: systemd: uninitialized urandom read (16 bytes read) [ 3.826479] systemd[1]: Initializing machine ID from random generator. [ 3.892750] random: ln: uninitialized urandom read (6 bytes read) [ 3.995502] random: systemd: uninitialized urandom read (16 bytes read) [ 3.998331] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ 4.002553] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ 4.010284] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Swap. [ OK ] Listening on Journal Socket. Starting Setup Virtual Console... [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... [ OK ] Reached target Paths. [ OK ] Reached target Timers. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on udev Control Socket. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on Journal Socket (/dev/log). Starting Journal Service... [ OK ] Reached target Sockets. [ OK ] Reached target Initrd Root Device. Starting Apply Kernel Variables... [ OK ] Started Memstrack Anylazing 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 Journal Service. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.792363] device-mapper: uevent: version 1.0.3 [ 4.794931] 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 [ 5.265229] random: fast init done ] 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... [ 5.685890] virtio_net virtio0 ens2: renamed from eth0 [ 5.727416] scsi host0: ata_piix [ 5.731097] scsi host1: ata_piix [ 5.733083] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.736616] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 10.226421] random: crng init done [ 10.228075] random: 7 urandom warning(s) missed due to ratelimiting [ 10.381604] dracut-initqueue[595]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 11.160875] 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 Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Remote File Systems. [ 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 Paths. [ OK ] Stopped target Sockets. [ OK ] Stopped target System Initialization. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Swap. [ OK ] Stopped target Local File Systems. Stopping udev Kernel Device Manager... [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 12.649853] printk: systemd: 25 output lines suppressed due to ratelimiting [ 12.946761] SELinux: Disabled at runtime. [ 13.004810] 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) [ 13.019061] systemd[1]: Detected virtualization kvm. [ 13.021198] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 13.652405] systemd[1]: initrd-switch-root.service: Succeeded. [ 13.656616] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 13.663042] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 13.667945] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 13.672079] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 13.685065] systemd[1]: Starting Journal Service... Starting Journal Service... [ 13.689532] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. Mounting POSIX Message Queue File System... [ OK ] Created slice system-serial\x2dgetty.slice. Activating swap /dev/disk/by-label/SWAP... [ OK ] Reached target rpc_pipefs.target. [ OK ] Created slice system-sshd\x2dkeygen.slice. Starting Remount Root and Kernel File Systems... [ 13.755111] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Created slice system-getty.slice. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Reached target Paths. [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... Mounting Huge Pages File System... [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. [ OK ] Stopped target Initrd Root File System. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on Process Core Dump Socket. Mounting Kernel Debug File System... [ OK ] Listening on initctl Compatibility Named Pipe. Starting Apply Kernel Variables... [ OK ] Started Journal Service. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted Huge Pages File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted Kernel Debug File System. [ OK ] Started Apply Kernel Variables. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... [ OK ] Reached target Swap. Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ 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 ] Started udev Coldplug all Devices. [ OK ] Mounted /mnt. [ 14.332622] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 14.672913] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 14.741366] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 14.975584] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 15.176673] EDAC sbridge: Ver: 1.1.2 [ 16.514426] Key type dns_resolver registered [ 16.925585] NFS: Registering the id_resolver key type [ 16.928343] Key type id_resolver registered [ 16.930828] Key type id_legacy registered [ 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 RPC Bind. [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Started dnf makecache --timer. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. [ OK ] Reached target Basic System. [ OK ] Started D-Bus System Message Bus. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Login Service... [ OK ] Started irqbalance daemon. [ OK ] Reached target sshd-keygen.target. Starting Network Manager... Starting Restore /run/initramfs on shutdown... [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting OpenSSH server daemon... Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Serial Getty on ttyS1. [ OK ] Reached target Login Prompts. [ OK ] Started Command Scheduler. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting System Logging Service... Starting Crash recovery kernel arming... Starting Notify NFS peers of a restart... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. [ OK ] Started Authorization Manager. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg119-client login: [ 50.765396] libcfs: loading out-of-tree module taints kernel. [ 50.921292] Key type ._llcrypt registered [ 50.936530] Key type .llcrypt registered [ 52.190845] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 52.203731] alg: No test for adler32 (adler32-zlib) [ 53.816540] Lustre: Lustre: Build Version: 2.17.52_93_g6cdb58a [ 54.725834] LNet: Added LNI 192.168.201.19@tcp [8/256/0/180] [ 56.503362] Key type lgssc registered [ 57.592130] hrtimer: interrupt took 2707966 ns [ 58.920462] Lustre: Echo OBD driver; http://www.lustre.org/ [ 297.562098] Lustre: Mounted lustre-client [ 304.178857] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 322.471848] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing check_logdir /tmp/testlogs/ [ 323.041663] Lustre: lustre-OST0000-osc-ffff9a7c84d88000: disconnect after 22s idle [ 332.872788] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing yml_node [ 343.384661] Lustre: DEBUG MARKER: Client: 2.17.52.93 [ 348.901502] Lustre: DEBUG MARKER: MDS: 2.17.52.93 [ 354.027583] Lustre: DEBUG MARKER: OSS: 2.17.52.93 [ 356.672016] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Wed Apr 29 16:23:44 EDT 2026 [ 385.313176] Lustre: DEBUG MARKER: excepting tests: 21e 32b 32c 32newtarball [ 387.275612] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 390.239162] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 400.658121] Lustre: Unmounted lustre-client [ 561.823314] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 567.493306] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 598.390445] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 648.840836] Lustre: DEBUG MARKER: == conf-sanity test 0: single mount setup ================ 16:28:36 (1777494516) [ 696.801755] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 702.854372] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 732.936713] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 734.022042] Lustre: Mounted lustre-client [ 740.876361] LustreError: 14123:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff9a7c991d1800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 740.985722] Lustre: Unmounted lustre-client [ 770.831894] Key type lgssc unregistered [ 771.366357] LNet: 14527:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 771.390660] LNetError: 14527:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 771.426783] LNet: Removed LNI 192.168.201.19@tcp [ 772.584289] Key type .llcrypt unregistered [ 772.586814] Key type ._llcrypt unregistered [ 794.954758] Key type ._llcrypt registered [ 794.960779] Key type .llcrypt registered [ 796.532886] Lustre: DEBUG MARKER: == conf-sanity test 1: start up ost twice (should return errors) ========================================================== 16:31:04 (1777494664) [ 799.978822] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 799.986949] alg: No test for adler32 (adler32-zlib) [ 801.059973] Lustre: Lustre: Build Version: 2.17.52_93_g6cdb58a [ 801.497296] LNet: Added LNI 192.168.201.19@tcp [8/256/0/180] [ 803.351231] Key type lgssc registered [ 805.218241] Lustre: Echo OBD driver; http://www.lustre.org/ [ 873.515292] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 878.607947] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 906.804292] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 923.353652] Lustre: Mounted lustre-client [ 928.482826] LustreError: 17715:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff9a7c84d98800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 928.659947] Lustre: Unmounted lustre-client [ 962.018470] Key type lgssc unregistered [ 962.315417] LNet: 18120:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 962.327261] LNetError: 18120:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 962.361462] LNet: Removed LNI 192.168.201.19@tcp [ 963.495584] Key type .llcrypt unregistered [ 963.500349] Key type ._llcrypt unregistered [ 987.653328] Key type ._llcrypt registered [ 987.662973] Key type .llcrypt registered [ 989.760091] Lustre: DEBUG MARKER: == conf-sanity test 2: start up mds twice (should return err) ========================================================== 16:34:17 (1777494857) [ 994.380743] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 994.440271] alg: No test for adler32 (adler32-zlib) [ 995.780609] Lustre: Lustre: Build Version: 2.17.52_93_g6cdb58a [ 996.068785] LNet: Added LNI 192.168.201.19@tcp [8/256/0/180] [ 997.815239] Key type lgssc registered [ 999.774337] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1073.864334] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1081.089570] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1117.558030] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1120.526115] Lustre: Mounted lustre-client [ 1124.458470] LustreError: 21291:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff9a7c987fb000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1124.685527] Lustre: Unmounted lustre-client [ 1168.572524] Key type lgssc unregistered [ 1168.990268] LNet: 21696:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1169.013496] LNetError: 21696:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1169.024201] LNet: Removed LNI 192.168.201.19@tcp [ 1170.040414] Key type .llcrypt unregistered [ 1170.047083] Key type ._llcrypt unregistered [ 1196.079670] Key type ._llcrypt registered [ 1196.084870] Key type .llcrypt registered [ 1198.121098] Lustre: DEBUG MARKER: == conf-sanity test 3: mount client twice (should return err) ========================================================== 16:37:45 (1777495065) [ 1201.342809] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1201.384217] alg: No test for adler32 (adler32-zlib) [ 1202.638577] Lustre: Lustre: Build Version: 2.17.52_93_g6cdb58a [ 1203.147049] LNet: Added LNI 192.168.201.19@tcp [8/256/0/180] [ 1204.967669] Key type lgssc registered [ 1207.201869] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1275.868106] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1280.959467] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1307.844053] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1313.885563] Lustre: Mounted lustre-client [ 1320.314906] LustreError: 24821:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff9a7c88c2a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1320.442196] Lustre: Unmounted lustre-client [ 1354.980252] Key type lgssc unregistered [ 1355.315151] LNet: 25225:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1355.328478] LNetError: 25225:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1355.357056] LNet: Removed LNI 192.168.201.19@tcp [ 1356.436112] Key type .llcrypt unregistered [ 1356.442429] Key type ._llcrypt unregistered [ 1380.264456] Key type ._llcrypt registered [ 1380.270996] Key type .llcrypt registered [ 1382.157531] Lustre: DEBUG MARKER: == conf-sanity test 4: force cleanup ost, then cleanup === 16:40:49 (1777495249) [ 1385.056310] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1385.081304] alg: No test for adler32 (adler32-zlib) [ 1386.114578] Lustre: Lustre: Build Version: 2.17.52_93_g6cdb58a [ 1386.505332] LNet: Added LNI 192.168.201.19@tcp [8/256/0/180] [ 1388.208238] Key type lgssc registered [ 1390.025703] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1463.170716] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1469.559578] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1494.957919] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1502.320143] Lustre: Mounted lustre-client [ 1507.308762] Lustre: lustre-OST0000-osc-ffff9a7c991d5000: Connection to lustre-OST0000 (at 192.168.201.119@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1519.788394] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1530.204457] Lustre: Unmounted lustre-client [ 1565.876344] Key type lgssc unregistered [ 1566.291582] LNet: 28762:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1566.300438] LNetError: 28762:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1566.346088] LNet: Removed LNI 192.168.201.19@tcp [ 1567.373065] Key type .llcrypt unregistered [ 1567.374803] Key type ._llcrypt unregistered [ 1591.646901] Key type ._llcrypt registered [ 1591.649447] Key type .llcrypt registered [ 1593.361306] Lustre: DEBUG MARKER: == conf-sanity test 5a: force cleanup mds, then cleanup == 16:44:21 (1777495461) [ 1595.809706] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1595.904134] alg: No test for adler32 (adler32-zlib) [ 1597.174260] Lustre: Lustre: Build Version: 2.17.52_93_g6cdb58a [ 1597.683777] LNet: Added LNI 192.168.201.19@tcp [8/256/0/180] [ 1599.516435] Key type lgssc registered [ 1601.820594] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1671.955868] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1677.926238] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1712.237942] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1715.135861] Lustre: Mounted lustre-client [ 1725.427290] Lustre: lustre-MDT0000-mdc-ffff9a7c84d83000: Connection to lustre-MDT0000 (at 192.168.201.119@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1735.667121] Lustre: lustre-MDT0001-mdc-ffff9a7c84d83000: Connection to lustre-MDT0001 (at 192.168.201.119@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1743.766272] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1746.911907] Lustre: 29721:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1777495600/real 1777495600] req@00000000de2dcec2 x1863839086811264/t0(0) o400->MGC192.168.201.119@tcp@192.168.201.119@tcp:26/25 lens 224/224 e 0 to 1 dl 1777495616 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1746.954586] LustreError: MGC192.168.201.119@tcp: Connection to MGS (at 192.168.201.119@tcp) was lost; in progress operations using this service will fail [ 1754.211985] Lustre: Unmounted lustre-client [ 1772.972670] Key type lgssc unregistered [ 1773.229779] LNet: 32333:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1773.249246] LNetError: 32333:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1773.292252] LNet: Removed LNI 192.168.201.19@tcp [ 1774.895667] Key type .llcrypt unregistered [ 1774.908452] Key type ._llcrypt unregistered [ 1803.121319] Key type ._llcrypt registered [ 1803.123843] Key type .llcrypt registered [ 1805.826062] Lustre: DEBUG MARKER: == conf-sanity test 5b: Try to start a client with no MGS (should return errs) ========================================================== 16:47:52 (1777495672) [ 1809.240477] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1809.310357] alg: No test for adler32 (adler32-zlib) [ 1810.607215] Lustre: Lustre: Build Version: 2.17.52_93_g6cdb58a [ 1810.994115] LNet: Added LNI 192.168.201.19@tcp [8/256/0/180] [ 1812.759206] Key type lgssc registered [ 1814.895802] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1916.691758] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1922.847366] LustreError: 34510:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.201.119@tcp: failed processing log, type 1: rc = -110 [ 1928.160177] LustreError: 34519:0:(mgc_request.c:614:do_requeue()) failed processing log: -110 [ 2080.735832] LustreError: MGC192.168.201.119@tcp: Confguration from log lustre-client failed from MGS -110. Communication error between node & MGS, a bad configuration, or other errors. See syslog for more info [ 2080.789224] Lustre: Unmounted lustre-client [ 2080.797469] LustreError: 34510:0:(super25.c:184:lustre_fill_super()) llite: Unable to mount : rc = -110 [ 2099.905524] Key type lgssc unregistered [ 2100.315788] LNet: 34937:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2100.329522] LNetError: 34937:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2100.350581] LNet: Removed LNI 192.168.201.19@tcp [ 2101.260896] Key type .llcrypt unregistered [ 2101.265537] Key type ._llcrypt unregistered [ 2125.640213] Key type ._llcrypt registered [ 2125.644858] Key type .llcrypt registered [ 2127.915994] Lustre: DEBUG MARKER: == conf-sanity test 5c: cleanup after failed mount (bug 2712) (should return errs) ========================================================== 16:53:15 (1777495995) [ 2131.514406] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2131.610450] alg: No test for adler32 (adler32-zlib) [ 2132.892755] Lustre: Lustre: Build Version: 2.17.52_93_g6cdb58a [ 2133.377768] LNet: Added LNI 192.168.201.19@tcp [8/256/0/180] [ 2135.199352] Key type lgssc registered [ 2136.841381] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2212.641974] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2219.287658] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2249.768889] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2250.687397] LustreError: 37948:0:(llite_lib.c:1415:ll_fill_super()) wrong.lustre: fsname longer than 8 characters: rc = -36 [ 2250.703957] Lustre: Unmounted wrong.lustre-client [ 2250.729065] LustreError: 37948:0:(super25.c:184:lustre_fill_super()) llite: Unable to mount : rc = -36 [ 2285.871535] Key type lgssc unregistered [ 2286.262930] LNet: 38366:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2286.279590] LNetError: 38366:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2286.304141] LNet: Removed LNI 192.168.201.19@tcp [ 2287.360630] Key type .llcrypt unregistered [ 2287.364476] Key type ._llcrypt unregistered [ 2312.409046] Key type ._llcrypt registered [ 2312.414722] Key type .llcrypt registered [ 2314.706378] Lustre: DEBUG MARKER: == conf-sanity test 5d: mount with ost down ============== 16:56:21 (1777496181) [ 2318.719580] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2318.755757] alg: No test for adler32 (adler32-zlib) [ 2320.094763] Lustre: Lustre: Build Version: 2.17.52_93_g6cdb58a [ 2320.699037] LNet: Added LNI 192.168.201.19@tcp [8/256/0/180] [ 2322.583246] Key type lgssc registered [ 2324.862454] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2426.169445] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2476.866327] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2482.896732] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2497.354030] Lustre: Mounted lustre-client [ 2500.618606] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2511.053862] Lustre: Unmounted lustre-client [ 2545.903795] Key type lgssc unregistered [ 2546.451653] LNet: 41859:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2546.466947] LNetError: 41859:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2546.495195] LNet: Removed LNI 192.168.201.19@tcp [ 2547.603080] Key type .llcrypt unregistered [ 2547.623174] Key type ._llcrypt unregistered [ 2572.180934] Key type ._llcrypt registered [ 2572.187963] Key type .llcrypt registered [ 2574.567767] Lustre: DEBUG MARKER: == conf-sanity test 5e: delayed connect, don't crash (bug 10268) ========================================================== 17:00:41 (1777496441) [ 2578.803734] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2578.821394] alg: No test for adler32 (adler32-zlib) [ 2580.104288] Lustre: Lustre: Build Version: 2.17.52_93_g6cdb58a [ 2580.514930] LNet: Added LNI 192.168.201.19@tcp [8/256/0/180] [ 2582.296795] Key type lgssc registered [ 2583.960339] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2657.416650] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2663.101902] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2694.971987] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2697.120028] LustreError: 42819:0:(niobuf.c:1106:ptl_send_rpc()) cfs_fail_timeout id 506 sleeping for 10000ms [ 2701.215549] LustreError: 44872:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.201.119@tcp: failed processing log, type 1: rc = -110 [ 2706.403777] LustreError: 44881:0:(mgc_request.c:614:do_requeue()) failed processing log: -110 [ 2707.183984] LustreError: 42819:0:(niobuf.c:1106:ptl_send_rpc()) cfs_fail_timeout id 506 awake [ 2718.124715] Lustre: Mounted lustre-client [ 2722.540310] LustreError: 44926:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff9a7c861b4800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2722.667446] Lustre: Unmounted lustre-client [ 2757.708721] Key type lgssc unregistered [ 2758.209682] LNet: 45332:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2758.234442] LNetError: 45332:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2758.283564] LNet: Removed LNI 192.168.201.19@tcp [ 2759.709223] Key type .llcrypt unregistered [ 2759.717854] Key type ._llcrypt unregistered [ 2787.578626] Key type ._llcrypt registered [ 2787.581877] Key type .llcrypt registered [ 2789.782725] Lustre: DEBUG MARKER: == conf-sanity test 5f: mds down, cleanup after failed mount (bug 2712) ========================================================== 17:04:17 (1777496657) [ 2792.567427] Lustre: DEBUG MARKER: SKIP: conf-sanity test_5f needs separate mgs and mds [ 2795.395951] Lustre: DEBUG MARKER: == conf-sanity test 5g: handle missing debugfs =========== 17:04:22 (1777496662) [ 2797.434993] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2797.490672] alg: No test for adler32 (adler32-zlib) [ 2798.919890] Lustre: Lustre: Build Version: 2.17.52_93_g6cdb58a [ 2799.321933] LNet: Added LNI 192.168.201.19@tcp [8/256/0/180] [ 2801.063992] Key type lgssc registered [ 2802.620988] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2839.206731] Lustre: DEBUG MARKER: == conf-sanity test 5h: start mdt failure at mdt_fs_setup() ========================================================== 17:05:06 (1777496706) [ 2887.859703] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2894.074571] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2924.377456] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2926.760656] Lustre: Mounted lustre-client [ 2936.816956] Lustre: lustre-MDT0000-mdc-ffff9a7c99261800: Connection to lustre-MDT0000 (at 192.168.201.119@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2947.058504] LustreError: MGC192.168.201.119@tcp: Connection to MGS (at 192.168.201.119@tcp) was lost; in progress operations using this service will fail [ 2947.117836] Lustre: Evicted from MGS (at 192.168.201.119@tcp) after server handle changed from 0xa2519d7b9194eaa4 to 0xa2519d7b9194eb76 [ 2947.155934] Lustre: MGC192.168.201.119@tcp: Connection restored to 192.168.201.119@tcp (at 192.168.201.119@tcp) [ 2962.416244] LustreError: MGC192.168.201.119@tcp: Connection to MGS (at 192.168.201.119@tcp) was lost; in progress operations using this service will fail [ 2962.440851] Lustre: 49234:0:(mgc_request.c:1897:mgc_process_log()) MGC192.168.201.119@tcp: IR log lustre-cliir failed, not fatal: rc = -5 [ 2962.454676] Lustre: Evicted from MGS (at 192.168.201.119@tcp) after server handle changed from 0xa2519d7b9194eb76 to 0xa2519d7b9194ed21 [ 2962.530884] Lustre: MGC192.168.201.119@tcp: Connection restored to 192.168.201.119@tcp (at 192.168.201.119@tcp) [ 2963.580081] Lustre: lustre-MDT0000-mdc-ffff9a7c99261800: Connection restored to 192.168.201.119@tcp (at 192.168.201.119@tcp) [ 2974.038869] LustreError: 49708:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff9a7c99261800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2974.212912] Lustre: Unmounted lustre-client [ 3017.156448] Key type lgssc unregistered [ 3017.482163] LNet: 50115:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3017.495937] LNetError: 50115:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3017.518336] LNet: Removed LNI 192.168.201.19@tcp [ 3018.584576] Key type .llcrypt unregistered [ 3018.587790] Key type ._llcrypt unregistered [ 3039.770150] Key type ._llcrypt registered [ 3039.771723] Key type .llcrypt registered [ 3041.571265] Lustre: DEBUG MARKER: == conf-sanity test 5i: start mdt failure at mdt_quota_init() ========================================================== 17:08:29 (1777496909) [ 3044.896400] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3045.028900] alg: No test for adler32 (adler32-zlib) [ 3046.431616] Lustre: Lustre: Build Version: 2.17.52_93_g6cdb58a [ 3047.037537] LNet: Added LNI 192.168.201.19@tcp [8/256/0/180] [ 3048.785444] Key type lgssc registered [ 3050.411957] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3120.615470] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3126.364888] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3155.191574] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3158.199842] Lustre: Mounted lustre-client [ 3168.239877] Lustre: lustre-MDT0000-mdc-ffff9a7c861b7800: Connection to lustre-MDT0000 (at 192.168.201.119@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3184.607185] Lustre: 51079:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1777497038/real 1777497038] req@00000000a1b487bc x1863840606195840/t0(0) o400->MGC192.168.201.119@tcp@192.168.201.119@tcp:26/25 lens 224/224 e 0 to 1 dl 1777497054 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3184.645501] LustreError: MGC192.168.201.119@tcp: Connection to MGS (at 192.168.201.119@tcp) was lost; in progress operations using this service will fail [ 3192.414454] Lustre: lustre-MDT0000-mdc-ffff9a7c861b7800: Connection restored to 192.168.201.119@tcp (at 192.168.201.119@tcp) [ 3194.521527] Lustre: Evicted from MGS (at 192.168.201.119@tcp) after server handle changed from 0x5377a13d409b8c8c to 0x5377a13d409b9059 [ 3194.608344] Lustre: MGC192.168.201.119@tcp: Connection restored to 192.168.201.119@tcp (at 192.168.201.119@tcp) [ 3202.225285] LustreError: 53596:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff9a7c861b7800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3202.338088] Lustre: Unmounted lustre-client [ 3236.804061] Key type lgssc unregistered [ 3237.179170] LNet: 54000:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3237.185903] LNetError: 54000:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3237.197502] LNet: Removed LNI 192.168.201.19@tcp [ 3237.862729] Key type .llcrypt unregistered [ 3237.864360] Key type ._llcrypt unregistered [ 3265.143639] Key type ._llcrypt registered [ 3265.145897] Key type .llcrypt registered [ 3266.758154] Lustre: DEBUG MARKER: == conf-sanity test 5j: verify libcfs doesn't crash when setting debug flags ========================================================== 17:12:14 (1777497134) [ 3267.552501] Key type .llcrypt unregistered [ 3267.558424] Key type ._llcrypt unregistered [ 3277.087316] Key type ._llcrypt registered [ 3277.091367] Key type .llcrypt registered [ 3277.185811] Key type .llcrypt unregistered [ 3277.192855] Key type ._llcrypt unregistered [ 3285.325744] Key type ._llcrypt registered [ 3285.327821] Key type .llcrypt registered [ 3287.283897] Lustre: DEBUG MARKER: == conf-sanity test 6: manual umount, then mount again === 17:12:34 (1777497154) [ 3290.550159] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3290.809672] alg: No test for adler32 (adler32-zlib) [ 3292.223249] Lustre: Lustre: Build Version: 2.17.52_93_g6cdb58a [ 3292.626919] LNet: Added LNI 192.168.201.19@tcp [8/256/0/180] [ 3294.383420] Key type lgssc registered [ 3296.082424] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3369.367149] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3374.748901] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3404.733560] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3407.725118] Lustre: Mounted lustre-client [ 3410.300988] LustreError: 57863:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff9a7c85718000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3410.530235] Lustre: Unmounted lustre-client [ 3411.091196] Lustre: Mounted lustre-client [ 3415.660395] LustreError: 57934:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff9a7c904e4800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3415.888113] Lustre: Unmounted lustre-client [ 3453.409169] Key type lgssc unregistered [ 3453.829726] LNet: 58339:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3453.839490] LNetError: 58339:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3453.872068] LNet: Removed LNI 192.168.201.19@tcp [ 3455.092448] Key type .llcrypt unregistered [ 3455.104472] Key type ._llcrypt unregistered [ 3482.497471] Key type ._llcrypt registered [ 3482.501426] Key type .llcrypt registered [ 3485.107392] Lustre: DEBUG MARKER: == conf-sanity test 7: manual umount, then cleanup ======= 17:15:51 (1777497351) [ 3489.298134] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3489.464792] alg: No test for adler32 (adler32-zlib) [ 3490.871865] Lustre: Lustre: Build Version: 2.17.52_93_g6cdb58a [ 3491.256968] LNet: Added LNI 192.168.201.19@tcp [8/256/0/180] [ 3493.191521] Key type lgssc registered [ 3494.456976] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3567.824778] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3573.640583] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3604.926581] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3608.357123] Lustre: Mounted lustre-client [ 3611.805389] LustreError: 61423:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff9a7c84b9f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3612.009832] Lustre: Unmounted lustre-client [ 3656.103709] Key type lgssc unregistered [ 3656.354956] LNet: 61840:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3656.365574] LNetError: 61840:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3656.379597] LNet: Removed LNI 192.168.201.19@tcp [ 3657.100276] Key type .llcrypt unregistered [ 3657.104471] Key type ._llcrypt unregistered [ 3686.264549] Key type ._llcrypt registered [ 3686.267187] Key type .llcrypt registered [ 3688.285605] Lustre: DEBUG MARKER: == conf-sanity test 8: double mount setup ================ 17:19:15 (1777497555) [ 3691.815622] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3691.932670] alg: No test for adler32 (adler32-zlib) [ 3693.074945] Lustre: Lustre: Build Version: 2.17.52_93_g6cdb58a [ 3693.474810] LNet: Added LNI 192.168.201.19@tcp [8/256/0/180] [ 3695.231293] Key type lgssc registered [ 3697.133614] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3765.988267] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3771.653556] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3801.663701] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3804.814537] Lustre: Mounted lustre-client [ 3807.934343] Lustre: Mounted lustre-client [ 3813.346140] LustreError: 64992:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff9a7c99262000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3813.537373] Lustre: Unmounted lustre-client [ 3816.363682] LustreError: 65011:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff9a7c991d2800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3816.478158] Lustre: Unmounted lustre-client [ 3853.989762] Key type lgssc unregistered [ 3854.438454] LNet: 65416:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3854.453176] LNetError: 65416:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3854.483463] LNet: Removed LNI 192.168.201.19@tcp [ 3855.463780] Key type .llcrypt unregistered [ 3855.467656] Key type ._llcrypt unregistered [ 3880.543980] Key type ._llcrypt registered [ 3880.552968] Key type .llcrypt registered [ 3882.747606] Lustre: DEBUG MARKER: == conf-sanity test 9: test ptldebug and subsystem for mkfs ========================================================== 17:22:30 (1777497750) [ 3886.467356] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3886.590147] alg: No test for adler32 (adler32-zlib) [ 3887.793800] Lustre: Lustre: Build Version: 2.17.52_93_g6cdb58a [ 3888.128595] LNet: Added LNI 192.168.201.19@tcp [8/256/0/180] [ 3889.879204] Key type lgssc registered [ 3891.644722] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3986.345620] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4005.400884] Lustre: DEBUG MARKER: == conf-sanity test 10a: find lctl param broken symlinks ========================================================== 17:24:33 (1777497873) [ 4049.892657] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4056.185817] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4083.019600] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4090.432272] Lustre: Mounted lustre-client [ 4100.594946] LustreError: 69676:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff9a7c84d9c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4100.690283] Lustre: Unmounted lustre-client [ 4134.568657] Key type lgssc unregistered [ 4134.898451] LNet: 70081:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4134.905947] LNetError: 70081:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4134.925514] LNet: Removed LNI 192.168.201.19@tcp [ 4136.084437] Key type .llcrypt unregistered [ 4136.087922] Key type ._llcrypt unregistered [ 4158.453970] Key type ._llcrypt registered [ 4158.457776] Key type .llcrypt registered [ 4160.679329] Lustre: DEBUG MARKER: == conf-sanity test 11: Verify tool --version option works properly ========================================================== 17:27:07 (1777498027) [ 4185.747984] Lustre: DEBUG MARKER: == conf-sanity test 17: Verify failed mds_postsetup won't fail assertion (2936) (should return errs) ========================================================== 17:27:33 (1777498053) [ 4189.410755] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4189.454050] alg: No test for adler32 (adler32-zlib) [ 4190.745347] Lustre: Lustre: Build Version: 2.17.52_93_g6cdb58a [ 4191.119600] LNet: Added LNI 192.168.201.19@tcp [8/256/0/180] [ 4192.855263] Key type lgssc registered [ 4194.462860] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4255.951220] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4260.886328] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4286.264722] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4289.023869] Lustre: Mounted lustre-client [ 4294.921391] LustreError: 73884:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff9a7c991d2000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4295.019620] Lustre: Unmounted lustre-client [ 4336.612962] Key type lgssc unregistered [ 4337.036980] LNet: 74290:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4337.058959] LNetError: 74290:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4337.091397] LNet: Removed LNI 192.168.201.19@tcp [ 4337.980561] Key type .llcrypt unregistered [ 4337.984479] Key type ._llcrypt unregistered [ 4356.640609] Key type ._llcrypt registered [ 4356.654195] Key type .llcrypt registered [ 4357.496421] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4357.510938] alg: No test for adler32 (adler32-zlib) [ 4358.872245] Lustre: Lustre: Build Version: 2.17.52_93_g6cdb58a [ 4359.270903] LNet: Added LNI 192.168.201.19@tcp [8/256/0/180] [ 4361.047164] Key type lgssc registered [ 4362.609794] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4455.303851] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4602.860983] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4606.745739] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4628.299887] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4669.673473] Lustre: DEBUG MARKER: == conf-sanity test 18: check mkfs creates large journals ========================================================== 17:35:38 (1777498538) [ 4673.541095] Lustre: DEBUG MARKER: use device /dev/mapper/mds1_flakey with MIN=2000000 [ 4770.705882] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4774.518596] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4795.807448] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4859.044525] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4863.273417] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4880.541684] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4886.547463] Lustre: Mounted lustre-client [ 4890.608971] Lustre: DEBUG MARKER: Success: mkfs creates large journals. Size: 78M [ 4892.204787] LustreError: 85282:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff9a7c84d8e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4892.293163] Lustre: Unmounted lustre-client [ 4916.217821] Key type lgssc unregistered [ 4916.425860] LNet: 85686:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4916.434518] LNetError: 85686:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4916.456356] LNet: Removed LNI 192.168.201.19@tcp [ 4916.995833] Key type .llcrypt unregistered [ 4916.999457] Key type ._llcrypt unregistered [ 4938.532474] Key type ._llcrypt registered [ 4938.536561] Key type .llcrypt registered [ 4938.754277] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4938.763721] alg: No test for adler32 (adler32-zlib) [ 4939.770553] Lustre: Lustre: Build Version: 2.17.52_93_g6cdb58a [ 4939.927349] LNet: Added LNI 192.168.201.19@tcp [8/256/0/180] [ 4941.575204] Key type lgssc registered [ 4942.585169] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5051.414982] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5057.466835] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5086.552467] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5135.093493] Lustre: DEBUG MARKER: == conf-sanity test 19a: start/stop MDS without OSTs ===== 17:43:23 (1777499003) [ 5174.684745] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5179.946925] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5205.361534] Lustre: DEBUG MARKER: == conf-sanity test 19b: start/stop OSTs without MDS ===== 17:44:33 (1777499073) [ 5277.281219] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5289.778016] Lustre: DEBUG MARKER: == conf-sanity test 20: remount ro,rw mounts work and doesn't break /etc/mtab ========================================================== 17:45:58 (1777499158) [ 5327.435614] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5331.519688] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5353.351795] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5355.175743] Lustre: Mounted lustre-client [ 5356.145377] Lustre: Remounted lustre-client read-only [ 5356.882225] Lustre: Remounted lustre-client read-write [ 5361.185783] LustreError: 94192:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff9a7c861b3000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5361.316678] Lustre: Unmounted lustre-client [ 5403.707201] Lustre: DEBUG MARKER: == conf-sanity test 21a: start mds before ost, stop ost first ========================================================== 17:47:51 (1777499271) [ 5442.821978] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5447.662682] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5473.646828] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5524.420603] Lustre: DEBUG MARKER: == conf-sanity test 21b: start ost before mds, stop mds first ========================================================== 17:49:52 (1777499392) [ 5596.356179] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5635.046206] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5640.588302] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5693.910091] Lustre: DEBUG MARKER: == conf-sanity test 21c: start mds between two osts, stop mds last ========================================================== 17:52:42 (1777499562) [ 5768.689486] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5810.240612] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5815.968187] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5845.814153] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 5934.539026] Lustre: DEBUG MARKER: == conf-sanity test 21d: start mgs then ost and then mds ========================================================== 17:56:42 (1777499802) [ 5936.633533] Lustre: DEBUG MARKER: SKIP: conf-sanity test_21d need separate mgs device [ 5938.710908] Lustre: DEBUG MARKER: SKIP: conf-sanity test_21e skipping excluded test 21e [ 5941.091590] Lustre: DEBUG MARKER: == conf-sanity test 22: start a client before osts (should return errs) ========================================================== 17:56:49 (1777499809) [ 5981.221903] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5986.462522] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6010.232668] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6035.528149] Lustre: Mounted lustre-client [ 6038.800525] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 6049.141391] Lustre: Unmounted lustre-client [ 6073.598463] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6074.389215] Lustre: Mounted lustre-client [ 6090.746162] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9a7c98400800.ost_server_uuid 50 [ 6092.621373] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9a7c98400800.ost_server_uuid in FULL state after 0 sec [ 6096.501573] LustreError: 104971:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff9a7c98400800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6096.653073] Lustre: Unmounted lustre-client [ 6134.124230] Key type lgssc unregistered [ 6134.406920] LNet: 105376:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6134.421321] LNetError: 105376:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6134.450276] LNet: Removed LNI 192.168.201.19@tcp [ 6135.483114] Key type .llcrypt unregistered [ 6135.487357] Key type ._llcrypt unregistered [ 6152.077347] Key type ._llcrypt registered [ 6152.079810] Key type .llcrypt registered [ 6153.310615] Lustre: DEBUG MARKER: == conf-sanity test 23a: interrupt client during recovery mount delay ========================================================== 18:00:21 (1777500021) [ 6156.212172] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6156.223816] alg: No test for adler32 (adler32-zlib) [ 6157.512092] Lustre: Lustre: Build Version: 2.17.52_93_g6cdb58a [ 6157.774350] LNet: Added LNI 192.168.201.19@tcp [8/256/0/180] [ 6159.487186] Key type lgssc registered [ 6160.885538] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6215.500834] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6219.843531] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6240.776929] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6243.613608] Lustre: Mounted lustre-client [ 6248.957882] Lustre: lustre-MDT0000-mdc-ffff9a7c82d70800: Connection to lustre-MDT0000 (at 192.168.201.119@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6251.863576] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 6262.222435] LustreError: MGC192.168.201.119@tcp: Connection to MGS (at 192.168.201.119@tcp) was lost; in progress operations using this service will fail [ 6262.286079] Lustre: Unmounted lustre-client [ 6287.496765] LustreError: lustre-MDT0000-mdc-ffff9a7c98400000: operation mds_connect to node 192.168.201.119@tcp failed: rc = -16 [ 6292.591688] LustreError: 108687:0:(lmv_obd.c:1449:lmv_statfs()) lustre-MDT0000-mdc-ffff9a7c98400000: can't stat MDS #0: rc = -16 [ 6292.691895] Lustre: Unmounted lustre-client [ 6292.708163] LustreError: 108687:0:(super25.c:184:lustre_fill_super()) llite: Unable to mount : rc = -16 [ 6326.607496] Key type lgssc unregistered [ 6326.973963] LNet: 109139:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6326.983205] LNetError: 109139:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6327.004295] LNet: Removed LNI 192.168.201.19@tcp [ 6327.829352] Key type .llcrypt unregistered [ 6327.835557] Key type ._llcrypt unregistered [ 6345.999152] Key type ._llcrypt registered [ 6346.002396] Key type .llcrypt registered [ 6347.668343] Lustre: DEBUG MARKER: == conf-sanity test 23b: Simulate -EINTR during mount ==== 18:03:35 (1777500215) [ 6350.272675] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6350.307111] alg: No test for adler32 (adler32-zlib) [ 6351.346656] Lustre: Lustre: Build Version: 2.17.52_93_g6cdb58a [ 6351.599620] LNet: Added LNI 192.168.201.19@tcp [8/256/0/180] [ 6353.295323] Key type lgssc registered [ 6354.527335] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6405.916545] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6409.973716] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6429.254677] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6431.936806] Lustre: Mounted lustre-client [ 6433.468611] LustreError: 112178:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff9a7c880c2000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6433.552956] Lustre: Unmounted lustre-client [ 6459.978165] Key type lgssc unregistered [ 6460.229579] LNet: 112580:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6460.238706] LNetError: 112580:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6460.259214] LNet: Removed LNI 192.168.201.19@tcp [ 6460.884429] Key type .llcrypt unregistered [ 6460.889065] Key type ._llcrypt unregistered [ 6476.003283] Key type ._llcrypt registered [ 6476.007757] Key type .llcrypt registered [ 6477.344735] Lustre: DEBUG MARKER: == conf-sanity test 24a: Multiple MDTs on a single node == 18:05:45 (1777500345) [ 6479.623624] Lustre: DEBUG MARKER: SKIP: conf-sanity test_24a mixed loopback and real device not working [ 6481.554879] Lustre: DEBUG MARKER: == conf-sanity test 24b: Multiple MGSs on a single node (should return err) ========================================================== 18:05:49 (1777500349) [ 6484.301988] Lustre: DEBUG MARKER: SKIP: conf-sanity test_24b mixed loopback and real device not working [ 6485.897072] Lustre: DEBUG MARKER: == conf-sanity test 25: Verify modules are referenced ==== 18:05:54 (1777500354) [ 6488.652302] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6488.679099] alg: No test for adler32 (adler32-zlib) [ 6490.056883] Lustre: Lustre: Build Version: 2.17.52_93_g6cdb58a [ 6490.345687] LNet: Added LNI 192.168.201.19@tcp [8/256/0/180] [ 6492.063154] Key type lgssc registered [ 6493.444790] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6539.266380] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6543.534561] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6563.299185] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6565.056962] Lustre: Mounted lustre-client [ 6567.613770] LustreError: 116274:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff9a7ca000a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6567.708835] Lustre: Unmounted lustre-client [ 6594.256920] Key type lgssc unregistered [ 6594.527974] LNet: 116679:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6594.538589] LNetError: 116679:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6594.577410] LNet: Removed LNI 192.168.201.19@tcp [ 6595.236658] Key type .llcrypt unregistered [ 6595.240643] Key type ._llcrypt unregistered [ 6608.681217] Key type ._llcrypt registered [ 6608.683232] Key type .llcrypt registered [ 6609.861440] Lustre: DEBUG MARKER: == conf-sanity test 26: MDT startup failure cleans LOV (should return errs) ========================================================== 18:07:58 (1777500478) [ 6611.074694] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6611.092340] alg: No test for adler32 (adler32-zlib) [ 6612.137170] Lustre: Lustre: Build Version: 2.17.52_93_g6cdb58a [ 6612.307896] LNet: Added LNI 192.168.201.19@tcp [8/256/0/180] [ 6613.951215] Key type lgssc registered [ 6614.971402] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6670.751502] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6674.237963] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6690.654842] Key type lgssc unregistered [ 6690.932099] LNet: 119701:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6690.936802] LNetError: 119701:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6690.957493] LNet: Removed LNI 192.168.201.19@tcp [ 6691.700383] Key type .llcrypt unregistered [ 6691.707400] Key type ._llcrypt unregistered [ 6708.005823] Key type ._llcrypt registered [ 6708.008690] Key type .llcrypt registered [ 6709.298553] Lustre: DEBUG MARKER: == conf-sanity test 27a: Reacquire MGS lock if OST started first ========================================================== 18:09:37 (1777500577) [ 6717.990391] Key type .llcrypt unregistered [ 6717.993342] Key type ._llcrypt unregistered [ 6726.845782] Key type ._llcrypt registered [ 6726.851369] Key type .llcrypt registered [ 6727.297110] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6727.312189] alg: No test for adler32 (adler32-zlib) [ 6728.360670] Lustre: Lustre: Build Version: 2.17.52_93_g6cdb58a [ 6728.601910] LNet: Added LNI 192.168.201.19@tcp [8/256/0/180] [ 6730.289436] Key type lgssc registered [ 6731.467198] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6813.648228] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6843.344522] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6846.484349] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6892.561430] Key type lgssc unregistered [ 6892.782539] LNet: 123484:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6892.789949] LNetError: 123484:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6892.819071] LNet: Removed LNI 192.168.201.19@tcp [ 6893.312619] Key type .llcrypt unregistered [ 6893.315227] Key type ._llcrypt unregistered [ 6906.920279] Key type ._llcrypt registered [ 6906.922430] Key type .llcrypt registered [ 6907.993414] Lustre: DEBUG MARKER: == conf-sanity test 27b: Reacquire MGS lock after failover ========================================================== 18:12:56 (1777500776) [ 6910.250789] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6910.280890] alg: No test for adler32 (adler32-zlib) [ 6911.321530] Lustre: Lustre: Build Version: 2.17.52_93_g6cdb58a [ 6911.548773] LNet: Added LNI 192.168.201.19@tcp [8/256/0/180] [ 6913.199390] Key type lgssc registered [ 6914.178549] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6954.167315] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6957.276274] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6974.107516] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6980.667587] Lustre: Mounted lustre-client [ 6985.702198] Lustre: lustre-MDT0000-mdc-ffff9a7c991d4000: Connection to lustre-MDT0000 (at 192.168.201.119@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7002.079421] Lustre: 124458:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1777500855/real 1777500855] req@00000000fda3c8e7 x1863844658941312/t0(0) o400->MGC192.168.201.119@tcp@192.168.201.119@tcp:26/25 lens 224/224 e 0 to 1 dl 1777500871 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 7002.120694] LustreError: MGC192.168.201.119@tcp: Connection to MGS (at 192.168.201.119@tcp) was lost; in progress operations using this service will fail [ 7002.148927] Lustre: Evicted from MGS (at 192.168.201.119@tcp) after server handle changed from 0x1066750525fbd06b to 0x1066750525fbd1a6 [ 7002.163940] Lustre: MGC192.168.201.119@tcp: Connection restored to 192.168.201.119@tcp (at 192.168.201.119@tcp) [ 7008.746098] Lustre: 126489:0:(mgc_request.c:1897:mgc_process_log()) MGC192.168.201.119@tcp: IR log lustre-cliir failed, not fatal: rc = -2 [ 7016.518671] Lustre: lustre-MDT0000-mdc-ffff9a7c991d4000: Connection restored to 192.168.201.119@tcp (at 192.168.201.119@tcp) [ 7032.669595] LustreError: 126969:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff9a7c991d4000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7032.770316] Lustre: Unmounted lustre-client [ 7058.226526] Key type lgssc unregistered [ 7058.499052] LNet: 127372:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7058.503981] LNetError: 127372:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7058.517358] LNet: Removed LNI 192.168.201.19@tcp [ 7059.071855] Key type .llcrypt unregistered [ 7059.075482] Key type ._llcrypt unregistered [ 7070.936693] Key type ._llcrypt registered [ 7070.938594] Key type .llcrypt registered [ 7072.079420] Lustre: DEBUG MARKER: == conf-sanity test 28A: permanent parameter setting ===== 18:15:40 (1777500940) [ 7074.052323] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7074.196591] alg: No test for adler32 (adler32-zlib) [ 7075.265024] Lustre: Lustre: Build Version: 2.17.52_93_g6cdb58a [ 7075.473428] LNet: Added LNI 192.168.201.19@tcp [8/256/0/180] [ 7077.111178] Key type lgssc registered [ 7078.016407] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7118.384749] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7122.492827] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7140.252249] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7147.042515] Lustre: Mounted lustre-client [ 7160.270856] LustreError: 130542:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff9a7c861b5000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7160.375334] Lustre: Unmounted lustre-client [ 7160.840560] Lustre: Mounted lustre-client [ 7169.394633] LustreError: 130668:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff9a7c84acd800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7169.473139] Lustre: Unmounted lustre-client [ 7193.719192] Key type lgssc unregistered [ 7193.924595] LNet: 131071:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7193.928750] LNetError: 131071:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7193.944499] LNet: Removed LNI 192.168.201.19@tcp [ 7194.437283] Key type .llcrypt unregistered [ 7194.443638] Key type ._llcrypt unregistered [ 7205.869063] Key type ._llcrypt registered [ 7205.871200] Key type .llcrypt registered [ 7206.887271] Lustre: DEBUG MARKER: == conf-sanity test 28a: set symlink parameters permanently with lctl ========================================================== 18:17:55 (1777501075) [ 7209.312737] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7209.374833] alg: No test for adler32 (adler32-zlib) [ 7210.455882] Lustre: Lustre: Build Version: 2.17.52_93_g6cdb58a [ 7210.781428] LNet: Added LNI 192.168.201.19@tcp [8/256/0/180] [ 7212.399433] Key type lgssc registered [ 7213.459147] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7249.895556] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7252.788468] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7268.361203] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7280.172136] Lustre: Mounted lustre-client [ 7316.742427] LustreError: 134387:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff9a7c84ac8000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7316.787379] Lustre: Unmounted lustre-client [ 7347.274984] Key type lgssc unregistered [ 7347.447496] LNet: 134792:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7347.452859] LNetError: 134792:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7347.468737] LNet: Removed LNI 192.168.201.19@tcp [ 7347.895107] Key type .llcrypt unregistered [ 7347.897347] Key type ._llcrypt unregistered [ 7358.413377] Key type ._llcrypt registered [ 7358.414924] Key type .llcrypt registered [ 7359.323152] Lustre: DEBUG MARKER: == conf-sanity test 28b: verify client-side parameters are set upon mount ========================================================== 18:20:28 (1777501228) [ 7361.345698] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7361.404080] alg: No test for adler32 (adler32-zlib) [ 7362.414523] Lustre: Lustre: Build Version: 2.17.52_93_g6cdb58a [ 7362.659928] LNet: Added LNI 192.168.201.19@tcp [8/256/0/180] [ 7364.311169] Key type lgssc registered [ 7365.018408] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7400.378128] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7403.291667] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7418.235642] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7420.735144] Lustre: Mounted lustre-client [ 7422.066172] LustreError: 137848:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff9a7c82d72800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7422.134086] Lustre: Unmounted lustre-client [ 7422.439333] Lustre: Mounted lustre-client [ 7427.454368] LustreError: 138339:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff9a7c861b5800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7427.537819] Lustre: Unmounted lustre-client [ 7450.877200] Key type lgssc unregistered [ 7451.120942] LNet: 138743:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7451.126778] LNetError: 138743:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7451.143552] LNet: Removed LNI 192.168.201.19@tcp [ 7451.625493] Key type .llcrypt unregistered [ 7451.629761] Key type ._llcrypt unregistered [ 7458.803965] Key type ._llcrypt registered [ 7458.805947] Key type .llcrypt registered [ 7459.567491] Lustre: DEBUG MARKER: == conf-sanity test 28c: verify filesystem parameters are set upon mount ========================================================== 18:22:08 (1777501328) [ 7461.056145] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7461.072671] alg: No test for adler32 (adler32-zlib) [ 7462.048195] Lustre: Lustre: Build Version: 2.17.52_93_g6cdb58a [ 7462.205577] LNet: Added LNI 192.168.201.19@tcp [8/256/0/180] [ 7463.847250] Key type lgssc registered [ 7464.522260] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7498.463901] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7501.216778] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7514.069894] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7516.486623] Lustre: Mounted lustre-client [ 7517.737300] LustreError: 141378:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff9a7c99266800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7517.802760] Lustre: Unmounted lustre-client [ 7518.135566] Lustre: Mounted lustre-client [ 7522.686249] LustreError: 141866:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff9a7ca000d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7522.759805] Lustre: Unmounted lustre-client [ 7545.763855] Key type lgssc unregistered [ 7545.983301] LNet: 142268:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7545.987608] LNetError: 142268:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7546.002235] LNet: Removed LNI 192.168.201.19@tcp [ 7546.482101] Key type .llcrypt unregistered [ 7546.484317] Key type ._llcrypt unregistered [ 7552.998431] Key type ._llcrypt registered [ 7553.001746] Key type .llcrypt registered [ 7554.078732] Lustre: DEBUG MARKER: == conf-sanity test 29: permanently remove an OST ======== 18:23:42 (1777501422) [ 7555.476868] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7555.517929] alg: No test for adler32 (adler32-zlib) [ 7556.435899] Lustre: Lustre: Build Version: 2.17.52_93_g6cdb58a [ 7556.554751] LNet: Added LNI 192.168.201.19@tcp [8/256/0/180] [ 7558.167156] Key type lgssc registered [ 7559.096787] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7595.157483] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7597.843266] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7609.358979] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7625.225245] Lustre: Mounted lustre-client [ 7638.034814] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 7641.317486] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 2 sec [ 7661.041253] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 7669.032594] LustreError: 145635:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff9a7c9868b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7669.082457] Lustre: Unmounted lustre-client [ 7669.323299] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 7669.403493] Lustre: Mounted lustre-client [ 7679.465501] LustreError: 145791:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff9a7ca000e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7679.528721] Lustre: Unmounted lustre-client [ 7710.314504] Key type lgssc unregistered [ 7710.504821] LNet: 146241:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7710.516674] LNetError: 146241:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7710.528339] LNet: Removed LNI 192.168.201.19@tcp [ 7710.934758] Key type .llcrypt unregistered [ 7710.938623] Key type ._llcrypt unregistered [ 7728.619275] Key type ._llcrypt registered [ 7728.622416] Key type .llcrypt registered [ 7729.364979] Lustre: DEBUG MARKER: == conf-sanity test 30a: Big config llog and permanent parameter deletion ========================================================== 18:26:38 (1777501598) [ 7730.462181] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7730.519462] alg: No test for adler32 (adler32-zlib) [ 7731.472222] Lustre: Lustre: Build Version: 2.17.52_93_g6cdb58a [ 7731.634599] LNet: Added LNI 192.168.201.19@tcp [8/256/0/180] [ 7733.239314] Key type lgssc registered [ 7733.763276] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7759.086492] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7761.339919] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7771.793774] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7773.045278] Lustre: Mounted lustre-client [ 7938.022043] LustreError: 151000:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff9a7c84045800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7938.059149] Lustre: Unmounted lustre-client [ 7938.294691] Lustre: Mounted lustre-client [ 7939.424315] LustreError: 151074:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff9a7ca000c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7939.473710] Lustre: Unmounted lustre-client [ 7939.701216] Lustre: Mounted lustre-client [ 7960.912177] Key type lgssc unregistered [ 7961.090943] LNet: 151543:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7961.098181] LNetError: 151543:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7961.112265] LNet: Removed LNI 192.168.201.19@tcp [ 7961.453822] Key type .llcrypt unregistered [ 7961.455503] Key type ._llcrypt unregistered [ 7970.430894] Key type ._llcrypt registered [ 7970.432327] Key type .llcrypt registered [ 7971.238160] Lustre: DEBUG MARKER: == conf-sanity test 30b: Remove failover nids ============ 18:30:40 (1777501840) [ 7972.507385] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7972.516294] alg: No test for adler32 (adler32-zlib) [ 7973.427375] Lustre: Lustre: Build Version: 2.17.52_93_g6cdb58a [ 7973.552400] LNet: Added LNI 192.168.201.19@tcp [8/256/0/180] [ 7975.175157] Key type lgssc registered [ 7975.800184] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8003.079680] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8005.098838] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8016.821393] Lustre: DEBUG MARKER: oleg119-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8018.169106] Lustre: Mounted lustre-client [ 8027.144111] LustreError: 154711:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff9a7c98796800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8027.203184] Lustre: Unmounted lustre-client [ 8027.419439] Lustre: Mounted lustre-client [ 8028.117235] LustreError: 154779:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff9a7c991d1000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8028.165520] Lustre: Unmounted lustre-client [ 8058.091982] Key type lgssc unregistered [ 8058.257607] LNet: 155182:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8058.260898] LNetError: 155182:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8058.272854] LNet: Removed LNI 192.168.201.19@tcp [ 8058.716036] Key type .llcrypt unregistered [ 8058.719349] Key type ._llcrypt unregistered [ 8068.447310] Key type ._llcrypt registered [ 8068.449016] Key type .llcrypt registered [ 8069.397300] Lustre: DEBUG MARKER: == conf-sanity test 31: Connect to non-existent node (shouldn't crash) ========================================================== 18:32:18 (1777501938) [ 8070.234309] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8070.245979] alg: No test for adler32 (adler32-zlib) [ 8071.212190] Lustre: Lustre: Build Version: 2.17.52_93_g6cdb58a [ 8071.350355] LNet: Added LNI 192.168.201.19@tcp [8/256/0/180] [ 8072.975217] Key type lgssc registered [ 8073.758909] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8090.079752] LustreError: 156875:0:(mgc_request.c:233:do_config_log_add()) MGC4.3.2.1@tcp: failed processing log, type 1: rc = -110 [ 8095.711794] LustreError: 156884:0:(mgc_request.c:614:do_requeue()) failed processing log: -110 [ 8136.928406] LNet: 1 local NIs in recovery (showing 1): 192.168.201.19@tcp [ 8213.727440] LNet: 1 local NIs in recovery (showing 1): 192.168.201.19@tcp [ 8249.311260] LustreError: MGC4.3.2.1@tcp: Confguration from log lustre-client failed from MGS -110. Communication error between node & MGS, a bad configuration, or other errors. See syslog for more info [ 8249.318554] Lustre: Unmounted lustre-client [ 8249.323767] LustreError: 156875:0:(super25.c:184:lustre_fill_super()) llite: Unable to mount : rc = -110 [ 8255.863881] Key type lgssc unregistered [ 8277.215215] LNet: 1 local NIs in recovery (showing 1): 192.168.201.19@tcp [ 8277.535619] LNet: 157292:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8277.542917] LNetError: 157292:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8345.125881] LNet: Removed LNI 192.168.201.19@tcp [ 8345.682693] Key type .llcrypt unregistered [ 8345.684735] Key type ._llcrypt unregistered [ 8369.258169] Key type ._llcrypt registered [ 8369.260217] Key type .llcrypt registered [ 8369.452862] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8369.461984] alg: No test for adler32 (adler32-zlib) [ 8370.437640] Lustre: Lustre: Build Version: 2.17.52_93_g6cdb58a [ 8370.598190] LNet: Added LNI 192.168.201.19@tcp [8/256/0/180] [ 8372.255179] Key type lgssc registered [ 8373.274382] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8401.160459] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 8043 sec ========== 18:37:49 (1777502269) [ 8402.353773] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 18:37:51 (1777502271) === [ 8404.767913] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 18:37:53 (1777502273) === [ 8425.975681] Key type lgssc unregistered [ 8426.157579] LNet: 161522:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8426.162521] LNetError: 161522:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8426.193513] LNet: Removed LNI 192.168.201.19@tcp [ 8426.661484] Key type .llcrypt unregistered [ 8426.663220] Key type ._llcrypt unregistered