[ 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-8.fc42 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 406116549 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 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 0x00000000BFFE2421 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE22BD 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 00227D (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2331 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE23C1 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE23F9 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe22bd-0xbffe2330] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22bc] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2331-0xbffe23c0] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe23c1-0xbffe23f8] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe23f9-0xbffe2420] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001014] APIC: Switch to symmetric I/O mode setup [ 0.002354] x2apic enabled [ 0.003008] Switched APIC routing to physical x2apic. [ 0.004081] kvm-guest: setup PV IPIs [ 0.007000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.007000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.007021] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.008014] pid_max: default: 32768 minimum: 301 [ 0.009134] LSM: Security Framework initializing [ 0.010053] Yama: becoming mindful. [ 0.011034] SELinux: Initializing. [ 0.012069] *** VALIDATE selinux *** [ 0.021411] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.026087] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.027139] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029103] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.030108] *** VALIDATE tmpfs *** [ 0.032249] *** VALIDATE proc *** [ 0.033247] *** VALIDATE cgroup *** [ 0.034007] *** VALIDATE cgroup2 *** [ 0.035246] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.036141] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.037007] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.038024] Spectre V2 : User space: Vulnerable [ 0.039008] Speculative Store Bypass: Vulnerable [ 0.042739] debug: unmapping init [mem 0xffffffffb5a59000-0xffffffffb5a60fff] [ 0.044264] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.045664] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.046020] ... version: 2 [ 0.047009] ... bit width: 48 [ 0.048009] ... generic registers: 4 [ 0.049009] ... value mask: 0000ffffffffffff [ 0.050013] ... max period: 00007fffffffffff [ 0.051012] ... fixed-purpose events: 3 [ 0.052008] ... event mask: 000000070000000f [ 0.053268] rcu: Hierarchical SRCU implementation. [ 0.056544] smp: Bringing up secondary CPUs ... [ 0.057634] x86: Booting SMP configuration: [ 0.058022] .... node #0, CPUs: #1 #2 #3 [ 0.064333] smp: Brought up 1 node, 4 CPUs [ 0.066015] smpboot: Max logical packages: 1 [ 0.067009] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.101643] node 0 deferred pages initialised in 33ms [ 0.106148] devtmpfs: initialized [ 0.107273] x86/mm: Memory block size: 128MB [ 0.110157] gcov: version magic: 0x41383552 [ 0.112284] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.113115] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.116422] pinctrl core: initialized pinctrl subsystem [ 0.119226] [ 0.120008] ************************************************************* [ 0.122011] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.125014] ** ** [ 0.127010] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.129011] ** ** [ 0.132012] ** This means that this kernel is built to expose internal ** [ 0.134008] ** IOMMU data structures, which may compromise security on ** [ 0.136009] ** your system. ** [ 0.138010] ** ** [ 0.140009] ** If you see this message and you are not debugging the ** [ 0.143016] ** kernel, report this immediately to your vendor! ** [ 0.145010] ** ** [ 0.147013] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.149012] ************************************************************* [ 0.152699] NET: Registered protocol family 16 [ 0.154746] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.157047] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.160062] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.163117] cpuidle: using governor menu [ 0.164888] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.167524] PCI: Using configuration type 1 for base access [ 0.169132] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.176530] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.178065] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.180209] cryptd: max_cpu_qlen set to 1000 [ 0.181231] ACPI: Added _OSI(Module Device) [ 0.182000] ACPI: Added _OSI(Processor Device) [ 0.182000] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.182000] ACPI: Added _OSI(Processor Aggregator Device) [ 0.186918] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.192640] ACPI: Interpreter enabled [ 0.194052] ACPI: PM: (supports S0 S3 S4 S5) [ 0.196010] ACPI: Using IOAPIC for interrupt routing [ 0.197173] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.200593] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.213076] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.215034] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.217016] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.220074] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.225653] acpiphp: Slot [2] registered [ 0.227085] acpiphp: Slot [5] registered [ 0.228087] acpiphp: Slot [6] registered [ 0.229181] acpiphp: Slot [3] registered [ 0.231076] acpiphp: Slot [4] registered [ 0.232068] acpiphp: Slot [7] registered [ 0.233073] acpiphp: Slot [8] registered [ 0.235075] acpiphp: Slot [9] registered [ 0.236088] acpiphp: Slot [10] registered [ 0.237074] acpiphp: Slot [11] registered [ 0.239072] acpiphp: Slot [12] registered [ 0.240070] acpiphp: Slot [13] registered [ 0.242208] acpiphp: Slot [14] registered [ 0.243083] acpiphp: Slot [15] registered [ 0.244072] acpiphp: Slot [16] registered [ 0.246094] acpiphp: Slot [17] registered [ 0.247077] acpiphp: Slot [18] registered [ 0.249166] acpiphp: Slot [19] registered [ 0.250101] acpiphp: Slot [20] registered [ 0.251124] acpiphp: Slot [21] registered [ 0.253085] acpiphp: Slot [22] registered [ 0.254084] acpiphp: Slot [23] registered [ 0.255125] acpiphp: Slot [24] registered [ 0.257074] acpiphp: Slot [25] registered [ 0.258112] acpiphp: Slot [26] registered [ 0.260142] acpiphp: Slot [27] registered [ 0.261071] acpiphp: Slot [28] registered [ 0.262102] acpiphp: Slot [29] registered [ 0.264076] acpiphp: Slot [30] registered [ 0.265083] acpiphp: Slot [31] registered [ 0.266147] PCI host bridge to bus 0000:00 [ 0.268013] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.270029] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.272044] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.275039] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.277053] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.280067] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.281288] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.285157] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.288319] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.296072] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.300051] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.303016] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.305019] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.307014] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.310357] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.312850] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.315040] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.318842] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.323012] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.331698] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.336013] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.342470] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.347010] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.352014] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.363013] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.376012] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.394026] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.402017] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.413016] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.423837] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.425372] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.428418] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.430378] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.432384] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.437098] iommu: Default domain type: Passthrough [ 0.438865] SCSI subsystem initialized [ 0.441122] ACPI: bus type USB registered [ 0.442105] usbcore: registered new interface driver usbfs [ 0.444097] usbcore: registered new interface driver hub [ 0.447109] usbcore: registered new device driver usb [ 0.449158] pps_core: LinuxPPS API ver. 1 registered [ 0.451012] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.454057] PTP clock support registered [ 0.464202] EDAC MC: Ver: 3.0.0 [ 0.465000] PCI: Using ACPI for IRQ routing [ 0.465000] NetLabel: Initializing [ 0.465000] NetLabel: domain hash size = 128 [ 0.465000] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.465000] NetLabel: unlabeled traffic allowed by default [ 0.465000] vgaarb: loaded [ 0.465000] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.465000] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.465179] clocksource: Switched to clocksource kvm-clock [ 0.587209] VFS: Disk quotas dquot_6.6.0 [ 0.588381] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.590444] *** VALIDATE ramfs *** [ 0.591518] *** VALIDATE hugetlbfs *** [ 0.593949] pnp: PnP ACPI init [ 0.596924] pnp: PnP ACPI: found 6 devices [ 0.613019] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.616394] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.618184] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.620326] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.622711] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.624804] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.627420] NET: Registered protocol family 2 [ 0.629693] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.634604] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.637873] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.642774] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.645781] TCP: Hash tables configured (established 65536 bind 65536) [ 0.648477] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.651125] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.653735] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.656487] NET: Registered protocol family 1 [ 0.658583] RPC: Registered named UNIX socket transport module. [ 0.660607] RPC: Registered udp transport module. [ 0.663116] RPC: Registered tcp transport module. [ 0.667420] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.670630] NET: Registered protocol family 44 [ 0.676091] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.678098] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.680091] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.682021] PCI: CLS 0 bytes, default 64 [ 0.683588] Unpacking initramfs... [ 2.337935] debug: unmapping init [mem 0xffff9dd43cc64000-0xffff9dd43ffcffff] [ 2.342431] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.344886] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.348206] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.885876] Initialise system trusted keyrings [ 2.887575] Key type blacklist registered [ 2.889296] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.898243] zbud: loaded [ 2.900875] *** VALIDATE nfs *** [ 2.902030] *** VALIDATE nfs4 *** [ 2.903750] pstore: using deflate compression [ 2.907229] Platform Keyring initialized [ 3.050502] NET: Registered protocol family 38 [ 3.052332] Key type asymmetric registered [ 3.053803] Asymmetric key parser 'x509' registered [ 3.055676] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 3.059289] io scheduler mq-deadline registered [ 3.061449] io scheduler kyber registered [ 3.063933] io scheduler bfq registered [ 3.066569] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 3.069578] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 3.071885] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 3.076650] ACPI: Power Button [PWRF] [ 3.081829] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.089602] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.098112] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.122185] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.166552] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.171274] Non-volatile memory driver v1.3 [ 3.172389] Linux agpgart interface v0.103 [ 3.198274] virtio_blk virtio1: [vda] 134096 512-byte logical blocks (68.7 MB/65.5 MiB) [ 3.201029] vda: detected capacity change from 0 to 68657152 [ 3.220847] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.222685] vdb: detected capacity change from 0 to 1073741824 [ 3.240790] libphy: Fixed MDIO Bus: probed [ 3.275735] usbcore: registered new interface driver usbserial_generic [ 3.277389] usbserial: USB Serial support registered for generic [ 3.279154] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.283585] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.285906] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.297149] mousedev: PS/2 mouse device common for all mice [ 3.301328] rtc_cmos 00:05: RTC can wake from S4 [ 3.305464] rtc_cmos 00:05: registered as rtc0 [ 3.308929] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.312334] intel_pstate: CPU model not supported [ 3.316965] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.322493] hid: raw HID events driver (C) Jiri Kosina [ 3.325927] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.332733] usbcore: registered new interface driver usbhid [ 3.332741] usbhid: USB HID core driver [ 3.332872] drop_monitor: Initializing network drop monitor service [ 3.333023] Initializing XFRM netlink socket [ 3.333533] NET: Registered protocol family 10 [ 3.334633] Segment Routing with IPv6 [ 3.334667] NET: Registered protocol family 17 [ 3.340912] mpls_gso: MPLS GSO support [ 3.342555] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.380099] RAS: Correctable Errors collector initialized. [ 3.381822] AVX version of gcm_enc/dec engaged. [ 3.383691] AES CTR mode by8 optimization enabled [ 3.572704] sched_clock: Marking stable (3572687192, 0)->(4576367108, -1003679916) [ 3.600439] registered taskstats version 1 [ 3.605961] Loading compiled-in X.509 certificates [ 3.608378] zswap: loaded using pool lzo/zbud [ 3.642172] Key type big_key registered [ 3.652861] Key type encrypted registered [ 3.654154] ima: No TPM chip found, activating TPM-bypass! [ 3.655715] ima: Allocated hash algorithm: sha1 [ 3.657066] ima: No architecture policies found [ 3.658682] evm: Initialising EVM extended attributes: [ 3.660464] evm: security.selinux [ 3.661634] evm: security.ima [ 3.662512] evm: security.capability [ 3.663543] evm: HMAC attrs: 0x1 [ 3.668770] rtc_cmos 00:05: setting system clock to 2026-01-16 06:55:30 UTC (1768546530) [ 3.680303] debug: unmapping init [mem 0xffffffffb6a03000-0xffffffffb6bfffff] [ 3.688389] debug: unmapping init [mem 0xffffffffb5782000-0xffffffffb5a58fff] [ 3.699110] Write protecting the kernel read-only data: 28672k [ 3.708323] debug: unmapping init [mem 0xffffffffb3e03000-0xffffffffb3ffffff] [ 3.715379] debug: unmapping init [mem 0xffffffffb4714000-0xffffffffb47fffff] [ 3.790330] 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.811435] systemd[1]: Detected virtualization kvm. [ 3.812933] systemd[1]: Detected architecture x86-64. [ 3.818077] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.869725] systemd[1]: No hostname configured. [ 3.872438] systemd[1]: Set hostname to . [ 3.876991] random: systemd: uninitialized urandom read (16 bytes read) [ 3.880752] systemd[1]: Initializing machine ID from random generator. [ 4.192081] random: systemd: uninitialized urandom read (16 bytes read) [ 4.194427] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ 4.230129] random: systemd: uninitialized urandom read (16 bytes read) [ 4.232516] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ 4.245717] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Slices. [ OK ] Listening on Journal Socket. [ OK ] Started Memstrack Anylazing Service. Starting Create list of required st…ce nodes for the current kernel... Starting Apply Kernel Variables... Starting Setup Virtual Console... [ OK ] Reached target Initrd Root Device. [ OK ] Reached target Timers. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. Starting Create Volatile Files and Directories... [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Sockets. Starting Journal Service... [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. [ OK ] Started Setup Virtual Console. [ OK ] Started Create Volatile Files and Directories. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 5.508949] device-mapper: uevent: version 1.0.3 [ 5.511720] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 6.573347] virtio_net virtio0 ens2: renamed from eth0 Starting dracut initqueue hook... [ 6.634290] random: fast init done [ 6.792788] scsi host0: ata_piix [ 6.840081] scsi host1: ata_piix [ 6.842037] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 6.846250] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 12.172752] random: crng init done [ 12.177931] random: 7 urandom warning(s) missed due to ratelimiting [ 13.062940] dracut-initqueue[586]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 14.407095] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Initrd Default Target. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Timers. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Sockets. [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ OK ] Stopped target System Initialization. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Swap. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 16.436607] printk: systemd: 26 output lines suppressed due to ratelimiting [ 17.295582] SELinux: Disabled at runtime. [ 17.450573] 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) [ 17.477356] systemd[1]: Detected virtualization kvm. [ 17.484657] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 19.135928] systemd[1]: initrd-switch-root.service: Succeeded. [ 19.151356] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 19.167189] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 19.175422] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 19.186313] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 19.208200] systemd[1]: Starting Journal Service... Starting Journal Service... [ 19.263473] systemd[1]: Listening on Process Core Dump Socket. [ OK ] Listening on Process Core Dump Socket. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Listening on udev Control Socket. Mounting Kernel Debug File System... Mounting POSIX Message Queue File System... [ OK ] Reached target rpc_pipefs.target. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Paths. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Created slice system-getty.slice. [ OK ] Listening on RPCbind Server Activation Socket. Starting Remount Root and Kernel File Systems... Mounting Huge Pages File System... [ OK ] Listening on udev Kernel Socket. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Created slice system-sshd\x2dkeygen.slice. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target RPC Port Mapper. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd Root 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 udev Coldplug all Devices... [ OK ] Stopped target Initrd File Systems. Activating swap /dev/disk/by-label/SWAP... Starting Apply Kernel Variables... [ OK ] Listening on initctl Compatibility Named Pipe. [ 19.838120] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Mounted Kernel Debug File System. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Started Journal Service. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted Huge Pages File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Started Apply Kernel Variables. [ OK ] Reached target Swap. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started udev Coldplug all Devices. [ OK ] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... [ OK ] Mounted /mnt. [ 20.554016] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 21.277919] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 21.338986] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 21.866804] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 21.986854] EDAC sbridge: Ver: 1.1.2 [ 25.592317] Key type dns_resolver registered [* ] A start job is running for Configur…-only root support (7s / no limit)[ 26.360453] NFS: Registering the id_resolver key type [ 26.361969] Key type id_resolver registered [ 26.363858] Key type id_legacy registered [** ] A start job is running for Configur…-only root support (7s / no limit) [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Mark the need to relabel after reboot... Starting Rebuild Dynamic Linker Cache... Starting Create Volatile Files and Directories... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started dnf makecache --timer. [ OK ] Started Daily Cleanup of Temporary Directories. [ 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 irqbalance daemon. [ OK ] Started D-Bus System Message Bus. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Timers. Starting Restore /run/initramfs on shutdown... Starting Network Manager... Starting Login Service... [ OK ] Reached target sshd-keygen.target. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... Starting OpenSSH server daemon... Starting Network Manager Wait Online... Starting Hostname Service... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started OpenSSH server daemon. [ OK ] Started Permit User Sessions. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Reached target Login Prompts. [ OK ] Started Command Scheduler. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting System Logging Service... Starting Crash recovery kernel arming... Starting Notify NFS peers of a restart... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg460-client login: [ 53.378014] hrtimer: interrupt took 4003656 ns [ 76.856993] libcfs: loading out-of-tree module taints kernel. [ 76.966304] Key type ._llcrypt registered [ 76.967742] Key type .llcrypt registered [ 77.215725] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 77.227129] alg: No test for adler32 (adler32-zlib) [ 78.360170] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 78.810453] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [ 80.471208] Key type lgssc registered [ 81.356200] Lustre: Echo OBD driver; http://www.lustre.org/ [ 176.247479] Lustre: Mounted lustre-client [ 179.214553] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 191.384341] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing check_logdir /tmp/testlogs/ [ 194.616839] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing yml_node [ 198.573308] Lustre: DEBUG MARKER: Client: 2.17.50.30 [ 200.656443] Lustre: DEBUG MARKER: MDS: 2.17.50.30 [ 201.695316] Lustre: lustre-OST0000-osc-ffff9dd4845c9800: disconnect after 24s idle [ 202.993868] Lustre: DEBUG MARKER: OSS: 2.17.50.30 [ 204.807527] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Fri Jan 16 01:58:50 EST 2026 [ 221.891539] Lustre: DEBUG MARKER: excepting tests: 21e 32b 32c 32newtarball [ 223.385210] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 224.997399] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 235.249672] LustreError: 8975:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 235.278423] Lustre: Unmounted lustre-client [ 321.175729] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 323.424629] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 337.850790] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 367.734909] Lustre: DEBUG MARKER: == conf-sanity test 0: single mount setup ================ 02:01:33 (1768546893) [ 392.612521] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 395.162358] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 407.831592] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 423.987835] Lustre: Mounted lustre-client [ 427.219026] LustreError: 14073:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9dd484f3b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 427.241455] LustreError: 14073:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 427.246106] LustreError: 14073:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 427.307963] Lustre: Unmounted lustre-client [ 452.199439] Key type lgssc unregistered [ 452.404952] LNet: 14475:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 452.410741] LNetError: 14475:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 452.425483] LNet: Removed LNI 192.168.204.60@tcp [ 452.922157] Key type .llcrypt unregistered [ 452.923652] Key type ._llcrypt unregistered [ 465.015051] Key type ._llcrypt registered [ 465.018825] Key type .llcrypt registered [ 466.002211] Lustre: DEBUG MARKER: == conf-sanity test 1: start up ost twice (should return errors) ========================================================== 02:03:11 (1768546991) [ 467.849552] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 467.960240] alg: No test for adler32 (adler32-zlib) [ 469.005528] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 469.176348] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [ 470.807242] Key type lgssc registered [ 471.892188] Lustre: Echo OBD driver; http://www.lustre.org/ [ 505.677883] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 508.103544] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 520.292654] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 527.931365] Lustre: Mounted lustre-client [ 529.109299] LustreError: 17654:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9dd4834fb800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 529.121851] LustreError: 17654:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 529.166324] Lustre: Unmounted lustre-client [ 550.648224] Key type lgssc unregistered [ 550.802786] LNet: 18055:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 550.807876] LNetError: 18055:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 550.821564] LNet: Removed LNI 192.168.204.60@tcp [ 551.240452] Key type .llcrypt unregistered [ 551.242584] Key type ._llcrypt unregistered [ 560.397049] Key type ._llcrypt registered [ 560.398749] Key type .llcrypt registered [ 561.413728] Lustre: DEBUG MARKER: == conf-sanity test 2: start up mds twice (should return err) ========================================================== 02:04:47 (1768547087) [ 562.974153] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 562.984845] alg: No test for adler32 (adler32-zlib) [ 563.938981] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 564.099462] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [ 565.743163] Key type lgssc registered [ 566.548386] Lustre: Echo OBD driver; http://www.lustre.org/ [ 597.624287] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 600.050089] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 614.129815] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 630.799415] Lustre: Mounted lustre-client [ 631.993894] LustreError: 21213:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9dd487263000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 632.019445] LustreError: 21213:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 632.060617] Lustre: Unmounted lustre-client [ 662.726328] Key type lgssc unregistered [ 662.901030] LNet: 21617:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 662.907724] LNetError: 21617:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 662.919818] LNet: Removed LNI 192.168.204.60@tcp [ 663.330712] Key type .llcrypt unregistered [ 663.333376] Key type ._llcrypt unregistered [ 672.878845] Key type ._llcrypt registered [ 672.880653] Key type .llcrypt registered [ 673.688945] Lustre: DEBUG MARKER: == conf-sanity test 3: mount client twice (should return err) ========================================================== 02:06:39 (1768547199) [ 674.959970] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 674.982757] alg: No test for adler32 (adler32-zlib) [ 675.915436] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 676.069665] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [ 677.687677] Key type lgssc registered [ 678.483952] Lustre: Echo OBD driver; http://www.lustre.org/ [ 704.035132] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 705.805492] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 715.447485] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 721.899776] Lustre: Mounted lustre-client [ 728.206292] LustreError: 24736:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9dd48869a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 728.217445] LustreError: 24736:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 728.245839] Lustre: Unmounted lustre-client [ 758.724938] Key type lgssc unregistered [ 758.935146] LNet: 25137:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 758.940437] LNetError: 25137:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 758.963735] LNet: Removed LNI 192.168.204.60@tcp [ 759.420238] Key type .llcrypt unregistered [ 759.421955] Key type ._llcrypt unregistered [ 768.118330] Key type ._llcrypt registered [ 768.120180] Key type .llcrypt registered [ 768.874385] Lustre: DEBUG MARKER: == conf-sanity test 4: force cleanup ost, then cleanup === 02:08:14 (1768547294) [ 770.055814] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 770.085374] alg: No test for adler32 (adler32-zlib) [ 770.983869] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 771.101609] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [ 772.703231] Key type lgssc registered [ 773.383314] Lustre: Echo OBD driver; http://www.lustre.org/ [ 799.061635] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 800.889882] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 811.308567] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 813.638162] Lustre: Mounted lustre-client [ 818.663765] Lustre: lustre-OST0000-osc-ffff9dd484f3d000: Connection to lustre-OST0000 (at 192.168.204.160@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 824.154575] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 834.415132] LustreError: 28266:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 834.455484] Lustre: Unmounted lustre-client [ 863.647564] Key type lgssc unregistered [ 864.215604] LNet: 28663:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 864.242063] LNetError: 28663:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 864.280712] LNet: Removed LNI 192.168.204.60@tcp [ 865.599079] Key type .llcrypt unregistered [ 865.600673] Key type ._llcrypt unregistered [ 888.403632] Key type ._llcrypt registered [ 888.405090] Key type .llcrypt registered [ 889.710704] Lustre: DEBUG MARKER: == conf-sanity test 5a: force cleanup mds, then cleanup == 02:10:14 (1768547414) [ 894.043827] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 894.113762] alg: No test for adler32 (adler32-zlib) [ 895.358517] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 896.148499] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [ 898.095184] Key type lgssc registered [ 901.227497] Lustre: Echo OBD driver; http://www.lustre.org/ [ 961.029554] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 965.580208] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 985.420092] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 987.603417] Lustre: Mounted lustre-client [ 992.750916] Lustre: lustre-MDT0000-mdc-ffff9dd487341800: Connection to lustre-MDT0000 (at 192.168.204.160@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1002.997569] Lustre: lustre-MDT0001-mdc-ffff9dd487341800: Connection to lustre-MDT0001 (at 192.168.204.160@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1011.185049] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1018.207158] Lustre: 29619:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1768547529/real 1768547529] req@000000002d9b6dfe x1854456381577216/t0(0) o400->MGC192.168.204.160@tcp@192.168.204.160@tcp:26/25 lens 224/224 e 0 to 1 dl 1768547545 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1018.229635] LustreError: MGC192.168.204.160@tcp: Connection to MGS (at 192.168.204.160@tcp) was lost; in progress operations using this service will fail [ 1021.492374] LustreError: 31840:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1021.553378] Lustre: Unmounted lustre-client [ 1042.584268] Key type lgssc unregistered [ 1042.859910] LNet: 32235:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1042.865895] LNetError: 32235:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1042.887591] LNet: Removed LNI 192.168.204.60@tcp [ 1043.693282] Key type .llcrypt unregistered [ 1043.695777] Key type ._llcrypt unregistered [ 1061.685715] Key type ._llcrypt registered [ 1061.688763] Key type .llcrypt registered [ 1062.895944] Lustre: DEBUG MARKER: == conf-sanity test 5b: Try to start a client with no MGS (should return errs) ========================================================== 02:13:08 (1768547588) [ 1065.512754] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1065.549745] alg: No test for adler32 (adler32-zlib) [ 1066.612386] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 1066.825339] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [ 1068.471163] Key type lgssc registered [ 1069.627491] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1153.842049] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1159.525196] LustreError: 34405:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.204.160@tcp: failed processing log, type 1: rc = -110 [ 1168.351311] LustreError: 34414:0:(mgc_request.c:614:do_requeue()) failed processing log: -110 [ 1318.879439] LustreError: MGC192.168.204.160@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 [ 1318.912652] Lustre: Unmounted lustre-client [ 1318.924247] LustreError: 34405:0:(obd_class.h:479:obd_check_dev()) Device 0 not setup [ 1318.950919] LustreError: 34405:0:(super25.c:199:lustre_fill_super()) llite: Unable to mount : rc = -110 [ 1337.656499] Key type lgssc unregistered [ 1337.962430] LNet: 34840:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1337.967804] LNetError: 34840:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1339.053181] LNet: Removed LNI 192.168.204.60@tcp [ 1339.983816] Key type .llcrypt unregistered [ 1339.985483] Key type ._llcrypt unregistered [ 1358.619375] Key type ._llcrypt registered [ 1358.620699] Key type .llcrypt registered [ 1359.838523] Lustre: DEBUG MARKER: == conf-sanity test 5c: cleanup after failed mount (bug 2712) (should return errs) ========================================================== 02:18:05 (1768547885) [ 1362.771425] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1362.800172] alg: No test for adler32 (adler32-zlib) [ 1364.016990] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 1364.289552] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [ 1365.983182] Key type lgssc registered [ 1367.297531] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1424.909393] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1428.785805] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1447.975968] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1448.722919] LustreError: 37843:0:(llite_lib.c:1440:ll_fill_super()) wrong.lustre: fsname longer than 8 characters: rc = -36 [ 1448.735914] Lustre: Unmounted wrong.lustre-client [ 1448.753824] LustreError: 37843:0:(obd_class.h:479:obd_check_dev()) Device 0 not setup [ 1448.759044] LustreError: 37843:0:(super25.c:199:lustre_fill_super()) llite: Unable to mount : rc = -36 [ 1478.787949] Key type lgssc unregistered [ 1479.071766] LNet: 38258:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1479.075748] LNetError: 38258:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1479.096759] LNet: Removed LNI 192.168.204.60@tcp [ 1479.821916] Key type .llcrypt unregistered [ 1479.826259] Key type ._llcrypt unregistered [ 1498.455275] Key type ._llcrypt registered [ 1498.459711] Key type .llcrypt registered [ 1499.951258] Lustre: DEBUG MARKER: == conf-sanity test 5d: mount with ost down ============== 02:20:25 (1768548025) [ 1502.982052] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1503.136689] alg: No test for adler32 (adler32-zlib) [ 1504.391735] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 1504.632064] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [ 1506.266532] Key type lgssc registered [ 1507.803358] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1594.595739] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1632.981713] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1637.509527] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1659.020215] Lustre: Mounted lustre-client [ 1661.770754] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1672.057364] LustreError: 41348:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1672.150859] Lustre: Unmounted lustre-client [ 1702.323875] Key type lgssc unregistered [ 1702.627099] LNet: 41744:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1702.637241] LNetError: 41744:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1702.661361] LNet: Removed LNI 192.168.204.60@tcp [ 1703.471278] Key type .llcrypt unregistered [ 1703.473157] Key type ._llcrypt unregistered [ 1720.763502] Key type ._llcrypt registered [ 1720.770749] Key type .llcrypt registered [ 1722.260586] Lustre: DEBUG MARKER: == conf-sanity test 5e: delayed connect, don't crash (bug 10268) ========================================================== 02:24:07 (1768548247) [ 1725.127365] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1725.165210] alg: No test for adler32 (adler32-zlib) [ 1726.262973] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 1726.563772] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [ 1728.304147] Key type lgssc registered [ 1729.990531] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1782.232518] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1786.892270] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1809.415811] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1809.994359] LustreError: 42700:0:(niobuf.c:1105:ptl_send_rpc()) cfs_fail_timeout id 506 sleeping for 10000ms [ 1815.139608] LustreError: 44736:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.204.160@tcp: failed processing log, type 1: rc = -110 [ 1820.031254] LustreError: 42700:0:(niobuf.c:1105:ptl_send_rpc()) cfs_fail_timeout id 506 awake [ 1821.435074] Lustre: Mounted lustre-client [ 1824.318554] LustreError: 44796:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9dd486ee9800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1824.354971] LustreError: 44796:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1824.442625] Lustre: Unmounted lustre-client [ 1854.503492] Key type lgssc unregistered [ 1854.831330] LNet: 45198:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1854.859978] LNetError: 45198:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1854.887445] LNet: Removed LNI 192.168.204.60@tcp [ 1855.757472] Key type .llcrypt unregistered [ 1855.758953] Key type ._llcrypt unregistered [ 1873.388801] Key type ._llcrypt registered [ 1873.391702] Key type .llcrypt registered [ 1875.003842] Lustre: DEBUG MARKER: == conf-sanity test 5f: mds down, cleanup after failed mount (bug 2712) ========================================================== 02:26:40 (1768548400) [ 1876.778566] Lustre: DEBUG MARKER: SKIP: conf-sanity test_5f needs separate mgs and mds [ 1878.398797] Lustre: DEBUG MARKER: == conf-sanity test 5g: handle missing debugfs =========== 02:26:43 (1768548403) [ 1879.735868] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1879.754018] alg: No test for adler32 (adler32-zlib) [ 1880.815986] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 1881.083192] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [ 1882.775339] Key type lgssc registered [ 1884.435535] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1908.244762] Lustre: DEBUG MARKER: == conf-sanity test 5h: start mdt failure at mdt_fs_setup() ========================================================== 02:27:13 (1768548433) [ 1941.452991] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1946.228205] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1967.490805] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1970.150140] Lustre: Mounted lustre-client [ 1975.277750] Lustre: lustre-MDT0000-mdc-ffff9dd4933fd000: Connection to lustre-MDT0000 (at 192.168.204.160@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1991.711159] Lustre: 46416:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1768548502/real 1768548502] req@00000000d8aef63c x1854457414422272/t0(0) o400->MGC192.168.204.160@tcp@192.168.204.160@tcp:26/25 lens 224/224 e 0 to 1 dl 1768548518 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1991.739834] LustreError: MGC192.168.204.160@tcp: Connection to MGS (at 192.168.204.160@tcp) was lost; in progress operations using this service will fail [ 1999.834701] Lustre: lustre-MDT0000-mdc-ffff9dd4933fd000: Connection restored to 192.168.204.160@tcp (at 192.168.204.160@tcp) [ 2001.962683] Lustre: Evicted from MGS (at 192.168.204.160@tcp) after server handle changed from 0xffe0a5cd1c8a2d5 to 0xffe0a5cd1c8a624 [ 2001.984323] Lustre: MGC192.168.204.160@tcp: Connection restored to 192.168.204.160@tcp (at 192.168.204.160@tcp) [ 2007.163061] LustreError: 49561:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9dd4933fd000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2007.194926] LustreError: 49561:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2007.287724] Lustre: Unmounted lustre-client [ 2036.264836] Key type lgssc unregistered [ 2036.666142] LNet: 49964:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2036.677029] LNetError: 49964:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2036.697172] LNet: Removed LNI 192.168.204.60@tcp [ 2037.498789] Key type .llcrypt unregistered [ 2037.500500] Key type ._llcrypt unregistered [ 2055.314618] Key type ._llcrypt registered [ 2055.321094] Key type .llcrypt registered [ 2056.923953] Lustre: DEBUG MARKER: == conf-sanity test 5i: start mdt failure at mdt_quota_init() ========================================================== 02:29:41 (1768548581) [ 2060.174417] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2060.271339] alg: No test for adler32 (adler32-zlib) [ 2061.477540] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 2061.811669] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [ 2063.551363] Key type lgssc registered [ 2064.846907] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2113.279959] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2116.911393] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2136.335879] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2139.174434] Lustre: Mounted lustre-client [ 2144.249517] Lustre: lustre-MDT0000-mdc-ffff9dd48d5fb800: Connection to lustre-MDT0000 (at 192.168.204.160@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2160.607721] Lustre: 50923:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1768548671/real 1768548671] req@00000000b2e36920 x1854457604214400/t0(0) o400->MGC192.168.204.160@tcp@192.168.204.160@tcp:26/25 lens 224/224 e 0 to 1 dl 1768548687 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2160.659757] LustreError: MGC192.168.204.160@tcp: Connection to MGS (at 192.168.204.160@tcp) was lost; in progress operations using this service will fail [ 2160.712802] Lustre: Evicted from MGS (at 192.168.204.160@tcp) after server handle changed from 0xc35b2cb188528c9b to 0xc35b2cb188528e77 [ 2160.724588] Lustre: MGC192.168.204.160@tcp: Connection restored to 192.168.204.160@tcp (at 192.168.204.160@tcp) [ 2165.767030] Lustre: lustre-MDT0000-mdc-ffff9dd48d5fb800: Connection restored to 192.168.204.160@tcp (at 192.168.204.160@tcp) [ 2172.024884] LustreError: 53442:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9dd48d5fb800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2172.059823] LustreError: 53442:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2172.110031] Lustre: Unmounted lustre-client [ 2199.818847] Key type lgssc unregistered [ 2200.143824] LNet: 53845:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2200.157219] LNetError: 53845:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2200.178454] LNet: Removed LNI 192.168.204.60@tcp [ 2200.860195] Key type .llcrypt unregistered [ 2200.862324] Key type ._llcrypt unregistered [ 2216.372533] Key type ._llcrypt registered [ 2216.378780] Key type .llcrypt registered [ 2217.625894] Lustre: DEBUG MARKER: == conf-sanity test 5j: verify libcfs doesn't crash when setting debug flags ========================================================== 02:32:23 (1768548743) [ 2218.087660] Key type .llcrypt unregistered [ 2218.092327] Key type ._llcrypt unregistered [ 2224.156852] Key type ._llcrypt registered [ 2224.159408] Key type .llcrypt registered [ 2224.236481] Key type .llcrypt unregistered [ 2224.238129] Key type ._llcrypt unregistered [ 2229.624444] Key type ._llcrypt registered [ 2229.626045] Key type .llcrypt registered [ 2230.910577] Lustre: DEBUG MARKER: == conf-sanity test 6: manual umount, then mount again === 02:32:36 (1768548756) [ 2233.602419] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2233.651452] alg: No test for adler32 (adler32-zlib) [ 2234.796936] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 2235.109584] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [ 2236.791191] Key type lgssc registered [ 2238.224958] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2282.831496] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2287.033090] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2305.860960] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2308.352777] Lustre: Mounted lustre-client [ 2310.231813] LustreError: 57706:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9dd485cea800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2310.252774] LustreError: 57706:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2310.320131] Lustre: Unmounted lustre-client [ 2310.679740] Lustre: Mounted lustre-client [ 2312.477825] LustreError: 57786:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9dd486c1c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2312.496941] LustreError: 57786:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2312.502392] LustreError: 57786:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 2312.597144] Lustre: Unmounted lustre-client [ 2338.257265] Key type lgssc unregistered [ 2338.590879] LNet: 58188:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2338.600368] LNetError: 58188:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2338.615969] LNet: Removed LNI 192.168.204.60@tcp [ 2339.150322] Key type .llcrypt unregistered [ 2339.154968] Key type ._llcrypt unregistered [ 2354.039384] Key type ._llcrypt registered [ 2354.041310] Key type .llcrypt registered [ 2355.211805] Lustre: DEBUG MARKER: == conf-sanity test 7: manual umount, then cleanup ======= 02:34:40 (1768548880) [ 2357.262991] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2357.281748] alg: No test for adler32 (adler32-zlib) [ 2358.247964] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 2358.413289] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [ 2360.111300] Key type lgssc registered [ 2361.164736] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2404.937724] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2408.852252] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2427.201373] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2430.072212] Lustre: Mounted lustre-client [ 2432.410800] LustreError: 61257:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9dd486c1d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2432.432931] LustreError: 61257:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2432.514141] Lustre: Unmounted lustre-client [ 2459.747740] Key type lgssc unregistered [ 2459.948941] LNet: 61666:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2459.953845] LNetError: 61666:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2459.988081] LNet: Removed LNI 192.168.204.60@tcp [ 2460.768614] Key type .llcrypt unregistered [ 2460.772588] Key type ._llcrypt unregistered [ 2476.097539] Key type ._llcrypt registered [ 2476.101086] Key type .llcrypt registered [ 2477.219186] Lustre: DEBUG MARKER: == conf-sanity test 8: double mount setup ================ 02:36:42 (1768549002) [ 2479.429412] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2479.575097] alg: No test for adler32 (adler32-zlib) [ 2480.585321] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 2480.753347] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [ 2482.399165] Key type lgssc registered [ 2483.527643] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2535.501790] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2539.756302] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2559.937079] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2562.728752] Lustre: Mounted lustre-client [ 2564.886950] Lustre: Mounted lustre-client [ 2567.744676] LustreError: 64828:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9dd488432800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2567.764303] LustreError: 64828:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 2567.833720] Lustre: Unmounted lustre-client [ 2569.403927] LustreError: 64848:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9dd487118800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2569.425411] LustreError: 64848:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2569.429514] LustreError: 64848:0:(obd_class.h:479:obd_check_dev()) Skipped 5 previous similar messages [ 2569.495527] Lustre: Unmounted lustre-client [ 2603.983702] Key type lgssc unregistered [ 2604.236795] LNet: 65251:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2604.259702] LNetError: 65251:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2604.273867] LNet: Removed LNI 192.168.204.60@tcp [ 2604.952303] Key type .llcrypt unregistered [ 2604.954229] Key type ._llcrypt unregistered [ 2620.561134] Key type ._llcrypt registered [ 2620.564360] Key type .llcrypt registered [ 2621.825541] Lustre: DEBUG MARKER: == conf-sanity test 9: test ptldebug and subsystem for mkfs ========================================================== 02:39:07 (1768549147) [ 2624.239351] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2624.285351] alg: No test for adler32 (adler32-zlib) [ 2625.259169] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 2625.440492] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [ 2627.080713] Key type lgssc registered [ 2628.303780] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2711.033282] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2725.048849] Lustre: DEBUG MARKER: == conf-sanity test 10a: find lctl param broken symlinks ========================================================== 02:40:50 (1768549250) [ 2753.620568] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2757.815854] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2777.372916] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2779.149630] Lustre: Mounted lustre-client [ 2786.808971] LustreError: 69502:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9dd487b65000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2786.863080] LustreError: 69502:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2786.953198] Lustre: Unmounted lustre-client [ 2824.508873] Key type lgssc unregistered [ 2824.733088] LNet: 69906:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2824.740488] LNetError: 69906:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2824.755520] LNet: Removed LNI 192.168.204.60@tcp [ 2825.482662] Key type .llcrypt unregistered [ 2825.488225] Key type ._llcrypt unregistered [ 2841.609890] Key type ._llcrypt registered [ 2841.612212] Key type .llcrypt registered [ 2842.926765] Lustre: DEBUG MARKER: == conf-sanity test 17: Verify failed mds_postsetup won't fail assertion (2936) (should return errs) ========================================================== 02:42:48 (1768549368) [ 2845.256481] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2845.337540] alg: No test for adler32 (adler32-zlib) [ 2846.473148] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 2846.652160] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [ 2848.343213] Key type lgssc registered [ 2849.805441] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2896.538571] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2900.206140] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2920.988104] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2923.779355] Lustre: Mounted lustre-client [ 2928.147950] LustreError: 73014:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9dd489dd7800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2928.169318] LustreError: 73014:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2928.243923] Lustre: Unmounted lustre-client [ 2956.038728] Key type lgssc unregistered [ 2956.237596] LNet: 73417:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2956.245798] LNetError: 73417:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2956.260590] LNet: Removed LNI 192.168.204.60@tcp [ 2956.928197] Key type .llcrypt unregistered [ 2956.929884] Key type ._llcrypt unregistered [ 2971.430741] Key type ._llcrypt registered [ 2971.434500] Key type .llcrypt registered [ 2971.928572] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2971.962224] alg: No test for adler32 (adler32-zlib) [ 2973.135712] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 2973.404305] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [ 2975.087185] Key type lgssc registered [ 2976.153459] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3059.414754] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3177.609795] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3181.378638] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3205.177681] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3251.540712] Lustre: DEBUG MARKER: == conf-sanity test 18: check mkfs creates large journals ========================================================== 02:49:36 (1768549776) [ 3256.475667] Lustre: DEBUG MARKER: use device /dev/mapper/mds1_flakey with MIN=2000000 [ 3359.113680] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3363.674843] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3388.534328] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3458.431666] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3462.945434] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3487.494175] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3490.286474] Lustre: Mounted lustre-client [ 3495.981767] Lustre: DEBUG MARKER: Success: mkfs creates large journals. Size: 78M [ 3498.537775] LustreError: 84427:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9dd487266000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3498.569431] LustreError: 84427:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3498.631578] Lustre: Unmounted lustre-client [ 3529.040433] Key type lgssc unregistered [ 3529.443594] LNet: 84831:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3529.462539] LNetError: 84831:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3529.490905] LNet: Removed LNI 192.168.204.60@tcp [ 3530.464854] Key type .llcrypt unregistered [ 3530.474558] Key type ._llcrypt unregistered [ 3556.637864] Key type ._llcrypt registered [ 3556.650217] Key type .llcrypt registered [ 3557.291912] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3557.306870] alg: No test for adler32 (adler32-zlib) [ 3558.453040] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 3558.697887] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [ 3560.328540] Key type lgssc registered [ 3561.646869] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3644.350910] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3647.744303] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3670.054364] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3713.884722] Lustre: DEBUG MARKER: == conf-sanity test 19a: start/stop MDS without OSTs ===== 02:57:19 (1768550239) [ 3747.145139] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3751.319228] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3772.195367] Lustre: DEBUG MARKER: == conf-sanity test 19b: start/stop OSTs without MDS ===== 02:58:17 (1768550297) [ 3839.290889] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3848.193650] Lustre: DEBUG MARKER: == conf-sanity test 20: remount ro,rw mounts work and doesn't break /etc/mtab ========================================================== 02:59:33 (1768550373) [ 3880.424922] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3883.981219] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3903.761732] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3905.368980] Lustre: Mounted lustre-client [ 3906.373139] Lustre: Remounted lustre-client read-only [ 3909.188199] LustreError: 93327:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9dd488eb8000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3909.225319] LustreError: 93327:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3909.274581] Lustre: Unmounted lustre-client [ 3939.197627] Lustre: DEBUG MARKER: == conf-sanity test 21a: start mds before ost, stop ost first ========================================================== 03:01:04 (1768550464) [ 3973.398681] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3979.362336] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4003.514927] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4052.655905] Lustre: DEBUG MARKER: == conf-sanity test 21b: start ost before mds, stop mds first ========================================================== 03:02:57 (1768550577) [ 4118.396760] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4152.281253] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4155.600828] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4203.375836] Lustre: DEBUG MARKER: == conf-sanity test 21c: start mds between two osts, stop mds last ========================================================== 03:05:28 (1768550728) [ 4272.044070] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4308.313639] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4311.898246] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4335.120860] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 4412.273563] Lustre: DEBUG MARKER: == conf-sanity test 21d: start mgs then ost and then mds ========================================================== 03:08:57 (1768550937) [ 4413.847899] Lustre: DEBUG MARKER: SKIP: conf-sanity test_21d need separate mgs device [ 4415.387714] Lustre: DEBUG MARKER: SKIP: conf-sanity test_21e skipping excluded test 21e [ 4417.373993] Lustre: DEBUG MARKER: == conf-sanity test 22: start a client before osts (should return errs) ========================================================== 03:09:02 (1768550942) [ 4448.082690] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4452.295926] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4474.567869] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4497.280646] Lustre: Mounted lustre-client [ 4499.545464] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4509.810692] LustreError: 103074:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4509.816036] LustreError: 103074:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 4509.863941] Lustre: Unmounted lustre-client [ 4533.913905] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4534.653936] Lustre: Mounted lustre-client [ 4549.390667] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9dd486ade000.ost_server_uuid 50 [ 4551.076453] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9dd486ade000.ost_server_uuid in FULL state after 0 sec [ 4554.337669] LustreError: 104124:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9dd486ade000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4554.363074] LustreError: 104124:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4554.374800] LustreError: 104124:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 4554.516180] Lustre: Unmounted lustre-client [ 4592.163193] Key type lgssc unregistered [ 4592.579497] LNet: 104527:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4592.612752] LNetError: 104527:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4592.634705] LNet: Removed LNI 192.168.204.60@tcp [ 4593.709356] Key type .llcrypt unregistered [ 4593.716634] Key type ._llcrypt unregistered [ 4610.268819] Key type ._llcrypt registered [ 4610.271587] Key type .llcrypt registered [ 4611.294704] Lustre: DEBUG MARKER: == conf-sanity test 23a: interrupt client during recovery mount delay ========================================================== 03:12:16 (1768551136) [ 4613.641351] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4613.700812] alg: No test for adler32 (adler32-zlib) [ 4614.686764] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 4614.906644] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [ 4616.583267] Key type lgssc registered [ 4617.838667] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4667.993025] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4672.018457] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4695.289506] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4698.053050] Lustre: Mounted lustre-client [ 4703.214318] Lustre: lustre-MDT0000-mdc-ffff9dd489dd6000: Connection to lustre-MDT0000 (at 192.168.204.160@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4704.401233] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4714.685780] LustreError: 107644:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4714.722868] LustreError: MGC192.168.204.160@tcp: Connection to MGS (at 192.168.204.160@tcp) was lost; in progress operations using this service will fail [ 4714.742022] Lustre: Unmounted lustre-client [ 4741.250472] LustreError: lustre-MDT0000-mdc-ffff9dd485de8000: operation mds_connect to node 192.168.204.160@tcp failed: rc = -16 [ 4746.182357] LustreError: 107832:0:(lmv_obd.c:1435:lmv_statfs()) lustre-MDT0000-mdc-ffff9dd485de8000: can't stat MDS #0: rc = -16 [ 4746.246747] LustreError: 107832:0:(obd_class.h:479:obd_check_dev()) Device 3 not setup [ 4746.267210] LustreError: 107832:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 4746.325825] Lustre: Unmounted lustre-client [ 4746.339071] LustreError: 107832:0:(super25.c:199:lustre_fill_super()) llite: Unable to mount : rc = -16 [ 4776.252923] Key type lgssc unregistered [ 4776.567549] LNet: 108285:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4776.576973] LNetError: 108285:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4776.596453] LNet: Removed LNI 192.168.204.60@tcp [ 4777.176664] Key type .llcrypt unregistered [ 4777.177773] Key type ._llcrypt unregistered [ 4793.292538] Key type ._llcrypt registered [ 4793.294383] Key type .llcrypt registered [ 4794.295227] Lustre: DEBUG MARKER: == conf-sanity test 23b: Simulate -EINTR during mount ==== 03:15:19 (1768551319) [ 4796.417713] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4796.638431] alg: No test for adler32 (adler32-zlib) [ 4797.804152] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 4798.070449] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [ 4799.768142] Key type lgssc registered [ 4801.042881] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4852.684763] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4856.673428] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4882.321305] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4885.275111] Lustre: Mounted lustre-client [ 4887.954846] LustreError: 111329:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9dd48713b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4887.984375] LustreError: 111329:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4888.038730] Lustre: Unmounted lustre-client [ 4917.240191] Key type lgssc unregistered [ 4917.492992] LNet: 111735:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4917.498434] LNetError: 111735:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4917.517728] LNet: Removed LNI 192.168.204.60@tcp [ 4918.371796] Key type .llcrypt unregistered [ 4918.376232] Key type ._llcrypt unregistered [ 4935.012504] Key type ._llcrypt registered [ 4935.017429] Key type .llcrypt registered [ 4936.772703] Lustre: DEBUG MARKER: == conf-sanity test 24a: Multiple MDTs on a single node == 03:17:41 (1768551461) [ 4939.554707] Lustre: DEBUG MARKER: SKIP: conf-sanity test_24a mixed loopback and real device not working [ 4941.301792] Lustre: DEBUG MARKER: == conf-sanity test 24b: Multiple MGSs on a single node (should return err) ========================================================== 03:17:46 (1768551466) [ 4943.911687] Lustre: DEBUG MARKER: SKIP: conf-sanity test_24b mixed loopback and real device not working [ 4945.564239] Lustre: DEBUG MARKER: == conf-sanity test 25: Verify modules are referenced ==== 03:17:51 (1768551471) [ 4948.347983] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4948.380944] alg: No test for adler32 (adler32-zlib) [ 4949.424820] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 4949.556803] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [ 4951.207568] Key type lgssc registered [ 4952.130471] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5005.718703] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5010.884553] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5031.412744] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5034.080544] Lustre: Mounted lustre-client [ 5036.567441] LustreError: 115440:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9dd486ade000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5036.605970] LustreError: 115440:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5036.713521] Lustre: Unmounted lustre-client [ 5060.582877] Key type lgssc unregistered [ 5060.839772] LNet: 115841:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5060.846567] LNetError: 115841:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5060.861725] LNet: Removed LNI 192.168.204.60@tcp [ 5061.663908] Key type .llcrypt unregistered [ 5061.668578] Key type ._llcrypt unregistered [ 5077.257732] Key type ._llcrypt registered [ 5077.262354] Key type .llcrypt registered [ 5078.519066] Lustre: DEBUG MARKER: == conf-sanity test 26: MDT startup failure cleans LOV (should return errs) ========================================================== 03:20:03 (1768551603) [ 5079.615090] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5079.657316] alg: No test for adler32 (adler32-zlib) [ 5080.732595] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 5080.951785] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [ 5082.607234] Key type lgssc registered [ 5083.964451] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5153.916307] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5157.410289] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5178.006401] Key type lgssc unregistered [ 5178.337384] LNet: 118864:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5178.342531] LNetError: 118864:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5178.359865] LNet: Removed LNI 192.168.204.60@tcp [ 5179.021700] Key type .llcrypt unregistered [ 5179.025750] Key type ._llcrypt unregistered [ 5195.907636] Key type ._llcrypt registered [ 5195.909899] Key type .llcrypt registered [ 5197.427436] Lustre: DEBUG MARKER: == conf-sanity test 27a: Reacquire MGS lock if OST started first ========================================================== 03:22:02 (1768551722) [ 5207.838603] Key type .llcrypt unregistered [ 5207.842269] Key type ._llcrypt unregistered [ 5217.356370] Key type ._llcrypt registered [ 5217.363769] Key type .llcrypt registered [ 5217.930494] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5217.940669] alg: No test for adler32 (adler32-zlib) [ 5218.959786] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 5219.172207] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [ 5220.855154] Key type lgssc registered [ 5221.910505] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5302.923809] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5330.899785] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5334.184317] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5371.205980] Key type lgssc unregistered [ 5371.490674] LNet: 122637:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5371.496165] LNetError: 122637:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5371.512888] LNet: Removed LNI 192.168.204.60@tcp [ 5371.954607] Key type .llcrypt unregistered [ 5371.956362] Key type ._llcrypt unregistered [ 5386.943390] Key type ._llcrypt registered [ 5386.948373] Key type .llcrypt registered [ 5388.405269] Lustre: DEBUG MARKER: == conf-sanity test 27b: Reacquire MGS lock after failover ========================================================== 03:25:13 (1768551913) [ 5391.235614] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5391.246362] alg: No test for adler32 (adler32-zlib) [ 5392.424957] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 5392.698212] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [ 5394.407232] Key type lgssc registered [ 5395.712363] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5444.059553] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5448.166216] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5466.921747] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5468.929073] Lustre: Mounted lustre-client [ 5474.286080] Lustre: lustre-MDT0000-mdc-ffff9dd4884d4000: Connection to lustre-MDT0000 (at 192.168.204.160@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5490.527765] Lustre: 123624:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1768552001/real 1768552001] req@000000006f942e66 x1854461097020416/t0(0) o400->MGC192.168.204.160@tcp@192.168.204.160@tcp:26/25 lens 224/224 e 0 to 1 dl 1768552017 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 5490.561080] LustreError: MGC192.168.204.160@tcp: Connection to MGS (at 192.168.204.160@tcp) was lost; in progress operations using this service will fail [ 5500.795079] Lustre: Evicted from MGS (at 192.168.204.160@tcp) after server handle changed from 0xeefaf7047b760892 to 0xeefaf7047b7608e6 [ 5500.811306] Lustre: MGC192.168.204.160@tcp: Connection restored to 192.168.204.160@tcp (at 192.168.204.160@tcp) [ 5506.525970] Lustre: lustre-MDT0000-mdc-ffff9dd4884d4000: Connection restored to 192.168.204.160@tcp (at 192.168.204.160@tcp) [ 5529.250143] LustreError: 126165:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9dd4884d4000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5529.270728] LustreError: 126165:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5529.331019] Lustre: Unmounted lustre-client [ 5559.130750] Key type lgssc unregistered [ 5559.631332] LNet: 126568:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5559.649473] LNetError: 126568:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5559.679350] LNet: Removed LNI 192.168.204.60@tcp [ 5560.471606] Key type .llcrypt unregistered [ 5560.473272] Key type ._llcrypt unregistered [ 5575.618359] Key type ._llcrypt registered [ 5575.622236] Key type .llcrypt registered [ 5577.015363] Lustre: DEBUG MARKER: == conf-sanity test 28A: permanent parameter setting ===== 03:28:22 (1768552102) [ 5579.328940] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5579.345442] alg: No test for adler32 (adler32-zlib) [ 5580.361773] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 5580.544644] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [ 5582.183409] Key type lgssc registered [ 5583.087677] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5635.092798] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5638.960830] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5659.509769] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5661.141858] Lustre: Mounted lustre-client [ 5680.737812] LustreError: 129774:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9dd487a1e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5680.760664] LustreError: 129774:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5680.818950] Lustre: Unmounted lustre-client [ 5681.312238] Lustre: Mounted lustre-client [ 5691.313074] LustreError: 129915:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9dd487118000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5691.333934] LustreError: 129915:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5691.336700] LustreError: 129915:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 5691.369476] Lustre: Unmounted lustre-client [ 5727.028664] Key type lgssc unregistered [ 5727.283863] LNet: 130319:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5727.288919] LNetError: 130319:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5727.322087] LNet: Removed LNI 192.168.204.60@tcp [ 5728.058951] Key type .llcrypt unregistered [ 5728.061528] Key type ._llcrypt unregistered [ 5746.430304] Key type ._llcrypt registered [ 5746.437283] Key type .llcrypt registered [ 5748.037559] Lustre: DEBUG MARKER: == conf-sanity test 28a: set symlink parameters permanently with lctl ========================================================== 03:31:13 (1768552273) [ 5750.263580] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5750.340038] alg: No test for adler32 (adler32-zlib) [ 5751.399834] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 5751.668371] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [ 5753.338174] Key type lgssc registered [ 5754.773529] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5806.967657] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5810.909568] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5827.877898] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5830.662683] Lustre: Mounted lustre-client [ 5862.530063] LustreError: 133595:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9dd487265800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5862.546501] LustreError: 133595:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5862.625358] Lustre: Unmounted lustre-client [ 5897.358153] Key type lgssc unregistered [ 5897.598854] LNet: 134000:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5897.606124] LNetError: 134000:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5897.636134] LNet: Removed LNI 192.168.204.60@tcp [ 5898.292640] Key type .llcrypt unregistered [ 5898.295377] Key type ._llcrypt unregistered [ 5912.749289] Key type ._llcrypt registered [ 5912.750926] Key type .llcrypt registered [ 5914.082585] Lustre: DEBUG MARKER: == conf-sanity test 28b: verify client-side parameters are set upon mount ========================================================== 03:33:59 (1768552439) [ 5916.558649] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5916.603753] alg: No test for adler32 (adler32-zlib) [ 5917.685746] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 5917.905163] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [ 5919.647163] Key type lgssc registered [ 5920.945330] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5970.843255] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5974.441122] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5992.264534] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5995.041201] Lustre: Mounted lustre-client [ 5997.333276] LustreError: 137063:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9dd489193800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5997.354812] LustreError: 137063:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5997.411134] Lustre: Unmounted lustre-client [ 5997.845641] Lustre: Mounted lustre-client [ 6005.223256] LustreError: 137558:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9dd487139800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6005.263468] LustreError: 137558:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6005.268034] LustreError: 137558:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 6005.319402] Lustre: Unmounted lustre-client [ 6032.942344] Key type lgssc unregistered [ 6033.208695] LNet: 137960:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6033.222076] LNetError: 137960:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6033.252156] LNet: Removed LNI 192.168.204.60@tcp [ 6033.835926] Key type .llcrypt unregistered [ 6033.837837] Key type ._llcrypt unregistered [ 6044.291528] Key type ._llcrypt registered [ 6044.295147] Key type .llcrypt registered [ 6046.075904] Lustre: DEBUG MARKER: == conf-sanity test 28c: verify filesystem parameters are set upon mount ========================================================== 03:36:10 (1768552570) [ 6048.364638] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6048.471278] alg: No test for adler32 (adler32-zlib) [ 6049.692158] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 6049.928337] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [ 6051.607329] Key type lgssc registered [ 6052.599781] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6101.226722] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6105.926808] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6125.288393] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6128.338198] Lustre: Mounted lustre-client [ 6131.321937] LustreError: 140599:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9dd48586d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6131.381711] LustreError: 140599:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6131.459980] Lustre: Unmounted lustre-client [ 6132.095242] Lustre: Mounted lustre-client [ 6139.549232] LustreError: 141097:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9dd487118800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6139.575690] LustreError: 141097:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6139.579527] LustreError: 141097:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 6139.651163] Lustre: Unmounted lustre-client [ 6169.889732] Key type lgssc unregistered [ 6170.257741] LNet: 141499:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6170.263554] LNetError: 141499:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6171.301734] LNet: Removed LNI 192.168.204.60@tcp [ 6172.124795] Key type .llcrypt unregistered [ 6172.126795] Key type ._llcrypt unregistered [ 6182.575039] Key type ._llcrypt registered [ 6182.580208] Key type .llcrypt registered [ 6183.840873] Lustre: DEBUG MARKER: == conf-sanity test 29: permanently remove an OST ======== 03:38:29 (1768552709) [ 6185.929341] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6185.940967] alg: No test for adler32 (adler32-zlib) [ 6187.021434] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 6187.254163] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [ 6188.927309] Key type lgssc registered [ 6190.516056] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6242.146412] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6246.165649] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6268.000751] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6269.776612] Lustre: Mounted lustre-client [ 6292.696334] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 6295.472737] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6316.580478] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 6323.794668] LustreError: 144857:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9dd489190000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6323.825911] LustreError: 144857:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 6323.894715] Lustre: Unmounted lustre-client [ 6324.696966] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 6324.996101] Lustre: Mounted lustre-client [ 6337.139791] LustreError: 145009:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9dd487262800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6337.172347] LustreError: 145009:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 6337.177608] LustreError: 145009:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 6337.298442] Lustre: Unmounted lustre-client [ 6383.668826] Key type lgssc unregistered [ 6383.933635] LNet: 145459:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6383.941109] LNetError: 145459:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6383.961368] LNet: Removed LNI 192.168.204.60@tcp [ 6384.592709] Key type .llcrypt unregistered [ 6384.601279] Key type ._llcrypt unregistered [ 6414.860949] Key type ._llcrypt registered [ 6414.866868] Key type .llcrypt registered [ 6416.274848] Lustre: DEBUG MARKER: == conf-sanity test 30a: Big config llog and permanent parameter deletion ========================================================== 03:42:21 (1768552941) [ 6419.234308] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6419.434673] alg: No test for adler32 (adler32-zlib) [ 6420.610742] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 6420.982865] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [ 6422.704599] Key type lgssc registered [ 6423.773647] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6476.446726] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6480.510925] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6503.065245] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6504.674728] Lustre: Mounted lustre-client [ 6669.498210] LustreError: 150081:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9dd489192800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6669.515861] LustreError: 150081:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6669.607509] Lustre: Unmounted lustre-client [ 6670.130768] Lustre: Mounted lustre-client [ 6674.534328] LustreError: 150161:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9dd488d85000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6674.574845] LustreError: 150161:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6674.579316] LustreError: 150161:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 6674.678783] Lustre: Unmounted lustre-client [ 6675.268756] Lustre: Mounted lustre-client [ 6678.360916] LustreError: 150232:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9dd486aee000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6678.394995] LustreError: 150232:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6678.404840] LustreError: 150232:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 6678.496309] Lustre: Unmounted lustre-client [ 6705.890995] Key type lgssc unregistered [ 6706.298372] LNet: 150635:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6706.303098] LNetError: 150635:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6707.370679] LNet: Removed LNI 192.168.204.60@tcp [ 6708.503381] Key type .llcrypt unregistered [ 6708.505788] Key type ._llcrypt unregistered [ 6725.957911] Key type ._llcrypt registered [ 6725.960825] Key type .llcrypt registered [ 6727.232287] Lustre: DEBUG MARKER: == conf-sanity test 30b: Remove failover nids ============ 03:47:32 (1768553252) [ 6729.320714] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6729.443078] alg: No test for adler32 (adler32-zlib) [ 6730.520533] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 6730.846401] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [ 6732.551189] Key type lgssc registered [ 6733.998795] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6785.319941] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6789.031382] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6812.801832] Lustre: DEBUG MARKER: oleg460-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6814.767617] Lustre: Mounted lustre-client [ 6825.303542] LustreError: 153785:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9dd486dca000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6825.341679] LustreError: 153785:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6825.425908] Lustre: Unmounted lustre-client [ 6826.453587] Lustre: Mounted lustre-client [ 6829.681637] LustreError: 153861:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9dd488d82800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6829.713587] LustreError: 153861:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6829.724431] LustreError: 153861:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 6829.811617] Lustre: Unmounted lustre-client [ 6867.594871] Key type lgssc unregistered [ 6867.910089] LNet: 154265:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6867.932137] LNetError: 154265:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6867.956950] LNet: Removed LNI 192.168.204.60@tcp [ 6868.721971] Key type .llcrypt unregistered [ 6868.730545] Key type ._llcrypt unregistered [ 6885.260938] Key type ._llcrypt registered [ 6885.264384] Key type .llcrypt registered [ 6886.992493] Lustre: DEBUG MARKER: == conf-sanity test 31: Connect to non-existent node (shouldn't crash) ========================================================== 03:50:11 (1768553411) [ 6888.637978] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6888.675378] alg: No test for adler32 (adler32-zlib) [ 6889.756196] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 6890.235572] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [ 6892.023168] Key type lgssc registered [ 6893.785831] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6917.216542] LustreError: 155957:0:(mgc_request.c:233:do_config_log_add()) MGC4.3.2.1@tcp: failed processing log, type 1: rc = -110 [ 6926.304856] LustreError: 155967:0:(mgc_request.c:614:do_requeue()) failed processing log: -110 [ 6968.095479] LNet: 1 local NIs in recovery (showing 1): 192.168.204.60@tcp [ 7042.847194] LNet: 1 local NIs in recovery (showing 1): 192.168.204.60@tcp [ 7073.759892] 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 [ 7073.784713] Lustre: Unmounted lustre-client [ 7073.800898] LustreError: 155957:0:(obd_class.h:479:obd_check_dev()) Device 0 not setup [ 7073.824791] LustreError: 155957:0:(super25.c:199:lustre_fill_super()) llite: Unable to mount : rc = -110 [ 7087.139478] Key type lgssc unregistered [ 7096.735630] LNet: 156376:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7096.769759] LNetError: 156376:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7173.606900] LNet: Removed LNI 192.168.204.60@tcp [ 7174.587895] Key type .llcrypt unregistered [ 7174.593376] Key type ._llcrypt unregistered [ 7215.822581] Key type ._llcrypt registered [ 7215.830714] Key type .llcrypt registered [ 7216.322875] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7216.337441] alg: No test for adler32 (adler32-zlib) [ 7217.535655] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 7217.797655] LNet: Added LNI 192.168.204.60@tcp [8/256/0/180] [ 7219.504143] Key type lgssc registered [ 7221.029971] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7274.082330] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 7067 sec ========== 03:56:39 (1768553799) [ 7275.819186] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 03:56:41 (1768553801) === [ 7279.924731] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 03:56:44 (1768553804) === [ 7323.793735] Key type lgssc unregistered [ 7324.089594] LNet: 160610:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7324.094964] LNetError: 160610:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7324.109926] LNet: Removed LNI 192.168.204.60@tcp [ 7325.107591] Key type .llcrypt unregistered [ 7325.111336] Key type ._llcrypt unregistered