[ 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 494095507 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: 2895288K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001011] APIC: Switch to symmetric I/O mode setup [ 0.002304] x2apic enabled [ 0.003009] Switched APIC routing to physical x2apic. [ 0.004011] kvm-guest: setup PV IPIs [ 0.006886] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.007000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.008020] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009014] pid_max: default: 32768 minimum: 301 [ 0.010153] LSM: Security Framework initializing [ 0.011065] Yama: becoming mindful. [ 0.014062] SELinux: Initializing. [ 0.015104] *** VALIDATE selinux *** [ 0.022161] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.027652] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.028154] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029098] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.030116] *** VALIDATE tmpfs *** [ 0.031459] *** VALIDATE proc *** [ 0.032281] *** VALIDATE cgroup *** [ 0.033011] *** VALIDATE cgroup2 *** [ 0.034244] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.035133] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.036007] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.037028] Spectre V2 : User space: Vulnerable [ 0.038008] Speculative Store Bypass: Vulnerable [ 0.041485] debug: unmapping init [mem 0xffffffffbc059000-0xffffffffbc060fff] [ 0.043165] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.044690] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.045023] ... version: 2 [ 0.046013] ... bit width: 48 [ 0.047011] ... generic registers: 4 [ 0.048013] ... value mask: 0000ffffffffffff [ 0.049018] ... max period: 00007fffffffffff [ 0.050013] ... fixed-purpose events: 3 [ 0.051011] ... event mask: 000000070000000f [ 0.052259] rcu: Hierarchical SRCU implementation. [ 0.054442] smp: Bringing up secondary CPUs ... [ 0.055563] x86: Booting SMP configuration: [ 0.056024] .... node #0, CPUs: #1 #2 #3 [ 0.063116] smp: Brought up 1 node, 4 CPUs [ 0.065016] smpboot: Max logical packages: 1 [ 0.066017] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.117052] node 0 deferred pages initialised in 47ms [ 0.120010] devtmpfs: initialized [ 0.121242] x86/mm: Memory block size: 128MB [ 0.123769] gcov: version magic: 0x41383552 [ 0.125042] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.126463] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.129360] pinctrl core: initialized pinctrl subsystem [ 0.131199] [ 0.131847] ************************************************************* [ 0.134012] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.136011] ** ** [ 0.138014] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.140011] ** ** [ 0.142013] ** This means that this kernel is built to expose internal ** [ 0.145013] ** IOMMU data structures, which may compromise security on ** [ 0.147056] ** your system. ** [ 0.149013] ** ** [ 0.151012] ** If you see this message and you are not debugging the ** [ 0.153013] ** kernel, report this immediately to your vendor! ** [ 0.156022] ** ** [ 0.158014] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.161014] ************************************************************* [ 0.164568] NET: Registered protocol family 16 [ 0.167492] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.171069] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.175067] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.179049] cpuidle: using governor menu [ 0.180743] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.183468] PCI: Using configuration type 1 for base access [ 0.186124] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.196051] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.200010] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.204233] cryptd: max_cpu_qlen set to 1000 [ 0.210024] ACPI: Added _OSI(Module Device) [ 0.211019] ACPI: Added _OSI(Processor Device) [ 0.212012] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.213533] ACPI: Added _OSI(Processor Aggregator Device) [ 0.214623] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.220645] ACPI: Interpreter enabled [ 0.222116] ACPI: PM: (supports S0 S3 S4 S5) [ 0.224011] ACPI: Using IOAPIC for interrupt routing [ 0.227124] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.229359] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.239357] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.242039] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.244018] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.248077] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.252395] acpiphp: Slot [2] registered [ 0.254177] acpiphp: Slot [5] registered [ 0.256135] acpiphp: Slot [6] registered [ 0.257247] acpiphp: Slot [3] registered [ 0.259107] acpiphp: Slot [4] registered [ 0.261120] acpiphp: Slot [7] registered [ 0.262116] acpiphp: Slot [8] registered [ 0.264108] acpiphp: Slot [9] registered [ 0.266446] acpiphp: Slot [10] registered [ 0.268105] acpiphp: Slot [11] registered [ 0.269215] acpiphp: Slot [12] registered [ 0.271119] acpiphp: Slot [13] registered [ 0.272148] acpiphp: Slot [14] registered [ 0.274108] acpiphp: Slot [15] registered [ 0.276149] acpiphp: Slot [16] registered [ 0.278105] acpiphp: Slot [17] registered [ 0.279092] acpiphp: Slot [18] registered [ 0.281132] acpiphp: Slot [19] registered [ 0.283544] acpiphp: Slot [20] registered [ 0.285122] acpiphp: Slot [21] registered [ 0.287196] acpiphp: Slot [22] registered [ 0.288121] acpiphp: Slot [23] registered [ 0.290107] acpiphp: Slot [24] registered [ 0.291094] acpiphp: Slot [25] registered [ 0.293106] acpiphp: Slot [26] registered [ 0.294104] acpiphp: Slot [27] registered [ 0.296110] acpiphp: Slot [28] registered [ 0.297000] acpiphp: Slot [29] registered [ 0.297000] acpiphp: Slot [30] registered [ 0.298107] acpiphp: Slot [31] registered [ 0.299060] PCI host bridge to bus 0000:00 [ 0.300023] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.302028] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.304022] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.305027] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.307025] pci_bus 0000:00: root bus resource [mem 0x380000000000-0x38007fffffff window] [ 0.310544] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.311217] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.314058] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.315000] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.324014] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.328749] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.331024] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.334018] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.336030] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.340551] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.343839] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.346046] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.348905] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.353013] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.364014] pci 0000:00:02.0: reg 0x20: [mem 0x380000000000-0x380000003fff 64bit pref] [ 0.369014] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.374609] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.380025] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.386020] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.401023] pci 0000:00:05.0: reg 0x20: [mem 0x380000004000-0x380000007fff 64bit pref] [ 0.411831] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.418015] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.426016] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.439063] pci 0000:00:06.0: reg 0x20: [mem 0x380000008000-0x38000000bfff 64bit pref] [ 0.452188] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.456522] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.459340] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.461448] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.464216] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.468185] iommu: Default domain type: Passthrough [ 0.470483] SCSI subsystem initialized [ 0.472164] ACPI: bus type USB registered [ 0.474328] usbcore: registered new interface driver usbfs [ 0.477083] usbcore: registered new interface driver hub [ 0.479544] usbcore: registered new device driver usb [ 0.481154] pps_core: LinuxPPS API ver. 1 registered [ 0.483011] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.484056] PTP clock support registered [ 0.487563] EDAC MC: Ver: 3.0.0 [ 0.488121] PCI: Using ACPI for IRQ routing [ 0.489779] NetLabel: Initializing [ 0.491011] NetLabel: domain hash size = 128 [ 0.493011] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.495080] NetLabel: unlabeled traffic allowed by default [ 0.498186] vgaarb: loaded [ 0.501282] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.504640] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.514031] clocksource: Switched to clocksource kvm-clock [ 0.644462] VFS: Disk quotas dquot_6.6.0 [ 0.648214] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.654781] *** VALIDATE ramfs *** [ 0.655959] *** VALIDATE hugetlbfs *** [ 0.657317] pnp: PnP ACPI init [ 0.659375] pnp: PnP ACPI: found 6 devices [ 0.686143] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.688894] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.690822] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.693448] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.696060] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.699418] pci_bus 0000:00: resource 8 [mem 0x380000000000-0x38007fffffff window] [ 0.702742] NET: Registered protocol family 2 [ 0.705334] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.710477] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.715200] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.723207] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.726417] TCP: Hash tables configured (established 65536 bind 65536) [ 0.730761] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.735994] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.739939] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.744129] NET: Registered protocol family 1 [ 0.754249] RPC: Registered named UNIX socket transport module. [ 0.757532] RPC: Registered udp transport module. [ 0.760066] RPC: Registered tcp transport module. [ 0.764083] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.766324] NET: Registered protocol family 44 [ 0.769829] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.773113] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.776535] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.779366] PCI: CLS 0 bytes, default 64 [ 0.781781] Unpacking initramfs... [ 2.473388] debug: unmapping init [mem 0xffff8c033cc64000-0xffff8c033ffcffff] [ 2.477958] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.480233] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.482992] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 3.036135] Initialise system trusted keyrings [ 3.037942] Key type blacklist registered [ 3.040559] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 3.048747] zbud: loaded [ 3.053055] *** VALIDATE nfs *** [ 3.054482] *** VALIDATE nfs4 *** [ 3.056202] pstore: using deflate compression [ 3.059848] Platform Keyring initialized [ 3.172063] hrtimer: interrupt took 3061408 ns [ 3.221277] NET: Registered protocol family 38 [ 3.223340] Key type asymmetric registered [ 3.224843] Asymmetric key parser 'x509' registered [ 3.227392] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 3.231818] io scheduler mq-deadline registered [ 3.238160] io scheduler kyber registered [ 3.239561] io scheduler bfq registered [ 3.242478] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 3.245715] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 3.248533] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 3.251142] ACPI: Power Button [PWRF] [ 3.391541] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.645792] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.959119] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.987265] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 4.060352] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 4.087767] Non-volatile memory driver v1.3 [ 4.089686] Linux agpgart interface v0.103 [ 4.239519] virtio_blk virtio1: [vda] 133848 512-byte logical blocks (68.5 MB/65.4 MiB) [ 4.251691] vda: detected capacity change from 0 to 68530176 [ 4.313531] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 4.324952] vdb: detected capacity change from 0 to 1073741824 [ 4.348386] libphy: Fixed MDIO Bus: probed [ 4.362838] usbcore: registered new interface driver usbserial_generic [ 4.365085] usbserial: USB Serial support registered for generic [ 4.374256] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 4.390590] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 4.392250] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 4.401373] mousedev: PS/2 mouse device common for all mice [ 4.412713] rtc_cmos 00:05: RTC can wake from S4 [ 4.417228] rtc_cmos 00:05: registered as rtc0 [ 4.425844] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 4.438618] intel_pstate: CPU model not supported [ 4.447621] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 4.451349] hid: raw HID events driver (C) Jiri Kosina [ 4.453062] usbcore: registered new interface driver usbhid [ 4.477311] usbhid: USB HID core driver [ 4.478639] drop_monitor: Initializing network drop monitor service [ 4.480657] Initializing XFRM netlink socket [ 4.482754] NET: Registered protocol family 10 [ 4.486141] Segment Routing with IPv6 [ 4.487251] NET: Registered protocol family 17 [ 4.490242] mpls_gso: MPLS GSO support [ 4.526083] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 4.529267] RAS: Correctable Errors collector initialized. [ 4.551112] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 4.553487] AVX version of gcm_enc/dec engaged. [ 4.553494] AES CTR mode by8 optimization enabled [ 4.708716] sched_clock: Marking stable (4708697250, 0)->(5705132486, -996435236) [ 4.715434] registered taskstats version 1 [ 4.717605] Loading compiled-in X.509 certificates [ 4.722731] zswap: loaded using pool lzo/zbud [ 4.771756] Key type big_key registered [ 4.805508] Key type encrypted registered [ 4.806910] ima: No TPM chip found, activating TPM-bypass! [ 4.808716] ima: Allocated hash algorithm: sha1 [ 4.810212] ima: No architecture policies found [ 4.811694] evm: Initialising EVM extended attributes: [ 4.813247] evm: security.selinux [ 4.815247] evm: security.ima [ 4.817904] evm: security.capability [ 4.827898] evm: HMAC attrs: 0x1 [ 4.832759] rtc_cmos 00:05: setting system clock to 2025-11-16 22:27:34 UTC (1763332054) [ 4.843737] debug: unmapping init [mem 0xffffffffbd003000-0xffffffffbd1fffff] [ 4.860584] debug: unmapping init [mem 0xffffffffbbd82000-0xffffffffbc058fff] [ 4.875080] Write protecting the kernel read-only data: 28672k [ 4.882883] debug: unmapping init [mem 0xffffffffba403000-0xffffffffba5fffff] [ 4.888295] debug: unmapping init [mem 0xffffffffbad14000-0xffffffffbadfffff] [ 4.957792] 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) [ 4.966715] systemd[1]: Detected virtualization kvm. [ 4.968542] systemd[1]: Detected architecture x86-64. [ 4.970599] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 5.005313] systemd[1]: No hostname configured. [ 5.007011] systemd[1]: Set hostname to . [ 5.008516] random: systemd: uninitialized urandom read (16 bytes read) [ 5.010496] systemd[1]: Initializing machine ID from random generator. [ 5.134893] random: ln: uninitialized urandom read (6 bytes read) [ 5.403469] random: systemd: uninitialized urandom read (16 bytes read) [ 5.407222] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. [ 5.417887] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ 5.425512] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Slices. Starting Apply Kernel Variables... Starting Journal Service... [ OK ] Reached target Local File Systems. [ OK ] Reached target Swap. Starting Setup Virtual Console... [ OK ] Reached target Timers. [ OK ] Reached target Initrd Root Device. [ OK ] Started Memstrack Anylazing Service. Starting Create list of required st…ce nodes for the current kernel... Starting Create Volatile Files and Directories... [ OK ] Reached target Paths. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Sockets. [ OK ] Started Apply Kernel Variables. [ OK ] Started Setup Virtual Console. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Create Volatile Files and Directories. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 7.196261] device-mapper: uevent: version 1.0.3 [ 7.199230] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 9.322056] virtio_net virtio0 ens2: renamed from eth0 [ 11.016190] scsi host0: ata_piix [ 11.367474] scsi host1: ata_piix [ 11.373501] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 11.384694] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 15.354615] random: crng init done [ 15.355983] random: 7 urandom warning(s) missed due to ratelimiting [ 17.823593] dracut-initqueue[594]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 19.232855] 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 Initrd Root Device. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Timers. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Slices. [ OK ] Stopped target Sockets. [ OK ] Stopped target Paths. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Swap. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ 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 Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 21.948624] printk: systemd: 26 output lines suppressed due to ratelimiting [ 23.084151] SELinux: Disabled at runtime. [ 23.170815] 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) [ 23.208576] systemd[1]: Detected virtualization kvm. [ 23.210442] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 25.234544] systemd[1]: initrd-switch-root.service: Succeeded. [ 25.243305] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 25.255227] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 25.264035] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 25.268275] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 25.297833] systemd[1]: Starting Journal Service... Starting Journal Service... [ 25.319172] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ OK ] Created slice system-serial\x2dgetty.slice. Activating swap /dev/disk/by-label/SWAP... [ OK ] Listening on udev Control Socket. [ OK ] Created slice system-getty.slice. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Listening on RPCbind Server Activation Socket. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. [ 25.498537] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. Starting Remount Root and Kernel File Systems... [ OK ] Listening on Process Core Dump Socket. Mounting Kernel Debug File System... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. [ OK ] Stopped target Initrd Root File System. Starting Apply Kernel Variables... [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. Starting udev Coldplug all Devices... [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Reached target RPC Port Mapper. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. Mounting POSIX Message Queue File System... Starting Create list of required st…ce nodes for the current kernel... Mounting Huge Pages File System... [ OK ] Reached target rpc_pipefs.target. [ OK ] Started Journal Service. [ OK ] Activated swap /dev/disk/by-label/SWAP. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted Kernel Debug File System. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted Huge Pages File System. Starting Create Static Device Nodes in /dev... Starting Configure read-only root support... [ OK ] Reached target Swap. Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started udev Coldplug all Devices. [ OK ] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... [ OK ] Mounted /mnt. [ 26.985582] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 28.448443] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 28.531934] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 29.180314] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 29.267323] EDAC sbridge: Ver: 1.1.2 [* ] A start job is running for Configur…-only root support (8s / no limit)[ 33.467483] Key type dns_resolver registered [** ] A start job is running for Configur…-only root support (8s / no limit) [*** ] A start job is running for Configur…-only root support (9s / no limit)[ 34.389645] NFS: Registering the id_resolver key type [ 34.391317] Key type id_resolver registered [ 34.402925] Key type id_legacy registered [ *** ] A start job is running for Configur…-only root support (9s / no limit) [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Load/Save Random Seed... Starting Create Volatile Files and Directories... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Load/Save Random Seed. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. Starting Login Service... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started D-Bus System Message Bus. [ OK ] Started dnf makecache --timer. Starting Restore /run/initramfs on shutdown... [ OK ] Started irqbalance daemon. [ OK ] Started daily update of the root trust anchor for DNSSEC. Starting Network Manager... [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. [ OK ] Reached target sshd-keygen.target. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. Starting Hostname Service... [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... Starting OpenSSH server daemon... Starting Network Manager Wait Online... [ 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 Getty on tty1. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Serial Getty on ttyS1. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Crash recovery kernel arming... Starting System Logging Service... Starting Notify NFS peers of a restart... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg111-client login: [ 90.693376] libcfs: loading out-of-tree module taints kernel. [ 90.806558] Key type ._llcrypt registered [ 90.808609] Key type .llcrypt registered [ 91.254380] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 91.272603] alg: No test for adler32 (adler32-zlib) [ 92.698570] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 93.540684] LNet: Added LNI 192.168.201.11@tcp [8/256/0/180] [ 95.303189] Key type lgssc registered [ 96.804983] Lustre: Echo OBD driver; http://www.lustre.org/ [ 236.748698] Lustre: Mounted lustre-client [ 241.063700] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 254.349069] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing check_logdir /tmp/testlogs/ [ 257.835886] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing yml_node [ 262.329284] Lustre: DEBUG MARKER: Client: 2.16.61.43 [ 262.624193] Lustre: lustre-OST0000-osc-ffff8c0388ba5800: disconnect after 24s idle [ 264.425320] Lustre: DEBUG MARKER: MDS: 2.16.61.43 [ 266.674936] Lustre: DEBUG MARKER: OSS: 2.16.61.43 [ 268.393580] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Sun Nov 16 17:31:56 EST 2025 [ 283.707712] Lustre: DEBUG MARKER: excepting tests: 21e 32b 32c 32newtarball [ 284.946654] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 286.592326] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 296.888221] LustreError: 8980:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 296.928862] Lustre: Unmounted lustre-client [ 388.731330] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 391.611779] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 406.767134] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 436.198787] Lustre: DEBUG MARKER: == conf-sanity test 0: single mount setup ================ 17:34:44 (1763332484) [ 459.755253] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 461.903653] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 475.361863] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 475.823072] Lustre: Mounted lustre-client [ 482.669373] LustreError: 14068:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c03865d0800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 482.688183] LustreError: 14068:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 482.691771] LustreError: 14068:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 482.749414] Lustre: Unmounted lustre-client [ 505.608379] Key type lgssc unregistered [ 505.797739] LNet: 14471:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 505.804506] LNetError: 14471:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 505.820059] LNet: Removed LNI 192.168.201.11@tcp [ 506.242181] Key type .llcrypt unregistered [ 506.244301] Key type ._llcrypt unregistered [ 515.768497] Key type ._llcrypt registered [ 515.769983] Key type .llcrypt registered [ 516.979462] Lustre: DEBUG MARKER: == conf-sanity test 1: start up ost twice (should return errors) ========================================================== 17:36:05 (1763332565) [ 518.633195] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 518.652286] alg: No test for adler32 (adler32-zlib) [ 519.668268] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 519.871793] LNet: Added LNI 192.168.201.11@tcp [8/256/0/180] [ 521.559168] Key type lgssc registered [ 522.519212] Lustre: Echo OBD driver; http://www.lustre.org/ [ 557.743669] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 560.772877] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 574.757430] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 588.318158] Lustre: Mounted lustre-client [ 590.658447] LustreError: 17653:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c0391f3a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 590.681777] LustreError: 17653:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 590.732987] Lustre: Unmounted lustre-client [ 614.570371] Key type lgssc unregistered [ 614.808720] LNet: 18054:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 614.825165] LNetError: 18054:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 614.849716] LNet: Removed LNI 192.168.201.11@tcp [ 615.435101] Key type .llcrypt unregistered [ 615.444810] Key type ._llcrypt unregistered [ 627.090417] Key type ._llcrypt registered [ 627.091780] Key type .llcrypt registered [ 628.224978] Lustre: DEBUG MARKER: == conf-sanity test 2: start up mds twice (should return err) ========================================================== 17:37:56 (1763332676) [ 630.127844] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 630.148435] alg: No test for adler32 (adler32-zlib) [ 631.159329] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 631.313122] LNet: Added LNI 192.168.201.11@tcp [8/256/0/180] [ 632.975166] Key type lgssc registered [ 634.000766] Lustre: Echo OBD driver; http://www.lustre.org/ [ 668.983757] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 672.295974] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 689.682674] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 700.440832] Lustre: Mounted lustre-client [ 701.888589] LustreError: 21212:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c03838aa800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 701.901738] LustreError: 21212:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 701.942985] Lustre: Unmounted lustre-client [ 726.353446] Key type lgssc unregistered [ 726.568726] LNet: 21613:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 726.575234] LNetError: 21613:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 726.593468] LNet: Removed LNI 192.168.201.11@tcp [ 727.050970] Key type .llcrypt unregistered [ 727.061614] Key type ._llcrypt unregistered [ 739.501464] Key type ._llcrypt registered [ 739.508871] Key type .llcrypt registered [ 740.595380] Lustre: DEBUG MARKER: == conf-sanity test 3: mount client twice (should return err) ========================================================== 17:39:49 (1763332789) [ 742.312534] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 742.331873] alg: No test for adler32 (adler32-zlib) [ 743.315035] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 743.514820] LNet: Added LNI 192.168.201.11@tcp [8/256/0/180] [ 745.183178] Key type lgssc registered [ 746.191761] Lustre: Echo OBD driver; http://www.lustre.org/ [ 782.750943] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 785.717047] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 799.267749] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 800.734593] Lustre: Mounted lustre-client [ 804.227188] LustreError: 24732:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c03896f3800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 804.254646] LustreError: 24732:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 804.311847] Lustre: Unmounted lustre-client [ 836.181721] Key type lgssc unregistered [ 836.387816] LNet: 25136:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 836.391432] LNetError: 25136:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 836.403567] LNet: Removed LNI 192.168.201.11@tcp [ 836.852411] Key type .llcrypt unregistered [ 836.854673] Key type ._llcrypt unregistered [ 847.740430] Key type ._llcrypt registered [ 847.742107] Key type .llcrypt registered [ 848.928954] Lustre: DEBUG MARKER: == conf-sanity test 4: force cleanup ost, then cleanup === 17:41:37 (1763332897) [ 850.898491] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 850.916813] alg: No test for adler32 (adler32-zlib) [ 852.050256] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 852.231683] LNet: Added LNI 192.168.201.11@tcp [8/256/0/180] [ 853.919182] Key type lgssc registered [ 854.821027] Lustre: Echo OBD driver; http://www.lustre.org/ [ 891.973667] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 894.794472] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 907.782951] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 923.697265] Lustre: Mounted lustre-client [ 928.762204] Lustre: lustre-OST0000-osc-ffff8c0388bc7000: Connection to lustre-OST0000 (at 192.168.201.111@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 935.631472] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 945.899146] LustreError: 28273:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 945.939247] Lustre: Unmounted lustre-client [ 968.485855] Key type lgssc unregistered [ 968.641747] LNet: 28669:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 968.645530] LNetError: 28669:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 968.661848] LNet: Removed LNI 192.168.201.11@tcp [ 969.014919] Key type .llcrypt unregistered [ 969.016783] Key type ._llcrypt unregistered [ 978.070203] Key type ._llcrypt registered [ 978.071562] Key type .llcrypt registered [ 978.909411] Lustre: DEBUG MARKER: == conf-sanity test 5a: force cleanup mds, then cleanup == 17:43:47 (1763333027) [ 980.452326] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 980.466168] alg: No test for adler32 (adler32-zlib) [ 981.437644] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 981.598644] LNet: Added LNI 192.168.201.11@tcp [8/256/0/180] [ 983.217649] Key type lgssc registered [ 984.075252] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1016.822990] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1019.149967] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1032.477924] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1034.977619] Lustre: Mounted lustre-client [ 1045.477187] Lustre: lustre-MDT0000-mdc-ffff8c0391f3a800: Connection to lustre-MDT0000 (at 192.168.201.111@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1055.732511] Lustre: lustre-MDT0001-mdc-ffff8c0391f3a800: Connection to lustre-MDT0001 (at 192.168.201.111@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1058.901331] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1066.975229] Lustre: 29623:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763333100/real 1763333100] req@0000000058b2a04c x1848988696320384/t0(0) o400->MGC192.168.201.111@tcp@192.168.201.111@tcp:26/25 lens 224/224 e 0 to 1 dl 1763333116 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1067.020201] LustreError: MGC192.168.201.111@tcp: Connection to MGS (at 192.168.201.111@tcp) was lost; in progress operations using this service will fail [ 1069.177379] LustreError: 31838:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1069.200571] Lustre: Unmounted lustre-client [ 1091.892615] Key type lgssc unregistered [ 1092.056675] LNet: 32235:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1092.065246] LNetError: 32235:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1092.075708] LNet: Removed LNI 192.168.201.11@tcp [ 1092.485917] Key type .llcrypt unregistered [ 1092.488975] Key type ._llcrypt unregistered [ 1102.132273] Key type ._llcrypt registered [ 1102.137567] Key type .llcrypt registered [ 1102.954512] Lustre: DEBUG MARKER: == conf-sanity test 5b: Try to start a client with no MGS (should return errs) ========================================================== 17:45:51 (1763333151) [ 1104.132511] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1104.161149] alg: No test for adler32 (adler32-zlib) [ 1105.102125] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 1105.240520] LNet: Added LNI 192.168.201.11@tcp [8/256/0/180] [ 1106.847340] Key type lgssc registered [ 1107.468822] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1177.747698] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1183.391300] LustreError: 34399:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.201.111@tcp: failed processing log, type 1: rc = -110 [ 1188.831354] LustreError: 34408:0:(mgc_request.c:614:do_requeue()) failed processing log: -110 [ 1339.359311] LustreError: MGC192.168.201.111@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 [ 1339.367726] Lustre: Unmounted lustre-client [ 1339.370855] LustreError: 34399:0:(obd_class.h:479:obd_check_dev()) Device 0 not setup [ 1339.373922] LustreError: 34399:0:(super25.c:199:lustre_fill_super()) llite: Unable to mount : rc = -110 [ 1346.409921] Key type lgssc unregistered [ 1346.555262] LNet: 34823:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1346.559932] LNetError: 34823:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1346.568804] LNet: Removed LNI 192.168.201.11@tcp [ 1346.901970] Key type .llcrypt unregistered [ 1346.903768] Key type ._llcrypt unregistered [ 1354.485369] Key type ._llcrypt registered [ 1354.487099] Key type .llcrypt registered [ 1355.209650] Lustre: DEBUG MARKER: == conf-sanity test 5c: cleanup after failed mount (bug 2712) (should return errs) ========================================================== 17:50:04 (1763333404) [ 1356.372520] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1356.403889] alg: No test for adler32 (adler32-zlib) [ 1357.321142] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 1357.441407] LNet: Added LNI 192.168.201.11@tcp [8/256/0/180] [ 1359.049117] Key type lgssc registered [ 1359.636703] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1393.084588] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1396.000608] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1409.361261] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1409.649400] LustreError: 37820:0:(llite_lib.c:1440:ll_fill_super()) wrong.lustre: fsname longer than 8 characters: rc = -36 [ 1409.653743] Lustre: Unmounted wrong.lustre-client [ 1409.656755] LustreError: 37820:0:(obd_class.h:479:obd_check_dev()) Device 0 not setup [ 1409.661165] LustreError: 37820:0:(super25.c:199:lustre_fill_super()) llite: Unable to mount : rc = -36 [ 1441.043302] Key type lgssc unregistered [ 1441.246262] LNet: 38237:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1441.252765] LNetError: 38237:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1441.268881] LNet: Removed LNI 192.168.201.11@tcp [ 1441.764190] Key type .llcrypt unregistered [ 1441.765824] Key type ._llcrypt unregistered [ 1452.499930] Key type ._llcrypt registered [ 1452.501568] Key type .llcrypt registered [ 1453.644912] Lustre: DEBUG MARKER: == conf-sanity test 5d: mount with ost down ============== 17:51:42 (1763333502) [ 1455.473281] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1455.515306] alg: No test for adler32 (adler32-zlib) [ 1456.581614] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 1456.810897] LNet: Added LNI 192.168.201.11@tcp [8/256/0/180] [ 1458.487258] Key type lgssc registered [ 1459.565676] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1533.455381] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1556.158828] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1559.682210] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1571.417107] Lustre: Mounted lustre-client [ 1572.783516] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1583.097230] LustreError: 41321:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1583.156598] Lustre: Unmounted lustre-client [ 1612.171437] Key type lgssc unregistered [ 1612.375950] LNet: 41717:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1612.384029] LNetError: 41717:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1612.403950] LNet: Removed LNI 192.168.201.11@tcp [ 1612.903742] Key type .llcrypt unregistered [ 1612.905643] Key type ._llcrypt unregistered [ 1624.278329] Key type ._llcrypt registered [ 1624.281275] Key type .llcrypt registered [ 1625.581043] Lustre: DEBUG MARKER: == conf-sanity test 5e: delayed connect, don't crash (bug 10268) ========================================================== 17:54:33 (1763333673) [ 1627.387341] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1627.478298] alg: No test for adler32 (adler32-zlib) [ 1628.553208] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 1628.792467] LNet: Added LNI 192.168.201.11@tcp [8/256/0/180] [ 1630.495193] Key type lgssc registered [ 1631.657577] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1662.680966] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1664.847524] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1676.452628] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1677.792634] LustreError: 42672:0:(niobuf.c:1105:ptl_send_rpc()) cfs_fail_timeout id 506 sleeping for 10000ms [ 1681.887434] LustreError: 44705:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.201.111@tcp: failed processing log, type 1: rc = -110 [ 1687.887141] LustreError: 42672:0:(niobuf.c:1105:ptl_send_rpc()) cfs_fail_timeout id 506 awake [ 1689.034481] Lustre: Mounted lustre-client [ 1689.930812] LustreError: 44764:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c0388ba3800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1689.945671] LustreError: 44764:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1689.997964] Lustre: Unmounted lustre-client [ 1714.344656] Key type lgssc unregistered [ 1714.596301] LNet: 45166:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1714.604269] LNetError: 45166:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1714.635692] LNet: Removed LNI 192.168.201.11@tcp [ 1715.140320] Key type .llcrypt unregistered [ 1715.142290] Key type ._llcrypt unregistered [ 1727.628165] Key type ._llcrypt registered [ 1727.629802] Key type .llcrypt registered [ 1728.734278] Lustre: DEBUG MARKER: == conf-sanity test 5f: mds down, cleanup after failed mount (bug 2712) ========================================================== 17:56:17 (1763333777) [ 1730.157205] Lustre: DEBUG MARKER: SKIP: conf-sanity test_5f needs separate mgs and mds [ 1731.609418] Lustre: DEBUG MARKER: == conf-sanity test 5g: handle missing debugfs =========== 17:56:20 (1763333780) [ 1736.513111] Lustre: DEBUG MARKER: == conf-sanity test 5h: start mdt failure at mdt_fs_setup() ========================================================== 17:56:25 (1763333785) [ 1737.961626] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1738.001367] alg: No test for adler32 (adler32-zlib) [ 1739.065859] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 1739.280164] LNet: Added LNI 192.168.201.11@tcp [8/256/0/180] [ 1740.927185] Key type lgssc registered [ 1741.723118] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1773.803587] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1775.923964] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1789.413516] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1791.006408] Lustre: Mounted lustre-client [ 1801.192492] Lustre: lustre-MDT0000-mdc-ffff8c0388bc7000: Connection to lustre-MDT0000 (at 192.168.201.111@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1811.435965] LustreError: MGC192.168.201.111@tcp: Connection to MGS (at 192.168.201.111@tcp) was lost; in progress operations using this service will fail [ 1811.467946] Lustre: Evicted from MGS (at 192.168.201.111@tcp) after server handle changed from 0x35d3eeaa48d7420d to 0x35d3eeaa48d743e2 [ 1811.481710] Lustre: MGC192.168.201.111@tcp: Connection restored to 192.168.201.111@tcp (at 192.168.201.111@tcp) [ 1814.376366] Lustre: lustre-MDT0000-mdc-ffff8c0388bc7000: Connection restored to 192.168.201.111@tcp (at 192.168.201.111@tcp) [ 1816.632878] LustreError: 49529:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c0388bc7000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1816.660931] LustreError: 49529:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1816.705134] Lustre: Unmounted lustre-client [ 1840.175894] Key type lgssc unregistered [ 1840.368921] LNet: 49931:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1840.375977] LNetError: 49931:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1840.390368] LNet: Removed LNI 192.168.201.11@tcp [ 1840.848208] Key type .llcrypt unregistered [ 1840.852251] Key type ._llcrypt unregistered [ 1853.722252] Key type ._llcrypt registered [ 1853.724084] Key type .llcrypt registered [ 1854.840857] Lustre: DEBUG MARKER: == conf-sanity test 5i: start mdt failure at mdt_quota_init() ========================================================== 17:58:23 (1763333903) [ 1856.471474] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1856.524322] alg: No test for adler32 (adler32-zlib) [ 1857.559425] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 1857.736688] LNet: Added LNI 192.168.201.11@tcp [8/256/0/180] [ 1859.383153] Key type lgssc registered [ 1860.370441] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1903.407599] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1906.887438] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1922.810133] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1929.764242] Lustre: Mounted lustre-client [ 1934.826759] Lustre: lustre-MDT0000-mdc-ffff8c03865d3800: Connection to lustre-MDT0000 (at 192.168.201.111@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1950.199371] LustreError: MGC192.168.201.111@tcp: Connection to MGS (at 192.168.201.111@tcp) was lost; in progress operations using this service will fail [ 1950.222850] Lustre: Evicted from MGS (at 192.168.201.111@tcp) after server handle changed from 0xace0b8cb874c3905 to 0xace0b8cb874c3c62 [ 1950.244456] Lustre: MGC192.168.201.111@tcp: Connection restored to 192.168.201.111@tcp (at 192.168.201.111@tcp) [ 1953.142492] Lustre: lustre-MDT0000-mdc-ffff8c03865d3800: Connection restored to 192.168.201.111@tcp (at 192.168.201.111@tcp) [ 1955.458096] LustreError: 53398:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c03865d3800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1955.471582] LustreError: 53398:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1955.526325] Lustre: Unmounted lustre-client [ 1979.000268] Key type lgssc unregistered [ 1979.248198] LNet: 53796:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1979.259167] LNetError: 53796:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1980.330214] LNet: Removed LNI 192.168.201.11@tcp [ 1980.848358] Key type .llcrypt unregistered [ 1980.851073] Key type ._llcrypt unregistered [ 1993.511758] Key type ._llcrypt registered [ 1993.513810] Key type .llcrypt registered [ 1994.821677] Lustre: DEBUG MARKER: == conf-sanity test 5j: verify libcfs doesn't crash when setting debug flags ========================================================== 18:00:42 (1763334042) [ 1995.222381] Key type .llcrypt unregistered [ 1995.227279] Key type ._llcrypt unregistered [ 1999.814661] Key type ._llcrypt registered [ 1999.819977] Key type .llcrypt registered [ 1999.871453] Key type .llcrypt unregistered [ 1999.873118] Key type ._llcrypt unregistered [ 2003.535515] Key type ._llcrypt registered [ 2003.537131] Key type .llcrypt registered [ 2004.371709] Lustre: DEBUG MARKER: == conf-sanity test 6: manual umount, then mount again === 18:00:53 (1763334053) [ 2005.610863] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2005.705910] alg: No test for adler32 (adler32-zlib) [ 2006.653515] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 2006.828955] LNet: Added LNI 192.168.201.11@tcp [8/256/0/180] [ 2008.471177] Key type lgssc registered [ 2009.356927] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2045.102643] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2047.500828] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2060.090093] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2062.515785] Lustre: Mounted lustre-client [ 2068.103214] LustreError: 57654:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c0390a0c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2068.122264] LustreError: 57654:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2068.184220] Lustre: Unmounted lustre-client [ 2068.589993] Lustre: Mounted lustre-client [ 2069.947767] LustreError: 57733:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c0388bc0800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2069.965218] LustreError: 57733:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2069.971834] LustreError: 57733:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 2070.041600] Lustre: Unmounted lustre-client [ 2095.859316] Key type lgssc unregistered [ 2096.082900] LNet: 58135:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2096.094227] LNetError: 58135:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2097.127126] LNet: Removed LNI 192.168.201.11@tcp [ 2097.864668] Key type .llcrypt unregistered [ 2097.866992] Key type ._llcrypt unregistered [ 2110.597874] Key type ._llcrypt registered [ 2110.599754] Key type .llcrypt registered [ 2111.437749] Lustre: DEBUG MARKER: == conf-sanity test 7: manual umount, then cleanup ======= 18:02:40 (1763334160) [ 2113.220307] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2113.243280] alg: No test for adler32 (adler32-zlib) [ 2114.251532] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 2114.415518] LNet: Added LNI 192.168.201.11@tcp [8/256/0/180] [ 2116.039363] Key type lgssc registered [ 2116.868913] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2153.055253] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2155.876779] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2170.372510] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2182.186126] Lustre: Mounted lustre-client [ 2184.027775] LustreError: 61203:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c0388ba4800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2184.040274] LustreError: 61203:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2184.092147] Lustre: Unmounted lustre-client [ 2218.290138] Key type lgssc unregistered [ 2218.552749] LNet: 61614:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2218.570402] LNetError: 61614:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2218.588426] LNet: Removed LNI 192.168.201.11@tcp [ 2219.069682] Key type .llcrypt unregistered [ 2219.070939] Key type ._llcrypt unregistered [ 2230.688783] Key type ._llcrypt registered [ 2230.691810] Key type .llcrypt registered [ 2231.709587] Lustre: DEBUG MARKER: == conf-sanity test 8: double mount setup ================ 18:04:40 (1763334280) [ 2233.944855] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2233.999945] alg: No test for adler32 (adler32-zlib) [ 2235.183606] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 2235.383290] LNet: Added LNI 192.168.201.11@tcp [8/256/0/180] [ 2237.047189] Key type lgssc registered [ 2237.907296] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2280.297950] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2283.319978] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2299.003083] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2309.715805] Lustre: Mounted lustre-client [ 2311.968844] Lustre: Mounted lustre-client [ 2313.665828] LustreError: 64764:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c039098d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2313.686774] LustreError: 64764:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 2313.741450] Lustre: Unmounted lustre-client [ 2315.129718] LustreError: 64784:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c03885b5800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2315.170925] LustreError: 64784:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2315.175480] LustreError: 64784:0:(obd_class.h:479:obd_check_dev()) Skipped 5 previous similar messages [ 2315.236706] Lustre: Unmounted lustre-client [ 2335.025880] Key type lgssc unregistered [ 2335.212605] LNet: 65185:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2335.218838] LNetError: 65185:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2335.231466] LNet: Removed LNI 192.168.201.11@tcp [ 2335.686483] Key type .llcrypt unregistered [ 2335.688585] Key type ._llcrypt unregistered [ 2346.824591] Key type ._llcrypt registered [ 2346.825903] Key type .llcrypt registered [ 2347.899765] Lustre: DEBUG MARKER: == conf-sanity test 9: test ptldebug and subsystem for mkfs ========================================================== 18:06:36 (1763334396) [ 2349.829187] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2349.847224] alg: No test for adler32 (adler32-zlib) [ 2350.921418] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 2351.131549] LNet: Added LNI 192.168.201.11@tcp [8/256/0/180] [ 2352.863746] Key type lgssc registered [ 2353.953128] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2430.229362] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2442.130607] Lustre: DEBUG MARKER: == conf-sanity test 10a: find lctl param broken symlinks ========================================================== 18:08:10 (1763334490) [ 2464.787716] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2467.385352] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2483.599292] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2485.286730] Lustre: Mounted lustre-client [ 2490.470919] LustreError: 69429:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c038a1b9800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2490.487310] LustreError: 69429:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2490.519422] Lustre: Unmounted lustre-client [ 2514.562924] Key type lgssc unregistered [ 2514.809387] LNet: 69830:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2514.818880] LNetError: 69830:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2514.836866] LNet: Removed LNI 192.168.201.11@tcp [ 2515.552933] Key type .llcrypt unregistered [ 2515.555141] Key type ._llcrypt unregistered [ 2528.548775] Key type ._llcrypt registered [ 2528.550598] Key type .llcrypt registered [ 2529.853874] Lustre: DEBUG MARKER: == conf-sanity test 17: Verify failed mds_postsetup won't fail assertion (2936) (should return errs) ========================================================== 18:09:38 (1763334578) [ 2531.608487] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2531.666447] alg: No test for adler32 (adler32-zlib) [ 2532.694491] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 2532.898736] LNet: Added LNI 192.168.201.11@tcp [8/256/0/180] [ 2534.543245] Key type lgssc registered [ 2535.518583] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2575.594698] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2578.260181] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2594.037808] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2606.149330] Lustre: Mounted lustre-client [ 2609.922211] LustreError: 72939:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c0385a33800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2609.945471] LustreError: 72939:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2610.016243] Lustre: Unmounted lustre-client [ 2637.454744] Key type lgssc unregistered [ 2637.747712] LNet: 73339:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2637.760548] LNetError: 73339:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2638.822706] LNet: Removed LNI 192.168.201.11@tcp [ 2639.543591] Key type .llcrypt unregistered [ 2639.546321] Key type ._llcrypt unregistered [ 2652.001338] Key type ._llcrypt registered [ 2652.002985] Key type .llcrypt registered [ 2652.303469] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2652.315773] alg: No test for adler32 (adler32-zlib) [ 2653.337117] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 2653.480064] LNet: Added LNI 192.168.201.11@tcp [8/256/0/180] [ 2655.143186] Key type lgssc registered [ 2656.117634] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2732.788565] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2837.427985] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2841.567317] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2862.738918] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2916.534440] Lustre: DEBUG MARKER: == conf-sanity test 18: check mkfs creates large journals ========================================================== 18:16:04 (1763334964) [ 2919.593671] Lustre: DEBUG MARKER: use device /dev/mapper/mds1_flakey with MIN=2000000 [ 3006.029987] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3009.478402] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3029.325364] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3098.048840] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3101.994793] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3120.787486] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3122.784284] Lustre: Mounted lustre-client [ 3127.696627] Lustre: DEBUG MARKER: Success: mkfs creates large journals. Size: 78M [ 3129.578713] LustreError: 84330:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c0387979000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3129.599154] LustreError: 84330:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3129.659485] Lustre: Unmounted lustre-client [ 3154.920117] Key type lgssc unregistered [ 3155.159839] LNet: 84732:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3155.165317] LNetError: 84732:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3155.186483] LNet: Removed LNI 192.168.201.11@tcp [ 3155.864404] Key type .llcrypt unregistered [ 3155.871570] Key type ._llcrypt unregistered [ 3179.080353] Key type ._llcrypt registered [ 3179.081810] Key type .llcrypt registered [ 3179.594644] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3179.618741] alg: No test for adler32 (adler32-zlib) [ 3180.650428] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 3180.880170] LNet: Added LNI 192.168.201.11@tcp [8/256/0/180] [ 3182.631296] Key type lgssc registered [ 3183.895451] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3270.420330] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3273.948752] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3294.561596] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3333.590595] Lustre: DEBUG MARKER: == conf-sanity test 19a: start/stop MDS without OSTs ===== 18:23:01 (1763335381) [ 3364.969779] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3368.377073] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3388.521254] Lustre: DEBUG MARKER: == conf-sanity test 19b: start/stop OSTs without MDS ===== 18:23:56 (1763335436) [ 3450.282387] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3460.782733] Lustre: DEBUG MARKER: == conf-sanity test 20: remount ro,rw mounts work and doesn't break /etc/mtab ========================================================== 18:25:09 (1763335509) [ 3486.084520] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3489.166509] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3506.481774] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3517.508396] Lustre: Mounted lustre-client [ 3518.129842] Lustre: Remounted lustre-client read-only [ 3520.011823] LustreError: 93234:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c0392ae6000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3520.047829] LustreError: 93234:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3520.121240] Lustre: Unmounted lustre-client [ 3551.871656] Lustre: DEBUG MARKER: == conf-sanity test 21a: start mds before ost, stop ost first ========================================================== 18:26:40 (1763335600) [ 3577.537546] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3580.952335] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3596.465204] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3636.878540] Lustre: DEBUG MARKER: == conf-sanity test 21b: start ost before mds, stop mds first ========================================================== 18:28:05 (1763335685) [ 3699.262195] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3726.168965] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3729.514190] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3768.474178] Lustre: DEBUG MARKER: == conf-sanity test 21c: start mds between two osts, stop mds last ========================================================== 18:30:16 (1763335816) [ 3828.958958] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3852.890485] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3855.471703] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3872.050956] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3927.869617] Lustre: DEBUG MARKER: == conf-sanity test 21d: start mgs then ost and then mds ========================================================== 18:32:56 (1763335976) [ 3929.160408] Lustre: DEBUG MARKER: SKIP: conf-sanity test_21d need separate mgs device [ 3930.500728] Lustre: DEBUG MARKER: SKIP: conf-sanity test_21e skipping excluded test 21e [ 3931.896690] Lustre: DEBUG MARKER: == conf-sanity test 22: start a client before osts (should return errs) ========================================================== 18:33:00 (1763335980) [ 3955.405267] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3958.218381] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3972.500988] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3987.472156] Lustre: Mounted lustre-client [ 3988.564786] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3998.831056] LustreError: 102955:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3998.837705] LustreError: 102955:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 3998.873045] Lustre: Unmounted lustre-client [ 4015.714153] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4016.626597] Lustre: Mounted lustre-client [ 4031.853541] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8c03924e6800.ost_server_uuid 50 [ 4033.288145] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8c03924e6800.ost_server_uuid in FULL state after 0 sec [ 4035.482052] LustreError: 104003:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c03924e6800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4035.498536] LustreError: 104003:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4035.502067] LustreError: 104003:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 4035.546948] Lustre: Unmounted lustre-client [ 4066.310646] Key type lgssc unregistered [ 4066.524173] LNet: 104406:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4066.532848] LNetError: 104406:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4066.560414] LNet: Removed LNI 192.168.201.11@tcp [ 4067.123677] Key type .llcrypt unregistered [ 4067.127478] Key type ._llcrypt unregistered [ 4078.666639] Key type ._llcrypt registered [ 4078.668808] Key type .llcrypt registered [ 4079.690436] Lustre: DEBUG MARKER: == conf-sanity test 23a: interrupt client during recovery mount delay ========================================================== 18:35:28 (1763336128) [ 4081.317380] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4081.375827] alg: No test for adler32 (adler32-zlib) [ 4082.383565] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 4082.613995] LNet: Added LNI 192.168.201.11@tcp [8/256/0/180] [ 4084.287565] Key type lgssc registered [ 4085.218746] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4121.944163] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4124.530608] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4138.936106] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4140.448305] Lustre: Mounted lustre-client [ 4145.642784] Lustre: lustre-MDT0000-mdc-ffff8c0388bc5800: Connection to lustre-MDT0000 (at 192.168.201.111@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4145.822038] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4156.149964] LustreError: MGC192.168.201.111@tcp: Connection to MGS (at 192.168.201.111@tcp) was lost; in progress operations using this service will fail [ 4156.151454] LustreError: 107517:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4156.189292] Lustre: Unmounted lustre-client [ 4174.663523] LustreError: lustre-MDT0000-mdc-ffff8c0392487800: operation mds_connect to node 192.168.201.111@tcp failed: rc = -16 [ 4179.690380] LustreError: 107703:0:(lmv_obd.c:1435:lmv_statfs()) lustre-MDT0000-mdc-ffff8c0392487800: can't stat MDS #0: rc = -16 [ 4179.729724] LustreError: 107703:0:(obd_class.h:479:obd_check_dev()) Device 3 not setup [ 4179.732948] LustreError: 107703:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 4179.760206] Lustre: Unmounted lustre-client [ 4179.787407] LustreError: 107703:0:(super25.c:199:lustre_fill_super()) llite: Unable to mount : rc = -16 [ 4212.593962] Key type lgssc unregistered [ 4212.792141] LNet: 108159:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4212.806478] LNetError: 108159:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4212.827367] LNet: Removed LNI 192.168.201.11@tcp [ 4213.346181] Key type .llcrypt unregistered [ 4213.348102] Key type ._llcrypt unregistered [ 4226.345967] Key type ._llcrypt registered [ 4226.349328] Key type .llcrypt registered [ 4227.783467] Lustre: DEBUG MARKER: == conf-sanity test 23b: Simulate -EINTR during mount ==== 18:37:55 (1763336275) [ 4229.725350] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4229.753698] alg: No test for adler32 (adler32-zlib) [ 4230.905858] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 4231.151676] LNet: Added LNI 192.168.201.11@tcp [8/256/0/180] [ 4232.887501] Key type lgssc registered [ 4233.902904] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4269.615380] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4272.683957] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4289.926535] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4296.226069] Lustre: Mounted lustre-client [ 4297.583176] LustreError: 111199:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c03865d4000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4297.607739] LustreError: 111199:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4297.675194] Lustre: Unmounted lustre-client [ 4321.936770] Key type lgssc unregistered [ 4322.117737] LNet: 111601:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4322.130201] LNetError: 111601:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4322.140259] LNet: Removed LNI 192.168.201.11@tcp [ 4322.695609] Key type .llcrypt unregistered [ 4322.697530] Key type ._llcrypt unregistered [ 4334.036924] Key type ._llcrypt registered [ 4334.039520] Key type .llcrypt registered [ 4335.312527] Lustre: DEBUG MARKER: == conf-sanity test 24a: Multiple MDTs on a single node == 18:39:43 (1763336383) [ 4337.667066] Lustre: DEBUG MARKER: SKIP: conf-sanity test_24a mixed loopback and real device not working [ 4339.130854] Lustre: DEBUG MARKER: == conf-sanity test 24b: Multiple MGSs on a single node (should return err) ========================================================== 18:39:47 (1763336387) [ 4341.786083] Lustre: DEBUG MARKER: SKIP: conf-sanity test_24b mixed loopback and real device not working [ 4343.590219] Lustre: DEBUG MARKER: == conf-sanity test 25: Verify modules are referenced ==== 18:39:51 (1763336391) [ 4346.165192] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4346.188263] alg: No test for adler32 (adler32-zlib) [ 4347.319042] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 4347.748083] LNet: Added LNI 192.168.201.11@tcp [8/256/0/180] [ 4349.439165] Key type lgssc registered [ 4350.835704] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4390.440606] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4394.081565] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4407.600272] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4418.585745] Lustre: Mounted lustre-client [ 4420.178378] LustreError: 115296:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c03929db000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4420.195510] LustreError: 115296:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4420.261903] Lustre: Unmounted lustre-client [ 4454.080914] Key type lgssc unregistered [ 4454.365918] LNet: 115699:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4454.394427] LNetError: 115699:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4454.406655] LNet: Removed LNI 192.168.201.11@tcp [ 4455.057447] Key type .llcrypt unregistered [ 4455.062601] Key type ._llcrypt unregistered [ 4466.734933] Key type ._llcrypt registered [ 4466.736571] Key type .llcrypt registered [ 4467.780900] Lustre: DEBUG MARKER: == conf-sanity test 26: MDT startup failure cleans LOV (should return errs) ========================================================== 18:41:56 (1763336516) [ 4468.665648] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4468.692845] alg: No test for adler32 (adler32-zlib) [ 4469.698741] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 4469.914306] LNet: Added LNI 192.168.201.11@tcp [8/256/0/180] [ 4471.623181] Key type lgssc registered [ 4472.683446] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4513.812657] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4516.379976] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4539.477967] Key type lgssc unregistered [ 4539.699582] LNet: 118713:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4539.706471] LNetError: 118713:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4539.722938] LNet: Removed LNI 192.168.201.11@tcp [ 4540.294576] Key type .llcrypt unregistered [ 4540.296907] Key type ._llcrypt unregistered [ 4551.554804] Key type ._llcrypt registered [ 4551.559529] Key type .llcrypt registered [ 4552.548528] Lustre: DEBUG MARKER: == conf-sanity test 27a: Reacquire MGS lock if OST started first ========================================================== 18:43:21 (1763336601) [ 4560.306181] Key type .llcrypt unregistered [ 4560.309783] Key type ._llcrypt unregistered [ 4567.318338] Key type ._llcrypt registered [ 4567.338251] Key type .llcrypt registered [ 4567.853273] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4567.865950] alg: No test for adler32 (adler32-zlib) [ 4568.899938] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 4569.142966] LNet: Added LNI 192.168.201.11@tcp [8/256/0/180] [ 4570.911202] Key type lgssc registered [ 4572.253932] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4646.940592] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4668.327622] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4670.501598] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4704.304248] Key type lgssc unregistered [ 4704.494984] LNet: 122444:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4704.501356] LNetError: 122444:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4704.514251] LNet: Removed LNI 192.168.201.11@tcp [ 4705.086456] Key type .llcrypt unregistered [ 4705.088284] Key type ._llcrypt unregistered [ 4716.197487] Key type ._llcrypt registered [ 4716.201173] Key type .llcrypt registered [ 4717.203907] Lustre: DEBUG MARKER: == conf-sanity test 27b: Reacquire MGS lock after failover ========================================================== 18:46:05 (1763336765) [ 4718.908839] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4718.921586] alg: No test for adler32 (adler32-zlib) [ 4719.887501] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 4720.017687] LNet: Added LNI 192.168.201.11@tcp [8/256/0/180] [ 4721.647380] Key type lgssc registered [ 4722.592491] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4761.651974] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4764.693893] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4779.109877] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4789.784840] Lustre: Mounted lustre-client [ 4794.857355] Lustre: lustre-MDT0000-mdc-ffff8c03865d5000: Connection to lustre-MDT0000 (at 192.168.201.111@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4810.224250] LustreError: MGC192.168.201.111@tcp: Connection to MGS (at 192.168.201.111@tcp) was lost; in progress operations using this service will fail [ 4810.239202] Lustre: Evicted from MGS (at 192.168.201.111@tcp) after server handle changed from 0x1cbe7c5453111150 to 0x1cbe7c5453111395 [ 4810.248171] Lustre: MGC192.168.201.111@tcp: Connection restored to 192.168.201.111@tcp (at 192.168.201.111@tcp) [ 4813.140665] Lustre: lustre-MDT0000-mdc-ffff8c03865d5000: Connection restored to 192.168.201.111@tcp (at 192.168.201.111@tcp) [ 4830.888659] LustreError: 125972:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c03865d5000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4830.905650] LustreError: 125972:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4830.947666] Lustre: Unmounted lustre-client [ 4862.300201] Key type lgssc unregistered [ 4862.536561] LNet: 126377:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4862.544284] LNetError: 126377:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4862.582366] LNet: Removed LNI 192.168.201.11@tcp [ 4863.181088] Key type .llcrypt unregistered [ 4863.182461] Key type ._llcrypt unregistered [ 4880.555684] Key type ._llcrypt registered [ 4880.560620] Key type .llcrypt registered [ 4882.379910] Lustre: DEBUG MARKER: == conf-sanity test 28A: permanent parameter setting ===== 18:48:50 (1763336930) [ 4885.475121] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4885.483570] alg: No test for adler32 (adler32-zlib) [ 4886.743139] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 4887.020402] LNet: Added LNI 192.168.201.11@tcp [8/256/0/180] [ 4888.735203] Key type lgssc registered [ 4889.868878] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4923.706216] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4926.837702] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4941.171908] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4942.751519] Lustre: Mounted lustre-client [ 4962.734455] LustreError: 129586:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c0385a36800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4962.753687] LustreError: 129586:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4962.820241] Lustre: Unmounted lustre-client [ 4963.271628] Lustre: Mounted lustre-client [ 4972.479036] LustreError: 129719:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c03881b6800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4972.500351] LustreError: 129719:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4972.506050] LustreError: 129719:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 4972.538137] Lustre: Unmounted lustre-client [ 4997.606711] Key type lgssc unregistered [ 4997.815941] LNet: 130120:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4997.821923] LNetError: 130120:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4997.832078] LNet: Removed LNI 192.168.201.11@tcp [ 4998.395100] Key type .llcrypt unregistered [ 4998.397199] Key type ._llcrypt unregistered [ 5013.850390] Key type ._llcrypt registered [ 5013.852035] Key type .llcrypt registered [ 5014.983641] Lustre: DEBUG MARKER: == conf-sanity test 28a: set symlink parameters permanently with lctl ========================================================== 18:51:03 (1763337063) [ 5016.929115] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5016.944079] alg: No test for adler32 (adler32-zlib) [ 5017.898977] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 5018.118200] LNet: Added LNI 192.168.201.11@tcp [8/256/0/180] [ 5019.775540] Key type lgssc registered [ 5020.913843] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5060.322651] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5063.612616] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5081.181194] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5088.322179] Lustre: Mounted lustre-client [ 5122.567195] LustreError: 133424:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c03881b3000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5122.585269] LustreError: 133424:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5122.633450] Lustre: Unmounted lustre-client [ 5147.363580] Key type lgssc unregistered [ 5147.625955] LNet: 133826:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5147.639351] LNetError: 133826:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5147.660282] LNet: Removed LNI 192.168.201.11@tcp [ 5148.363553] Key type .llcrypt unregistered [ 5148.365277] Key type ._llcrypt unregistered [ 5161.163273] Key type ._llcrypt registered [ 5161.168697] Key type .llcrypt registered [ 5162.339632] Lustre: DEBUG MARKER: == conf-sanity test 28b: verify client-side parameters are set upon mount ========================================================== 18:53:30 (1763337210) [ 5164.516047] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5164.541413] alg: No test for adler32 (adler32-zlib) [ 5165.546897] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 5165.751928] LNet: Added LNI 192.168.201.11@tcp [8/256/0/180] [ 5167.431161] Key type lgssc registered [ 5168.432207] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5201.552954] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5204.266969] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5216.676315] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5219.189132] Lustre: Mounted lustre-client [ 5220.316465] LustreError: 136883:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c0392ae3000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5220.336951] LustreError: 136883:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5220.379760] Lustre: Unmounted lustre-client [ 5220.671249] Lustre: Mounted lustre-client [ 5224.624502] LustreError: 137378:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c0388df7800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5224.646170] LustreError: 137378:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5224.649491] LustreError: 137378:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 5224.693805] Lustre: Unmounted lustre-client [ 5246.876140] Key type lgssc unregistered [ 5247.067609] LNet: 137782:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5247.079865] LNetError: 137782:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5247.102103] LNet: Removed LNI 192.168.201.11@tcp [ 5247.489950] Key type .llcrypt unregistered [ 5247.493283] Key type ._llcrypt unregistered [ 5253.655531] Key type ._llcrypt registered [ 5253.657155] Key type .llcrypt registered [ 5254.644730] Lustre: DEBUG MARKER: == conf-sanity test 28c: verify filesystem parameters are set upon mount ========================================================== 18:55:03 (1763337303) [ 5256.399958] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5256.423833] alg: No test for adler32 (adler32-zlib) [ 5257.532943] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 5257.726683] LNet: Added LNI 192.168.201.11@tcp [8/256/0/180] [ 5259.375812] Key type lgssc registered [ 5260.158220] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5303.639813] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5306.802553] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5322.073353] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5328.923836] Lustre: Mounted lustre-client [ 5330.064140] LustreError: 140417:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c0383ff8800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5330.078989] LustreError: 140417:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5330.123195] Lustre: Unmounted lustre-client [ 5330.441572] Lustre: Mounted lustre-client [ 5335.237843] LustreError: 140909:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c038c588800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5335.247621] LustreError: 140909:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5335.250376] LustreError: 140909:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 5335.318845] Lustre: Unmounted lustre-client [ 5359.487545] Key type lgssc unregistered [ 5359.681077] LNet: 141311:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5359.687683] LNetError: 141311:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5359.710044] LNet: Removed LNI 192.168.201.11@tcp [ 5360.247753] Key type .llcrypt unregistered [ 5360.249712] Key type ._llcrypt unregistered [ 5370.006651] Key type ._llcrypt registered [ 5370.008247] Key type .llcrypt registered [ 5371.194417] Lustre: DEBUG MARKER: == conf-sanity test 29: permanently remove an OST ======== 18:56:59 (1763337419) [ 5373.154784] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5373.242979] alg: No test for adler32 (adler32-zlib) [ 5374.339728] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 5374.545214] LNet: Added LNI 192.168.201.11@tcp [8/256/0/180] [ 5376.191242] Key type lgssc registered [ 5377.319479] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5418.671651] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5421.742429] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5437.206835] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5449.300647] Lustre: Mounted lustre-client [ 5467.623081] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 5469.723213] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 5489.680880] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 5498.912819] LustreError: 144673:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c03874d9800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5498.922029] LustreError: 144673:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 5498.984808] Lustre: Unmounted lustre-client [ 5499.299032] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 5499.410590] Lustre: Mounted lustre-client [ 5507.396754] LustreError: 144817:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c038a1b9000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5507.419863] LustreError: 144817:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 5507.424791] LustreError: 144817:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 5507.497554] Lustre: Unmounted lustre-client [ 5541.868357] Key type lgssc unregistered [ 5542.089970] LNet: 145263:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5542.094684] LNetError: 145263:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5542.111260] LNet: Removed LNI 192.168.201.11@tcp [ 5542.757735] Key type .llcrypt unregistered [ 5542.762041] Key type ._llcrypt unregistered [ 5575.651605] Key type ._llcrypt registered [ 5575.653154] Key type .llcrypt registered [ 5575.916141] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5575.928614] alg: No test for adler32 (adler32-zlib) [ 5576.939629] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 5577.154722] LNet: Added LNI 192.168.201.11@tcp [8/256/0/180] [ 5578.775546] Key type lgssc registered [ 5579.428883] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5642.818969] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5646.990812] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5668.856480] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5704.034533] Lustre: DEBUG MARKER: == conf-sanity test 30a: Big config llog and permanent parameter deletion ========================================================== 19:02:32 (1763337752) [ 5727.821806] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5731.070071] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5748.526555] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5759.523167] Lustre: Mounted lustre-client [ 5921.626223] LustreError: 152754:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c03885b6000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5921.641651] LustreError: 152754:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5921.688931] Lustre: Unmounted lustre-client [ 5922.181296] Lustre: Mounted lustre-client [ 5924.784665] LustreError: 152841:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c03874de000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5924.806953] LustreError: 152841:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5924.810532] LustreError: 152841:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 5924.951429] Lustre: Unmounted lustre-client [ 5925.658399] Lustre: Mounted lustre-client [ 5927.570447] LustreError: 152916:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c03907e3800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5927.609043] LustreError: 152916:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5927.611762] LustreError: 152916:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 5927.693469] Lustre: Unmounted lustre-client [ 5953.307733] Key type lgssc unregistered [ 5953.589417] LNet: 153320:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5953.603109] LNetError: 153320:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5953.629428] LNet: Removed LNI 192.168.201.11@tcp [ 5954.223990] Key type .llcrypt unregistered [ 5954.225659] Key type ._llcrypt unregistered [ 5967.644398] Key type ._llcrypt registered [ 5967.645916] Key type .llcrypt registered [ 5968.784401] Lustre: DEBUG MARKER: == conf-sanity test 30b: Remove failover nids ============ 19:06:57 (1763338017) [ 5970.820843] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5970.829189] alg: No test for adler32 (adler32-zlib) [ 5971.903597] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 5972.094302] LNet: Added LNI 192.168.201.11@tcp [8/256/0/180] [ 5973.727180] Key type lgssc registered [ 5974.737694] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6016.805100] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6021.150514] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6038.226117] Lustre: DEBUG MARKER: oleg111-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6044.187105] Lustre: Mounted lustre-client [ 6049.837914] LustreError: 156449:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c03885b1800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6049.852758] LustreError: 156449:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6049.938700] Lustre: Unmounted lustre-client [ 6050.412739] Lustre: Mounted lustre-client [ 6051.737434] LustreError: 156525:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c03929da000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6051.754361] LustreError: 156525:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6051.758267] LustreError: 156525:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 6051.808357] Lustre: Unmounted lustre-client [ 6081.686889] Key type lgssc unregistered [ 6081.953466] LNet: 156928:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6081.959855] LNetError: 156928:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6081.988409] LNet: Removed LNI 192.168.201.11@tcp [ 6082.840264] Key type .llcrypt unregistered [ 6082.842925] Key type ._llcrypt unregistered [ 6097.423694] Key type ._llcrypt registered [ 6097.430699] Key type .llcrypt registered [ 6098.876498] Lustre: DEBUG MARKER: == conf-sanity test 31: Connect to non-existent node (shouldn't crash) ========================================================== 19:09:06 (1763338146) [ 6107.025836] Key type .llcrypt unregistered [ 6107.028166] Key type ._llcrypt unregistered [ 6131.797976] Key type ._llcrypt registered [ 6131.806743] Key type .llcrypt registered [ 6132.166706] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6132.186505] alg: No test for adler32 (adler32-zlib) [ 6133.305896] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 6133.619149] LNet: Added LNI 192.168.201.11@tcp [8/256/0/180] [ 6135.287185] Key type lgssc registered [ 6136.356460] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6180.077709] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 5910 sec ========== 19:10:28 (1763338228) [ 6181.664667] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 19:10:29 (1763338229) === [ 6185.181378] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 19:10:33 (1763338233) === [ 6220.668852] Key type lgssc unregistered [ 6221.047143] LNet: 162037:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6221.068311] LNetError: 162037:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6221.106936] LNet: Removed LNI 192.168.201.11@tcp [ 6221.744462] Key type .llcrypt unregistered [ 6221.746273] Key type ._llcrypt unregistered