[ 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.16.2-1.fc38 04/01/2014 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 496025243 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 0x000f5b50-0x000f5b5f] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F5970 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE1BB7 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE1A53 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 001A13 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE1AC7 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE1B57 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE1B8F 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe1a53-0xbffe1ac6] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe1a52] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe1ac7-0xbffe1b56] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe1b57-0xbffe1b8e] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe1b8f-0xbffe1bb6] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2895288K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001012] APIC: Switch to symmetric I/O mode setup [ 0.002376] x2apic enabled [ 0.003009] Switched APIC routing to physical x2apic. [ 0.004011] kvm-guest: setup PV IPIs [ 0.006845] ..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.007028] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.008010] pid_max: default: 32768 minimum: 301 [ 0.010073] LSM: Security Framework initializing [ 0.011204] Yama: becoming mindful. [ 0.012054] SELinux: Initializing. [ 0.013065] *** VALIDATE selinux *** [ 0.022327] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.028275] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.030106] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.032164] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.033134] *** VALIDATE tmpfs *** [ 0.034687] *** VALIDATE proc *** [ 0.035304] *** VALIDATE cgroup *** [ 0.036014] *** VALIDATE cgroup2 *** [ 0.037323] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.038164] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.039008] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.040034] Spectre V2 : User space: Vulnerable [ 0.041005] Speculative Store Bypass: Vulnerable [ 0.044777] debug: unmapping init [mem 0xffffffffbaa59000-0xffffffffbaa60fff] [ 0.046000] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.046753] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.047025] ... version: 2 [ 0.047993] ... bit width: 48 [ 0.048012] ... generic registers: 4 [ 0.049006] ... value mask: 0000ffffffffffff [ 0.050010] ... max period: 00007fffffffffff [ 0.051008] ... fixed-purpose events: 3 [ 0.052007] ... event mask: 000000070000000f [ 0.053342] rcu: Hierarchical SRCU implementation. [ 0.055436] smp: Bringing up secondary CPUs ... [ 0.056515] x86: Booting SMP configuration: [ 0.057015] .... node #0, CPUs: #1 #2 #3 [ 0.073014] smp: Brought up 1 node, 4 CPUs [ 0.075016] smpboot: Max logical packages: 1 [ 0.076010] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.178033] node 0 deferred pages initialised in 95ms [ 0.181423] devtmpfs: initialized [ 0.182375] x86/mm: Memory block size: 128MB [ 0.188000] gcov: version magic: 0x41383552 [ 0.192313] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.195110] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.197413] pinctrl core: initialized pinctrl subsystem [ 0.199176] [ 0.199630] ************************************************************* [ 0.202015] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.203014] ** ** [ 0.208019] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.210016] ** ** [ 0.213015] ** This means that this kernel is built to expose internal ** [ 0.214015] ** IOMMU data structures, which may compromise security on ** [ 0.215000] ** your system. ** [ 0.216033] ** ** [ 0.218018] ** If you see this message and you are not debugging the ** [ 0.220018] ** kernel, report this immediately to your vendor! ** [ 0.223022] ** ** [ 0.225017] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.227011] ************************************************************* [ 0.229893] NET: Registered protocol family 16 [ 0.231589] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.234071] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.237075] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.241739] cpuidle: using governor menu [ 0.243974] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.246739] PCI: Using configuration type 1 for base access [ 0.249134] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.264177] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.265029] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.267047] cryptd: max_cpu_qlen set to 1000 [ 0.270247] ACPI: Added _OSI(Module Device) [ 0.272013] ACPI: Added _OSI(Processor Device) [ 0.273012] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.275011] ACPI: Added _OSI(Processor Aggregator Device) [ 0.280053] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.288563] ACPI: Interpreter enabled [ 0.289057] ACPI: PM: (supports S0 S3 S4 S5) [ 0.290011] ACPI: Using IOAPIC for interrupt routing [ 0.291000] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.291484] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.302100] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.304036] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.307018] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.310103] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.316000] acpiphp: Slot [2] registered [ 0.316000] acpiphp: Slot [3] registered [ 0.316081] acpiphp: Slot [4] registered [ 0.317000] acpiphp: Slot [5] registered [ 0.319128] acpiphp: Slot [6] registered [ 0.321128] acpiphp: Slot [7] registered [ 0.322078] acpiphp: Slot [8] registered [ 0.323334] acpiphp: Slot [9] registered [ 0.325086] acpiphp: Slot [10] registered [ 0.327124] acpiphp: Slot [11] registered [ 0.328097] acpiphp: Slot [12] registered [ 0.331149] acpiphp: Slot [13] registered [ 0.333152] acpiphp: Slot [14] registered [ 0.335114] acpiphp: Slot [15] registered [ 0.336102] acpiphp: Slot [16] registered [ 0.338149] acpiphp: Slot [17] registered [ 0.340071] acpiphp: Slot [18] registered [ 0.341074] acpiphp: Slot [19] registered [ 0.343122] acpiphp: Slot [20] registered [ 0.345121] acpiphp: Slot [21] registered [ 0.346094] acpiphp: Slot [22] registered [ 0.348129] acpiphp: Slot [23] registered [ 0.350079] acpiphp: Slot [24] registered [ 0.352083] acpiphp: Slot [25] registered [ 0.353084] acpiphp: Slot [26] registered [ 0.355094] acpiphp: Slot [27] registered [ 0.356062] acpiphp: Slot [28] registered [ 0.357054] acpiphp: Slot [29] registered [ 0.358060] acpiphp: Slot [30] registered [ 0.359086] acpiphp: Slot [31] registered [ 0.361056] PCI host bridge to bus 0000:00 [ 0.363020] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.366031] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.369022] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.378018] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.383032] pci_bus 0000:00: root bus resource [mem 0x180000000-0x1ffffffff window] [ 0.388036] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.390235] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.395880] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.400799] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.409015] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.416852] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.419022] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.421019] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.422018] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.425246] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.433835] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.438038] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.440739] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.443948] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.452013] pci 0000:00:02.0: reg 0x20: [mem 0xfebf4000-0xfebf7fff 64bit pref] [ 0.458721] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.464700] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.471016] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.476016] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.495019] pci 0000:00:05.0: reg 0x20: [mem 0xfebf8000-0xfebfbfff 64bit pref] [ 0.509000] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.513013] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.518013] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.527016] pci 0000:00:06.0: reg 0x20: [mem 0xfebfc000-0xfebfffff 64bit pref] [ 0.542769] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.545419] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.547312] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.548331] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.550223] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.555154] iommu: Default domain type: Passthrough [ 0.556399] SCSI subsystem initialized [ 0.557096] ACPI: bus type USB registered [ 0.559116] usbcore: registered new interface driver usbfs [ 0.560046] usbcore: registered new interface driver hub [ 0.561079] usbcore: registered new device driver usb [ 0.562098] pps_core: LinuxPPS API ver. 1 registered [ 0.564008] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.567055] PTP clock support registered [ 0.570072] EDAC MC: Ver: 3.0.0 [ 0.572122] PCI: Using ACPI for IRQ routing [ 0.573579] NetLabel: Initializing [ 0.574007] NetLabel: domain hash size = 128 [ 0.574962] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.577120] NetLabel: unlabeled traffic allowed by default [ 0.579218] vgaarb: loaded [ 0.582261] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.585012] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.589113] clocksource: Switched to clocksource kvm-clock [ 0.744327] VFS: Disk quotas dquot_6.6.0 [ 0.745696] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.748115] *** VALIDATE ramfs *** [ 0.749082] *** VALIDATE hugetlbfs *** [ 0.750414] pnp: PnP ACPI init [ 0.753231] pnp: PnP ACPI: found 6 devices [ 0.770376] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.772977] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.774838] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.777036] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.778919] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.780874] pci_bus 0000:00: resource 8 [mem 0x180000000-0x1ffffffff window] [ 0.783090] NET: Registered protocol family 2 [ 0.785192] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.789481] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.792704] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.796647] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.799496] TCP: Hash tables configured (established 65536 bind 65536) [ 0.802310] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.805173] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.807702] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.810340] NET: Registered protocol family 1 [ 0.814740] RPC: Registered named UNIX socket transport module. [ 0.817153] RPC: Registered udp transport module. [ 0.818715] RPC: Registered tcp transport module. [ 0.820152] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.822262] NET: Registered protocol family 44 [ 0.823430] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.825550] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.827284] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.829081] PCI: CLS 0 bytes, default 64 [ 0.830569] Unpacking initramfs... [ 2.976363] debug: unmapping init [mem 0xffff9cb1fcc64000-0xffff9cb1fffcffff] [ 2.987839] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.994603] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 3.002995] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 3.935235] Initialise system trusted keyrings [ 3.937251] Key type blacklist registered [ 3.943984] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 3.956541] zbud: loaded [ 3.961373] *** VALIDATE nfs *** [ 3.963309] *** VALIDATE nfs4 *** [ 3.964478] pstore: using deflate compression [ 3.968539] Platform Keyring initialized [ 4.153287] NET: Registered protocol family 38 [ 4.155344] Key type asymmetric registered [ 4.160176] Asymmetric key parser 'x509' registered [ 4.161802] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 4.164928] io scheduler mq-deadline registered [ 4.166710] io scheduler kyber registered [ 4.169618] io scheduler bfq registered [ 4.172682] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 4.177660] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 4.182719] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 4.189694] ACPI: Power Button [PWRF] [ 4.349602] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 4.485728] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 4.667556] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 4.698865] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 4.746312] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 4.757332] Non-volatile memory driver v1.3 [ 4.765774] Linux agpgart interface v0.103 [ 4.852241] virtio_blk virtio1: [vda] 133600 512-byte logical blocks (68.4 MB/65.2 MiB) [ 4.859242] vda: detected capacity change from 0 to 68403200 [ 4.880224] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 4.882939] vdb: detected capacity change from 0 to 1073741824 [ 4.894798] libphy: Fixed MDIO Bus: probed [ 4.914189] usbcore: registered new interface driver usbserial_generic [ 4.922635] usbserial: USB Serial support registered for generic [ 4.935496] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 4.943421] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 4.949134] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 4.981431] mousedev: PS/2 mouse device common for all mice [ 4.985371] rtc_cmos 00:05: RTC can wake from S4 [ 4.989720] rtc_cmos 00:05: registered as rtc0 [ 4.991227] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 4.994800] intel_pstate: CPU model not supported [ 5.000354] hid: raw HID events driver (C) Jiri Kosina [ 5.002265] usbcore: registered new interface driver usbhid [ 5.003818] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 5.011555] usbhid: USB HID core driver [ 5.013105] drop_monitor: Initializing network drop monitor service [ 5.018854] Initializing XFRM netlink socket [ 5.020600] NET: Registered protocol family 10 [ 5.020895] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 5.031322] Segment Routing with IPv6 [ 5.032720] NET: Registered protocol family 17 [ 5.037655] mpls_gso: MPLS GSO support [ 5.042403] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 5.046813] RAS: Correctable Errors collector initialized. [ 5.048457] AVX version of gcm_enc/dec engaged. [ 5.050481] AES CTR mode by8 optimization enabled [ 5.344426] sched_clock: Marking stable (5344392615, 0)->(6349093962, -1004701347) [ 5.362872] registered taskstats version 1 [ 5.378701] Loading compiled-in X.509 certificates [ 5.387607] zswap: loaded using pool lzo/zbud [ 5.448682] Key type big_key registered [ 5.472535] Key type encrypted registered [ 5.476514] ima: No TPM chip found, activating TPM-bypass! [ 5.485339] ima: Allocated hash algorithm: sha1 [ 5.486934] ima: No architecture policies found [ 5.488459] evm: Initialising EVM extended attributes: [ 5.491884] evm: security.selinux [ 5.501596] evm: security.ima [ 5.504865] evm: security.capability [ 5.506107] evm: HMAC attrs: 0x1 [ 5.520339] rtc_cmos 00:05: setting system clock to 2025-11-03 10:39:45 UTC (1762166385) [ 5.532280] debug: unmapping init [mem 0xffffffffbba03000-0xffffffffbbbfffff] [ 5.535501] debug: unmapping init [mem 0xffffffffba782000-0xffffffffbaa58fff] [ 5.543132] Write protecting the kernel read-only data: 28672k [ 5.549910] debug: unmapping init [mem 0xffffffffb8e03000-0xffffffffb8ffffff] [ 5.552827] debug: unmapping init [mem 0xffffffffb9714000-0xffffffffb97fffff] [ 5.655217] 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) [ 5.677330] systemd[1]: Detected virtualization kvm. [ 5.688141] systemd[1]: Detected architecture x86-64. [ 5.689772] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 5.744385] systemd[1]: No hostname configured. [ 5.745748] systemd[1]: Set hostname to . [ 5.747815] random: systemd: uninitialized urandom read (16 bytes read) [ 5.750203] systemd[1]: Initializing machine ID from random generator. [ 5.868745] random: ln: uninitialized urandom read (6 bytes read) [ 6.264907] random: systemd: uninitialized urandom read (16 bytes read) [ 6.280198] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ 6.293328] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. [ 6.306832] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Timers. [ OK ] Listening on Journal Socket. Starting Setup Virtual Console... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Swap. [ OK ] Started Memstrack Anylazing Service. Starting Apply Kernel Variables... [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Sockets. Starting Journal Service... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Reached target Slices. Starting Create Volatile Files and Directories... [ OK ] Started Setup Virtual Console. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create Volatile Files and Directories. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 8.909614] device-mapper: uevent: version 1.0.3 [ 8.913097] 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. [ 10.893842] virtio_net virtio0 ens2: renamed from eth0 Starting dracut initqueue hook... [ 11.459422] scsi host0: ata_piix [ 11.516636] scsi host1: ata_piix [ 11.526953] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 11.540149] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 17.048207] random: crng init done [ 17.049565] random: 7 urandom warning(s) missed due to ratelimiting [ 19.808596] 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. [ 22.109179] 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 target Timers. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Paths. [ OK ] Stopped target Sockets. [ OK ] Stopped target Slices. [ OK ] Stopped target System Initialization. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. Stopping udev Kernel Device Manager... [ OK ] Stopped target Swap. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Local File Systems. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 24.866676] printk: systemd: 26 output lines suppressed due to ratelimiting [ 25.681309] SELinux: Disabled at runtime. [ 25.823265] 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) [ 25.842371] systemd[1]: Detected virtualization kvm. [ 25.843733] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 27.395321] systemd[1]: initrd-switch-root.service: Succeeded. [ 27.399568] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 27.419378] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 27.425554] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 27.449409] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 27.474181] systemd[1]: Starting Journal Service... Starting Journal Service... [ 27.503550] systemd[1]: Mounting Huge Pages File System... Mounting Huge Pages File System... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Reached target rpc_pipefs.target. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Created slice User and Session Slice. [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on Process Core Dump Socket. Mounting POSIX Message Queue File System... [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... [ OK ] Reached target Slices. Starting Apply Kernel Variables... Activating swap /dev/disk/by-label/SWAP... Mounting Kernel Debug File System... [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. [ OK ] Stopped target Initrd Root File System. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. Starting Remount Root and Kernel File Systems... [ OK ] Created slic[ 28.093983] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS e system-getty.slice. [ OK ] Started Journal Service. [ OK ] Mounted Huge Pages File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Started Apply Kernel Variables. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted Kernel Debug File System. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. Starting Configure read-only root support... [ OK ] Reached target Swap. Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started udev Coldplug all Devices. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... Starting udev Kernel Device Manager... [ OK ] Mounted /mnt. [ 28.923848] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 30.140622] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 30.168466] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 31.620696] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 31.817731] EDAC sbridge: Ver: 1.1.2 [* ] A start job is running for Configur…-only root support (7s / no limit)[ 34.928144] Key type dns_resolver registered [** ] A start job is running for Configur…-only root support (8s / no limit) [*** ] A start job is running for Configur…-only root support (8s / no limit)[ 36.034112] NFS: Registering the id_resolver key type [ 36.036062] Key type id_resolver registered [ 36.055735] Key type id_legacy registered [ *** ] A start job is running for Configur…-only root support (9s / no limit) [ *** ] A start job is running for Configur…-only root support (9s / 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. Starting Restore /run/initramfs on shutdown... [ OK ] Started irqbalance daemon. [ OK ] Started D-Bus System Message Bus. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Network Manager... [ OK ] Reached target sshd-keygen.target. Starting Login Service... [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Timers. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... Starting OpenSSH server daemon... 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 Serial Getty on ttyS0. [ OK ] Started Getty on tty1. [ 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. [ 46.158105] hrtimer: interrupt took 2215913 ns Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg241-client login: [ 75.197980] libcfs: loading out-of-tree module taints kernel. [ 75.357479] Key type ._llcrypt registered [ 75.359110] Key type .llcrypt registered [ 75.728255] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 75.737570] alg: No test for adler32 (adler32-zlib) [ 76.789092] Lustre: Lustre: Build Version: 2.16.59_50_g4204c43 [ 77.141830] LNet: Added LNI 192.168.202.41@tcp [8/256/0/180] [ 78.794055] Key type lgssc registered [ 79.697333] Lustre: Echo OBD driver; http://www.lustre.org/ [ 163.587178] Lustre: Mounted lustre-client [ 167.118662] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 183.872339] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing check_logdir /tmp/testlogs/ [ 186.316710] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing yml_node [ 188.914937] Lustre: DEBUG MARKER: Client: 2.16.59.50 [ 189.408697] Lustre: lustre-OST0000-osc-ffff9cb248672000: disconnect after 24s idle [ 190.129835] Lustre: DEBUG MARKER: MDS: 2.16.59.50 [ 191.412978] Lustre: DEBUG MARKER: OSS: 2.16.59.50 [ 192.356472] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Mon Nov 3 05:42:51 EST 2025 [ 202.459783] Lustre: DEBUG MARKER: excepting tests: 21e 32b 32c 32newtarball [ 203.468327] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 204.640204] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 214.892748] LustreError: 9002:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 214.930533] Lustre: Unmounted lustre-client [ 275.354293] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 277.254967] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 287.127461] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 309.321925] Lustre: DEBUG MARKER: == conf-sanity test 0: single mount setup ================ 05:44:48 (1762166688) [ 323.570340] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 325.380134] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 334.200923] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 339.964572] Lustre: Mounted lustre-client [ 341.893032] LustreError: 14075:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cb251b17000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 341.903220] LustreError: 14075:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 341.906313] LustreError: 14075:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 341.935324] Lustre: Unmounted lustre-client [ 352.879399] Key type lgssc unregistered [ 353.034780] LNet: 14476:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 353.038055] LNetError: 14476:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 353.048367] LNet: Removed LNI 192.168.202.41@tcp [ 353.394387] Key type .llcrypt unregistered [ 353.396275] Key type ._llcrypt unregistered [ 360.204974] Key type ._llcrypt registered [ 360.206159] Key type .llcrypt registered [ 360.794472] Lustre: DEBUG MARKER: == conf-sanity test 1: start up ost twice (should return errors) ========================================================== 05:45:40 (1762166740) [ 361.852558] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 361.875182] alg: No test for adler32 (adler32-zlib) [ 362.754424] Lustre: Lustre: Build Version: 2.16.59_50_g4204c43 [ 362.866357] LNet: Added LNI 192.168.202.41@tcp [8/256/0/180] [ 364.471641] Key type lgssc registered [ 365.070789] Lustre: Echo OBD driver; http://www.lustre.org/ [ 388.132247] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 389.972906] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 398.673849] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 409.069192] Lustre: Mounted lustre-client [ 409.841366] LustreError: 17650:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cb2487ff800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 409.854184] LustreError: 17650:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 409.900089] Lustre: Unmounted lustre-client [ 429.248157] Key type lgssc unregistered [ 429.383676] LNet: 18052:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 429.387135] LNetError: 18052:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 429.396650] LNet: Removed LNI 192.168.202.41@tcp [ 429.688833] Key type .llcrypt unregistered [ 429.690429] Key type ._llcrypt unregistered [ 435.845487] Key type ._llcrypt registered [ 435.846737] Key type .llcrypt registered [ 436.419346] Lustre: DEBUG MARKER: == conf-sanity test 2: start up mds twice (should return err) ========================================================== 05:46:55 (1762166815) [ 437.351719] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 437.380597] alg: No test for adler32 (adler32-zlib) [ 438.268184] Lustre: Lustre: Build Version: 2.16.59_50_g4204c43 [ 438.374337] LNet: Added LNI 192.168.202.41@tcp [8/256/0/180] [ 439.975173] Key type lgssc registered [ 440.505927] Lustre: Echo OBD driver; http://www.lustre.org/ [ 462.396351] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 463.844805] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 474.026079] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 480.740578] Lustre: Mounted lustre-client [ 486.452471] LustreError: 21209:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cb247f7f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 486.462925] LustreError: 21209:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 486.494742] Lustre: Unmounted lustre-client [ 500.786366] Key type lgssc unregistered [ 500.931211] LNet: 21610:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 500.935766] LNetError: 21610:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 500.946783] LNet: Removed LNI 192.168.202.41@tcp [ 501.235531] Key type .llcrypt unregistered [ 501.237878] Key type ._llcrypt unregistered [ 507.309582] Key type ._llcrypt registered [ 507.311030] Key type .llcrypt registered [ 507.871941] Lustre: DEBUG MARKER: == conf-sanity test 3: mount client twice (should return err) ========================================================== 05:48:07 (1762166887) [ 508.733689] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 508.764941] alg: No test for adler32 (adler32-zlib) [ 509.652771] Lustre: Lustre: Build Version: 2.16.59_50_g4204c43 [ 509.757696] LNet: Added LNI 192.168.202.41@tcp [8/256/0/180] [ 511.351234] Key type lgssc registered [ 511.786943] Lustre: Echo OBD driver; http://www.lustre.org/ [ 532.615263] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 534.089419] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 542.262837] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 552.968335] Lustre: Mounted lustre-client [ 555.300443] LustreError: 24722:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cb24835a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 555.312778] LustreError: 24722:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 555.362529] Lustre: Unmounted lustre-client [ 574.068618] Key type lgssc unregistered [ 574.209707] LNet: 25124:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 574.213343] LNetError: 25124:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 574.223751] LNet: Removed LNI 192.168.202.41@tcp [ 574.522475] Key type .llcrypt unregistered [ 574.524108] Key type ._llcrypt unregistered [ 580.453425] Key type ._llcrypt registered [ 580.454774] Key type .llcrypt registered [ 581.041411] Lustre: DEBUG MARKER: == conf-sanity test 4: force cleanup ost, then cleanup === 05:49:20 (1762166960) [ 581.890784] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 581.898422] alg: No test for adler32 (adler32-zlib) [ 582.806389] Lustre: Lustre: Build Version: 2.16.59_50_g4204c43 [ 582.920388] LNet: Added LNI 192.168.202.41@tcp [8/256/0/180] [ 584.521809] Key type lgssc registered [ 585.164884] Lustre: Echo OBD driver; http://www.lustre.org/ [ 605.714927] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 607.362440] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 615.121521] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 625.666643] Lustre: Mounted lustre-client [ 630.759455] Lustre: lustre-OST0000-osc-ffff9cb246e87800: Connection to lustre-OST0000 (at 192.168.202.141@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 635.508603] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 645.802349] LustreError: 28248:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 645.825399] Lustre: Unmounted lustre-client [ 664.179841] Key type lgssc unregistered [ 664.312913] LNet: 28643:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 664.316207] LNetError: 28643:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 664.329320] LNet: Removed LNI 192.168.202.41@tcp [ 664.602294] Key type .llcrypt unregistered [ 664.604349] Key type ._llcrypt unregistered [ 670.558312] Key type ._llcrypt registered [ 670.559981] Key type .llcrypt registered [ 671.137296] Lustre: DEBUG MARKER: == conf-sanity test 5a: force cleanup mds, then cleanup == 05:50:50 (1762167050) [ 671.970898] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 672.002669] alg: No test for adler32 (adler32-zlib) [ 672.876719] Lustre: Lustre: Build Version: 2.16.59_50_g4204c43 [ 672.978507] LNet: Added LNI 192.168.202.41@tcp [8/256/0/180] [ 674.575191] Key type lgssc registered [ 675.042384] Lustre: Echo OBD driver; http://www.lustre.org/ [ 695.560155] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 697.116260] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 704.563613] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 716.276056] Lustre: Mounted lustre-client [ 721.381065] Lustre: lustre-MDT0000-mdc-ffff9cb2488ab800: Connection to lustre-MDT0000 (at 192.168.202.141@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 731.622286] Lustre: lustre-MDT0001-mdc-ffff9cb2488ab800: Connection to lustre-MDT0001 (at 192.168.202.141@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 741.789878] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 742.879463] Lustre: 29596:0:(client.c:2468:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1762167106/real 1762167106] req@00000000432b5a76 x1847766078724352/t0(0) o400->MGC192.168.202.141@tcp@192.168.202.141@tcp:26/25 lens 224/224 e 0 to 1 dl 1762167122 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 742.889646] LustreError: MGC192.168.202.141@tcp: Connection to MGS (at 192.168.202.141@tcp) was lost; in progress operations using this service will fail [ 752.039775] LustreError: 31808:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 752.055147] Lustre: Unmounted lustre-client [ 763.569332] Key type lgssc unregistered [ 763.703689] LNet: 32203:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 763.707857] LNetError: 32203:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 763.716291] LNet: Removed LNI 192.168.202.41@tcp [ 764.019330] Key type .llcrypt unregistered [ 764.022362] Key type ._llcrypt unregistered [ 771.151858] Key type ._llcrypt registered [ 771.153200] Key type .llcrypt registered [ 771.778528] Lustre: DEBUG MARKER: == conf-sanity test 5b: Try to start a client with no MGS (should return errs) ========================================================== 05:52:31 (1762167151) [ 772.794153] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 772.805555] alg: No test for adler32 (adler32-zlib) [ 773.719916] Lustre: Lustre: Build Version: 2.16.59_50_g4204c43 [ 773.855960] LNet: Added LNI 192.168.202.41@tcp [8/256/0/180] [ 775.471180] Key type lgssc registered [ 775.971569] Lustre: Echo OBD driver; http://www.lustre.org/ [ 837.582618] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 842.911348] LustreError: 34366:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.202.141@tcp: failed processing log, type 1: rc = -110 [ 850.911438] LustreError: 34375:0:(mgc_request.c:614:do_requeue()) failed processing log: -110 [ 999.391388] LustreError: MGC192.168.202.141@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 [ 999.396945] Lustre: Unmounted lustre-client [ 999.398719] LustreError: 34366:0:(obd_class.h:479:obd_check_dev()) Device 0 not setup [ 999.403749] LustreError: 34366:0:(super25.c:199:lustre_fill_super()) llite: Unable to mount : rc = -110 [ 1004.214812] Key type lgssc unregistered [ 1004.338542] LNet: 34789:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1004.341585] LNetError: 34789:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1004.352350] LNet: Removed LNI 192.168.202.41@tcp [ 1004.616680] Key type .llcrypt unregistered [ 1004.618303] Key type ._llcrypt unregistered [ 1010.319789] Key type ._llcrypt registered [ 1010.321046] Key type .llcrypt registered [ 1010.877783] Lustre: DEBUG MARKER: == conf-sanity test 5c: cleanup after failed mount (bug 2712) (should return errs) ========================================================== 05:56:30 (1762167390) [ 1011.815131] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1011.823759] alg: No test for adler32 (adler32-zlib) [ 1012.708735] Lustre: Lustre: Build Version: 2.16.59_50_g4204c43 [ 1012.813335] LNet: Added LNI 192.168.202.41@tcp [8/256/0/180] [ 1014.407206] Key type lgssc registered [ 1014.873913] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1036.071988] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1037.636560] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1045.516297] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1045.678955] LustreError: 37781:0:(llite_lib.c:1440:ll_fill_super()) wrong.lustre: fsname longer than 8 characters: rc = -36 [ 1045.684434] Lustre: Unmounted wrong.lustre-client [ 1045.686895] LustreError: 37781:0:(obd_class.h:479:obd_check_dev()) Device 0 not setup [ 1045.690039] LustreError: 37781:0:(super25.c:199:lustre_fill_super()) llite: Unable to mount : rc = -36 [ 1059.670520] Key type lgssc unregistered [ 1059.797368] LNet: 38195:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1059.800131] LNetError: 38195:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1059.807459] LNet: Removed LNI 192.168.202.41@tcp [ 1060.062818] Key type .llcrypt unregistered [ 1060.064339] Key type ._llcrypt unregistered [ 1065.716120] Key type ._llcrypt registered [ 1065.717227] Key type .llcrypt registered [ 1066.225653] Lustre: DEBUG MARKER: == conf-sanity test 5d: mount with ost down ============== 05:57:25 (1762167445) [ 1067.142467] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1067.151303] alg: No test for adler32 (adler32-zlib) [ 1068.028684] Lustre: Lustre: Build Version: 2.16.59_50_g4204c43 [ 1068.126741] LNet: Added LNI 192.168.202.41@tcp [8/256/0/180] [ 1069.719240] Key type lgssc registered [ 1070.175860] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1131.444509] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1143.327609] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1144.816059] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1153.755528] Lustre: Mounted lustre-client [ 1154.254317] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1164.520772] LustreError: 41270:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1164.537196] Lustre: Unmounted lustre-client [ 1190.845377] Key type lgssc unregistered [ 1190.971020] LNet: 41667:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1190.974775] LNetError: 41667:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1190.986170] LNet: Removed LNI 192.168.202.41@tcp [ 1191.256666] Key type .llcrypt unregistered [ 1191.258526] Key type ._llcrypt unregistered [ 1197.093569] Key type ._llcrypt registered [ 1197.094734] Key type .llcrypt registered [ 1197.573669] Lustre: DEBUG MARKER: == conf-sanity test 5e: delayed connect, don't crash (bug 10268) ========================================================== 05:59:37 (1762167577) [ 1198.360001] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1198.389039] alg: No test for adler32 (adler32-zlib) [ 1199.255506] Lustre: Lustre: Build Version: 2.16.59_50_g4204c43 [ 1199.343240] LNet: Added LNI 192.168.202.41@tcp [8/256/0/180] [ 1200.927202] Key type lgssc registered [ 1201.390309] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1219.341946] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1220.569697] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1226.614033] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1227.743350] LustreError: 42630:0:(niobuf.c:1104:ptl_send_rpc()) cfs_fail_timeout id 506 sleeping for 10000ms [ 1231.839361] LustreError: 44656:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.202.141@tcp: failed processing log, type 1: rc = -110 [ 1237.831138] LustreError: 42630:0:(niobuf.c:1104:ptl_send_rpc()) cfs_fail_timeout id 506 awake [ 1238.892991] Lustre: Mounted lustre-client [ 1239.372886] LustreError: 44714:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cb2487fc800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1239.386704] LustreError: 44714:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1239.410459] Lustre: Unmounted lustre-client [ 1256.955971] Key type lgssc unregistered [ 1257.091709] LNet: 45115:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1257.096182] LNetError: 45115:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1257.106811] LNet: Removed LNI 192.168.202.41@tcp [ 1257.366132] Key type .llcrypt unregistered [ 1257.367344] Key type ._llcrypt unregistered [ 1262.285688] Key type ._llcrypt registered [ 1262.287042] Key type .llcrypt registered [ 1262.754671] Lustre: DEBUG MARKER: == conf-sanity test 5f: mds down, cleanup after failed mount (bug 2712) ========================================================== 06:00:42 (1762167642) [ 1263.280416] Lustre: DEBUG MARKER: SKIP: conf-sanity test_5f needs separate mgs and mds [ 1263.804669] Lustre: DEBUG MARKER: == conf-sanity test 5g: handle missing debugfs =========== 06:00:43 (1762167643) [ 1265.948840] Lustre: DEBUG MARKER: == conf-sanity test 5h: start mdt failure at mdt_fs_setup() ========================================================== 06:00:45 (1762167645) [ 1266.809448] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1266.821302] alg: No test for adler32 (adler32-zlib) [ 1267.684950] Lustre: Lustre: Build Version: 2.16.59_50_g4204c43 [ 1267.767560] LNet: Added LNI 192.168.202.41@tcp [8/256/0/180] [ 1269.351170] Key type lgssc registered [ 1269.798285] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1286.943248] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1288.160228] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1294.337174] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1305.072492] Lustre: Mounted lustre-client [ 1310.179108] Lustre: lustre-MDT0000-mdc-ffff9cb252067000: Connection to lustre-MDT0000 (at 192.168.202.141@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1315.298726] LustreError: MGC192.168.202.141@tcp: Connection to MGS (at 192.168.202.141@tcp) was lost; in progress operations using this service will fail [ 1315.305917] Lustre: Evicted from MGS (at 192.168.202.141@tcp) after server handle changed from 0x946e04d7673911ba to 0x946e04d767391492 [ 1315.310883] Lustre: MGC192.168.202.141@tcp: Connection restored to 192.168.202.141@tcp (at 192.168.202.141@tcp) [ 1318.429309] Lustre: lustre-MDT0000-mdc-ffff9cb252067000: Connection restored to 192.168.202.141@tcp (at 192.168.202.141@tcp) [ 1318.784289] LustreError: 49481:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cb252067000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1318.792954] LustreError: 49481:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1318.816151] Lustre: Unmounted lustre-client [ 1336.225417] Key type lgssc unregistered [ 1336.340573] LNet: 49883:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1336.343245] LNetError: 49883:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1336.351756] LNet: Removed LNI 192.168.202.41@tcp [ 1336.570532] Key type .llcrypt unregistered [ 1336.571609] Key type ._llcrypt unregistered [ 1341.650733] Key type ._llcrypt registered [ 1341.651914] Key type .llcrypt registered [ 1342.100942] Lustre: DEBUG MARKER: == conf-sanity test 5i: start mdt failure at mdt_quota_init() ========================================================== 06:02:01 (1762167721) [ 1342.899375] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1342.936288] alg: No test for adler32 (adler32-zlib) [ 1343.788986] Lustre: Lustre: Build Version: 2.16.59_50_g4204c43 [ 1343.872707] LNet: Added LNI 192.168.202.41@tcp [8/256/0/180] [ 1345.455102] Key type lgssc registered [ 1345.824196] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1363.012780] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1364.319881] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1370.194948] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1380.846674] Lustre: Mounted lustre-client [ 1385.953934] Lustre: lustre-MDT0000-mdc-ffff9cb242873000: Connection to lustre-MDT0000 (at 192.168.202.141@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1391.073986] LustreError: MGC192.168.202.141@tcp: Connection to MGS (at 192.168.202.141@tcp) was lost; in progress operations using this service will fail [ 1391.080984] Lustre: Evicted from MGS (at 192.168.202.141@tcp) after server handle changed from 0x9dc782dea9d6a229 to 0x9dc782dea9d6a508 [ 1391.084715] Lustre: MGC192.168.202.141@tcp: Connection restored to 192.168.202.141@tcp (at 192.168.202.141@tcp) [ 1394.198835] Lustre: lustre-MDT0000-mdc-ffff9cb242873000: Connection restored to 192.168.202.141@tcp (at 192.168.202.141@tcp) [ 1394.485951] LustreError: 53339:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cb242873000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1394.491975] LustreError: 53339:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1394.511165] Lustre: Unmounted lustre-client [ 1419.575298] Key type lgssc unregistered [ 1419.686526] LNet: 53742:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1419.689038] LNetError: 53742:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1419.696427] LNet: Removed LNI 192.168.202.41@tcp [ 1419.921356] Key type .llcrypt unregistered [ 1419.922303] Key type ._llcrypt unregistered [ 1424.516292] Key type ._llcrypt registered [ 1424.517201] Key type .llcrypt registered [ 1424.936625] Lustre: DEBUG MARKER: == conf-sanity test 5j: verify libcfs doesn't crash when setting debug flags ========================================================== 06:03:24 (1762167804) [ 1425.085441] Key type .llcrypt unregistered [ 1425.087204] Key type ._llcrypt unregistered [ 1426.813054] Key type ._llcrypt registered [ 1426.813771] Key type .llcrypt registered [ 1426.834301] Key type .llcrypt unregistered [ 1426.835258] Key type ._llcrypt unregistered [ 1428.455488] Key type ._llcrypt registered [ 1428.456532] Key type .llcrypt registered [ 1428.900652] Lustre: DEBUG MARKER: == conf-sanity test 6: manual umount, then mount again === 06:03:28 (1762167808) [ 1429.691945] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1429.696912] alg: No test for adler32 (adler32-zlib) [ 1430.549152] Lustre: Lustre: Build Version: 2.16.59_50_g4204c43 [ 1430.630791] LNet: Added LNI 192.168.202.41@tcp [8/256/0/180] [ 1432.215110] Key type lgssc registered [ 1432.586180] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1449.917781] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1451.259326] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1457.554408] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1467.888948] Lustre: Mounted lustre-client [ 1469.272861] LustreError: 57591:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cb248976000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1469.280266] LustreError: 57591:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1469.305200] Lustre: Unmounted lustre-client [ 1469.422467] Lustre: Mounted lustre-client [ 1469.805679] LustreError: 57667:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cb246ef3000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1469.812056] LustreError: 57667:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1469.815500] LustreError: 57667:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 1469.841150] Lustre: Unmounted lustre-client [ 1487.485898] Key type lgssc unregistered [ 1487.598420] LNet: 58068:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1487.601956] LNetError: 58068:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1487.612574] LNet: Removed LNI 192.168.202.41@tcp [ 1487.875422] Key type .llcrypt unregistered [ 1487.876431] Key type ._llcrypt unregistered [ 1492.613203] Key type ._llcrypt registered [ 1492.614543] Key type .llcrypt registered [ 1493.079905] Lustre: DEBUG MARKER: == conf-sanity test 7: manual umount, then cleanup ======= 06:04:32 (1762167872) [ 1493.789952] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1493.827708] alg: No test for adler32 (adler32-zlib) [ 1494.686361] Lustre: Lustre: Build Version: 2.16.59_50_g4204c43 [ 1494.773624] LNet: Added LNI 192.168.202.41@tcp [8/256/0/180] [ 1496.359378] Key type lgssc registered [ 1496.765059] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1513.393457] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1514.612158] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1520.304129] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1535.983032] Lustre: Mounted lustre-client [ 1537.335434] LustreError: 61131:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cb247f7d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1537.341306] LustreError: 61131:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1537.362748] Lustre: Unmounted lustre-client [ 1554.781823] Key type lgssc unregistered [ 1554.903040] LNet: 61541:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1554.905308] LNetError: 61541:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1554.915339] LNet: Removed LNI 192.168.202.41@tcp [ 1555.161373] Key type .llcrypt unregistered [ 1555.162446] Key type ._llcrypt unregistered [ 1560.060354] Key type ._llcrypt registered [ 1560.061355] Key type .llcrypt registered [ 1560.533153] Lustre: DEBUG MARKER: == conf-sanity test 8: double mount setup ================ 06:05:39 (1762167939) [ 1561.221467] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1561.262446] alg: No test for adler32 (adler32-zlib) [ 1562.143647] Lustre: Lustre: Build Version: 2.16.59_50_g4204c43 [ 1562.234506] LNet: Added LNI 192.168.202.41@tcp [8/256/0/180] [ 1563.815197] Key type lgssc registered [ 1564.181755] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1579.990105] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1581.036138] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1587.201285] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1588.341443] Lustre: Mounted lustre-client [ 1589.760923] Lustre: Mounted lustre-client [ 1590.194391] LustreError: 64679:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cb2501af000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1590.200330] LustreError: 64679:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 1590.221187] Lustre: Unmounted lustre-client [ 1616.105263] Key type lgssc unregistered [ 1616.230527] LNet: 65102:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1616.232817] LNetError: 65102:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1616.243783] LNet: Removed LNI 192.168.202.41@tcp [ 1616.495145] Key type .llcrypt unregistered [ 1616.496391] Key type ._llcrypt unregistered [ 1621.307626] Key type ._llcrypt registered [ 1621.308682] Key type .llcrypt registered [ 1621.719381] Lustre: DEBUG MARKER: == conf-sanity test 9: test ptldebug and subsystem for mkfs ========================================================== 06:06:41 (1762168001) [ 1622.355867] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1622.361596] alg: No test for adler32 (adler32-zlib) [ 1623.220229] Lustre: Lustre: Build Version: 2.16.59_50_g4204c43 [ 1623.297339] LNet: Added LNI 192.168.202.41@tcp [8/256/0/180] [ 1624.871220] Key type lgssc registered [ 1625.217547] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1691.108855] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1700.063329] Lustre: DEBUG MARKER: == conf-sanity test 10a: find lctl param broken symlinks ========================================================== 06:07:59 (1762168079) [ 1713.887958] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1715.571303] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1723.636527] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1730.035719] Lustre: Mounted lustre-client [ 1732.977667] LustreError: 69339:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cb252067000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1732.986801] LustreError: 69339:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1733.019723] Lustre: Unmounted lustre-client [ 1743.787560] Key type lgssc unregistered [ 1743.926801] LNet: 69739:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1743.931152] LNetError: 69739:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1743.948161] LNet: Removed LNI 192.168.202.41@tcp [ 1744.327550] Key type .llcrypt unregistered [ 1744.329978] Key type ._llcrypt unregistered [ 1751.276067] Key type ._llcrypt registered [ 1751.277407] Key type .llcrypt registered [ 1751.859509] Lustre: DEBUG MARKER: == conf-sanity test 17: Verify failed mds_postsetup won't fail assertion (2936) (should return errs) ========================================================== 06:08:51 (1762168131) [ 1752.757107] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1752.765471] alg: No test for adler32 (adler32-zlib) [ 1753.659088] Lustre: Lustre: Build Version: 2.16.59_50_g4204c43 [ 1753.748344] LNet: Added LNI 192.168.202.41@tcp [8/256/0/180] [ 1755.343197] Key type lgssc registered [ 1755.846715] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1776.916925] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1778.695349] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1785.701030] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1796.098413] Lustre: Mounted lustre-client [ 1798.076658] LustreError: 72856:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cb246e86800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1798.088966] LustreError: 72856:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1798.125792] Lustre: Unmounted lustre-client [ 1826.907432] Key type lgssc unregistered [ 1827.128904] LNet: 73260:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1827.134626] LNetError: 73260:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1828.199071] LNet: Removed LNI 192.168.202.41@tcp [ 1828.663546] Key type .llcrypt unregistered [ 1828.664973] Key type ._llcrypt unregistered [ 1837.556405] Key type ._llcrypt registered [ 1837.561019] Key type .llcrypt registered [ 1837.870536] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1837.897096] alg: No test for adler32 (adler32-zlib) [ 1838.974712] Lustre: Lustre: Build Version: 2.16.59_50_g4204c43 [ 1839.155808] LNet: Added LNI 192.168.202.41@tcp [8/256/0/180] [ 1840.808501] Key type lgssc registered [ 1841.674952] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1909.342584] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1970.872981] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1973.022400] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1984.617209] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2020.895881] Lustre: DEBUG MARKER: == conf-sanity test 18: check mkfs creates large journals ========================================================== 06:13:20 (1762168400) [ 2022.453430] Lustre: DEBUG MARKER: use device /dev/mapper/mds1_flakey with MIN=2000000 [ 2061.501387] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2063.287840] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2072.493362] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2100.299913] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2101.757145] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2109.628167] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2120.179143] Lustre: Mounted lustre-client [ 2123.013225] Lustre: DEBUG MARKER: Success: mkfs creates large journals. Size: 78M [ 2123.497309] LustreError: 84221:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cb248bbd800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2123.506483] LustreError: 84221:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2123.530137] Lustre: Unmounted lustre-client [ 2142.036721] Key type lgssc unregistered [ 2142.161067] LNet: 84622:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2142.164660] LNetError: 84622:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2142.176618] LNet: Removed LNI 192.168.202.41@tcp [ 2142.441404] Key type .llcrypt unregistered [ 2142.443108] Key type ._llcrypt unregistered [ 2153.499522] Key type ._llcrypt registered [ 2153.501296] Key type .llcrypt registered [ 2153.696578] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2153.715424] alg: No test for adler32 (adler32-zlib) [ 2154.601294] Lustre: Lustre: Build Version: 2.16.59_50_g4204c43 [ 2154.700088] LNet: Added LNI 192.168.202.41@tcp [8/256/0/180] [ 2156.287167] Key type lgssc registered [ 2156.782066] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2192.404909] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2193.806437] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2202.645683] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2222.299928] Lustre: DEBUG MARKER: == conf-sanity test 19a: start/stop MDS without OSTs ===== 06:16:41 (1762168601) [ 2233.912206] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2235.521061] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2248.152271] Lustre: DEBUG MARKER: == conf-sanity test 19b: start/stop OSTs without MDS ===== 06:17:07 (1762168627) [ 2301.742420] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2305.840564] Lustre: DEBUG MARKER: == conf-sanity test 20: remount ro,rw mounts work and doesn't break /etc/mtab ========================================================== 06:18:05 (1762168685) [ 2316.909610] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2318.195785] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2324.956631] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2335.737656] Lustre: Mounted lustre-client [ 2335.997422] Lustre: Remounted lustre-client read-only [ 2336.583710] LustreError: 93084:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cb248972000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2336.593802] LustreError: 93084:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2336.618534] Lustre: Unmounted lustre-client [ 2360.183795] Lustre: DEBUG MARKER: == conf-sanity test 21a: start mds before ost, stop ost first ========================================================== 06:18:59 (1762168739) [ 2371.086973] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2372.560244] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2380.104375] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2410.632173] Lustre: DEBUG MARKER: == conf-sanity test 21b: start ost before mds, stop mds first ========================================================== 06:19:50 (1762168790) [ 2464.117526] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2476.561842] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2477.956246] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2507.251829] Lustre: DEBUG MARKER: == conf-sanity test 21c: start mds between two osts, stop mds last ========================================================== 06:21:26 (1762168886) [ 2560.341608] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2571.381145] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2572.634721] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2580.896384] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 2615.601494] Lustre: DEBUG MARKER: == conf-sanity test 21d: start mgs then ost and then mds ========================================================== 06:23:15 (1762168995) [ 2616.132149] Lustre: DEBUG MARKER: SKIP: conf-sanity test_21d need separate mgs device [ 2616.673475] Lustre: DEBUG MARKER: SKIP: conf-sanity test_21e skipping excluded test 21e [ 2617.230379] Lustre: DEBUG MARKER: == conf-sanity test 22: start a client before osts (should return errs) ========================================================== 06:23:16 (1762168996) [ 2627.466662] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2628.807565] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2635.271402] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2647.037104] Lustre: Mounted lustre-client [ 2647.440952] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2657.704924] LustreError: 102775:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2657.708162] LustreError: 102775:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 2657.725159] Lustre: Unmounted lustre-client [ 2664.425687] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2664.621922] Lustre: Mounted lustre-client [ 2669.275179] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9cb247fd8000.ost_server_uuid 50 [ 2669.852326] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9cb247fd8000.ost_server_uuid in FULL state after 0 sec [ 2670.370043] LustreError: 103816:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cb247fd8000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2670.385994] LustreError: 103816:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2670.388412] LustreError: 103816:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 2670.419119] Lustre: Unmounted lustre-client [ 2702.841864] Key type lgssc unregistered [ 2702.971083] LNet: 104221:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2702.974549] LNetError: 104221:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2702.985148] LNet: Removed LNI 192.168.202.41@tcp [ 2703.297115] Key type .llcrypt unregistered [ 2703.299528] Key type ._llcrypt unregistered [ 2709.594596] Key type ._llcrypt registered [ 2709.596200] Key type .llcrypt registered [ 2710.176167] Lustre: DEBUG MARKER: == conf-sanity test 23a: interrupt client during recovery mount delay ========================================================== 06:24:49 (1762169089) [ 2711.120624] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2711.131480] alg: No test for adler32 (adler32-zlib) [ 2712.022455] Lustre: Lustre: Build Version: 2.16.59_50_g4204c43 [ 2712.142878] LNet: Added LNI 192.168.202.41@tcp [8/256/0/180] [ 2713.743203] Key type lgssc registered [ 2714.206330] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2745.564548] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2750.892466] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2771.591966] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2774.619614] Lustre: Mounted lustre-client [ 2779.659799] Lustre: lustre-MDT0000-mdc-ffff9cb248973800: Connection to lustre-MDT0000 (at 192.168.202.141@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2781.706886] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2791.979225] LustreError: 107328:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2791.996485] LustreError: MGC192.168.202.141@tcp: Connection to MGS (at 192.168.202.141@tcp) was lost; in progress operations using this service will fail [ 2792.022373] Lustre: Unmounted lustre-client [ 2800.384533] LustreError: lustre-MDT0000-mdc-ffff9cb252064000: operation mds_connect to node 192.168.202.141@tcp failed: rc = -16 [ 2805.441620] LustreError: 107513:0:(lmv_obd.c:1435:lmv_statfs()) lustre-MDT0000-mdc-ffff9cb252064000: can't stat MDS #0: rc = -16 [ 2805.466881] LustreError: 107513:0:(obd_class.h:479:obd_check_dev()) Device 3 not setup [ 2805.470738] LustreError: 107513:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 2805.486208] Lustre: Unmounted lustre-client [ 2805.498878] LustreError: 107513:0:(super25.c:199:lustre_fill_super()) llite: Unable to mount : rc = -16 [ 2827.510097] Key type lgssc unregistered [ 2827.682607] LNet: 107966:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2827.688499] LNetError: 107966:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2827.699363] LNet: Removed LNI 192.168.202.41@tcp [ 2828.103572] Key type .llcrypt unregistered [ 2828.106375] Key type ._llcrypt unregistered [ 2837.242874] Key type ._llcrypt registered [ 2837.244784] Key type .llcrypt registered [ 2838.123169] Lustre: DEBUG MARKER: == conf-sanity test 23b: Simulate -EINTR during mount ==== 06:26:57 (1762169217) [ 2839.294435] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2839.357430] alg: No test for adler32 (adler32-zlib) [ 2840.272237] Lustre: Lustre: Build Version: 2.16.59_50_g4204c43 [ 2840.412724] LNet: Added LNI 192.168.202.41@tcp [8/256/0/180] [ 2842.031185] Key type lgssc registered [ 2842.748317] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2871.632726] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2873.622156] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2883.475978] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2884.833701] Lustre: Mounted lustre-client [ 2885.633509] LustreError: 111001:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cb2445e7000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2885.649833] LustreError: 111001:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2885.690742] Lustre: Unmounted lustre-client [ 2901.448342] Key type lgssc unregistered [ 2901.579847] LNet: 111403:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2901.583649] LNetError: 111403:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2901.595734] LNet: Removed LNI 192.168.202.41@tcp [ 2901.990811] Key type .llcrypt unregistered [ 2901.992372] Key type ._llcrypt unregistered [ 2912.100940] Key type ._llcrypt registered [ 2912.102826] Key type .llcrypt registered [ 2912.884327] Lustre: DEBUG MARKER: == conf-sanity test 24a: Multiple MDTs on a single node == 06:28:12 (1762169292) [ 2914.298409] Lustre: DEBUG MARKER: SKIP: conf-sanity test_24a mixed loopback and real device not working [ 2915.425158] Lustre: DEBUG MARKER: == conf-sanity test 24b: Multiple MGSs on a single node (should return err) ========================================================== 06:28:14 (1762169294) [ 2916.902357] Lustre: DEBUG MARKER: SKIP: conf-sanity test_24b mixed loopback and real device not working [ 2917.792633] Lustre: DEBUG MARKER: == conf-sanity test 25: Verify modules are referenced ==== 06:28:17 (1762169297) [ 2919.100460] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2919.109795] alg: No test for adler32 (adler32-zlib) [ 2920.043955] Lustre: Lustre: Build Version: 2.16.59_50_g4204c43 [ 2920.217894] LNet: Added LNI 192.168.202.41@tcp [8/256/0/180] [ 2921.863200] Key type lgssc registered [ 2922.570480] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2947.875050] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2949.988220] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2959.694772] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2966.530347] Lustre: Mounted lustre-client [ 2972.511566] LustreError: 115093:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cb246ef5800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2972.525562] LustreError: 115093:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2972.563129] Lustre: Unmounted lustre-client [ 2992.308163] Key type lgssc unregistered [ 2992.445657] LNet: 115493:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2992.448469] LNetError: 115493:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2992.459448] LNet: Removed LNI 192.168.202.41@tcp [ 2992.823813] Key type .llcrypt unregistered [ 2992.826069] Key type ._llcrypt unregistered [ 3001.052514] Key type ._llcrypt registered [ 3001.054203] Key type .llcrypt registered [ 3001.788522] Lustre: DEBUG MARKER: == conf-sanity test 26: MDT startup failure cleans LOV (should return errs) ========================================================== 06:29:40 (1762169380) [ 3002.408802] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3002.450204] alg: No test for adler32 (adler32-zlib) [ 3003.404670] Lustre: Lustre: Build Version: 2.16.59_50_g4204c43 [ 3003.547467] LNet: Added LNI 192.168.202.41@tcp [8/256/0/180] [ 3005.167962] Key type lgssc registered [ 3005.829603] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3039.814363] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3041.764805] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3053.729636] Key type lgssc unregistered [ 3053.879743] LNet: 118506:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3053.884941] LNetError: 118506:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3053.900559] LNet: Removed LNI 192.168.202.41@tcp [ 3054.209929] Key type .llcrypt unregistered [ 3054.211609] Key type ._llcrypt unregistered [ 3060.987803] Key type ._llcrypt registered [ 3060.989490] Key type .llcrypt registered [ 3061.654787] Lustre: DEBUG MARKER: == conf-sanity test 27a: Reacquire MGS lock if OST started first ========================================================== 06:30:40 (1762169440) [ 3065.983412] Key type .llcrypt unregistered [ 3065.985991] Key type ._llcrypt unregistered [ 3069.600313] Key type ._llcrypt registered [ 3069.603640] Key type .llcrypt registered [ 3069.830053] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3069.839720] alg: No test for adler32 (adler32-zlib) [ 3070.774394] Lustre: Lustre: Build Version: 2.16.59_50_g4204c43 [ 3070.898770] LNet: Added LNI 192.168.202.41@tcp [8/256/0/180] [ 3072.503823] Key type lgssc registered [ 3073.136585] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3136.557410] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3148.931878] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3150.432553] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3184.920509] Key type lgssc unregistered [ 3185.072823] LNet: 122275:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3185.077560] LNetError: 122275:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3185.089707] LNet: Removed LNI 192.168.202.41@tcp [ 3185.381852] Key type .llcrypt unregistered [ 3185.383435] Key type ._llcrypt unregistered [ 3192.410530] Key type ._llcrypt registered [ 3192.412117] Key type .llcrypt registered [ 3193.210062] Lustre: DEBUG MARKER: == conf-sanity test 27b: Reacquire MGS lock after failover ========================================================== 06:32:52 (1762169572) [ 3194.472733] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3194.487784] alg: No test for adler32 (adler32-zlib) [ 3195.456883] Lustre: Lustre: Build Version: 2.16.59_50_g4204c43 [ 3195.610131] LNet: Added LNI 192.168.202.41@tcp [8/256/0/180] [ 3197.232697] Key type lgssc registered [ 3197.853108] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3221.683049] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3223.584612] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3233.292267] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3240.939150] Lustre: Mounted lustre-client [ 3246.051605] Lustre: lustre-MDT0000-mdc-ffff9cb250f64000: Connection to lustre-MDT0000 (at 192.168.202.141@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3261.416736] LustreError: MGC192.168.202.141@tcp: Connection to MGS (at 192.168.202.141@tcp) was lost; in progress operations using this service will fail [ 3261.426299] Lustre: Evicted from MGS (at 192.168.202.141@tcp) after server handle changed from 0xbfd46bb799795258 to 0xbfd46bb799795488 [ 3261.433515] Lustre: MGC192.168.202.141@tcp: Connection restored to 192.168.202.141@tcp (at 192.168.202.141@tcp) [ 3262.500642] Lustre: lustre-MDT0000-mdc-ffff9cb250f64000: Connection restored to 192.168.202.141@tcp (at 192.168.202.141@tcp) [ 3272.807898] LustreError: 125761:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cb250f64000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3272.820337] LustreError: 125761:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3272.871424] Lustre: Unmounted lustre-client [ 3293.291311] Key type lgssc unregistered [ 3293.437372] LNet: 126163:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3293.440789] LNetError: 126163:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3293.447780] LNet: Removed LNI 192.168.202.41@tcp [ 3293.756965] Key type .llcrypt unregistered [ 3293.758627] Key type ._llcrypt unregistered [ 3301.579806] Key type ._llcrypt registered [ 3301.581036] Key type .llcrypt registered [ 3302.321145] Lustre: DEBUG MARKER: == conf-sanity test 28A: permanent parameter setting ===== 06:34:41 (1762169681) [ 3303.796923] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3303.807580] alg: No test for adler32 (adler32-zlib) [ 3304.828539] Lustre: Lustre: Build Version: 2.16.59_50_g4204c43 [ 3304.955737] LNet: Added LNI 192.168.202.41@tcp [8/256/0/180] [ 3306.567305] Key type lgssc registered [ 3307.222980] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3330.204205] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3332.086493] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3341.574801] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3347.981187] Lustre: Mounted lustre-client [ 3359.608261] LustreError: 129327:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cb252060800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3359.622253] LustreError: 129327:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3359.658528] Lustre: Unmounted lustre-client [ 3359.911441] Lustre: Mounted lustre-client [ 3369.712598] LustreError: 129477:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cb24808c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3369.730464] LustreError: 129477:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3369.733242] LustreError: 129477:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 3369.767063] Lustre: Unmounted lustre-client [ 3389.841541] Key type lgssc unregistered [ 3389.984241] LNet: 129878:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3389.988215] LNetError: 129878:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3390.000062] LNet: Removed LNI 192.168.202.41@tcp [ 3390.366838] Key type .llcrypt unregistered [ 3390.368579] Key type ._llcrypt unregistered [ 3397.967585] Key type ._llcrypt registered [ 3397.969105] Key type .llcrypt registered [ 3398.799521] Lustre: DEBUG MARKER: == conf-sanity test 28a: set symlink parameters permanently with lctl ========================================================== 06:36:17 (1762169777) [ 3400.035079] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3400.074196] alg: No test for adler32 (adler32-zlib) [ 3400.998539] Lustre: Lustre: Build Version: 2.16.59_50_g4204c43 [ 3401.145707] LNet: Added LNI 192.168.202.41@tcp [8/256/0/180] [ 3402.792153] Key type lgssc registered [ 3403.510710] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3429.303805] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3431.489464] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3441.888790] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3443.221652] Lustre: Mounted lustre-client [ 3480.722245] LustreError: 133246:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cb247fdd000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3480.727933] LustreError: 133246:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3480.752402] Lustre: Unmounted lustre-client [ 3508.526438] Key type lgssc unregistered [ 3508.722333] LNet: 133649:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3508.726248] LNetError: 133649:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3508.746399] LNet: Removed LNI 192.168.202.41@tcp [ 3509.122815] Key type .llcrypt unregistered [ 3509.124488] Key type ._llcrypt unregistered [ 3516.672784] Key type ._llcrypt registered [ 3516.674502] Key type .llcrypt registered [ 3517.378693] Lustre: DEBUG MARKER: == conf-sanity test 28b: verify client-side parameters are set upon mount ========================================================== 06:38:16 (1762169896) [ 3518.482223] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3518.513763] alg: No test for adler32 (adler32-zlib) [ 3519.441631] Lustre: Lustre: Build Version: 2.16.59_50_g4204c43 [ 3519.576169] LNet: Added LNI 192.168.202.41@tcp [8/256/0/180] [ 3521.175186] Key type lgssc registered [ 3521.747965] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3545.931969] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3547.596375] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3555.979046] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3562.493044] Lustre: Mounted lustre-client [ 3563.023044] LustreError: 136705:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cb24423e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3563.033072] LustreError: 136705:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3563.061223] Lustre: Unmounted lustre-client [ 3563.232676] Lustre: Mounted lustre-client [ 3565.901110] LustreError: 137200:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cb252065000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3565.911661] LustreError: 137200:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3565.913803] LustreError: 137200:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 3565.938137] Lustre: Unmounted lustre-client [ 3593.136149] Key type lgssc unregistered [ 3593.302303] LNet: 137604:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3593.305679] LNetError: 137604:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3593.316662] LNet: Removed LNI 192.168.202.41@tcp [ 3593.601417] Key type .llcrypt unregistered [ 3593.603553] Key type ._llcrypt unregistered [ 3597.847492] Key type ._llcrypt registered [ 3597.849046] Key type .llcrypt registered [ 3598.436026] Lustre: DEBUG MARKER: == conf-sanity test 28c: verify filesystem parameters are set upon mount ========================================================== 06:39:37 (1762169977) [ 3599.424655] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3599.431554] alg: No test for adler32 (adler32-zlib) [ 3600.330971] Lustre: Lustre: Build Version: 2.16.59_50_g4204c43 [ 3600.444466] LNet: Added LNI 192.168.202.41@tcp [8/256/0/180] [ 3602.047160] Key type lgssc registered [ 3602.695471] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3624.174623] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3625.671067] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3633.438502] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3643.907819] Lustre: Mounted lustre-client [ 3644.425632] LustreError: 140241:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cb248975000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3644.433527] LustreError: 140241:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3644.459447] Lustre: Unmounted lustre-client [ 3644.617466] Lustre: Mounted lustre-client [ 3647.256195] LustreError: 140736:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cb246e86800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3647.266194] LustreError: 140736:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3647.269044] LustreError: 140736:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 3647.296001] Lustre: Unmounted lustre-client [ 3665.580425] Key type lgssc unregistered [ 3665.710996] LNet: 141138:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3665.713967] LNetError: 141138:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3665.726525] LNet: Removed LNI 192.168.202.41@tcp [ 3666.005507] Key type .llcrypt unregistered [ 3666.007382] Key type ._llcrypt unregistered [ 3670.151926] Key type ._llcrypt registered [ 3670.153155] Key type .llcrypt registered [ 3670.732409] Lustre: DEBUG MARKER: == conf-sanity test 29: permanently remove an OST ======== 06:40:50 (1762170050) [ 3671.702429] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3671.712318] alg: No test for adler32 (adler32-zlib) [ 3672.634187] Lustre: Lustre: Build Version: 2.16.59_50_g4204c43 [ 3672.758205] LNet: Added LNI 192.168.202.41@tcp [8/256/0/180] [ 3674.377738] Key type lgssc registered [ 3674.880644] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3695.693221] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3697.265791] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3705.480564] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3710.968713] Lustre: Mounted lustre-client [ 3718.618466] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3722.353787] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 3 sec [ 3741.673304] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 3743.664433] LustreError: 144507:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cb247fda000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3743.674723] LustreError: 144507:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 3743.700737] Lustre: Unmounted lustre-client [ 3743.818075] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 3743.885504] Lustre: Mounted lustre-client [ 3755.276549] LustreError: 144680:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cb245e84000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3755.284945] LustreError: 144680:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 3755.287819] LustreError: 144680:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 3755.319644] Lustre: Unmounted lustre-client [ 3781.690233] Key type lgssc unregistered [ 3781.816471] LNet: 145126:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3781.819721] LNetError: 145126:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3781.830976] LNet: Removed LNI 192.168.202.41@tcp [ 3782.093444] Key type .llcrypt unregistered [ 3782.094571] Key type ._llcrypt unregistered [ 3800.125766] Key type ._llcrypt registered [ 3800.127162] Key type .llcrypt registered [ 3800.308637] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3800.315771] alg: No test for adler32 (adler32-zlib) [ 3801.201420] Lustre: Lustre: Build Version: 2.16.59_50_g4204c43 [ 3801.303271] LNet: Added LNI 192.168.202.41@tcp [8/256/0/180] [ 3802.903397] Key type lgssc registered [ 3803.389339] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3838.585190] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3840.100836] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3849.172696] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3881.350482] Lustre: DEBUG MARKER: == conf-sanity test 30a: Big config llog and permanent parameter deletion ========================================================== 06:44:20 (1762170260) [ 3893.696419] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3895.213775] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3902.454912] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3913.221990] Lustre: Mounted lustre-client [ 4082.951236] LustreError: 152743:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cb2501af000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4082.956982] LustreError: 152743:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4082.981917] Lustre: Unmounted lustre-client [ 4083.139783] Lustre: Mounted lustre-client [ 4084.056563] LustreError: 152829:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cb250fbc000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4084.063080] LustreError: 152829:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4084.065264] LustreError: 152829:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 4084.087144] Lustre: Unmounted lustre-client [ 4084.225731] Lustre: Mounted lustre-client [ 4102.485564] Key type lgssc unregistered [ 4102.606936] LNet: 153306:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4102.610481] LNetError: 153306:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4102.620463] LNet: Removed LNI 192.168.202.41@tcp [ 4102.903747] Key type .llcrypt unregistered [ 4102.905981] Key type ._llcrypt unregistered [ 4108.377464] Key type ._llcrypt registered [ 4108.378779] Key type .llcrypt registered [ 4108.841854] Lustre: DEBUG MARKER: == conf-sanity test 30b: Remove failover nids ============ 06:48:08 (1762170488) [ 4109.723180] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4109.738530] alg: No test for adler32 (adler32-zlib) [ 4110.617777] Lustre: Lustre: Build Version: 2.16.59_50_g4204c43 [ 4110.708706] LNet: Added LNI 192.168.202.41@tcp [8/256/0/180] [ 4112.303157] Key type lgssc registered [ 4112.736782] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4131.586126] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4133.059413] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4140.176489] Lustre: DEBUG MARKER: oleg241-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4151.790882] Lustre: Mounted lustre-client [ 4158.670887] LustreError: 156465:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cb246ef7800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4158.677731] LustreError: 156465:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4158.700217] Lustre: Unmounted lustre-client [ 4158.839642] Lustre: Mounted lustre-client [ 4159.184982] LustreError: 156539:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cb24835a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4159.191303] LustreError: 156539:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4159.193781] LustreError: 156539:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 4159.216403] Lustre: Unmounted lustre-client [ 4177.012788] Key type lgssc unregistered [ 4177.137418] LNet: 156941:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4177.140489] LNetError: 156941:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4177.149471] LNet: Removed LNI 192.168.202.41@tcp [ 4177.418541] Key type .llcrypt unregistered [ 4177.419677] Key type ._llcrypt unregistered [ 4183.572619] Key type ._llcrypt registered [ 4183.574454] Key type .llcrypt registered [ 4184.078314] Lustre: DEBUG MARKER: == conf-sanity test 31: Connect to non-existent node (shouldn't crash) ========================================================== 06:49:23 (1762170563) [ 4187.370260] Key type .llcrypt unregistered [ 4187.371794] Key type ._llcrypt unregistered [ 4198.761730] Key type ._llcrypt registered [ 4198.764409] Key type .llcrypt registered [ 4198.943180] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4198.950941] alg: No test for adler32 (adler32-zlib) [ 4199.818323] Lustre: Lustre: Build Version: 2.16.59_50_g4204c43 [ 4199.911026] LNet: Added LNI 192.168.202.41@tcp [8/256/0/180] [ 4201.503103] Key type lgssc registered [ 4201.927690] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4218.317326] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 4025 sec ========== 06:49:57 (1762170597) [ 4218.918953] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 06:49:58 (1762170598) === [ 4220.022987] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 06:49:59 (1762170599) === [ 4231.578523] Key type lgssc unregistered [ 4231.713324] LNet: 162041:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4231.716732] LNetError: 162041:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4231.724325] LNet: Removed LNI 192.168.202.41@tcp [ 4231.992643] Key type .llcrypt unregistered [ 4231.993877] Key type ._llcrypt unregistered