[ 0.000000] Linux version 4.18.0rh8.10-debug (green@maintenance) (gcc version 8.5.0 20210514 (Red Hat 8.5.0-26) (GCC)) #2 SMP Mon Jul 14 01:24:22 EDT 2025 [ 0.000000] Command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' [ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 [ 0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. [ 0.000000] signal: max sigframe size: 1776 [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bffd9fff] usable [ 0.000000] BIOS-e820: [mem 0x00000000bffda000-0x00000000bfffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x0000000146dfffff] usable [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] SMBIOS 2.8 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.17.0-10.fc44 06/10/2025 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 571725639 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2400.000 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2464 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE2300 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 0022C0 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2374 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE2404 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE243C 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe2300-0xbffe2373] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22ff] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2374-0xbffe2403] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe2404-0xbffe243b] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe243c-0xbffe2463] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001011] APIC: Switch to symmetric I/O mode setup [ 0.002357] x2apic enabled [ 0.003008] Switched APIC routing to physical x2apic. [ 0.005009] kvm-guest: setup PV IPIs [ 0.007931] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.008000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x22983777dd9, max_idle_ns: 440795300422 ns [ 0.008022] Calibrating delay loop (skipped) preset value.. 4800.00 BogoMIPS (lpj=2400000) [ 0.009016] pid_max: default: 32768 minimum: 301 [ 0.010143] LSM: Security Framework initializing [ 0.011062] Yama: becoming mindful. [ 0.012056] SELinux: Initializing. [ 0.013094] *** VALIDATE selinux *** [ 0.021638] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.026666] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.027132] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.028097] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029124] *** VALIDATE tmpfs *** [ 0.030371] *** VALIDATE proc *** [ 0.032096] *** VALIDATE cgroup *** [ 0.032826] *** VALIDATE cgroup2 *** [ 0.034157] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.035171] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.036010] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.037029] Spectre V2 : User space: Vulnerable [ 0.038013] Speculative Store Bypass: Vulnerable [ 0.041706] debug: unmapping init [mem 0xffffffffb6259000-0xffffffffb6260fff] [ 0.044209] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.045735] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.046025] ... version: 2 [ 0.047014] ... bit width: 48 [ 0.048016] ... generic registers: 4 [ 0.049015] ... value mask: 0000ffffffffffff [ 0.050016] ... max period: 00007fffffffffff [ 0.051018] ... fixed-purpose events: 3 [ 0.052018] ... event mask: 000000070000000f [ 0.053295] rcu: Hierarchical SRCU implementation. [ 0.055572] smp: Bringing up secondary CPUs ... [ 0.056619] x86: Booting SMP configuration: [ 0.057026] .... node #0, CPUs: #1 #2 #3 [ 0.065115] smp: Brought up 1 node, 4 CPUs [ 0.067016] smpboot: Max logical packages: 1 [ 0.068012] smpboot: Total of 4 processors activated (19200.00 BogoMIPS) [ 0.111615] node 0 deferred pages initialised in 39ms [ 0.114022] devtmpfs: initialized [ 0.114815] x86/mm: Memory block size: 128MB [ 0.116241] gcov: version magic: 0x41383552 [ 0.119159] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.121061] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.123279] pinctrl core: initialized pinctrl subsystem [ 0.124103] [ 0.124372] ************************************************************* [ 0.126009] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.127006] ** ** [ 0.128009] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.130011] ** ** [ 0.131009] ** This means that this kernel is built to expose internal ** [ 0.132011] ** IOMMU data structures, which may compromise security on ** [ 0.134011] ** your system. ** [ 0.136010] ** ** [ 0.137012] ** If you see this message and you are not debugging the ** [ 0.139010] ** kernel, report this immediately to your vendor! ** [ 0.140011] ** ** [ 0.142014] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.144055] ************************************************************* [ 0.148851] NET: Registered protocol family 16 [ 0.151495] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.154071] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.157073] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.161163] cpuidle: using governor menu [ 0.162888] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.165461] PCI: Using configuration type 1 for base access [ 0.167134] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.177054] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.179086] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.181451] cryptd: max_cpu_qlen set to 1000 [ 0.184251] ACPI: Added _OSI(Module Device) [ 0.185011] ACPI: Added _OSI(Processor Device) [ 0.187013] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.188014] ACPI: Added _OSI(Processor Aggregator Device) [ 0.192265] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.197649] ACPI: Interpreter enabled [ 0.199049] ACPI: PM: (supports S0 S3 S4 S5) [ 0.200012] ACPI: Using IOAPIC for interrupt routing [ 0.201086] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.204413] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.215224] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.217043] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.220033] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.224080] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.229457] acpiphp: Slot [2] registered [ 0.230125] acpiphp: Slot [5] registered [ 0.232146] acpiphp: Slot [6] registered [ 0.234116] acpiphp: Slot [3] registered [ 0.235121] acpiphp: Slot [4] registered [ 0.237119] acpiphp: Slot [7] registered [ 0.238140] acpiphp: Slot [8] registered [ 0.240107] acpiphp: Slot [9] registered [ 0.241188] acpiphp: Slot [10] registered [ 0.242193] acpiphp: Slot [11] registered [ 0.244097] acpiphp: Slot [12] registered [ 0.246105] acpiphp: Slot [13] registered [ 0.247082] acpiphp: Slot [14] registered [ 0.248112] acpiphp: Slot [15] registered [ 0.250080] acpiphp: Slot [16] registered [ 0.251115] acpiphp: Slot [17] registered [ 0.253121] acpiphp: Slot [18] registered [ 0.254117] acpiphp: Slot [19] registered [ 0.256109] acpiphp: Slot [20] registered [ 0.258115] acpiphp: Slot [21] registered [ 0.259252] acpiphp: Slot [22] registered [ 0.261133] acpiphp: Slot [23] registered [ 0.262106] acpiphp: Slot [24] registered [ 0.264109] acpiphp: Slot [25] registered [ 0.266118] acpiphp: Slot [26] registered [ 0.268125] acpiphp: Slot [27] registered [ 0.269116] acpiphp: Slot [28] registered [ 0.270109] acpiphp: Slot [29] registered [ 0.271000] acpiphp: Slot [30] registered [ 0.271000] acpiphp: Slot [31] registered [ 0.271000] PCI host bridge to bus 0000:00 [ 0.271000] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.271000] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.271000] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.272025] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.273000] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.278029] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.280160] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.282996] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.287359] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.293015] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.296530] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.298014] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.300015] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.302020] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.304546] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.307790] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.310048] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.312832] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.316010] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.321013] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.325014] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.329998] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.337019] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.344019] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.364021] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.374601] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.379014] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.383014] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.394018] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.402000] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.404377] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.406290] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.408329] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.411264] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.418036] iommu: Default domain type: Passthrough [ 0.419499] SCSI subsystem initialized [ 0.421105] ACPI: bus type USB registered [ 0.422082] usbcore: registered new interface driver usbfs [ 0.424116] usbcore: registered new interface driver hub [ 0.426078] usbcore: registered new device driver usb [ 0.428224] pps_core: LinuxPPS API ver. 1 registered [ 0.430013] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.433074] PTP clock support registered [ 0.435125] EDAC MC: Ver: 3.0.0 [ 0.436424] PCI: Using ACPI for IRQ routing [ 0.437662] NetLabel: Initializing [ 0.439015] NetLabel: domain hash size = 128 [ 0.441013] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.443091] NetLabel: unlabeled traffic allowed by default [ 0.445149] vgaarb: loaded [ 0.447459] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.449015] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.457178] clocksource: Switched to clocksource kvm-clock [ 0.563571] VFS: Disk quotas dquot_6.6.0 [ 0.564883] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.567441] *** VALIDATE ramfs *** [ 0.568464] *** VALIDATE hugetlbfs *** [ 0.569593] pnp: PnP ACPI init [ 0.571258] pnp: PnP ACPI: found 6 devices [ 0.588916] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.591751] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.593835] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.596022] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.598309] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.600607] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.603416] NET: Registered protocol family 2 [ 0.605805] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.610472] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.613799] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.618573] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.621743] TCP: Hash tables configured (established 65536 bind 65536) [ 0.624599] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.627612] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.630370] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.633504] NET: Registered protocol family 1 [ 0.636438] RPC: Registered named UNIX socket transport module. [ 0.639065] RPC: Registered udp transport module. [ 0.640725] RPC: Registered tcp transport module. [ 0.642397] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.645040] NET: Registered protocol family 44 [ 0.646531] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.649053] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.651273] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.653826] PCI: CLS 0 bytes, default 64 [ 0.655700] Unpacking initramfs... [ 2.134817] debug: unmapping init [mem 0xffff9e2afcc64000-0xffff9e2afffcffff] [ 2.140833] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.143540] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.146874] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x22983777dd9, max_idle_ns: 440795300422 ns [ 2.632805] Initialise system trusted keyrings [ 2.634812] Key type blacklist registered [ 2.637146] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.650667] zbud: loaded [ 2.654722] *** VALIDATE nfs *** [ 2.656288] *** VALIDATE nfs4 *** [ 2.658301] pstore: using deflate compression [ 2.662694] Platform Keyring initialized [ 2.769559] NET: Registered protocol family 38 [ 2.771529] Key type asymmetric registered [ 2.772961] Asymmetric key parser 'x509' registered [ 2.774146] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.776696] io scheduler mq-deadline registered [ 2.778198] io scheduler kyber registered [ 2.779426] io scheduler bfq registered [ 2.781067] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.783427] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.785660] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.787719] ACPI: Power Button [PWRF] [ 2.792923] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.799424] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.811054] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.839256] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.866358] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.871401] Non-volatile memory driver v1.3 [ 2.873314] Linux agpgart interface v0.103 [ 2.904328] virtio_blk virtio1: [vda] 149968 512-byte logical blocks (76.8 MB/73.2 MiB) [ 2.907518] vda: detected capacity change from 0 to 76783616 [ 2.921301] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 2.924199] vdb: detected capacity change from 0 to 1073741824 [ 2.936346] libphy: Fixed MDIO Bus: probed [ 2.952665] usbcore: registered new interface driver usbserial_generic [ 2.955465] usbserial: USB Serial support registered for generic [ 2.958304] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 2.963367] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 2.965688] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 2.968289] mousedev: PS/2 mouse device common for all mice [ 2.972273] rtc_cmos 00:05: RTC can wake from S4 [ 2.976352] rtc_cmos 00:05: registered as rtc0 [ 2.978776] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 2.982176] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 2.983507] intel_pstate: CPU model not supported [ 2.990253] hid: raw HID events driver (C) Jiri Kosina [ 2.994533] usbcore: registered new interface driver usbhid [ 2.996511] usbhid: USB HID core driver [ 2.998247] drop_monitor: Initializing network drop monitor service [ 3.000717] Initializing XFRM netlink socket [ 3.002150] NET: Registered protocol family 10 [ 3.004527] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.009302] Segment Routing with IPv6 [ 3.014018] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.014350] NET: Registered protocol family 17 [ 3.020279] mpls_gso: MPLS GSO support [ 3.026267] RAS: Correctable Errors collector initialized. [ 3.028534] AVX version of gcm_enc/dec engaged. [ 3.030037] AES CTR mode by8 optimization enabled [ 3.100796] sched_clock: Marking stable (3100759003, 0)->(4041349484, -940590481) [ 3.104399] registered taskstats version 1 [ 3.106310] Loading compiled-in X.509 certificates [ 3.108107] zswap: loaded using pool lzo/zbud [ 3.133539] Key type big_key registered [ 3.144192] Key type encrypted registered [ 3.145799] ima: No TPM chip found, activating TPM-bypass! [ 3.147564] ima: Allocated hash algorithm: sha1 [ 3.148824] ima: No architecture policies found [ 3.150176] evm: Initialising EVM extended attributes: [ 3.151426] evm: security.selinux [ 3.152208] evm: security.ima [ 3.152961] evm: security.capability [ 3.153809] evm: HMAC attrs: 0x1 [ 3.155711] rtc_cmos 00:05: setting system clock to 2026-09-10 18:43:05 UTC (1789065785) [ 3.160116] debug: unmapping init [mem 0xffffffffb7203000-0xffffffffb73fffff] [ 3.162243] debug: unmapping init [mem 0xffffffffb5f82000-0xffffffffb6258fff] [ 3.175049] Write protecting the kernel read-only data: 28672k [ 3.179208] debug: unmapping init [mem 0xffffffffb4603000-0xffffffffb47fffff] [ 3.182198] debug: unmapping init [mem 0xffffffffb4f14000-0xffffffffb4ffffff] [ 3.211463] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 3.218341] systemd[1]: Detected virtualization kvm. [ 3.219645] systemd[1]: Detected architecture x86-64. [ 3.221585] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.247473] systemd[1]: No hostname configured. [ 3.249399] systemd[1]: Set hostname to . [ 3.251466] random: systemd: uninitialized urandom read (16 bytes read) [ 3.253389] systemd[1]: Initializing machine ID from random generator. [ 3.377741] random: systemd: uninitialized urandom read (16 bytes read) [ 3.381197] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. [ 3.386135] random: systemd: uninitialized urandom read (16 bytes read) [ 3.393445] systemd[1]: Starting Create list of required static device nodes for the current kernel... Starting Create list of required st…ce nodes for the current kernel... [ 3.401312] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ OK ] Listening on Journal Socket (/dev/log). Starting Journal Service... [ OK ] Started Memstrack Anylazing Service. [ OK ] Listening on udev Control Socket. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. Starting Apply Kernel Variables... [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... Starting Setup Virtual Console... [ OK ] Reached target Initrd Root Device. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Timers. [ OK ] Reached target Swap. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Setup Virtual Console. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 3.968432] device-mapper: uevent: version 1.0.3 [ 3.970124] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting dracut initqueue hook... [ 4.678499] virtio_net virtio0 ens2: renamed from eth0 [ 4.793088] scsi host0: ata_piix [ 4.810275] scsi host1: ata_piix [ 4.812067] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 4.815282] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 8.499895] dracut-initqueue[578]: RTNETLINK answers: File exists [ 9.658994] random: crng init done [ 9.660533] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 10.049402] 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... Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Timers. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Swap. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ 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 target Sockets. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 11.197955] printk: systemd: 26 output lines suppressed due to ratelimiting [ 11.429626] SELinux: Disabled at runtime. [ 11.486197] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 11.494877] systemd[1]: Detected virtualization kvm. [ 11.496795] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 11.992535] systemd[1]: initrd-switch-root.service: Succeeded. [ 11.995117] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 11.999618] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 12.004252] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 12.007456] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 12.014495] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.026099] systemd[1]: Created slice system-sshd\x2dkeygen.slice. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Started Forward Password Requests to Wall Directory Watch. Mounting POSIX Message Queue File System... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Created slice system-getty.slice. Starting Create list of required st…ce nodes for the current kernel... Activating swap /dev/disk/by-label/SWAP... [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Reached target Local Encrypted Volumes. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Created slice User and Session Slice. Starting Apply Kernel Variables... [ 12.114105] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS Mounting Kernel Debug File System... [ OK ] Reached target Paths. Mounting Huge Pages File System... Starting Remount Root and Kernel File Systems... [ OK ] Listening on udev Control Socket. [ OK ] Listening on udev Kernel Socket. Starting udev Coldplug all Devices... [ OK ] Reached target Slices. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd Root File System. [ OK ] Stopped target Initrd File Systems. [ OK ] Reached target rpc_pipefs.target. [ OK ] Listening on Process Core Dump Socket. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Started Journal Service. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted Kernel Debug File System. [ OK ] Mounted Huge Pages File System. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. Starting Configure read-only root support... [ OK ] Reached target Swap. Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... [ OK ] Mounted /mnt. [ OK ] Started udev Coldplug all Devices. [ 12.479592] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 12.767800] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 12.780882] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 12.894361] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 12.913920] EDAC sbridge: Ver: 1.1.2 [ 14.018801] Key type dns_resolver registered [ 14.315964] NFS: Registering the id_resolver key type [ 14.317698] Key type id_resolver registered [ 14.321134] Key type id_legacy registered [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting Update UTMP about System Boot/Shutdown... Starting RPC Bind... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started dnf makecache --timer. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Timers. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Restore /run/initramfs on shutdown... [ OK ] Started D-Bus System Message Bus. Starting Network Manager... Starting Login Service... [ OK ] Started irqbalance daemon. [ OK ] Reached target sshd-keygen.target. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting OpenSSH server daemon... Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... Starting Network Manager Wait Online... Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Getty on tty1. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS0. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting System Logging Service... Starting Crash recovery kernel arming... Starting Notify NFS peers of a restart... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Authorization Manager. [ OK ] Started Update UTMP about System Runlevel Changes. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg112-client login: [ 42.586869] libcfs: loading out-of-tree module taints kernel. [ 42.697348] Key type ._llcrypt registered [ 42.698928] Key type .llcrypt registered [ 43.008894] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 43.016705] alg: No test for adler32 (adler32-zlib) [ 44.044273] Lustre: Lustre: Build Version: 2.17.58_40_g706ff57 [ 44.376288] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 46.023267] Key type lgssc registered [ 46.830648] Lustre: Echo OBD driver; http://www.lustre.org/ [ 90.807304] hrtimer: interrupt took 5335583 ns [ 222.513544] Lustre: Mounted lustre-client - version 2.17.58_40_g706ff57 [ 227.654322] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 245.099518] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing check_logdir /tmp/testlogs/ [ 248.287429] Lustre: lustre-OST0000-osc-ffff9e2b42d28000: disconnect after 23s idle [ 252.016027] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing yml_node [ 257.446364] Lustre: DEBUG MARKER: Client: 2.17.58.40 [ 261.549871] Lustre: DEBUG MARKER: MDS: 2.17.58.40 [ 265.250740] Lustre: DEBUG MARKER: OSS: 2.17.58.40 [ 267.681438] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Thu Sep 10 14:47:27 EDT 2026 [ 288.661171] Lustre: DEBUG MARKER: excepting tests: 21e 32b 32c 32newtarball [ 290.401549] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 293.997643] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 304.416502] Lustre: Unmounted lustre-client [ 436.683901] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 443.536694] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 471.719782] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 514.120025] Lustre: DEBUG MARKER: == conf-sanity test 0: single mount setup ================ 14:51:34 (1789066294) [ 552.427043] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 560.548383] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 589.720132] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 595.530074] Lustre: Mounted lustre-client - version 2.17.58_40_g706ff57 [ 600.475283] Lustre: Unmounted lustre-client [ 635.816463] Key type lgssc unregistered [ 636.054981] LNet: 14703:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 636.091354] LNetError: 14703:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 636.133850] LNet: Removed LNI 192.168.201.12@tcp [ 637.266556] Key type .llcrypt unregistered [ 637.276094] Key type ._llcrypt unregistered [ 657.786241] Key type ._llcrypt registered [ 657.794814] Key type .llcrypt registered [ 659.420178] Lustre: DEBUG MARKER: == conf-sanity test 1: start up ost twice (should return errors) ========================================================== 14:53:59 (1789066439) [ 662.481332] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 662.517022] alg: No test for adler32 (adler32-zlib) [ 663.598524] Lustre: Lustre: Build Version: 2.17.58_40_g706ff57 [ 664.055648] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 665.807276] Key type lgssc registered [ 666.904557] Lustre: Echo OBD driver; http://www.lustre.org/ [ 729.047882] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 736.375824] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 760.000887] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 771.663258] Lustre: Mounted lustre-client - version 2.17.58_40_g706ff57 [ 775.001841] Lustre: Unmounted lustre-client [ 809.814339] Key type lgssc unregistered [ 810.123776] LNet: 18374:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 810.134919] LNetError: 18374:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 810.158070] LNet: Removed LNI 192.168.201.12@tcp [ 811.042722] Key type .llcrypt unregistered [ 811.046411] Key type ._llcrypt unregistered [ 827.164951] Key type ._llcrypt registered [ 827.167345] Key type .llcrypt registered [ 828.441499] Lustre: DEBUG MARKER: == conf-sanity test 2: start up mds twice (should return err) ========================================================== 14:56:49 (1789066609) [ 831.210097] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 831.246846] alg: No test for adler32 (adler32-zlib) [ 832.696709] Lustre: Lustre: Build Version: 2.17.58_40_g706ff57 [ 833.046739] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 834.769552] Key type lgssc registered [ 836.284866] Lustre: Echo OBD driver; http://www.lustre.org/ [ 895.649722] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 901.678354] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 938.324790] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 939.816847] Lustre: Mounted lustre-client - version 2.17.58_40_g706ff57 [ 942.844922] Lustre: Unmounted lustre-client [ 973.050323] Key type lgssc unregistered [ 973.332629] LNet: 22033:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 973.343842] LNetError: 22033:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 973.372486] LNet: Removed LNI 192.168.201.12@tcp [ 974.264612] Key type .llcrypt unregistered [ 974.267125] Key type ._llcrypt unregistered [ 994.839894] Key type ._llcrypt registered [ 994.845303] Key type .llcrypt registered [ 996.265093] Lustre: DEBUG MARKER: == conf-sanity test 3: mount client twice (should return err) ========================================================== 14:59:37 (1789066777) [ 998.548779] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 998.645060] alg: No test for adler32 (adler32-zlib) [ 999.810670] Lustre: Lustre: Build Version: 2.17.58_40_g706ff57 [ 1000.038259] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 1001.783298] Key type lgssc registered [ 1003.015642] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1063.508644] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1071.109884] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1101.251475] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1104.236614] Lustre: Mounted lustre-client - version 2.17.58_40_g706ff57 [ 1109.921311] Lustre: Unmounted lustre-client [ 1140.947890] Key type lgssc unregistered [ 1141.195304] LNet: 25659:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1141.201852] LNetError: 25659:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1141.215885] LNet: Removed LNI 192.168.201.12@tcp [ 1141.860730] Key type .llcrypt unregistered [ 1141.864221] Key type ._llcrypt unregistered [ 1162.092482] Key type ._llcrypt registered [ 1162.094179] Key type .llcrypt registered [ 1163.561800] Lustre: DEBUG MARKER: == conf-sanity test 4: force cleanup ost, then cleanup === 15:02:24 (1789066944) [ 1166.729498] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1166.799161] alg: No test for adler32 (adler32-zlib) [ 1167.951953] Lustre: Lustre: Build Version: 2.17.58_40_g706ff57 [ 1168.284739] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 1169.967928] Key type lgssc registered [ 1171.541917] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1231.906031] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1239.592190] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1265.992581] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1272.896390] Lustre: Mounted lustre-client - version 2.17.58_40_g706ff57 [ 1277.938164] Lustre: lustre-OST0000-osc-ffff9e2b50b69000: Connection to lustre-OST0000 (at 192.168.201.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1288.094689] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1298.436708] Lustre: Unmounted lustre-client [ 1328.843777] Key type lgssc unregistered [ 1329.175435] LNet: 29287:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1329.191713] LNetError: 29287:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1330.216448] LNet: Removed LNI 192.168.201.12@tcp [ 1331.254258] Key type .llcrypt unregistered [ 1331.256601] Key type ._llcrypt unregistered [ 1350.948483] Key type ._llcrypt registered [ 1350.952687] Key type .llcrypt registered [ 1352.889617] Lustre: DEBUG MARKER: == conf-sanity test 5a: force cleanup mds, then cleanup == 15:05:33 (1789067133) [ 1355.872292] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1355.918676] alg: No test for adler32 (adler32-zlib) [ 1357.097696] Lustre: Lustre: Build Version: 2.17.58_40_g706ff57 [ 1357.557911] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 1359.295416] Key type lgssc registered [ 1360.779537] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1419.503323] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1427.968310] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1456.942446] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1459.939678] Lustre: Mounted lustre-client - version 2.17.58_40_g706ff57 [ 1470.454869] Lustre: lustre-MDT0000-mdc-ffff9e2b50dec800: Connection to lustre-MDT0000 (at 192.168.201.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1480.695833] Lustre: lustre-MDT0001-mdc-ffff9e2b50dec800: Connection to lustre-MDT0001 (at 192.168.201.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1485.672886] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1490.913337] Lustre: 30242:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789067257/real 1789067257] req@000000007fb5a70c x1875972864350336/t0(0) o400->MGC192.168.201.112@tcp@192.168.201.112@tcp:26/25 lens 224/224 e 0 to 1 dl 1789067273 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1490.940099] LustreError: MGC192.168.201.112@tcp: Connection to MGS (at 192.168.201.112@tcp) was lost; in progress operations using this service will fail [ 1496.053070] Lustre: Unmounted lustre-client [ 1518.046320] Key type lgssc unregistered [ 1518.398365] LNet: 32941:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1518.408383] LNetError: 32941:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1519.463279] LNet: Removed LNI 192.168.201.12@tcp [ 1520.147983] Key type .llcrypt unregistered [ 1520.150280] Key type ._llcrypt unregistered [ 1541.387043] Key type ._llcrypt registered [ 1541.390223] Key type .llcrypt registered [ 1543.058434] Lustre: DEBUG MARKER: == conf-sanity test 5b: Try to start a client with no MGS (should return errs) ========================================================== 15:08:43 (1789067323) [ 1545.541054] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1545.556592] alg: No test for adler32 (adler32-zlib) [ 1546.801795] Lustre: Lustre: Build Version: 2.17.58_40_g706ff57 [ 1547.237672] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 1548.975242] Key type lgssc registered [ 1550.084419] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1637.387526] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1643.041396] LustreError: 35143:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.201.112@tcp: failed processing log, type 1: rc = -110 [ 1653.216742] LustreError: 35152:0:(mgc_request.c:614:do_requeue()) failed processing log: -110 [ 1802.208489] LustreError: MGC192.168.201.112@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 [ 1802.234479] Lustre: Unmounted lustre-client [ 1802.244126] LustreError: 35143:0:(super25.c:179:lustre_fill_super()) llite: Unable to mount : rc = -110 [ 1818.860903] Key type lgssc unregistered [ 1819.129697] LNet: 35568:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1819.138964] LNetError: 35568:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1820.200824] LNet: Removed LNI 192.168.201.12@tcp [ 1821.007532] Key type .llcrypt unregistered [ 1821.009871] Key type ._llcrypt unregistered [ 1838.588696] Key type ._llcrypt registered [ 1838.591204] Key type .llcrypt registered [ 1839.922497] Lustre: DEBUG MARKER: == conf-sanity test 5c: cleanup after failed mount (bug 2712) (should return errs) ========================================================== 15:13:40 (1789067620) [ 1842.968899] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1842.988391] alg: No test for adler32 (adler32-zlib) [ 1844.096486] Lustre: Lustre: Build Version: 2.17.58_40_g706ff57 [ 1844.431734] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 1846.111549] Key type lgssc registered [ 1847.445026] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1907.030285] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1914.663507] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1939.179869] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1939.625168] LustreError: 38663:0:(llite_lib.c:1555:ll_fill_super()) wrong.lustre: fsname longer than 8 characters: rc = -36 [ 1939.641514] Lustre: Unmounted wrong.lustre-client [ 1939.648240] LustreError: 38663:0:(super25.c:179:lustre_fill_super()) llite: Unable to mount : rc = -36 [ 1969.110686] Key type lgssc unregistered [ 1969.409882] LNet: 39079:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1969.425724] LNetError: 39079:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1969.454718] LNet: Removed LNI 192.168.201.12@tcp [ 1970.281267] Key type .llcrypt unregistered [ 1970.282913] Key type ._llcrypt unregistered [ 1987.780678] Key type ._llcrypt registered [ 1987.783298] Key type .llcrypt registered [ 1989.359709] Lustre: DEBUG MARKER: == conf-sanity test 5d: mount with ost down ============== 15:16:09 (1789067769) [ 1991.343075] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1991.426744] alg: No test for adler32 (adler32-zlib) [ 1992.358277] Lustre: Lustre: Build Version: 2.17.58_40_g706ff57 [ 1992.502827] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 1994.231243] Key type lgssc registered [ 1995.707182] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2082.414943] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2120.472334] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2126.161934] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2138.163681] Lustre: Mounted lustre-client - version 2.17.58_40_g706ff57 [ 2141.547104] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2151.955973] Lustre: Unmounted lustre-client [ 2180.554906] Key type lgssc unregistered [ 2180.822935] LNet: 42650:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2180.829419] LNetError: 42650:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2180.846148] LNet: Removed LNI 192.168.201.12@tcp [ 2181.525037] Key type .llcrypt unregistered [ 2181.526586] Key type ._llcrypt unregistered [ 2198.162906] Key type ._llcrypt registered [ 2198.165129] Key type .llcrypt registered [ 2199.271066] Lustre: DEBUG MARKER: == conf-sanity test 5e: delayed connect, don't crash (bug 10268) ========================================================== 15:19:40 (1789067980) [ 2202.235412] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2202.266281] alg: No test for adler32 (adler32-zlib) [ 2203.418759] Lustre: Lustre: Build Version: 2.17.58_40_g706ff57 [ 2203.778278] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 2205.519297] Key type lgssc registered [ 2206.603936] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2262.470820] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2269.020519] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2289.513795] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2291.168886] LustreError: 43606:0:(niobuf.c:1106:ptl_send_rpc()) cfs_fail_timeout id 506 sleeping for 10000ms [ 2295.265700] LustreError: 45734:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.201.112@tcp: failed processing log, type 1: rc = -110 [ 2301.274805] LustreError: 43606:0:(niobuf.c:1106:ptl_send_rpc()) cfs_fail_timeout id 506 awake [ 2302.577042] Lustre: Mounted lustre-client - version 2.17.58_40_g706ff57 [ 2304.472473] Lustre: Unmounted lustre-client [ 2333.795061] Key type lgssc unregistered [ 2334.118820] LNet: 46191:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2334.127904] LNetError: 46191:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2334.151866] LNet: Removed LNI 192.168.201.12@tcp [ 2334.867573] Key type .llcrypt unregistered [ 2334.869825] Key type ._llcrypt unregistered [ 2351.379629] Key type ._llcrypt registered [ 2351.386212] Key type .llcrypt registered [ 2352.616054] Lustre: DEBUG MARKER: == conf-sanity test 5f: mds down, cleanup after failed mount (bug 2712) ========================================================== 15:22:13 (1789068133) [ 2354.600331] Lustre: DEBUG MARKER: SKIP: conf-sanity test_5f needs separate mgs and mds [ 2357.097908] Lustre: DEBUG MARKER: == conf-sanity test 5g: handle missing debugfs =========== 15:22:17 (1789068137) [ 2358.344345] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2358.353374] alg: No test for adler32 (adler32-zlib) [ 2359.461417] Lustre: Lustre: Build Version: 2.17.58_40_g706ff57 [ 2359.743471] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 2361.447292] Key type lgssc registered [ 2362.909895] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2393.932798] Lustre: DEBUG MARKER: == conf-sanity test 5h: start mdt failure at mdt_fs_setup() ========================================================== 15:22:54 (1789068174) [ 2428.291094] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2436.684688] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2461.809137] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2463.528674] Lustre: Mounted lustre-client - version 2.17.58_40_g706ff57 [ 2468.835224] Lustre: lustre-MDT0000-mdc-ffff9e2b42c48000: Connection to lustre-MDT0000 (at 192.168.201.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2485.215234] Lustre: 47410:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789068251/real 1789068251] req@0000000004102d4e x1875973916069376/t0(0) o400->MGC192.168.201.112@tcp@192.168.201.112@tcp:26/25 lens 224/224 e 0 to 1 dl 1789068267 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2485.257404] LustreError: MGC192.168.201.112@tcp: Connection to MGS (at 192.168.201.112@tcp) was lost; in progress operations using this service will fail [ 2485.290300] Lustre: Evicted from MGS (at 192.168.201.112@tcp) after server handle changed from 0x54b0e0f53235933a to 0x54b0e0f5323595b0 [ 2485.305900] Lustre: MGC192.168.201.112@tcp: Connection restored to 192.168.201.112@tcp (at 192.168.201.112@tcp) [ 2490.611568] Lustre: lustre-MDT0000-mdc-ffff9e2b42c48000: Connection restored to 192.168.201.112@tcp (at 192.168.201.112@tcp) [ 2496.244378] Lustre: Unmounted lustre-client [ 2525.692879] Key type lgssc unregistered [ 2526.054952] LNet: 51047:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2526.062716] LNetError: 51047:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2526.093312] LNet: Removed LNI 192.168.201.12@tcp [ 2527.254048] Key type .llcrypt unregistered [ 2527.269214] Key type ._llcrypt unregistered [ 2543.114734] Key type ._llcrypt registered [ 2543.117555] Key type .llcrypt registered [ 2544.466701] Lustre: DEBUG MARKER: == conf-sanity test 5i: start mdt failure at mdt_quota_init() ========================================================== 15:25:25 (1789068325) [ 2546.452388] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2546.574050] alg: No test for adler32 (adler32-zlib) [ 2547.544409] Lustre: Lustre: Build Version: 2.17.58_40_g706ff57 [ 2547.753719] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 2549.407482] Key type lgssc registered [ 2550.205645] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2597.009386] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2603.846727] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2627.240555] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2630.217514] Lustre: Mounted lustre-client - version 2.17.58_40_g706ff57 [ 2640.372584] Lustre: lustre-MDT0000-mdc-ffff9e2b500de800: Connection to lustre-MDT0000 (at 192.168.201.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2655.711159] Lustre: 52008:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789068422/real 1789068422] req@00000000671cd59b x1875974113202432/t0(0) o400->MGC192.168.201.112@tcp@192.168.201.112@tcp:26/25 lens 224/224 e 0 to 1 dl 1789068438 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2655.732223] LustreError: MGC192.168.201.112@tcp: Connection to MGS (at 192.168.201.112@tcp) was lost; in progress operations using this service will fail [ 2663.178771] Lustre: lustre-MDT0000-mdc-ffff9e2b500de800: Connection restored to 192.168.201.112@tcp (at 192.168.201.112@tcp) [ 2666.225979] Lustre: Evicted from MGS (at 192.168.201.112@tcp) after server handle changed from 0xa6475fbbc27372e5 to 0xa6475fbbc2737696 [ 2666.239812] Lustre: MGC192.168.201.112@tcp: Connection restored to 192.168.201.112@tcp (at 192.168.201.112@tcp) [ 2668.165534] Lustre: Unmounted lustre-client [ 2698.336462] Key type lgssc unregistered [ 2698.739412] LNet: 55006:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2698.750257] LNetError: 55006:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2698.767470] LNet: Removed LNI 192.168.201.12@tcp [ 2699.482138] Key type .llcrypt unregistered [ 2699.484570] Key type ._llcrypt unregistered [ 2719.479520] Key type ._llcrypt registered [ 2719.482501] Key type .llcrypt registered [ 2721.303629] Lustre: DEBUG MARKER: == conf-sanity test 5j: verify libcfs doesn't crash when setting debug flags ========================================================== 15:28:21 (1789068501) [ 2721.882529] Key type .llcrypt unregistered [ 2721.884346] Key type ._llcrypt unregistered [ 2728.984309] Key type ._llcrypt registered [ 2728.987274] Key type .llcrypt registered [ 2729.053793] Key type .llcrypt unregistered [ 2729.057673] Key type ._llcrypt unregistered [ 2734.733157] Key type ._llcrypt registered [ 2734.735594] Key type .llcrypt registered [ 2736.429680] Lustre: DEBUG MARKER: == conf-sanity test 6: manual umount, then mount again === 15:28:36 (1789068516) [ 2739.049443] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2739.230091] alg: No test for adler32 (adler32-zlib) [ 2740.309979] Lustre: Lustre: Build Version: 2.17.58_40_g706ff57 [ 2740.531845] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 2742.207257] Key type lgssc registered [ 2743.365456] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2800.788213] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2808.136561] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2834.135798] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2836.949399] Lustre: Mounted lustre-client - version 2.17.58_40_g706ff57 [ 2839.228182] Lustre: Unmounted lustre-client [ 2839.559901] Lustre: Mounted lustre-client - version 2.17.58_40_g706ff57 [ 2841.305197] Lustre: Unmounted lustre-client [ 2870.636667] Key type lgssc unregistered [ 2870.850419] LNet: 59415:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2870.871038] LNetError: 59415:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2870.899552] LNet: Removed LNI 192.168.201.12@tcp [ 2871.631522] Key type .llcrypt unregistered [ 2871.640616] Key type ._llcrypt unregistered [ 2889.775816] Key type ._llcrypt registered [ 2889.782151] Key type .llcrypt registered [ 2891.430666] Lustre: DEBUG MARKER: == conf-sanity test 7: manual umount, then cleanup ======= 15:31:11 (1789068671) [ 2894.205086] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2894.333440] alg: No test for adler32 (adler32-zlib) [ 2895.571458] Lustre: Lustre: Build Version: 2.17.58_40_g706ff57 [ 2895.869890] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 2897.655446] Key type lgssc registered [ 2899.335203] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2961.251800] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2968.474974] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2991.807317] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3002.968693] Lustre: Mounted lustre-client - version 2.17.58_40_g706ff57 [ 3005.653042] Lustre: Unmounted lustre-client [ 3036.685820] Key type lgssc unregistered [ 3037.032189] LNet: 62979:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3037.039649] LNetError: 62979:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3037.072276] LNet: Removed LNI 192.168.201.12@tcp [ 3037.720348] Key type .llcrypt unregistered [ 3037.722513] Key type ._llcrypt unregistered [ 3054.848686] Key type ._llcrypt registered [ 3054.851158] Key type .llcrypt registered [ 3056.058682] Lustre: DEBUG MARKER: == conf-sanity test 8: double mount setup ================ 15:33:57 (1789068837) [ 3058.529726] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3058.576878] alg: No test for adler32 (adler32-zlib) [ 3059.711277] Lustre: Lustre: Build Version: 2.17.58_40_g706ff57 [ 3059.929139] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 3061.568074] Key type lgssc registered [ 3062.825896] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3122.994898] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3129.203859] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3151.888439] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3162.673367] Lustre: Mounted lustre-client - version 2.17.58_40_g706ff57 [ 3165.480662] Lustre: Mounted lustre-client - version 2.17.58_40_g706ff57 [ 3168.950871] Lustre: Unmounted lustre-client [ 3171.001198] Lustre: Unmounted lustre-client [ 3208.369646] Key type lgssc unregistered [ 3208.619938] LNet: 66635:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3208.625779] LNetError: 66635:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3208.644609] LNet: Removed LNI 192.168.201.12@tcp [ 3209.262657] Key type .llcrypt unregistered [ 3209.266686] Key type ._llcrypt unregistered [ 3226.733379] Key type ._llcrypt registered [ 3226.742463] Key type .llcrypt registered [ 3228.590398] Lustre: DEBUG MARKER: == conf-sanity test 9: test ptldebug and subsystem for mkfs ========================================================== 15:36:48 (1789069008) [ 3231.122763] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3231.206079] alg: No test for adler32 (adler32-zlib) [ 3232.351710] Lustre: Lustre: Build Version: 2.17.58_40_g706ff57 [ 3232.695683] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 3234.367907] Key type lgssc registered [ 3235.782963] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3322.458874] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3336.909207] Lustre: DEBUG MARKER: == conf-sanity test 10a: find lctl param broken symlinks ========================================================== 15:38:37 (1789069117) [ 3369.320728] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3376.069086] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3400.431493] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3403.130686] Lustre: Mounted lustre-client - version 2.17.58_40_g706ff57 [ 3409.536508] Lustre: Unmounted lustre-client [ 3437.268372] Key type lgssc unregistered [ 3437.519222] LNet: 71405:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3437.530278] LNetError: 71405:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3437.554274] LNet: Removed LNI 192.168.201.12@tcp [ 3438.058622] Key type .llcrypt unregistered [ 3438.060277] Key type ._llcrypt unregistered [ 3454.972720] Key type ._llcrypt registered [ 3454.977470] Key type .llcrypt registered [ 3456.519032] Lustre: DEBUG MARKER: == conf-sanity test 11: Verify tool --version option works properly ========================================================== 15:40:37 (1789069237) [ 3475.052626] Lustre: DEBUG MARKER: == conf-sanity test 17: Verify failed mds_postsetup won't fail assertion (2936) (should return errs) ========================================================== 15:40:55 (1789069255) [ 3477.819553] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3477.926681] alg: No test for adler32 (adler32-zlib) [ 3479.035901] Lustre: Lustre: Build Version: 2.17.58_40_g706ff57 [ 3479.506673] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 3481.231301] Key type lgssc registered [ 3482.509982] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3536.940965] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3543.722154] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3567.921781] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3570.758871] Lustre: Mounted lustre-client - version 2.17.58_40_g706ff57 [ 3575.962288] Lustre: Unmounted lustre-client [ 3605.184733] Key type lgssc unregistered [ 3605.612576] LNet: 75694:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3605.620136] LNetError: 75694:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3605.635281] LNet: Removed LNI 192.168.201.12@tcp [ 3606.426959] Key type .llcrypt unregistered [ 3606.431229] Key type ._llcrypt unregistered [ 3622.643088] Key type ._llcrypt registered [ 3622.644666] Key type .llcrypt registered [ 3623.227262] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3623.252818] alg: No test for adler32 (adler32-zlib) [ 3624.307246] Lustre: Lustre: Build Version: 2.17.58_40_g706ff57 [ 3624.636887] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 3626.471597] Key type lgssc registered [ 3628.304681] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3719.753900] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3848.904807] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3855.843516] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3887.257622] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3935.588921] Lustre: DEBUG MARKER: == conf-sanity test 18: check mkfs creates large journals ========================================================== 15:48:36 (1789069716) [ 3938.934841] Lustre: DEBUG MARKER: use device /dev/mapper/mds1_flakey with MIN=2000000 [ 4043.513408] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4049.891309] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4075.727789] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4158.747373] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4165.153752] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4189.172973] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4200.619603] Lustre: Mounted lustre-client - version 2.17.58_40_g706ff57 [ 4207.462704] Lustre: DEBUG MARKER: Success: mkfs creates large journals. Size: 78M [ 4210.408546] Lustre: Unmounted lustre-client [ 4240.776465] Key type lgssc unregistered [ 4241.096172] LNet: 87408:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4241.108265] LNetError: 87408:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4241.125218] LNet: Removed LNI 192.168.201.12@tcp [ 4241.772935] Key type .llcrypt unregistered [ 4241.775728] Key type ._llcrypt unregistered [ 4272.056233] Key type ._llcrypt registered [ 4272.060659] Key type .llcrypt registered [ 4272.651058] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4272.675042] alg: No test for adler32 (adler32-zlib) [ 4273.859269] Lustre: Lustre: Build Version: 2.17.58_40_g706ff57 [ 4274.216737] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 4275.983329] Key type lgssc registered [ 4277.464767] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4371.805606] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4379.050552] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4403.662965] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4450.251628] Lustre: DEBUG MARKER: == conf-sanity test 19a: start/stop MDS without OSTs ===== 15:57:10 (1789070230) [ 4489.383361] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4497.225187] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4520.875526] Lustre: DEBUG MARKER: == conf-sanity test 19b: start/stop OSTs without MDS ===== 15:58:21 (1789070301) [ 4590.534163] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4603.038699] Lustre: DEBUG MARKER: == conf-sanity test 20: remount ro,rw mounts work and doesn't break /etc/mtab ========================================================== 15:59:43 (1789070383) [ 4650.805953] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4658.033620] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4684.458649] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4687.429497] Lustre: Mounted lustre-client - version 2.17.58_40_g706ff57 [ 4688.173422] Lustre: Remounted lustre-client read-only [ 4689.994811] Lustre: Unmounted lustre-client [ 4727.501692] Lustre: DEBUG MARKER: == conf-sanity test 21a: start mds before ost, stop ost first ========================================================== 16:01:48 (1789070508) [ 4761.695147] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4769.454929] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4795.155994] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4851.659211] Lustre: DEBUG MARKER: == conf-sanity test 21b: start ost before mds, stop mds first ========================================================== 16:03:52 (1789070632) [ 4920.250822] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4961.671709] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4968.968285] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5021.024605] Lustre: DEBUG MARKER: == conf-sanity test 21c: start mds between two osts, stop mds last ========================================================== 16:06:41 (1789070801) [ 5095.329796] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5134.916842] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5140.860496] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5166.972733] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5241.014286] Lustre: DEBUG MARKER: == conf-sanity test 21d: start mgs then ost and then mds ========================================================== 16:10:21 (1789071021) [ 5242.895277] Lustre: DEBUG MARKER: SKIP: conf-sanity test_21d need separate mgs device [ 5245.056605] Lustre: DEBUG MARKER: SKIP: conf-sanity test_21e skipping excluded test 21e [ 5247.119063] Lustre: DEBUG MARKER: == conf-sanity test 22: start a client before osts (should return errs) ========================================================== 16:10:27 (1789071027) [ 5295.187351] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5301.416231] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5326.514451] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5350.471056] Lustre: Mounted lustre-client - version 2.17.58_40_g706ff57 [ 5353.099548] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5363.485181] Lustre: Unmounted lustre-client [ 5389.414897] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5390.296863] Lustre: Mounted lustre-client - version 2.17.58_40_g706ff57 [ 5408.916859] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9e2b515f4800.ost_server_uuid 50 [ 5410.628705] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9e2b515f4800.ost_server_uuid in FULL state after 0 sec [ 5413.612493] Lustre: Unmounted lustre-client [ 5455.909344] Key type lgssc unregistered [ 5456.190869] LNet: 107800:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5456.200379] LNetError: 107800:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5456.226679] LNet: Removed LNI 192.168.201.12@tcp [ 5456.939674] Key type .llcrypt unregistered [ 5456.943604] Key type ._llcrypt unregistered [ 5475.185730] Key type ._llcrypt registered [ 5475.188524] Key type .llcrypt registered [ 5476.872913] Lustre: DEBUG MARKER: == conf-sanity test 23a: interrupt client during recovery mount delay ========================================================== 16:14:17 (1789071257) [ 5479.407983] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5479.457817] alg: No test for adler32 (adler32-zlib) [ 5480.593439] Lustre: Lustre: Build Version: 2.17.58_40_g706ff57 [ 5480.864931] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 5482.575279] Key type lgssc registered [ 5484.048379] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5550.620191] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5557.953360] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5580.382448] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5582.988079] Lustre: Mounted lustre-client - version 2.17.58_40_g706ff57 [ 5587.973226] Lustre: lustre-MDT0000-mdc-ffff9e2b44b44000: Connection to lustre-MDT0000 (at 192.168.201.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5590.359530] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5600.708645] LustreError: MGC192.168.201.112@tcp: Connection to MGS (at 192.168.201.112@tcp) was lost; in progress operations using this service will fail [ 5600.748662] Lustre: Unmounted lustre-client [ 5626.520826] LustreError: lustre-MDT0000-mdc-ffff9e2b50df1000: operation mds_connect to node 192.168.201.112@tcp failed: rc = -16 [ 5631.708058] LustreError: 111183:0:(lmv_obd.c:1471:lmv_statfs()) lustre-MDT0000-mdc-ffff9e2b50df1000: can't stat MDS #0: rc = -16 [ 5631.846141] Lustre: Unmounted lustre-client [ 5631.900213] LustreError: 111183:0:(super25.c:179:lustre_fill_super()) llite: Unable to mount : rc = -16 [ 5666.560604] Key type lgssc unregistered [ 5666.857342] LNet: 111635:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5666.865537] LNetError: 111635:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5666.884694] LNet: Removed LNI 192.168.201.12@tcp [ 5667.594156] Key type .llcrypt unregistered [ 5667.595721] Key type ._llcrypt unregistered [ 5683.348873] Key type ._llcrypt registered [ 5683.352972] Key type .llcrypt registered [ 5685.220464] Lustre: DEBUG MARKER: == conf-sanity test 23b: Simulate -EINTR during mount ==== 16:17:45 (1789071465) [ 5688.143176] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5688.303321] alg: No test for adler32 (adler32-zlib) [ 5689.753426] Lustre: Lustre: Build Version: 2.17.58_40_g706ff57 [ 5690.126055] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 5691.871984] Key type lgssc registered [ 5693.472493] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5753.363624] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5759.819899] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5784.766168] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5795.947450] Lustre: Mounted lustre-client - version 2.17.58_40_g706ff57 [ 5798.433499] Lustre: Unmounted lustre-client [ 5830.071972] Key type lgssc unregistered [ 5830.447072] LNet: 115168:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5830.482576] LNetError: 115168:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5830.515357] LNet: Removed LNI 192.168.201.12@tcp [ 5831.615933] Key type .llcrypt unregistered [ 5831.620314] Key type ._llcrypt unregistered [ 5851.628232] Key type ._llcrypt registered [ 5851.630776] Key type .llcrypt registered [ 5853.098666] Lustre: DEBUG MARKER: == conf-sanity test 24a: Multiple MDTs on a single node == 16:20:33 (1789071633) [ 5855.864561] Lustre: DEBUG MARKER: SKIP: conf-sanity test_24a mixed loopback and real device not working [ 5858.102383] Lustre: DEBUG MARKER: == conf-sanity test 24b: Multiple MGSs on a single node (should return err) ========================================================== 16:20:38 (1789071638) [ 5861.513919] Lustre: DEBUG MARKER: SKIP: conf-sanity test_24b mixed loopback and real device not working [ 5863.480993] Lustre: DEBUG MARKER: == conf-sanity test 25: Verify modules are referenced ==== 16:20:44 (1789071644) [ 5867.622799] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5867.725659] alg: No test for adler32 (adler32-zlib) [ 5869.169736] Lustre: Lustre: Build Version: 2.17.58_40_g706ff57 [ 5869.536849] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 5871.279224] Key type lgssc registered [ 5873.156878] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5944.565542] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5952.236407] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5975.133424] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5981.265958] Lustre: Mounted lustre-client - version 2.17.58_40_g706ff57 [ 5984.085529] Lustre: Unmounted lustre-client [ 6017.311283] Key type lgssc unregistered [ 6017.708102] LNet: 119356:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6017.724561] LNetError: 119356:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6017.762351] LNet: Removed LNI 192.168.201.12@tcp [ 6018.857607] Key type .llcrypt unregistered [ 6018.861507] Key type ._llcrypt unregistered [ 6041.704626] Key type ._llcrypt registered [ 6041.707555] Key type .llcrypt registered [ 6043.013785] Lustre: DEBUG MARKER: == conf-sanity test 26: MDT startup failure cleans LOV (should return errs) ========================================================== 16:23:43 (1789071823) [ 6044.467058] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6044.662333] alg: No test for adler32 (adler32-zlib) [ 6045.654504] Lustre: Lustre: Build Version: 2.17.58_40_g706ff57 [ 6045.949587] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 6047.743955] Key type lgssc registered [ 6049.988874] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6123.686426] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6130.657299] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6150.664556] Key type lgssc unregistered [ 6151.032248] LNet: 122438:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6151.051055] LNetError: 122438:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6151.095883] LNet: Removed LNI 192.168.201.12@tcp [ 6151.832707] Key type .llcrypt unregistered [ 6151.837487] Key type ._llcrypt unregistered [ 6167.505657] Key type ._llcrypt registered [ 6167.509127] Key type .llcrypt registered [ 6168.903635] Lustre: DEBUG MARKER: == conf-sanity test 27a: Reacquire MGS lock if OST started first ========================================================== 16:25:49 (1789071949) [ 6178.081341] Key type .llcrypt unregistered [ 6178.083483] Key type ._llcrypt unregistered [ 6187.020644] Key type ._llcrypt registered [ 6187.024957] Key type .llcrypt registered [ 6187.741284] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6187.756633] alg: No test for adler32 (adler32-zlib) [ 6188.884308] Lustre: Lustre: Build Version: 2.17.58_40_g706ff57 [ 6189.194616] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 6190.879406] Key type lgssc registered [ 6192.323707] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6282.070116] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6318.810963] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6325.285347] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6368.867291] Key type lgssc unregistered [ 6369.661678] LNet: 126289:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6369.673639] LNetError: 126289:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6369.686566] LNet: Removed LNI 192.168.201.12@tcp [ 6370.665790] Key type .llcrypt unregistered [ 6370.668383] Key type ._llcrypt unregistered [ 6396.984251] Key type ._llcrypt registered [ 6396.990369] Key type .llcrypt registered [ 6398.628983] Lustre: DEBUG MARKER: == conf-sanity test 27b: Reacquire MGS lock after failover ========================================================== 16:29:39 (1789072179) [ 6402.538211] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6402.554131] alg: No test for adler32 (adler32-zlib) [ 6403.750733] Lustre: Lustre: Build Version: 2.17.58_40_g706ff57 [ 6404.005404] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 6405.688403] Key type lgssc registered [ 6407.101374] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6464.798914] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6471.414504] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6500.366780] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6506.592246] Lustre: Mounted lustre-client - version 2.17.58_40_g706ff57 [ 6511.591898] Lustre: lustre-MDT0000-mdc-ffff9e2b42c48000: Connection to lustre-MDT0000 (at 192.168.201.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6526.943301] Lustre: 127263:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789072293/real 1789072293] req@00000000c9913913 x1875978156510464/t0(0) o400->MGC192.168.201.112@tcp@192.168.201.112@tcp:26/25 lens 224/224 e 0 to 1 dl 1789072309 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 6526.998065] LustreError: MGC192.168.201.112@tcp: Connection to MGS (at 192.168.201.112@tcp) was lost; in progress operations using this service will fail [ 6537.205251] Lustre: Evicted from MGS (at 192.168.201.112@tcp) after server handle changed from 0x89c5451a4d6e5552 to 0x89c5451a4d6e568d [ 6537.221497] Lustre: MGC192.168.201.112@tcp: Connection restored to 192.168.201.112@tcp (at 192.168.201.112@tcp) [ 6545.691102] Lustre: lustre-MDT0000-mdc-ffff9e2b42c48000: Connection restored to 192.168.201.112@tcp (at 192.168.201.112@tcp) [ 6575.355681] Lustre: Unmounted lustre-client [ 6606.872648] Key type lgssc unregistered [ 6607.053598] LNet: 130288:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6607.058274] LNetError: 130288:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6607.070347] LNet: Removed LNI 192.168.201.12@tcp [ 6607.452473] Key type .llcrypt unregistered [ 6607.454673] Key type ._llcrypt unregistered [ 6632.204217] Key type ._llcrypt registered [ 6632.206222] Key type .llcrypt registered [ 6633.493854] Lustre: DEBUG MARKER: == conf-sanity test 28A: permanent parameter setting ===== 16:33:34 (1789072414) [ 6637.368552] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6637.380483] alg: No test for adler32 (adler32-zlib) [ 6638.721564] Lustre: Lustre: Build Version: 2.17.58_40_g706ff57 [ 6639.113497] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 6640.847214] Key type lgssc registered [ 6641.974692] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6698.703449] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6705.450350] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6728.408452] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6730.302800] Lustre: Mounted lustre-client - version 2.17.58_40_g706ff57 [ 6747.887840] Lustre: Unmounted lustre-client [ 6748.783106] Lustre: Mounted lustre-client - version 2.17.58_40_g706ff57 [ 6762.402750] Lustre: Unmounted lustre-client [ 6791.103166] Key type lgssc unregistered [ 6791.448726] LNet: 134505:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6791.458481] LNetError: 134505:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6791.474357] LNet: Removed LNI 192.168.201.12@tcp [ 6792.098465] Key type .llcrypt unregistered [ 6792.106584] Key type ._llcrypt unregistered [ 6804.451455] Key type ._llcrypt registered [ 6804.464328] Key type .llcrypt registered [ 6805.946817] Lustre: DEBUG MARKER: == conf-sanity test 28a: set symlink parameters permanently with lctl ========================================================== 16:36:26 (1789072586) [ 6809.938534] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6810.083824] alg: No test for adler32 (adler32-zlib) [ 6811.390299] Lustre: Lustre: Build Version: 2.17.58_40_g706ff57 [ 6811.907435] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 6813.714256] Key type lgssc registered [ 6816.195906] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6886.746942] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6892.993907] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6919.221793] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6922.614236] Lustre: Mounted lustre-client - version 2.17.58_40_g706ff57 [ 6963.074959] Lustre: Unmounted lustre-client [ 6998.319766] Key type lgssc unregistered [ 6998.630830] LNet: 137869:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6998.638440] LNetError: 137869:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6998.670094] LNet: Removed LNI 192.168.201.12@tcp [ 7000.020305] Key type .llcrypt unregistered [ 7000.023411] Key type ._llcrypt unregistered [ 7020.889890] Key type ._llcrypt registered [ 7020.893827] Key type .llcrypt registered [ 7021.852779] Lustre: DEBUG MARKER: == conf-sanity test 28b: verify client-side parameters are set upon mount ========================================================== 16:40:03 (1789072803) [ 7024.081022] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7024.184048] alg: No test for adler32 (adler32-zlib) [ 7025.496860] Lustre: Lustre: Build Version: 2.17.58_40_g706ff57 [ 7025.941668] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 7027.737085] Key type lgssc registered [ 7029.596858] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7097.092914] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7104.294541] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7133.682104] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7140.978406] Lustre: Mounted lustre-client - version 2.17.58_40_g706ff57 [ 7143.231733] Lustre: Unmounted lustre-client [ 7143.637768] Lustre: Mounted lustre-client - version 2.17.58_40_g706ff57 [ 7153.352758] Lustre: Unmounted lustre-client [ 7192.229868] Key type lgssc unregistered [ 7192.513880] LNet: 141920:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7192.519353] LNetError: 141920:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7192.538875] LNet: Removed LNI 192.168.201.12@tcp [ 7193.238753] Key type .llcrypt unregistered [ 7193.242275] Key type ._llcrypt unregistered [ 7205.930746] Key type ._llcrypt registered [ 7205.932937] Key type .llcrypt registered [ 7207.468074] Lustre: DEBUG MARKER: == conf-sanity test 28c: verify filesystem parameters are set upon mount ========================================================== 16:43:08 (1789072988) [ 7209.762253] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7209.934068] alg: No test for adler32 (adler32-zlib) [ 7211.123820] Lustre: Lustre: Build Version: 2.17.58_40_g706ff57 [ 7211.396421] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 7213.095246] Key type lgssc registered [ 7214.383546] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7271.009918] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7281.060617] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7314.873791] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7316.770837] Lustre: Mounted lustre-client - version 2.17.58_40_g706ff57 [ 7320.103600] Lustre: Unmounted lustre-client [ 7320.571206] Lustre: Mounted lustre-client - version 2.17.58_40_g706ff57 [ 7329.806111] Lustre: Unmounted lustre-client [ 7370.221287] Key type lgssc unregistered [ 7370.501701] LNet: 145547:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7370.518595] LNetError: 145547:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7370.552512] LNet: Removed LNI 192.168.201.12@tcp [ 7371.770624] Key type .llcrypt unregistered [ 7371.777717] Key type ._llcrypt unregistered [ 7384.972406] Key type ._llcrypt registered [ 7384.983260] Key type .llcrypt registered [ 7386.811991] Lustre: DEBUG MARKER: == conf-sanity test 29: permanently remove an OST ======== 16:46:07 (1789073167) [ 7390.193691] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7390.231667] alg: No test for adler32 (adler32-zlib) [ 7391.312863] Lustre: Lustre: Build Version: 2.17.58_40_g706ff57 [ 7391.630041] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 7393.368512] Key type lgssc registered [ 7394.200033] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7453.193846] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7459.738825] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7483.882542] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7487.039796] Lustre: Mounted lustre-client - version 2.17.58_40_g706ff57 [ 7516.873085] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7519.287322] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7541.771115] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 7549.659744] Lustre: Unmounted lustre-client [ 7550.167589] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 7550.384519] Lustre: Mounted lustre-client - version 2.17.58_40_g706ff57 [ 7557.806338] Lustre: Unmounted lustre-client [ 7596.022802] Key type lgssc unregistered [ 7596.403037] LNet: 149593:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7596.423924] LNetError: 149593:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7597.481783] LNet: Removed LNI 192.168.201.12@tcp [ 7598.064870] Key type .llcrypt unregistered [ 7598.066640] Key type ._llcrypt unregistered [ 7637.067749] Key type ._llcrypt registered [ 7637.071450] Key type .llcrypt registered [ 7638.882926] Lustre: DEBUG MARKER: == conf-sanity test 30a: Big config llog and permanent parameter deletion ========================================================== 16:50:19 (1789073419) [ 7641.330138] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7641.558687] alg: No test for adler32 (adler32-zlib) [ 7642.843288] Lustre: Lustre: Build Version: 2.17.58_40_g706ff57 [ 7643.157741] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 7644.871251] Key type lgssc registered [ 7646.188689] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7703.277769] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7710.406272] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7739.663610] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7742.811112] Lustre: Mounted lustre-client - version 2.17.58_40_g706ff57 [ 7910.214662] Lustre: Unmounted lustre-client [ 7910.536683] Lustre: Mounted lustre-client - version 2.17.58_40_g706ff57 [ 7915.220390] Lustre: Unmounted lustre-client [ 7915.724528] Lustre: Mounted lustre-client - version 2.17.58_40_g706ff57 [ 7917.917175] Lustre: Unmounted lustre-client [ 7948.033750] Key type lgssc unregistered [ 7948.359881] LNet: 154826:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7948.373783] LNetError: 154826:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7948.398323] LNet: Removed LNI 192.168.201.12@tcp [ 7949.237880] Key type .llcrypt unregistered [ 7949.240170] Key type ._llcrypt unregistered [ 7970.269760] Key type ._llcrypt registered [ 7970.277887] Key type .llcrypt registered [ 7972.381579] Lustre: DEBUG MARKER: == conf-sanity test 30b: Remove failover nids ============ 16:55:52 (1789073752) [ 7976.042342] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7976.078345] alg: No test for adler32 (adler32-zlib) [ 7977.111223] Lustre: Lustre: Build Version: 2.17.58_40_g706ff57 [ 7977.366095] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 7979.071367] Key type lgssc registered [ 7980.630799] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8053.394448] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8059.313578] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8085.668902] Lustre: DEBUG MARKER: oleg112-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8091.767804] Lustre: Mounted lustre-client - version 2.17.58_40_g706ff57 [ 8110.082587] Lustre: Unmounted lustre-client [ 8110.475980] Lustre: Mounted lustre-client - version 2.17.58_40_g706ff57 [ 8112.110225] Lustre: Unmounted lustre-client [ 8152.622978] Key type lgssc unregistered [ 8152.865369] LNet: 158599:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8152.872434] LNetError: 158599:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8152.887022] LNet: Removed LNI 192.168.201.12@tcp [ 8153.581676] Key type .llcrypt unregistered [ 8153.584302] Key type ._llcrypt unregistered [ 8179.220890] Key type ._llcrypt registered [ 8179.222468] Key type .llcrypt registered [ 8180.950201] Lustre: DEBUG MARKER: == conf-sanity test 31: Connect to non-existent node (shouldn't crash) ========================================================== 16:59:21 (1789073961) [ 8182.530234] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8182.616391] alg: No test for adler32 (adler32-zlib) [ 8183.572428] Lustre: Lustre: Build Version: 2.17.58_40_g706ff57 [ 8183.766738] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 8185.423291] Key type lgssc registered [ 8186.684718] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8216.096066] LustreError: 160293:0:(mgc_request.c:233:do_config_log_add()) MGC4.3.2.1@tcp: failed processing log, type 1: rc = -110 [ 8223.199646] LustreError: 160302:0:(mgc_request.c:614:do_requeue()) failed processing log: -110 [ 8261.599468] LNet: 1 local NIs in recovery (showing 1): 192.168.201.12@tcp [ 8340.511616] LNet: 1 local NIs in recovery (showing 1): 192.168.201.12@tcp [ 8372.192846] LustreError: MGC4.3.2.1@tcp: Confguration from log lustre-client failed from MGS -110. Communication error between node & MGS, a bad configuration, or other errors. See syslog for more info [ 8372.214288] Lustre: Unmounted lustre-client [ 8372.219573] LustreError: 160293:0:(super25.c:179:lustre_fill_super()) llite: Unable to mount : rc = -110 [ 8392.894575] Key type lgssc unregistered [ 8403.041322] LNet: 1 local NIs in recovery (showing 1): 192.168.201.12@tcp [ 8403.682427] LNet: 160722:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8403.692300] LNetError: 160722:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8472.300323] LNet: Removed LNI 192.168.201.12@tcp [ 8473.295072] Key type .llcrypt unregistered [ 8473.303878] Key type ._llcrypt unregistered [ 8516.512334] Key type ._llcrypt registered [ 8516.517740] Key type .llcrypt registered [ 8516.881161] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8516.893592] alg: No test for adler32 (adler32-zlib) [ 8518.213530] Lustre: Lustre: Build Version: 2.17.58_40_g706ff57 [ 8518.534410] LNet: Added LNI 192.168.201.12@tcp [8/256/0/180] [ 8520.279258] Key type lgssc registered [ 8522.477385] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8582.297907] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 8312 sec ========== 17:06:02 (1789074362) [ 8584.096437] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 17:06:04 (1789074364) === [ 8587.412133] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 17:06:08 (1789074368) === [ 8626.835151] Key type lgssc unregistered [ 8627.200721] LNet: 164960:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8627.213414] LNetError: 164960:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8627.254681] LNet: Removed LNI 192.168.201.12@tcp [ 8628.069630] Key type .llcrypt unregistered [ 8628.071548] Key type ._llcrypt unregistered