[ 0.000000] Linux version 4.18.0rh8.10-debug (green@maintenance) (gcc version 8.5.0 20210514 (Red Hat 8.5.0-26) (GCC)) #2 SMP Mon Jul 14 01:24:22 EDT 2025 [ 0.000000] Command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' [ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 [ 0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. [ 0.000000] signal: max sigframe size: 1776 [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bffd9fff] usable [ 0.000000] BIOS-e820: [mem 0x00000000bffda000-0x00000000bfffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x0000000146dfffff] usable [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] SMBIOS 2.8 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.17.0-10.fc44 06/10/2025 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 466086978 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2464 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE2300 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 0022C0 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2374 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE2404 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE243C 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe2300-0xbffe2373] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22ff] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2374-0xbffe2403] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe2404-0xbffe243b] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe243c-0xbffe2463] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524588K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001011] APIC: Switch to symmetric I/O mode setup [ 0.002413] x2apic enabled [ 0.003009] Switched APIC routing to physical x2apic. [ 0.004010] kvm-guest: setup PV IPIs [ 0.007745] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.008000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.008018] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.010007] pid_max: default: 32768 minimum: 301 [ 0.012087] LSM: Security Framework initializing [ 0.013051] Yama: becoming mindful. [ 0.014035] SELinux: Initializing. [ 0.015064] *** VALIDATE selinux *** [ 0.023740] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.028197] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.029152] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.030125] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.031114] *** VALIDATE tmpfs *** [ 0.033348] *** VALIDATE proc *** [ 0.035169] *** VALIDATE cgroup *** [ 0.036010] *** VALIDATE cgroup2 *** [ 0.038047] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.039147] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.040009] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.041029] Spectre V2 : User space: Vulnerable [ 0.042010] Speculative Store Bypass: Vulnerable [ 0.045210] debug: unmapping init [mem 0xffffffff94659000-0xffffffff94660fff] [ 0.048164] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.049699] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.050026] ... version: 2 [ 0.051014] ... bit width: 48 [ 0.052012] ... generic registers: 4 [ 0.053012] ... value mask: 0000ffffffffffff [ 0.054016] ... max period: 00007fffffffffff [ 0.055014] ... fixed-purpose events: 3 [ 0.056013] ... event mask: 000000070000000f [ 0.057268] rcu: Hierarchical SRCU implementation. [ 0.059399] smp: Bringing up secondary CPUs ... [ 0.060586] x86: Booting SMP configuration: [ 0.061022] .... node #0, CPUs: #1 #2 #3 [ 0.067098] smp: Brought up 1 node, 4 CPUs [ 0.069013] smpboot: Max logical packages: 1 [ 0.070015] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.225018] node 0 deferred pages initialised in 153ms [ 0.229185] devtmpfs: initialized [ 0.230213] x86/mm: Memory block size: 128MB [ 0.233016] gcov: version magic: 0x41383552 [ 0.235286] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.236091] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.237236] pinctrl core: initialized pinctrl subsystem [ 0.238205] [ 0.238868] ************************************************************* [ 0.239014] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.240015] ** ** [ 0.241014] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.242013] ** ** [ 0.243024] ** This means that this kernel is built to expose internal ** [ 0.244014] ** IOMMU data structures, which may compromise security on ** [ 0.245009] ** your system. ** [ 0.246008] ** ** [ 0.247014] ** If you see this message and you are not debugging the ** [ 0.248015] ** kernel, report this immediately to your vendor! ** [ 0.249014] ** ** [ 0.250017] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.251015] ************************************************************* [ 0.252839] NET: Registered protocol family 16 [ 0.253450] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.254062] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.255058] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.256453] cpuidle: using governor menu [ 0.258856] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.262471] PCI: Using configuration type 1 for base access [ 0.266188] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.276223] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.277037] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.278223] cryptd: max_cpu_qlen set to 1000 [ 0.282504] ACPI: Added _OSI(Module Device) [ 0.284017] ACPI: Added _OSI(Processor Device) [ 0.286022] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.288017] ACPI: Added _OSI(Processor Aggregator Device) [ 0.294114] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.302247] ACPI: Interpreter enabled [ 0.304072] ACPI: PM: (supports S0 S3 S4 S5) [ 0.306030] ACPI: Using IOAPIC for interrupt routing [ 0.308241] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.312536] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.327398] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.330064] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.334029] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.338124] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.345696] acpiphp: Slot [2] registered [ 0.347155] acpiphp: Slot [5] registered [ 0.349193] acpiphp: Slot [6] registered [ 0.351153] acpiphp: Slot [3] registered [ 0.353133] acpiphp: Slot [4] registered [ 0.355147] acpiphp: Slot [7] registered [ 0.357108] acpiphp: Slot [8] registered [ 0.358140] acpiphp: Slot [9] registered [ 0.360166] acpiphp: Slot [10] registered [ 0.361155] acpiphp: Slot [11] registered [ 0.363105] acpiphp: Slot [12] registered [ 0.365123] acpiphp: Slot [13] registered [ 0.367161] acpiphp: Slot [14] registered [ 0.369096] acpiphp: Slot [15] registered [ 0.370145] acpiphp: Slot [16] registered [ 0.372111] acpiphp: Slot [17] registered [ 0.373104] acpiphp: Slot [18] registered [ 0.375171] acpiphp: Slot [19] registered [ 0.377329] acpiphp: Slot [20] registered [ 0.380180] acpiphp: Slot [21] registered [ 0.382152] acpiphp: Slot [22] registered [ 0.384144] acpiphp: Slot [23] registered [ 0.386112] acpiphp: Slot [24] registered [ 0.389138] acpiphp: Slot [25] registered [ 0.391135] acpiphp: Slot [26] registered [ 0.393183] acpiphp: Slot [27] registered [ 0.395230] acpiphp: Slot [28] registered [ 0.397158] acpiphp: Slot [29] registered [ 0.398159] acpiphp: Slot [30] registered [ 0.400141] acpiphp: Slot [31] registered [ 0.402084] PCI host bridge to bus 0000:00 [ 0.404026] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.407033] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.411037] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.414035] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.417032] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.421048] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.424304] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.429257] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.434240] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.442974] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.448065] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.450020] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.452015] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.455026] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.458608] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.461989] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.464046] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.467984] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.473013] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.482013] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.488014] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.494417] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.503014] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.512019] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.532015] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.545691] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.552020] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.556929] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.570018] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.581187] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.587521] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.590374] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.593467] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.595312] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.600293] iommu: Default domain type: Passthrough [ 0.601491] SCSI subsystem initialized [ 0.602304] ACPI: bus type USB registered [ 0.604248] usbcore: registered new interface driver usbfs [ 0.607097] usbcore: registered new interface driver hub [ 0.609133] usbcore: registered new device driver usb [ 0.611228] pps_core: LinuxPPS API ver. 1 registered [ 0.613012] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.617074] PTP clock support registered [ 0.619289] EDAC MC: Ver: 3.0.0 [ 0.622283] PCI: Using ACPI for IRQ routing [ 0.625138] NetLabel: Initializing [ 0.627015] NetLabel: domain hash size = 128 [ 0.629018] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.631110] NetLabel: unlabeled traffic allowed by default [ 0.635195] vgaarb: loaded [ 0.637316] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.638019] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.643000] clocksource: Switched to clocksource kvm-clock [ 0.748213] VFS: Disk quotas dquot_6.6.0 [ 0.749995] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.753233] *** VALIDATE ramfs *** [ 0.754708] *** VALIDATE hugetlbfs *** [ 0.756124] pnp: PnP ACPI init [ 0.758050] pnp: PnP ACPI: found 6 devices [ 0.777022] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.780209] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.781866] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.784015] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.785995] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.787733] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.790502] NET: Registered protocol family 2 [ 0.792947] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.797276] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.800723] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.805617] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.808754] TCP: Hash tables configured (established 65536 bind 65536) [ 0.811797] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.814240] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.816336] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.819274] NET: Registered protocol family 1 [ 0.821689] RPC: Registered named UNIX socket transport module. [ 0.823749] RPC: Registered udp transport module. [ 0.824902] RPC: Registered tcp transport module. [ 0.826517] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.828407] NET: Registered protocol family 44 [ 0.829877] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.831487] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.833178] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.835499] PCI: CLS 0 bytes, default 64 [ 0.837462] Unpacking initramfs... [ 2.266265] debug: unmapping init [mem 0xffff96933cc64000-0xffff96933ffcffff] [ 2.271498] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.273370] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.275614] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.801173] Initialise system trusted keyrings [ 2.802855] Key type blacklist registered [ 2.805753] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.820190] zbud: loaded [ 2.825365] *** VALIDATE nfs *** [ 2.826948] *** VALIDATE nfs4 *** [ 2.829199] pstore: using deflate compression [ 2.833478] Platform Keyring initialized [ 2.955537] NET: Registered protocol family 38 [ 2.957076] Key type asymmetric registered [ 2.958519] Asymmetric key parser 'x509' registered [ 2.960348] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.963108] io scheduler mq-deadline registered [ 2.964967] io scheduler kyber registered [ 2.966768] io scheduler bfq registered [ 2.968694] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.971889] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.975453] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.979015] ACPI: Power Button [PWRF] [ 2.984205] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.990938] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.006777] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.036390] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.064534] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.072248] Non-volatile memory driver v1.3 [ 3.074118] Linux agpgart interface v0.103 [ 3.110528] virtio_blk virtio1: [vda] 145224 512-byte logical blocks (74.4 MB/70.9 MiB) [ 3.114113] vda: detected capacity change from 0 to 74354688 [ 3.128971] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.132360] vdb: detected capacity change from 0 to 1073741824 [ 3.137917] libphy: Fixed MDIO Bus: probed [ 3.146232] usbcore: registered new interface driver usbserial_generic [ 3.149152] usbserial: USB Serial support registered for generic [ 3.151721] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.156847] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.158903] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.161235] mousedev: PS/2 mouse device common for all mice [ 3.164288] rtc_cmos 00:05: RTC can wake from S4 [ 3.168118] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.170309] rtc_cmos 00:05: registered as rtc0 [ 3.173885] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.177186] intel_pstate: CPU model not supported [ 3.179964] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.183983] hid: raw HID events driver (C) Jiri Kosina [ 3.186826] usbcore: registered new interface driver usbhid [ 3.188342] usbhid: USB HID core driver [ 3.189077] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.189628] drop_monitor: Initializing network drop monitor service [ 3.195297] Initializing XFRM netlink socket [ 3.197370] NET: Registered protocol family 10 [ 3.200424] Segment Routing with IPv6 [ 3.202095] NET: Registered protocol family 17 [ 3.204086] mpls_gso: MPLS GSO support [ 3.209587] RAS: Correctable Errors collector initialized. [ 3.213924] AVX version of gcm_enc/dec engaged. [ 3.216192] AES CTR mode by8 optimization enabled [ 3.291974] sched_clock: Marking stable (3291946609, 0)->(4241021812, -949075203) [ 3.295978] registered taskstats version 1 [ 3.298180] Loading compiled-in X.509 certificates [ 3.300342] zswap: loaded using pool lzo/zbud [ 3.326646] Key type big_key registered [ 3.339884] Key type encrypted registered [ 3.341763] ima: No TPM chip found, activating TPM-bypass! [ 3.344125] ima: Allocated hash algorithm: sha1 [ 3.346081] ima: No architecture policies found [ 3.348094] evm: Initialising EVM extended attributes: [ 3.350276] evm: security.selinux [ 3.351472] evm: security.ima [ 3.352609] evm: security.capability [ 3.354075] evm: HMAC attrs: 0x1 [ 3.356537] rtc_cmos 00:05: setting system clock to 2026-06-23 18:47:05 UTC (1782240425) [ 3.363300] debug: unmapping init [mem 0xffffffff95603000-0xffffffff957fffff] [ 3.367151] debug: unmapping init [mem 0xffffffff94382000-0xffffffff94658fff] [ 3.378197] Write protecting the kernel read-only data: 28672k [ 3.382307] debug: unmapping init [mem 0xffffffff92a03000-0xffffffff92bfffff] [ 3.385719] debug: unmapping init [mem 0xffffffff93314000-0xffffffff933fffff] [ 3.428188] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 3.437880] systemd[1]: Detected virtualization kvm. [ 3.440106] systemd[1]: Detected architecture x86-64. [ 3.442272] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.469288] systemd[1]: No hostname configured. [ 3.471072] systemd[1]: Set hostname to . [ 3.473050] random: systemd: uninitialized urandom read (16 bytes read) [ 3.475552] systemd[1]: Initializing machine ID from random generator. [ 3.524764] random: ln: uninitialized urandom read (6 bytes read) [ 3.611915] random: systemd: uninitialized urandom read (16 bytes read) [ 3.614068] systemd[1]: Reached target Timers. [ OK ] Reached target Timers. [ 3.617773] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ 3.620706] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on Journal Socket. [ OK ] Started Memstrack Anylazing Service. Starting Apply Kernel Variables... Starting Create list of required st…ce nodes for the current kernel... Starting Setup Virtual Console... [ OK ] Listening on Journal Socket (/dev/log). Starting Journal Service... [ OK ] Reached target Initrd Root Device. [ OK ] Reached target Paths. [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... [ OK ] Listening on udev Control Socket. [ OK ] Reached target Sockets. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Setup Virtual Console. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.304503] device-mapper: uevent: version 1.0.3 [ 4.307484] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting dracut initqueue hook... [ 5.123572] random: fast init done [ 5.174570] virtio_net virtio0 ens2: renamed from eth0 [ 5.198274] scsi host0: ata_piix [ 5.230698] scsi host1: ata_piix [ 5.232553] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.236511] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 8.980261] dracut-initqueue[583]: RTNETLINK answers: File exists [ 9.859285] random: crng init done [ 9.863531] random: 7 urandom warning(s) missed due to ratelimiting 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. [ 10.389988] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Timers. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Paths. [ OK ] Stopped target Slices. [ OK ] Stopped target Sockets. [ OK ] Stopped target System Initialization. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped target Swap. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 11.514564] printk: systemd: 26 output lines suppressed due to ratelimiting [ 11.772936] SELinux: Disabled at runtime. [ 11.835328] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 11.845279] systemd[1]: Detected virtualization kvm. [ 11.847459] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 12.343489] systemd[1]: initrd-switch-root.service: Succeeded. [ 12.347141] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 12.353378] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 12.358035] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 12.361697] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 12.368211] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.374541] systemd[1]: Listening on initctl Compatibility Named Pipe. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Listening on Process Core Dump Socket. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Created slice system-sshd\x2dkeygen.slice. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. Starting Apply Kernel Variables... [ OK ] Listening on udev Control Socket. [ OK ] Reached target rpc_pipefs.target. Starting Remount Root and Kernel File Systems... [ OK ] Reached target RPC Port Mapper. Mounting Huge Pages File System... Mounting Kernel Debug File System... [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd Root File System. [ OK ] Stopped target Initrd File Systems. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Listening on udev Kernel Socket. Starting udev Coldplug all Devices... [ OK ] Created slice system-getty.slice. Activating swap /dev/disk/by-label/SWAP... [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. Starting Create list of required st…ce nodes for the current kernel... Mounting POSIX Message Queue File System... [ 12.541054] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Started Journal Service. [ OK ] Started Apply Kernel Variables. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted Huge Pages File System. [ OK ] Mounted Kernel Debug File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Reached target Swap. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... [ OK ] Started udev Coldplug all Devices. [ OK ] Mounted /mnt. [ 12.812173] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 13.070728] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 13.088933] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 13.161208] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 13.177043] EDAC sbridge: Ver: 1.1.2 [ 14.160525] Key type dns_resolver registered [ 14.451974] NFS: Registering the id_resolver key type [ 14.453759] Key type id_resolver registered [ 14.455137] Key type id_legacy registered [ OK ] Started Configure read-only root support. [ 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... Starting Load/Save Random Seed... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Load/Save Random Seed. [ OK ] Started Create Volatile Files and Directories. Starting Update UTMP about System Boot/Shutdown... Starting RPC Bind... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started irqbalance daemon. Starting Login Service... [ OK ] Started D-Bus System Message Bus. Starting Restore /run/initramfs on shutdown... [ OK ] Reached target sshd-keygen.target. Starting Network Manager... [ OK ] Started Login Service. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting OpenSSH server daemon... Starting Dynamic System Tuning Daemon... Starting GSSAPI Proxy Daemon... Starting Network Manager Wait Online... [ OK ] Started 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 Permit User Sessions. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Getty on tty1. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting System Logging Service... Starting Crash recovery kernel arming... Starting Notify NFS peers of a restart... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started Authorization Manager. [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg345-client login: [ 51.708473] libcfs: loading out-of-tree module taints kernel. [ 51.825634] Key type ._llcrypt registered [ 51.827822] Key type .llcrypt registered [ 52.489828] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 52.501926] alg: No test for adler32 (adler32-zlib) [ 54.087552] Lustre: Lustre: Build Version: 2.17.54_83_g947bf2b [ 54.950537] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 56.703562] Key type lgssc registered [ 58.396694] Lustre: Echo OBD driver; http://www.lustre.org/ [ 209.760779] Lustre: Mounted lustre-client [ 214.352754] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 227.549122] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing check_logdir /tmp/testlogs/ [ 232.553589] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing yml_node [ 235.495930] Lustre: lustre-OST0000-osc-ffff9693885cd000: disconnect after 23s idle [ 236.882300] Lustre: DEBUG MARKER: Client: 2.17.54.83 [ 239.408493] Lustre: DEBUG MARKER: MDS: 2.17.54.83 [ 241.563036] Lustre: DEBUG MARKER: OSS: 2.17.54.83 [ 243.016330] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Tue Jun 23 14:51:04 EDT 2026 [ 258.738602] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 260.026210] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 263.666626] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 274.000305] Lustre: Unmounted lustre-client [ 374.191442] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 379.482974] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 400.535943] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 434.781407] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 14:54:15 (1782240855) [ 436.730780] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 438.205716] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 14:54:19 (1782240859) [ 467.424592] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 472.429073] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 491.525198] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 492.191540] Lustre: Mounted lustre-client [ 497.069172] Lustre: Unmounted lustre-client [ 517.977970] Key type lgssc unregistered [ 518.210974] LNet: 14986:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 518.217996] LNetError: 14986:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 518.238259] LNet: Removed LNI 192.168.203.45@tcp [ 518.973168] Key type .llcrypt unregistered [ 518.976434] Key type ._llcrypt unregistered [ 533.750721] Key type ._llcrypt registered [ 533.759293] Key type .llcrypt registered [ 534.879852] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 14:55:55 (1782240955) [ 536.279203] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 536.306210] alg: No test for adler32 (adler32-zlib) [ 537.402567] Lustre: Lustre: Build Version: 2.17.54_83_g947bf2b [ 537.706887] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 539.423165] Key type lgssc registered [ 540.600305] Lustre: Echo OBD driver; http://www.lustre.org/ [ 543.738237] hrtimer: interrupt took 2146438 ns [ 613.452905] Lustre: Mounted lustre-client [ 699.645150] Lustre: Unmounted lustre-client [ 737.426872] Key type lgssc unregistered [ 737.744794] LNet: 18789:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 737.756816] LNetError: 18789:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 737.781744] LNet: Removed LNI 192.168.203.45@tcp [ 738.655889] Key type .llcrypt unregistered [ 738.657546] Key type ._llcrypt unregistered [ 749.927310] Key type ._llcrypt registered [ 749.929960] Key type .llcrypt registered [ 751.157632] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 14:59:31 (1782241171) [ 753.219563] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 753.261023] alg: No test for adler32 (adler32-zlib) [ 754.336853] Lustre: Lustre: Build Version: 2.17.54_83_g947bf2b [ 754.638608] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 756.343225] Key type lgssc registered [ 757.331124] Lustre: Echo OBD driver; http://www.lustre.org/ [ 805.386257] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 811.640153] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 832.182875] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 834.829146] Lustre: Mounted lustre-client [ 848.365621] Lustre: Unmounted lustre-client [ 875.175425] Key type lgssc unregistered [ 875.408831] LNet: 22375:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 875.420746] LNetError: 22375:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 875.439506] LNet: Removed LNI 192.168.203.45@tcp [ 876.064355] Key type .llcrypt unregistered [ 876.067958] Key type ._llcrypt unregistered [ 885.964501] Key type ._llcrypt registered [ 885.967427] Key type .llcrypt registered [ 887.286236] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 15:01:47 (1782241307) [ 889.776636] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 889.800792] alg: No test for adler32 (adler32-zlib) [ 890.859813] Lustre: Lustre: Build Version: 2.17.54_83_g947bf2b [ 891.090684] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 892.791173] Key type lgssc registered [ 893.813860] Lustre: Echo OBD driver; http://www.lustre.org/ [ 943.581826] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 948.862749] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 969.460153] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 971.585039] Lustre: Mounted lustre-client [ 977.828673] Lustre: Unmounted lustre-client [ 1005.474444] Key type lgssc unregistered [ 1005.768585] LNet: 25539:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1005.784697] LNetError: 25539:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1005.818664] LNet: Removed LNI 192.168.203.45@tcp [ 1006.408320] Key type .llcrypt unregistered [ 1006.411942] Key type ._llcrypt unregistered [ 1021.437600] Key type ._llcrypt registered [ 1021.441380] Key type .llcrypt registered [ 1022.780465] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 15:04:03 (1782241443) [ 1025.079545] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1025.199401] alg: No test for adler32 (adler32-zlib) [ 1026.380744] Lustre: Lustre: Build Version: 2.17.54_83_g947bf2b [ 1026.630625] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 1028.359192] Key type lgssc registered [ 1029.613543] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1079.347243] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1084.851426] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1106.556882] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1108.444233] Lustre: Mounted lustre-client [ 1113.573619] Lustre: lustre-MDT0000-mdc-ffff969387fab800: Connection to lustre-MDT0000 (at 192.168.203.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1118.701215] Lustre: lustre-MDT0001-mdc-ffff969387fab800: Connection to lustre-MDT0001 (at 192.168.203.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1125.698053] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1134.047205] Lustre: 26498:0:(client.c:2489:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1782241540/real 1782241540] req@00000000d54c3b33 x1868815608583552/t0(0) o400->MGC192.168.203.145@tcp@192.168.203.145@tcp:26/25 lens 224/224 e 0 to 1 dl 1782241556 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1134.095524] LustreError: MGC192.168.203.145@tcp: Connection to MGS (at 192.168.203.145@tcp) was lost; in progress operations using this service will fail [ 1136.035188] Lustre: Unmounted lustre-client [ 1155.260376] Key type lgssc unregistered [ 1155.514722] LNet: 29171:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1155.528979] LNetError: 29171:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1155.551932] LNet: Removed LNI 192.168.203.45@tcp [ 1156.625671] Key type .llcrypt unregistered [ 1156.630401] Key type ._llcrypt unregistered [ 1172.941745] Key type ._llcrypt registered [ 1172.943780] Key type .llcrypt registered [ 1174.264809] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 15:06:34 (1782241594) [ 1176.334852] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1176.351019] alg: No test for adler32 (adler32-zlib) [ 1177.360880] Lustre: Lustre: Build Version: 2.17.54_83_g947bf2b [ 1177.564856] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 1179.328131] Key type lgssc registered [ 1180.591822] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1225.997708] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1230.962406] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1249.749259] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1252.419204] Lustre: Mounted lustre-client [ 1257.453811] Lustre: lustre-OST0000-osc-ffff969387f9c800: Connection to lustre-OST0000 (at 192.168.203.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1264.111868] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1274.470991] Lustre: Unmounted lustre-client [ 1300.578514] Key type lgssc unregistered [ 1300.796425] LNet: 32763:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1300.801665] LNetError: 32763:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1300.817235] LNet: Removed LNI 192.168.203.45@tcp [ 1301.481278] Key type .llcrypt unregistered [ 1301.485762] Key type ._llcrypt unregistered [ 1319.435276] Key type ._llcrypt registered [ 1319.442825] Key type .llcrypt registered [ 1320.707208] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 15:09:01 (1782241741) [ 1323.219967] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1323.326605] alg: No test for adler32 (adler32-zlib) [ 1324.431593] Lustre: Lustre: Build Version: 2.17.54_83_g947bf2b [ 1324.670645] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 1326.407534] Key type lgssc registered [ 1327.843975] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1373.238670] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1378.237159] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1399.106759] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1401.874525] Lustre: Mounted lustre-client [ 1405.433517] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1408.988674] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 1420.556703] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2026-06-2315h10m41s [ 1422.279245] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 1427.429604] Lustre: lustre-MDT0000-mdc-ffff96938a799000: Connection to lustre-MDT0000 (at 192.168.203.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1440.148026] LustreError: 36530:0:(lmv_obd.c:1466:lmv_statfs()) lustre-MDT0000-mdc-ffff96938a799000: can't stat MDS #0: rc = -19 [ 1441.490733] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 1450.988222] LustreError: MGC192.168.203.145@tcp: Connection to MGS (at 192.168.203.145@tcp) was lost; in progress operations using this service will fail [ 1451.018914] Lustre: Evicted from MGS (at 192.168.203.145@tcp) after server handle changed from 0xc3ee28826ff5d5db to 0xc3ee28826ff5d89e [ 1451.028667] Lustre: MGC192.168.203.145@tcp: Connection restored to 192.168.203.145@tcp (at 192.168.203.145@tcp) [ 1452.075154] LustreError: lustre-MDT0000-mdc-ffff96938a799000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 1452.088781] Lustre: lustre-MDT0000-mdc-ffff96938a799000: Connection restored to 192.168.203.145@tcp (at 192.168.203.145@tcp) [ 1455.679626] Lustre: DEBUG MARKER: Wait for df (36530) ... [ 1457.332285] Lustre: DEBUG MARKER: done [ 1459.810406] Lustre: Unmounted lustre-client [ 1485.353716] Key type lgssc unregistered [ 1485.630206] LNet: 37545:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1485.643387] LNetError: 37545:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1485.680096] LNet: Removed LNI 192.168.203.45@tcp [ 1486.304489] Key type .llcrypt unregistered [ 1486.307257] Key type ._llcrypt unregistered [ 1513.594783] Key type ._llcrypt registered [ 1513.597238] Key type .llcrypt registered [ 1514.683712] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 15:12:15 (1782241935) [ 1517.128847] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1517.155234] alg: No test for adler32 (adler32-zlib) [ 1518.245467] Lustre: Lustre: Build Version: 2.17.54_83_g947bf2b [ 1518.454523] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 1520.111629] Key type lgssc registered [ 1521.017961] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1563.184312] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1568.176341] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1586.632737] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1589.244335] Lustre: Mounted lustre-client [ 1593.101330] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2026-06-2315h13m34s [ 1594.610320] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1600.542490] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 1602.760842] Lustre: DEBUG MARKER: Creating a test file and stat it [ 1623.007184] Lustre: 41660:0:(client.c:2489:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1782242024/real 1782242024] req@0000000024bd9c31 x1868816124483712/t0(0) o101->lustre-MDT0000-mdc-ffff969387fad000@192.168.203.145@tcp:12/10 lens 576/1152 e 0 to 1 dl 1782242044 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:1 [ 1623.048165] Lustre: lustre-MDT0000-mdc-ffff969387fad000: Connection to lustre-MDT0000 (at 192.168.203.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1623.089595] Lustre: lustre-MDT0000-mdc-ffff969387fad000: Connection restored to 192.168.203.145@tcp (at 192.168.203.145@tcp) [ 1644.517036] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 1646.988660] Lustre: DEBUG MARKER: done [ 1650.648321] Lustre: Unmounted lustre-client [ 1675.910548] Key type lgssc unregistered [ 1676.150408] LNet: 42545:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1676.154420] LNetError: 42545:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1676.178084] LNet: Removed LNI 192.168.203.45@tcp [ 1676.710638] Key type .llcrypt unregistered [ 1676.715824] Key type ._llcrypt unregistered [ 1703.655288] Key type ._llcrypt registered [ 1703.656596] Key type .llcrypt registered [ 1704.753756] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 15:15:25 (1782242125) [ 1707.289735] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 1709.754816] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 15:15:29 (1782242129) [ 1714.387843] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1714.583704] alg: No test for adler32 (adler32-zlib) [ 1715.585981] Lustre: Lustre: Build Version: 2.17.54_83_g947bf2b [ 1715.785631] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 1717.442094] Key type lgssc registered [ 1718.971960] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1745.206195] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 15:16:06 (1782242166) [ 1775.637276] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1780.877175] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1798.747897] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1801.303942] Lustre: Mounted lustre-client [ 1804.811665] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 1808.432812] Lustre: Unmounted lustre-client [ 1818.880414] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 1865.800404] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1871.593755] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1872.279837] Lustre: Mounted lustre-client [ 1876.274079] Lustre: Unmounted lustre-client [ 1937.505681] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1942.582291] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1943.090658] Lustre: Mounted lustre-client [ 1946.528856] Lustre: Unmounted lustre-client [ 1964.624497] Lustre: DEBUG MARKER: files compared the same [ 1985.950720] Key type lgssc unregistered [ 1986.251669] LNet: 50103:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1986.263481] LNetError: 50103:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1986.291437] LNet: Removed LNI 192.168.203.45@tcp [ 1987.074651] Key type .llcrypt unregistered [ 1987.080784] Key type ._llcrypt unregistered [ 2003.849426] Key type ._llcrypt registered [ 2003.857390] Key type .llcrypt registered [ 2005.237952] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 15:20:25 (1782242425) [ 2007.835427] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2007.861459] alg: No test for adler32 (adler32-zlib) [ 2008.928827] Lustre: Lustre: Build Version: 2.17.54_83_g947bf2b [ 2009.148649] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 2010.793967] Key type lgssc registered [ 2012.060923] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2059.195442] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2064.434245] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2083.367416] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2084.985720] Lustre: Mounted lustre-client [ 2088.998508] Lustre: Unmounted lustre-client [ 2114.559683] Key type lgssc unregistered [ 2114.754818] LNet: 53657:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2114.764391] LNetError: 53657:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2114.777125] LNet: Removed LNI 192.168.203.45@tcp [ 2115.421397] Key type .llcrypt unregistered [ 2115.423954] Key type ._llcrypt unregistered [ 2129.244625] Key type ._llcrypt registered [ 2129.246472] Key type .llcrypt registered [ 2130.405768] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 15:22:31 (1782242551) [ 2132.418147] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2132.443424] alg: No test for adler32 (adler32-zlib) [ 2133.492941] Lustre: Lustre: Build Version: 2.17.54_83_g947bf2b [ 2133.725496] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 2135.407279] Key type lgssc registered [ 2136.566257] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2217.048479] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2249.448381] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2254.260211] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2280.136737] Key type lgssc unregistered [ 2280.486701] LNet: 57119:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2280.497421] LNetError: 57119:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2280.514796] LNet: Removed LNI 192.168.203.45@tcp [ 2281.243854] Key type .llcrypt unregistered [ 2281.246694] Key type ._llcrypt unregistered [ 2297.008905] Key type ._llcrypt registered [ 2297.010512] Key type .llcrypt registered [ 2298.214638] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 15:25:18 (1782242718) [ 2302.052041] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2302.098682] alg: No test for adler32 (adler32-zlib) [ 2303.185669] Lustre: Lustre: Build Version: 2.17.54_83_g947bf2b [ 2303.423379] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 2305.127190] Key type lgssc registered [ 2306.272963] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2375.034557] Lustre: Mounted lustre-client [ 2382.258186] Lustre: Unmounted lustre-client [ 2418.120596] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 15:27:19 (1782242839) [ 2523.728448] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2545.206801] Lustre: Mounted lustre-client [ 2553.262568] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2553.269225] LustreError: 63588:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff969387089000: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 2553.290712] Lustre: 58104:0:(llite_lib.c:4202:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.203.145@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 2563.621316] Lustre: Unmounted lustre-client [ 2610.400982] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 15:30:31 (1782243031) [ 2626.183274] Key type lgssc unregistered [ 2626.626704] LNet: 64804:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2626.638360] LNetError: 64804:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2626.665657] LNet: Removed LNI 192.168.203.45@tcp [ 2627.526961] Key type .llcrypt unregistered [ 2627.529501] Key type ._llcrypt unregistered [ 2641.788334] Key type ._llcrypt registered [ 2641.795299] Key type .llcrypt registered [ 2642.246439] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2642.271237] alg: No test for adler32 (adler32-zlib) [ 2643.513903] Lustre: Lustre: Build Version: 2.17.54_83_g947bf2b [ 2643.770520] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 2645.527245] Key type lgssc registered [ 2646.987749] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2697.186782] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2702.113547] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2724.372333] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2726.368585] Lustre: Mounted lustre-client [ 2730.200909] Lustre: Unmounted lustre-client [ 2772.752812] Key type lgssc unregistered [ 2773.041766] LNet: 67799:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2773.052028] LNetError: 67799:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2773.085331] LNet: Removed LNI 192.168.203.45@tcp [ 2773.802571] Key type .llcrypt unregistered [ 2773.812965] Key type ._llcrypt unregistered [ 2783.494078] Key type ._llcrypt registered [ 2783.500461] Key type .llcrypt registered [ 2783.899205] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2783.914847] alg: No test for adler32 (adler32-zlib) [ 2784.991964] Lustre: Lustre: Build Version: 2.17.54_83_g947bf2b [ 2785.273772] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 2786.959177] Key type lgssc registered [ 2788.313547] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2886.867559] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2892.824761] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2914.073805] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2915.663739] Lustre: Mounted lustre-client [ 2918.379590] Lustre: Unmounted lustre-client [ 2944.555515] Key type lgssc unregistered [ 2944.797881] LNet: 71207:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2944.808227] LNetError: 71207:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2944.835975] LNet: Removed LNI 192.168.203.45@tcp [ 2945.553764] Key type .llcrypt unregistered [ 2945.559204] Key type ._llcrypt unregistered [ 2960.904673] Key type ._llcrypt registered [ 2960.909782] Key type .llcrypt registered [ 2962.222829] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 15:36:22 (1782243382) [ 2964.577308] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2964.602693] alg: No test for adler32 (adler32-zlib) [ 2965.658656] Lustre: Lustre: Build Version: 2.17.54_83_g947bf2b [ 2965.931710] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 2967.639364] Key type lgssc registered [ 2968.830501] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3017.014612] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3022.729162] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3041.484593] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3044.200849] Lustre: Mounted lustre-client [ 3049.307560] Lustre: Unmounted lustre-client [ 3049.772327] Lustre: Mounted lustre-client [ 3052.494959] Lustre: Unmounted lustre-client [ 3079.301492] Key type lgssc unregistered [ 3079.629722] LNet: 74874:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3079.646802] LNetError: 74874:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3079.661780] LNet: Removed LNI 192.168.203.45@tcp [ 3080.509111] Key type .llcrypt unregistered [ 3080.516123] Key type ._llcrypt unregistered [ 3096.251661] Key type ._llcrypt registered [ 3096.254559] Key type .llcrypt registered [ 3096.552489] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3096.562648] alg: No test for adler32 (adler32-zlib) [ 3097.687522] Lustre: Lustre: Build Version: 2.17.54_83_g947bf2b [ 3097.877732] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 3099.551166] Key type lgssc registered [ 3101.014115] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3153.380567] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3159.058399] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3180.287665] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3182.101044] Lustre: Mounted lustre-client [ 3189.602333] Lustre: Unmounted lustre-client [ 3216.835121] Key type lgssc unregistered [ 3217.114456] LNet: 77886:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3217.118205] LNetError: 77886:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3217.141466] LNet: Removed LNI 192.168.203.45@tcp [ 3217.923436] Key type .llcrypt unregistered [ 3217.927149] Key type ._llcrypt unregistered [ 3232.386320] Key type ._llcrypt registered [ 3232.388860] Key type .llcrypt registered [ 3233.719966] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 15:40:54 (1782243654) [ 3235.866369] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3235.898099] alg: No test for adler32 (adler32-zlib) [ 3237.042097] Lustre: Lustre: Build Version: 2.17.54_83_g947bf2b [ 3237.352379] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 3239.103170] Key type lgssc registered [ 3240.169196] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3287.673804] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3293.755777] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3316.630823] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3318.545437] Lustre: Mounted lustre-client [ 3335.213959] Lustre: lustre: root_squash is set to 0:0 [ 3335.235819] Lustre: lustre: nosquash_nids is cleared [ 3344.435980] Lustre: lustre: root_squash is set to 500:500 [ 3354.166411] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.203.45@tcp 192.168.0.[2,10]@tcp [ 3362.733674] Lustre: lustre: nosquash_nids set to 192.168.203.45/24@tcp [ 3370.476634] Lustre: Unmounted lustre-client [ 3399.859822] Key type lgssc unregistered [ 3400.200932] LNet: 81826:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3400.217744] LNetError: 81826:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3400.241597] LNet: Removed LNI 192.168.203.45@tcp [ 3401.024246] Key type .llcrypt unregistered [ 3401.027165] Key type ._llcrypt unregistered [ 3418.857288] Key type ._llcrypt registered [ 3418.863343] Key type .llcrypt registered [ 3420.147406] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 15:44:00 (1782243840) [ 3422.712473] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 3424.613963] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 15:44:05 (1782243845) [ 3427.530257] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3427.548077] alg: No test for adler32 (adler32-zlib) [ 3428.618703] Lustre: Lustre: Build Version: 2.17.54_83_g947bf2b [ 3428.936207] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 3430.631198] Key type lgssc registered [ 3431.903523] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3478.154582] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3483.334908] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3505.772327] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3507.236959] Lustre: lustre: root_squash is set to 500:500 [ 3507.250511] Lustre: lustre: nosquash_nids set to 192.168.203.45/24@tcp [ 3508.347595] Lustre: Mounted lustre-client [ 3513.669311] Lustre: Unmounted lustre-client [ 3543.016718] Key type lgssc unregistered [ 3543.340476] LNet: 85759:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3543.347159] LNetError: 85759:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3543.367346] LNet: Removed LNI 192.168.203.45@tcp [ 3544.172165] Key type .llcrypt unregistered [ 3544.176335] Key type ._llcrypt unregistered [ 3559.168863] Key type ._llcrypt registered [ 3559.176522] Key type .llcrypt registered [ 3560.317969] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 3561.975976] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 15:46:22 (1782243982) [ 3578.357837] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3578.442062] alg: No test for adler32 (adler32-zlib) [ 3579.506416] Lustre: Lustre: Build Version: 2.17.54_83_g947bf2b [ 3579.750804] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 3581.457539] Key type lgssc registered [ 3582.712574] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3654.176567] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3658.940348] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3679.815871] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3748.708908] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3754.219918] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3774.486411] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3787.205651] Lustre: Mounted lustre-client [ 3824.632326] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff969383821000.ost_server_uuid 50 [ 3826.510834] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff969383821000.ost_server_uuid in IDLE state after 0 sec [ 3826.833960] Lustre: Mounted lustre-client [ 3830.603399] Lustre: Unmounted lustre-client [ 3832.563578] Lustre: Unmounted lustre-client [ 3894.204497] Key type lgssc unregistered [ 3894.487713] LNet: 93382:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3894.508255] LNetError: 93382:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3894.530527] LNet: Removed LNI 192.168.203.45@tcp [ 3895.379894] Key type .llcrypt unregistered [ 3895.384442] Key type ._llcrypt unregistered [ 3925.706697] Key type ._llcrypt registered [ 3925.710317] Key type .llcrypt registered [ 3927.462365] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 15:52:27 (1782244347) [ 3944.923755] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3945.010156] alg: No test for adler32 (adler32-zlib) [ 3946.184174] Lustre: Lustre: Build Version: 2.17.54_83_g947bf2b [ 3946.421810] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 3948.127185] Key type lgssc registered [ 3949.461771] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4028.968592] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4034.449544] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4057.344490] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4060.277342] Lustre: Mounted lustre-client [ 4081.632583] Lustre: 94744:0:(client.c:2489:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1782244487/real 1782244487] req@000000008e0630d2 x1868818670425344/t0(0) o400->lustre-OST0000-osc-ffff96938b7a2000@192.168.203.145@tcp:28/4 lens 224/224 e 0 to 1 dl 1782244503 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4081.698787] Lustre: lustre-OST0000-osc-ffff96938b7a2000: Connection to lustre-OST0000 (at 192.168.203.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4087.775176] Lustre: 94744:0:(client.c:2489:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1782244492/real 1782244492] req@0000000062b837c8 x1868818670425856/t0(0) o400->lustre-OST0000-osc-ffff96938b7a2000@192.168.203.145@tcp:28/4 lens 224/224 e 0 to 1 dl 1782244508 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4091.231115] Lustre: 94745:0:(client.c:2489:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1782244497/real 1782244497] req@000000002a0a8ab5 x1868818670426368/t0(0) o400->lustre-OST0000-osc-ffff96938b7a2000@192.168.203.145@tcp:28/4 lens 224/224 e 0 to 1 dl 1782244513 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4091.932927] Lustre: lustre-OST0000-osc-ffff96938b7a2000: Connection restored to 192.168.203.145@tcp (at 192.168.203.145@tcp) [ 4096.095328] Lustre: 94744:0:(client.c:2489:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1782244502/real 1782244502] req@00000000730dcbae x1868818670426880/t0(0) o400->lustre-OST0000-osc-ffff96938b7a2000@192.168.203.145@tcp:28/4 lens 224/224 e 0 to 1 dl 1782244518 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4097.003305] Lustre: lustre-MDT0000-mdc-ffff96938b7a2000: Connection to lustre-MDT0000 (at 192.168.203.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4113.375158] Lustre: 94745:0:(client.c:2489:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1782244519/real 1782244519] req@000000002a0a8ab5 x1868818670429184/t0(0) o400->MGC192.168.203.145@tcp@192.168.203.145@tcp:26/25 lens 224/224 e 0 to 1 dl 1782244535 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4113.415164] LustreError: MGC192.168.203.145@tcp: Connection to MGS (at 192.168.203.145@tcp) was lost; in progress operations using this service will fail [ 4113.457318] Lustre: Evicted from MGS (at 192.168.203.145@tcp) after server handle changed from 0x870ab684f1fc8f71 to 0x870ab684f1fc9203 [ 4113.481254] Lustre: MGC192.168.203.145@tcp: Connection restored to 192.168.203.145@tcp (at 192.168.203.145@tcp) [ 4118.075126] Lustre: lustre-MDT0000-mdc-ffff96938b7a2000: Connection restored to 192.168.203.145@tcp (at 192.168.203.145@tcp) [ 4122.637138] Lustre: Unmounted lustre-client [ 4148.892585] Key type lgssc unregistered [ 4149.238348] LNet: 99018:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4149.247758] LNetError: 99018:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4149.274599] LNet: Removed LNI 192.168.203.45@tcp [ 4150.051811] Key type .llcrypt unregistered [ 4150.060353] Key type ._llcrypt unregistered [ 4166.110367] Key type ._llcrypt registered [ 4166.112932] Key type .llcrypt registered [ 4167.668582] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 15:56:28 (1782244588) [ 4182.286913] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4182.354518] alg: No test for adler32 (adler32-zlib) [ 4183.537810] Lustre: Lustre: Build Version: 2.17.54_83_g947bf2b [ 4183.764729] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 4185.455171] Key type lgssc registered [ 4186.701556] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4224.950727] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4322.235668] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4327.888249] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4352.335074] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4355.192709] Lustre: Mounted lustre-client [ 4357.638740] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 4359.610702] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 15:59:40 (1782244780) [ 4576.511942] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4586.861256] Lustre: Unmounted lustre-client [ 4708.212959] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4713.365991] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4736.095607] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4774.326408] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 16:06:35 (1782245195) [ 4874.178396] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4879.747387] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4901.916984] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4902.474445] Lustre: Mounted lustre-client [ 4906.818501] Lustre: Unmounted lustre-client [ 4944.967614] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 16:09:25 (1782245365) [ 5034.497480] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5040.445082] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5059.419776] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5059.837579] Lustre: Mounted lustre-client [ 5063.396886] Lustre: Unmounted lustre-client [ 5089.252601] Key type lgssc unregistered [ 5089.446737] LNet: 121951:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5089.458987] LNetError: 121951:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5089.477930] LNet: Removed LNI 192.168.203.45@tcp [ 5090.089676] Key type .llcrypt unregistered [ 5090.093240] Key type ._llcrypt unregistered [ 5103.535602] Key type ._llcrypt registered [ 5103.537884] Key type .llcrypt registered [ 5104.835981] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 16:12:05 (1782245525) [ 5107.015960] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5107.050141] alg: No test for adler32 (adler32-zlib) [ 5108.163295] Lustre: Lustre: Build Version: 2.17.54_83_g947bf2b [ 5108.424653] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 5110.105974] Key type lgssc registered [ 5111.237616] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5158.158495] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5163.586835] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5183.106123] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5184.835378] Lustre: Mounted lustre-client [ 5198.575506] Lustre: Unmounted lustre-client [ 5225.524616] Key type lgssc unregistered [ 5225.726116] LNet: 125521:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5225.729243] LNetError: 125521:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5225.738373] LNet: Removed LNI 192.168.203.45@tcp [ 5226.405070] Key type .llcrypt unregistered [ 5226.409260] Key type ._llcrypt unregistered [ 5242.643522] Key type ._llcrypt registered [ 5242.645815] Key type .llcrypt registered [ 5244.183802] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 16:14:24 (1782245664) [ 5245.989888] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5246.030540] alg: No test for adler32 (adler32-zlib) [ 5247.139869] Lustre: Lustre: Build Version: 2.17.54_83_g947bf2b [ 5247.459494] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 5249.111153] Key type lgssc registered [ 5250.308650] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5305.284349] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5311.134065] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5332.118716] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5334.754574] Lustre: Mounted lustre-client [ 5340.150262] Lustre: lustre-OST0000-osc-ffff96938512d800: Connection to lustre-OST0000 (at 192.168.203.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5352.678755] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff96938512d800.ost_server_uuid 50 [ 5354.290427] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 5365.450827] Lustre: Unmounted lustre-client [ 5385.582650] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 16:16:46 (1782245806) [ 5417.603114] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5422.723183] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5442.105145] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5462.348670] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 5463.066563] Lustre: Mounted lustre-client [ 5468.135875] Lustre: lustre-OST0000-osc-ffff9693881d8000: Connection to lustre-OST0000 (at 192.168.203.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5500.180758] Lustre: Unmounted lustre-client [ 5549.354141] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 16:19:30 (1782245970) [ 5576.064550] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5582.003301] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5600.641479] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5618.992435] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 5619.644918] Lustre: Mounted lustre-client [ 5624.812595] Lustre: lustre-OST0000-osc-ffff969385e7f800: Connection to lustre-OST0000 (at 192.168.203.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5639.743658] Lustre: Unmounted lustre-client [ 5680.670340] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 16:21:41 (1782246101) [ 5762.611132] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5768.232588] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5790.427812] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5857.539576] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5863.397892] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5882.222953] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5914.999677] Lustre: Mounted lustre-client [ 5955.458646] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5962.451184] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 5 sec [ 5965.127394] Lustre: Unmounted lustre-client [ 6003.418794] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 16:27:04 (1782246424) [ 6037.101530] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6045.961674] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6070.498985] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6120.285291] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 6149.553456] Lustre: Mounted lustre-client [ 6192.622297] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 6194.405258] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6205.947783] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 6216.298681] Lustre: Unmounted lustre-client [ 6285.456180] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 16:31:46 (1782246706) [ 6316.425075] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6321.426680] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6340.003116] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6340.443573] Lustre: Mounted lustre-client [ 6365.831163] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 6369.684163] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6386.111849] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff969389689800.ost_server_uuid 50 [ 6387.472693] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff969389689800.ost_server_uuid in IDLE state after 0 sec [ 6390.670417] Lustre: Unmounted lustre-client [ 6390.895844] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 6390.913430] Lustre: Skipped 3 previous similar messages [ 6390.994639] Lustre: Mounted lustre-client [ 6391.700626] LustreError: 148780:0:(sec.c:413:import_sec_validate_get()) import 0000000045cb1098 (NEW) with no sec [ 6462.174451] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 16:34:43 (1782246883) [ 6495.745806] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6500.825586] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6518.104448] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6535.922184] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 6536.249071] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 6536.379316] Lustre: Mounted lustre-client [ 6547.190490] Lustre: Unmounted lustre-client [ 6547.194587] Lustre: Skipped 1 previous similar message [ 6583.162410] Key type lgssc unregistered [ 6583.462993] LNet: 152156:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6583.475828] LNetError: 152156:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6583.501396] LNet: Removed LNI 192.168.203.45@tcp [ 6584.016300] Key type .llcrypt unregistered [ 6584.018868] Key type ._llcrypt unregistered [ 6599.464831] Key type ._llcrypt registered [ 6599.467310] Key type .llcrypt registered [ 6600.823743] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 16:37:01 (1782247021) [ 6601.909447] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6601.921990] alg: No test for adler32 (adler32-zlib) [ 6602.987268] Lustre: Lustre: Build Version: 2.17.54_83_g947bf2b [ 6603.241852] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 6604.903285] Key type lgssc registered [ 6605.948458] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6656.697460] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6662.046839] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6685.102697] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6705.447599] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 6706.029876] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 6706.732552] Lustre: Mounted lustre-client [ 6711.603156] LustreError: lustre-MDT0001-mdc-ffff96938b776000: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 6711.635760] Lustre: lustre-MDT0001-mdc-ffff96938b776000: Connection restored to 192.168.203.145@tcp (at 192.168.203.145@tcp) [ 6719.306106] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6721.442756] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 6746.146211] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 6750.927430] Lustre: Unmounted lustre-client [ 6783.903531] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 16:40:04 (1782247204) [ 6874.870826] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6880.758544] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6903.271927] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6903.909107] Lustre: Mounted lustre-client [ 6926.356256] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 6927.812547] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6929.899467] Lustre: lustre-OST0001-osc-ffff969383825800: Connection to lustre-OST0001 (at 192.168.203.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6939.643310] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 6950.051216] Lustre: Unmounted lustre-client [ 6983.699481] Key type lgssc unregistered [ 6983.926475] LNet: 161724:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6983.934540] LNetError: 161724:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6983.949744] LNet: Removed LNI 192.168.203.45@tcp [ 6984.525099] Key type .llcrypt unregistered [ 6984.527209] Key type ._llcrypt unregistered [ 7019.076390] Key type ._llcrypt registered [ 7019.080224] Key type .llcrypt registered [ 7020.779316] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 16:44:01 (1782247441) [ 7022.841244] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7022.891509] alg: No test for adler32 (adler32-zlib) [ 7024.041321] Lustre: Lustre: Build Version: 2.17.54_83_g947bf2b [ 7024.309400] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 7025.935190] Key type lgssc registered [ 7027.245352] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7075.694425] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7081.817101] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7103.593506] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7105.281191] Lustre: Mounted lustre-client [ 7153.151252] Lustre: Unmounted lustre-client [ 7191.453733] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7191.899757] Lustre: Mounted lustre-client [ 7227.224685] Lustre: Unmounted lustre-client [ 7253.990395] Key type lgssc unregistered [ 7254.311052] LNet: 166140:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7254.321206] LNetError: 166140:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7254.350673] LNet: Removed LNI 192.168.203.45@tcp [ 7255.111410] Key type .llcrypt unregistered [ 7255.113188] Key type ._llcrypt unregistered [ 7270.739181] Key type ._llcrypt registered [ 7270.744257] Key type .llcrypt registered [ 7272.442096] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 16:48:12 (1782247692) [ 7274.624533] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7274.676967] alg: No test for adler32 (adler32-zlib) [ 7275.836467] Lustre: Lustre: Build Version: 2.17.54_83_g947bf2b [ 7276.070207] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 7277.767893] Key type lgssc registered [ 7278.947046] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7326.002634] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7331.006582] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7350.966794] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7352.675926] Lustre: Mounted lustre-client [ 7370.711573] Lustre: Unmounted lustre-client [ 7399.159344] Key type lgssc unregistered [ 7399.481448] LNet: 169862:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7399.489330] LNetError: 169862:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7399.500961] LNet: Removed LNI 192.168.203.45@tcp [ 7400.168248] Key type .llcrypt unregistered [ 7400.174202] Key type ._llcrypt unregistered [ 7411.928137] Key type ._llcrypt registered [ 7411.966340] Key type .llcrypt registered [ 7412.576889] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7412.602702] alg: No test for adler32 (adler32-zlib) [ 7413.802348] Lustre: Lustre: Build Version: 2.17.54_83_g947bf2b [ 7414.037613] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 7415.743628] Key type lgssc registered [ 7417.105454] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7471.277420] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7477.031273] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7498.638743] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7500.307824] Lustre: Mounted lustre-client [ 7511.223039] Lustre: Unmounted lustre-client [ 7545.140833] Key type lgssc unregistered [ 7545.385170] LNet: 172900:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7545.391339] LNetError: 172900:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7545.403960] LNet: Removed LNI 192.168.203.45@tcp [ 7546.042497] Key type .llcrypt unregistered [ 7546.045898] Key type ._llcrypt unregistered [ 7560.882163] Key type ._llcrypt registered [ 7560.884629] Key type .llcrypt registered [ 7562.118033] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 16:53:02 (1782247982) [ 7564.447525] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7564.461916] alg: No test for adler32 (adler32-zlib) [ 7565.515912] Lustre: Lustre: Build Version: 2.17.54_83_g947bf2b [ 7565.717201] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 7567.383348] Key type lgssc registered [ 7568.598357] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7615.934677] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7621.623408] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7642.063200] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7644.666213] Lustre: Mounted lustre-client [ 7663.293204] Lustre: Unmounted lustre-client [ 7689.291993] Key type lgssc unregistered [ 7689.510977] LNet: 176624:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7689.519418] LNetError: 176624:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7689.533184] LNet: Removed LNI 192.168.203.45@tcp [ 7690.092148] Key type .llcrypt unregistered [ 7690.099050] Key type ._llcrypt unregistered [ 7701.856780] Key type ._llcrypt registered [ 7701.858882] Key type .llcrypt registered [ 7702.494454] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7702.533217] alg: No test for adler32 (adler32-zlib) [ 7703.637592] Lustre: Lustre: Build Version: 2.17.54_83_g947bf2b [ 7703.905096] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 7705.583370] Key type lgssc registered [ 7706.925627] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7758.552365] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7765.258915] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7788.636303] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7790.290506] Lustre: Mounted lustre-client [ 7798.092913] Lustre: Unmounted lustre-client [ 7834.076806] Key type lgssc unregistered [ 7834.354592] LNet: 179662:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7834.361592] LNetError: 179662:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7834.401942] LNet: Removed LNI 192.168.203.45@tcp [ 7835.074729] Key type .llcrypt unregistered [ 7835.077223] Key type ._llcrypt unregistered [ 7851.718394] Key type ._llcrypt registered [ 7851.720724] Key type .llcrypt registered [ 7853.271831] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 16:57:53 (1782248273) [ 7875.363247] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7875.417386] alg: No test for adler32 (adler32-zlib) [ 7876.392335] Lustre: Lustre: Build Version: 2.17.54_83_g947bf2b [ 7876.660681] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 7878.367257] Key type lgssc registered [ 7879.899225] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7960.082182] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7965.629229] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7990.627855] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8034.495914] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 17:00:55 (1782248455) [ 8067.554832] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8073.973357] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8097.312657] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8099.037534] Lustre: Mounted lustre-client [ 8107.349226] Lustre: Unmounted lustre-client [ 8135.430959] Key type lgssc unregistered [ 8135.681229] LNet: 186721:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8135.691983] LNetError: 186721:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8135.722568] LNet: Removed LNI 192.168.203.45@tcp [ 8136.476664] Key type .llcrypt unregistered [ 8136.478372] Key type ._llcrypt unregistered [ 8152.150151] Key type ._llcrypt registered [ 8152.154151] Key type .llcrypt registered [ 8153.434475] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 8155.084599] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 17:02:55 (1782248575) [ 8172.980867] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8172.994242] alg: No test for adler32 (adler32-zlib) [ 8174.099662] Lustre: Lustre: Build Version: 2.17.54_83_g947bf2b [ 8174.366339] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 8175.991140] Key type lgssc registered [ 8177.745598] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8280.200297] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8287.255855] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8313.033883] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid [ 8337.736991] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid [ 8340.549690] Lustre: Mounted lustre-client [ 8367.804197] Lustre: Unmounted lustre-client [ 8480.964979] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8486.042256] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8507.516405] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8547.934867] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 17:09:28 (1782248968) [ 8549.387265] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 8550.947169] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 17:09:31 (1782248971) [ 8638.537988] Key type lgssc unregistered [ 8638.759478] LNet: 198105:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8638.764926] LNetError: 198105:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8638.777700] LNet: Removed LNI 192.168.203.45@tcp [ 8639.504656] Key type .llcrypt unregistered [ 8639.506953] Key type ._llcrypt unregistered [ 8655.739789] Key type ._llcrypt registered [ 8655.744460] Key type .llcrypt registered [ 8657.251903] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 17:11:17 (1782249077) [ 8698.035288] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8698.097096] alg: No test for adler32 (adler32-zlib) [ 8699.221402] Lustre: Lustre: Build Version: 2.17.54_83_g947bf2b [ 8699.463415] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 8701.167197] Key type lgssc registered [ 8702.127702] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8744.172104] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8749.114660] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8782.107110] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8807.583260] Key type lgssc unregistered [ 8807.852817] LNet: 201813:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8807.858535] LNetError: 201813:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8807.874782] LNet: Removed LNI 192.168.203.45@tcp [ 8808.527142] Key type .llcrypt unregistered [ 8808.529146] Key type ._llcrypt unregistered [ 8823.736586] Key type ._llcrypt registered [ 8823.738863] Key type .llcrypt registered [ 8824.905387] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 17:14:05 (1782249245) [ 8827.401845] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8827.434043] alg: No test for adler32 (adler32-zlib) [ 8828.638288] Lustre: Lustre: Build Version: 2.17.54_83_g947bf2b [ 8828.908090] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 8830.615175] Key type lgssc registered [ 8831.715257] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8876.557489] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8881.402701] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8898.380815] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8899.986155] Lustre: Mounted lustre-client [ 8905.203235] Lustre: lustre-MDT0000-mdc-ffff969384562800: Connection to lustre-MDT0000 (at 192.168.203.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8915.436412] Lustre: lustre-MDT0001-mdc-ffff969384562800: Connection to lustre-MDT0001 (at 192.168.203.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8931.615138] Lustre: 202803:0:(client.c:2489:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1782249337/real 1782249337] req@00000000011b15ce x1868823790698496/t0(0) o400->MGC192.168.203.145@tcp@192.168.203.145@tcp:26/25 lens 224/224 e 0 to 1 dl 1782249353 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 8931.646777] LustreError: MGC192.168.203.145@tcp: Connection to MGS (at 192.168.203.145@tcp) was lost; in progress operations using this service will fail [ 8940.908578] Lustre: Evicted from MGS (at 192.168.203.145@tcp) after server handle changed from 0x23d6ec4ebe9f0cd to 0x23d6ec4ebea0dd7 [ 8940.936222] Lustre: MGC192.168.203.145@tcp: Connection restored to 192.168.203.145@tcp (at 192.168.203.145@tcp) [ 8960.508553] LustreError: lustre-MDT0000-mdc-ffff969384562800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 8960.662325] Lustre: lustre-MDT0000-mdc-ffff969384562800: Connection restored to 192.168.203.145@tcp (at 192.168.203.145@tcp) [ 8968.604863] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8970.176086] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 8975.708464] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8977.433328] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 8979.983031] Lustre: Unmounted lustre-client [ 9005.172382] Key type lgssc unregistered [ 9005.426064] LNet: 206729:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9005.430992] LNetError: 206729:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9006.502555] LNet: Removed LNI 192.168.203.45@tcp [ 9007.138762] Key type .llcrypt unregistered [ 9007.142962] Key type ._llcrypt unregistered [ 9021.963994] Key type ._llcrypt registered [ 9021.966988] Key type .llcrypt registered [ 9023.273880] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 17:17:23 (1782249443) [ 9025.328961] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9025.337653] alg: No test for adler32 (adler32-zlib) [ 9026.351807] Lustre: Lustre: Build Version: 2.17.54_83_g947bf2b [ 9026.561233] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 9028.215303] Key type lgssc registered [ 9029.346606] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9075.692562] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9081.098820] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9128.866212] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9135.334522] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9166.496934] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9189.025279] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 9224.261440] Key type lgssc unregistered [ 9224.485344] LNet: 211971:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9224.490334] LNetError: 211971:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9224.515435] LNet: Removed LNI 192.168.203.45@tcp [ 9225.173656] Key type .llcrypt unregistered [ 9225.175607] Key type ._llcrypt unregistered [ 9265.120378] Key type ._llcrypt registered [ 9265.123098] Key type .llcrypt registered [ 9266.247662] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 17:21:27 (1782249687) [ 9297.284893] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9297.401882] alg: No test for adler32 (adler32-zlib) [ 9298.514100] Lustre: Lustre: Build Version: 2.17.54_83_g947bf2b [ 9298.701222] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 9300.343171] Key type lgssc registered [ 9301.560705] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9378.817439] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9384.354992] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9406.660865] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9452.744622] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 17:24:33 (1782249873) [ 9459.665500] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 17:24:40 (1782249880) [ 9489.252707] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9494.583028] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9512.307874] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9514.887526] Lustre: Mounted lustre-client [ 9520.098552] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [ 9521.776614] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 9523.245509] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 9524.762135] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [ 9530.345640] Lustre: lustre-MDT0000-mdc-ffff969383822800: Connection to lustre-MDT0000 (at 192.168.203.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9545.710843] LustreError: MGC192.168.203.145@tcp: Connection to MGS (at 192.168.203.145@tcp) was lost; in progress operations using this service will fail [ 9545.733510] Lustre: Evicted from MGS (at 192.168.203.145@tcp) after server handle changed from 0x6b4f0b06dc704084 to 0x6b4f0b06dc7042f3 [ 9545.750910] Lustre: MGC192.168.203.145@tcp: Connection restored to 192.168.203.145@tcp (at 192.168.203.145@tcp) [ 9549.892898] Lustre: lustre-MDT0000-mdc-ffff969383822800: Connection restored to 192.168.203.145@tcp (at 192.168.203.145@tcp) [ 9557.004880] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9558.515985] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9561.800428] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [ 9566.198502] Lustre: lustre-MDT0000-mdc-ffff969383822800: Connection to lustre-MDT0000 (at 192.168.203.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9582.431127] Lustre: 213864:0:(client.c:2489:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1782249988/real 1782249988] req@0000000007affc8f x1868824282416256/t0(0) o400->MGC192.168.203.145@tcp@192.168.203.145@tcp:26/25 lens 224/224 e 0 to 1 dl 1782250004 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 9582.471664] LustreError: MGC192.168.203.145@tcp: Connection to MGS (at 192.168.203.145@tcp) was lost; in progress operations using this service will fail [ 9592.817881] Lustre: Evicted from MGS (at 192.168.203.145@tcp) after server handle changed from 0x6b4f0b06dc7042f3 to 0x6b4f0b06dc704603 [ 9592.833306] Lustre: MGC192.168.203.145@tcp: Connection restored to 192.168.203.145@tcp (at 192.168.203.145@tcp) [ 9597.016417] Lustre: lustre-MDT0000-mdc-ffff969383822800: Connection restored to 192.168.203.145@tcp (at 192.168.203.145@tcp) [ 9606.896599] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9608.587620] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9613.188692] Lustre: Unmounted lustre-client [ 9681.635201] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9686.709473] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9707.493722] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9708.027532] Lustre: Mounted lustre-client [ 9710.513888] Lustre: Unmounted lustre-client [ 9737.994959] Key type lgssc unregistered [ 9738.211687] LNet: 224367:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9738.220734] LNetError: 224367:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9738.240036] LNet: Removed LNI 192.168.203.45@tcp [ 9739.067578] Key type .llcrypt unregistered [ 9739.069819] Key type ._llcrypt unregistered [ 9755.890558] Key type ._llcrypt registered [ 9755.892600] Key type .llcrypt registered [ 9757.138145] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 17:29:37 (1782250177) [ 9761.158436] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9761.179588] alg: No test for adler32 (adler32-zlib) [ 9762.235506] Lustre: Lustre: Build Version: 2.17.54_83_g947bf2b [ 9762.622923] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [ 9764.407174] Key type lgssc registered [ 9765.872158] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9815.780154] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9821.367934] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9841.445308] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9843.411668] Lustre: Mounted lustre-client [ 9850.566280] Lustre: Unmounted lustre-client [ 9913.056371] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9918.665759] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9939.221230] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9939.835707] Lustre: Mounted lustre-client [ 9948.614214] Lustre: Unmounted lustre-client [10017.914484] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10023.233720] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10042.699815] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10043.433662] Lustre: Mounted lustre-client [10045.881620] Lustre: Unmounted lustre-client [10075.083379] Key type lgssc unregistered [10075.365669] LNet: 231339:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10075.385512] LNetError: 231339:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [10075.409120] LNet: Removed LNI 192.168.203.45@tcp [10076.289791] Key type .llcrypt unregistered [10076.300299] Key type ._llcrypt unregistered [10091.983209] Key type ._llcrypt registered [10091.985992] Key type .llcrypt registered [10093.083498] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 17:35:13 (1782250513) [10096.264762] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10096.363745] alg: No test for adler32 (adler32-zlib) [10097.491992] Lustre: Lustre: Build Version: 2.17.54_83_g947bf2b [10097.763942] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [10099.471345] Key type lgssc registered [10101.025447] Lustre: Echo OBD driver; http://www.lustre.org/ [10138.260943] Key type lgssc unregistered [10138.464508] LNet: 233661:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10138.471993] LNetError: 233661:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [10138.484852] LNet: Removed LNI 192.168.203.45@tcp [10138.943782] Key type .llcrypt unregistered [10138.948731] Key type ._llcrypt unregistered [10161.552108] Key type ._llcrypt registered [10161.557468] Key type .llcrypt registered [10161.841989] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10161.859209] alg: No test for adler32 (adler32-zlib) [10162.882976] Lustre: Lustre: Build Version: 2.17.54_83_g947bf2b [10163.119674] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [10164.783960] Key type lgssc registered [10165.765191] Lustre: Echo OBD driver; http://www.lustre.org/ [10237.866758] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10242.077957] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10264.242887] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10302.984987] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [10304.649534] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 17:38:45 (1782250725) [10336.176110] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10343.711170] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10364.680937] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10385.835468] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [10387.379379] Lustre: Mounted lustre-client [10392.552912] Lustre: lustre-OST0001-osc-ffff969385e7e000: Connection to lustre-OST0001 (at 192.168.203.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [10398.947683] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [10409.332779] Lustre: Unmounted lustre-client [10441.957455] Key type lgssc unregistered [10442.263433] LNet: 240712:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10442.267967] LNetError: 240712:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [10442.300213] LNet: Removed LNI 192.168.203.45@tcp [10442.904410] Key type .llcrypt unregistered [10442.907465] Key type ._llcrypt unregistered [10470.139513] Key type ._llcrypt registered [10470.143330] Key type .llcrypt registered [10471.331595] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 17:41:32 (1782250892) [10482.988375] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10483.042330] alg: No test for adler32 (adler32-zlib) [10484.055827] Lustre: Lustre: Build Version: 2.17.54_83_g947bf2b [10484.311981] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [10485.991262] Key type lgssc registered [10487.108876] Lustre: Echo OBD driver; http://www.lustre.org/ [10538.855417] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10544.969537] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10567.882885] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 17:43:08 (1782250988) [10599.105816] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10604.762433] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10625.945237] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10628.697913] Lustre: Mounted lustre-client [10635.844941] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [10645.837166] Lustre: Unmounted lustre-client [10749.956593] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10755.371797] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10807.024965] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10807.705883] Lustre: Mounted lustre-client [10810.421723] Lustre: Unmounted lustre-client [10837.609991] Key type lgssc unregistered [10837.932917] LNet: 248343:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10837.937336] LNetError: 248343:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [10837.965901] LNet: Removed LNI 192.168.203.45@tcp [10838.648754] Key type .llcrypt unregistered [10838.654180] Key type ._llcrypt unregistered [10866.564221] Key type ._llcrypt registered [10866.629793] Key type .llcrypt registered [10867.013347] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10867.024272] alg: No test for adler32 (adler32-zlib) [10868.105854] Lustre: Lustre: Build Version: 2.17.54_83_g947bf2b [10868.402050] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [10870.047438] Key type lgssc registered [10871.142499] Lustre: Echo OBD driver; http://www.lustre.org/ [10949.106565] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10954.608341] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10980.887195] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11024.336159] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [11026.168611] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 17:50:46 (1782251446) [11057.263169] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11062.376325] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11082.372872] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11087.412757] Lustre: Mounted lustre-client [11091.298288] Lustre: Unmounted lustre-client [11118.288526] Key type lgssc unregistered [11118.560378] LNet: 254925:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11118.576379] LNetError: 254925:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [11118.594356] LNet: Removed LNI 192.168.203.45@tcp [11119.343662] Key type .llcrypt unregistered [11119.351411] Key type ._llcrypt unregistered [11136.327776] Key type ._llcrypt registered [11136.331451] Key type .llcrypt registered [11137.508225] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [11139.238542] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 17:52:39 (1782251559) [11148.384499] Key type .llcrypt unregistered [11148.390713] Key type ._llcrypt unregistered [11157.909849] Key type ._llcrypt registered [11157.918721] Key type .llcrypt registered [11158.655216] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11158.675946] alg: No test for adler32 (adler32-zlib) [11159.867598] Lustre: Lustre: Build Version: 2.17.54_83_g947bf2b [11160.073827] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [11161.759339] Key type lgssc registered [11163.010486] Lustre: Echo OBD driver; http://www.lustre.org/ [11209.129808] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11222.373922] Lustre: Mounted lustre-client [11224.735723] Lustre: Unmounted lustre-client [11257.753426] Key type lgssc unregistered [11258.057272] LNet: 258375:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11258.065749] LNetError: 258375:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [11258.088117] LNet: Removed LNI 192.168.203.45@tcp [11258.859476] Key type .llcrypt unregistered [11258.862464] Key type ._llcrypt unregistered [11274.975748] Key type ._llcrypt registered [11274.977759] Key type .llcrypt registered [11276.177891] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 17:54:56 (1782251696) [11278.671698] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11278.823240] alg: No test for adler32 (adler32-zlib) [11279.938930] Lustre: Lustre: Build Version: 2.17.54_83_g947bf2b [11280.221180] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [11281.959816] Key type lgssc registered [11283.429802] Lustre: Echo OBD driver; http://www.lustre.org/ [11370.275308] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11402.403112] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11409.842327] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11412.722917] Lustre: Mounted lustre-client [11416.201340] Lustre: Unmounted lustre-client [11442.229305] Key type lgssc unregistered [11442.448793] LNet: 261897:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11442.462513] LNetError: 261897:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [11443.495332] LNet: Removed LNI 192.168.203.45@tcp [11444.481154] Key type .llcrypt unregistered [11444.489670] Key type ._llcrypt unregistered [11464.099867] Key type ._llcrypt registered [11464.102655] Key type .llcrypt registered [11465.613131] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 17:58:06 (1782251886) [11467.847979] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11467.995488] alg: No test for adler32 (adler32-zlib) [11469.119529] Lustre: Lustre: Build Version: 2.17.54_83_g947bf2b [11469.405902] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [11471.167174] Key type lgssc registered [11472.580492] Lustre: Echo OBD driver; http://www.lustre.org/ [11519.696793] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11524.854978] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11543.728882] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11546.555557] Lustre: Mounted lustre-client [11551.726619] Lustre: lustre-MDT0000-mdc-ffff96938b7a1800: Connection to lustre-MDT0000 (at 192.168.203.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [11554.522252] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [11554.553115] LustreError: 265027:0:(file.c:6088:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [11555.067668] LustreError: MGC192.168.203.145@tcp: Connection to MGS (at 192.168.203.145@tcp) was lost; in progress operations using this service will fail [11555.123091] Lustre: Unmounted lustre-client [11573.510183] Key type lgssc unregistered [11573.871155] LNet: 265439:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11573.899592] LNetError: 265439:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [11573.941042] LNet: Removed LNI 192.168.203.45@tcp [11574.748254] Key type .llcrypt unregistered [11574.752231] Key type ._llcrypt unregistered [11592.170410] Key type ._llcrypt registered [11592.174042] Key type .llcrypt registered [11593.916669] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 18:00:14 (1782252014) [11596.294806] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11596.331744] alg: No test for adler32 (adler32-zlib) [11597.457716] Lustre: Lustre: Build Version: 2.17.54_83_g947bf2b [11597.765251] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [11599.487189] Key type lgssc registered [11601.163928] Lustre: Echo OBD driver; http://www.lustre.org/ [11652.523782] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11658.728631] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11678.200513] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11680.792301] Lustre: Mounted lustre-client [11690.977279] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [11693.385711] Lustre: Unmounted lustre-client [11713.988981] Key type lgssc unregistered [11714.184868] LNet: 269002:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11714.197675] LNetError: 269002:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [11714.224797] LNet: Removed LNI 192.168.203.45@tcp [11715.140536] Key type .llcrypt unregistered [11715.147831] Key type ._llcrypt unregistered [11730.400375] Key type ._llcrypt registered [11730.402656] Key type .llcrypt registered [11731.489373] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 18:02:32 (1782252152) [11751.536113] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11751.590133] alg: No test for adler32 (adler32-zlib) [11752.679894] Lustre: Lustre: Build Version: 2.17.54_83_g947bf2b [11753.011105] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [11754.727141] Key type lgssc registered [11756.135175] Lustre: Echo OBD driver; http://www.lustre.org/ [11871.511640] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11873.400951] Lustre: Mounted lustre-client [11913.193908] Lustre: lustre-MDT0001-mdc-ffff969385129800: Connection to lustre-MDT0001 (at 192.168.203.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [11921.321690] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [11931.678780] Lustre: Unmounted lustre-client [11959.889918] Key type lgssc unregistered [11960.220143] LNet: 274783:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11960.224549] LNetError: 274783:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [11960.264133] LNet: Removed LNI 192.168.203.45@tcp [11961.099301] Key type .llcrypt unregistered [11961.101829] Key type ._llcrypt unregistered [11978.175688] Key type ._llcrypt registered [11978.177120] Key type .llcrypt registered [11979.441616] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 18:06:40 (1782252400) [11981.339773] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [11983.174942] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 18:06:43 (1782252403) [11985.002645] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [11986.932620] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 18:06:47 (1782252407) [11988.731397] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [11990.711243] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 18:06:51 (1782252411) [11992.631839] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [11994.911515] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 18:06:55 (1782252415) [11996.712537] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [11998.379980] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 18:06:59 (1782252419) [12007.776329] Key type .llcrypt unregistered [12007.781384] Key type ._llcrypt unregistered [12015.010897] Key type ._llcrypt registered [12015.016992] Key type .llcrypt registered [12015.447179] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12015.470873] alg: No test for adler32 (adler32-zlib) [12016.545382] Lustre: Lustre: Build Version: 2.17.54_83_g947bf2b [12016.881685] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [12018.623238] Key type lgssc registered [12019.832860] Lustre: Echo OBD driver; http://www.lustre.org/ [12096.921211] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12103.095836] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12122.691404] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12124.304736] Lustre: Mounted lustre-client [12126.899551] Lustre: Unmounted lustre-client [12162.493291] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 18:09:43 (1782252583) [12194.186630] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12198.717769] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12229.992750] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12240.951608] Lustre: Mounted lustre-client [12243.242480] Lustre: Unmounted lustre-client [12292.511133] LNet: 1 local NIs in recovery (showing 1): 192.168.203.45@tcp [12294.056446] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 18:11:54 (1782252714) [12935.004056] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12940.418817] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12973.415439] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [13063.953602] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 18:24:44 (1782253484) [13075.803295] Key type lgssc unregistered [13076.326527] LNet: 288230:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13076.358209] LNetError: 288230:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [13076.382917] LNet: Removed LNI 192.168.203.45@tcp [13077.342938] Key type .llcrypt unregistered [13077.346683] Key type ._llcrypt unregistered [13218.275148] Key type ._llcrypt registered [13218.279516] Key type .llcrypt registered [13218.579824] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13218.591438] alg: No test for adler32 (adler32-zlib) [13219.706942] Lustre: Lustre: Build Version: 2.17.54_83_g947bf2b [13219.937085] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [13221.727223] Key type lgssc registered [13223.039863] Lustre: Echo OBD driver; http://www.lustre.org/ [13312.791566] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [13317.858453] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [13339.171364] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [13370.592456] Key type lgssc unregistered [13370.846796] LNet: 291904:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13370.852857] LNetError: 291904:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [13370.866341] LNet: Removed LNI 192.168.203.45@tcp [13371.579235] Key type .llcrypt unregistered [13371.580945] Key type ._llcrypt unregistered [13407.376790] Key type ._llcrypt registered [13407.380794] Key type .llcrypt registered [13408.665051] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 18:30:29 (1782253829) [13427.555771] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13427.601438] alg: No test for adler32 (adler32-zlib) [13428.644068] Lustre: Lustre: Build Version: 2.17.54_83_g947bf2b [13428.896066] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [13430.583242] Key type lgssc registered [13431.630776] Lustre: Echo OBD driver; http://www.lustre.org/ [13485.604544] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 18:31:45 (1782253905) [13497.797856] Key type lgssc unregistered [13498.223352] LNet: 295458:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13498.234479] LNetError: 295458:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [13498.254352] LNet: Removed LNI 192.168.203.45@tcp [13499.214738] Key type .llcrypt unregistered [13499.220440] Key type ._llcrypt unregistered [13510.901938] Key type ._llcrypt registered [13510.904328] Key type .llcrypt registered [13511.262251] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13511.292540] alg: No test for adler32 (adler32-zlib) [13512.446833] Lustre: Lustre: Build Version: 2.17.54_83_g947bf2b [13512.781474] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [13514.487222] Key type lgssc registered [13515.881613] Lustre: Echo OBD driver; http://www.lustre.org/ [13570.137876] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [13575.668844] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [13598.089782] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [13859.170290] LNet: Added LNI 192.168.203.45@tcp50 [8/256/0/180] [13861.820709] LNet: Removed LNI 192.168.203.45@tcp [13863.950962] Lustre: 299823:0:(ldlm_lib.c:563:client_obd_setup()) lustre-MDT0000-mdc-ffff969391568000: config has no valid NIDs, force dynamic NIDs [13865.143458] Lustre: Mounted lustre-client [13865.287920] Lustre: Unmounted lustre-client [13867.932280] Key type lgssc unregistered [13868.145972] LNet: 300084:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13868.150520] LNetError: 300084:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [13868.162887] LNet: Removed LNI 192.168.203.45@tcp50 [13868.619029] Key type .llcrypt unregistered [13868.623167] Key type ._llcrypt unregistered [13880.933812] Key type ._llcrypt registered [13880.940027] Key type .llcrypt registered [13881.568431] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13881.604856] alg: No test for adler32 (adler32-zlib) [13882.742035] Lustre: Lustre: Build Version: 2.17.54_83_g947bf2b [13883.018589] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [13884.727205] Key type lgssc registered [13885.770716] Lustre: Echo OBD driver; http://www.lustre.org/ [13941.822913] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [13946.999457] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [13954.317378] LNet: Added LNI 192.168.203.45@tcp50 [8/256/0/180] [13955.095315] LNet: Removed LNI 192.168.203.45@tcp [13956.322767] Lustre: 302761:0:(ldlm_lib.c:563:client_obd_setup()) lustre-MDT0000-mdc-ffff969386836000: config has no valid NIDs, force dynamic NIDs [13957.592933] Lustre: Mounted lustre-client [13958.256690] Lustre: Unmounted lustre-client [13989.623611] Key type lgssc unregistered [13990.025970] LNet: 303631:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13990.034729] LNetError: 303631:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [13990.053331] LNet: Removed LNI 192.168.203.45@tcp50 [13990.724689] Key type .llcrypt unregistered [13990.727727] Key type ._llcrypt unregistered [14002.078134] Key type ._llcrypt registered [14002.083134] Key type .llcrypt registered [14003.422335] Lustre: DEBUG MARKER: == conf-sanity test 73f: mgc register before connect ===== 18:40:24 (1782254424) [14020.719728] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14020.742598] alg: No test for adler32 (adler32-zlib) [14021.817271] Lustre: Lustre: Build Version: 2.17.54_83_g947bf2b [14022.181595] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [14023.855802] Key type lgssc registered [14024.891056] Lustre: Echo OBD driver; http://www.lustre.org/ [14108.213802] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [14114.123177] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [14137.683621] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [14177.514250] Key type lgssc unregistered [14177.746539] LNet: 308350:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14177.758253] LNetError: 308350:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [14177.775937] LNet: Removed LNI 192.168.203.45@tcp [14178.416036] Key type .llcrypt unregistered [14178.417739] Key type ._llcrypt unregistered [14188.004529] Key type ._llcrypt registered [14188.008920] Key type .llcrypt registered [14189.213442] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 18:43:29 (1782254609) [14191.712988] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14191.722541] alg: No test for adler32 (adler32-zlib) [14192.832081] Lustre: Lustre: Build Version: 2.17.54_83_g947bf2b [14193.126300] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [14194.807712] Key type lgssc registered [14196.074932] Lustre: Echo OBD driver; http://www.lustre.org/ [14242.365135] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [14248.049905] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [14271.788824] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [14273.890346] Lustre: Mounted lustre-client [14328.372576] Lustre: Mounted fs15246-client [14342.905534] Lustre: Unmounted fs15246-client [14367.289603] Lustre: Unmounted lustre-client [14393.387252] Key type lgssc unregistered [14393.611929] LNet: 313186:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14393.619836] LNetError: 313186:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [14394.662782] LNet: Removed LNI 192.168.203.45@tcp [14395.329347] Key type .llcrypt unregistered [14395.337318] Key type ._llcrypt unregistered [14405.259080] Key type ._llcrypt registered [14405.261171] Key type .llcrypt registered [14406.690913] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 18:47:07 (1782254827) [14408.387168] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14408.434943] alg: No test for adler32 (adler32-zlib) [14409.544467] Lustre: Lustre: Build Version: 2.17.54_83_g947bf2b [14409.790842] LNet: Added LNI 192.168.203.45@tcp [8/256/0/180] [14411.463475] Key type lgssc registered [14412.494430] Lustre: Echo OBD driver; http://www.lustre.org/ [14539.791214] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [14545.496934] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [14568.309718] Lustre: DEBUG MARKER: oleg345-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [14665.205908] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 14420 sec ========= 18:51:25 (1782255085) [14666.952568] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 18:51:27 (1782255087) === [14670.676368] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 18:51:31 (1782255091) === [14703.453857] Key type lgssc unregistered [14703.640827] LNet: 322422:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14703.649874] LNetError: 322422:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [14703.673478] LNet: Removed LNI 192.168.203.45@tcp [14704.338853] Key type .llcrypt unregistered [14704.342883] Key type ._llcrypt unregistered