[ 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 371025420 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.996 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: 2895288K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001010] APIC: Switch to symmetric I/O mode setup [ 0.003323] x2apic enabled [ 0.004005] Switched APIC routing to physical x2apic. [ 0.005010] kvm-guest: setup PV IPIs [ 0.008000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.008000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229833f6470, max_idle_ns: 440795327230 ns [ 0.008019] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399996) [ 0.009007] pid_max: default: 32768 minimum: 301 [ 0.010159] LSM: Security Framework initializing [ 0.011000] Yama: becoming mindful. [ 0.011028] SELinux: Initializing. [ 0.012040] *** VALIDATE selinux *** [ 0.020121] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.024194] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.025150] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.026104] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.027068] *** VALIDATE tmpfs *** [ 0.028322] *** VALIDATE proc *** [ 0.029132] *** VALIDATE cgroup *** [ 0.030004] *** VALIDATE cgroup2 *** [ 0.031178] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.033033] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.034003] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.035022] Spectre V2 : User space: Vulnerable [ 0.036004] Speculative Store Bypass: Vulnerable [ 0.037261] debug: unmapping init [mem 0xffffffff94259000-0xffffffff94260fff] [ 0.039156] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.040611] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.041014] ... version: 2 [ 0.041973] ... bit width: 48 [ 0.042010] ... generic registers: 4 [ 0.043008] ... value mask: 0000ffffffffffff [ 0.044008] ... max period: 00007fffffffffff [ 0.045007] ... fixed-purpose events: 3 [ 0.046007] ... event mask: 000000070000000f [ 0.047293] rcu: Hierarchical SRCU implementation. [ 0.049404] smp: Bringing up secondary CPUs ... [ 0.050429] x86: Booting SMP configuration: [ 0.051013] .... node #0, CPUs: #1 #2 #3 [ 0.054017] smp: Brought up 1 node, 4 CPUs [ 0.056008] smpboot: Max logical packages: 1 [ 0.057017] smpboot: Total of 4 processors activated (19199.96 BogoMIPS) [ 0.239416] node 0 deferred pages initialised in 180ms [ 0.243256] devtmpfs: initialized [ 0.244222] x86/mm: Memory block size: 128MB [ 0.246662] gcov: version magic: 0x41383552 [ 0.252273] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.255055] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.257246] pinctrl core: initialized pinctrl subsystem [ 0.259101] [ 0.259644] ************************************************************* [ 0.261008] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.263007] ** ** [ 0.265008] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.266008] ** ** [ 0.268007] ** This means that this kernel is built to expose internal ** [ 0.270007] ** IOMMU data structures, which may compromise security on ** [ 0.271006] ** your system. ** [ 0.273007] ** ** [ 0.275011] ** If you see this message and you are not debugging the ** [ 0.277009] ** kernel, report this immediately to your vendor! ** [ 0.278005] ** ** [ 0.280008] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.282008] ************************************************************* [ 0.284555] NET: Registered protocol family 16 [ 0.285383] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.287034] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.289036] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.292350] cpuidle: using governor menu [ 0.293538] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.295144] PCI: Using configuration type 1 for base access [ 0.296112] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.306105] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.307015] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.308145] cryptd: max_cpu_qlen set to 1000 [ 0.310083] ACPI: Added _OSI(Module Device) [ 0.311018] ACPI: Added _OSI(Processor Device) [ 0.312011] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.314009] ACPI: Added _OSI(Processor Aggregator Device) [ 0.317170] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.325578] ACPI: Interpreter enabled [ 0.326046] ACPI: PM: (supports S0 S3 S4 S5) [ 0.329039] ACPI: Using IOAPIC for interrupt routing [ 0.331156] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.335826] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.346786] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.349023] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.352015] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.355072] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.360396] acpiphp: Slot [2] registered [ 0.361081] acpiphp: Slot [5] registered [ 0.361952] acpiphp: Slot [6] registered [ 0.363063] acpiphp: Slot [3] registered [ 0.363940] acpiphp: Slot [4] registered [ 0.365048] acpiphp: Slot [7] registered [ 0.365982] acpiphp: Slot [8] registered [ 0.367047] acpiphp: Slot [9] registered [ 0.367930] acpiphp: Slot [10] registered [ 0.368058] acpiphp: Slot [11] registered [ 0.368972] acpiphp: Slot [12] registered [ 0.370046] acpiphp: Slot [13] registered [ 0.370949] acpiphp: Slot [14] registered [ 0.372041] acpiphp: Slot [15] registered [ 0.374077] acpiphp: Slot [16] registered [ 0.375068] acpiphp: Slot [17] registered [ 0.377104] acpiphp: Slot [18] registered [ 0.378045] acpiphp: Slot [19] registered [ 0.379069] acpiphp: Slot [20] registered [ 0.381064] acpiphp: Slot [21] registered [ 0.382055] acpiphp: Slot [22] registered [ 0.382898] acpiphp: Slot [23] registered [ 0.384078] acpiphp: Slot [24] registered [ 0.384928] acpiphp: Slot [25] registered [ 0.386064] acpiphp: Slot [26] registered [ 0.388064] acpiphp: Slot [27] registered [ 0.389061] acpiphp: Slot [28] registered [ 0.390063] acpiphp: Slot [29] registered [ 0.392071] acpiphp: Slot [30] registered [ 0.393080] acpiphp: Slot [31] registered [ 0.395046] PCI host bridge to bus 0000:00 [ 0.396011] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.398012] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.399011] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.402014] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.404010] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.406011] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.407118] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.409505] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.412006] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.418469] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.421520] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.423010] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.425011] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.427012] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.430563] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.433748] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.436032] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.438663] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.441738] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.449865] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.454010] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.458996] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.464000] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.467015] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.483019] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.485000] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.490014] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.499027] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.509018] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.525113] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.527287] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.528238] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.530248] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.532138] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.535007] iommu: Default domain type: Passthrough [ 0.536443] SCSI subsystem initialized [ 0.537112] ACPI: bus type USB registered [ 0.538085] usbcore: registered new interface driver usbfs [ 0.540054] usbcore: registered new interface driver hub [ 0.541061] usbcore: registered new device driver usb [ 0.542129] pps_core: LinuxPPS API ver. 1 registered [ 0.543008] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.546097] PTP clock support registered [ 0.549041] EDAC MC: Ver: 3.0.0 [ 0.550371] PCI: Using ACPI for IRQ routing [ 0.552908] NetLabel: Initializing [ 0.554017] NetLabel: domain hash size = 128 [ 0.556015] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.558108] NetLabel: unlabeled traffic allowed by default [ 0.560195] vgaarb: loaded [ 0.562353] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.564011] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.571319] clocksource: Switched to clocksource kvm-clock [ 0.670979] VFS: Disk quotas dquot_6.6.0 [ 0.672145] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.674293] *** VALIDATE ramfs *** [ 0.675405] *** VALIDATE hugetlbfs *** [ 0.676709] pnp: PnP ACPI init [ 0.678872] pnp: PnP ACPI: found 6 devices [ 0.698283] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.701081] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.702891] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.704614] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.706558] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.708492] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.710621] NET: Registered protocol family 2 [ 0.712526] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.716198] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.718996] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.723392] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.726166] TCP: Hash tables configured (established 65536 bind 65536) [ 0.728501] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.730772] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.732983] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.735291] NET: Registered protocol family 1 [ 0.737183] RPC: Registered named UNIX socket transport module. [ 0.738770] RPC: Registered udp transport module. [ 0.740110] RPC: Registered tcp transport module. [ 0.741632] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.743709] NET: Registered protocol family 44 [ 0.744674] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.746624] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.748701] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.750928] PCI: CLS 0 bytes, default 64 [ 0.752112] Unpacking initramfs... [ 2.053185] debug: unmapping init [mem 0xffff98977cc64000-0xffff98977ffcffff] [ 2.056567] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.058275] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.060592] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229833f6470, max_idle_ns: 440795327230 ns [ 2.508966] Initialise system trusted keyrings [ 2.510549] Key type blacklist registered [ 2.512423] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.525122] zbud: loaded [ 2.528104] *** VALIDATE nfs *** [ 2.529168] *** VALIDATE nfs4 *** [ 2.530758] pstore: using deflate compression [ 2.535748] Platform Keyring initialized [ 2.631782] NET: Registered protocol family 38 [ 2.633367] Key type asymmetric registered [ 2.634846] Asymmetric key parser 'x509' registered [ 2.636773] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.639463] io scheduler mq-deadline registered [ 2.640949] io scheduler kyber registered [ 2.642573] io scheduler bfq registered [ 2.644796] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.647889] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.651626] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.655881] ACPI: Power Button [PWRF] [ 2.660021] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.665579] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.673637] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.699445] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.726084] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.730801] Non-volatile memory driver v1.3 [ 2.733073] Linux agpgart interface v0.103 [ 2.796767] virtio_blk virtio1: [vda] 145248 512-byte logical blocks (74.4 MB/70.9 MiB) [ 2.800715] vda: detected capacity change from 0 to 74366976 [ 2.815182] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 2.817511] vdb: detected capacity change from 0 to 1073741824 [ 2.823083] libphy: Fixed MDIO Bus: probed [ 2.840715] usbcore: registered new interface driver usbserial_generic [ 2.846114] usbserial: USB Serial support registered for generic [ 2.848475] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 2.856143] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 2.859110] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 2.862444] mousedev: PS/2 mouse device common for all mice [ 2.866750] rtc_cmos 00:05: RTC can wake from S4 [ 2.869954] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 2.872627] rtc_cmos 00:05: registered as rtc0 [ 2.874746] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 2.875230] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 2.880226] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 2.882420] intel_pstate: CPU model not supported [ 2.888921] hid: raw HID events driver (C) Jiri Kosina [ 2.890142] usbcore: registered new interface driver usbhid [ 2.891438] usbhid: USB HID core driver [ 2.894651] drop_monitor: Initializing network drop monitor service [ 2.897290] Initializing XFRM netlink socket [ 2.898879] NET: Registered protocol family 10 [ 2.901357] Segment Routing with IPv6 [ 2.902297] NET: Registered protocol family 17 [ 2.903793] mpls_gso: MPLS GSO support [ 2.907786] RAS: Correctable Errors collector initialized. [ 2.908971] AVX version of gcm_enc/dec engaged. [ 2.909992] AES CTR mode by8 optimization enabled [ 2.969993] sched_clock: Marking stable (2969973309, 0)->(3726660825, -756687516) [ 2.973191] registered taskstats version 1 [ 2.974893] Loading compiled-in X.509 certificates [ 2.976838] zswap: loaded using pool lzo/zbud [ 3.011666] Key type big_key registered [ 3.023906] Key type encrypted registered [ 3.025529] ima: No TPM chip found, activating TPM-bypass! [ 3.027320] ima: Allocated hash algorithm: sha1 [ 3.028964] ima: No architecture policies found [ 3.030529] evm: Initialising EVM extended attributes: [ 3.032221] evm: security.selinux [ 3.033267] evm: security.ima [ 3.034174] evm: security.capability [ 3.035457] evm: HMAC attrs: 0x1 [ 3.037586] rtc_cmos 00:05: setting system clock to 2026-06-29 17:57:47 UTC (1782755867) [ 3.044775] debug: unmapping init [mem 0xffffffff95203000-0xffffffff953fffff] [ 3.047850] debug: unmapping init [mem 0xffffffff93f82000-0xffffffff94258fff] [ 3.060104] Write protecting the kernel read-only data: 28672k [ 3.063579] debug: unmapping init [mem 0xffffffff92603000-0xffffffff927fffff] [ 3.066178] debug: unmapping init [mem 0xffffffff92f14000-0xffffffff92ffffff] [ 3.092787] 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.100978] systemd[1]: Detected virtualization kvm. [ 3.103561] systemd[1]: Detected architecture x86-64. [ 3.105772] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.133928] systemd[1]: No hostname configured. [ 3.136332] systemd[1]: Set hostname to . [ 3.138352] random: systemd: uninitialized urandom read (16 bytes read) [ 3.140671] systemd[1]: Initializing machine ID from random generator. [ 3.193325] random: ln: uninitialized urandom read (6 bytes read) [ 3.269586] random: systemd: uninitialized urandom read (16 bytes read) [ 3.270923] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ 3.273462] systemd[1]: Reached target Timers. [ OK ] Reached target Timers. [ 3.275907] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Initrd Root Device. [ OK ] Reached target Paths. [ OK ] Reached target Swap. [ OK ] Listening on udev Control Socket. [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on Journal Socket. Starting Journal Service... [ OK ] Reached target Sockets. [ OK ] Started Memstrack Anylazing Service. Starting Setup Virtual Console... Starting Create list of required st…ce nodes for the current kernel... Starting Create Volatile Files and Directories... Starting Apply Kernel Variables... [ OK ] Started Setup Virtual Console. [ OK ] Started Create list of required sta…vice nodes for the current kernel. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Apply Kernel Variables. [ OK ] Started Journal Service. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started dracut cmdline hook. [ 3.842058] random: fast init done Starting dracut pre-udev hook... [ 3.888702] device-mapper: uevent: version 1.0.3 [ 3.890918] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 4.706816] virtio_net virtio0 ens2: renamed from eth0 [ 4.883367] scsi host0: ata_piix [ 4.886919] scsi host1: ata_piix [ 4.888292] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 4.890713] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 9.541124] random: crng init done [ 9.544237] random: 7 urandom warning(s) missed due to ratelimiting [ 9.776618] dracut-initqueue[589]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre).[ 10.482711] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Reached target Remote File Systems. [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Timers. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ OK ] Stopped target Sockets. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Swap. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev 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.634633] printk: systemd: 25 output lines suppressed due to ratelimiting [ 11.856538] SELinux: Disabled at runtime. [ 11.911501] 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.920613] systemd[1]: Detected virtualization kvm. [ 11.922585] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 12.426555] systemd[1]: initrd-switch-root.service: Succeeded. [ 12.429792] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 12.434651] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 12.438278] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 12.441212] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 12.451127] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.467727] systemd[1]: Created slice system-sshd\x2dkeygen.slice. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Created slice system-getty.slice. Mounting Huge Pages File System... [ OK ] Created slice system-serial\x2dgetty.slice. Mounting Kernel Debug File System... [ OK ] Listening on Process Core Dump Socket. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Reached target rpc_pipefs.target. Mounting POSIX Message Queue File System... [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. Starting Remount Root and Kernel File Systems... [ OK ] Reached target RPC Port Mapper. [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. Starting Apply Kernel Variables... Activating swap /dev/disk/by-label/SWAP... [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd Root File System. [ OK ] Stopped target Initrd File Systems. Starting Create list of required st…ce nodes for the current kernel... [ 12.648783] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Started Journal Service. [ OK ] Mounted Huge Pages File System. [ OK ] Mounted Kernel Debug File System. [ OK ] Mounted POSIX Message Queue File System. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Started Apply Kernel Variables. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Reached target Swap. Starting Create Static Device Nodes in /dev... Starting Configure read-only root support... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... [ OK ] Started udev Coldplug all Devices. [ OK ] Mounted /mnt. [ 12.900186] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 13.192547] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 13.232585] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 13.339719] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 13.364213] EDAC sbridge: Ver: 1.1.2 [ 14.709490] Key type dns_resolver registered [ 15.030793] NFS: Registering the id_resolver key type [ 15.032485] Key type id_resolver registered [ 15.033653] Key type id_legacy registered [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Load/Save Random Seed... Starting Create Volatile Files and Directories... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Load/Save Random Seed. [ OK ] Started Create Volatile Files and Directories. Starting 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 daily update of the root trust anchor for DNSSEC. [ 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. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. Starting Restore /run/initramfs on shutdown... [ OK ] Reached target sshd-keygen.target. Starting Login Service... [ OK ] Started irqbalance daemon. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting Dynamic System Tuning Daemon... Starting OpenSSH server daemon... Starting GSSAPI Proxy Daemon... Starting Network Manager Wait Online... [ OK ] Started Login Service. Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS1. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting System Logging Service... Starting Notify NFS peers of a restart... Starting Crash recovery kernel arming... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started Authorization Manager. [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg228-client login: [ 39.851739] libcfs: loading out-of-tree module taints kernel. [ 39.874136] Key type ._llcrypt registered [ 39.875134] Key type .llcrypt registered [ 40.157370] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 40.163370] alg: No test for adler32 (adler32-zlib) [ 41.180755] Lustre: Lustre: Build Version: 2.17.54_83_g2cb683b [ 41.516532] LNet: Added LNI 192.168.202.28@tcp [8/256/0/180] [ 43.135159] Key type lgssc registered [ 43.966640] Lustre: Echo OBD driver; http://www.lustre.org/ [ 145.003371] hrtimer: interrupt took 4017441 ns [ 187.524104] Lustre: Mounted lustre-client [ 191.791866] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 202.182412] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing check_logdir /tmp/testlogs/ [ 205.889109] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing yml_node [ 209.220111] Lustre: DEBUG MARKER: Client: 2.17.54.83 [ 211.366935] Lustre: DEBUG MARKER: MDS: 2.17.54.83 [ 213.202783] Lustre: DEBUG MARKER: OSS: 2.17.54.83 [ 213.471169] Lustre: lustre-OST0000-osc-ffff9897c51f5000: disconnect after 23s idle [ 214.462779] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Mon Jun 29 14:01:17 EDT 2026 [ 227.943967] Lustre: DEBUG MARKER: excepting tests: 21e 32b 32c 32newtarball [ 229.394925] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 231.566344] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 241.906871] Lustre: Unmounted lustre-client [ 353.097264] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 358.648179] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 381.405898] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 418.823272] Lustre: DEBUG MARKER: == conf-sanity test 0: single mount setup ================ 14:04:41 (1782756281) [ 452.380184] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 458.504922] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 480.899529] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 481.524320] Lustre: Mounted lustre-client [ 485.835959] Lustre: Unmounted lustre-client [ 519.257849] Key type lgssc unregistered [ 519.463871] LNet: 14620:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 519.466766] LNetError: 14620:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 519.475356] LNet: Removed LNI 192.168.202.28@tcp [ 520.271327] Key type .llcrypt unregistered [ 520.283891] Key type ._llcrypt unregistered [ 544.165253] Key type ._llcrypt registered [ 544.171576] Key type .llcrypt registered [ 545.688537] Lustre: DEBUG MARKER: == conf-sanity test 1: start up ost twice (should return errors) ========================================================== 14:06:48 (1782756408) [ 548.961618] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 549.087620] alg: No test for adler32 (adler32-zlib) [ 550.548256] Lustre: Lustre: Build Version: 2.17.54_83_g2cb683b [ 550.780680] LNet: Added LNI 192.168.202.28@tcp [8/256/0/180] [ 552.439478] Key type lgssc registered [ 554.714222] Lustre: Echo OBD driver; http://www.lustre.org/ [ 615.545756] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 622.658872] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 649.253244] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 663.196298] Lustre: Mounted lustre-client [ 666.951727] Lustre: Unmounted lustre-client [ 697.972710] Key type lgssc unregistered [ 698.310172] LNet: 18272:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 698.320594] LNetError: 18272:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 698.353579] LNet: Removed LNI 192.168.202.28@tcp [ 699.305585] Key type .llcrypt unregistered [ 699.321583] Key type ._llcrypt unregistered [ 718.645318] Key type ._llcrypt registered [ 718.647322] Key type .llcrypt registered [ 720.158827] Lustre: DEBUG MARKER: == conf-sanity test 2: start up mds twice (should return err) ========================================================== 14:09:42 (1782756582) [ 723.338321] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 723.454496] alg: No test for adler32 (adler32-zlib) [ 724.642714] Lustre: Lustre: Build Version: 2.17.54_83_g2cb683b [ 724.973560] LNet: Added LNI 192.168.202.28@tcp [8/256/0/180] [ 726.679571] Key type lgssc registered [ 728.214445] Lustre: Echo OBD driver; http://www.lustre.org/ [ 783.702554] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 789.458672] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 822.425992] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 833.595754] Lustre: Mounted lustre-client [ 836.994822] Lustre: Unmounted lustre-client [ 869.162562] Key type lgssc unregistered [ 869.481088] LNet: 21908:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 869.489692] LNetError: 21908:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 869.515146] LNet: Removed LNI 192.168.202.28@tcp [ 870.576977] Key type .llcrypt unregistered [ 870.583717] Key type ._llcrypt unregistered [ 891.089250] Key type ._llcrypt registered [ 891.090979] Key type .llcrypt registered [ 893.033362] Lustre: DEBUG MARKER: == conf-sanity test 3: mount client twice (should return err) ========================================================== 14:12:35 (1782756755) [ 895.980036] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 896.244761] alg: No test for adler32 (adler32-zlib) [ 897.567598] Lustre: Lustre: Build Version: 2.17.54_83_g2cb683b [ 897.893767] LNet: Added LNI 192.168.202.28@tcp [8/256/0/180] [ 899.631305] Key type lgssc registered [ 901.059380] Lustre: Echo OBD driver; http://www.lustre.org/ [ 956.873812] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 967.994315] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1005.899867] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1007.636839] Lustre: Mounted lustre-client [ 1012.384801] Lustre: Unmounted lustre-client [ 1050.238308] Key type lgssc unregistered [ 1050.528029] LNet: 25506:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1050.544929] LNetError: 25506:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1051.558332] LNet: Removed LNI 192.168.202.28@tcp [ 1052.052965] Key type .llcrypt unregistered [ 1052.055330] Key type ._llcrypt unregistered [ 1069.458891] Key type ._llcrypt registered [ 1069.461873] Key type .llcrypt registered [ 1070.832969] Lustre: DEBUG MARKER: == conf-sanity test 4: force cleanup ost, then cleanup === 14:15:33 (1782756933) [ 1074.050186] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1074.087330] alg: No test for adler32 (adler32-zlib) [ 1075.088806] Lustre: Lustre: Build Version: 2.17.54_83_g2cb683b [ 1075.268856] LNet: Added LNI 192.168.202.28@tcp [8/256/0/180] [ 1076.944091] Key type lgssc registered [ 1078.220353] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1137.124503] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1142.540754] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1165.545096] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1168.699431] Lustre: Mounted lustre-client [ 1179.121409] Lustre: lustre-OST0000-osc-ffff9897c696e800: Connection to lustre-OST0000 (at 192.168.202.128@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1187.473963] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1197.810471] Lustre: Unmounted lustre-client [ 1229.911388] Key type lgssc unregistered [ 1230.215861] LNet: 29112:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1230.220366] LNetError: 29112:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1230.231065] LNet: Removed LNI 192.168.202.28@tcp [ 1230.998689] Key type .llcrypt unregistered [ 1231.005432] Key type ._llcrypt unregistered [ 1252.098162] Key type ._llcrypt registered [ 1252.100854] Key type .llcrypt registered [ 1253.472543] Lustre: DEBUG MARKER: == conf-sanity test 5a: force cleanup mds, then cleanup == 14:18:36 (1782757116) [ 1256.350194] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1256.528105] alg: No test for adler32 (adler32-zlib) [ 1257.671889] Lustre: Lustre: Build Version: 2.17.54_83_g2cb683b [ 1257.952221] LNet: Added LNI 192.168.202.28@tcp [8/256/0/180] [ 1259.767280] Key type lgssc registered [ 1261.257989] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1318.526546] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1326.559457] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1359.648466] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1361.427671] Lustre: Mounted lustre-client [ 1371.621614] Lustre: lustre-MDT0000-mdc-ffff9897c564a000: Connection to lustre-MDT0000 (at 192.168.202.128@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1381.878427] Lustre: lustre-MDT0001-mdc-ffff9897c564a000: Connection to lustre-MDT0001 (at 192.168.202.128@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1387.283380] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1393.119373] Lustre: 30071:0:(client.c:2489:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1782757241/real 1782757241] req@00000000d877ab06 x1869356331964416/t0(0) o400->MGC192.168.202.128@tcp@192.168.202.128@tcp:26/25 lens 224/224 e 0 to 1 dl 1782757257 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1393.194281] LustreError: MGC192.168.202.128@tcp: Connection to MGS (at 192.168.202.128@tcp) was lost; in progress operations using this service will fail [ 1397.682443] Lustre: Unmounted lustre-client [ 1417.669656] Key type lgssc unregistered [ 1417.996331] LNet: 32746:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1418.009652] LNetError: 32746:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1418.033223] LNet: Removed LNI 192.168.202.28@tcp [ 1418.800081] Key type .llcrypt unregistered [ 1418.802437] Key type ._llcrypt unregistered [ 1437.436781] Key type ._llcrypt registered [ 1437.439822] Key type .llcrypt registered [ 1439.036606] Lustre: DEBUG MARKER: == conf-sanity test 5b: Try to start a client with no MGS (should return errs) ========================================================== 14:21:41 (1782757301) [ 1443.520336] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1443.663188] alg: No test for adler32 (adler32-zlib) [ 1445.162891] Lustre: Lustre: Build Version: 2.17.54_83_g2cb683b [ 1445.534932] LNet: Added LNI 192.168.202.28@tcp [8/256/0/180] [ 1447.303339] Key type lgssc registered [ 1449.841232] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1541.971099] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1547.748681] LustreError: 34953:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.202.128@tcp: failed processing log, type 1: rc = -110 [ 1556.959735] LustreError: 34962:0:(mgc_request.c:614:do_requeue()) failed processing log: -110 [ 1703.942163] LustreError: MGC192.168.202.128@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 [ 1703.981623] Lustre: Unmounted lustre-client [ 1703.990838] LustreError: 34953:0:(super25.c:184:lustre_fill_super()) llite: Unable to mount : rc = -110 [ 1720.431679] Key type lgssc unregistered [ 1720.706180] LNet: 35378:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1720.720268] LNetError: 35378:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1720.754908] LNet: Removed LNI 192.168.202.28@tcp [ 1722.064331] Key type .llcrypt unregistered [ 1722.076774] Key type ._llcrypt unregistered [ 1740.187922] Key type ._llcrypt registered [ 1740.190303] Key type .llcrypt registered [ 1741.829984] Lustre: DEBUG MARKER: == conf-sanity test 5c: cleanup after failed mount (bug 2712) (should return errs) ========================================================== 14:26:44 (1782757604) [ 1745.808044] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1745.839887] alg: No test for adler32 (adler32-zlib) [ 1746.959756] Lustre: Lustre: Build Version: 2.17.54_83_g2cb683b [ 1747.185691] LNet: Added LNI 192.168.202.28@tcp [8/256/0/180] [ 1748.823265] Key type lgssc registered [ 1751.196902] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1812.589445] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1819.401668] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1845.307722] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1845.929354] LustreError: 38451:0:(llite_lib.c:1460:ll_fill_super()) wrong.lustre: fsname longer than 8 characters: rc = -36 [ 1845.947811] Lustre: Unmounted wrong.lustre-client [ 1845.980712] LustreError: 38451:0:(super25.c:184:lustre_fill_super()) llite: Unable to mount : rc = -36 [ 1879.011082] Key type lgssc unregistered [ 1879.316331] LNet: 38868:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1879.320727] LNetError: 38868:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1879.338750] LNet: Removed LNI 192.168.202.28@tcp [ 1880.028419] Key type .llcrypt unregistered [ 1880.029985] Key type ._llcrypt unregistered [ 1899.838703] Key type ._llcrypt registered [ 1899.840874] Key type .llcrypt registered [ 1901.412538] Lustre: DEBUG MARKER: == conf-sanity test 5d: mount with ost down ============== 14:29:24 (1782757764) [ 1904.557560] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1904.946756] alg: No test for adler32 (adler32-zlib) [ 1906.214528] Lustre: Lustre: Build Version: 2.17.54_83_g2cb683b [ 1906.584576] LNet: Added LNI 192.168.202.28@tcp [8/256/0/180] [ 1908.399899] Key type lgssc registered [ 1910.258540] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1996.461977] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2034.435678] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2040.983924] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2061.115428] Lustre: Mounted lustre-client [ 2063.477640] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2073.849932] Lustre: Unmounted lustre-client [ 2105.555234] Key type lgssc unregistered [ 2105.899892] LNet: 42417:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2105.917030] LNetError: 42417:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2105.952298] LNet: Removed LNI 192.168.202.28@tcp [ 2106.817467] Key type .llcrypt unregistered [ 2106.822332] Key type ._llcrypt unregistered [ 2126.048234] Key type ._llcrypt registered [ 2126.056628] Key type .llcrypt registered [ 2127.998234] Lustre: DEBUG MARKER: == conf-sanity test 5e: delayed connect, don't crash (bug 10268) ========================================================== 14:33:10 (1782757990) [ 2130.552768] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2130.785532] alg: No test for adler32 (adler32-zlib) [ 2132.031374] Lustre: Lustre: Build Version: 2.17.54_83_g2cb683b [ 2132.376373] LNet: Added LNI 192.168.202.28@tcp [8/256/0/180] [ 2134.207269] Key type lgssc registered [ 2135.371692] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2199.493240] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2206.260956] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2228.959263] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2229.421034] LustreError: 43378:0:(niobuf.c:1106:ptl_send_rpc()) cfs_fail_timeout id 506 sleeping for 10000ms [ 2234.528267] LustreError: 45482:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.202.128@tcp: failed processing log, type 1: rc = -110 [ 2239.448623] LustreError: 43378:0:(niobuf.c:1106:ptl_send_rpc()) cfs_fail_timeout id 506 awake [ 2240.745872] Lustre: Mounted lustre-client [ 2242.717530] Lustre: Unmounted lustre-client [ 2271.525134] Key type lgssc unregistered [ 2271.746783] LNet: 45949:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2271.762921] LNetError: 45949:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2271.792077] LNet: Removed LNI 192.168.202.28@tcp [ 2272.803606] Key type .llcrypt unregistered [ 2272.805917] Key type ._llcrypt unregistered [ 2288.852560] Key type ._llcrypt registered [ 2288.856269] Key type .llcrypt registered [ 2290.277088] Lustre: DEBUG MARKER: == conf-sanity test 5f: mds down, cleanup after failed mount (bug 2712) ========================================================== 14:35:53 (1782758153) [ 2292.298139] Lustre: DEBUG MARKER: SKIP: conf-sanity test_5f needs separate mgs and mds [ 2294.344290] Lustre: DEBUG MARKER: == conf-sanity test 5g: handle missing debugfs =========== 14:35:57 (1782758157) [ 2295.405577] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2295.423620] alg: No test for adler32 (adler32-zlib) [ 2296.676405] Lustre: Lustre: Build Version: 2.17.54_83_g2cb683b [ 2296.972551] LNet: Added LNI 192.168.202.28@tcp [8/256/0/180] [ 2298.602054] Key type lgssc registered [ 2300.271761] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2330.566953] Lustre: DEBUG MARKER: == conf-sanity test 5h: start mdt failure at mdt_fs_setup() ========================================================== 14:36:33 (1782758193) [ 2370.879898] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2377.525957] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2402.318206] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2405.290533] Lustre: Mounted lustre-client [ 2410.479308] Lustre: lustre-MDT0000-mdc-ffff9897c5b2f800: Connection to lustre-MDT0000 (at 192.168.202.128@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2425.631213] Lustre: 47171:0:(client.c:2489:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1782758274/real 1782758274] req@000000008944c721 x1869357421432448/t0(0) o400->MGC192.168.202.128@tcp@192.168.202.128@tcp:26/25 lens 224/224 e 0 to 1 dl 1782758290 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2425.657482] LustreError: MGC192.168.202.128@tcp: Connection to MGS (at 192.168.202.128@tcp) was lost; in progress operations using this service will fail [ 2431.907141] Lustre: lustre-MDT0000-mdc-ffff9897c5b2f800: Connection restored to 192.168.202.128@tcp (at 192.168.202.128@tcp) [ 2436.029149] Lustre: Evicted from MGS (at 192.168.202.128@tcp) after server handle changed from 0xa7768d10f0a78f86 to 0xa7768d10f0a7931b [ 2436.064566] Lustre: MGC192.168.202.128@tcp: Connection restored to 192.168.202.128@tcp (at 192.168.202.128@tcp) [ 2440.243807] Lustre: Unmounted lustre-client [ 2468.693633] Key type lgssc unregistered [ 2468.991912] LNet: 50788:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2469.001965] LNetError: 50788:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2469.035601] LNet: Removed LNI 192.168.202.28@tcp [ 2469.676882] Key type .llcrypt unregistered [ 2469.679062] Key type ._llcrypt unregistered [ 2487.728400] Key type ._llcrypt registered [ 2487.731717] Key type .llcrypt registered [ 2489.663786] Lustre: DEBUG MARKER: == conf-sanity test 5i: start mdt failure at mdt_quota_init() ========================================================== 14:39:11 (1782758351) [ 2493.287554] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2493.321576] alg: No test for adler32 (adler32-zlib) [ 2494.480262] Lustre: Lustre: Build Version: 2.17.54_83_g2cb683b [ 2494.842103] LNet: Added LNI 192.168.202.28@tcp [8/256/0/180] [ 2496.681863] Key type lgssc registered [ 2498.364779] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2557.184316] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2564.609820] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2590.635439] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2593.543160] Lustre: Mounted lustre-client [ 2598.897336] Lustre: lustre-MDT0000-mdc-ffff9897c51f5800: Connection to lustre-MDT0000 (at 192.168.202.128@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2615.263224] Lustre: 51750:0:(client.c:2489:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1782758463/real 1782758463] req@00000000db8a814c x1869357629050496/t0(0) o400->MGC192.168.202.128@tcp@192.168.202.128@tcp:26/25 lens 224/224 e 0 to 1 dl 1782758479 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2615.317857] LustreError: MGC192.168.202.128@tcp: Connection to MGS (at 192.168.202.128@tcp) was lost; in progress operations using this service will fail [ 2622.907737] Lustre: lustre-MDT0000-mdc-ffff9897c51f5800: Connection restored to 192.168.202.128@tcp (at 192.168.202.128@tcp) [ 2624.943141] Lustre: Evicted from MGS (at 192.168.202.128@tcp) after server handle changed from 0x5c272f2cdccd6b94 to 0x5c272f2cdccd6f30 [ 2624.971408] Lustre: MGC192.168.202.128@tcp: Connection restored to 192.168.202.128@tcp (at 192.168.202.128@tcp) [ 2628.010718] Lustre: Unmounted lustre-client [ 2657.278153] Key type lgssc unregistered [ 2657.597728] LNet: 54733:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2657.608316] LNetError: 54733:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2657.628375] LNet: Removed LNI 192.168.202.28@tcp [ 2658.519328] Key type .llcrypt unregistered [ 2658.521800] Key type ._llcrypt unregistered [ 2673.727681] Key type ._llcrypt registered [ 2673.730705] Key type .llcrypt registered [ 2675.155795] Lustre: DEBUG MARKER: == conf-sanity test 5j: verify libcfs doesn't crash when setting debug flags ========================================================== 14:42:18 (1782758538) [ 2675.700621] Key type .llcrypt unregistered [ 2675.709847] Key type ._llcrypt unregistered [ 2682.204914] Key type ._llcrypt registered [ 2682.207582] Key type .llcrypt registered [ 2682.254542] Key type .llcrypt unregistered [ 2682.255727] Key type ._llcrypt unregistered [ 2687.204626] Key type ._llcrypt registered [ 2687.206993] Key type .llcrypt registered [ 2688.448169] Lustre: DEBUG MARKER: == conf-sanity test 6: manual umount, then mount again === 14:42:31 (1782758551) [ 2690.805291] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2690.854103] alg: No test for adler32 (adler32-zlib) [ 2691.841536] Lustre: Lustre: Build Version: 2.17.54_83_g2cb683b [ 2692.049660] LNet: Added LNI 192.168.202.28@tcp [8/256/0/180] [ 2693.703365] Key type lgssc registered [ 2694.851154] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2757.823893] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2765.103872] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2788.865792] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2800.247579] Lustre: Mounted lustre-client [ 2802.983174] Lustre: Unmounted lustre-client [ 2803.484879] Lustre: Mounted lustre-client [ 2805.351372] Lustre: Unmounted lustre-client [ 2834.917840] Key type lgssc unregistered [ 2835.175290] LNet: 59132:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2835.185950] LNetError: 59132:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2835.212241] LNet: Removed LNI 192.168.202.28@tcp [ 2835.899615] Key type .llcrypt unregistered [ 2835.902267] Key type ._llcrypt unregistered [ 2850.546077] Key type ._llcrypt registered [ 2850.551706] Key type .llcrypt registered [ 2851.812409] Lustre: DEBUG MARKER: == conf-sanity test 7: manual umount, then cleanup ======= 14:45:14 (1782758714) [ 2853.790798] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2853.914140] alg: No test for adler32 (adler32-zlib) [ 2854.933127] Lustre: Lustre: Build Version: 2.17.54_83_g2cb683b [ 2855.250730] LNet: Added LNI 192.168.202.28@tcp [8/256/0/180] [ 2857.047311] Key type lgssc registered [ 2858.067854] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2915.272646] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2922.452482] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2946.132306] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2947.831650] Lustre: Mounted lustre-client [ 2950.330183] Lustre: Unmounted lustre-client [ 2983.338766] Key type lgssc unregistered [ 2983.721578] LNet: 62678:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2983.733397] LNetError: 62678:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2983.748915] LNet: Removed LNI 192.168.202.28@tcp [ 2984.516268] Key type .llcrypt unregistered [ 2984.520627] Key type ._llcrypt unregistered [ 3001.615275] Key type ._llcrypt registered [ 3001.617609] Key type .llcrypt registered [ 3003.084994] Lustre: DEBUG MARKER: == conf-sanity test 8: double mount setup ================ 14:47:45 (1782758865) [ 3005.038745] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3005.152350] alg: No test for adler32 (adler32-zlib) [ 3006.196637] Lustre: Lustre: Build Version: 2.17.54_83_g2cb683b [ 3006.448070] LNet: Added LNI 192.168.202.28@tcp [8/256/0/180] [ 3008.127238] Key type lgssc registered [ 3009.443702] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3066.719896] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3073.350793] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3106.174663] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3110.126607] Lustre: Mounted lustre-client [ 3113.802960] Lustre: Mounted lustre-client [ 3118.346329] Lustre: Unmounted lustre-client [ 3119.674458] Lustre: Unmounted lustre-client [ 3150.360928] Key type lgssc unregistered [ 3150.591157] LNet: 66316:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3150.605317] LNetError: 66316:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3150.621918] LNet: Removed LNI 192.168.202.28@tcp [ 3151.310316] Key type .llcrypt unregistered [ 3151.312724] Key type ._llcrypt unregistered [ 3165.970739] Key type ._llcrypt registered [ 3165.973293] Key type .llcrypt registered [ 3167.123558] Lustre: DEBUG MARKER: == conf-sanity test 9: test ptldebug and subsystem for mkfs ========================================================== 14:50:30 (1782759030) [ 3169.529018] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3169.578172] alg: No test for adler32 (adler32-zlib) [ 3170.621474] Lustre: Lustre: Build Version: 2.17.54_83_g2cb683b [ 3170.830207] LNet: Added LNI 192.168.202.28@tcp [8/256/0/180] [ 3172.479306] Key type lgssc registered [ 3173.499320] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3254.830214] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3266.668258] Lustre: DEBUG MARKER: == conf-sanity test 10a: find lctl param broken symlinks ========================================================== 14:52:10 (1782759130) [ 3294.816091] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3299.177834] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3316.425914] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3322.398675] Lustre: Mounted lustre-client [ 3329.054034] Lustre: Unmounted lustre-client [ 3353.663851] Key type lgssc unregistered [ 3353.856342] LNet: 71048:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3353.860072] LNetError: 71048:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3353.872477] LNet: Removed LNI 192.168.202.28@tcp [ 3354.289405] Key type .llcrypt unregistered [ 3354.291768] Key type ._llcrypt unregistered [ 3365.211629] Key type ._llcrypt registered [ 3365.213654] Key type .llcrypt registered [ 3366.253541] Lustre: DEBUG MARKER: == conf-sanity test 11: Verify tool --version option works properly ========================================================== 14:53:49 (1782759229) [ 3379.195822] Lustre: DEBUG MARKER: == conf-sanity test 17: Verify failed mds_postsetup won't fail assertion (2936) (should return errs) ========================================================== 14:54:02 (1782759242) [ 3380.823093] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3380.839350] alg: No test for adler32 (adler32-zlib) [ 3381.819919] Lustre: Lustre: Build Version: 2.17.54_83_g2cb683b [ 3381.966889] LNet: Added LNI 192.168.202.28@tcp [8/256/0/180] [ 3383.599230] Key type lgssc registered [ 3384.395459] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3416.516171] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3419.991860] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3431.277300] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3433.683864] Lustre: Mounted lustre-client [ 3440.231830] Lustre: Unmounted lustre-client [ 3461.537045] Key type lgssc unregistered [ 3461.725875] LNet: 75306:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3461.729630] LNetError: 75306:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3461.742061] LNet: Removed LNI 192.168.202.28@tcp [ 3462.180711] Key type .llcrypt unregistered [ 3462.184409] Key type ._llcrypt unregistered [ 3469.896286] Key type ._llcrypt registered [ 3469.930317] Key type .llcrypt registered [ 3470.175326] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3470.191437] alg: No test for adler32 (adler32-zlib) [ 3471.146510] Lustre: Lustre: Build Version: 2.17.54_83_g2cb683b [ 3471.285561] LNet: Added LNI 192.168.202.28@tcp [8/256/0/180] [ 3472.895247] Key type lgssc registered [ 3473.572768] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3541.342619] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3603.430817] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3606.305744] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3617.241749] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3664.570780] Lustre: DEBUG MARKER: == conf-sanity test 18: check mkfs creates large journals ========================================================== 14:58:47 (1782759527) [ 3669.439437] Lustre: DEBUG MARKER: use device /dev/mapper/mds1_flakey with MIN=2000000 [ 3811.659504] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3818.236761] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3844.916874] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3924.777756] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3932.026690] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3954.561543] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3956.571185] Lustre: Mounted lustre-client [ 3962.205918] Lustre: DEBUG MARKER: Success: mkfs creates large journals. Size: 78M [ 3964.785364] Lustre: Unmounted lustre-client [ 3995.911442] Key type lgssc unregistered [ 3996.235953] LNet: 86924:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3996.239590] LNetError: 86924:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3996.259573] LNet: Removed LNI 192.168.202.28@tcp [ 3997.058995] Key type .llcrypt unregistered [ 3997.060825] Key type ._llcrypt unregistered [ 4030.270918] Key type ._llcrypt registered [ 4030.318047] Key type .llcrypt registered [ 4030.998518] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4031.025816] alg: No test for adler32 (adler32-zlib) [ 4032.203879] Lustre: Lustre: Build Version: 2.17.54_83_g2cb683b [ 4032.489801] LNet: Added LNI 192.168.202.28@tcp [8/256/0/180] [ 4034.247275] Key type lgssc registered [ 4035.580712] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4131.721136] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4139.114829] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4163.937995] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4205.131702] Lustre: DEBUG MARKER: == conf-sanity test 19a: start/stop MDS without OSTs ===== 15:07:48 (1782760068) [ 4240.799280] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4246.441455] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4268.461584] Lustre: DEBUG MARKER: == conf-sanity test 19b: start/stop OSTs without MDS ===== 15:08:51 (1782760131) [ 4337.653655] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4347.996621] Lustre: DEBUG MARKER: == conf-sanity test 20: remount ro,rw mounts work and doesn't break /etc/mtab ========================================================== 15:10:11 (1782760211) [ 4383.484697] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4389.442305] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4411.733897] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4413.487956] Lustre: Mounted lustre-client [ 4419.007561] Lustre: Remounted lustre-client read-only [ 4421.480027] Lustre: Unmounted lustre-client [ 4457.900431] Lustre: DEBUG MARKER: == conf-sanity test 21a: start mds before ost, stop ost first ========================================================== 15:12:00 (1782760320) [ 4494.362589] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4501.209547] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4522.935909] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4571.276578] Lustre: DEBUG MARKER: == conf-sanity test 21b: start ost before mds, stop mds first ========================================================== 15:13:54 (1782760434) [ 4643.016294] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4682.239756] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4690.290957] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4743.495717] Lustre: DEBUG MARKER: == conf-sanity test 21c: start mds between two osts, stop mds last ========================================================== 15:16:45 (1782760605) [ 4814.902240] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4850.885224] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4858.027258] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4884.478973] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 4975.409621] Lustre: DEBUG MARKER: == conf-sanity test 21d: start mgs then ost and then mds ========================================================== 15:20:38 (1782760838) [ 4977.690207] Lustre: DEBUG MARKER: SKIP: conf-sanity test_21d need separate mgs device [ 4980.639666] Lustre: DEBUG MARKER: SKIP: conf-sanity test_21e skipping excluded test 21e [ 4984.334703] Lustre: DEBUG MARKER: == conf-sanity test 22: start a client before osts (should return errs) ========================================================== 15:20:45 (1782760845) [ 5025.851302] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5032.087814] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5053.948711] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5073.971652] Lustre: Mounted lustre-client [ 5075.813817] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5086.148313] Lustre: Unmounted lustre-client [ 5106.504786] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5106.980640] Lustre: Mounted lustre-client [ 5120.362316] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9897c95b4000.ost_server_uuid 50 [ 5121.826183] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9897c95b4000.ost_server_uuid in FULL state after 0 sec [ 5125.241333] Lustre: Unmounted lustre-client [ 5160.838085] Key type lgssc unregistered [ 5161.216541] LNet: 107106:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5161.227694] LNetError: 107106:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5161.244515] LNet: Removed LNI 192.168.202.28@tcp [ 5162.383642] Key type .llcrypt unregistered [ 5162.387577] Key type ._llcrypt unregistered [ 5180.650649] Key type ._llcrypt registered [ 5180.654602] Key type .llcrypt registered [ 5182.559888] Lustre: DEBUG MARKER: == conf-sanity test 23a: interrupt client during recovery mount delay ========================================================== 15:24:04 (1782761044) [ 5185.074191] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5185.116069] alg: No test for adler32 (adler32-zlib) [ 5186.210496] Lustre: Lustre: Build Version: 2.17.54_83_g2cb683b [ 5186.509558] LNet: Added LNI 192.168.202.28@tcp [8/256/0/180] [ 5188.175202] Key type lgssc registered [ 5189.359231] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5236.652720] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5242.119098] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5261.020867] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5262.528564] Lustre: Mounted lustre-client [ 5267.962529] Lustre: lustre-MDT0000-mdc-ffff9897c7754800: Connection to lustre-MDT0000 (at 192.168.202.128@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5268.595143] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5278.906059] LustreError: MGC192.168.202.128@tcp: Connection to MGS (at 192.168.202.128@tcp) was lost; in progress operations using this service will fail [ 5278.964061] Lustre: Unmounted lustre-client [ 5303.134417] LustreError: lustre-MDT0000-mdc-ffff9897d231a800: operation mds_connect to node 192.168.202.128@tcp failed: rc = -16 [ 5308.176170] LustreError: 110466:0:(lmv_obd.c:1466:lmv_statfs()) lustre-MDT0000-mdc-ffff9897d231a800: can't stat MDS #0: rc = -16 [ 5308.271399] Lustre: Unmounted lustre-client [ 5308.300029] LustreError: 110466:0:(super25.c:184:lustre_fill_super()) llite: Unable to mount : rc = -16 [ 5336.234312] Key type lgssc unregistered [ 5336.625607] LNet: 110918:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5336.633826] LNetError: 110918:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5336.649210] LNet: Removed LNI 192.168.202.28@tcp [ 5337.408942] Key type .llcrypt unregistered [ 5337.413193] Key type ._llcrypt unregistered [ 5354.090569] Key type ._llcrypt registered [ 5354.092634] Key type .llcrypt registered [ 5355.287827] Lustre: DEBUG MARKER: == conf-sanity test 23b: Simulate -EINTR during mount ==== 15:26:58 (1782761218) [ 5358.173462] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5358.197426] alg: No test for adler32 (adler32-zlib) [ 5359.342378] Lustre: Lustre: Build Version: 2.17.54_83_g2cb683b [ 5359.551349] LNet: Added LNI 192.168.202.28@tcp [8/256/0/180] [ 5361.199515] Key type lgssc registered [ 5362.367757] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5412.915129] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5419.686741] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5442.269210] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5445.017616] Lustre: Mounted lustre-client [ 5447.400444] Lustre: Unmounted lustre-client [ 5485.168662] Key type lgssc unregistered [ 5485.396376] LNet: 114423:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5485.403946] LNetError: 114423:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5485.421363] LNet: Removed LNI 192.168.202.28@tcp [ 5486.029031] Key type .llcrypt unregistered [ 5486.034071] Key type ._llcrypt unregistered [ 5503.492741] Key type ._llcrypt registered [ 5503.500898] Key type .llcrypt registered [ 5505.137335] Lustre: DEBUG MARKER: == conf-sanity test 24a: Multiple MDTs on a single node == 15:29:27 (1782761367) [ 5507.986699] Lustre: DEBUG MARKER: SKIP: conf-sanity test_24a mixed loopback and real device not working [ 5510.263574] Lustre: DEBUG MARKER: == conf-sanity test 24b: Multiple MGSs on a single node (should return err) ========================================================== 15:29:32 (1782761372) [ 5513.400702] Lustre: DEBUG MARKER: SKIP: conf-sanity test_24b mixed loopback and real device not working [ 5515.275613] Lustre: DEBUG MARKER: == conf-sanity test 25: Verify modules are referenced ==== 15:29:38 (1782761378) [ 5518.210892] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5518.253032] alg: No test for adler32 (adler32-zlib) [ 5519.304554] Lustre: Lustre: Build Version: 2.17.54_83_g2cb683b [ 5519.530443] LNet: Added LNI 192.168.202.28@tcp [8/256/0/180] [ 5521.199528] Key type lgssc registered [ 5522.643506] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5577.432307] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5583.687303] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5608.565637] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5611.485843] Lustre: Mounted lustre-client [ 5614.358301] Lustre: Unmounted lustre-client [ 5644.559986] Key type lgssc unregistered [ 5644.916267] LNet: 118588:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5644.924525] LNetError: 118588:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5645.995696] LNet: Removed LNI 192.168.202.28@tcp [ 5647.174357] Key type .llcrypt unregistered [ 5647.178640] Key type ._llcrypt unregistered [ 5666.812901] Key type ._llcrypt registered [ 5666.815217] Key type .llcrypt registered [ 5668.039724] Lustre: DEBUG MARKER: == conf-sanity test 26: MDT startup failure cleans LOV (should return errs) ========================================================== 15:32:11 (1782761531) [ 5669.656410] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5669.674223] alg: No test for adler32 (adler32-zlib) [ 5670.754626] Lustre: Lustre: Build Version: 2.17.54_83_g2cb683b [ 5671.044800] LNet: Added LNI 192.168.202.28@tcp [8/256/0/180] [ 5672.679192] Key type lgssc registered [ 5674.131802] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5745.052487] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5751.664960] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5779.575286] Key type lgssc unregistered [ 5779.971710] LNet: 121657:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5779.981146] LNetError: 121657:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5780.004428] LNet: Removed LNI 192.168.202.28@tcp [ 5781.110351] Key type .llcrypt unregistered [ 5781.113420] Key type ._llcrypt unregistered [ 5800.528509] Key type ._llcrypt registered [ 5800.531469] Key type .llcrypt registered [ 5802.151798] Lustre: DEBUG MARKER: == conf-sanity test 27a: Reacquire MGS lock if OST started first ========================================================== 15:34:24 (1782761664) [ 5811.951409] Key type .llcrypt unregistered [ 5811.954603] Key type ._llcrypt unregistered [ 5821.846993] Key type ._llcrypt registered [ 5821.849379] Key type .llcrypt registered [ 5822.405462] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5822.449592] alg: No test for adler32 (adler32-zlib) [ 5823.531631] Lustre: Lustre: Build Version: 2.17.54_83_g2cb683b [ 5823.815098] LNet: Added LNI 192.168.202.28@tcp [8/256/0/180] [ 5825.623580] Key type lgssc registered [ 5827.394945] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5922.568721] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5966.593747] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5973.966131] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6026.279404] Key type lgssc unregistered [ 6026.627943] LNet: 125511:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6026.643296] LNetError: 125511:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6026.662090] LNet: Removed LNI 192.168.202.28@tcp [ 6027.521881] Key type .llcrypt unregistered [ 6027.525090] Key type ._llcrypt unregistered [ 6047.696383] Key type ._llcrypt registered [ 6047.700338] Key type .llcrypt registered [ 6049.545953] Lustre: DEBUG MARKER: == conf-sanity test 27b: Reacquire MGS lock after failover ========================================================== 15:38:31 (1782761911) [ 6052.404335] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6052.457436] alg: No test for adler32 (adler32-zlib) [ 6053.608742] Lustre: Lustre: Build Version: 2.17.54_83_g2cb683b [ 6053.897855] LNet: Added LNI 192.168.202.28@tcp [8/256/0/180] [ 6055.655394] Key type lgssc registered [ 6056.752832] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6119.507389] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6127.036751] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6151.879174] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6158.915458] Lustre: Mounted lustre-client [ 6163.945788] Lustre: lustre-MDT0000-mdc-ffff9897c95b4000: Connection to lustre-MDT0000 (at 192.168.202.128@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6180.319229] Lustre: 126487:0:(client.c:2489:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1782762028/real 1782762028] req@000000000933269c x1869361360932224/t0(0) o400->MGC192.168.202.128@tcp@192.168.202.128@tcp:26/25 lens 224/224 e 0 to 1 dl 1782762044 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 6180.363632] LustreError: MGC192.168.202.128@tcp: Connection to MGS (at 192.168.202.128@tcp) was lost; in progress operations using this service will fail [ 6190.585802] Lustre: Evicted from MGS (at 192.168.202.128@tcp) after server handle changed from 0x78af7990727531ff to 0x78af799072753436 [ 6190.604153] Lustre: MGC192.168.202.128@tcp: Connection restored to 192.168.202.128@tcp (at 192.168.202.128@tcp) [ 6195.570389] Lustre: lustre-MDT0000-mdc-ffff9897c95b4000: Connection restored to 192.168.202.128@tcp (at 192.168.202.128@tcp) [ 6212.522648] Lustre: Unmounted lustre-client [ 6238.067141] Key type lgssc unregistered [ 6238.329079] LNet: 129475:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6238.335426] LNetError: 129475:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6239.438529] LNet: Removed LNI 192.168.202.28@tcp [ 6240.113516] Key type .llcrypt unregistered [ 6240.116095] Key type ._llcrypt unregistered [ 6258.176674] Key type ._llcrypt registered [ 6258.182331] Key type .llcrypt registered [ 6260.149882] Lustre: DEBUG MARKER: == conf-sanity test 28A: permanent parameter setting ===== 15:42:02 (1782762122) [ 6263.560416] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6263.618915] alg: No test for adler32 (adler32-zlib) [ 6264.795482] Lustre: Lustre: Build Version: 2.17.54_83_g2cb683b [ 6265.123687] LNet: Added LNI 192.168.202.28@tcp [8/256/0/180] [ 6266.839300] Key type lgssc registered [ 6268.509479] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6326.904383] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6334.681251] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6359.398646] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6371.427281] Lustre: Mounted lustre-client [ 6388.517092] Lustre: Unmounted lustre-client [ 6389.030854] Lustre: Mounted lustre-client [ 6405.015980] Lustre: Unmounted lustre-client [ 6443.663900] Key type lgssc unregistered [ 6444.031582] LNet: 133676:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6444.040169] LNetError: 133676:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6444.052954] LNet: Removed LNI 192.168.202.28@tcp [ 6445.160572] Key type .llcrypt unregistered [ 6445.164932] Key type ._llcrypt unregistered [ 6458.440200] Key type ._llcrypt registered [ 6458.443546] Key type .llcrypt registered [ 6460.311054] Lustre: DEBUG MARKER: == conf-sanity test 28a: set symlink parameters permanently with lctl ========================================================== 15:45:22 (1782762322) [ 6463.480713] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6463.546414] alg: No test for adler32 (adler32-zlib) [ 6464.763484] Lustre: Lustre: Build Version: 2.17.54_83_g2cb683b [ 6465.093195] LNet: Added LNI 192.168.202.28@tcp [8/256/0/180] [ 6466.775370] Key type lgssc registered [ 6467.942223] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6525.504782] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6531.871965] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6555.651873] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6558.411105] Lustre: Mounted lustre-client [ 6598.381391] Lustre: Unmounted lustre-client [ 6636.844801] Key type lgssc unregistered [ 6637.191386] LNet: 137025:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6637.196983] LNetError: 137025:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6637.223720] LNet: Removed LNI 192.168.202.28@tcp [ 6637.961744] Key type .llcrypt unregistered [ 6637.968829] Key type ._llcrypt unregistered [ 6657.155561] Key type ._llcrypt registered [ 6657.157405] Key type .llcrypt registered [ 6658.399395] Lustre: DEBUG MARKER: == conf-sanity test 28b: verify client-side parameters are set upon mount ========================================================== 15:48:41 (1782762521) [ 6661.178143] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6661.194713] alg: No test for adler32 (adler32-zlib) [ 6662.274510] Lustre: Lustre: Build Version: 2.17.54_83_g2cb683b [ 6662.491413] LNet: Added LNI 192.168.202.28@tcp [8/256/0/180] [ 6664.175288] Key type lgssc registered [ 6665.573572] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6715.484823] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6720.773448] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6741.714652] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6744.743690] Lustre: Mounted lustre-client [ 6746.581735] Lustre: Unmounted lustre-client [ 6746.959988] Lustre: Mounted lustre-client [ 6753.053693] Lustre: Unmounted lustre-client [ 6781.565737] Key type lgssc unregistered [ 6781.810860] LNet: 141050:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6781.817754] LNetError: 141050:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6781.833659] LNet: Removed LNI 192.168.202.28@tcp [ 6782.494688] Key type .llcrypt unregistered [ 6782.497992] Key type ._llcrypt unregistered [ 6793.494244] Key type ._llcrypt registered [ 6793.495708] Key type .llcrypt registered [ 6794.792499] Lustre: DEBUG MARKER: == conf-sanity test 28c: verify filesystem parameters are set upon mount ========================================================== 15:50:57 (1782762657) [ 6797.121990] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6797.186761] alg: No test for adler32 (adler32-zlib) [ 6798.252696] Lustre: Lustre: Build Version: 2.17.54_83_g2cb683b [ 6798.441724] LNet: Added LNI 192.168.202.28@tcp [8/256/0/180] [ 6800.087244] Key type lgssc registered [ 6800.989466] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6851.681188] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6858.364812] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6880.943294] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6882.875249] Lustre: Mounted lustre-client [ 6885.331961] Lustre: Unmounted lustre-client [ 6885.751446] Lustre: Mounted lustre-client [ 6892.819754] Lustre: Unmounted lustre-client [ 6923.381894] Key type lgssc unregistered [ 6923.685777] LNet: 144642:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6923.696944] LNetError: 144642:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6923.724742] LNet: Removed LNI 192.168.202.28@tcp [ 6924.438474] Key type .llcrypt unregistered [ 6924.440411] Key type ._llcrypt unregistered [ 6937.146439] Key type ._llcrypt registered [ 6937.148625] Key type .llcrypt registered [ 6938.497259] Lustre: DEBUG MARKER: == conf-sanity test 29: permanently remove an OST ======== 15:53:21 (1782762801) [ 6941.359290] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6941.371372] alg: No test for adler32 (adler32-zlib) [ 6942.413596] Lustre: Lustre: Build Version: 2.17.54_83_g2cb683b [ 6942.730031] LNet: Added LNI 192.168.202.28@tcp [8/256/0/180] [ 6944.431265] Key type lgssc registered [ 6945.870611] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7003.616788] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7010.056407] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7031.694750] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7034.591859] Lustre: Mounted lustre-client [ 7057.111819] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 7058.876419] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7081.499530] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 7088.117851] Lustre: Unmounted lustre-client [ 7088.556568] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 7088.689183] Lustre: Mounted lustre-client [ 7096.482786] Lustre: Unmounted lustre-client [ 7139.664353] Key type lgssc unregistered [ 7139.986255] LNet: 148670:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7139.997101] LNetError: 148670:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7141.031167] LNet: Removed LNI 192.168.202.28@tcp [ 7141.949834] Key type .llcrypt unregistered [ 7141.958094] Key type ._llcrypt unregistered [ 7174.377620] Key type ._llcrypt registered [ 7174.381221] Key type .llcrypt registered [ 7175.877671] Lustre: DEBUG MARKER: == conf-sanity test 30a: Big config llog and permanent parameter deletion ========================================================== 15:57:18 (1782763038) [ 7178.414568] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7178.537834] alg: No test for adler32 (adler32-zlib) [ 7179.594820] Lustre: Lustre: Build Version: 2.17.54_83_g2cb683b [ 7179.840403] LNet: Added LNI 192.168.202.28@tcp [8/256/0/180] [ 7181.535330] Key type lgssc registered [ 7182.669401] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7234.969327] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7241.279142] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7264.850984] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7267.568632] Lustre: Mounted lustre-client [ 7427.527125] Lustre: Unmounted lustre-client [ 7428.043594] Lustre: Mounted lustre-client [ 7432.926136] Lustre: Unmounted lustre-client [ 7433.522639] Lustre: Mounted lustre-client [ 7435.847231] Lustre: Unmounted lustre-client [ 7465.443493] Key type lgssc unregistered [ 7465.791539] LNet: 153834:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7465.799192] LNetError: 153834:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7465.817189] LNet: Removed LNI 192.168.202.28@tcp [ 7466.699317] Key type .llcrypt unregistered [ 7466.701667] Key type ._llcrypt unregistered [ 7484.498673] Key type ._llcrypt registered [ 7484.501927] Key type .llcrypt registered [ 7486.163644] Lustre: DEBUG MARKER: == conf-sanity test 30b: Remove failover nids ============ 16:02:28 (1782763348) [ 7489.097637] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7489.223949] alg: No test for adler32 (adler32-zlib) [ 7490.291518] Lustre: Lustre: Build Version: 2.17.54_83_g2cb683b [ 7490.574584] LNet: Added LNI 192.168.202.28@tcp [8/256/0/180] [ 7492.319254] Key type lgssc registered [ 7493.639702] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7548.723209] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7555.668177] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7579.082253] Lustre: DEBUG MARKER: oleg228-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7582.041050] Lustre: Mounted lustre-client [ 7590.653288] Lustre: Unmounted lustre-client [ 7591.225504] Lustre: Mounted lustre-client [ 7594.061723] Lustre: Unmounted lustre-client [ 7624.191540] Key type lgssc unregistered [ 7624.515743] LNet: 157509:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7624.521979] LNetError: 157509:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7625.589094] LNet: Removed LNI 192.168.202.28@tcp [ 7626.411270] Key type .llcrypt unregistered [ 7626.414961] Key type ._llcrypt unregistered [ 7644.463811] Key type ._llcrypt registered [ 7644.468276] Key type .llcrypt registered [ 7645.935088] Lustre: DEBUG MARKER: == conf-sanity test 31: Connect to non-existent node (shouldn't crash) ========================================================== 16:05:08 (1782763508) [ 7647.933325] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7647.968833] alg: No test for adler32 (adler32-zlib) [ 7649.002355] Lustre: Lustre: Build Version: 2.17.54_83_g2cb683b [ 7649.275277] LNet: Added LNI 192.168.202.28@tcp [8/256/0/180] [ 7650.944230] Key type lgssc registered [ 7652.738496] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7679.328473] LustreError: 159208:0:(mgc_request.c:233:do_config_log_add()) MGC4.3.2.1@tcp: failed processing log, type 1: rc = -110 [ 7688.159653] LustreError: 159217:0:(mgc_request.c:614:do_requeue()) failed processing log: -110 [ 7727.136563] LNet: 1 local NIs in recovery (showing 1): 192.168.202.28@tcp [ 7803.935213] LNet: 1 local NIs in recovery (showing 1): 192.168.202.28@tcp [ 7835.615968] 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 [ 7835.657922] Lustre: Unmounted lustre-client [ 7835.677515] LustreError: 159208:0:(super25.c:184:lustre_fill_super()) llite: Unable to mount : rc = -110 [ 7851.056788] Key type lgssc unregistered [ 7855.520895] LNet: 159624:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7855.529155] LNetError: 159624:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7935.473698] LNet: Removed LNI 192.168.202.28@tcp [ 7936.338153] Key type .llcrypt unregistered [ 7936.346873] Key type ._llcrypt unregistered [ 7979.755854] Key type ._llcrypt registered [ 7979.762768] Key type .llcrypt registered [ 7980.198636] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7980.220732] alg: No test for adler32 (adler32-zlib) [ 7981.283785] Lustre: Lustre: Build Version: 2.17.54_83_g2cb683b [ 7981.573110] LNet: Added LNI 192.168.202.28@tcp [8/256/0/180] [ 7983.295216] Key type lgssc registered [ 7984.527523] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8040.956848] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 7824 sec ========== 16:11:43 (1782763903) [ 8043.446523] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 16:11:45 (1782763905) === [ 8048.356058] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 16:11:50 (1782763910) === [ 8088.051392] Key type lgssc unregistered [ 8088.485236] LNet: 163867:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8088.498289] LNetError: 163867:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8088.510091] LNet: Removed LNI 192.168.202.28@tcp [ 8089.470847] Key type .llcrypt unregistered [ 8089.474625] Key type ._llcrypt unregistered