[ 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 3.0.0 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.16.3-2.fc40 04/01/2014 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 508023792 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 0x000f5410-0x000f541f] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F5220 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE1D6F 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE1C0B 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 001BCB (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE1C7F 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE1D0F 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE1D47 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe1c0b-0xbffe1c7e] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe1c0a] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe1c7f-0xbffe1d0e] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe1d0f-0xbffe1d46] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe1d47-0xbffe1d6e] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001014] APIC: Switch to symmetric I/O mode setup [ 0.002000] x2apic enabled [ 0.002000] Switched APIC routing to physical x2apic. [ 0.002000] kvm-guest: setup PV IPIs [ 0.002000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.002000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.003025] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.004014] pid_max: default: 32768 minimum: 301 [ 0.005210] LSM: Security Framework initializing [ 0.006069] Yama: becoming mindful. [ 0.007000] SELinux: Initializing. [ 0.007000] *** VALIDATE selinux *** [ 0.015325] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.020625] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.021153] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.023117] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.024131] *** VALIDATE tmpfs *** [ 0.026430] *** VALIDATE proc *** [ 0.028014] *** VALIDATE cgroup *** [ 0.029015] *** VALIDATE cgroup2 *** [ 0.031199] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.032178] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.033014] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.034033] Spectre V2 : User space: Vulnerable [ 0.036005] Speculative Store Bypass: Vulnerable [ 0.039534] debug: unmapping init [mem 0xffffffff92659000-0xffffffff92660fff] [ 0.042179] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.043746] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.044026] ... version: 2 [ 0.045015] ... bit width: 48 [ 0.046020] ... generic registers: 4 [ 0.047020] ... value mask: 0000ffffffffffff [ 0.048026] ... max period: 00007fffffffffff [ 0.049017] ... fixed-purpose events: 3 [ 0.050013] ... event mask: 000000070000000f [ 0.051389] rcu: Hierarchical SRCU implementation. [ 0.053732] smp: Bringing up secondary CPUs ... [ 0.054766] x86: Booting SMP configuration: [ 0.055035] .... node #0, CPUs: #1 #2 #3 [ 0.059029] smp: Brought up 1 node, 4 CPUs [ 0.061018] smpboot: Max logical packages: 1 [ 0.062018] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.141021] node 0 deferred pages initialised in 77ms [ 0.144019] devtmpfs: initialized [ 0.145197] x86/mm: Memory block size: 128MB [ 0.147808] gcov: version magic: 0x41383552 [ 0.148641] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.152095] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.155419] pinctrl core: initialized pinctrl subsystem [ 0.157193] [ 0.157738] ************************************************************* [ 0.160020] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.162012] ** ** [ 0.164020] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.166013] ** ** [ 0.168016] ** This means that this kernel is built to expose internal ** [ 0.170015] ** IOMMU data structures, which may compromise security on ** [ 0.173015] ** your system. ** [ 0.175013] ** ** [ 0.177017] ** If you see this message and you are not debugging the ** [ 0.179016] ** kernel, report this immediately to your vendor! ** [ 0.181014] ** ** [ 0.183015] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.185020] ************************************************************* [ 0.187710] NET: Registered protocol family 16 [ 0.189285] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.191093] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.194059] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.198033] cpuidle: using governor menu [ 0.199503] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.201443] PCI: Using configuration type 1 for base access [ 0.203155] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.211125] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.213026] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.217230] cryptd: max_cpu_qlen set to 1000 [ 0.221361] ACPI: Added _OSI(Module Device) [ 0.223018] ACPI: Added _OSI(Processor Device) [ 0.225025] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.227023] ACPI: Added _OSI(Processor Aggregator Device) [ 0.230000] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.237671] ACPI: Interpreter enabled [ 0.239091] ACPI: PM: (supports S0 S3 S4 S5) [ 0.241018] ACPI: Using IOAPIC for interrupt routing [ 0.243167] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.246415] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.256098] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.258076] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.261030] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.263114] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.269498] acpiphp: Slot [2] registered [ 0.270163] acpiphp: Slot [5] registered [ 0.272204] acpiphp: Slot [6] registered [ 0.274160] acpiphp: Slot [3] registered [ 0.275143] acpiphp: Slot [4] registered [ 0.277168] acpiphp: Slot [7] registered [ 0.278080] acpiphp: Slot [8] registered [ 0.280105] acpiphp: Slot [9] registered [ 0.281096] acpiphp: Slot [10] registered [ 0.283118] acpiphp: Slot [11] registered [ 0.284199] acpiphp: Slot [12] registered [ 0.285120] acpiphp: Slot [13] registered [ 0.287146] acpiphp: Slot [14] registered [ 0.288120] acpiphp: Slot [15] registered [ 0.289132] acpiphp: Slot [16] registered [ 0.290119] acpiphp: Slot [17] registered [ 0.292189] acpiphp: Slot [18] registered [ 0.293175] acpiphp: Slot [19] registered [ 0.295123] acpiphp: Slot [20] registered [ 0.296148] acpiphp: Slot [21] registered [ 0.298157] acpiphp: Slot [22] registered [ 0.299110] acpiphp: Slot [23] registered [ 0.300140] acpiphp: Slot [24] registered [ 0.302184] acpiphp: Slot [25] registered [ 0.303134] acpiphp: Slot [26] registered [ 0.305149] acpiphp: Slot [27] registered [ 0.306211] acpiphp: Slot [28] registered [ 0.308222] acpiphp: Slot [29] registered [ 0.310177] acpiphp: Slot [30] registered [ 0.311189] acpiphp: Slot [31] registered [ 0.313087] PCI host bridge to bus 0000:00 [ 0.314027] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.317042] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.319037] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.322035] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.324029] pci_bus 0000:00: root bus resource [mem 0x380000000000-0x38007fffffff window] [ 0.326030] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.328226] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.331185] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.334592] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.341573] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.346490] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.349030] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.351030] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.354023] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.355705] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.358845] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.361054] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.363944] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.368040] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.382032] pci 0000:00:02.0: reg 0x20: [mem 0x380000000000-0x380000003fff 64bit pref] [ 0.391028] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.399351] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.408020] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.414031] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.428047] pci 0000:00:05.0: reg 0x20: [mem 0x380000004000-0x380000007fff 64bit pref] [ 0.437456] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.443014] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.447021] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.458024] pci 0000:00:06.0: reg 0x20: [mem 0x380000008000-0x38000000bfff 64bit pref] [ 0.467920] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.470517] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.474068] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.476421] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.479277] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.483167] iommu: Default domain type: Passthrough [ 0.485537] SCSI subsystem initialized [ 0.487167] ACPI: bus type USB registered [ 0.489120] usbcore: registered new interface driver usbfs [ 0.491108] usbcore: registered new interface driver hub [ 0.492080] usbcore: registered new device driver usb [ 0.494168] pps_core: LinuxPPS API ver. 1 registered [ 0.496011] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.498086] PTP clock support registered [ 0.501079] EDAC MC: Ver: 3.0.0 [ 0.502429] PCI: Using ACPI for IRQ routing [ 0.504920] NetLabel: Initializing [ 0.506019] NetLabel: domain hash size = 128 [ 0.508012] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.510107] NetLabel: unlabeled traffic allowed by default [ 0.512160] vgaarb: loaded [ 0.514324] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.516018] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.524493] clocksource: Switched to clocksource kvm-clock [ 0.638057] VFS: Disk quotas dquot_6.6.0 [ 0.639832] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.642755] *** VALIDATE ramfs *** [ 0.644031] *** VALIDATE hugetlbfs *** [ 0.645799] pnp: PnP ACPI init [ 0.649564] pnp: PnP ACPI: found 6 devices [ 0.668855] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.672572] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.674955] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.677285] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.679980] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.682657] pci_bus 0000:00: resource 8 [mem 0x380000000000-0x38007fffffff window] [ 0.686134] NET: Registered protocol family 2 [ 0.689542] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.695431] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.699490] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.705649] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.709371] TCP: Hash tables configured (established 65536 bind 65536) [ 0.712361] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.715413] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.718754] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.722194] NET: Registered protocol family 1 [ 0.728786] RPC: Registered named UNIX socket transport module. [ 0.731145] RPC: Registered udp transport module. [ 0.733240] RPC: Registered tcp transport module. [ 0.735018] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.737950] NET: Registered protocol family 44 [ 0.739766] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.741744] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.743822] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.746433] PCI: CLS 0 bytes, default 64 [ 0.748331] Unpacking initramfs... [ 2.221510] debug: unmapping init [mem 0xffff8a527cc64000-0xffff8a527ffcffff] [ 2.231195] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.233642] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.236802] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.728293] Initialise system trusted keyrings [ 2.730074] Key type blacklist registered [ 2.731854] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.740904] zbud: loaded [ 2.743922] *** VALIDATE nfs *** [ 2.745230] *** VALIDATE nfs4 *** [ 2.746914] pstore: using deflate compression [ 2.749888] Platform Keyring initialized [ 2.851178] NET: Registered protocol family 38 [ 2.853089] Key type asymmetric registered [ 2.854299] Asymmetric key parser 'x509' registered [ 2.856432] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.859696] io scheduler mq-deadline registered [ 2.861910] io scheduler kyber registered [ 2.863168] io scheduler bfq registered [ 2.865082] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.868791] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.872046] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.875600] ACPI: Power Button [PWRF] [ 2.952297] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.042061] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.135984] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.164743] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.195048] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.200906] Non-volatile memory driver v1.3 [ 3.202929] Linux agpgart interface v0.103 [ 3.242410] virtio_blk virtio1: [vda] 134032 512-byte logical blocks (68.6 MB/65.4 MiB) [ 3.245835] vda: detected capacity change from 0 to 68624384 [ 3.264103] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.267167] vdb: detected capacity change from 0 to 1073741824 [ 3.274942] libphy: Fixed MDIO Bus: probed [ 3.280841] usbcore: registered new interface driver usbserial_generic [ 3.283572] usbserial: USB Serial support registered for generic [ 3.286396] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.291216] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.293330] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.295968] mousedev: PS/2 mouse device common for all mice [ 3.298751] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.300621] rtc_cmos 00:05: RTC can wake from S4 [ 3.305407] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.307930] rtc_cmos 00:05: registered as rtc0 [ 3.309853] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.312423] intel_pstate: CPU model not supported [ 3.314901] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.316207] hid: raw HID events driver (C) Jiri Kosina [ 3.321150] usbcore: registered new interface driver usbhid [ 3.323047] usbhid: USB HID core driver [ 3.324132] drop_monitor: Initializing network drop monitor service [ 3.325522] Initializing XFRM netlink socket [ 3.327731] NET: Registered protocol family 10 [ 3.330464] Segment Routing with IPv6 [ 3.331580] NET: Registered protocol family 17 [ 3.333451] mpls_gso: MPLS GSO support [ 3.338265] RAS: Correctable Errors collector initialized. [ 3.340653] AVX version of gcm_enc/dec engaged. [ 3.342365] AES CTR mode by8 optimization enabled [ 3.420540] sched_clock: Marking stable (3420507520, 0)->(4415482665, -994975145) [ 3.424272] registered taskstats version 1 [ 3.426293] Loading compiled-in X.509 certificates [ 3.427947] zswap: loaded using pool lzo/zbud [ 3.456542] Key type big_key registered [ 3.471116] Key type encrypted registered [ 3.472527] ima: No TPM chip found, activating TPM-bypass! [ 3.474728] ima: Allocated hash algorithm: sha1 [ 3.476682] ima: No architecture policies found [ 3.478835] evm: Initialising EVM extended attributes: [ 3.480971] evm: security.selinux [ 3.482484] evm: security.ima [ 3.483721] evm: security.capability [ 3.485326] evm: HMAC attrs: 0x1 [ 3.488351] rtc_cmos 00:05: setting system clock to 2025-12-22 14:03:12 UTC (1766412192) [ 3.495687] debug: unmapping init [mem 0xffffffff93603000-0xffffffff937fffff] [ 3.499085] debug: unmapping init [mem 0xffffffff92382000-0xffffffff92658fff] [ 3.507119] Write protecting the kernel read-only data: 28672k [ 3.512793] debug: unmapping init [mem 0xffffffff90a03000-0xffffffff90bfffff] [ 3.515880] debug: unmapping init [mem 0xffffffff91314000-0xffffffff913fffff] [ 3.552362] 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.561822] systemd[1]: Detected virtualization kvm. [ 3.563918] systemd[1]: Detected architecture x86-64. [ 3.566082] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.593606] systemd[1]: No hostname configured. [ 3.595301] systemd[1]: Set hostname to . [ 3.597257] random: systemd: uninitialized urandom read (16 bytes read) [ 3.600022] systemd[1]: Initializing machine ID from random generator. [ 3.743448] random: systemd: uninitialized urandom read (16 bytes read) [ 3.746310] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ 3.750441] random: systemd: uninitialized urandom read (16 bytes read) [ 3.753149] systemd[1]: Reached target Timers. [ OK ] Reached target Timers. [ 3.757161] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Local File Systems. [ OK ] Listening on Journal Socket. [ OK ] Started Memstrack Anylazing Service. Starting Create list of required st…ce nodes for the current kernel... Starting Apply Kernel Variables... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. Starting Setup Virtual Console... [ OK ] Reached target Slices. [ OK ] Reached target Local Encrypted Volumes. Starting Create Volatile Files and Directories... [ OK ] Listening on udev Control Socket. Starting Journal Service... [ OK ] Reached target Sockets. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. [ OK ] Started Setup Virtual Console. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.448256] device-mapper: uevent: version 1.0.3 [ 4.450537] 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. [ 5.103265] random: fast init done Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 5.156460] virtio_net virtio0 ens2: renamed from eth0 [ 5.180432] scsi host0: ata_piix [ 5.194441] scsi host1: ata_piix [ 5.195883] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.197810] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 9.512982] dracut-initqueue[588]: RTNETLINK answers: File exists [ 9.992880] random: crng init done [ 9.994608] random: 7 urandom warning(s) missed due to ratelimiting 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... [ 10.432449] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped target Timers. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ 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 Paths. [ OK ] Stopped target Sockets. [ OK ] Stopped target System Initialization. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Swap. [ OK ] Stopped target Local File Systems. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 11.644169] printk: systemd: 26 output lines suppressed due to ratelimiting [ 11.894963] SELinux: Disabled at runtime. [ 11.955733] 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) [ 11.964994] systemd[1]: Detected virtualization kvm. [ 11.967160] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 12.440379] systemd[1]: initrd-switch-root.service: Succeeded. [ 12.444257] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 12.448701] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 12.452921] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 12.456910] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 12.468255] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.473060] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. Starting Remount Root and Kernel File Systems... [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Listening on Process Core Dump Socket. Starting Apply Kernel Variables... [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Listening on udev Control Socket. Mounting Huge Pages File System... Mounting POSIX Message Queue File System... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Created slice system-getty.slice. [ OK ] Created slice system-serial\x2dgetty.slice. Activating swap /dev/disk/by-label/SWAP... [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. Starting udev Coldplug all Devices... [ 12.623097] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. [ OK ] Stopped target Initrd Root File System. Mounting Kernel Debug File System... [ OK ] Reached target rpc_pipefs.target. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Started Journal Service. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted Huge Pages File System. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted Kernel Debug File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Reached target Swap. Starting Create Static Device Nodes in /dev... Starting Configure read-only root support... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... [ OK ] Started udev Coldplug all Devices. [ OK ] Mounted /mnt. [ 13.031307] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 13.276817] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 13.455691] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 13.531343] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 13.572559] EDAC sbridge: Ver: 1.1.2 [ 15.223544] Key type dns_resolver registered [ 15.549304] NFS: Registering the id_resolver key type [ 15.552871] Key type id_resolver registered [ 15.554473] Key type id_legacy registered [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... Starting Load/Save Random Seed... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Load/Save Random Seed. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started 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 ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Basic System. [ OK ] Started D-Bus System Message Bus. [ OK ] Started irqbalance daemon. [ OK ] Started dnf makecache --timer. Starting Restore /run/initramfs on shutdown... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. Starting Network Manager... [ OK ] Reached target sshd-keygen.target. Starting Login Service... [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... Starting OpenSSH server daemon... Starting Network Manager Wait Online... Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Getty on tty1. [ 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 Notify NFS peers of a restart... Starting Crash recovery kernel arming... Starting System Logging Service... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started Authorization Manager. [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg125-client login: [ 64.498674] libcfs: loading out-of-tree module taints kernel. [ 64.730335] Key type ._llcrypt registered [ 64.733595] Key type .llcrypt registered [ 65.211580] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 65.226373] alg: No test for adler32 (adler32-zlib) [ 66.670262] Lustre: Lustre: Build Version: 2.17.0_RC3_1_g3717bff [ 67.818756] LNet: Added LNI 192.168.201.25@tcp [8/256/0/180] [ 69.719755] Key type lgssc registered [ 70.594251] hrtimer: interrupt took 2463260 ns [ 71.611670] Lustre: Echo OBD driver; http://www.lustre.org/ [ 214.920748] Lustre: Mounted lustre-client [ 219.791312] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 239.660134] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing check_logdir /tmp/testlogs/ [ 240.609162] Lustre: lustre-OST0000-osc-ffff8a52c8bba000: disconnect after 23s idle [ 243.824511] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing yml_node [ 248.106984] Lustre: DEBUG MARKER: Client: 2.17.0.RC3 [ 250.661681] Lustre: DEBUG MARKER: MDS: 2.17.0.RC3 [ 252.802155] Lustre: DEBUG MARKER: OSS: 2.17.0.RC3 [ 254.487349] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Mon Dec 22 09:07:22 EST 2025 [ 271.090654] Lustre: DEBUG MARKER: excepting tests: 21e 32b 32c 32newtarball [ 272.673462] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 274.943715] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 285.244535] LustreError: 9000:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 285.310616] Lustre: Unmounted lustre-client [ 381.681692] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 384.792974] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 401.883248] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 432.874791] Lustre: DEBUG MARKER: == conf-sanity test 0: single mount setup ================ 09:10:20 (1766412620) [ 454.254796] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 457.113977] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 470.845474] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 471.588688] Lustre: Mounted lustre-client [ 478.382867] LustreError: 14093:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a52c8ac3800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 478.411385] LustreError: 14093:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 478.414976] LustreError: 14093:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 478.459202] Lustre: Unmounted lustre-client [ 502.904608] Key type lgssc unregistered [ 503.242510] LNet: 14495:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 503.257682] LNetError: 14495:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 503.290582] LNet: Removed LNI 192.168.201.25@tcp [ 503.954201] Key type .llcrypt unregistered [ 503.961375] Key type ._llcrypt unregistered [ 516.213077] Key type ._llcrypt registered [ 516.218223] Key type .llcrypt registered [ 517.302750] Lustre: DEBUG MARKER: == conf-sanity test 1: start up ost twice (should return errors) ========================================================== 09:11:45 (1766412705) [ 519.196795] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 519.229046] alg: No test for adler32 (adler32-zlib) [ 520.237901] Lustre: Lustre: Build Version: 2.17.0_RC3_1_g3717bff [ 520.417216] LNet: Added LNI 192.168.201.25@tcp [8/256/0/180] [ 522.064263] Key type lgssc registered [ 523.192342] Lustre: Echo OBD driver; http://www.lustre.org/ [ 564.714905] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 567.637943] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 582.539156] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 591.570918] Lustre: Mounted lustre-client [ 593.260133] LustreError: 17678:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a52d11f7000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 593.288738] LustreError: 17678:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 593.346302] Lustre: Unmounted lustre-client [ 624.808252] Key type lgssc unregistered [ 624.994864] LNet: 18082:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 625.003244] LNetError: 18082:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 625.016866] LNet: Removed LNI 192.168.201.25@tcp [ 625.529378] Key type .llcrypt unregistered [ 625.532722] Key type ._llcrypt unregistered [ 635.283630] Key type ._llcrypt registered [ 635.285299] Key type .llcrypt registered [ 636.408113] Lustre: DEBUG MARKER: == conf-sanity test 2: start up mds twice (should return err) ========================================================== 09:13:44 (1766412824) [ 638.562439] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 638.640332] alg: No test for adler32 (adler32-zlib) [ 639.686288] Lustre: Lustre: Build Version: 2.17.0_RC3_1_g3717bff [ 639.847630] LNet: Added LNI 192.168.201.25@tcp [8/256/0/180] [ 641.495272] Key type lgssc registered [ 642.432430] Lustre: Echo OBD driver; http://www.lustre.org/ [ 675.636435] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 679.062182] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 699.748680] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 705.562937] Lustre: Mounted lustre-client [ 707.471149] LustreError: 21241:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a52c8554000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 707.490376] LustreError: 21241:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 707.538430] Lustre: Unmounted lustre-client [ 729.046713] Key type lgssc unregistered [ 729.287264] LNet: 21642:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 729.298136] LNetError: 21642:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 729.310102] LNet: Removed LNI 192.168.201.25@tcp [ 729.862752] Key type .llcrypt unregistered [ 729.868742] Key type ._llcrypt unregistered [ 744.089634] Key type ._llcrypt registered [ 744.092564] Key type .llcrypt registered [ 745.406185] Lustre: DEBUG MARKER: == conf-sanity test 3: mount client twice (should return err) ========================================================== 09:15:32 (1766412932) [ 747.710789] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 747.744521] alg: No test for adler32 (adler32-zlib) [ 748.899173] Lustre: Lustre: Build Version: 2.17.0_RC3_1_g3717bff [ 749.167518] LNet: Added LNI 192.168.201.25@tcp [8/256/0/180] [ 750.863180] Key type lgssc registered [ 752.207426] Lustre: Echo OBD driver; http://www.lustre.org/ [ 794.552587] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 798.538217] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 814.167566] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 826.399186] Lustre: Mounted lustre-client [ 831.301805] LustreError: 24769:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a52d11d7800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 831.336442] LustreError: 24769:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 831.404507] Lustre: Unmounted lustre-client [ 856.765919] Key type lgssc unregistered [ 857.031180] LNet: 25170:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 857.042822] LNetError: 25170:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 857.062816] LNet: Removed LNI 192.168.201.25@tcp [ 857.700419] Key type .llcrypt unregistered [ 857.702165] Key type ._llcrypt unregistered [ 870.904428] Key type ._llcrypt registered [ 870.906144] Key type .llcrypt registered [ 872.041031] Lustre: DEBUG MARKER: == conf-sanity test 4: force cleanup ost, then cleanup === 09:17:39 (1766413059) [ 874.179730] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 874.269985] alg: No test for adler32 (adler32-zlib) [ 875.303600] Lustre: Lustre: Build Version: 2.17.0_RC3_1_g3717bff [ 875.520213] LNet: Added LNI 192.168.201.25@tcp [8/256/0/180] [ 877.203229] Key type lgssc registered [ 878.317557] Lustre: Echo OBD driver; http://www.lustre.org/ [ 925.058487] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 928.278809] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 943.110767] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 954.920534] Lustre: Mounted lustre-client [ 959.990342] Lustre: lustre-OST0000-osc-ffff8a52c75ea800: Connection to lustre-OST0000 (at 192.168.201.125@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 969.046082] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 979.318985] LustreError: 28308:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 979.382795] Lustre: Unmounted lustre-client [ 1005.324306] Key type lgssc unregistered [ 1005.550882] LNet: 28704:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1005.558349] LNetError: 28704:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1005.581341] LNet: Removed LNI 192.168.201.25@tcp [ 1006.185917] Key type .llcrypt unregistered [ 1006.187800] Key type ._llcrypt unregistered [ 1019.564332] Key type ._llcrypt registered [ 1019.566239] Key type .llcrypt registered [ 1021.064979] Lustre: DEBUG MARKER: == conf-sanity test 5a: force cleanup mds, then cleanup == 09:20:08 (1766413208) [ 1023.295739] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1023.329363] alg: No test for adler32 (adler32-zlib) [ 1024.399139] Lustre: Lustre: Build Version: 2.17.0_RC3_1_g3717bff [ 1024.594883] LNet: Added LNI 192.168.201.25@tcp [8/256/0/180] [ 1026.255193] Key type lgssc registered [ 1027.276041] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1067.469366] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1071.032946] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1088.809979] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1095.725170] Lustre: Mounted lustre-client [ 1100.784237] Lustre: lustre-MDT0000-mdc-ffff8a52c9231800: Connection to lustre-MDT0000 (at 192.168.201.125@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1107.784976] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1118.076803] LustreError: 31874:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1118.089828] LustreError: MGC192.168.201.125@tcp: Connection to MGS (at 192.168.201.125@tcp) was lost; in progress operations using this service will fail [ 1118.114113] Lustre: Unmounted lustre-client [ 1144.864373] Key type lgssc unregistered [ 1145.066825] LNet: 32270:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1145.072996] LNetError: 32270:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1146.086232] LNet: Removed LNI 192.168.201.25@tcp [ 1146.639430] Key type .llcrypt unregistered [ 1146.642784] Key type ._llcrypt unregistered [ 1163.795612] Key type ._llcrypt registered [ 1163.797809] Key type .llcrypt registered [ 1165.285746] Lustre: DEBUG MARKER: == conf-sanity test 5b: Try to start a client with no MGS (should return errs) ========================================================== 09:22:32 (1766413352) [ 1168.429195] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1168.440484] alg: No test for adler32 (adler32-zlib) [ 1169.652586] Lustre: Lustre: Build Version: 2.17.0_RC3_1_g3717bff [ 1170.095049] LNet: Added LNI 192.168.201.25@tcp [8/256/0/180] [ 1171.775192] Key type lgssc registered [ 1172.885891] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1253.948166] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1259.615396] LustreError: 34439:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.201.125@tcp: failed processing log, type 1: rc = -110 [ 1267.679365] LustreError: 34448:0:(mgc_request.c:614:do_requeue()) failed processing log: -110 [ 1417.183758] LustreError: MGC192.168.201.125@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 [ 1417.202439] Lustre: Unmounted lustre-client [ 1417.208149] LustreError: 34439:0:(obd_class.h:479:obd_check_dev()) Device 0 not setup [ 1417.227295] LustreError: 34439:0:(super25.c:199:lustre_fill_super()) llite: Unable to mount : rc = -110 [ 1427.799563] Key type lgssc unregistered [ 1428.028549] LNet: 34865:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1428.037571] LNetError: 34865:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1428.060845] LNet: Removed LNI 192.168.201.25@tcp [ 1428.603608] Key type .llcrypt unregistered [ 1428.605412] Key type ._llcrypt unregistered [ 1441.822844] Key type ._llcrypt registered [ 1441.825816] Key type .llcrypt registered [ 1443.275975] Lustre: DEBUG MARKER: == conf-sanity test 5c: cleanup after failed mount (bug 2712) (should return errs) ========================================================== 09:27:10 (1766413630) [ 1445.960028] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1445.990632] alg: No test for adler32 (adler32-zlib) [ 1447.156973] Lustre: Lustre: Build Version: 2.17.0_RC3_1_g3717bff [ 1447.528307] LNet: Added LNI 192.168.201.25@tcp [8/256/0/180] [ 1449.239524] Key type lgssc registered [ 1450.682546] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1492.410982] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1495.663746] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1509.950398] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1510.396258] LustreError: 37878:0:(llite_lib.c:1440:ll_fill_super()) wrong.lustre: fsname longer than 8 characters: rc = -36 [ 1510.409393] Lustre: Unmounted wrong.lustre-client [ 1510.419063] LustreError: 37878:0:(obd_class.h:479:obd_check_dev()) Device 0 not setup [ 1510.422076] LustreError: 37878:0:(super25.c:199:lustre_fill_super()) llite: Unable to mount : rc = -36 [ 1535.577092] Key type lgssc unregistered [ 1535.821509] LNet: 38293:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1535.832592] LNetError: 38293:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1535.852894] LNet: Removed LNI 192.168.201.25@tcp [ 1536.389797] Key type .llcrypt unregistered [ 1536.391649] Key type ._llcrypt unregistered [ 1549.654854] Key type ._llcrypt registered [ 1549.657693] Key type .llcrypt registered [ 1551.020448] Lustre: DEBUG MARKER: == conf-sanity test 5d: mount with ost down ============== 09:28:58 (1766413738) [ 1553.183908] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1553.205892] alg: No test for adler32 (adler32-zlib) [ 1554.502814] Lustre: Lustre: Build Version: 2.17.0_RC3_1_g3717bff [ 1554.847303] LNet: Added LNI 192.168.201.25@tcp [8/256/0/180] [ 1556.583216] Key type lgssc registered [ 1557.553729] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1634.116890] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1661.277401] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1664.604562] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1684.210120] Lustre: Mounted lustre-client [ 1685.421355] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1695.726096] LustreError: 41378:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1695.789413] Lustre: Unmounted lustre-client [ 1721.687757] Key type lgssc unregistered [ 1722.054064] LNet: 41774:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1722.068598] LNetError: 41774:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1722.089906] LNet: Removed LNI 192.168.201.25@tcp [ 1722.858420] Key type .llcrypt unregistered [ 1722.865382] Key type ._llcrypt unregistered [ 1735.603633] Key type ._llcrypt registered [ 1735.606751] Key type .llcrypt registered [ 1736.959986] Lustre: DEBUG MARKER: == conf-sanity test 5e: delayed connect, don't crash (bug 10268) ========================================================== 09:32:04 (1766413924) [ 1738.988323] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1739.077478] alg: No test for adler32 (adler32-zlib) [ 1740.219375] Lustre: Lustre: Build Version: 2.17.0_RC3_1_g3717bff [ 1740.453330] LNet: Added LNI 192.168.201.25@tcp [8/256/0/180] [ 1742.127353] Key type lgssc registered [ 1743.272435] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1783.386701] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1786.673678] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1803.750535] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1804.267521] LustreError: 42729:0:(niobuf.c:1105:ptl_send_rpc()) cfs_fail_timeout id 506 sleeping for 10000ms [ 1809.375686] LustreError: 44764:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.201.125@tcp: failed processing log, type 1: rc = -110 [ 1814.304067] LustreError: 42729:0:(niobuf.c:1105:ptl_send_rpc()) cfs_fail_timeout id 506 awake [ 1815.533819] Lustre: Mounted lustre-client [ 1816.667394] LustreError: 44822:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a52d84c0000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1816.687559] LustreError: 44822:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1816.775581] Lustre: Unmounted lustre-client [ 1843.531776] Key type lgssc unregistered [ 1843.776490] LNet: 45223:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1843.787136] LNetError: 45223:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1843.807429] LNet: Removed LNI 192.168.201.25@tcp [ 1844.457137] Key type .llcrypt unregistered [ 1844.459846] Key type ._llcrypt unregistered [ 1857.693169] Key type ._llcrypt registered [ 1857.694624] Key type .llcrypt registered [ 1858.967941] Lustre: DEBUG MARKER: == conf-sanity test 5f: mds down, cleanup after failed mount (bug 2712) ========================================================== 09:34:06 (1766414046) [ 1860.630303] Lustre: DEBUG MARKER: SKIP: conf-sanity test_5f needs separate mgs and mds [ 1862.296553] Lustre: DEBUG MARKER: == conf-sanity test 5g: handle missing debugfs =========== 09:34:09 (1766414049) [ 1863.343793] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1863.370422] alg: No test for adler32 (adler32-zlib) [ 1864.398207] Lustre: Lustre: Build Version: 2.17.0_RC3_1_g3717bff [ 1864.599508] LNet: Added LNI 192.168.201.25@tcp [8/256/0/180] [ 1866.247201] Key type lgssc registered [ 1867.599505] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1887.565287] Lustre: DEBUG MARKER: == conf-sanity test 5h: start mdt failure at mdt_fs_setup() ========================================================== 09:34:35 (1766414075) [ 1912.941398] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1917.056680] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1935.894233] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1942.545035] Lustre: Mounted lustre-client [ 1947.631500] Lustre: lustre-MDT0000-mdc-ffff8a52d0625800: Connection to lustre-MDT0000 (at 192.168.201.125@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1963.007348] LustreError: MGC192.168.201.125@tcp: Connection to MGS (at 192.168.201.125@tcp) was lost; in progress operations using this service will fail [ 1963.041299] Lustre: Evicted from MGS (at 192.168.201.125@tcp) after server handle changed from 0x3a630dee1a9f3bd0 to 0x3a630dee1a9f3e85 [ 1963.062482] Lustre: MGC192.168.201.125@tcp: Connection restored to 192.168.201.125@tcp (at 192.168.201.125@tcp) [ 1966.828295] Lustre: lustre-MDT0000-mdc-ffff8a52d0625800: Connection restored to 192.168.201.125@tcp (at 192.168.201.125@tcp) [ 1970.383172] LustreError: 49594:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a52d0625800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1970.409624] LustreError: 49594:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1970.505741] Lustre: Unmounted lustre-client [ 1995.802314] Key type lgssc unregistered [ 1996.045314] LNet: 49996:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1996.064278] LNetError: 49996:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1996.081706] LNet: Removed LNI 192.168.201.25@tcp [ 1996.632698] Key type .llcrypt unregistered [ 1996.634687] Key type ._llcrypt unregistered [ 2010.014827] Key type ._llcrypt registered [ 2010.016566] Key type .llcrypt registered [ 2011.239600] Lustre: DEBUG MARKER: == conf-sanity test 5i: start mdt failure at mdt_quota_init() ========================================================== 09:36:38 (1766414198) [ 2013.097567] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2013.134085] alg: No test for adler32 (adler32-zlib) [ 2014.204328] Lustre: Lustre: Build Version: 2.17.0_RC3_1_g3717bff [ 2014.481584] LNet: Added LNI 192.168.201.25@tcp [8/256/0/180] [ 2016.177340] Key type lgssc registered [ 2017.505322] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2059.432321] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2062.556250] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2075.944634] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2088.012142] Lustre: Mounted lustre-client [ 2093.038392] Lustre: lustre-MDT0000-mdc-ffff8a52c3667800: Connection to lustre-MDT0000 (at 192.168.201.125@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2108.389625] LustreError: MGC192.168.201.125@tcp: Connection to MGS (at 192.168.201.125@tcp) was lost; in progress operations using this service will fail [ 2108.410295] Lustre: Evicted from MGS (at 192.168.201.125@tcp) after server handle changed from 0x9649834538ddbf94 to 0x9649834538ddc2e3 [ 2108.418491] Lustre: MGC192.168.201.125@tcp: Connection restored to 192.168.201.125@tcp (at 192.168.201.125@tcp) [ 2111.164374] Lustre: lustre-MDT0000-mdc-ffff8a52c3667800: Connection restored to 192.168.201.125@tcp (at 192.168.201.125@tcp) [ 2113.957571] LustreError: 53469:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a52c3667800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2113.976672] LustreError: 53469:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2114.054223] Lustre: Unmounted lustre-client [ 2135.272494] Key type lgssc unregistered [ 2135.506830] LNet: 53871:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2135.518810] LNetError: 53871:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2135.536664] LNet: Removed LNI 192.168.201.25@tcp [ 2136.320899] Key type .llcrypt unregistered [ 2136.334898] Key type ._llcrypt unregistered [ 2150.245175] Key type ._llcrypt registered [ 2150.247504] Key type .llcrypt registered [ 2152.172516] Lustre: DEBUG MARKER: == conf-sanity test 5j: verify libcfs doesn't crash when setting debug flags ========================================================== 09:38:59 (1766414339) [ 2152.917336] Key type .llcrypt unregistered [ 2152.922546] Key type ._llcrypt unregistered [ 2159.610605] Key type ._llcrypt registered [ 2159.612395] Key type .llcrypt registered [ 2159.711715] Key type .llcrypt unregistered [ 2159.717693] Key type ._llcrypt unregistered [ 2165.554044] Key type ._llcrypt registered [ 2165.564945] Key type .llcrypt registered [ 2167.391223] Lustre: DEBUG MARKER: == conf-sanity test 6: manual umount, then mount again === 09:39:14 (1766414354) [ 2169.793365] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2169.850460] alg: No test for adler32 (adler32-zlib) [ 2171.017080] Lustre: Lustre: Build Version: 2.17.0_RC3_1_g3717bff [ 2171.256583] LNet: Added LNI 192.168.201.25@tcp [8/256/0/180] [ 2172.991190] Key type lgssc registered [ 2174.122559] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2217.758427] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2221.951136] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2242.698907] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2244.427683] Lustre: Mounted lustre-client [ 2246.583110] LustreError: 57722:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a52c8ac7800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2246.611478] LustreError: 57722:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2246.743443] Lustre: Unmounted lustre-client [ 2247.216204] Lustre: Mounted lustre-client [ 2248.872786] LustreError: 57803:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a52d07fb000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2248.904226] LustreError: 57803:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2248.907549] LustreError: 57803:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 2248.975668] Lustre: Unmounted lustre-client [ 2273.957542] Key type lgssc unregistered [ 2274.168448] LNet: 58206:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2274.175171] LNetError: 58206:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2274.191623] LNet: Removed LNI 192.168.201.25@tcp [ 2274.807553] Key type .llcrypt unregistered [ 2274.809484] Key type ._llcrypt unregistered [ 2286.137951] Key type ._llcrypt registered [ 2286.139808] Key type .llcrypt registered [ 2287.293567] Lustre: DEBUG MARKER: == conf-sanity test 7: manual umount, then cleanup ======= 09:41:14 (1766414474) [ 2289.807454] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2289.841381] alg: No test for adler32 (adler32-zlib) [ 2290.992146] Lustre: Lustre: Build Version: 2.17.0_RC3_1_g3717bff [ 2291.401070] LNet: Added LNI 192.168.201.25@tcp [8/256/0/180] [ 2293.111733] Key type lgssc registered [ 2294.496157] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2338.651832] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2342.372501] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2359.589562] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2366.501413] Lustre: Mounted lustre-client [ 2368.555499] LustreError: 61275:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a52c3664000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2368.586727] LustreError: 61275:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2368.716911] Lustre: Unmounted lustre-client [ 2394.067784] Key type lgssc unregistered [ 2394.287929] LNet: 61685:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2394.298767] LNetError: 61685:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2394.320646] LNet: Removed LNI 192.168.201.25@tcp [ 2394.858812] Key type .llcrypt unregistered [ 2394.860950] Key type ._llcrypt unregistered [ 2408.050121] Key type ._llcrypt registered [ 2408.051833] Key type .llcrypt registered [ 2409.237552] Lustre: DEBUG MARKER: == conf-sanity test 8: double mount setup ================ 09:43:16 (1766414596) [ 2411.949663] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2411.987643] alg: No test for adler32 (adler32-zlib) [ 2413.002180] Lustre: Lustre: Build Version: 2.17.0_RC3_1_g3717bff [ 2413.143390] LNet: Added LNI 192.168.201.25@tcp [8/256/0/180] [ 2414.791207] Key type lgssc registered [ 2415.701335] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2460.243395] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2464.475756] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2481.996340] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2483.715384] Lustre: Mounted lustre-client [ 2486.304678] Lustre: Mounted lustre-client [ 2489.394299] LustreError: 64834:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a52d0623800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2489.433590] LustreError: 64834:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 2489.537116] Lustre: Unmounted lustre-client [ 2491.646386] LustreError: 64854:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a52c5c87000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2491.682635] LustreError: 64854:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2491.691874] LustreError: 64854:0:(obd_class.h:479:obd_check_dev()) Skipped 5 previous similar messages [ 2491.780080] Lustre: Unmounted lustre-client [ 2517.759128] Key type lgssc unregistered [ 2518.028251] LNet: 65255:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2518.036170] LNetError: 65255:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2518.062478] LNet: Removed LNI 192.168.201.25@tcp [ 2518.745421] Key type .llcrypt unregistered [ 2518.750654] Key type ._llcrypt unregistered [ 2531.519480] Key type ._llcrypt registered [ 2531.523895] Key type .llcrypt registered [ 2532.867195] Lustre: DEBUG MARKER: == conf-sanity test 9: test ptldebug and subsystem for mkfs ========================================================== 09:45:20 (1766414720) [ 2535.462465] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2535.522126] alg: No test for adler32 (adler32-zlib) [ 2536.709891] Lustre: Lustre: Build Version: 2.17.0_RC3_1_g3717bff [ 2536.969142] LNet: Added LNI 192.168.201.25@tcp [8/256/0/180] [ 2538.783193] Key type lgssc registered [ 2540.236277] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2617.754968] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2629.322923] Lustre: DEBUG MARKER: == conf-sanity test 10a: find lctl param broken symlinks ========================================================== 09:46:57 (1766414817) [ 2653.133500] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2656.493292] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2672.240749] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2682.924471] Lustre: Mounted lustre-client [ 2688.572977] LustreError: 69502:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a52c84a9800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2688.588783] LustreError: 69502:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2688.647692] Lustre: Unmounted lustre-client [ 2712.793565] Key type lgssc unregistered [ 2713.035270] LNet: 69903:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2713.053060] LNetError: 69903:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2714.086774] LNet: Removed LNI 192.168.201.25@tcp [ 2714.646552] Key type .llcrypt unregistered [ 2714.647821] Key type ._llcrypt unregistered [ 2727.251630] Key type ._llcrypt registered [ 2727.253437] Key type .llcrypt registered [ 2728.777633] Lustre: DEBUG MARKER: == conf-sanity test 17: Verify failed mds_postsetup won't fail assertion (2936) (should return errs) ========================================================== 09:48:36 (1766414916) [ 2730.711324] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2730.806636] alg: No test for adler32 (adler32-zlib) [ 2731.814347] Lustre: Lustre: Build Version: 2.17.0_RC3_1_g3717bff [ 2731.974577] LNet: Added LNI 192.168.201.25@tcp [8/256/0/180] [ 2733.607361] Key type lgssc registered [ 2734.600409] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2775.657680] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2779.818858] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2794.790695] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2800.682903] Lustre: Mounted lustre-client [ 2804.015605] LustreError: 73010:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a52c8553000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2804.036699] LustreError: 73010:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2804.114372] Lustre: Unmounted lustre-client [ 2830.384176] Key type lgssc unregistered [ 2830.748406] LNet: 73412:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2830.757050] LNetError: 73412:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2831.784539] LNet: Removed LNI 192.168.201.25@tcp [ 2832.520813] Key type .llcrypt unregistered [ 2832.526308] Key type ._llcrypt unregistered [ 2843.622366] Key type ._llcrypt registered [ 2843.624035] Key type .llcrypt registered [ 2843.980197] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2844.006828] alg: No test for adler32 (adler32-zlib) [ 2845.025628] Lustre: Lustre: Build Version: 2.17.0_RC3_1_g3717bff [ 2845.252514] LNet: Added LNI 192.168.201.25@tcp [8/256/0/180] [ 2846.911813] Key type lgssc registered [ 2848.089899] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2923.122134] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3039.761951] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3043.369924] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3060.218312] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3095.555431] Lustre: DEBUG MARKER: == conf-sanity test 18: check mkfs creates large journals ========================================================== 09:54:43 (1766415283) [ 3098.598545] Lustre: DEBUG MARKER: use device /dev/mapper/mds1_flakey with MIN=2000000 [ 3176.916552] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3179.956885] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3198.585530] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3253.029731] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3255.889534] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3271.211918] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3281.937303] Lustre: Mounted lustre-client [ 3287.120259] Lustre: DEBUG MARKER: Success: mkfs creates large journals. Size: 78M [ 3288.656951] LustreError: 84393:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a52d1b5e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3288.687413] LustreError: 84393:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3288.737674] Lustre: Unmounted lustre-client [ 3312.455920] Key type lgssc unregistered [ 3312.692643] LNet: 84793:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3312.700345] LNetError: 84793:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3312.735332] LNet: Removed LNI 192.168.201.25@tcp [ 3313.383288] Key type .llcrypt unregistered [ 3313.389133] Key type ._llcrypt unregistered [ 3337.586132] Key type ._llcrypt registered [ 3337.596288] Key type .llcrypt registered [ 3338.030719] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3338.071536] alg: No test for adler32 (adler32-zlib) [ 3339.136128] Lustre: Lustre: Build Version: 2.17.0_RC3_1_g3717bff [ 3339.339945] LNet: Added LNI 192.168.201.25@tcp [8/256/0/180] [ 3340.991168] Key type lgssc registered [ 3341.778969] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3401.299160] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3404.475593] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3422.519545] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3456.592162] Lustre: DEBUG MARKER: == conf-sanity test 19a: start/stop MDS without OSTs ===== 10:00:44 (1766415644) [ 3481.966385] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3484.493350] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3501.839387] Lustre: DEBUG MARKER: == conf-sanity test 19b: start/stop OSTs without MDS ===== 10:01:29 (1766415689) [ 3562.808437] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3570.390695] Lustre: DEBUG MARKER: == conf-sanity test 20: remount ro,rw mounts work and doesn't break /etc/mtab ========================================================== 10:02:38 (1766415758) [ 3592.965708] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3595.688327] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3611.813261] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3623.479627] Lustre: Mounted lustre-client [ 3624.071957] Lustre: Remounted lustre-client read-only [ 3625.440558] LustreError: 93287:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a52d0623800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3625.453814] LustreError: 93287:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3625.496866] Lustre: Unmounted lustre-client [ 3653.723608] Lustre: DEBUG MARKER: == conf-sanity test 21a: start mds before ost, stop ost first ========================================================== 10:04:01 (1766415841) [ 3670.730580] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3672.666931] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3682.011791] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3713.954117] Lustre: DEBUG MARKER: == conf-sanity test 21b: start ost before mds, stop mds first ========================================================== 10:05:01 (1766415901) [ 3771.018296] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3788.124148] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3789.914499] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3821.811891] Lustre: DEBUG MARKER: == conf-sanity test 21c: start mds between two osts, stop mds last ========================================================== 10:06:49 (1766416009) [ 3877.946868] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3893.061720] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3895.050243] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3905.365245] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3954.651759] Lustre: DEBUG MARKER: == conf-sanity test 21d: start mgs then ost and then mds ========================================================== 10:09:02 (1766416142) [ 3955.496137] Lustre: DEBUG MARKER: SKIP: conf-sanity test_21d need separate mgs device [ 3956.313341] Lustre: DEBUG MARKER: SKIP: conf-sanity test_21e skipping excluded test 21e [ 3957.351181] Lustre: DEBUG MARKER: == conf-sanity test 22: start a client before osts (should return errs) ========================================================== 10:09:05 (1766416145) [ 3975.414580] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3977.358563] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3988.433554] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4001.510624] Lustre: Mounted lustre-client [ 4002.189700] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4012.455049] LustreError: 102996:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4012.460730] LustreError: 102996:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 4012.493264] Lustre: Unmounted lustre-client [ 4021.207693] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4021.430461] Lustre: Mounted lustre-client [ 4026.860948] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8a52c84ac800.ost_server_uuid 50 [ 4027.503629] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8a52c84ac800.ost_server_uuid in FULL state after 0 sec [ 4028.374601] LustreError: 104040:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a52c84ac800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4028.385543] LustreError: 104040:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4028.388788] LustreError: 104040:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 4028.421165] Lustre: Unmounted lustre-client [ 4062.627239] Key type lgssc unregistered [ 4062.758684] LNet: 104444:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4062.762625] LNetError: 104444:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4062.772067] LNet: Removed LNI 192.168.201.25@tcp [ 4063.071224] Key type .llcrypt unregistered [ 4063.073485] Key type ._llcrypt unregistered [ 4069.828117] Key type ._llcrypt registered [ 4069.829846] Key type .llcrypt registered [ 4070.476286] Lustre: DEBUG MARKER: == conf-sanity test 23a: interrupt client during recovery mount delay ========================================================== 10:10:58 (1766416258) [ 4071.517515] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4071.531254] alg: No test for adler32 (adler32-zlib) [ 4072.444441] Lustre: Lustre: Build Version: 2.17.0_RC3_1_g3717bff [ 4072.549814] LNet: Added LNI 192.168.201.25@tcp [8/256/0/180] [ 4074.167190] Key type lgssc registered [ 4074.770037] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4096.605406] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4098.461938] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4106.489129] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4112.898275] Lustre: Mounted lustre-client [ 4116.509990] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4126.764182] LustreError: 107550:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4126.766595] LustreError: MGC192.168.201.125@tcp: Connection to MGS (at 192.168.201.125@tcp) was lost; in progress operations using this service will fail [ 4126.790513] Lustre: Unmounted lustre-client [ 4133.432278] LustreError: lustre-MDT0000-mdc-ffff8a52c3663800: operation mds_connect to node 192.168.201.125@tcp failed: rc = -16 [ 4138.434364] LustreError: 107734:0:(lmv_obd.c:1435:lmv_statfs()) lustre-MDT0000-mdc-ffff8a52c3663800: can't stat MDS #0: rc = -16 [ 4138.458268] LustreError: 107734:0:(obd_class.h:479:obd_check_dev()) Device 3 not setup [ 4138.462226] LustreError: 107734:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 4138.473849] Lustre: Unmounted lustre-client [ 4138.481535] LustreError: 107734:0:(super25.c:199:lustre_fill_super()) llite: Unable to mount : rc = -16 [ 4157.889637] Key type lgssc unregistered [ 4158.026268] LNet: 108184:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4158.029595] LNetError: 108184:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4158.038807] LNet: Removed LNI 192.168.201.25@tcp [ 4158.351372] Key type .llcrypt unregistered [ 4158.353177] Key type ._llcrypt unregistered [ 4164.757147] Key type ._llcrypt registered [ 4164.758742] Key type .llcrypt registered [ 4165.477319] Lustre: DEBUG MARKER: == conf-sanity test 23b: Simulate -EINTR during mount ==== 10:12:33 (1766416353) [ 4166.414585] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4166.474858] alg: No test for adler32 (adler32-zlib) [ 4167.363618] Lustre: Lustre: Build Version: 2.17.0_RC3_1_g3717bff [ 4167.469674] LNet: Added LNI 192.168.201.25@tcp [8/256/0/180] [ 4169.063196] Key type lgssc registered [ 4169.608818] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4195.980412] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4197.889822] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4207.149198] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4209.491872] Lustre: Mounted lustre-client [ 4209.961259] LustreError: 111217:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a52d11d1000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4209.973388] LustreError: 111217:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4209.992881] Lustre: Unmounted lustre-client [ 4236.719915] Key type lgssc unregistered [ 4236.874712] LNet: 111619:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4236.880279] LNetError: 111619:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4236.889922] LNet: Removed LNI 192.168.201.25@tcp [ 4237.176309] Key type .llcrypt unregistered [ 4237.178060] Key type ._llcrypt unregistered [ 4243.394547] Key type ._llcrypt registered [ 4243.396130] Key type .llcrypt registered [ 4244.039913] Lustre: DEBUG MARKER: == conf-sanity test 24a: Multiple MDTs on a single node == 10:13:52 (1766416432) [ 4245.192862] Lustre: DEBUG MARKER: SKIP: conf-sanity test_24a mixed loopback and real device not working [ 4246.036806] Lustre: DEBUG MARKER: == conf-sanity test 24b: Multiple MGSs on a single node (should return err) ========================================================== 10:13:54 (1766416434) [ 4247.213789] Lustre: DEBUG MARKER: SKIP: conf-sanity test_24b mixed loopback and real device not working [ 4247.979506] Lustre: DEBUG MARKER: == conf-sanity test 25: Verify modules are referenced ==== 10:13:56 (1766416436) [ 4249.186614] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4249.202568] alg: No test for adler32 (adler32-zlib) [ 4250.132398] Lustre: Lustre: Build Version: 2.17.0_RC3_1_g3717bff [ 4250.250424] LNet: Added LNI 192.168.201.25@tcp [8/256/0/180] [ 4251.871222] Key type lgssc registered [ 4252.437333] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4276.394731] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4278.299078] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4287.243476] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4293.655271] Lustre: Mounted lustre-client [ 4294.538518] LustreError: 115309:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a52d07f9000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4294.554405] LustreError: 115309:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4294.590868] Lustre: Unmounted lustre-client [ 4305.642585] Key type lgssc unregistered [ 4305.780239] LNet: 115709:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4305.784125] LNetError: 115709:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4305.795930] LNet: Removed LNI 192.168.201.25@tcp [ 4306.083996] Key type .llcrypt unregistered [ 4306.086205] Key type ._llcrypt unregistered [ 4312.156617] Key type ._llcrypt registered [ 4312.158111] Key type .llcrypt registered [ 4312.745610] Lustre: DEBUG MARKER: == conf-sanity test 26: MDT startup failure cleans LOV (should return errs) ========================================================== 10:15:01 (1766416501) [ 4313.338632] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4313.354301] alg: No test for adler32 (adler32-zlib) [ 4314.233669] Lustre: Lustre: Build Version: 2.17.0_RC3_1_g3717bff [ 4314.351063] LNet: Added LNI 192.168.201.25@tcp [8/256/0/180] [ 4315.951328] Key type lgssc registered [ 4316.490921] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4341.775556] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4343.233877] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4354.474378] Key type lgssc unregistered [ 4354.601450] LNet: 118719:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4354.604594] LNetError: 118719:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4354.612410] LNet: Removed LNI 192.168.201.25@tcp [ 4354.864862] Key type .llcrypt unregistered [ 4354.867167] Key type ._llcrypt unregistered [ 4360.677154] Key type ._llcrypt registered [ 4360.678815] Key type .llcrypt registered [ 4361.246334] Lustre: DEBUG MARKER: == conf-sanity test 27a: Reacquire MGS lock if OST started first ========================================================== 10:15:49 (1766416549) [ 4365.024382] Key type .llcrypt unregistered [ 4365.025905] Key type ._llcrypt unregistered [ 4368.181300] Key type ._llcrypt registered [ 4368.182799] Key type .llcrypt registered [ 4368.388763] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4368.396869] alg: No test for adler32 (adler32-zlib) [ 4369.287501] Lustre: Lustre: Build Version: 2.17.0_RC3_1_g3717bff [ 4369.400174] LNet: Added LNI 192.168.201.25@tcp [8/256/0/180] [ 4370.999153] Key type lgssc registered [ 4371.459544] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4433.233169] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4444.730887] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4446.121429] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4467.893870] Key type lgssc unregistered [ 4468.019358] LNet: 122459:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4468.022792] LNetError: 122459:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4468.035811] LNet: Removed LNI 192.168.201.25@tcp [ 4468.271628] Key type .llcrypt unregistered [ 4468.273087] Key type ._llcrypt unregistered [ 4473.583583] Key type ._llcrypt registered [ 4473.584706] Key type .llcrypt registered [ 4474.072265] Lustre: DEBUG MARKER: == conf-sanity test 27b: Reacquire MGS lock after failover ========================================================== 10:17:42 (1766416662) [ 4474.904450] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4474.980696] alg: No test for adler32 (adler32-zlib) [ 4475.844694] Lustre: Lustre: Build Version: 2.17.0_RC3_1_g3717bff [ 4475.933386] LNet: Added LNI 192.168.201.25@tcp [8/256/0/180] [ 4477.519166] Key type lgssc registered [ 4477.982646] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4496.284063] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4497.592595] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4504.129699] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4514.805093] Lustre: Mounted lustre-client [ 4519.907137] Lustre: lustre-MDT0000-mdc-ffff8a52c2b3a800: Connection to lustre-MDT0000 (at 192.168.201.125@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4530.145628] LustreError: MGC192.168.201.125@tcp: Connection to MGS (at 192.168.201.125@tcp) was lost; in progress operations using this service will fail [ 4530.151885] Lustre: Evicted from MGS (at 192.168.201.125@tcp) after server handle changed from 0xea9edf27938fb365 to 0xea9edf27938fb58e [ 4530.157320] Lustre: MGC192.168.201.125@tcp: Connection restored to 192.168.201.125@tcp (at 192.168.201.125@tcp) [ 4534.965772] Lustre: lustre-MDT0000-mdc-ffff8a52c2b3a800: Connection restored to 192.168.201.125@tcp (at 192.168.201.125@tcp) [ 4546.538219] LustreError: 125971:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a52c2b3a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4546.546564] LustreError: 125971:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4546.569400] Lustre: Unmounted lustre-client [ 4564.273352] Key type lgssc unregistered [ 4564.395640] LNet: 126372:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4564.399248] LNetError: 126372:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4564.410335] LNet: Removed LNI 192.168.201.25@tcp [ 4564.678536] Key type .llcrypt unregistered [ 4564.680381] Key type ._llcrypt unregistered [ 4570.419706] Key type ._llcrypt registered [ 4570.421167] Key type .llcrypt registered [ 4570.982890] Lustre: DEBUG MARKER: == conf-sanity test 28A: permanent parameter setting ===== 10:19:19 (1766416759) [ 4571.952873] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4571.996711] alg: No test for adler32 (adler32-zlib) [ 4572.909852] Lustre: Lustre: Build Version: 2.17.0_RC3_1_g3717bff [ 4573.021476] LNet: Added LNI 192.168.201.25@tcp [8/256/0/180] [ 4574.615197] Key type lgssc registered [ 4575.074732] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4596.253190] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4597.745592] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4606.633113] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4613.108622] Lustre: Mounted lustre-client [ 4620.583943] LustreError: 129521:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a52c84ae800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4620.591355] LustreError: 129521:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4620.616124] Lustre: Unmounted lustre-client [ 4620.758487] Lustre: Mounted lustre-client [ 4629.878729] LustreError: 129671:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a52d07f9000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4629.887109] LustreError: 129671:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4629.890281] LustreError: 129671:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 4629.917458] Lustre: Unmounted lustre-client [ 4648.398246] Key type lgssc unregistered [ 4648.528520] LNet: 130074:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4648.532057] LNetError: 130074:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4648.542846] LNet: Removed LNI 192.168.201.25@tcp [ 4648.792105] Key type .llcrypt unregistered [ 4648.794783] Key type ._llcrypt unregistered [ 4655.192765] Key type ._llcrypt registered [ 4655.194669] Key type .llcrypt registered [ 4655.702822] Lustre: DEBUG MARKER: == conf-sanity test 28a: set symlink parameters permanently with lctl ========================================================== 10:20:44 (1766416844) [ 4656.699618] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4656.738692] alg: No test for adler32 (adler32-zlib) [ 4657.622806] Lustre: Lustre: Build Version: 2.17.0_RC3_1_g3717bff [ 4657.719179] LNet: Added LNI 192.168.201.25@tcp [8/256/0/180] [ 4659.319177] Key type lgssc registered [ 4659.784743] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4681.486254] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4682.920150] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4690.599255] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4701.208644] Lustre: Mounted lustre-client [ 4740.739873] LustreError: 133489:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a52c874c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4740.746380] LustreError: 133489:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4740.771697] Lustre: Unmounted lustre-client [ 4766.786512] Key type lgssc unregistered [ 4766.913735] LNet: 133892:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4766.917466] LNetError: 133892:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4766.927883] LNet: Removed LNI 192.168.201.25@tcp [ 4767.186332] Key type .llcrypt unregistered [ 4767.188069] Key type ._llcrypt unregistered [ 4772.864155] Key type ._llcrypt registered [ 4772.865424] Key type .llcrypt registered [ 4773.399787] Lustre: DEBUG MARKER: == conf-sanity test 28b: verify client-side parameters are set upon mount ========================================================== 10:22:41 (1766416961) [ 4774.277951] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4774.335577] alg: No test for adler32 (adler32-zlib) [ 4775.209250] Lustre: Lustre: Build Version: 2.17.0_RC3_1_g3717bff [ 4775.304869] LNet: Added LNI 192.168.201.25@tcp [8/256/0/180] [ 4776.903213] Key type lgssc registered [ 4777.328870] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4795.752020] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4797.060304] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4803.906221] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4814.329375] Lustre: Mounted lustre-client [ 4814.739377] LustreError: 136947:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a52d0c58000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4814.753428] LustreError: 136947:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4814.778126] Lustre: Unmounted lustre-client [ 4814.932365] Lustre: Mounted lustre-client [ 4817.253994] LustreError: 137444:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a52d0620000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4817.262350] LustreError: 137444:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4817.265548] LustreError: 137444:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 4817.291629] Lustre: Unmounted lustre-client [ 4826.801853] Key type lgssc unregistered [ 4826.936461] LNet: 137844:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4826.939180] LNetError: 137844:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4826.948703] LNet: Removed LNI 192.168.201.25@tcp [ 4827.186163] Key type .llcrypt unregistered [ 4827.187615] Key type ._llcrypt unregistered [ 4830.639259] Key type ._llcrypt registered [ 4830.640682] Key type .llcrypt registered [ 4831.116926] Lustre: DEBUG MARKER: == conf-sanity test 28c: verify filesystem parameters are set upon mount ========================================================== 10:23:39 (1766417019) [ 4831.911939] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4831.960141] alg: No test for adler32 (adler32-zlib) [ 4832.812980] Lustre: Lustre: Build Version: 2.17.0_RC3_1_g3717bff [ 4832.896919] LNet: Added LNI 192.168.201.25@tcp [8/256/0/180] [ 4834.479105] Key type lgssc registered [ 4834.832347] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4854.204933] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4855.793750] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4863.628931] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4874.237502] Lustre: Mounted lustre-client [ 4874.738251] LustreError: 140478:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a52d11d2800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4874.749108] LustreError: 140478:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4874.776196] Lustre: Unmounted lustre-client [ 4874.943467] Lustre: Mounted lustre-client [ 4877.503073] LustreError: 140971:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a52d0c5c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4877.511630] LustreError: 140971:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4877.514136] LustreError: 140971:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 4877.547503] Lustre: Unmounted lustre-client [ 4895.842679] Key type lgssc unregistered [ 4895.968633] LNet: 141373:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4895.972448] LNetError: 141373:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4895.982555] LNet: Removed LNI 192.168.201.25@tcp [ 4896.241467] Key type .llcrypt unregistered [ 4896.243138] Key type ._llcrypt unregistered [ 4900.228614] Key type ._llcrypt registered [ 4900.230323] Key type .llcrypt registered [ 4900.782467] Lustre: DEBUG MARKER: == conf-sanity test 29: permanently remove an OST ======== 10:24:49 (1766417089) [ 4901.781076] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4901.800686] alg: No test for adler32 (adler32-zlib) [ 4902.675580] Lustre: Lustre: Build Version: 2.17.0_RC3_1_g3717bff [ 4902.768248] LNet: Added LNI 192.168.201.25@tcp [8/256/0/180] [ 4904.359238] Key type lgssc registered [ 4904.850938] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4925.830767] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4927.324981] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4934.950601] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4945.399881] Lustre: Mounted lustre-client [ 4954.082966] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 4954.745387] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 4970.992124] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 4978.438752] LustreError: 144713:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a52c8553000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4978.445107] LustreError: 144713:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 4978.472322] Lustre: Unmounted lustre-client [ 4978.589449] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 4978.631809] Lustre: Mounted lustre-client [ 4987.860948] LustreError: 144873:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a52d0c5e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4987.871522] LustreError: 144873:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 4987.874465] LustreError: 144873:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 4987.900219] Lustre: Unmounted lustre-client [ 5022.029500] Key type lgssc unregistered [ 5022.157097] LNet: 145321:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5022.160830] LNetError: 145321:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5022.169708] LNet: Removed LNI 192.168.201.25@tcp [ 5022.450438] Key type .llcrypt unregistered [ 5022.451703] Key type ._llcrypt unregistered [ 5039.185940] Key type ._llcrypt registered [ 5039.188174] Key type .llcrypt registered [ 5039.374403] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5039.382859] alg: No test for adler32 (adler32-zlib) [ 5040.257907] Lustre: Lustre: Build Version: 2.17.0_RC3_1_g3717bff [ 5040.351051] LNet: Added LNI 192.168.201.25@tcp [8/256/0/180] [ 5041.943153] Key type lgssc registered [ 5042.375127] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5077.263984] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5078.792162] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5086.982416] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5108.752699] Lustre: DEBUG MARKER: == conf-sanity test 30a: Big config llog and permanent parameter deletion ========================================================== 10:28:17 (1766417297) [ 5119.094378] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5120.277410] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5126.628769] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5137.391855] Lustre: Mounted lustre-client [ 5290.776469] LustreError: 152865:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a52d1022000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5290.782150] LustreError: 152865:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5290.805154] Lustre: Unmounted lustre-client [ 5290.953222] Lustre: Mounted lustre-client [ 5291.695594] LustreError: 152951:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a52c8555800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5291.703792] LustreError: 152951:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5291.706297] LustreError: 152951:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 5291.741349] Lustre: Unmounted lustre-client [ 5309.541524] Key type lgssc unregistered [ 5309.653596] LNet: 153427:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5309.656375] LNetError: 153427:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5309.665488] LNet: Removed LNI 192.168.201.25@tcp [ 5309.881973] Key type .llcrypt unregistered [ 5309.883196] Key type ._llcrypt unregistered [ 5314.832700] Key type ._llcrypt registered [ 5314.834091] Key type .llcrypt registered [ 5315.271545] Lustre: DEBUG MARKER: == conf-sanity test 30b: Remove failover nids ============ 10:31:43 (1766417503) [ 5316.067741] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5316.074776] alg: No test for adler32 (adler32-zlib) [ 5316.911325] Lustre: Lustre: Build Version: 2.17.0_RC3_1_g3717bff [ 5316.985362] LNet: Added LNI 192.168.201.25@tcp [8/256/0/180] [ 5318.559156] Key type lgssc registered [ 5319.003847] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5336.039878] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5337.191375] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5342.849562] Lustre: DEBUG MARKER: oleg125-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5358.575820] Lustre: Mounted lustre-client [ 5361.181983] LustreError: 156543:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a52d0c5b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5361.190106] LustreError: 156543:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5361.210606] Lustre: Unmounted lustre-client [ 5361.338829] Lustre: Mounted lustre-client [ 5361.718969] LustreError: 156617:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a52c8d1d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5361.727759] LustreError: 156617:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5361.729798] LustreError: 156617:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 5361.753735] Lustre: Unmounted lustre-client [ 5379.040318] Key type lgssc unregistered [ 5379.157620] LNet: 157020:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5379.159949] LNetError: 157020:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5379.169582] LNet: Removed LNI 192.168.201.25@tcp [ 5379.387274] Key type .llcrypt unregistered [ 5379.388451] Key type ._llcrypt unregistered [ 5384.377277] Key type ._llcrypt registered [ 5384.378336] Key type .llcrypt registered [ 5384.829757] Lustre: DEBUG MARKER: == conf-sanity test 31: Connect to non-existent node (shouldn't crash) ========================================================== 10:32:53 (1766417573) [ 5385.280786] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5385.313294] alg: No test for adler32 (adler32-zlib) [ 5386.183147] Lustre: Lustre: Build Version: 2.17.0_RC3_1_g3717bff [ 5386.286660] LNet: Added LNI 192.168.201.25@tcp [8/256/0/180] [ 5387.871134] Key type lgssc registered [ 5388.236342] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5400.415262] LustreError: 158706:0:(mgc_request.c:233:do_config_log_add()) MGC4.3.2.1@tcp: failed processing log, type 1: rc = -110 [ 5405.663297] LustreError: 158715:0:(mgc_request.c:614:do_requeue()) failed processing log: -110 [ 5452.831356] LNet: 1 local NIs in recovery (showing 1): 192.168.201.25@tcp [ 5526.559141] LNet: 1 local NIs in recovery (showing 1): 192.168.201.25@tcp [ 5558.239300] 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 [ 5558.244491] Lustre: Unmounted lustre-client [ 5558.245841] LustreError: 158706:0:(obd_class.h:479:obd_check_dev()) Device 0 not setup [ 5558.250390] LustreError: 158706:0:(super25.c:199:lustre_fill_super()) llite: Unable to mount : rc = -110 [ 5562.107689] Key type lgssc unregistered [ 5580.639276] LNet: 159120:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5580.642162] LNetError: 159120:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5658.469363] LNet: Removed LNI 192.168.201.25@tcp [ 5658.713647] Key type .llcrypt unregistered [ 5658.714945] Key type ._llcrypt unregistered [ 5671.239955] Key type ._llcrypt registered [ 5671.242234] Key type .llcrypt registered [ 5671.453226] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5671.458878] alg: No test for adler32 (adler32-zlib) [ 5672.340591] Lustre: Lustre: Build Version: 2.17.0_RC3_1_g3717bff [ 5672.430843] LNet: Added LNI 192.168.201.25@tcp [8/256/0/180] [ 5674.023116] Key type lgssc registered [ 5674.487300] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5691.427438] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 5436 sec ========== 10:37:59 (1766417879) [ 5692.026496] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 10:38:00 (1766417880) === [ 5693.119040] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 10:38:01 (1766417881) === [ 5704.869290] Key type lgssc unregistered [ 5704.984345] LNet: 163341:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5704.986940] LNetError: 163341:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5704.995438] LNet: Removed LNI 192.168.201.25@tcp [ 5705.233664] Key type .llcrypt unregistered [ 5705.235708] Key type ._llcrypt unregistered