[ 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 435025533 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, 524584K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001008] APIC: Switch to symmetric I/O mode setup [ 0.002215] x2apic enabled [ 0.003006] Switched APIC routing to physical x2apic. [ 0.004014] kvm-guest: setup PV IPIs [ 0.007600] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.008000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.008024] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009014] pid_max: default: 32768 minimum: 301 [ 0.010147] LSM: Security Framework initializing [ 0.011081] Yama: becoming mindful. [ 0.012036] SELinux: Initializing. [ 0.013072] *** VALIDATE selinux *** [ 0.022261] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.027547] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.029162] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.031103] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.032128] *** VALIDATE tmpfs *** [ 0.034251] *** VALIDATE proc *** [ 0.035261] *** VALIDATE cgroup *** [ 0.036011] *** VALIDATE cgroup2 *** [ 0.038099] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.039153] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.040008] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.041032] Spectre V2 : User space: Vulnerable [ 0.043012] Speculative Store Bypass: Vulnerable [ 0.046221] debug: unmapping init [mem 0xffffffffa0a59000-0xffffffffa0a60fff] [ 0.048265] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.049784] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.050029] ... version: 2 [ 0.051016] ... bit width: 48 [ 0.052013] ... generic registers: 4 [ 0.053016] ... value mask: 0000ffffffffffff [ 0.054018] ... max period: 00007fffffffffff [ 0.055018] ... fixed-purpose events: 3 [ 0.056011] ... event mask: 000000070000000f [ 0.057399] rcu: Hierarchical SRCU implementation. [ 0.059730] smp: Bringing up secondary CPUs ... [ 0.060616] x86: Booting SMP configuration: [ 0.061027] .... node #0, CPUs: #1 #2 #3 [ 0.068114] smp: Brought up 1 node, 4 CPUs [ 0.070015] smpboot: Max logical packages: 1 [ 0.071018] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.220454] node 0 deferred pages initialised in 147ms [ 0.224012] devtmpfs: initialized [ 0.225108] x86/mm: Memory block size: 128MB [ 0.228073] gcov: version magic: 0x41383552 [ 0.230174] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.234090] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.237317] pinctrl core: initialized pinctrl subsystem [ 0.239163] [ 0.239736] ************************************************************* [ 0.242013] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.244015] ** ** [ 0.247014] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.249011] ** ** [ 0.251019] ** This means that this kernel is built to expose internal ** [ 0.253010] ** IOMMU data structures, which may compromise security on ** [ 0.255016] ** your system. ** [ 0.257014] ** ** [ 0.260013] ** If you see this message and you are not debugging the ** [ 0.262023] ** kernel, report this immediately to your vendor! ** [ 0.264012] ** ** [ 0.266015] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.268017] ************************************************************* [ 0.271125] NET: Registered protocol family 16 [ 0.273527] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.276090] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.278100] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.281688] cpuidle: using governor menu [ 0.283931] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.287619] PCI: Using configuration type 1 for base access [ 0.290147] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.300133] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.301049] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.303040] cryptd: max_cpu_qlen set to 1000 [ 0.306286] ACPI: Added _OSI(Module Device) [ 0.307000] ACPI: Added _OSI(Processor Device) [ 0.310059] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.311011] ACPI: Added _OSI(Processor Aggregator Device) [ 0.315288] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.322693] ACPI: Interpreter enabled [ 0.324147] ACPI: PM: (supports S0 S3 S4 S5) [ 0.326021] ACPI: Using IOAPIC for interrupt routing [ 0.328174] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.331449] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.342000] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.344059] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.347030] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.350106] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.355506] acpiphp: Slot [2] registered [ 0.357125] acpiphp: Slot [5] registered [ 0.359264] acpiphp: Slot [6] registered [ 0.360181] acpiphp: Slot [3] registered [ 0.361090] acpiphp: Slot [4] registered [ 0.362154] acpiphp: Slot [7] registered [ 0.364087] acpiphp: Slot [8] registered [ 0.365105] acpiphp: Slot [9] registered [ 0.366189] acpiphp: Slot [10] registered [ 0.367074] acpiphp: Slot [11] registered [ 0.369119] acpiphp: Slot [12] registered [ 0.370174] acpiphp: Slot [13] registered [ 0.372109] acpiphp: Slot [14] registered [ 0.373108] acpiphp: Slot [15] registered [ 0.374116] acpiphp: Slot [16] registered [ 0.375000] acpiphp: Slot [17] registered [ 0.375000] acpiphp: Slot [18] registered [ 0.376079] acpiphp: Slot [19] registered [ 0.377108] acpiphp: Slot [20] registered [ 0.379193] acpiphp: Slot [21] registered [ 0.380124] acpiphp: Slot [22] registered [ 0.382104] acpiphp: Slot [23] registered [ 0.383104] acpiphp: Slot [24] registered [ 0.384098] acpiphp: Slot [25] registered [ 0.385112] acpiphp: Slot [26] registered [ 0.386065] acpiphp: Slot [27] registered [ 0.387155] acpiphp: Slot [28] registered [ 0.388086] acpiphp: Slot [29] registered [ 0.389073] acpiphp: Slot [30] registered [ 0.390076] acpiphp: Slot [31] registered [ 0.391097] PCI host bridge to bus 0000:00 [ 0.392018] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.394033] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.397036] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.399033] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.401041] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.405020] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.407235] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.411933] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.416101] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.424020] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.428568] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.431028] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.434031] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.436038] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.439273] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.441612] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.444057] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.448143] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.453026] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.464021] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.469030] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.476000] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.482021] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.490020] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.505028] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.515370] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.520024] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.525017] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.538022] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.552630] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.554411] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.556491] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.559419] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.561251] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.566137] iommu: Default domain type: Passthrough [ 0.567454] SCSI subsystem initialized [ 0.569139] ACPI: bus type USB registered [ 0.570100] usbcore: registered new interface driver usbfs [ 0.571072] usbcore: registered new interface driver hub [ 0.572059] usbcore: registered new device driver usb [ 0.572975] pps_core: LinuxPPS API ver. 1 registered [ 0.574011] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.576051] PTP clock support registered [ 0.577154] EDAC MC: Ver: 3.0.0 [ 0.578356] PCI: Using ACPI for IRQ routing [ 0.579771] NetLabel: Initializing [ 0.580000] NetLabel: domain hash size = 128 [ 0.581030] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.583087] NetLabel: unlabeled traffic allowed by default [ 0.584180] vgaarb: loaded [ 0.585392] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.587010] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.594000] clocksource: Switched to clocksource kvm-clock [ 0.721230] VFS: Disk quotas dquot_6.6.0 [ 0.722612] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.724365] *** VALIDATE ramfs *** [ 0.725187] *** VALIDATE hugetlbfs *** [ 0.726931] pnp: PnP ACPI init [ 0.728666] pnp: PnP ACPI: found 6 devices [ 0.748467] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.751378] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.753361] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.755592] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.758084] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.760717] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.763824] NET: Registered protocol family 2 [ 0.766474] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.771473] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.775501] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.780802] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.784398] TCP: Hash tables configured (established 65536 bind 65536) [ 0.787634] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.790931] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.793984] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.797263] NET: Registered protocol family 1 [ 0.799149] RPC: Registered named UNIX socket transport module. [ 0.800930] RPC: Registered udp transport module. [ 0.801965] RPC: Registered tcp transport module. [ 0.803010] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.804283] NET: Registered protocol family 44 [ 0.805235] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.806394] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.807788] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.809087] PCI: CLS 0 bytes, default 64 [ 0.810168] Unpacking initramfs... [ 2.225114] debug: unmapping init [mem 0xffff94c33cc64000-0xffff94c33ffcffff] [ 2.228777] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.230737] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.233817] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.740174] Initialise system trusted keyrings [ 2.741470] Key type blacklist registered [ 2.744229] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.755549] zbud: loaded [ 2.759321] *** VALIDATE nfs *** [ 2.760388] *** VALIDATE nfs4 *** [ 2.762611] pstore: using deflate compression [ 2.765981] Platform Keyring initialized [ 2.870385] NET: Registered protocol family 38 [ 2.872638] Key type asymmetric registered [ 2.875607] Asymmetric key parser 'x509' registered [ 2.877574] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.881212] io scheduler mq-deadline registered [ 2.882828] io scheduler kyber registered [ 2.884735] io scheduler bfq registered [ 2.887328] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.891510] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.894264] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.897044] ACPI: Power Button [PWRF] [ 2.903220] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.912421] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.926778] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.956285] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.985939] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.992664] Non-volatile memory driver v1.3 [ 2.993892] Linux agpgart interface v0.103 [ 3.029747] virtio_blk virtio1: [vda] 134360 512-byte logical blocks (68.8 MB/65.6 MiB) [ 3.032577] vda: detected capacity change from 0 to 68792320 [ 3.053400] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.056752] vdb: detected capacity change from 0 to 1073741824 [ 3.064287] libphy: Fixed MDIO Bus: probed [ 3.070117] usbcore: registered new interface driver usbserial_generic [ 3.072782] usbserial: USB Serial support registered for generic [ 3.076528] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.082602] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.086722] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.090402] mousedev: PS/2 mouse device common for all mice [ 3.093139] rtc_cmos 00:05: RTC can wake from S4 [ 3.096860] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.096986] rtc_cmos 00:05: registered as rtc0 [ 3.102837] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.104219] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.105962] intel_pstate: CPU model not supported [ 3.111518] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.115167] hid: raw HID events driver (C) Jiri Kosina [ 3.125192] usbcore: registered new interface driver usbhid [ 3.127374] usbhid: USB HID core driver [ 3.130733] drop_monitor: Initializing network drop monitor service [ 3.133204] Initializing XFRM netlink socket [ 3.137225] NET: Registered protocol family 10 [ 3.141092] Segment Routing with IPv6 [ 3.143117] NET: Registered protocol family 17 [ 3.146135] mpls_gso: MPLS GSO support [ 3.154669] RAS: Correctable Errors collector initialized. [ 3.156475] AVX version of gcm_enc/dec engaged. [ 3.158027] AES CTR mode by8 optimization enabled [ 3.233114] sched_clock: Marking stable (3233081382, 0)->(4141099560, -908018178) [ 3.237445] registered taskstats version 1 [ 3.239734] Loading compiled-in X.509 certificates [ 3.241777] zswap: loaded using pool lzo/zbud [ 3.268350] Key type big_key registered [ 3.283621] Key type encrypted registered [ 3.285466] ima: No TPM chip found, activating TPM-bypass! [ 3.287754] ima: Allocated hash algorithm: sha1 [ 3.290136] ima: No architecture policies found [ 3.291911] evm: Initialising EVM extended attributes: [ 3.294217] evm: security.selinux [ 3.295586] evm: security.ima [ 3.296686] evm: security.capability [ 3.297635] evm: HMAC attrs: 0x1 [ 3.299623] rtc_cmos 00:05: setting system clock to 2026-01-30 06:43:23 UTC (1769755403) [ 3.306317] debug: unmapping init [mem 0xffffffffa1a03000-0xffffffffa1bfffff] [ 3.308918] debug: unmapping init [mem 0xffffffffa0782000-0xffffffffa0a58fff] [ 3.317390] Write protecting the kernel read-only data: 28672k [ 3.321023] debug: unmapping init [mem 0xffffffff9ee03000-0xffffffff9effffff] [ 3.324198] debug: unmapping init [mem 0xffffffff9f714000-0xffffffff9f7fffff] [ 3.368228] 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.375636] systemd[1]: Detected virtualization kvm. [ 3.377488] systemd[1]: Detected architecture x86-64. [ 3.379663] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.411486] systemd[1]: No hostname configured. [ 3.413712] systemd[1]: Set hostname to . [ 3.416193] random: systemd: uninitialized urandom read (16 bytes read) [ 3.419226] systemd[1]: Initializing machine ID from random generator. [ 3.469871] random: ln: uninitialized urandom read (6 bytes read) [ 3.556257] random: systemd: uninitialized urandom read (16 bytes read) [ 3.558703] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ 3.563875] systemd[1]: Reached target Timers. [ OK ] Reached target Timers. [ 3.568242] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Started Memstrack Anylazing Service. Starting Journal Service... Starting Setup Virtual Console... [ OK ] Reached target Local File Systems. [ OK ] Reached target Swap. [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Initrd Root Device. Starting Apply Kernel Variables... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. Starting Create Volatile Files and Directories... [ OK ] Reached target Paths. [ OK ] Reached target Slices. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Setup Virtual Console. [ OK ] Started Apply Kernel Variables. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Journal Service. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.203298] device-mapper: uevent: version 1.0.3 [ 4.205372] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 5.060541] virtio_net virtio0 ens2: renamed from eth0 [ 5.133198] scsi host0: ata_piix [ 5.178649] scsi host1: ata_piix [ 5.181138] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.184057] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 9.823431] random: crng init done [ 9.825592] random: 7 urandom warning(s) missed due to ratelimiting [ 10.198892] dracut-initqueue[592]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 11.503515] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Timers. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Slices. [ OK ] Stopped target Sockets. [ OK ] Stopped target System Initialization. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped target Swap. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. Stopping udev Kernel Device Manager... [ OK ] Stopped target Paths. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ 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... [ 14.332658] printk: systemd: 25 output lines suppressed due to ratelimiting [ 15.129692] SELinux: Disabled at runtime. [ 15.285155] 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) [ 15.306880] systemd[1]: Detected virtualization kvm. [ 15.314677] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 16.803894] systemd[1]: initrd-switch-root.service: Succeeded. [ 16.810644] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 16.817355] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 16.822296] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 16.826440] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 16.840318] systemd[1]: Starting Journal Service... Starting Journal Service... [ 16.883720] systemd[1]: Activating swap /dev/disk/by-label/SWAP... Activating swap /dev/disk/by-label/SWAP... [ OK ] Stopped target Switch Root. Starting Remount Root and Kernel File Systems... [ OK ] Stopped target Initrd Root File System. [ 16.943667] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Stopped target Initrd File Systems. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Created slice system-sshd\x2dkeygen.slice. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on udev Control Socket. [ OK ] Listening on initctl Compatibility Named Pipe. Mounting Huge Pages File System... [ OK ] Listening on Process Core Dump Socket. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target RPC Port Mapper. [ 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. Mounting POSIX Message Queue File System... [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Listening on udev Kernel Socket. Starting udev Coldplug all Devices... [ OK ] Reached target rpc_pipefs.target. Starting Apply Kernel Variables... Mounting Kernel Debug File System... [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Started Journal Service. [ OK ] Activated swap /dev/disk/by-label/SWAP. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted Huge Pages File System. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted Kernel Debug File System. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... [ OK ] Reached target Swap. Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started udev Coldplug all Devices. [ OK ] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... [ OK ] Mounted /mnt. [ 18.514995] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 19.503819] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 19.521530] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 20.325155] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 20.504749] EDAC sbridge: Ver: 1.1.2 [ 24.004691] Key type dns_resolver registered [* ] A start job is running for Configur…-only root support (7s / no limit)[ 24.972099] NFS: Registering the id_resolver key type [ 24.977851] Key type id_resolver registered [ 24.979487] Key type id_legacy registered [** ] A start job is running for Configur…-only root support (8s / no limit) [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting Update UTMP about System Boot/Shutdown... Starting RPC Bind... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started dnf makecache --timer. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started irqbalance daemon. [ OK ] Reached target sshd-keygen.target. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Login Service... Starting Restore /run/initramfs on shutdown... [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Timers. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting Dynamic System Tuning Daemon... Starting GSSAPI Proxy Daemon... Starting OpenSSH server daemon... [ OK ] Started Login Service. Starting Hostname Service... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started OpenSSH server daemon. [ OK ] Started Permit User Sessions. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Command Scheduler. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting System Logging Service... Starting Crash recovery kernel arming... Starting Notify NFS peers of a restart... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg637-client login: [ 67.059008] hrtimer: interrupt took 1997309 ns [ 97.687106] libcfs: loading out-of-tree module taints kernel. [ 97.730060] Key type ._llcrypt registered [ 97.738798] Key type .llcrypt registered [ 98.054675] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 98.066704] alg: No test for adler32 (adler32-zlib) [ 100.008659] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 101.294823] LNet: Added LNI 192.168.206.37@tcp [8/256/0/180] [ 103.255186] Key type lgssc registered [ 106.655756] Lustre: Echo OBD driver; http://www.lustre.org/ [ 265.315892] Lustre: Mounted lustre-client [ 270.627896] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 290.783153] Lustre: lustre-OST0000-osc-ffff94c384c6c800: disconnect after 23s idle [ 293.588230] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing check_logdir /tmp/testlogs/ [ 298.483365] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing yml_node [ 303.094976] Lustre: DEBUG MARKER: Client: 2.17.50.86 [ 306.076488] Lustre: DEBUG MARKER: MDS: 2.17.50.86 [ 308.593806] Lustre: DEBUG MARKER: OSS: 2.17.50.86 [ 310.087430] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Fri Jan 30 01:48:28 EST 2026 [ 326.931993] Lustre: DEBUG MARKER: excepting tests: 21e 32b 32c 32newtarball [ 328.411234] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 331.887414] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 342.200228] LustreError: 8254:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 342.235156] Lustre: Unmounted lustre-client [ 423.492428] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 442.732771] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 463.412963] Lustre: DEBUG MARKER: == conf-sanity test 0: single mount setup ================ 01:51:02 (1769755862) [ 484.034889] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 503.292365] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 504.139032] Lustre: Mounted lustre-client [ 507.854198] LustreError: 11977:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff94c388624800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 507.873185] LustreError: 11977:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 507.878608] LustreError: 11977:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 507.925808] Lustre: Unmounted lustre-client [ 532.906757] Key type lgssc unregistered [ 533.216548] LNet: 12327:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 533.239152] LNetError: 12327:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 533.262547] LNet: Removed LNI 192.168.206.37@tcp [ 534.069158] Key type .llcrypt unregistered [ 534.080172] Key type ._llcrypt unregistered [ 547.482460] Key type ._llcrypt registered [ 547.488434] Key type .llcrypt registered [ 548.986846] Lustre: DEBUG MARKER: == conf-sanity test 1: start up ost twice (should return errors) ========================================================== 01:52:27 (1769755947) [ 551.373306] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 551.404057] alg: No test for adler32 (adler32-zlib) [ 552.601850] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 552.819359] LNet: Added LNI 192.168.206.37@tcp [8/256/0/180] [ 554.471176] Key type lgssc registered [ 555.518177] Lustre: Echo OBD driver; http://www.lustre.org/ [ 587.385086] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 605.738069] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 617.551876] Lustre: Mounted lustre-client [ 619.593888] LustreError: 14996:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff94c39b99d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 619.613232] LustreError: 14996:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 619.657776] Lustre: Unmounted lustre-client [ 643.143869] Key type lgssc unregistered [ 643.574101] LNet: 15347:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 643.600190] LNetError: 15347:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 644.649148] LNet: Removed LNI 192.168.206.37@tcp [ 645.027800] Key type .llcrypt unregistered [ 645.029518] Key type ._llcrypt unregistered [ 662.338591] Key type ._llcrypt registered [ 662.341203] Key type .llcrypt registered [ 663.674432] Lustre: DEBUG MARKER: == conf-sanity test 2: start up mds twice (should return err) ========================================================== 01:54:22 (1769756062) [ 666.819929] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 666.938456] alg: No test for adler32 (adler32-zlib) [ 668.114912] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 668.325621] LNet: Added LNI 192.168.206.37@tcp [8/256/0/180] [ 670.000291] Key type lgssc registered [ 671.071563] Lustre: Echo OBD driver; http://www.lustre.org/ [ 709.206769] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 738.433817] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 746.560669] Lustre: Mounted lustre-client [ 748.818519] LustreError: 17999:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff94c387e15800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 748.839605] LustreError: 17999:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 748.881125] Lustre: Unmounted lustre-client [ 766.441417] Key type lgssc unregistered [ 766.737954] LNet: 18350:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 766.751051] LNetError: 18350:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 766.773661] LNet: Removed LNI 192.168.206.37@tcp [ 767.480121] Key type .llcrypt unregistered [ 767.492050] Key type ._llcrypt unregistered [ 784.757876] Key type ._llcrypt registered [ 784.762563] Key type .llcrypt registered [ 786.163656] Lustre: DEBUG MARKER: == conf-sanity test 3: mount client twice (should return err) ========================================================== 01:56:24 (1769756184) [ 788.664701] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 788.830000] alg: No test for adler32 (adler32-zlib) [ 789.854853] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 790.131606] LNet: Added LNI 192.168.206.37@tcp [8/256/0/180] [ 791.855181] Key type lgssc registered [ 793.333183] Lustre: Echo OBD driver; http://www.lustre.org/ [ 830.748574] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 849.558165] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 852.256166] Lustre: Mounted lustre-client [ 857.014917] LustreError: 20961:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff94c3a06be800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 857.034700] LustreError: 20961:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 857.058247] Lustre: Unmounted lustre-client [ 882.398429] Key type lgssc unregistered [ 882.765485] LNet: 21312:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 882.775990] LNetError: 21312:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 882.802373] LNet: Removed LNI 192.168.206.37@tcp [ 883.353484] Key type .llcrypt unregistered [ 883.357258] Key type ._llcrypt unregistered [ 897.803375] Key type ._llcrypt registered [ 897.809442] Key type .llcrypt registered [ 898.943842] Lustre: DEBUG MARKER: == conf-sanity test 4: force cleanup ost, then cleanup === 01:58:17 (1769756297) [ 900.986894] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 901.134301] alg: No test for adler32 (adler32-zlib) [ 902.306255] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 902.478792] LNet: Added LNI 192.168.206.37@tcp [8/256/0/180] [ 904.143282] Key type lgssc registered [ 905.504932] Lustre: Echo OBD driver; http://www.lustre.org/ [ 936.998037] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 953.301620] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 955.788931] Lustre: Mounted lustre-client [ 961.003591] Lustre: lustre-OST0000-osc-ffff94c3a01de800: Connection to lustre-OST0000 (at 192.168.206.137@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 969.430612] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 979.724841] LustreError: 23932:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 979.742828] Lustre: Unmounted lustre-client [ 996.630830] Key type lgssc unregistered [ 996.864967] LNet: 24274:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 996.874841] LNetError: 24274:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 997.928313] LNet: Removed LNI 192.168.206.37@tcp [ 998.814315] Key type .llcrypt unregistered [ 998.819481] Key type ._llcrypt unregistered [ 1014.647647] Key type ._llcrypt registered [ 1014.649283] Key type .llcrypt registered [ 1016.157614] Lustre: DEBUG MARKER: == conf-sanity test 5a: force cleanup mds, then cleanup == 02:00:14 (1769756414) [ 1018.777469] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1018.849261] alg: No test for adler32 (adler32-zlib) [ 1019.881473] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 1020.101218] LNet: Added LNI 192.168.206.37@tcp [8/256/0/180] [ 1021.959285] Key type lgssc registered [ 1024.030211] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1060.991553] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1079.481716] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1082.593371] Lustre: Mounted lustre-client [ 1087.992658] Lustre: lustre-MDT0000-mdc-ffff94c384eb9800: Connection to lustre-MDT0000 (at 192.168.206.137@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1095.636952] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1105.924794] LustreError: 26894:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 1105.955399] LustreError: MGC192.168.206.137@tcp: Connection to MGS (at 192.168.206.137@tcp) was lost; in progress operations using this service will fail [ 1105.957717] Lustre: Unmounted lustre-client [ 1105.978761] LustreError: 25218:0:(client.c:1380:ptlrpc_import_delay_req()) @@@ IMP_CLOSED req@000000005ab0f820 x1855724107863936/t0(0) o250->MGC192.168.206.137@tcp@192.168.206.137@tcp:26/25 lens 520/544 e 0 to 0 dl 0 ref 1 fl Rpc:NQU/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1121.790391] Key type lgssc unregistered [ 1122.078761] LNet: 27245:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1122.084626] LNetError: 27245:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1122.096195] LNet: Removed LNI 192.168.206.37@tcp [ 1122.766906] Key type .llcrypt unregistered [ 1122.776135] Key type ._llcrypt unregistered [ 1138.818865] Key type ._llcrypt registered [ 1138.821409] Key type .llcrypt registered [ 1140.125540] Lustre: DEBUG MARKER: == conf-sanity test 5b: Try to start a client with no MGS (should return errs) ========================================================== 02:02:18 (1769756538) [ 1142.204510] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1142.386460] alg: No test for adler32 (adler32-zlib) [ 1143.553694] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 1143.789683] LNet: Added LNI 192.168.206.37@tcp [8/256/0/180] [ 1145.513091] Key type lgssc registered [ 1146.966738] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1227.602714] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1233.311340] LustreError: 29331:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.206.137@tcp: failed processing log, type 1: rc = -110 [ 1240.543465] LustreError: 29340:0:(mgc_request.c:614:do_requeue()) failed processing log: -110 [ 1392.607324] LustreError: MGC192.168.206.137@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 [ 1392.629756] Lustre: Unmounted lustre-client [ 1392.637395] LustreError: 29331:0:(obd_class.h:479:obd_check_dev()) Device 0 not setup [ 1392.654699] LustreError: 29331:0:(super25.c:190:lustre_fill_super()) llite: Unable to mount : rc = -110 [ 1404.344556] Key type lgssc unregistered [ 1404.687420] LNet: 29714:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1404.696607] LNetError: 29714:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1405.737084] LNet: Removed LNI 192.168.206.37@tcp [ 1406.732955] Key type .llcrypt unregistered [ 1406.736892] Key type ._llcrypt unregistered [ 1425.385516] Key type ._llcrypt registered [ 1425.388788] Key type .llcrypt registered [ 1426.905295] Lustre: DEBUG MARKER: == conf-sanity test 5c: cleanup after failed mount (bug 2712) (should return errs) ========================================================== 02:07:05 (1769756825) [ 1429.642355] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1429.745289] alg: No test for adler32 (adler32-zlib) [ 1430.864181] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 1431.212354] LNet: Added LNI 192.168.206.37@tcp [8/256/0/180] [ 1432.903871] Key type lgssc registered [ 1434.000236] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1470.059532] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1490.803550] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1491.253896] LustreError: 32200:0:(llite_lib.c:1441:ll_fill_super()) wrong.lustre: fsname longer than 8 characters: rc = -36 [ 1491.277122] Lustre: Unmounted wrong.lustre-client [ 1491.294373] LustreError: 32200:0:(obd_class.h:479:obd_check_dev()) Device 0 not setup [ 1491.325923] LustreError: 32200:0:(super25.c:190:lustre_fill_super()) llite: Unable to mount : rc = -36 [ 1510.257282] Key type lgssc unregistered [ 1510.568904] LNet: 32564:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1510.589799] LNetError: 32564:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1510.621680] LNet: Removed LNI 192.168.206.37@tcp [ 1511.388448] Key type .llcrypt unregistered [ 1511.390063] Key type ._llcrypt unregistered [ 1528.131986] Key type ._llcrypt registered [ 1528.134605] Key type .llcrypt registered [ 1529.456463] Lustre: DEBUG MARKER: == conf-sanity test 5d: mount with ost down ============== 02:08:48 (1769756928) [ 1532.278441] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1532.332557] alg: No test for adler32 (adler32-zlib) [ 1533.404146] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 1533.686386] LNet: Added LNI 192.168.206.37@tcp [8/256/0/180] [ 1535.439184] Key type lgssc registered [ 1536.745892] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1618.481520] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1637.679514] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1657.961505] Lustre: Mounted lustre-client [ 1660.973637] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1671.274748] LustreError: 35153:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 1671.319061] Lustre: Unmounted lustre-client [ 1687.989252] Key type lgssc unregistered [ 1688.306813] LNet: 35495:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1688.314338] LNetError: 35495:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1688.331140] LNet: Removed LNI 192.168.206.37@tcp [ 1689.191066] Key type .llcrypt unregistered [ 1689.193636] Key type ._llcrypt unregistered [ 1705.791725] Key type ._llcrypt registered [ 1705.794615] Key type .llcrypt registered [ 1707.117617] Lustre: DEBUG MARKER: == conf-sanity test 5e: delayed connect, don't crash (bug 10268) ========================================================== 02:11:45 (1769757105) [ 1709.184987] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1709.332202] alg: No test for adler32 (adler32-zlib) [ 1710.514513] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 1710.750688] LNet: Added LNI 192.168.206.37@tcp [8/256/0/180] [ 1712.431672] Key type lgssc registered [ 1713.915497] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1751.615628] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1770.820152] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1772.192478] LustreError: 36440:0:(niobuf.c:1105:ptl_send_rpc()) cfs_fail_timeout id 506 sleeping for 10000ms [ 1776.287352] LustreError: 37976:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.206.137@tcp: failed processing log, type 1: rc = -110 [ 1782.295208] LustreError: 36440:0:(niobuf.c:1105:ptl_send_rpc()) cfs_fail_timeout id 506 awake [ 1783.604864] Lustre: Mounted lustre-client [ 1785.368272] LustreError: 38032:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff94c3991b4000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1785.411308] LustreError: 38032:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 1785.468924] Lustre: Unmounted lustre-client [ 1807.189493] Key type lgssc unregistered [ 1807.484994] LNet: 38382:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1807.495157] LNetError: 38382:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1807.518360] LNet: Removed LNI 192.168.206.37@tcp [ 1808.174679] Key type .llcrypt unregistered [ 1808.182126] Key type ._llcrypt unregistered [ 1822.448587] Key type ._llcrypt registered [ 1822.452502] Key type .llcrypt registered [ 1823.818437] Lustre: DEBUG MARKER: == conf-sanity test 5f: mds down, cleanup after failed mount (bug 2712) ========================================================== 02:13:42 (1769757222) [ 1825.431525] Lustre: DEBUG MARKER: SKIP: conf-sanity test_5f needs separate mgs and mds [ 1827.345232] Lustre: DEBUG MARKER: == conf-sanity test 5g: handle missing debugfs =========== 02:13:45 (1769757225) [ 1828.347222] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1828.383606] alg: No test for adler32 (adler32-zlib) [ 1829.393422] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 1829.648968] LNet: Added LNI 192.168.206.37@tcp [8/256/0/180] [ 1831.343566] Key type lgssc registered [ 1832.600740] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1852.515046] Lustre: DEBUG MARKER: == conf-sanity test 5h: start mdt failure at mdt_fs_setup() ========================================================== 02:14:11 (1769757251) [ 1868.818801] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1885.917271] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1888.547459] Lustre: Mounted lustre-client [ 1909.223421] Lustre: lustre-MDT0000-mdc-ffff94c388621000: Connection to lustre-MDT0000 (at 192.168.206.137@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1909.251272] LustreError: MGC192.168.206.137@tcp: Connection to MGS (at 192.168.206.137@tcp) was lost; in progress operations using this service will fail [ 1909.290638] Lustre: Evicted from MGS (at 192.168.206.137@tcp) after server handle changed from 0x81d988090133bf42 to 0x81d988090133c0fb [ 1909.315193] Lustre: lustre-MDT0000-mdc-ffff94c388621000: Connection restored to 192.168.206.137@tcp (at 192.168.206.137@tcp) [ 1909.331591] Lustre: Skipped 1 previous similar message [ 1910.239160] Lustre: 39587:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1769757294/real 1769757294] req@00000000ab082c1d x1855724956159232/t0(0) o400->lustre-MDT0000-mdc-ffff94c388621000@192.168.206.137@tcp:12/10 lens 224/224 e 0 to 1 dl 1769757310 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1915.359113] Lustre: 39588:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1769757299/real 1769757299] req@000000009820dbc8 x1855724956159616/t0(0) o400->lustre-MDT0000-mdc-ffff94c388621000@192.168.206.137@tcp:12/10 lens 224/224 e 0 to 1 dl 1769757315 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1916.193226] LustreError: 42216:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff94c388621000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1916.204476] LustreError: 42216:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 1916.226304] Lustre: Unmounted lustre-client [ 1932.903364] Key type lgssc unregistered [ 1933.184716] LNet: 42565:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1933.192281] LNetError: 42565:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1933.209276] LNet: Removed LNI 192.168.206.37@tcp [ 1933.764171] Key type .llcrypt unregistered [ 1933.765688] Key type ._llcrypt unregistered [ 1946.412530] Key type ._llcrypt registered [ 1946.414884] Key type .llcrypt registered [ 1947.468152] Lustre: DEBUG MARKER: == conf-sanity test 5i: start mdt failure at mdt_quota_init() ========================================================== 02:15:46 (1769757346) [ 1949.679916] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1949.859604] alg: No test for adler32 (adler32-zlib) [ 1950.974779] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 1951.226121] LNet: Added LNI 192.168.206.37@tcp [8/256/0/180] [ 1952.975143] Key type lgssc registered [ 1954.030574] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1988.622651] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2008.286369] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2010.003031] Lustre: Mounted lustre-client [ 2030.566267] Lustre: lustre-MDT0000-mdc-ffff94c390c6c800: Connection to lustre-MDT0000 (at 192.168.206.137@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2030.597264] LustreError: MGC192.168.206.137@tcp: Connection to MGS (at 192.168.206.137@tcp) was lost; in progress operations using this service will fail [ 2030.620891] Lustre: Evicted from MGS (at 192.168.206.137@tcp) after server handle changed from 0xf4c4219a346c636 to 0xf4c4219a346c7a9 [ 2030.635527] Lustre: MGC192.168.206.137@tcp: Connection restored to 192.168.206.137@tcp (at 192.168.206.137@tcp) [ 2031.391175] Lustre: 43513:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1769757415/real 1769757415] req@00000000e8bf7c5c x1855725084085376/t0(0) o400->lustre-MDT0000-mdc-ffff94c390c6c800@192.168.206.137@tcp:12/10 lens 224/224 e 0 to 1 dl 1769757431 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2035.770540] Lustre: lustre-MDT0000-mdc-ffff94c390c6c800: Connection restored to 192.168.206.137@tcp (at 192.168.206.137@tcp) [ 2036.639257] Lustre: 43514:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1769757420/real 1769757420] req@000000005456552a x1855725084085760/t0(0) o400->lustre-MDT0000-mdc-ffff94c390c6c800@192.168.206.137@tcp:12/10 lens 224/224 e 0 to 1 dl 1769757436 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2040.703080] LustreError: 45521:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff94c390c6c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2040.727608] LustreError: 45521:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 2040.771206] Lustre: Unmounted lustre-client [ 2066.882479] Key type lgssc unregistered [ 2067.132620] LNet: 45871:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2067.135674] LNetError: 45871:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2067.155174] LNet: Removed LNI 192.168.206.37@tcp [ 2067.617575] Key type .llcrypt unregistered [ 2067.623024] Key type ._llcrypt unregistered [ 2083.158372] Key type ._llcrypt registered [ 2083.162345] Key type .llcrypt registered [ 2084.561913] Lustre: DEBUG MARKER: == conf-sanity test 5j: verify libcfs doesn't crash when setting debug flags ========================================================== 02:18:03 (1769757483) [ 2085.063631] Key type .llcrypt unregistered [ 2085.065215] Key type ._llcrypt unregistered [ 2091.285299] Key type ._llcrypt registered [ 2091.288091] Key type .llcrypt registered [ 2091.345067] Key type .llcrypt unregistered [ 2091.346652] Key type ._llcrypt unregistered [ 2095.668942] Key type ._llcrypt registered [ 2095.671148] Key type .llcrypt registered [ 2096.807843] Lustre: DEBUG MARKER: == conf-sanity test 6: manual umount, then mount again === 02:18:15 (1769757495) [ 2098.786238] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2098.938214] alg: No test for adler32 (adler32-zlib) [ 2099.889248] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 2100.054696] LNet: Added LNI 192.168.206.37@tcp [8/256/0/180] [ 2101.815286] Key type lgssc registered [ 2102.893864] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2134.309462] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2151.539960] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2154.165191] Lustre: Mounted lustre-client [ 2156.197206] LustreError: 49197:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff94c3991b6000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2156.228861] LustreError: 49197:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 2156.256202] Lustre: Unmounted lustre-client [ 2156.625238] Lustre: Mounted lustre-client [ 2159.252342] LustreError: 49271:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff94c385ee7800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2159.280687] LustreError: 49271:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 2159.284200] LustreError: 49271:0:(obd_class.h:479:obd_check_dev()) Skipped 5 previous similar messages [ 2159.314765] Lustre: Unmounted lustre-client [ 2178.172569] Key type lgssc unregistered [ 2178.410620] LNet: 49621:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2178.420475] LNetError: 49621:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2178.445797] LNet: Removed LNI 192.168.206.37@tcp [ 2179.224853] Key type .llcrypt unregistered [ 2179.228230] Key type ._llcrypt unregistered [ 2194.768182] Key type ._llcrypt registered [ 2194.773141] Key type .llcrypt registered [ 2196.076187] Lustre: DEBUG MARKER: == conf-sanity test 7: manual umount, then cleanup ======= 02:19:54 (1769757594) [ 2198.404988] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2198.432451] alg: No test for adler32 (adler32-zlib) [ 2199.579720] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 2199.784448] LNet: Added LNI 192.168.206.37@tcp [8/256/0/180] [ 2201.439171] Key type lgssc registered [ 2202.838232] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2236.545979] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2254.074283] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2256.702197] Lustre: Mounted lustre-client [ 2258.627903] LustreError: 52173:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff94c390c69000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2258.650318] LustreError: 52173:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 2258.675867] Lustre: Unmounted lustre-client [ 2273.798240] Key type lgssc unregistered [ 2274.034564] LNet: 52530:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2274.043428] LNetError: 52530:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2274.058706] LNet: Removed LNI 192.168.206.37@tcp [ 2274.676743] Key type .llcrypt unregistered [ 2274.679291] Key type ._llcrypt unregistered [ 2289.710758] Key type ._llcrypt registered [ 2289.714871] Key type .llcrypt registered [ 2290.962447] Lustre: DEBUG MARKER: == conf-sanity test 8: double mount setup ================ 02:21:29 (1769757689) [ 2293.141366] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2293.162858] alg: No test for adler32 (adler32-zlib) [ 2294.273985] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 2294.515946] LNet: Added LNI 192.168.206.37@tcp [8/256/0/180] [ 2296.199442] Key type lgssc registered [ 2297.485765] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2327.594806] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2341.546957] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2348.558481] Lustre: Mounted lustre-client [ 2350.744079] Lustre: Mounted lustre-client [ 2353.169958] LustreError: 55164:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff94c385ee4000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2353.184800] LustreError: 55164:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 2353.201260] Lustre: Unmounted lustre-client [ 2354.571911] LustreError: 55183:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff94c3861c7800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2354.604814] LustreError: 55183:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 2354.613597] LustreError: 55183:0:(obd_class.h:479:obd_check_dev()) Skipped 3 previous similar messages [ 2354.677475] Lustre: Unmounted lustre-client [ 2370.067849] Key type lgssc unregistered [ 2370.282671] LNet: 55533:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2370.295621] LNetError: 55533:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2370.316175] LNet: Removed LNI 192.168.206.37@tcp [ 2370.910584] Key type .llcrypt unregistered [ 2370.911889] Key type ._llcrypt unregistered [ 2383.608442] Key type ._llcrypt registered [ 2383.612615] Key type .llcrypt registered [ 2384.609951] Lustre: DEBUG MARKER: == conf-sanity test 9: test ptldebug and subsystem for mkfs ========================================================== 02:23:03 (1769757783) [ 2386.477519] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2386.626498] alg: No test for adler32 (adler32-zlib) [ 2387.654175] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 2387.856261] LNet: Added LNI 192.168.206.37@tcp [8/256/0/180] [ 2389.511181] Key type lgssc registered [ 2390.683641] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2469.151497] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2483.403984] Lustre: DEBUG MARKER: == conf-sanity test 10a: find lctl param broken symlinks ========================================================== 02:24:42 (1769757882) [ 2499.034454] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2515.548349] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2518.179824] Lustre: Mounted lustre-client [ 2524.429511] LustreError: 59256:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff94c387ebc800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2524.463888] LustreError: 59256:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 2524.519251] Lustre: Unmounted lustre-client [ 2540.928627] Key type lgssc unregistered [ 2541.180077] LNet: 59608:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2541.185922] LNetError: 59608:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2541.224500] LNet: Removed LNI 192.168.206.37@tcp [ 2541.714255] Key type .llcrypt unregistered [ 2541.716052] Key type ._llcrypt unregistered [ 2554.497790] Key type ._llcrypt registered [ 2554.499691] Key type .llcrypt registered [ 2555.711854] Lustre: DEBUG MARKER: == conf-sanity test 17: Verify failed mds_postsetup won't fail assertion (2936) (should return errs) ========================================================== 02:25:54 (1769757954) [ 2557.919995] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2557.980385] alg: No test for adler32 (adler32-zlib) [ 2559.142857] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 2559.493381] LNet: Added LNI 192.168.206.37@tcp [8/256/0/180] [ 2561.199614] Key type lgssc registered [ 2562.509660] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2594.953468] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2612.066676] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2614.918084] Lustre: Mounted lustre-client [ 2618.764098] LustreError: 62203:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff94c387eb8000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2618.782994] LustreError: 62203:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 2618.807602] Lustre: Unmounted lustre-client [ 2633.814880] Key type lgssc unregistered [ 2634.088921] LNet: 62552:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2634.097608] LNetError: 62552:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2634.110364] LNet: Removed LNI 192.168.206.37@tcp [ 2634.713246] Key type .llcrypt unregistered [ 2634.715810] Key type ._llcrypt unregistered [ 2647.336776] Key type ._llcrypt registered [ 2647.338329] Key type .llcrypt registered [ 2647.740688] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2647.761416] alg: No test for adler32 (adler32-zlib) [ 2648.770792] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 2648.960963] LNet: Added LNI 192.168.206.37@tcp [8/256/0/180] [ 2650.639191] Key type lgssc registered [ 2651.712834] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2729.234853] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2800.875385] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2818.317854] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2838.666418] Lustre: DEBUG MARKER: == conf-sanity test 18: check mkfs creates large journals ========================================================== 02:30:37 (1769758237) [ 2841.344702] Lustre: DEBUG MARKER: use device /dev/mapper/mds1_flakey with MIN=2000000 [ 2890.330525] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2905.367346] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2930.307853] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2943.154801] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2954.773060] Lustre: Mounted lustre-client [ 2958.795837] Lustre: DEBUG MARKER: Success: mkfs creates large journals. Size: 78M [ 2960.018180] LustreError: 71166:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff94c385811800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2960.033669] LustreError: 71166:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 2960.057178] Lustre: Unmounted lustre-client [ 2973.816148] Key type lgssc unregistered [ 2974.040273] LNet: 71516:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2974.046154] LNetError: 71516:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2974.070458] LNet: Removed LNI 192.168.206.37@tcp [ 2974.590534] Key type .llcrypt unregistered [ 2974.591720] Key type ._llcrypt unregistered [ 2992.977717] Key type ._llcrypt registered [ 2992.984345] Key type .llcrypt registered [ 2993.424506] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2993.440295] alg: No test for adler32 (adler32-zlib) [ 2994.487726] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 2994.682196] LNet: Added LNI 192.168.206.37@tcp [8/256/0/180] [ 2996.335166] Key type lgssc registered [ 2997.207475] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3038.522687] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3052.709123] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3071.260104] Lustre: DEBUG MARKER: == conf-sanity test 19a: start/stop MDS without OSTs ===== 02:34:30 (1769758470) [ 3083.156409] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3090.462646] Lustre: DEBUG MARKER: == conf-sanity test 19b: start/stop OSTs without MDS ===== 02:34:49 (1769758489) [ 3150.117116] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3157.257893] Lustre: DEBUG MARKER: == conf-sanity test 20: remount ro,rw mounts work and doesn't break /etc/mtab ========================================================== 02:35:56 (1769758556) [ 3169.536797] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3182.561655] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3189.249540] Lustre: Mounted lustre-client [ 3189.716656] Lustre: Remounted lustre-client read-only [ 3191.104618] LustreError: 78129:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff94c390c69000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3191.124402] LustreError: 78129:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 3191.148135] Lustre: Unmounted lustre-client [ 3206.741105] Lustre: DEBUG MARKER: == conf-sanity test 21a: start mds before ost, stop ost first ========================================================== 02:36:45 (1769758605) [ 3218.163691] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3231.069806] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3252.453117] Lustre: DEBUG MARKER: == conf-sanity test 21b: start ost before mds, stop mds first ========================================================== 02:37:31 (1769758651) [ 3308.130837] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3316.392238] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3335.994335] Lustre: DEBUG MARKER: == conf-sanity test 21c: start mds between two osts, stop mds last ========================================================== 02:38:55 (1769758735) [ 3392.725145] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3401.427510] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3414.002818] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3447.798362] Lustre: DEBUG MARKER: == conf-sanity test 21d: start mgs then ost and then mds ========================================================== 02:40:47 (1769758847) [ 3448.817828] Lustre: DEBUG MARKER: SKIP: conf-sanity test_21d need separate mgs device [ 3449.865275] Lustre: DEBUG MARKER: SKIP: conf-sanity test_21e skipping excluded test 21e [ 3451.018221] Lustre: DEBUG MARKER: == conf-sanity test 22: start a client before osts (should return errs) ========================================================== 02:40:50 (1769758850) [ 3461.664615] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3473.088710] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3485.281887] Lustre: Mounted lustre-client [ 3486.367996] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3496.615587] LustreError: 85572:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 3496.618406] LustreError: 85572:0:(obd_class.h:479:obd_check_dev()) Skipped 5 previous similar messages [ 3496.629429] Lustre: Unmounted lustre-client [ 3506.772089] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3507.140935] Lustre: Mounted lustre-client [ 3522.216241] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff94c3991b2800.ost_server_uuid 50 [ 3523.334802] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff94c3991b2800.ost_server_uuid in FULL state after 0 sec [ 3524.600505] LustreError: 86548:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff94c3991b2800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3524.621981] LustreError: 86548:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 3524.628703] LustreError: 86548:0:(obd_class.h:479:obd_check_dev()) Skipped 5 previous similar messages [ 3524.658703] Lustre: Unmounted lustre-client [ 3545.818566] Key type lgssc unregistered [ 3546.000522] LNet: 86900:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3546.005926] LNetError: 86900:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3546.015567] LNet: Removed LNI 192.168.206.37@tcp [ 3546.454229] Key type .llcrypt unregistered [ 3546.455788] Key type ._llcrypt unregistered [ 3555.436459] Key type ._llcrypt registered [ 3555.438396] Key type .llcrypt registered [ 3556.252454] Lustre: DEBUG MARKER: == conf-sanity test 23a: interrupt client during recovery mount delay ========================================================== 02:42:35 (1769758955) [ 3557.609678] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3557.641295] alg: No test for adler32 (adler32-zlib) [ 3558.551077] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 3558.686713] LNet: Added LNI 192.168.206.37@tcp [8/256/0/180] [ 3560.311592] Key type lgssc registered [ 3561.003882] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3583.941855] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3596.360040] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3603.981915] Lustre: Mounted lustre-client [ 3608.584112] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3618.859086] LustreError: 89494:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 3618.873743] LustreError: MGC192.168.206.137@tcp: Connection to MGS (at 192.168.206.137@tcp) was lost; in progress operations using this service will fail [ 3618.879601] Lustre: Unmounted lustre-client [ 3628.717813] LustreError: lustre-MDT0000-mdc-ffff94c3a0499800: operation mds_connect to node 192.168.206.137@tcp failed: rc = -16 [ 3633.681793] LustreError: 89678:0:(lmv_obd.c:1435:lmv_statfs()) lustre-MDT0000-mdc-ffff94c3a0499800: can't stat MDS #0: rc = -16 [ 3633.705265] LustreError: 89678:0:(obd_class.h:479:obd_check_dev()) Device 3 not setup [ 3633.707631] LustreError: 89678:0:(obd_class.h:479:obd_check_dev()) Skipped 5 previous similar messages [ 3633.720483] Lustre: Unmounted lustre-client [ 3633.735259] LustreError: 89678:0:(super25.c:190:lustre_fill_super()) llite: Unable to mount : rc = -16 [ 3648.098245] Key type lgssc unregistered [ 3648.266487] LNet: 90079:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3648.272520] LNetError: 90079:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3648.282819] LNet: Removed LNI 192.168.206.37@tcp [ 3648.634588] Key type .llcrypt unregistered [ 3648.635934] Key type ._llcrypt unregistered [ 3657.582789] Key type ._llcrypt registered [ 3657.584577] Key type .llcrypt registered [ 3658.316554] Lustre: DEBUG MARKER: == conf-sanity test 23b: Simulate -EINTR during mount ==== 02:44:17 (1769759057) [ 3659.770483] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3659.787960] alg: No test for adler32 (adler32-zlib) [ 3660.836111] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 3661.007438] LNet: Added LNI 192.168.206.37@tcp [8/256/0/180] [ 3662.663416] Key type lgssc registered [ 3663.379470] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3684.536856] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3695.189438] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3697.581269] Lustre: Mounted lustre-client [ 3698.210525] LustreError: 92597:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff94c3861c2800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3698.232954] LustreError: 92597:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 3698.264385] Lustre: Unmounted lustre-client [ 3710.585606] Key type lgssc unregistered [ 3710.747519] LNet: 92946:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3710.754117] LNetError: 92946:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3710.767768] LNet: Removed LNI 192.168.206.37@tcp [ 3711.192234] Key type .llcrypt unregistered [ 3711.194471] Key type ._llcrypt unregistered [ 3719.770491] Key type ._llcrypt registered [ 3719.772125] Key type .llcrypt registered [ 3720.590517] Lustre: DEBUG MARKER: == conf-sanity test 24a: Multiple MDTs on a single node == 02:45:19 (1769759119) [ 3722.067601] Lustre: DEBUG MARKER: SKIP: conf-sanity test_24a mixed loopback and real device not working [ 3723.207597] Lustre: DEBUG MARKER: == conf-sanity test 24b: Multiple MGSs on a single node (should return err) ========================================================== 02:45:22 (1769759122) [ 3724.744800] Lustre: DEBUG MARKER: SKIP: conf-sanity test_24b mixed loopback and real device not working [ 3725.829340] Lustre: DEBUG MARKER: == conf-sanity test 25: Verify modules are referenced ==== 02:45:25 (1769759125) [ 3727.433698] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3727.447271] alg: No test for adler32 (adler32-zlib) [ 3728.406268] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 3728.549774] LNet: Added LNI 192.168.206.37@tcp [8/256/0/180] [ 3730.169182] Key type lgssc registered [ 3730.878426] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3753.290885] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3764.911270] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3777.528942] Lustre: Mounted lustre-client [ 3778.525871] LustreError: 96116:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff94c384c6d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3778.536980] LustreError: 96116:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 3778.567194] Lustre: Unmounted lustre-client [ 3790.905249] Key type lgssc unregistered [ 3791.086762] LNet: 96465:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3791.092402] LNetError: 96465:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3791.103022] LNet: Removed LNI 192.168.206.37@tcp [ 3791.528734] Key type .llcrypt unregistered [ 3791.532700] Key type ._llcrypt unregistered [ 3798.969504] Key type ._llcrypt registered [ 3798.970613] Key type .llcrypt registered [ 3799.560940] Lustre: DEBUG MARKER: == conf-sanity test 26: MDT startup failure cleans LOV (should return errs) ========================================================== 02:46:39 (1769759199) [ 3799.987407] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3800.013199] alg: No test for adler32 (adler32-zlib) [ 3800.896768] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 3801.005135] LNet: Added LNI 192.168.206.37@tcp [8/256/0/180] [ 3802.607142] Key type lgssc registered [ 3803.339405] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3827.511745] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3831.110625] Key type lgssc unregistered [ 3831.247614] LNet: 98915:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3831.252408] LNetError: 98915:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3831.260919] LNet: Removed LNI 192.168.206.37@tcp [ 3831.665704] Key type .llcrypt unregistered [ 3831.667105] Key type ._llcrypt unregistered [ 3839.843336] Key type ._llcrypt registered [ 3839.845180] Key type .llcrypt registered [ 3840.664791] Lustre: DEBUG MARKER: == conf-sanity test 27a: Reacquire MGS lock if OST started first ========================================================== 02:47:19 (1769759239) [ 3844.097390] Key type .llcrypt unregistered [ 3844.099648] Key type ._llcrypt unregistered [ 3847.785832] Key type ._llcrypt registered [ 3847.787531] Key type .llcrypt registered [ 3848.042168] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3848.053790] alg: No test for adler32 (adler32-zlib) [ 3848.966348] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 3849.090398] LNet: Added LNI 192.168.206.37@tcp [8/256/0/180] [ 3850.703161] Key type lgssc registered [ 3851.270478] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3915.709928] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3924.129837] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3938.311877] Key type lgssc unregistered [ 3938.446722] LNet: 102043:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3938.451234] LNetError: 102043:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3938.459990] LNet: Removed LNI 192.168.206.37@tcp [ 3938.729781] Key type .llcrypt unregistered [ 3938.731259] Key type ._llcrypt unregistered [ 3945.045039] Key type ._llcrypt registered [ 3945.047090] Key type .llcrypt registered [ 3945.625667] Lustre: DEBUG MARKER: == conf-sanity test 27b: Reacquire MGS lock after failover ========================================================== 02:49:05 (1769759345) [ 3946.752513] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3946.759796] alg: No test for adler32 (adler32-zlib) [ 3947.643652] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 3947.731836] LNet: Added LNI 192.168.206.37@tcp [8/256/0/180] [ 3949.335350] Key type lgssc registered [ 3950.029526] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3965.805206] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3973.906850] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3976.207375] Lustre: Mounted lustre-client [ 3996.643911] LustreError: MGC192.168.206.137@tcp: Connection to MGS (at 192.168.206.137@tcp) was lost; in progress operations using this service will fail [ 3996.643940] Lustre: lustre-MDT0000-mdc-ffff94c38437c000: Connection to lustre-MDT0000 (at 192.168.206.137@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3996.659554] Lustre: lustre-MDT0000-mdc-ffff94c38437c000: Connection restored to 192.168.206.137@tcp (at 192.168.206.137@tcp) [ 3996.663126] Lustre: Evicted from MGS (at 192.168.206.137@tcp) after server handle changed from 0x249b91c5825a3a23 to 0x249b91c5825a3b50 [ 3997.663820] Lustre: 103004:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1769759381/real 1769759381] req@00000000e63a4890 x1855727177042816/t0(0) o400->lustre-MDT0000-mdc-ffff94c38437c000@192.168.206.137@tcp:12/10 lens 224/224 e 0 to 1 dl 1769759397 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4002.783145] Lustre: 103007:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1769759386/real 1769759386] req@00000000efe90d6a x1855727177043200/t0(0) o400->lustre-MDT0000-mdc-ffff94c38437c000@192.168.206.137@tcp:12/10 lens 224/224 e 0 to 1 dl 1769759402 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4006.091083] LustreError: 105025:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff94c38437c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4006.101268] LustreError: 105025:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 4006.117441] Lustre: Unmounted lustre-client [ 4017.142872] Key type lgssc unregistered [ 4017.285923] LNet: 105374:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4017.289472] LNetError: 105374:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4017.298383] LNet: Removed LNI 192.168.206.37@tcp [ 4017.634118] Key type .llcrypt unregistered [ 4017.636807] Key type ._llcrypt unregistered [ 4024.210208] Key type ._llcrypt registered [ 4024.211587] Key type .llcrypt registered [ 4024.865930] Lustre: DEBUG MARKER: == conf-sanity test 28A: permanent parameter setting ===== 02:50:24 (1769759424) [ 4025.843773] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4025.869373] alg: No test for adler32 (adler32-zlib) [ 4026.802950] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 4026.901581] LNet: Added LNI 192.168.206.37@tcp [8/256/0/180] [ 4028.487159] Key type lgssc registered [ 4028.936907] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4045.056320] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4054.187337] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4056.508173] Lustre: Mounted lustre-client [ 4073.658236] LustreError: 108073:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff94c385817000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4073.666872] LustreError: 108073:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 4073.680148] Lustre: Unmounted lustre-client [ 4073.848795] Lustre: Mounted lustre-client [ 4084.772316] LustreError: 108225:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff94c390c69000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4084.786359] LustreError: 108225:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 4084.788426] LustreError: 108225:0:(obd_class.h:479:obd_check_dev()) Skipped 5 previous similar messages [ 4084.806160] Lustre: Unmounted lustre-client [ 4095.541463] Key type lgssc unregistered [ 4095.660422] LNet: 108574:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4095.663269] LNetError: 108574:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4095.673481] LNet: Removed LNI 192.168.206.37@tcp [ 4095.932762] Key type .llcrypt unregistered [ 4095.934599] Key type ._llcrypt unregistered [ 4101.581700] Key type ._llcrypt registered [ 4101.583132] Key type .llcrypt registered [ 4102.165361] Lustre: DEBUG MARKER: == conf-sanity test 28a: set symlink parameters permanently with lctl ========================================================== 02:51:41 (1769759501) [ 4103.101997] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4103.144030] alg: No test for adler32 (adler32-zlib) [ 4104.023197] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 4104.130127] LNet: Added LNI 192.168.206.37@tcp [8/256/0/180] [ 4105.727141] Key type lgssc registered [ 4106.273420] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4122.738317] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4130.152241] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4131.339539] Lustre: Mounted lustre-client [ 4155.129368] LustreError: 111362:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff94c385815800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4155.137409] LustreError: 111362:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 4155.152439] Lustre: Unmounted lustre-client [ 4173.246135] Key type lgssc unregistered [ 4173.404416] LNet: 111712:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4173.409460] LNetError: 111712:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4173.422494] LNet: Removed LNI 192.168.206.37@tcp [ 4173.761357] Key type .llcrypt unregistered [ 4173.763385] Key type ._llcrypt unregistered [ 4182.114117] Key type ._llcrypt registered [ 4182.115445] Key type .llcrypt registered [ 4182.916829] Lustre: DEBUG MARKER: == conf-sanity test 28b: verify client-side parameters are set upon mount ========================================================== 02:53:02 (1769759582) [ 4184.227818] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4184.239325] alg: No test for adler32 (adler32-zlib) [ 4185.185427] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 4185.292712] LNet: Added LNI 192.168.206.37@tcp [8/256/0/180] [ 4186.895177] Key type lgssc registered [ 4187.536242] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4206.740340] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4216.032573] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4218.419711] Lustre: Mounted lustre-client [ 4219.064621] LustreError: 114272:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff94c388621000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4219.074837] LustreError: 114272:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 4219.093706] Lustre: Unmounted lustre-client [ 4219.280869] Lustre: Mounted lustre-client [ 4222.273849] LustreError: 114758:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff94c3a0610000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4222.288992] LustreError: 114758:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 4222.292660] LustreError: 114758:0:(obd_class.h:479:obd_check_dev()) Skipped 5 previous similar messages [ 4222.309228] Lustre: Unmounted lustre-client [ 4239.930519] Key type lgssc unregistered [ 4240.082870] LNet: 115109:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4240.087689] LNetError: 115109:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4240.104433] LNet: Removed LNI 192.168.206.37@tcp [ 4240.453250] Key type .llcrypt unregistered [ 4240.455775] Key type ._llcrypt unregistered [ 4245.615507] Key type ._llcrypt registered [ 4245.617020] Key type .llcrypt registered [ 4246.223685] Lustre: DEBUG MARKER: == conf-sanity test 28c: verify filesystem parameters are set upon mount ========================================================== 02:54:05 (1769759645) [ 4247.353470] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4247.363455] alg: No test for adler32 (adler32-zlib) [ 4248.313643] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 4248.419678] LNet: Added LNI 192.168.206.37@tcp [8/256/0/180] [ 4250.007102] Key type lgssc registered [ 4250.584832] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4267.555447] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4277.578810] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4278.937859] Lustre: Mounted lustre-client [ 4279.384012] LustreError: 117236:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff94c385ee6000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4279.400249] LustreError: 117236:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 4279.421165] Lustre: Unmounted lustre-client [ 4279.612064] Lustre: Mounted lustre-client [ 4282.679524] LustreError: 117724:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff94c387cfe800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4282.698522] LustreError: 117724:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 4282.702709] LustreError: 117724:0:(obd_class.h:479:obd_check_dev()) Skipped 5 previous similar messages [ 4282.726347] Lustre: Unmounted lustre-client [ 4301.302631] Key type lgssc unregistered [ 4301.468081] LNet: 118074:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4301.472205] LNetError: 118074:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4301.484682] LNet: Removed LNI 192.168.206.37@tcp [ 4301.836065] Key type .llcrypt unregistered [ 4301.838563] Key type ._llcrypt unregistered [ 4306.971388] Key type ._llcrypt registered [ 4306.972667] Key type .llcrypt registered [ 4307.614390] Lustre: DEBUG MARKER: == conf-sanity test 29: permanently remove an OST ======== 02:55:07 (1769759707) [ 4308.752827] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4308.761778] alg: No test for adler32 (adler32-zlib) [ 4309.652581] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 4309.773798] LNet: Added LNI 192.168.206.37@tcp [8/256/0/180] [ 4311.383122] Key type lgssc registered [ 4311.980440] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4329.221436] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4338.388396] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4340.758954] Lustre: Mounted lustre-client [ 4350.391934] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 4351.187629] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 4367.866996] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 4375.453609] LustreError: 120888:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff94c386d91000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4375.460953] LustreError: 120888:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4375.476106] Lustre: Unmounted lustre-client [ 4375.643846] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 4375.718811] Lustre: Mounted lustre-client [ 4384.290919] LustreError: 121038:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff94c385ee2800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4384.303794] LustreError: 121038:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4384.306640] LustreError: 121038:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 4384.335174] Lustre: Unmounted lustre-client [ 4403.959259] Key type lgssc unregistered [ 4404.095784] LNet: 121432:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4404.098796] LNetError: 121432:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4404.107675] LNet: Removed LNI 192.168.206.37@tcp [ 4404.434121] Key type .llcrypt unregistered [ 4404.435684] Key type ._llcrypt unregistered [ 4415.835662] Key type ._llcrypt registered [ 4415.837016] Key type .llcrypt registered [ 4416.362320] Lustre: DEBUG MARKER: == conf-sanity test 30a: Big config llog and permanent parameter deletion ========================================================== 02:56:55 (1769759815) [ 4417.426329] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4417.433778] alg: No test for adler32 (adler32-zlib) [ 4418.365270] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 4418.472771] LNet: Added LNI 192.168.206.37@tcp [8/256/0/180] [ 4420.087151] Key type lgssc registered [ 4420.643286] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4437.296706] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4445.342790] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4451.812054] Lustre: Mounted lustre-client [ 4608.013108] LustreError: 125603:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff94c385a60000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4608.019295] LustreError: 125603:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 4608.034118] Lustre: Unmounted lustre-client [ 4608.184784] Lustre: Mounted lustre-client [ 4608.917476] LustreError: 125679:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff94c3a04d5800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4608.928359] LustreError: 125679:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 4608.930761] LustreError: 125679:0:(obd_class.h:479:obd_check_dev()) Skipped 5 previous similar messages [ 4608.945606] Lustre: Unmounted lustre-client [ 4620.157820] Key type lgssc unregistered [ 4620.306827] LNet: 126093:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4620.309944] LNetError: 126093:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4620.321256] LNet: Removed LNI 192.168.206.37@tcp [ 4620.593646] Key type .llcrypt unregistered [ 4620.594870] Key type ._llcrypt unregistered [ 4626.590952] Key type ._llcrypt registered [ 4626.592226] Key type .llcrypt registered [ 4627.147064] Lustre: DEBUG MARKER: == conf-sanity test 30b: Remove failover nids ============ 03:00:26 (1769760026) [ 4628.091537] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4628.108207] alg: No test for adler32 (adler32-zlib) [ 4628.990660] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 4629.079314] LNet: Added LNI 192.168.206.37@tcp [8/256/0/180] [ 4630.663122] Key type lgssc registered [ 4631.075693] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4645.865328] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4652.928575] Lustre: DEBUG MARKER: oleg637-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4655.189344] Lustre: Mounted lustre-client [ 4660.123455] LustreError: 128716:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff94c38774d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4660.134128] LustreError: 128716:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 4660.151605] Lustre: Unmounted lustre-client [ 4660.306845] Lustre: Mounted lustre-client [ 4660.790509] LustreError: 128789:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff94c385ee3800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4660.802664] LustreError: 128789:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 4660.806130] LustreError: 128789:0:(obd_class.h:479:obd_check_dev()) Skipped 5 previous similar messages [ 4660.821757] Lustre: Unmounted lustre-client [ 4671.869071] Key type lgssc unregistered [ 4672.010420] LNet: 129149:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4672.014356] LNetError: 129149:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4672.025641] LNet: Removed LNI 192.168.206.37@tcp [ 4672.323340] Key type .llcrypt unregistered [ 4672.324835] Key type ._llcrypt unregistered [ 4678.259681] Key type ._llcrypt registered [ 4678.261232] Key type .llcrypt registered [ 4678.810858] Lustre: DEBUG MARKER: == conf-sanity test 31: Connect to non-existent node (shouldn't crash) ========================================================== 03:01:18 (1769760078) [ 4679.235540] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4679.257420] alg: No test for adler32 (adler32-zlib) [ 4680.168762] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 4680.273345] LNet: Added LNI 192.168.206.37@tcp [8/256/0/180] [ 4681.863162] Key type lgssc registered [ 4682.353598] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4696.031309] LustreError: 130767:0:(mgc_request.c:233:do_config_log_add()) MGC4.3.2.1@tcp: failed processing log, type 1: rc = -110 [ 4704.735256] LustreError: 130776:0:(mgc_request.c:614:do_requeue()) failed processing log: -110 [ 4746.849912] LNet: 1 local NIs in recovery (showing 1): 192.168.206.37@tcp [ 4822.623294] LNet: 1 local NIs in recovery (showing 1): 192.168.206.37@tcp [ 4853.727428] 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 [ 4853.735495] Lustre: Unmounted lustre-client [ 4853.738086] LustreError: 130767:0:(obd_class.h:479:obd_check_dev()) Device 0 not setup [ 4853.745883] LustreError: 130767:0:(super25.c:190:lustre_fill_super()) llite: Unable to mount : rc = -110 [ 4857.592867] Key type lgssc unregistered [ 4874.143602] LNet: 131138:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4874.146293] LNetError: 131138:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4954.021623] LNet: Removed LNI 192.168.206.37@tcp [ 4954.290689] Key type .llcrypt unregistered [ 4954.291853] Key type ._llcrypt unregistered [ 4965.822181] Key type ._llcrypt registered [ 4965.823743] Key type .llcrypt registered [ 4966.028438] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4966.033824] alg: No test for adler32 (adler32-zlib) [ 4966.911333] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 4967.007394] LNet: Added LNI 192.168.206.37@tcp [8/256/0/180] [ 4968.599155] Key type lgssc registered [ 4969.122705] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4984.458963] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 4674 sec ========== 03:06:24 (1769760384) [ 4985.120755] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 03:06:24 (1769760384) === [ 4986.460897] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 03:06:26 (1769760386) === [ 4996.998585] Key type lgssc unregistered [ 4997.121434] LNet: 134890:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4997.125336] LNetError: 134890:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4997.133353] LNet: Removed LNI 192.168.206.37@tcp [ 4997.392710] Key type .llcrypt unregistered [ 4997.394178] Key type ._llcrypt unregistered