[ 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 369102554 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, 524588K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001011] APIC: Switch to symmetric I/O mode setup [ 0.002277] x2apic enabled [ 0.003005] Switched APIC routing to physical x2apic. [ 0.004008] kvm-guest: setup PV IPIs [ 0.007506] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.008000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.008015] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009006] pid_max: default: 32768 minimum: 301 [ 0.010126] LSM: Security Framework initializing [ 0.011032] Yama: becoming mindful. [ 0.012021] SELinux: Initializing. [ 0.013052] *** VALIDATE selinux *** [ 0.021539] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.026360] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.028134] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029075] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.030091] *** VALIDATE tmpfs *** [ 0.032152] *** VALIDATE proc *** [ 0.033198] *** VALIDATE cgroup *** [ 0.034005] *** VALIDATE cgroup2 *** [ 0.036176] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.037117] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.038003] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.039022] Spectre V2 : User space: Vulnerable [ 0.040004] Speculative Store Bypass: Vulnerable [ 0.043118] debug: unmapping init [mem 0xffffffffb4259000-0xffffffffb4260fff] [ 0.045165] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.046580] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.047011] ... version: 2 [ 0.048007] ... bit width: 48 [ 0.049006] ... generic registers: 4 [ 0.050005] ... value mask: 0000ffffffffffff [ 0.051020] ... max period: 00007fffffffffff [ 0.052007] ... fixed-purpose events: 3 [ 0.053007] ... event mask: 000000070000000f [ 0.054277] rcu: Hierarchical SRCU implementation. [ 0.056379] smp: Bringing up secondary CPUs ... [ 0.057490] x86: Booting SMP configuration: [ 0.058012] .... node #0, CPUs: #1 #2 #3 [ 0.060545] smp: Brought up 1 node, 4 CPUs [ 0.062007] smpboot: Max logical packages: 1 [ 0.063007] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.218318] node 0 deferred pages initialised in 154ms [ 0.221268] devtmpfs: initialized [ 0.222292] x86/mm: Memory block size: 128MB [ 0.225089] gcov: version magic: 0x41383552 [ 0.227347] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.228098] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.229332] pinctrl core: initialized pinctrl subsystem [ 0.230202] [ 0.230754] ************************************************************* [ 0.231015] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.232008] ** ** [ 0.233007] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.234009] ** ** [ 0.235011] ** This means that this kernel is built to expose internal ** [ 0.236008] ** IOMMU data structures, which may compromise security on ** [ 0.237011] ** your system. ** [ 0.238009] ** ** [ 0.239010] ** If you see this message and you are not debugging the ** [ 0.240008] ** kernel, report this immediately to your vendor! ** [ 0.241007] ** ** [ 0.242008] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.243010] ************************************************************* [ 0.244859] NET: Registered protocol family 16 [ 0.245515] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.246062] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.247068] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.248649] cpuidle: using governor menu [ 0.250885] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.253551] PCI: Using configuration type 1 for base access [ 0.254146] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.261127] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.262032] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.264038] cryptd: max_cpu_qlen set to 1000 [ 0.266283] ACPI: Added _OSI(Module Device) [ 0.268015] ACPI: Added _OSI(Processor Device) [ 0.269007] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.271009] ACPI: Added _OSI(Processor Aggregator Device) [ 0.275000] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.280598] ACPI: Interpreter enabled [ 0.281064] ACPI: PM: (supports S0 S3 S4 S5) [ 0.282009] ACPI: Using IOAPIC for interrupt routing [ 0.283103] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.284437] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.294922] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.296021] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.297009] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.300090] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.305071] acpiphp: Slot [2] registered [ 0.305944] acpiphp: Slot [5] registered [ 0.306095] acpiphp: Slot [6] registered [ 0.307078] acpiphp: Slot [3] registered [ 0.307892] acpiphp: Slot [4] registered [ 0.309072] acpiphp: Slot [7] registered [ 0.309997] acpiphp: Slot [8] registered [ 0.311056] acpiphp: Slot [9] registered [ 0.311985] acpiphp: Slot [10] registered [ 0.313057] acpiphp: Slot [11] registered [ 0.313938] acpiphp: Slot [12] registered [ 0.314086] acpiphp: Slot [13] registered [ 0.315017] acpiphp: Slot [14] registered [ 0.316067] acpiphp: Slot [15] registered [ 0.318091] acpiphp: Slot [16] registered [ 0.320068] acpiphp: Slot [17] registered [ 0.321230] acpiphp: Slot [18] registered [ 0.323093] acpiphp: Slot [19] registered [ 0.324064] acpiphp: Slot [20] registered [ 0.326066] acpiphp: Slot [21] registered [ 0.327095] acpiphp: Slot [22] registered [ 0.329061] acpiphp: Slot [23] registered [ 0.330062] acpiphp: Slot [24] registered [ 0.331065] acpiphp: Slot [25] registered [ 0.333061] acpiphp: Slot [26] registered [ 0.334089] acpiphp: Slot [27] registered [ 0.335096] acpiphp: Slot [28] registered [ 0.337062] acpiphp: Slot [29] registered [ 0.338061] acpiphp: Slot [30] registered [ 0.339080] acpiphp: Slot [31] registered [ 0.341074] PCI host bridge to bus 0000:00 [ 0.342014] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.344012] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.347013] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.349012] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.352012] pci_bus 0000:00: root bus resource [mem 0x380000000000-0x38007fffffff window] [ 0.354013] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.356219] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.359075] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.362081] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.368014] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.373058] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.376015] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.378010] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.380025] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.383846] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.385935] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.389040] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.391853] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.396012] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.409023] pci 0000:00:02.0: reg 0x20: [mem 0x380000000000-0x380000003fff 64bit pref] [ 0.415014] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.421086] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.428039] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.433017] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.445020] pci 0000:00:05.0: reg 0x20: [mem 0x380000004000-0x380000007fff 64bit pref] [ 0.460085] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.467053] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.473025] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.491027] pci 0000:00:06.0: reg 0x20: [mem 0x380000008000-0x38000000bfff 64bit pref] [ 0.501965] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.504419] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.507430] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.510394] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.513181] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.516224] iommu: Default domain type: Passthrough [ 0.517494] SCSI subsystem initialized [ 0.519123] ACPI: bus type USB registered [ 0.521088] usbcore: registered new interface driver usbfs [ 0.522047] usbcore: registered new interface driver hub [ 0.523029] usbcore: registered new device driver usb [ 0.524113] pps_core: LinuxPPS API ver. 1 registered [ 0.525008] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.527069] PTP clock support registered [ 0.529122] EDAC MC: Ver: 3.0.0 [ 0.530330] PCI: Using ACPI for IRQ routing [ 0.532829] NetLabel: Initializing [ 0.534008] NetLabel: domain hash size = 128 [ 0.535020] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.538103] NetLabel: unlabeled traffic allowed by default [ 0.540136] vgaarb: loaded [ 0.541348] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.543016] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.548402] clocksource: Switched to clocksource kvm-clock [ 0.657481] VFS: Disk quotas dquot_6.6.0 [ 0.659116] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.661565] *** VALIDATE ramfs *** [ 0.662919] *** VALIDATE hugetlbfs *** [ 0.664682] pnp: PnP ACPI init [ 0.666954] pnp: PnP ACPI: found 6 devices [ 0.684879] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.688355] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.690366] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.692082] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.694047] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.695797] pci_bus 0000:00: resource 8 [mem 0x380000000000-0x38007fffffff window] [ 0.697883] NET: Registered protocol family 2 [ 0.699919] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.703646] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.706314] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.710702] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.713670] TCP: Hash tables configured (established 65536 bind 65536) [ 0.716422] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.719062] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.721301] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.724049] NET: Registered protocol family 1 [ 0.727102] RPC: Registered named UNIX socket transport module. [ 0.729312] RPC: Registered udp transport module. [ 0.731082] RPC: Registered tcp transport module. [ 0.732884] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.735039] NET: Registered protocol family 44 [ 0.736501] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.738086] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.740097] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.741497] PCI: CLS 0 bytes, default 64 [ 0.742563] Unpacking initramfs... [ 2.166722] debug: unmapping init [mem 0xffff91db3cc64000-0xffff91db3ffcffff] [ 2.174185] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.176581] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.183254] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.699690] Initialise system trusted keyrings [ 2.701174] Key type blacklist registered [ 2.702821] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.711286] zbud: loaded [ 2.714268] *** VALIDATE nfs *** [ 2.715609] *** VALIDATE nfs4 *** [ 2.717863] pstore: using deflate compression [ 2.722252] Platform Keyring initialized [ 2.880557] NET: Registered protocol family 38 [ 2.883046] Key type asymmetric registered [ 2.884461] Asymmetric key parser 'x509' registered [ 2.886332] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.889857] io scheduler mq-deadline registered [ 2.892934] io scheduler kyber registered [ 2.897706] io scheduler bfq registered [ 2.899703] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.903916] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.907494] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.910780] ACPI: Power Button [PWRF] [ 3.038676] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.131526] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.244537] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.274986] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.308667] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.313680] Non-volatile memory driver v1.3 [ 3.315597] Linux agpgart interface v0.103 [ 3.374644] virtio_blk virtio1: [vda] 133912 512-byte logical blocks (68.6 MB/65.4 MiB) [ 3.378686] vda: detected capacity change from 0 to 68562944 [ 3.405355] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.408789] vdb: detected capacity change from 0 to 1073741824 [ 3.418848] libphy: Fixed MDIO Bus: probed [ 3.426609] usbcore: registered new interface driver usbserial_generic [ 3.429016] usbserial: USB Serial support registered for generic [ 3.431102] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.434674] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.436553] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.439337] mousedev: PS/2 mouse device common for all mice [ 3.442416] rtc_cmos 00:05: RTC can wake from S4 [ 3.447316] rtc_cmos 00:05: registered as rtc0 [ 3.447811] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.450315] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.459355] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.460306] intel_pstate: CPU model not supported [ 3.473852] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.477927] hid: raw HID events driver (C) Jiri Kosina [ 3.480322] usbcore: registered new interface driver usbhid [ 3.482677] usbhid: USB HID core driver [ 3.484263] drop_monitor: Initializing network drop monitor service [ 3.486896] Initializing XFRM netlink socket [ 3.489031] NET: Registered protocol family 10 [ 3.492846] Segment Routing with IPv6 [ 3.494450] NET: Registered protocol family 17 [ 3.497046] mpls_gso: MPLS GSO support [ 3.504333] RAS: Correctable Errors collector initialized. [ 3.505956] AVX version of gcm_enc/dec engaged. [ 3.507776] AES CTR mode by8 optimization enabled [ 3.611560] sched_clock: Marking stable (3611481017, 0)->(4334164105, -722683088) [ 3.615311] registered taskstats version 1 [ 3.617318] Loading compiled-in X.509 certificates [ 3.619422] zswap: loaded using pool lzo/zbud [ 3.650771] Key type big_key registered [ 3.674470] Key type encrypted registered [ 3.678038] ima: No TPM chip found, activating TPM-bypass! [ 3.684235] ima: Allocated hash algorithm: sha1 [ 3.685831] ima: No architecture policies found [ 3.687764] evm: Initialising EVM extended attributes: [ 3.693975] evm: security.selinux [ 3.695263] evm: security.ima [ 3.696339] evm: security.capability [ 3.697504] evm: HMAC attrs: 0x1 [ 3.700558] rtc_cmos 00:05: setting system clock to 2025-10-24 05:19:24 UTC (1761283164) [ 3.710933] debug: unmapping init [mem 0xffffffffb5203000-0xffffffffb53fffff] [ 3.714949] debug: unmapping init [mem 0xffffffffb3f82000-0xffffffffb4258fff] [ 3.728655] Write protecting the kernel read-only data: 28672k [ 3.735714] debug: unmapping init [mem 0xffffffffb2603000-0xffffffffb27fffff] [ 3.739693] debug: unmapping init [mem 0xffffffffb2f14000-0xffffffffb2ffffff] [ 3.777723] 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.794337] systemd[1]: Detected virtualization kvm. [ 3.798390] systemd[1]: Detected architecture x86-64. [ 3.801066] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.841054] systemd[1]: No hostname configured. [ 3.842393] systemd[1]: Set hostname to . [ 3.844174] random: systemd: uninitialized urandom read (16 bytes read) [ 3.846482] systemd[1]: Initializing machine ID from random generator. [ 3.910538] random: ln: uninitialized urandom read (6 bytes read) [ 4.023861] random: systemd: uninitialized urandom read (16 bytes read) [ 4.028454] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ 4.033292] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ 4.038704] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... [ OK ] Listening on Journal Socket (/dev/log). Starting Apply Kernel Variables... [ OK ] Started Memstrack Anylazing Service. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Initrd Root Device. [ OK ] Reached target Sockets. [ OK ] Reached target Timers. Starting Setup Virtual Console... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Slices. Starting Journal Service... [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Apply Kernel Variables. [ OK ] Started Setup Virtual Console. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 5.036758] device-mapper: uevent: version 1.0.3 [ 5.040627] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting dracut initqueue hook... [ 6.329234] virtio_net virtio0 ens2: renamed from eth0 [ 6.376900] scsi host0: ata_piix [ 6.428516] scsi host1: ata_piix [ 6.430100] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 6.448751] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 11.263484] random: crng init done [ 11.265200] random: 7 urandom warning(s) missed due to ratelimiting [ 13.434672] dracut-initqueue[586]: RTNETLINK answers: File exists [ 14.822943] hrtimer: interrupt took 13936068 ns Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 17.357755] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Timers. [ 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 target Swap. [ 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 Apply Kernel Variables. [ OK ] Stopped Create Volatile Files and Directories. [ 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... [ 20.156089] printk: systemd: 26 output lines suppressed due to ratelimiting [ 20.669066] SELinux: Disabled at runtime. [ 20.757070] 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) [ 20.780912] systemd[1]: Detected virtualization kvm. [ 20.785019] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 21.750412] systemd[1]: initrd-switch-root.service: Succeeded. [ 21.758332] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 21.769548] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 21.776970] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 21.782445] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 21.794695] systemd[1]: Starting Journal Service... Starting Journal Service... [ 21.812793] systemd[1]: proc-sys-fs-binfmt_misc.automount: Refusing to start, unit to trigger not loaded. [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 ] Stopped target Switch Root. [ OK ] Listening on udev Kernel Socket. Starting Remount Root and Kernel File Systems... Mounting POSIX Message Queue File System... [ OK ] Listening on udev Control Socket. [ OK ] Listening on Process Core Dump Socket. [ OK ] Reached target rpc_pipefs.target. Starting Apply Kernel Variables... [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Stopped target Initrd Root File System. [ OK ] Stopped target Initrd File Systems. Mounting Huge Pages File System... Starting udev Coldplug all Devices... Activating swap /dev/disk/by-label/SWAP... [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. Mounting Kernel Debug File System... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Listening on initctl Compatibility Named Pipe. [ 22.123266] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Started Journal Service. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted Huge Pages 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 Configure read-only root support... Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started udev Coldplug all Devices. [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... [ OK ] Mounted /mnt. [ 22.850310] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 23.534152] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 23.549579] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 24.044708] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 24.100422] EDAC sbridge: Ver: 1.1.2 [ 27.844938] Key type dns_resolver registered [ 28.576946] NFS: Registering the id_resolver key type [ 28.579121] Key type id_resolver registered [ 28.580623] Key type id_legacy registered [* ] A start job is running for Configur…-only root support (7s / no limit) [** ] A start job is running for Configur…-only root support (7s / no limit) [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting Update UTMP about System Boot/Shutdown... Starting RPC Bind... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Started dnf makecache --timer. [ OK ] Reached target Basic System. Starting Login Service... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Started irqbalance daemon. Starting Restore /run/initramfs on shutdown... [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Timers. [ OK ] Reached target sshd-keygen.target. [ OK ] Started Login Service. [ OK ] Started Restore /run/initramfs on shutdown. [ 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 GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started OpenSSH server daemon. [ OK ] Started Permit User Sessions. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Notify NFS peers of a restart... Starting System Logging Service... Starting Crash recovery kernel arming... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg149-client login: [ 62.179720] libcfs: loading out-of-tree module taints kernel. [ 62.194857] Key type ._llcrypt registered [ 62.196571] Key type .llcrypt registered [ 62.484281] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 62.489264] alg: No test for adler32 (adler32-zlib) [ 63.450541] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 63.715279] LNet: Added LNI 192.168.201.49@tcp [8/256/0/180] [ 65.320175] Key type lgssc registered [ 65.909955] Lustre: Echo OBD driver; http://www.lustre.org/ [ 127.184932] Lustre: Mounted lustre-client [ 129.536679] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 138.957197] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing check_logdir /tmp/testlogs/ [ 140.569312] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing yml_node [ 142.269095] Lustre: DEBUG MARKER: Client: 2.16.59.47 [ 143.233833] Lustre: DEBUG MARKER: MDS: 2.16.59.47 [ 144.217201] Lustre: DEBUG MARKER: OSS: 2.16.59.47 [ 144.854088] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Fri Oct 24 01:21:45 EDT 2025 [ 151.259385] Lustre: DEBUG MARKER: excepting tests: 21e 32b 32c 32newtarball [ 151.779635] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 152.329930] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 162.600422] LustreError: 8957:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 162.614107] Lustre: Unmounted lustre-client [ 236.421768] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 237.751552] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 245.191264] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 275.620677] Lustre: DEBUG MARKER: == conf-sanity test 0: single mount setup ================ 01:23:55 (1761283435) [ 285.726873] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 287.039751] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 293.243469] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 303.617838] Lustre: Mounted lustre-client [ 305.457313] LustreError: 14031:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff91db995a6000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 305.467113] LustreError: 14031:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 305.470248] LustreError: 14031:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 305.501165] Lustre: Unmounted lustre-client [ 331.323132] Key type lgssc unregistered [ 331.451552] LNet: 14434:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 331.454711] LNetError: 14434:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 331.463314] LNet: Removed LNI 192.168.201.49@tcp [ 331.742185] Key type .llcrypt unregistered [ 331.743763] Key type ._llcrypt unregistered [ 336.950082] Key type ._llcrypt registered [ 336.951758] Key type .llcrypt registered [ 337.436849] Lustre: DEBUG MARKER: == conf-sanity test 1: start up ost twice (should return errors) ========================================================== 01:24:57 (1761283497) [ 338.147525] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 338.220197] alg: No test for adler32 (adler32-zlib) [ 339.069522] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 339.157066] LNet: Added LNI 192.168.201.49@tcp [8/256/0/180] [ 340.736243] Key type lgssc registered [ 341.117886] Lustre: Echo OBD driver; http://www.lustre.org/ [ 358.375144] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 359.680485] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 366.088517] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 379.885631] Lustre: Mounted lustre-client [ 380.262078] LustreError: 17607:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff91db912a0000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 380.269681] LustreError: 17607:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 380.290654] Lustre: Unmounted lustre-client [ 389.531838] Key type lgssc unregistered [ 389.665623] LNet: 18007:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 389.668986] LNetError: 18007:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 389.679805] LNet: Removed LNI 192.168.201.49@tcp [ 389.943883] Key type .llcrypt unregistered [ 389.945597] Key type ._llcrypt unregistered [ 394.698939] Key type ._llcrypt registered [ 394.700610] Key type .llcrypt registered [ 395.118763] Lustre: DEBUG MARKER: == conf-sanity test 2: start up mds twice (should return err) ========================================================== 01:25:55 (1761283555) [ 395.850923] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 395.910166] alg: No test for adler32 (adler32-zlib) [ 396.766820] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 396.864651] LNet: Added LNI 192.168.201.49@tcp [8/256/0/180] [ 398.464300] Key type lgssc registered [ 398.864545] Lustre: Echo OBD driver; http://www.lustre.org/ [ 416.878967] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 418.089347] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 426.852984] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 437.236323] Lustre: Mounted lustre-client [ 437.707489] LustreError: 21160:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff91db84c46800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 437.715578] LustreError: 21160:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 437.741045] Lustre: Unmounted lustre-client [ 447.395750] Key type lgssc unregistered [ 447.521763] LNet: 21560:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 447.525353] LNetError: 21560:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 447.535209] LNet: Removed LNI 192.168.201.49@tcp [ 447.779574] Key type .llcrypt unregistered [ 447.780675] Key type ._llcrypt unregistered [ 452.855444] Key type ._llcrypt registered [ 452.856569] Key type .llcrypt registered [ 453.314259] Lustre: DEBUG MARKER: == conf-sanity test 3: mount client twice (should return err) ========================================================== 01:26:53 (1761283613) [ 454.092628] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 454.102509] alg: No test for adler32 (adler32-zlib) [ 454.963369] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 455.048701] LNet: Added LNI 192.168.201.49@tcp [8/256/0/180] [ 456.632170] Key type lgssc registered [ 457.006026] Lustre: Echo OBD driver; http://www.lustre.org/ [ 474.507509] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 475.687299] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 482.046295] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 497.650078] Lustre: Mounted lustre-client [ 499.485875] LustreError: 24673:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff91db991e2800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 499.494193] LustreError: 24673:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 499.515115] Lustre: Unmounted lustre-client [ 517.059802] Key type lgssc unregistered [ 517.186793] LNet: 25075:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 517.190495] LNetError: 25075:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 517.198100] LNet: Removed LNI 192.168.201.49@tcp [ 517.464076] Key type .llcrypt unregistered [ 517.465516] Key type ._llcrypt unregistered [ 522.473607] Key type ._llcrypt registered [ 522.475225] Key type .llcrypt registered [ 522.949507] Lustre: DEBUG MARKER: == conf-sanity test 4: force cleanup ost, then cleanup === 01:28:03 (1761283683) [ 523.874374] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 523.882473] alg: No test for adler32 (adler32-zlib) [ 524.759403] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 524.857521] LNet: Added LNI 192.168.201.49@tcp [8/256/0/180] [ 526.448169] Key type lgssc registered [ 526.880213] Lustre: Echo OBD driver; http://www.lustre.org/ [ 544.617892] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 545.910723] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 552.558683] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 563.188565] Lustre: Mounted lustre-client [ 568.292671] Lustre: lustre-OST0000-osc-ffff91db99437800: Connection to lustre-OST0000 (at 192.168.201.149@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 572.694126] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 582.954816] LustreError: 28201:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 582.970120] Lustre: Unmounted lustre-client [ 600.463809] Key type lgssc unregistered [ 600.589765] LNet: 28598:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 600.593545] LNetError: 28598:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 600.603833] LNet: Removed LNI 192.168.201.49@tcp [ 600.893892] Key type .llcrypt unregistered [ 600.895621] Key type ._llcrypt unregistered [ 606.496307] Key type ._llcrypt registered [ 606.497508] Key type .llcrypt registered [ 607.040785] Lustre: DEBUG MARKER: == conf-sanity test 5a: force cleanup mds, then cleanup == 01:29:27 (1761283767) [ 607.924650] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 607.950752] alg: No test for adler32 (adler32-zlib) [ 608.826706] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 608.921583] LNet: Added LNI 192.168.201.49@tcp [8/256/0/180] [ 610.512155] Key type lgssc registered [ 610.916964] Lustre: Echo OBD driver; http://www.lustre.org/ [ 628.813177] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 630.067690] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 636.526820] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 647.153752] Lustre: Mounted lustre-client [ 652.259678] Lustre: lustre-MDT0000-mdc-ffff91db88d1b000: Connection to lustre-MDT0000 (at 192.168.201.149@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 657.378734] Lustre: lustre-MDT0001-mdc-ffff91db88d1b000: Connection to lustre-MDT0001 (at 192.168.201.149@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 671.914912] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 673.568146] Lustre: 29551:0:(client.c:2468:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761283818/real 1761283818] req@000000002cf6d0eb x1846839889369216/t0(0) o400->MGC192.168.201.149@tcp@192.168.201.149@tcp:26/25 lens 224/224 e 0 to 1 dl 1761283834 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 673.576856] LustreError: MGC192.168.201.149@tcp: Connection to MGS (at 192.168.201.149@tcp) was lost; in progress operations using this service will fail [ 682.152787] LustreError: 31763:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 682.168141] Lustre: Unmounted lustre-client [ 701.027628] Key type lgssc unregistered [ 701.147805] LNet: 32158:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 701.150533] LNetError: 32158:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 701.161655] LNet: Removed LNI 192.168.201.49@tcp [ 701.397944] Key type .llcrypt unregistered [ 701.399200] Key type ._llcrypt unregistered [ 707.535097] Key type ._llcrypt registered [ 707.536224] Key type .llcrypt registered [ 707.996189] Lustre: DEBUG MARKER: == conf-sanity test 5b: Try to start a client with no MGS (should return errs) ========================================================== 01:31:08 (1761283868) [ 708.749040] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 708.756402] alg: No test for adler32 (adler32-zlib) [ 709.613053] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 709.691908] LNet: Added LNI 192.168.201.49@tcp [8/256/0/180] [ 711.272160] Key type lgssc registered [ 711.656577] Lustre: Echo OBD driver; http://www.lustre.org/ [ 771.350378] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 776.608294] LustreError: 34320:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.201.149@tcp: failed processing log, type 1: rc = -110 [ 782.304293] LustreError: 34329:0:(mgc_request.c:614:do_requeue()) failed processing log: -110 [ 933.856389] LustreError: MGC192.168.201.149@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 [ 933.863156] Lustre: Unmounted lustre-client [ 933.865345] LustreError: 34320:0:(obd_class.h:479:obd_check_dev()) Device 0 not setup [ 933.873449] LustreError: 34320:0:(super25.c:199:lustre_fill_super()) llite: Unable to mount : rc = -110 [ 938.680809] Key type lgssc unregistered [ 938.801160] LNet: 34751:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 938.804700] LNetError: 34751:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 938.813320] LNet: Removed LNI 192.168.201.49@tcp [ 939.071830] Key type .llcrypt unregistered [ 939.073489] Key type ._llcrypt unregistered [ 944.428429] Key type ._llcrypt registered [ 944.429615] Key type .llcrypt registered [ 944.934137] Lustre: DEBUG MARKER: == conf-sanity test 5c: cleanup after failed mount (bug 2712) (should return errs) ========================================================== 01:35:05 (1761284105) [ 945.752382] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 945.769340] alg: No test for adler32 (adler32-zlib) [ 946.633932] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 946.726943] LNet: Added LNI 192.168.201.49@tcp [8/256/0/180] [ 948.320162] Key type lgssc registered [ 948.742226] Lustre: Echo OBD driver; http://www.lustre.org/ [ 966.362989] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 967.572814] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 973.623470] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 973.736776] LustreError: 37740:0:(llite_lib.c:1440:ll_fill_super()) wrong.lustre: fsname longer than 8 characters: rc = -36 [ 973.740185] Lustre: Unmounted wrong.lustre-client [ 973.743511] LustreError: 37740:0:(obd_class.h:479:obd_check_dev()) Device 0 not setup [ 973.748841] LustreError: 37740:0:(super25.c:199:lustre_fill_super()) llite: Unable to mount : rc = -36 [ 990.776623] Key type lgssc unregistered [ 990.891442] LNet: 38154:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 990.894537] LNetError: 38154:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 990.903317] LNet: Removed LNI 192.168.201.49@tcp [ 991.126505] Key type .llcrypt unregistered [ 991.127789] Key type ._llcrypt unregistered [ 996.047079] Key type ._llcrypt registered [ 996.048161] Key type .llcrypt registered [ 996.497399] Lustre: DEBUG MARKER: == conf-sanity test 5d: mount with ost down ============== 01:35:56 (1761284156) [ 997.254806] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 997.261366] alg: No test for adler32 (adler32-zlib) [ 998.109850] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 998.188581] LNet: Added LNI 192.168.201.49@tcp [8/256/0/180] [ 999.768192] Key type lgssc registered [ 1000.115571] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1059.481864] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1069.588361] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1070.875780] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1079.409094] Lustre: Mounted lustre-client [ 1079.698309] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1089.962103] LustreError: 41230:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1089.979177] Lustre: Unmounted lustre-client [ 1106.842425] Key type lgssc unregistered [ 1106.972480] LNet: 41626:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1106.974986] LNetError: 41626:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1106.984639] LNet: Removed LNI 192.168.201.49@tcp [ 1107.221086] Key type .llcrypt unregistered [ 1107.222470] Key type ._llcrypt unregistered [ 1111.711614] Key type ._llcrypt registered [ 1111.713062] Key type .llcrypt registered [ 1112.133905] Lustre: DEBUG MARKER: == conf-sanity test 5e: delayed connect, don't crash (bug 10268) ========================================================== 01:37:52 (1761284272) [ 1112.855793] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1112.866967] alg: No test for adler32 (adler32-zlib) [ 1113.714447] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 1113.803841] LNet: Added LNI 192.168.201.49@tcp [8/256/0/180] [ 1115.384220] Key type lgssc registered [ 1115.788053] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1131.917858] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1133.057926] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1140.898572] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1142.240979] LustreError: 42594:0:(niobuf.c:1104:ptl_send_rpc()) cfs_fail_timeout id 506 sleeping for 10000ms [ 1146.336318] LustreError: 44621:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.201.149@tcp: failed processing log, type 1: rc = -110 [ 1151.969175] LustreError: 44630:0:(mgc_request.c:614:do_requeue()) failed processing log: -110 [ 1152.344173] LustreError: 42594:0:(niobuf.c:1104:ptl_send_rpc()) cfs_fail_timeout id 506 awake [ 1161.350838] Lustre: Mounted lustre-client [ 1162.304142] LustreError: 44680:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff91db989fb800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1162.323580] LustreError: 44680:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1162.380974] Lustre: Unmounted lustre-client [ 1191.758358] Key type lgssc unregistered [ 1192.322677] LNet: 45083:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1192.326737] LNetError: 45083:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1192.348243] LNet: Removed LNI 192.168.201.49@tcp [ 1193.131296] Key type .llcrypt unregistered [ 1193.138051] Key type ._llcrypt unregistered [ 1214.078224] Key type ._llcrypt registered [ 1214.080745] Key type .llcrypt registered [ 1215.162351] Lustre: DEBUG MARKER: == conf-sanity test 5f: mds down, cleanup after failed mount (bug 2712) ========================================================== 01:39:34 (1761284374) [ 1216.628086] Lustre: DEBUG MARKER: SKIP: conf-sanity test_5f needs separate mgs and mds [ 1217.897706] Lustre: DEBUG MARKER: == conf-sanity test 5g: handle missing debugfs =========== 01:39:37 (1761284377) [ 1222.312836] Lustre: DEBUG MARKER: == conf-sanity test 5h: start mdt failure at mdt_fs_setup() ========================================================== 01:39:42 (1761284382) [ 1223.750250] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1223.760471] alg: No test for adler32 (adler32-zlib) [ 1224.724415] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 1224.878125] LNet: Added LNI 192.168.201.49@tcp [8/256/0/180] [ 1226.504414] Key type lgssc registered [ 1227.135022] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1254.566476] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1256.165661] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1265.943727] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1272.840751] Lustre: Mounted lustre-client [ 1283.052046] Lustre: lustre-MDT0000-mdc-ffff91db91105800: Connection to lustre-MDT0000 (at 192.168.201.149@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1293.286561] LustreError: MGC192.168.201.149@tcp: Connection to MGS (at 192.168.201.149@tcp) was lost; in progress operations using this service will fail [ 1293.303620] Lustre: Evicted from MGS (at 192.168.201.149@tcp) after server handle changed from 0x543b29d30ddb18cd to 0x543b29d30ddb1b89 [ 1293.315338] Lustre: MGC192.168.201.149@tcp: Connection restored to 192.168.201.149@tcp (at 192.168.201.149@tcp) [ 1295.818655] Lustre: lustre-MDT0000-mdc-ffff91db91105800: Connection restored to 192.168.201.149@tcp (at 192.168.201.149@tcp) [ 1296.530186] LustreError: 49445:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff91db91105800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1296.541761] LustreError: 49445:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1296.578188] Lustre: Unmounted lustre-client [ 1315.793359] Key type lgssc unregistered [ 1315.948125] LNet: 49845:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1315.952250] LNetError: 49845:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1315.962626] LNet: Removed LNI 192.168.201.49@tcp [ 1316.281351] Key type .llcrypt unregistered [ 1316.283616] Key type ._llcrypt unregistered [ 1323.076202] Key type ._llcrypt registered [ 1323.077447] Key type .llcrypt registered [ 1323.639369] Lustre: DEBUG MARKER: == conf-sanity test 5i: start mdt failure at mdt_quota_init() ========================================================== 01:41:23 (1761284483) [ 1324.605677] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1324.615249] alg: No test for adler32 (adler32-zlib) [ 1325.539051] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 1325.650405] LNet: Added LNI 192.168.201.49@tcp [8/256/0/180] [ 1327.248904] Key type lgssc registered [ 1327.789165] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1354.126223] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1357.201578] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1377.176032] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1380.096948] Lustre: Mounted lustre-client [ 1385.445026] Lustre: lustre-MDT0000-mdc-ffff91db99434800: Connection to lustre-MDT0000 (at 192.168.201.149@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1395.696211] LustreError: MGC192.168.201.149@tcp: Connection to MGS (at 192.168.201.149@tcp) was lost; in progress operations using this service will fail [ 1395.712169] Lustre: Evicted from MGS (at 192.168.201.149@tcp) after server handle changed from 0x930f74cae120d1dd to 0x930f74cae120d3b9 [ 1395.722518] Lustre: MGC192.168.201.149@tcp: Connection restored to 192.168.201.149@tcp (at 192.168.201.149@tcp) [ 1400.252688] Lustre: lustre-MDT0000-mdc-ffff91db99434800: Connection restored to 192.168.201.149@tcp (at 192.168.201.149@tcp) [ 1401.118595] LustreError: 53327:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff91db99434800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1401.130901] LustreError: 53327:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1401.176334] Lustre: Unmounted lustre-client [ 1424.163684] Key type lgssc unregistered [ 1424.342659] LNet: 53729:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1424.348915] LNetError: 53729:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1424.363978] LNet: Removed LNI 192.168.201.49@tcp [ 1424.857547] Key type .llcrypt unregistered [ 1424.859830] Key type ._llcrypt unregistered [ 1434.060212] Key type ._llcrypt registered [ 1434.061614] Key type .llcrypt registered [ 1434.997110] Lustre: DEBUG MARKER: == conf-sanity test 5j: verify libcfs doesn't crash when setting debug flags ========================================================== 01:43:14 (1761284594) [ 1435.279812] Key type .llcrypt unregistered [ 1435.282197] Key type ._llcrypt unregistered [ 1438.784120] Key type ._llcrypt registered [ 1438.785727] Key type .llcrypt registered [ 1438.819319] Key type .llcrypt unregistered [ 1438.823755] Key type ._llcrypt unregistered [ 1442.131584] Key type ._llcrypt registered [ 1442.134116] Key type .llcrypt registered [ 1443.214609] Lustre: DEBUG MARKER: == conf-sanity test 6: manual umount, then mount again === 01:43:22 (1761284602) [ 1444.865572] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1444.888628] alg: No test for adler32 (adler32-zlib) [ 1445.878804] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 1446.028289] LNet: Added LNI 192.168.201.49@tcp [8/256/0/180] [ 1447.648300] Key type lgssc registered [ 1448.479123] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1479.511512] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1481.862976] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1493.278519] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1495.729523] Lustre: Mounted lustre-client [ 1501.232868] LustreError: 57577:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff91db91100800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1501.245714] LustreError: 57577:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1501.283929] Lustre: Unmounted lustre-client [ 1501.520427] Lustre: Mounted lustre-client [ 1502.320540] LustreError: 57653:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff91db912a1800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1502.343429] LustreError: 57653:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1502.346586] LustreError: 57653:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 1502.392685] Lustre: Unmounted lustre-client [ 1523.647176] Key type lgssc unregistered [ 1523.826966] LNet: 58055:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1523.838316] LNetError: 58055:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1524.907423] LNet: Removed LNI 192.168.201.49@tcp [ 1525.330099] Key type .llcrypt unregistered [ 1525.331112] Key type ._llcrypt unregistered [ 1533.871470] Key type ._llcrypt registered [ 1533.872787] Key type .llcrypt registered [ 1534.733906] Lustre: DEBUG MARKER: == conf-sanity test 7: manual umount, then cleanup ======= 01:44:54 (1761284694) [ 1536.182357] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1536.198938] alg: No test for adler32 (adler32-zlib) [ 1537.168646] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 1537.337748] LNet: Added LNI 192.168.201.49@tcp [8/256/0/180] [ 1538.960266] Key type lgssc registered [ 1539.698219] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1565.818287] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1567.552186] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1581.253975] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1584.125980] Lustre: Mounted lustre-client [ 1589.416637] LustreError: 61123:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff91db9038d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1589.444594] LustreError: 61123:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1589.535835] Lustre: Unmounted lustre-client [ 1617.341329] Key type lgssc unregistered [ 1617.678206] LNet: 61534:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1617.688719] LNetError: 61534:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1617.724380] LNet: Removed LNI 192.168.201.49@tcp [ 1618.425939] Key type .llcrypt unregistered [ 1618.433981] Key type ._llcrypt unregistered [ 1630.094134] Key type ._llcrypt registered [ 1630.095861] Key type .llcrypt registered [ 1631.159477] Lustre: DEBUG MARKER: == conf-sanity test 8: double mount setup ================ 01:46:30 (1761284790) [ 1632.758569] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1632.781211] alg: No test for adler32 (adler32-zlib) [ 1633.775977] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 1633.985748] LNet: Added LNI 192.168.201.49@tcp [8/256/0/180] [ 1635.648202] Key type lgssc registered [ 1636.493523] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1670.807575] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1673.516676] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1685.674988] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1702.429713] Lustre: Mounted lustre-client [ 1704.462095] Lustre: Mounted lustre-client [ 1705.942624] LustreError: 64687:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff91db88d1e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1705.968831] LustreError: 64687:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 1706.025559] Lustre: Unmounted lustre-client [ 1707.268871] LustreError: 64706:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff91db83d1e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1707.291618] LustreError: 64706:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1707.295595] LustreError: 64706:0:(obd_class.h:479:obd_check_dev()) Skipped 5 previous similar messages [ 1707.352457] Lustre: Unmounted lustre-client [ 1727.465274] Key type lgssc unregistered [ 1727.681603] LNet: 65108:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1727.686568] LNetError: 65108:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1727.698604] LNet: Removed LNI 192.168.201.49@tcp [ 1728.152465] Key type .llcrypt unregistered [ 1728.156897] Key type ._llcrypt unregistered [ 1738.613193] Key type ._llcrypt registered [ 1738.614927] Key type .llcrypt registered [ 1739.733196] Lustre: DEBUG MARKER: == conf-sanity test 9: test ptldebug and subsystem for mkfs ========================================================== 01:48:19 (1761284899) [ 1741.344779] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1741.432174] alg: No test for adler32 (adler32-zlib) [ 1742.422757] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 1742.597713] LNet: Added LNI 192.168.201.49@tcp [8/256/0/180] [ 1744.248208] Key type lgssc registered [ 1745.066091] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1818.004573] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1828.230975] Lustre: DEBUG MARKER: == conf-sanity test 10a: find lctl param broken symlinks ========================================================== 01:49:48 (1761284988) [ 1848.958130] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1851.852337] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1864.185645] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1866.554502] Lustre: Mounted lustre-client [ 1874.523114] LustreError: 69353:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff91db9889b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1874.544691] LustreError: 69353:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1874.585581] Lustre: Unmounted lustre-client [ 1898.877410] Key type lgssc unregistered [ 1899.150732] LNet: 69755:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1899.161171] LNetError: 69755:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1900.208586] LNet: Removed LNI 192.168.201.49@tcp [ 1900.880990] Key type .llcrypt unregistered [ 1900.886422] Key type ._llcrypt unregistered [ 1912.995526] Key type ._llcrypt registered [ 1912.997848] Key type .llcrypt registered [ 1914.154161] Lustre: DEBUG MARKER: == conf-sanity test 17: Verify failed mds_postsetup won't fail assertion (2936) (should return errs) ========================================================== 01:51:13 (1761285073) [ 1916.392742] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1916.441094] alg: No test for adler32 (adler32-zlib) [ 1917.545862] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 1917.728807] LNet: Added LNI 192.168.201.49@tcp [8/256/0/180] [ 1919.371412] Key type lgssc registered [ 1920.408624] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1958.671607] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1961.218943] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1975.459816] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1986.575073] Lustre: Mounted lustre-client [ 1989.606405] LustreError: 72865:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff91db98bb7800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1989.625150] LustreError: 72865:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1989.663726] Lustre: Unmounted lustre-client [ 2020.564278] Key type lgssc unregistered [ 2020.759338] LNet: 73268:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2020.763086] LNetError: 73268:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2020.783059] LNet: Removed LNI 192.168.201.49@tcp [ 2021.199647] Key type .llcrypt unregistered [ 2021.201457] Key type ._llcrypt unregistered [ 2028.820820] Key type ._llcrypt registered [ 2028.823357] Key type .llcrypt registered [ 2029.144934] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2029.159853] alg: No test for adler32 (adler32-zlib) [ 2030.236881] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 2030.437301] LNet: Added LNI 192.168.201.49@tcp [8/256/0/180] [ 2032.112452] Key type lgssc registered [ 2033.104489] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2103.899909] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2189.605151] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2192.993183] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2210.583992] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2246.443958] Lustre: DEBUG MARKER: == conf-sanity test 18: check mkfs creates large journals ========================================================== 01:56:46 (1761285406) [ 2249.244808] Lustre: DEBUG MARKER: use device /dev/mapper/mds1_flakey with MIN=2000000 [ 2321.048790] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2323.940751] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2338.251362] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2392.476757] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2394.586477] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2405.725785] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2408.373446] Lustre: Mounted lustre-client [ 2415.004886] Lustre: DEBUG MARKER: Success: mkfs creates large journals. Size: 78M [ 2415.691761] LustreError: 84251:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff91db91025800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2415.704732] LustreError: 84251:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2415.731280] Lustre: Unmounted lustre-client [ 2437.393236] Key type lgssc unregistered [ 2437.576719] LNet: 84652:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2437.581549] LNetError: 84652:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2438.631684] LNet: Removed LNI 192.168.201.49@tcp [ 2439.062378] Key type .llcrypt unregistered [ 2439.064235] Key type ._llcrypt unregistered [ 2455.666874] Key type ._llcrypt registered [ 2455.668443] Key type .llcrypt registered [ 2455.991944] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2456.002834] alg: No test for adler32 (adler32-zlib) [ 2456.953656] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 2457.118357] LNet: Added LNI 192.168.201.49@tcp [8/256/0/180] [ 2458.784214] Key type lgssc registered [ 2459.569886] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2516.251199] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2519.264539] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2535.595525] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2569.105851] Lustre: DEBUG MARKER: == conf-sanity test 19a: start/stop MDS without OSTs ===== 02:02:08 (1761285728) [ 2592.229565] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2595.239455] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2613.712731] Lustre: DEBUG MARKER: == conf-sanity test 19b: start/stop OSTs without MDS ===== 02:02:53 (1761285773) [ 2674.910857] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2682.313733] Lustre: DEBUG MARKER: == conf-sanity test 20: remount ro,rw mounts work and doesn't break /etc/mtab ========================================================== 02:04:02 (1761285842) [ 2707.371747] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2711.055800] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2723.646328] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2734.610388] Lustre: Mounted lustre-client [ 2735.203894] Lustre: Remounted lustre-client read-only [ 2736.679152] LustreError: 93147:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff91db98bb6800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2736.704923] LustreError: 93147:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2736.761141] Lustre: Unmounted lustre-client [ 2767.939173] Lustre: DEBUG MARKER: == conf-sanity test 21a: start mds before ost, stop ost first ========================================================== 02:05:27 (1761285927) [ 2792.942987] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2796.595883] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2810.682651] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2846.676547] Lustre: DEBUG MARKER: == conf-sanity test 21b: start ost before mds, stop mds first ========================================================== 02:06:46 (1761286006) [ 2908.680486] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2930.465045] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2933.748540] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2970.352840] Lustre: DEBUG MARKER: == conf-sanity test 21c: start mds between two osts, stop mds last ========================================================== 02:08:49 (1761286129) [ 3029.300102] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3049.544984] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3051.940522] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3065.750695] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3136.945865] Lustre: DEBUG MARKER: == conf-sanity test 21d: start mgs then ost and then mds ========================================================== 02:11:36 (1761286296) [ 3138.365555] Lustre: DEBUG MARKER: SKIP: conf-sanity test_21d need separate mgs device [ 3139.761365] Lustre: DEBUG MARKER: SKIP: conf-sanity test_21e skipping excluded test 21e [ 3141.291298] Lustre: DEBUG MARKER: == conf-sanity test 22: start a client before osts (should return errs) ========================================================== 02:11:40 (1761286300) [ 3167.759991] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3171.532335] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3189.014593] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3206.959640] Lustre: Mounted lustre-client [ 3208.744538] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3219.055546] LustreError: 102867:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3219.062554] LustreError: 102867:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 3219.088541] Lustre: Unmounted lustre-client [ 3235.669473] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3236.312772] Lustre: Mounted lustre-client [ 3251.946697] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff91db98bb0000.ost_server_uuid 50 [ 3253.780881] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff91db98bb0000.ost_server_uuid in FULL state after 0 sec [ 3256.294544] LustreError: 103912:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff91db98bb0000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3256.314900] LustreError: 103912:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3256.320600] LustreError: 103912:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 3256.373065] Lustre: Unmounted lustre-client [ 3297.674491] Key type lgssc unregistered [ 3297.909678] LNet: 104315:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3297.916754] LNetError: 104315:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3297.946695] LNet: Removed LNI 192.168.201.49@tcp [ 3298.463475] Key type .llcrypt unregistered [ 3298.466562] Key type ._llcrypt unregistered [ 3309.900856] Key type ._llcrypt registered [ 3309.902957] Key type .llcrypt registered [ 3311.037025] Lustre: DEBUG MARKER: == conf-sanity test 23a: interrupt client during recovery mount delay ========================================================== 02:14:30 (1761286470) [ 3312.893972] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3312.915544] alg: No test for adler32 (adler32-zlib) [ 3313.952119] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 3314.183949] LNet: Added LNI 192.168.201.49@tcp [8/256/0/180] [ 3315.872277] Key type lgssc registered [ 3317.144324] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3355.108184] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3358.264387] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3373.253752] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3384.412133] Lustre: Mounted lustre-client [ 3389.424597] Lustre: lustre-MDT0000-mdc-ffff91db995a6800: Connection to lustre-MDT0000 (at 192.168.201.149@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3390.768841] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3401.077412] LustreError: 107429:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3401.080312] LustreError: MGC192.168.201.149@tcp: Connection to MGS (at 192.168.201.149@tcp) was lost; in progress operations using this service will fail [ 3401.113910] Lustre: Unmounted lustre-client [ 3411.975830] LustreError: lustre-MDT0000-mdc-ffff91db9950f000: operation mds_connect to node 192.168.201.149@tcp failed: rc = -16 [ 3417.012322] LustreError: 107613:0:(lmv_obd.c:1435:lmv_statfs()) lustre-MDT0000-mdc-ffff91db9950f000: can't stat MDS #0: rc = -16 [ 3417.066776] LustreError: 107613:0:(obd_class.h:479:obd_check_dev()) Device 3 not setup [ 3417.076446] LustreError: 107613:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 3417.098739] Lustre: Unmounted lustre-client [ 3417.112581] LustreError: 107613:0:(super25.c:199:lustre_fill_super()) llite: Unable to mount : rc = -16 [ 3441.614301] Key type lgssc unregistered [ 3441.813553] LNet: 108067:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3441.819397] LNetError: 108067:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3441.830739] LNet: Removed LNI 192.168.201.49@tcp [ 3442.334499] Key type .llcrypt unregistered [ 3442.342579] Key type ._llcrypt unregistered [ 3453.193674] Key type ._llcrypt registered [ 3453.195551] Key type .llcrypt registered [ 3454.154655] Lustre: DEBUG MARKER: == conf-sanity test 23b: Simulate -EINTR during mount ==== 02:16:53 (1761286613) [ 3455.866881] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3455.895531] alg: No test for adler32 (adler32-zlib) [ 3456.911849] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 3457.094245] LNet: Added LNI 192.168.201.49@tcp [8/256/0/180] [ 3458.755250] Key type lgssc registered [ 3459.543441] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3496.992958] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3500.454625] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3514.162406] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3525.165425] Lustre: Mounted lustre-client [ 3526.234380] LustreError: 111110:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff91db98bb5800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3526.258751] LustreError: 111110:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3526.319207] Lustre: Unmounted lustre-client [ 3560.474108] Key type lgssc unregistered [ 3560.720099] LNet: 111514:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3560.725634] LNetError: 111514:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3560.745567] LNet: Removed LNI 192.168.201.49@tcp [ 3561.254781] Key type .llcrypt unregistered [ 3561.256719] Key type ._llcrypt unregistered [ 3574.131165] Key type ._llcrypt registered [ 3574.132917] Key type .llcrypt registered [ 3575.201465] Lustre: DEBUG MARKER: == conf-sanity test 24a: Multiple MDTs on a single node == 02:18:54 (1761286734) [ 3576.929129] Lustre: DEBUG MARKER: SKIP: conf-sanity test_24a mixed loopback and real device not working [ 3578.592544] Lustre: DEBUG MARKER: == conf-sanity test 24b: Multiple MGSs on a single node (should return err) ========================================================== 02:18:58 (1761286738) [ 3580.779141] Lustre: DEBUG MARKER: SKIP: conf-sanity test_24b mixed loopback and real device not working [ 3582.567837] Lustre: DEBUG MARKER: == conf-sanity test 25: Verify modules are referenced ==== 02:19:01 (1761286741) [ 3584.406340] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3584.441072] alg: No test for adler32 (adler32-zlib) [ 3585.527559] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 3585.699287] LNet: Added LNI 192.168.201.49@tcp [8/256/0/180] [ 3587.384201] Key type lgssc registered [ 3588.533553] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3622.282564] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3624.958344] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3638.503372] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3641.176412] Lustre: Mounted lustre-client [ 3648.170345] LustreError: 115209:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff91db99434000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3648.189930] LustreError: 115209:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3648.251347] Lustre: Unmounted lustre-client [ 3672.186970] Key type lgssc unregistered [ 3672.402299] LNet: 115609:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3672.411397] LNetError: 115609:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3672.429051] LNet: Removed LNI 192.168.201.49@tcp [ 3672.966919] Key type .llcrypt unregistered [ 3672.968775] Key type ._llcrypt unregistered [ 3685.628748] Key type ._llcrypt registered [ 3685.630110] Key type .llcrypt registered [ 3686.642547] Lustre: DEBUG MARKER: == conf-sanity test 26: MDT startup failure cleans LOV (should return errs) ========================================================== 02:20:46 (1761286846) [ 3687.462783] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3687.502931] alg: No test for adler32 (adler32-zlib) [ 3688.567216] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 3688.768984] LNet: Added LNI 192.168.201.49@tcp [8/256/0/180] [ 3690.432166] Key type lgssc registered [ 3691.402803] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3751.456402] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3754.746795] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3770.665993] Key type lgssc unregistered [ 3770.921835] LNet: 118629:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3770.928128] LNetError: 118629:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3770.939244] LNet: Removed LNI 192.168.201.49@tcp [ 3771.458380] Key type .llcrypt unregistered [ 3771.460358] Key type ._llcrypt unregistered [ 3785.240343] Key type ._llcrypt registered [ 3785.241889] Key type .llcrypt registered [ 3786.781697] Lustre: DEBUG MARKER: == conf-sanity test 27a: Reacquire MGS lock if OST started first ========================================================== 02:22:25 (1761286945) [ 3795.183522] Key type .llcrypt unregistered [ 3795.185418] Key type ._llcrypt unregistered [ 3802.434895] Key type ._llcrypt registered [ 3802.436731] Key type .llcrypt registered [ 3802.904848] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3802.918160] alg: No test for adler32 (adler32-zlib) [ 3803.976603] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 3804.207954] LNet: Added LNI 192.168.201.49@tcp [8/256/0/180] [ 3805.880223] Key type lgssc registered [ 3807.075606] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3885.267829] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3913.410778] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3917.248657] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3955.606733] Key type lgssc unregistered [ 3955.908214] LNet: 122404:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3955.920973] LNetError: 122404:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3955.933724] LNet: Removed LNI 192.168.201.49@tcp [ 3956.717703] Key type .llcrypt unregistered [ 3956.723695] Key type ._llcrypt unregistered [ 3972.856763] Key type ._llcrypt registered [ 3972.865377] Key type .llcrypt registered [ 3974.260905] Lustre: DEBUG MARKER: == conf-sanity test 27b: Reacquire MGS lock after failover ========================================================== 02:25:33 (1761287133) [ 3977.268364] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3977.294779] alg: No test for adler32 (adler32-zlib) [ 3978.529278] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 3978.789160] LNet: Added LNI 192.168.201.49@tcp [8/256/0/180] [ 3980.499655] Key type lgssc registered [ 3981.856143] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4028.459559] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4032.136839] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4046.047966] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4052.014418] Lustre: Mounted lustre-client [ 4057.065781] Lustre: lustre-MDT0000-mdc-ffff91db8883c800: Connection to lustre-MDT0000 (at 192.168.201.149@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4072.416205] Lustre: 123376:0:(client.c:2468:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761287217/real 1761287217] req@0000000085d641a3 x1846843423067392/t0(0) o400->MGC192.168.201.149@tcp@192.168.201.149@tcp:26/25 lens 224/224 e 0 to 1 dl 1761287233 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4072.436588] LustreError: MGC192.168.201.149@tcp: Connection to MGS (at 192.168.201.149@tcp) was lost; in progress operations using this service will fail [ 4082.673929] Lustre: Evicted from MGS (at 192.168.201.149@tcp) after server handle changed from 0x25e83bb5de99b9ff to 0x25e83bb5de99bc44 [ 4082.689651] Lustre: MGC192.168.201.149@tcp: Connection restored to 192.168.201.149@tcp (at 192.168.201.149@tcp) [ 4087.817136] Lustre: lustre-MDT0000-mdc-ffff91db8883c800: Connection restored to 192.168.201.149@tcp (at 192.168.201.149@tcp) [ 4102.350569] LustreError: 125902:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff91db8883c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4102.367684] LustreError: 125902:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4102.398472] Lustre: Unmounted lustre-client [ 4131.149909] Key type lgssc unregistered [ 4131.528689] LNet: 126308:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4131.539802] LNetError: 126308:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4131.571996] LNet: Removed LNI 192.168.201.49@tcp [ 4132.295873] Key type .llcrypt unregistered [ 4132.297922] Key type ._llcrypt unregistered [ 4150.100070] Key type ._llcrypt registered [ 4150.102164] Key type .llcrypt registered [ 4151.501179] Lustre: DEBUG MARKER: == conf-sanity test 28A: permanent parameter setting ===== 02:28:30 (1761287310) [ 4154.291215] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4154.309892] alg: No test for adler32 (adler32-zlib) [ 4155.406674] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 4155.656132] LNet: Added LNI 192.168.201.49@tcp [8/256/0/180] [ 4157.344363] Key type lgssc registered [ 4158.692539] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4207.517209] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4211.406786] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4229.246554] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4231.803906] Lustre: Mounted lustre-client [ 4242.133915] LustreError: 129472:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff91db91026800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4242.150502] LustreError: 129472:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4242.214283] Lustre: Unmounted lustre-client [ 4242.660308] Lustre: Mounted lustre-client [ 4250.991243] LustreError: 129602:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff91db864b5000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4251.028572] LustreError: 129602:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4251.031458] LustreError: 129602:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 4251.079258] Lustre: Unmounted lustre-client [ 4280.259776] Key type lgssc unregistered [ 4280.552889] LNet: 130004:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4280.559664] LNetError: 130004:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4280.588396] LNet: Removed LNI 192.168.201.49@tcp [ 4281.337757] Key type .llcrypt unregistered [ 4281.346696] Key type ._llcrypt unregistered [ 4295.892336] Key type ._llcrypt registered [ 4295.894046] Key type .llcrypt registered [ 4297.233800] Lustre: DEBUG MARKER: == conf-sanity test 28a: set symlink parameters permanently with lctl ========================================================== 02:30:56 (1761287456) [ 4299.483675] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4299.511891] alg: No test for adler32 (adler32-zlib) [ 4300.597335] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 4300.803600] LNet: Added LNI 192.168.201.49@tcp [8/256/0/180] [ 4302.480197] Key type lgssc registered [ 4303.220603] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4343.050496] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4346.133808] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4361.671824] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4372.530670] Lustre: Mounted lustre-client [ 4406.650307] LustreError: 133301:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff91db9950e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4406.664793] LustreError: 133301:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4406.761832] Lustre: Unmounted lustre-client [ 4430.464691] Key type lgssc unregistered [ 4430.749577] LNet: 133703:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4430.765580] LNetError: 133703:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4430.794587] LNet: Removed LNI 192.168.201.49@tcp [ 4431.394468] Key type .llcrypt unregistered [ 4431.397133] Key type ._llcrypt unregistered [ 4443.530793] Key type ._llcrypt registered [ 4443.532585] Key type .llcrypt registered [ 4444.709958] Lustre: DEBUG MARKER: == conf-sanity test 28b: verify client-side parameters are set upon mount ========================================================== 02:33:24 (1761287604) [ 4446.770540] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4446.788731] alg: No test for adler32 (adler32-zlib) [ 4447.842888] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 4448.072886] LNet: Added LNI 192.168.201.49@tcp [8/256/0/180] [ 4449.720187] Key type lgssc registered [ 4450.726847] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4492.374927] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4495.833995] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4509.670164] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4516.371169] Lustre: Mounted lustre-client [ 4517.579964] LustreError: 136767:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff91db881c7000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4517.606917] LustreError: 136767:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4517.672740] Lustre: Unmounted lustre-client [ 4517.992560] Lustre: Mounted lustre-client [ 4522.627451] LustreError: 137262:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff91db98bb1000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4522.661995] LustreError: 137262:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4522.665036] LustreError: 137262:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 4522.716619] Lustre: Unmounted lustre-client [ 4549.492956] Key type lgssc unregistered [ 4549.838299] LNet: 137665:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4549.853193] LNetError: 137665:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4549.887347] LNet: Removed LNI 192.168.201.49@tcp [ 4550.823194] Key type .llcrypt unregistered [ 4550.832174] Key type ._llcrypt unregistered [ 4561.316618] Key type ._llcrypt registered [ 4561.318398] Key type .llcrypt registered [ 4562.594148] Lustre: DEBUG MARKER: == conf-sanity test 28c: verify filesystem parameters are set upon mount ========================================================== 02:35:22 (1761287722) [ 4564.465746] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4564.475977] alg: No test for adler32 (adler32-zlib) [ 4565.514439] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 4565.762148] LNet: Added LNI 192.168.201.49@tcp [8/256/0/180] [ 4567.410044] Key type lgssc registered [ 4568.699779] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4608.577124] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4611.550512] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4627.864154] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4638.758545] Lustre: Mounted lustre-client [ 4640.334802] LustreError: 140311:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff91db8930e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4640.369094] LustreError: 140311:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4640.447564] Lustre: Unmounted lustre-client [ 4641.128753] Lustre: Mounted lustre-client [ 4649.094905] LustreError: 140805:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff91db85a49800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4649.124289] LustreError: 140805:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4649.126762] LustreError: 140805:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 4649.213198] Lustre: Unmounted lustre-client [ 4678.153119] Key type lgssc unregistered [ 4678.407743] LNet: 141208:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4678.415097] LNetError: 141208:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4678.434444] LNet: Removed LNI 192.168.201.49@tcp [ 4679.077900] Key type .llcrypt unregistered [ 4679.081560] Key type ._llcrypt unregistered [ 4687.003260] Key type ._llcrypt registered [ 4687.004794] Key type .llcrypt registered [ 4688.031740] Lustre: DEBUG MARKER: == conf-sanity test 29: permanently remove an OST ======== 02:37:27 (1761287847) [ 4689.674263] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4689.769083] alg: No test for adler32 (adler32-zlib) [ 4690.852809] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 4691.126495] LNet: Added LNI 192.168.201.49@tcp [8/256/0/180] [ 4692.808175] Key type lgssc registered [ 4693.984332] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4732.790826] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4735.587506] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4749.630460] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4760.602976] Lustre: Mounted lustre-client [ 4782.554790] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 4785.478510] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 4804.117996] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 4813.949393] LustreError: 144556:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff91db881c3000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4813.967426] LustreError: 144556:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 4814.047328] Lustre: Unmounted lustre-client [ 4814.426989] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 4814.547249] Lustre: Mounted lustre-client [ 4824.539079] LustreError: 144711:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff91db87d49000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4824.562860] LustreError: 144711:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 4824.565915] LustreError: 144711:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 4824.624875] Lustre: Unmounted lustre-client [ 4866.697734] Key type lgssc unregistered [ 4866.979520] LNet: 145160:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4866.989402] LNetError: 145160:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4867.011327] LNet: Removed LNI 192.168.201.49@tcp [ 4867.612814] Key type .llcrypt unregistered [ 4867.617106] Key type ._llcrypt unregistered [ 4904.015197] Key type ._llcrypt registered [ 4904.020803] Key type .llcrypt registered [ 4904.355897] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4904.376501] alg: No test for adler32 (adler32-zlib) [ 4905.445976] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 4905.624342] LNet: Added LNI 192.168.201.49@tcp [8/256/0/180] [ 4907.296177] Key type lgssc registered [ 4908.380647] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4970.820615] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4973.621870] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4991.293285] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5031.356236] Lustre: DEBUG MARKER: == conf-sanity test 30a: Big config llog and permanent parameter deletion ========================================================== 02:43:11 (1761288191) [ 5050.301180] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5053.296955] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5066.629827] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5068.115719] Lustre: Mounted lustre-client [ 5231.526725] LustreError: 152693:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff91db90389800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5231.551228] LustreError: 152693:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5231.715381] Lustre: Unmounted lustre-client [ 5232.703158] Lustre: Mounted lustre-client [ 5238.508393] LustreError: 152779:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff91db891be800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5238.553441] LustreError: 152779:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5238.558260] LustreError: 152779:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 5238.617605] Lustre: Unmounted lustre-client [ 5239.535093] Lustre: Mounted lustre-client [ 5241.819287] LustreError: 152851:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff91db879eb800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5241.862570] LustreError: 152851:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5241.872591] LustreError: 152851:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 5241.970798] Lustre: Unmounted lustre-client [ 5281.546800] Key type lgssc unregistered [ 5281.852956] LNet: 153256:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5281.859825] LNetError: 153256:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5281.877150] LNet: Removed LNI 192.168.201.49@tcp [ 5282.549532] Key type .llcrypt unregistered [ 5282.552757] Key type ._llcrypt unregistered [ 5295.471350] Key type ._llcrypt registered [ 5295.473303] Key type .llcrypt registered [ 5296.746997] Lustre: DEBUG MARKER: == conf-sanity test 30b: Remove failover nids ============ 02:47:36 (1761288456) [ 5299.415635] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5299.571669] alg: No test for adler32 (adler32-zlib) [ 5300.594095] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 5300.764065] LNet: Added LNI 192.168.201.49@tcp [8/256/0/180] [ 5302.408572] Key type lgssc registered [ 5303.525390] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5343.065427] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5346.256587] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5361.391520] Lustre: DEBUG MARKER: oleg149-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5373.474879] Lustre: Mounted lustre-client [ 5385.407163] LustreError: 156447:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff91db912a5000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5385.428311] LustreError: 156447:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5385.475217] Lustre: Unmounted lustre-client [ 5385.853871] Lustre: Mounted lustre-client [ 5387.273474] LustreError: 156522:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff91db891ba000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5387.292377] LustreError: 156522:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5387.296168] LustreError: 156522:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 5387.353406] Lustre: Unmounted lustre-client [ 5407.900112] Key type lgssc unregistered [ 5408.143133] LNet: 156924:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5408.152560] LNetError: 156924:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5408.176311] LNet: Removed LNI 192.168.201.49@tcp [ 5408.845606] Key type .llcrypt unregistered [ 5408.851441] Key type ._llcrypt unregistered [ 5419.474809] Key type ._llcrypt registered [ 5419.476441] Key type .llcrypt registered [ 5420.598100] Lustre: DEBUG MARKER: == conf-sanity test 31: Connect to non-existent node (shouldn't crash) ========================================================== 02:49:40 (1761288580) [ 5428.365681] Key type .llcrypt unregistered [ 5428.367569] Key type ._llcrypt unregistered [ 5453.523437] Key type ._llcrypt registered [ 5453.524768] Key type .llcrypt registered [ 5454.017527] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5454.034176] alg: No test for adler32 (adler32-zlib) [ 5455.165888] Lustre: Lustre: Build Version: 2.16.59_47_g5ba01f8 [ 5455.361469] LNet: Added LNI 192.168.201.49@tcp [8/256/0/180] [ 5457.048259] Key type lgssc registered [ 5458.408094] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5498.538538] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 5352 sec ========== 02:50:57 (1761288657) [ 5499.884691] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 02:50:59 (1761288659) === [ 5502.730464] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 02:51:02 (1761288662) === [ 5529.332407] Key type lgssc unregistered [ 5529.542216] LNet: 162029:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5529.547642] LNetError: 162029:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5529.567229] LNet: Removed LNI 192.168.201.49@tcp [ 5530.116499] Key type .llcrypt unregistered [ 5530.119543] Key type ._llcrypt unregistered