[ 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 779784022 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, 524584K 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.001009] APIC: Switch to symmetric I/O mode setup [ 0.003075] x2apic enabled [ 0.004006] Switched APIC routing to physical x2apic. [ 0.005010] kvm-guest: setup PV IPIs [ 0.007855] ..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.008020] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009008] pid_max: default: 32768 minimum: 301 [ 0.010122] LSM: Security Framework initializing [ 0.011035] Yama: becoming mindful. [ 0.012025] SELinux: Initializing. [ 0.013047] *** VALIDATE selinux *** [ 0.022013] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.026710] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.027129] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.028107] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029110] *** VALIDATE tmpfs *** [ 0.030400] *** VALIDATE proc *** [ 0.032096] *** VALIDATE cgroup *** [ 0.033006] *** VALIDATE cgroup2 *** [ 0.034230] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.035133] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.036006] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.037037] Spectre V2 : User space: Vulnerable [ 0.038020] Speculative Store Bypass: Vulnerable [ 0.041608] debug: unmapping init [mem 0xffffffff95859000-0xffffffff95860fff] [ 0.044244] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.045683] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.046028] ... version: 2 [ 0.047009] ... bit width: 48 [ 0.048008] ... generic registers: 4 [ 0.049012] ... value mask: 0000ffffffffffff [ 0.050010] ... max period: 00007fffffffffff [ 0.051073] ... fixed-purpose events: 3 [ 0.052011] ... event mask: 000000070000000f [ 0.053313] rcu: Hierarchical SRCU implementation. [ 0.055575] smp: Bringing up secondary CPUs ... [ 0.056665] x86: Booting SMP configuration: [ 0.057019] .... node #0, CPUs: #1 #2 #3 [ 0.068188] smp: Brought up 1 node, 4 CPUs [ 0.070028] smpboot: Max logical packages: 1 [ 0.071014] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.124000] node 0 deferred pages initialised in 46ms [ 0.131647] devtmpfs: initialized [ 0.132199] x86/mm: Memory block size: 128MB [ 0.135363] gcov: version magic: 0x41383552 [ 0.137223] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.138179] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.139572] pinctrl core: initialized pinctrl subsystem [ 0.140232] [ 0.140943] ************************************************************* [ 0.141014] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.142010] ** ** [ 0.143013] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.144012] ** ** [ 0.145015] ** This means that this kernel is built to expose internal ** [ 0.146022] ** IOMMU data structures, which may compromise security on ** [ 0.147015] ** your system. ** [ 0.148078] ** ** [ 0.149077] ** If you see this message and you are not debugging the ** [ 0.150016] ** kernel, report this immediately to your vendor! ** [ 0.151014] ** ** [ 0.152019] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.153028] ************************************************************* [ 0.154924] NET: Registered protocol family 16 [ 0.155557] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.156057] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.157052] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.158628] cpuidle: using governor menu [ 0.161099] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.163572] PCI: Using configuration type 1 for base access [ 0.166145] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.178123] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.179016] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.183133] cryptd: max_cpu_qlen set to 1000 [ 0.185315] ACPI: Added _OSI(Module Device) [ 0.186018] ACPI: Added _OSI(Processor Device) [ 0.187011] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.188012] ACPI: Added _OSI(Processor Aggregator Device) [ 0.193504] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.197094] ACPI: Interpreter enabled [ 0.199164] ACPI: PM: (supports S0 S3 S4 S5) [ 0.204014] ACPI: Using IOAPIC for interrupt routing [ 0.208093] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.217580] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.240147] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.245187] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.249073] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.253088] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.260013] acpiphp: Slot [2] registered [ 0.262188] acpiphp: Slot [5] registered [ 0.263238] acpiphp: Slot [6] registered [ 0.265186] acpiphp: Slot [3] registered [ 0.267156] acpiphp: Slot [4] registered [ 0.269122] acpiphp: Slot [7] registered [ 0.271165] acpiphp: Slot [8] registered [ 0.272075] acpiphp: Slot [9] registered [ 0.274086] acpiphp: Slot [10] registered [ 0.275100] acpiphp: Slot [11] registered [ 0.276083] acpiphp: Slot [12] registered [ 0.278131] acpiphp: Slot [13] registered [ 0.279124] acpiphp: Slot [14] registered [ 0.281123] acpiphp: Slot [15] registered [ 0.283101] acpiphp: Slot [16] registered [ 0.285121] acpiphp: Slot [17] registered [ 0.287106] acpiphp: Slot [18] registered [ 0.289199] acpiphp: Slot [19] registered [ 0.290118] acpiphp: Slot [20] registered [ 0.292091] acpiphp: Slot [21] registered [ 0.294081] acpiphp: Slot [22] registered [ 0.296083] acpiphp: Slot [23] registered [ 0.297080] acpiphp: Slot [24] registered [ 0.299100] acpiphp: Slot [25] registered [ 0.301091] acpiphp: Slot [26] registered [ 0.304084] acpiphp: Slot [27] registered [ 0.306571] acpiphp: Slot [28] registered [ 0.308764] acpiphp: Slot [29] registered [ 0.310137] acpiphp: Slot [30] registered [ 0.311077] acpiphp: Slot [31] registered [ 0.313050] PCI host bridge to bus 0000:00 [ 0.314016] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.317032] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.319015] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.322017] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.324015] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.327018] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.329150] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.332023] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.335242] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.344014] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.348362] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.352016] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.355063] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.357017] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.360826] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.364080] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.367037] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.371115] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.375017] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.388014] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.393943] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.400146] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.408033] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.415010] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.436014] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.475078] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.490015] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.503019] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.525021] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.539148] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.542349] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.544388] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.548375] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.550377] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.556024] iommu: Default domain type: Passthrough [ 0.557447] SCSI subsystem initialized [ 0.559117] ACPI: bus type USB registered [ 0.561115] usbcore: registered new interface driver usbfs [ 0.564083] usbcore: registered new interface driver hub [ 0.566121] usbcore: registered new device driver usb [ 0.569257] pps_core: LinuxPPS API ver. 1 registered [ 0.571009] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.574094] PTP clock support registered [ 0.577119] EDAC MC: Ver: 3.0.0 [ 0.579017] PCI: Using ACPI for IRQ routing [ 0.580742] NetLabel: Initializing [ 0.582010] NetLabel: domain hash size = 128 [ 0.583008] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.586076] NetLabel: unlabeled traffic allowed by default [ 0.589112] vgaarb: loaded [ 0.590558] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.592014] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.601715] clocksource: Switched to clocksource kvm-clock [ 0.720284] VFS: Disk quotas dquot_6.6.0 [ 0.721739] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.723627] *** VALIDATE ramfs *** [ 0.724396] *** VALIDATE hugetlbfs *** [ 0.725647] pnp: PnP ACPI init [ 0.727797] pnp: PnP ACPI: found 6 devices [ 0.749384] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.754214] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.756989] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.760408] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.763654] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.770474] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.774649] NET: Registered protocol family 2 [ 0.796489] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.803923] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.809509] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.818146] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.823187] TCP: Hash tables configured (established 65536 bind 65536) [ 0.832769] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.838944] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.843947] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.849794] NET: Registered protocol family 1 [ 0.854273] RPC: Registered named UNIX socket transport module. [ 0.860505] RPC: Registered udp transport module. [ 0.865089] RPC: Registered tcp transport module. [ 0.869226] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.874691] NET: Registered protocol family 44 [ 0.878167] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.881633] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.886871] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.890932] PCI: CLS 0 bytes, default 64 [ 0.894928] Unpacking initramfs... [ 2.770422] debug: unmapping init [mem 0xffff8d113cc64000-0xffff8d113ffcffff] [ 2.774305] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.776420] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.778958] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 3.422102] Initialise system trusted keyrings [ 3.423693] Key type blacklist registered [ 3.425532] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 3.438696] zbud: loaded [ 3.443379] *** VALIDATE nfs *** [ 3.444757] *** VALIDATE nfs4 *** [ 3.446557] pstore: using deflate compression [ 3.452657] Platform Keyring initialized [ 3.644398] NET: Registered protocol family 38 [ 3.646459] Key type asymmetric registered [ 3.647974] Asymmetric key parser 'x509' registered [ 3.650649] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 3.654355] io scheduler mq-deadline registered [ 3.656250] io scheduler kyber registered [ 3.658222] io scheduler bfq registered [ 3.660317] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 3.663377] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 3.666706] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 3.669887] ACPI: Power Button [PWRF] [ 3.678296] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.691936] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.723546] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.758676] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.788172] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.806821] Non-volatile memory driver v1.3 [ 3.808437] Linux agpgart interface v0.103 [ 3.841132] virtio_blk virtio1: [vda] 151304 512-byte logical blocks (77.5 MB/73.9 MiB) [ 3.843768] vda: detected capacity change from 0 to 77467648 [ 3.992947] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.996849] vdb: detected capacity change from 0 to 1073741824 [ 4.036382] libphy: Fixed MDIO Bus: probed [ 4.047666] usbcore: registered new interface driver usbserial_generic [ 4.051128] usbserial: USB Serial support registered for generic [ 4.056614] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 4.064930] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 4.068945] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 4.074908] mousedev: PS/2 mouse device common for all mice [ 4.079810] rtc_cmos 00:05: RTC can wake from S4 [ 4.084306] rtc_cmos 00:05: registered as rtc0 [ 4.086262] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 4.089207] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 4.092823] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 4.092878] intel_pstate: CPU model not supported [ 4.095541] hid: raw HID events driver (C) Jiri Kosina [ 4.100380] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 4.102337] usbcore: registered new interface driver usbhid [ 4.102342] usbhid: USB HID core driver [ 4.102454] drop_monitor: Initializing network drop monitor service [ 4.102580] Initializing XFRM netlink socket [ 4.103078] NET: Registered protocol family 10 [ 4.104389] Segment Routing with IPv6 [ 4.104511] NET: Registered protocol family 17 [ 4.109969] mpls_gso: MPLS GSO support [ 4.132551] RAS: Correctable Errors collector initialized. [ 4.134220] AVX version of gcm_enc/dec engaged. [ 4.135978] AES CTR mode by8 optimization enabled [ 4.231507] sched_clock: Marking stable (4231473501, 0)->(5570857972, -1339384471) [ 4.697973] registered taskstats version 1 [ 4.699811] Loading compiled-in X.509 certificates [ 4.704363] zswap: loaded using pool lzo/zbud [ 4.739288] Key type big_key registered [ 4.758969] Key type encrypted registered [ 4.761388] ima: No TPM chip found, activating TPM-bypass! [ 4.763303] ima: Allocated hash algorithm: sha1 [ 4.764689] ima: No architecture policies found [ 4.766147] evm: Initialising EVM extended attributes: [ 4.767678] evm: security.selinux [ 4.768720] evm: security.ima [ 4.769605] evm: security.capability [ 4.770638] evm: HMAC attrs: 0x1 [ 4.772747] rtc_cmos 00:05: setting system clock to 2026-09-18 05:14:02 UTC (1789708442) [ 4.778725] debug: unmapping init [mem 0xffffffff96803000-0xffffffff969fffff] [ 4.790583] debug: unmapping init [mem 0xffffffff95582000-0xffffffff95858fff] [ 4.799253] Write protecting the kernel read-only data: 28672k [ 4.803315] debug: unmapping init [mem 0xffffffff93c03000-0xffffffff93dfffff] [ 4.805955] debug: unmapping init [mem 0xffffffff94514000-0xffffffff945fffff] [ 4.843202] 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) [ 4.850822] systemd[1]: Detected virtualization kvm. [ 4.852737] systemd[1]: Detected architecture x86-64. [ 4.854428] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 4.880935] systemd[1]: No hostname configured. [ 4.882412] systemd[1]: Set hostname to . [ 4.884342] random: systemd: uninitialized urandom read (16 bytes read) [ 4.889094] systemd[1]: Initializing machine ID from random generator. [ 5.047749] random: ln: uninitialized urandom read (6 bytes read) [ 5.512256] random: systemd: uninitialized urandom read (16 bytes read) [ 5.515745] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ 5.527376] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ 5.535979] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. Starting Journal Service... [ OK ] Started Memstrack Anylazing Service. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Paths. [ OK ] Reached target Initrd Root Device. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on udev Kernel Socket. Starting Setup Virtual Console... [ OK ] Reached target Swap. [ OK ] Reached target Timers. Starting Apply Kernel Variables... [ OK ] Listening on udev Control Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... [ OK ] Reached target Slices. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Setup Virtual Console. [ OK ] Started Journal Service. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create Volatile Files and Directories. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 8.464777] device-mapper: uevent: version 1.0.3 [ 8.467682] 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. [ 9.467888] random: fast init done 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... [ 10.392619] virtio_net virtio0 ens2: renamed from eth0 [ 11.291626] scsi host0: ata_piix [ 11.346417] scsi host1: ata_piix [ 11.347768] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 11.350627] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 16.895024] random: crng init done [ 16.899063] random: 7 urandom warning(s) missed due to ratelimiting [ 19.388185] dracut-initqueue[582]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 21.668040] 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 Timers. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Sockets. [ OK ] Stopped target Paths. [ OK ] Stopped target Slices. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Swap. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 23.902953] printk: systemd: 21 output lines suppressed due to ratelimiting [ 24.514832] SELinux: Disabled at runtime. [ 24.587276] 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) [ 24.596126] systemd[1]: Detected virtualization kvm. [ 24.597964] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 26.934226] systemd[1]: initrd-switch-root.service: Succeeded. [ 26.964852] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 27.209670] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 27.260624] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 27.267414] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 27.277806] systemd[1]: Starting Journal Service... Starting Journal Service... [ 27.285709] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on udev Control Socket. Mounting Kernel Debug File System... [ OK ] Reached target rpc_pipefs.target. [ OK ] Listening on RPCbind Server Activation Socket. Starting Remount Root and Kernel File Systems... [ OK ] Created slice system-getty.slice. [ OK ] Reached target RPC Port Mapper. [ OK ] Created slice User and Session Slice. [ OK ] Created slice system-sshd\x2dkeygen.slice. Activating swap /dev/disk/by-label/SWAP... [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. [ 27.827886] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS Starting Apply Kernel Variables... [ OK ] Stopped target Initrd Root File System. Mounting POSIX Message Queue File System... [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Created slice system-serial\x2dgetty.slice. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Listening on udev Kernel Socket. Starting udev Coldplug all Devices... Mounting Huge Pages File System... [ OK ] Reached target Slices. [ OK ] Listening on Process Core Dump Socket. [ OK ] Started Journal Service. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted Kernel Debug File System. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Mounted Huge Pages 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 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 Flush Journal to Persistent Storage. [ OK ] Mounted /mnt. [ 29.317636] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Started udev Coldplug all Devices. [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 31.734310] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 31.965113] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 34.195496] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 34.283681] EDAC sbridge: Ver: 1.1.2 [* ] A start job is running for Configur…-only root support (9s / no limit)[ 36.534261] Key type dns_resolver registered [** ] A start job is running for Configur…only root support (10s / no limit) [*** ] A start job is running for Configur…only root support (10s / no limit)[ 37.480897] NFS: Registering the id_resolver key type [ 37.483231] Key type id_resolver registered [ 37.485104] Key type id_legacy registered [ *** ] A start job is running for Configur…only root support (11s / no limit) [ *** ] A start job is running for Configur…only root support (11s / no limit) [ ***] A start job is running for Configur…only root support (12s / no limit) [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... Starting Load/Save Random Seed... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Load/Save Random Seed. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started dnf makecache --timer. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started irqbalance daemon. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Timers. Starting Restore /run/initramfs on shutdown... [ OK ] Reached target sshd-keygen.target. Starting Login Service... [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting OpenSSH server daemon... Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... Starting Network Manager Wait Online... [ 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 ttyS1. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Getty on tty1. [ OK ] Reached target Login Prompts. [ OK ] Started Login Service. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting System Logging Service... Starting Crash recovery kernel arming... Starting Notify NFS peers of a restart... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg254-client login: [ 67.571210] hrtimer: interrupt took 3201616 ns [ 99.609564] libcfs: loading out-of-tree module taints kernel. [ 99.772197] Key type ._llcrypt registered [ 99.778649] Key type .llcrypt registered [ 100.152386] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 100.165808] alg: No test for adler32 (adler32-zlib) [ 101.313402] Lustre: Lustre: Build Version: 2.17.58_39_g8752880 [ 101.867853] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 103.560194] Key type lgssc registered [ 104.837767] Lustre: Echo OBD driver; http://www.lustre.org/ [ 204.541731] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 207.973543] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 221.411619] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing check_logdir /tmp/testlogs/ [ 224.219384] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing yml_node [ 226.730752] Lustre: DEBUG MARKER: Client: 2.17.58.39 [ 228.022650] Lustre: DEBUG MARKER: MDS: 2.17.58.39 [ 229.280534] Lustre: DEBUG MARKER: OSS: 2.17.58.39 [ 230.328275] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Fri Sep 18 01:17:47 EDT 2026 [ 230.370203] Lustre: lustre-OST0000-osc-ffff8d118d4dc000: disconnect after 24s idle [ 249.044851] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 250.452579] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 252.634985] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 262.993934] Lustre: Unmounted lustre-client [ 391.349819] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 396.578296] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 422.400814] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 462.893638] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 01:21:39 (1789708899) [ 465.277851] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 466.942962] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 01:21:43 (1789708903) [ 502.653578] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 508.655784] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 531.036838] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 531.756342] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 540.173882] Lustre: Unmounted lustre-client [ 568.361503] Key type lgssc unregistered [ 568.821619] LNet: 15052:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 568.826890] LNetError: 15052:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 568.844381] LNet: Removed LNI 192.168.202.54@tcp [ 569.862151] Key type .llcrypt unregistered [ 569.864416] Key type ._llcrypt unregistered [ 585.571357] Key type ._llcrypt registered [ 585.573271] Key type .llcrypt registered [ 587.114671] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 01:23:43 (1789709023) [ 588.718850] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 588.753601] alg: No test for adler32 (adler32-zlib) [ 589.976689] Lustre: Lustre: Build Version: 2.17.58_39_g8752880 [ 590.327845] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 592.064362] Key type lgssc registered [ 593.345246] Lustre: Echo OBD driver; http://www.lustre.org/ [ 670.638550] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 750.824387] Lustre: Unmounted lustre-client [ 771.379337] Key type lgssc unregistered [ 771.565737] LNet: 18853:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 771.570862] LNetError: 18853:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 771.582348] LNet: Removed LNI 192.168.202.54@tcp [ 772.072026] Key type .llcrypt unregistered [ 772.073109] Key type ._llcrypt unregistered [ 779.909422] Key type ._llcrypt registered [ 779.911841] Key type .llcrypt registered [ 780.949887] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 01:26:57 (1789709217) [ 782.592885] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 782.636801] alg: No test for adler32 (adler32-zlib) [ 783.621549] Lustre: Lustre: Build Version: 2.17.58_39_g8752880 [ 783.803718] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 785.440199] Key type lgssc registered [ 786.317518] Lustre: Echo OBD driver; http://www.lustre.org/ [ 818.761820] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 822.244465] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 835.073235] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 836.451139] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 846.233220] Lustre: Unmounted lustre-client [ 874.462451] Key type lgssc unregistered [ 874.697365] LNet: 22444:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 874.703519] LNetError: 22444:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 874.724439] LNet: Removed LNI 192.168.202.54@tcp [ 875.285896] Key type .llcrypt unregistered [ 875.287834] Key type ._llcrypt unregistered [ 883.279898] Key type ._llcrypt registered [ 883.281428] Key type .llcrypt registered [ 884.115719] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 01:28:40 (1789709320) [ 885.750271] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 885.763488] alg: No test for adler32 (adler32-zlib) [ 886.753853] Lustre: Lustre: Build Version: 2.17.58_39_g8752880 [ 887.100682] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 888.848255] Key type lgssc registered [ 889.713772] Lustre: Echo OBD driver; http://www.lustre.org/ [ 926.411539] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 930.036208] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 943.236299] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 953.864762] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 957.997141] Lustre: Unmounted lustre-client [ 996.491523] Key type lgssc unregistered [ 996.671687] LNet: 25630:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 996.677264] LNetError: 25630:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 996.689050] LNet: Removed LNI 192.168.202.54@tcp [ 997.225876] Key type .llcrypt unregistered [ 997.228860] Key type ._llcrypt unregistered [ 1006.315479] Key type ._llcrypt registered [ 1006.316945] Key type .llcrypt registered [ 1007.049727] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 01:30:43 (1789709443) [ 1008.553708] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1008.578549] alg: No test for adler32 (adler32-zlib) [ 1009.536794] Lustre: Lustre: Build Version: 2.17.58_39_g8752880 [ 1009.679224] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 1011.304504] Key type lgssc registered [ 1011.971452] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1038.208576] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1040.901044] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1050.974501] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1052.236568] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 1067.494558] Lustre: lustre-MDT0000-mdc-ffff8d11880bb000: Connection to lustre-MDT0000 (at 192.168.202.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1072.615671] Lustre: lustre-MDT0001-mdc-ffff8d11880bb000: Connection to lustre-MDT0001 (at 192.168.202.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1078.936170] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1088.992112] Lustre: 26586:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789709510/real 1789709510] req@000000003a2f45c3 x1876646373104000/t0(0) o400->MGC192.168.202.154@tcp@192.168.202.154@tcp:26/25 lens 224/224 e 0 to 1 dl 1789709526 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1089.012239] LustreError: MGC192.168.202.154@tcp: Connection to MGS (at 192.168.202.154@tcp) was lost; in progress operations using this service will fail [ 1089.239993] Lustre: Unmounted lustre-client [ 1102.015763] Key type lgssc unregistered [ 1102.172698] LNet: 29274:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1102.177249] LNetError: 29274:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1102.186420] LNet: Removed LNI 192.168.202.54@tcp [ 1102.555431] Key type .llcrypt unregistered [ 1102.557600] Key type ._llcrypt unregistered [ 1112.174590] Key type ._llcrypt registered [ 1112.176181] Key type .llcrypt registered [ 1113.110346] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 01:32:29 (1789709549) [ 1114.145920] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1114.183135] alg: No test for adler32 (adler32-zlib) [ 1115.128588] Lustre: Lustre: Build Version: 2.17.58_39_g8752880 [ 1115.308966] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 1116.929445] Key type lgssc registered [ 1117.641862] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1145.698671] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1148.847704] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1160.106192] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1162.447168] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 1172.045221] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1182.341271] Lustre: Unmounted lustre-client [ 1202.731147] Key type lgssc unregistered [ 1202.896623] LNet: 32875:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1202.901428] LNetError: 32875:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1202.914000] LNet: Removed LNI 192.168.202.54@tcp [ 1203.381832] Key type .llcrypt unregistered [ 1203.385420] Key type ._llcrypt unregistered [ 1211.164192] Key type ._llcrypt registered [ 1211.165835] Key type .llcrypt registered [ 1211.850831] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 01:34:08 (1789709648) [ 1212.874169] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1212.903134] alg: No test for adler32 (adler32-zlib) [ 1213.837810] Lustre: Lustre: Build Version: 2.17.58_39_g8752880 [ 1213.957730] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 1215.576262] Key type lgssc registered [ 1216.206398] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1241.658104] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1243.957994] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1254.095657] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1256.467830] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 1262.192485] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1263.660798] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 1274.583377] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2026-09-18 1h35m11s [ 1275.704513] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 1276.899811] Lustre: lustre-MDT0000-mdc-ffff8d11856cc000: Connection to lustre-MDT0000 (at 192.168.202.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1283.953162] LustreError: 36652:0:(lmv_obd.c:1471:lmv_statfs()) lustre-MDT0000-mdc-ffff8d11856cc000: can't stat MDS #0: rc = -19 [ 1284.704632] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 1289.126073] LustreError: MGC192.168.202.154@tcp: Connection to MGS (at 192.168.202.154@tcp) was lost; in progress operations using this service will fail [ 1289.134331] LustreError: lustre-MDT0000-mdc-ffff8d11856cc000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 1289.146985] Lustre: lustre-MDT0000-mdc-ffff8d11856cc000: Connection restored to 192.168.202.154@tcp (at 192.168.202.154@tcp) [ 1289.155260] Lustre: Evicted from MGS (at 192.168.202.154@tcp) after server handle changed from 0xfab24c3e2bbb7595 to 0xfab24c3e2bbb784a [ 1292.553069] Lustre: DEBUG MARKER: Wait for df (36652) ... [ 1293.346245] Lustre: DEBUG MARKER: done [ 1294.211179] Lustre: Unmounted lustre-client [ 1313.845686] Key type lgssc unregistered [ 1313.986895] LNet: 37662:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1313.993386] LNetError: 37662:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1314.002880] LNet: Removed LNI 192.168.202.54@tcp [ 1314.374940] Key type .llcrypt unregistered [ 1314.377407] Key type ._llcrypt unregistered [ 1328.831320] Key type ._llcrypt registered [ 1328.833186] Key type .llcrypt registered [ 1329.448974] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 01:36:06 (1789709766) [ 1330.600537] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1330.661596] alg: No test for adler32 (adler32-zlib) [ 1331.572589] Lustre: Lustre: Build Version: 2.17.58_39_g8752880 [ 1331.689709] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 1333.288233] Key type lgssc registered [ 1333.870060] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1358.874951] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1361.171928] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1370.403891] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1372.730149] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 1375.292261] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2026-09-18 1h36m52s [ 1375.981683] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1379.176778] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 1380.320831] Lustre: DEBUG MARKER: Creating a test file and stat it [ 1400.800283] Lustre: 41795:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789709818/real 1789709818] req@000000000701b352 x1876646710744832/t0(0) o101->lustre-MDT0000-mdc-ffff8d1185036000@192.168.202.154@tcp:12/10 lens 576/1152 e 0 to 1 dl 1789709838 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:1 [ 1400.812799] Lustre: lustre-MDT0000-mdc-ffff8d1185036000: Connection to lustre-MDT0000 (at 192.168.202.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1400.835606] Lustre: lustre-MDT0000-mdc-ffff8d1185036000: Connection restored to 192.168.202.154@tcp (at 192.168.202.154@tcp) [ 1421.218497] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 1422.599065] Lustre: DEBUG MARKER: done [ 1423.755522] Lustre: Unmounted lustre-client [ 1442.350591] Key type lgssc unregistered [ 1442.481333] LNet: 42673:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1442.487308] LNetError: 42673:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1442.498982] LNet: Removed LNI 192.168.202.54@tcp [ 1442.775467] Key type .llcrypt unregistered [ 1442.776596] Key type ._llcrypt unregistered [ 1456.992376] Key type ._llcrypt registered [ 1456.994034] Key type .llcrypt registered [ 1457.642900] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 01:38:14 (1789709894) [ 1458.329563] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 1459.148371] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 01:38:16 (1789709896) [ 1460.883858] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1460.910756] alg: No test for adler32 (adler32-zlib) [ 1461.816218] Lustre: Lustre: Build Version: 2.17.58_39_g8752880 [ 1461.927565] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 1463.536232] Key type lgssc registered [ 1464.059921] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1477.790872] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 01:38:34 (1789709914) [ 1492.462982] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1494.980605] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1503.782905] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1510.388933] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 1512.616837] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 1513.789766] Lustre: Unmounted lustre-client [ 1524.142133] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 1544.227102] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1546.831390] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1547.148402] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 1548.703601] Lustre: Unmounted lustre-client [ 1580.064924] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1582.854809] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1583.094379] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 1584.813569] Lustre: Unmounted lustre-client [ 1599.438122] Lustre: DEBUG MARKER: files compared the same [ 1615.775623] Key type lgssc unregistered [ 1615.905595] LNet: 50259:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1615.909079] LNetError: 50259:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1615.921097] LNet: Removed LNI 192.168.202.54@tcp [ 1616.238433] Key type .llcrypt unregistered [ 1616.239929] Key type ._llcrypt unregistered [ 1623.312536] Key type ._llcrypt registered [ 1623.313800] Key type .llcrypt registered [ 1623.974880] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 01:41:00 (1789710060) [ 1625.079646] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1625.098298] alg: No test for adler32 (adler32-zlib) [ 1626.036607] Lustre: Lustre: Build Version: 2.17.58_39_g8752880 [ 1626.185361] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 1627.800254] Key type lgssc registered [ 1628.462804] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1651.290531] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1653.733731] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1663.345607] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1671.164163] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 1676.660626] Lustre: Unmounted lustre-client [ 1696.123750] Key type lgssc unregistered [ 1696.277670] LNet: 53825:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1696.281579] LNetError: 53825:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1696.296488] LNet: Removed LNI 192.168.202.54@tcp [ 1696.616944] Key type .llcrypt unregistered [ 1696.618798] Key type ._llcrypt unregistered [ 1703.109896] Key type ._llcrypt registered [ 1703.111613] Key type .llcrypt registered [ 1703.712836] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 01:42:20 (1789710140) [ 1704.773856] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1704.781691] alg: No test for adler32 (adler32-zlib) [ 1705.697949] Lustre: Lustre: Build Version: 2.17.58_39_g8752880 [ 1705.832821] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 1707.432254] Key type lgssc registered [ 1707.917153] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1770.468169] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1784.927293] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1787.499605] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1806.815629] Key type lgssc unregistered [ 1806.977341] LNet: 57296:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1806.979995] LNetError: 57296:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1806.988405] LNet: Removed LNI 192.168.202.54@tcp [ 1807.319212] Key type .llcrypt unregistered [ 1807.321082] Key type ._llcrypt unregistered [ 1812.999550] Key type ._llcrypt registered [ 1813.000969] Key type .llcrypt registered [ 1813.487644] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 01:44:10 (1789710250) [ 1814.626597] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1814.665098] alg: No test for adler32 (adler32-zlib) [ 1815.552681] Lustre: Lustre: Build Version: 2.17.58_39_g8752880 [ 1815.662199] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 1817.264183] Key type lgssc registered [ 1817.721693] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1849.867771] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 1855.410794] Lustre: Unmounted lustre-client [ 1877.775351] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 01:45:14 (1789710314) [ 1925.185015] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1942.025036] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 1947.520804] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1947.524728] LustreError: 63737:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff8d11856cd000: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 1947.535355] Lustre: 58274:0:(llite_lib.c:4342:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.202.154@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 1957.817116] Lustre: Unmounted lustre-client [ 1993.737329] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 01:47:10 (1789710430) [ 1998.309089] Key type lgssc unregistered [ 1998.440732] LNet: 64945:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1998.444297] LNetError: 64945:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1998.453768] LNet: Removed LNI 192.168.202.54@tcp [ 1998.730858] Key type .llcrypt unregistered [ 1998.732055] Key type ._llcrypt unregistered [ 2003.362462] Key type ._llcrypt registered [ 2003.363932] Key type .llcrypt registered [ 2003.554653] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2003.564000] alg: No test for adler32 (adler32-zlib) [ 2004.447346] Lustre: Lustre: Build Version: 2.17.58_39_g8752880 [ 2004.568200] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 2006.160245] Key type lgssc registered [ 2006.631869] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2026.692452] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2029.108137] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2038.349164] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2044.898810] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 2050.508593] Lustre: Unmounted lustre-client [ 2061.086721] Key type lgssc unregistered [ 2061.220765] LNet: 67949:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2061.225652] LNetError: 67949:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2061.233974] LNet: Removed LNI 192.168.202.54@tcp [ 2061.531206] Key type .llcrypt unregistered [ 2061.532559] Key type ._llcrypt unregistered [ 2065.571434] Key type ._llcrypt registered [ 2065.573254] Key type .llcrypt registered [ 2065.748816] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2065.756987] alg: No test for adler32 (adler32-zlib) [ 2066.637952] Lustre: Lustre: Build Version: 2.17.58_39_g8752880 [ 2066.744418] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 2068.336265] Key type lgssc registered [ 2068.762207] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2121.541428] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2123.755167] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2131.759238] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2137.101214] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 2137.779858] Lustre: Unmounted lustre-client [ 2157.021686] Key type lgssc unregistered [ 2157.158392] LNet: 71358:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2157.162579] LNetError: 71358:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2157.171770] LNet: Removed LNI 192.168.202.54@tcp [ 2157.456021] Key type .llcrypt unregistered [ 2157.457227] Key type ._llcrypt unregistered [ 2163.953363] Key type ._llcrypt registered [ 2163.954678] Key type .llcrypt registered [ 2164.574827] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 01:50:01 (1789710601) [ 2165.548163] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2165.587329] alg: No test for adler32 (adler32-zlib) [ 2166.481875] Lustre: Lustre: Build Version: 2.17.58_39_g8752880 [ 2166.590705] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 2168.177115] Key type lgssc registered [ 2168.682104] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2189.564335] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2191.528376] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2198.703899] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2209.269885] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 2211.713368] Lustre: Unmounted lustre-client [ 2211.830226] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 2212.581064] Lustre: Unmounted lustre-client [ 2238.916732] Key type lgssc unregistered [ 2239.054566] LNet: 75022:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2239.057524] LNetError: 75022:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2239.068107] LNet: Removed LNI 192.168.202.54@tcp [ 2239.357788] Key type .llcrypt unregistered [ 2239.359185] Key type ._llcrypt unregistered [ 2243.790561] Key type ._llcrypt registered [ 2243.791658] Key type .llcrypt registered [ 2243.937987] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2243.945979] alg: No test for adler32 (adler32-zlib) [ 2244.823495] Lustre: Lustre: Build Version: 2.17.58_39_g8752880 [ 2244.924040] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 2246.512232] Key type lgssc registered [ 2246.995780] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2266.650260] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2268.591911] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2276.318918] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2287.099088] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 2288.866593] Lustre: Unmounted lustre-client [ 2306.456336] Key type lgssc unregistered [ 2306.570578] LNet: 78044:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2306.572790] LNetError: 78044:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2306.581822] LNet: Removed LNI 192.168.202.54@tcp [ 2306.844822] Key type .llcrypt unregistered [ 2306.846070] Key type ._llcrypt unregistered [ 2312.491319] Key type ._llcrypt registered [ 2312.492529] Key type .llcrypt registered [ 2313.029924] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 01:52:30 (1789710750) [ 2313.808367] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2313.851060] alg: No test for adler32 (adler32-zlib) [ 2314.723473] Lustre: Lustre: Build Version: 2.17.58_39_g8752880 [ 2314.826749] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 2316.424247] Key type lgssc registered [ 2316.854909] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2335.846603] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2337.881778] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2345.540336] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2356.209750] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 2362.229138] Lustre: lustre: nosquash_nids is cleared [ 2362.237588] Lustre: lustre: root_squash is set to 500:500 [ 2380.781388] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.202.54@tcp 192.168.0.[2,10]@tcp [ 2386.156963] Lustre: lustre: nosquash_nids set to 192.168.202.54/24@tcp [ 2386.793151] Lustre: Unmounted lustre-client [ 2404.185954] Key type lgssc unregistered [ 2404.305737] LNet: 82036:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2404.308598] LNetError: 82036:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2404.319638] LNet: Removed LNI 192.168.202.54@tcp [ 2404.589202] Key type .llcrypt unregistered [ 2404.590762] Key type ._llcrypt unregistered [ 2409.648834] Key type ._llcrypt registered [ 2409.649914] Key type .llcrypt registered [ 2410.134365] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 01:54:07 (1789710847) [ 2410.947371] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 2411.498744] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 01:54:08 (1789710848) [ 2412.197568] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2412.243257] alg: No test for adler32 (adler32-zlib) [ 2413.109304] Lustre: Lustre: Build Version: 2.17.58_39_g8752880 [ 2413.201262] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 2414.784184] Key type lgssc registered [ 2415.164104] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2433.160429] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2434.911409] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2442.109864] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2442.299758] Lustre: lustre: root_squash is set to 500:500 [ 2442.309317] Lustre: lustre: nosquash_nids set to 192.168.202.54/24@tcp [ 2452.691446] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 2458.677167] Lustre: Unmounted lustre-client [ 2486.149507] Key type lgssc unregistered [ 2486.312718] LNet: 85979:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2486.321898] LNetError: 85979:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2487.399887] LNet: Removed LNI 192.168.202.54@tcp [ 2487.926405] Key type .llcrypt unregistered [ 2487.928566] Key type ._llcrypt unregistered [ 2496.073846] Key type ._llcrypt registered [ 2496.075045] Key type .llcrypt registered [ 2496.701388] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 2497.538034] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 01:55:34 (1789710934) [ 2505.704921] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2505.721230] alg: No test for adler32 (adler32-zlib) [ 2506.661147] Lustre: Lustre: Build Version: 2.17.58_39_g8752880 [ 2506.810461] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 2508.408240] Key type lgssc registered [ 2509.018045] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2549.485851] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2552.266307] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2564.279445] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2603.590080] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2606.527797] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2618.200216] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2625.278613] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 2641.099594] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8d11869cc000.ost_server_uuid 50 [ 2641.873971] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8d11869cc000.ost_server_uuid in FULL state after 0 sec [ 2642.083167] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 2643.330914] Lustre: Unmounted lustre-client [ 2643.954622] Lustre: Unmounted lustre-client [ 2673.781159] Key type lgssc unregistered [ 2673.923802] LNet: 93609:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2673.928732] LNetError: 93609:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2673.941817] LNet: Removed LNI 192.168.202.54@tcp [ 2674.294407] Key type .llcrypt unregistered [ 2674.295836] Key type ._llcrypt unregistered [ 2686.627601] Key type ._llcrypt registered [ 2686.629038] Key type .llcrypt registered [ 2687.141697] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 01:58:44 (1789711124) [ 2694.049593] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2694.094310] alg: No test for adler32 (adler32-zlib) [ 2694.959410] Lustre: Lustre: Build Version: 2.17.58_39_g8752880 [ 2695.049771] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 2696.648312] Key type lgssc registered [ 2697.029962] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2726.973747] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2728.918332] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2737.306367] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2738.494679] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 2753.060982] Lustre: lustre-OST0000-osc-ffff8d118a74b000: Connection to lustre-OST0000 (at 192.168.202.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2758.050690] Lustre: lustre-MDT0000-mdc-ffff8d118a74b000: Connection to lustre-MDT0000 (at 192.168.202.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2760.160213] Lustre: 94965:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789711181/real 1789711181] req@00000000e866533d x1876648139953280/t0(0) o400->lustre-OST0000-osc-ffff8d118a74b000@192.168.202.154@tcp:28/4 lens 224/224 e 0 to 1 dl 1789711197 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2765.280549] Lustre: 94965:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789711186/real 1789711186] req@0000000085813a9d x1876648139953792/t0(0) o400->lustre-OST0000-osc-ffff8d118a74b000@192.168.202.154@tcp:28/4 lens 224/224 e 0 to 1 dl 1789711202 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2773.472165] Lustre: 94966:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789711195/real 1789711195] req@00000000035685ab x1876648139954944/t0(0) o400->MGC192.168.202.154@tcp@192.168.202.154@tcp:26/25 lens 224/224 e 0 to 1 dl 1789711211 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2773.476699] LustreError: MGC192.168.202.154@tcp: Connection to MGS (at 192.168.202.154@tcp) was lost; in progress operations using this service will fail [ 2773.493680] Lustre: Evicted from MGS (at 192.168.202.154@tcp) after server handle changed from 0xe7d176c29fc3f847 to 0xe7d176c29fc3faf5 [ 2773.499912] Lustre: MGC192.168.202.154@tcp: Connection restored to 192.168.202.154@tcp (at 192.168.202.154@tcp) [ 2775.221419] Lustre: lustre-MDT0000-mdc-ffff8d118a74b000: Connection restored to 192.168.202.154@tcp (at 192.168.202.154@tcp) [ 2775.653872] Lustre: Unmounted lustre-client [ 2793.765356] Key type lgssc unregistered [ 2793.890429] LNet: 99245:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2793.893473] LNetError: 99245:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2793.901199] LNet: Removed LNI 192.168.202.54@tcp [ 2794.197184] Key type .llcrypt unregistered [ 2794.199518] Key type ._llcrypt unregistered [ 2800.219633] Key type ._llcrypt registered [ 2800.220833] Key type .llcrypt registered [ 2800.804613] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 02:00:37 (1789711237) [ 2812.813500] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2812.822636] alg: No test for adler32 (adler32-zlib) [ 2813.721515] Lustre: Lustre: Build Version: 2.17.58_39_g8752880 [ 2813.838829] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 2815.440834] Key type lgssc registered [ 2815.872110] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2832.606670] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2869.131657] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2871.143447] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2879.478244] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2886.107772] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 2886.814603] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 2887.412278] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 02:02:04 (1789711324) [ 2946.754895] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2957.045067] Lustre: Unmounted lustre-client [ 3018.269705] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3020.281239] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3029.652417] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3048.019573] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 02:04:45 (1789711485) [ 3078.756510] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3080.584034] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3088.385284] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3094.001128] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 3095.076141] Lustre: Unmounted lustre-client [ 3109.311807] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 02:05:46 (1789711546) [ 3139.506835] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3141.421850] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3149.660588] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3149.811357] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 3150.852193] Lustre: Unmounted lustre-client [ 3176.550549] Key type lgssc unregistered [ 3176.661380] LNet: 122172:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3176.663626] LNetError: 122172:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3176.676584] LNet: Removed LNI 192.168.202.54@tcp [ 3176.922882] Key type .llcrypt unregistered [ 3176.924114] Key type ._llcrypt unregistered [ 3181.968853] Key type ._llcrypt registered [ 3181.970152] Key type .llcrypt registered [ 3182.424029] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 02:06:59 (1789711619) [ 3183.232749] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3183.249628] alg: No test for adler32 (adler32-zlib) [ 3184.122929] Lustre: Lustre: Build Version: 2.17.58_39_g8752880 [ 3184.226277] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 3185.816214] Key type lgssc registered [ 3186.249293] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3204.760487] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3206.693785] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3213.864884] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3224.566281] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 3235.293730] Lustre: Unmounted lustre-client [ 3252.545160] Key type lgssc unregistered [ 3252.663426] LNet: 125752:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3252.665876] LNetError: 125752:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3252.674768] LNet: Removed LNI 192.168.202.54@tcp [ 3252.907134] Key type .llcrypt unregistered [ 3252.908223] Key type ._llcrypt unregistered [ 3257.906511] Key type ._llcrypt registered [ 3257.907687] Key type .llcrypt registered [ 3258.404136] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 02:08:15 (1789711695) [ 3259.211809] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3259.227316] alg: No test for adler32 (adler32-zlib) [ 3260.082532] Lustre: Lustre: Build Version: 2.17.58_39_g8752880 [ 3260.173432] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 3261.760201] Key type lgssc registered [ 3262.144053] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3279.046622] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3280.811258] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3287.218115] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3297.775809] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 3302.883434] Lustre: lustre-OST0000-osc-ffff8d118d4da000: Connection to lustre-OST0000 (at 192.168.202.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3316.782681] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff8d118d4da000.ost_server_uuid 50 [ 3317.265770] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8d118d4da000.ost_server_uuid in DISCONN state after 0 sec [ 3317.767711] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 3327.083952] Lustre: Unmounted lustre-client [ 3337.855947] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 02:09:35 (1789711775) [ 3347.712682] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3349.421209] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3356.109461] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3364.187939] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3364.376847] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 3369.444487] Lustre: lustre-OST0000-osc-ffff8d118bb0a000: Connection to lustre-OST0000 (at 192.168.202.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3384.069168] Lustre: Unmounted lustre-client [ 3446.677375] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 02:11:22 (1789711882) [ 3504.295607] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3511.154785] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3539.411612] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3559.534686] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3560.119729] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 3565.546848] Lustre: lustre-OST0000-osc-ffff8d118d4dc000: Connection to lustre-OST0000 (at 192.168.202.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3589.498426] Lustre: Unmounted lustre-client [ 3637.247259] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 02:14:33 (1789712073) [ 3726.439143] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3733.346150] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3761.249732] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3833.464878] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3839.556613] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3861.234224] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3904.558754] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 3955.815517] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3958.021399] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 3959.558689] Lustre: Unmounted lustre-client [ 3996.328726] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 02:20:31 (1789712431) [ 4036.515687] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4043.890752] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4072.906643] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4106.164988] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4143.902046] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 4197.435481] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4199.921160] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 4212.285112] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4222.645225] Lustre: Unmounted lustre-client [ 4287.902508] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 02:25:23 (1789712723) [ 4319.084637] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4324.157139] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4352.471113] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4352.863437] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 4374.712472] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4376.192869] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 4393.414114] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8d118637c800.ost_server_uuid 50 [ 4395.044278] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8d118637c800.ost_server_uuid in IDLE state after 0 sec [ 4398.440698] Lustre: Unmounted lustre-client [ 4398.645021] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 4398.648501] Lustre: Skipped 3 previous similar messages [ 4398.703759] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 4399.896338] LustreError: 149305:0:(sec.c:409:import_sec_validate_get()) import 00000000ecc6eaeb (NEW) with no sec [ 4478.257087] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 02:28:34 (1789712914) [ 4510.338756] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4518.132607] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4549.975659] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4576.913586] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4577.510890] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 4577.697737] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 4587.866263] Lustre: Unmounted lustre-client [ 4587.867934] Lustre: Skipped 1 previous similar message [ 4626.427196] Key type lgssc unregistered [ 4626.667494] LNet: 152712:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4626.692820] LNetError: 152712:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4626.716217] LNet: Removed LNI 192.168.202.54@tcp [ 4627.566801] Key type .llcrypt unregistered [ 4627.571514] Key type ._llcrypt unregistered [ 4643.158874] Key type ._llcrypt registered [ 4643.160933] Key type .llcrypt registered [ 4644.500591] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 02:31:20 (1789713080) [ 4645.726242] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4645.766046] alg: No test for adler32 (adler32-zlib) [ 4646.931494] Lustre: Lustre: Build Version: 2.17.58_39_g8752880 [ 4647.273097] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 4648.912831] Key type lgssc registered [ 4650.252542] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4702.569745] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4708.245977] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4731.554435] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4752.422499] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4754.073397] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 4755.233902] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 4760.330114] LustreError: lustre-MDT0001-mdc-ffff8d1187976000: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 4760.356381] Lustre: lustre-MDT0001-mdc-ffff8d1187976000: Connection restored to 192.168.202.154@tcp (at 192.168.202.154@tcp) [ 4766.933596] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4768.648701] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 4791.310473] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 4793.624395] Lustre: Unmounted lustre-client [ 4822.820982] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 02:34:19 (1789713259) [ 4933.724641] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4941.695887] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4979.287441] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4980.701578] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 5006.818305] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5008.055627] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 5009.925924] Lustre: lustre-OST0001-osc-ffff8d1186d9d000: Connection to lustre-OST0001 (at 192.168.202.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5019.270284] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5029.599092] Lustre: Unmounted lustre-client [ 5078.333527] Key type lgssc unregistered [ 5078.612719] LNet: 162339:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5078.627490] LNetError: 162339:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5079.657355] LNet: Removed LNI 192.168.202.54@tcp [ 5080.388289] Key type .llcrypt unregistered [ 5080.391670] Key type ._llcrypt unregistered [ 5102.974190] Key type ._llcrypt registered [ 5102.976040] Key type .llcrypt registered [ 5103.753745] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 02:39:00 (1789713540) [ 5105.334957] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5105.400959] alg: No test for adler32 (adler32-zlib) [ 5106.395359] Lustre: Lustre: Build Version: 2.17.58_39_g8752880 [ 5106.616990] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 5108.312203] Key type lgssc registered [ 5109.060726] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5140.291579] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5143.498599] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5153.094962] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5155.398067] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 5193.579620] Lustre: Unmounted lustre-client [ 5216.486874] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5216.738786] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 5249.437137] Lustre: Unmounted lustre-client [ 5267.279486] Key type lgssc unregistered [ 5267.431885] LNet: 166768:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5267.435214] LNetError: 166768:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5267.448778] LNet: Removed LNI 192.168.202.54@tcp [ 5267.732397] Key type .llcrypt unregistered [ 5267.733767] Key type ._llcrypt unregistered [ 5273.290974] Key type ._llcrypt registered [ 5273.292015] Key type .llcrypt registered [ 5273.846364] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 02:41:50 (1789713710) [ 5274.653660] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5274.669765] alg: No test for adler32 (adler32-zlib) [ 5275.524962] Lustre: Lustre: Build Version: 2.17.58_39_g8752880 [ 5275.613790] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 5277.200221] Key type lgssc registered [ 5277.612475] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5297.229768] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5299.180616] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5306.072983] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5316.597865] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 5323.684118] Lustre: Unmounted lustre-client [ 5349.955799] Key type lgssc unregistered [ 5350.086698] LNet: 170510:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5350.091144] LNetError: 170510:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5350.103750] LNet: Removed LNI 192.168.202.54@tcp [ 5350.393920] Key type .llcrypt unregistered [ 5350.395504] Key type ._llcrypt unregistered [ 5354.867253] Key type ._llcrypt registered [ 5354.868278] Key type .llcrypt registered [ 5355.061654] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5355.067723] alg: No test for adler32 (adler32-zlib) [ 5355.935300] Lustre: Lustre: Build Version: 2.17.58_39_g8752880 [ 5356.039388] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 5357.624180] Key type lgssc registered [ 5358.035081] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5377.128444] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5379.135353] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5386.303714] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5396.990994] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 5400.098153] Lustre: Unmounted lustre-client [ 5418.198117] Key type lgssc unregistered [ 5418.319385] LNet: 173557:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5418.322711] LNetError: 173557:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5418.334038] LNet: Removed LNI 192.168.202.54@tcp [ 5418.624924] Key type .llcrypt unregistered [ 5418.626246] Key type ._llcrypt unregistered [ 5424.505223] Key type ._llcrypt registered [ 5424.506552] Key type .llcrypt registered [ 5425.039830] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 02:44:22 (1789713862) [ 5425.961506] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5425.967726] alg: No test for adler32 (adler32-zlib) [ 5426.846961] Lustre: Lustre: Build Version: 2.17.58_39_g8752880 [ 5426.955890] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 5428.544220] Key type lgssc registered [ 5428.994672] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5449.387108] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5451.609453] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5460.094655] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5466.618503] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 5473.943632] Lustre: Unmounted lustre-client [ 5492.098480] Key type lgssc unregistered [ 5492.243900] LNet: 177290:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5492.247260] LNetError: 177290:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5492.257495] LNet: Removed LNI 192.168.202.54@tcp [ 5492.552835] Key type .llcrypt unregistered [ 5492.555081] Key type ._llcrypt unregistered [ 5497.289257] Key type ._llcrypt registered [ 5497.290672] Key type .llcrypt registered [ 5497.466191] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5497.474086] alg: No test for adler32 (adler32-zlib) [ 5498.354528] Lustre: Lustre: Build Version: 2.17.58_39_g8752880 [ 5498.472339] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 5500.072239] Key type lgssc registered [ 5500.528153] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5521.033275] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5523.285769] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5531.062882] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5541.879860] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 5544.990989] Lustre: Unmounted lustre-client [ 5563.224702] Key type lgssc unregistered [ 5563.347754] LNet: 180337:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5563.352443] LNetError: 180337:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5563.363157] LNet: Removed LNI 192.168.202.54@tcp [ 5563.651241] Key type .llcrypt unregistered [ 5563.652830] Key type ._llcrypt unregistered [ 5569.749172] Key type ._llcrypt registered [ 5569.750375] Key type .llcrypt registered [ 5570.292347] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 02:46:47 (1789714007) [ 5578.111200] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5578.121444] alg: No test for adler32 (adler32-zlib) [ 5578.994464] Lustre: Lustre: Build Version: 2.17.58_39_g8752880 [ 5579.092410] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 5580.704207] Key type lgssc registered [ 5581.174761] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5614.432628] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5616.537218] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5625.995227] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5665.064922] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 02:48:22 (1789714102) [ 5676.743429] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5678.813964] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5686.500097] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5698.042546] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 5700.101126] Lustre: Unmounted lustre-client [ 5717.967328] Key type lgssc unregistered [ 5718.080666] LNet: 187407:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5718.084046] LNetError: 187407:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5718.094801] LNet: Removed LNI 192.168.202.54@tcp [ 5718.357483] Key type .llcrypt unregistered [ 5718.358526] Key type ._llcrypt unregistered [ 5723.407046] Key type ._llcrypt registered [ 5723.408343] Key type .llcrypt registered [ 5723.871526] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 5724.434822] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 02:49:21 (1789714161) [ 5730.529407] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5730.568421] alg: No test for adler32 (adler32-zlib) [ 5731.420489] Lustre: Lustre: Build Version: 2.17.58_39_g8752880 [ 5731.508235] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 5733.088209] Key type lgssc registered [ 5733.440816] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5766.060618] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5767.811532] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5775.587063] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5782.240807] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5783.413054] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 5789.615120] Lustre: Unmounted lustre-client [ 5850.631372] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5852.371545] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5860.186141] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5882.427117] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 02:51:59 (1789714319) [ 5882.904887] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 5883.484715] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 02:52:00 (1789714320) [ 5926.446708] Key type lgssc unregistered [ 5926.572643] LNet: 198793:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5926.576466] LNetError: 198793:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5926.587732] LNet: Removed LNI 192.168.202.54@tcp [ 5926.851652] Key type .llcrypt unregistered [ 5926.853128] Key type ._llcrypt unregistered [ 5931.780314] Key type ._llcrypt registered [ 5931.781735] Key type .llcrypt registered [ 5932.226925] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 02:52:49 (1789714369) [ 5954.459360] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5954.466582] alg: No test for adler32 (adler32-zlib) [ 5955.313980] Lustre: Lustre: Build Version: 2.17.58_39_g8752880 [ 5955.403844] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 5956.976193] Key type lgssc registered [ 5957.314712] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5971.338452] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5973.039874] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5992.546041] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6003.430673] Key type lgssc unregistered [ 6003.543306] LNet: 202505:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6003.545075] LNetError: 202505:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6003.551239] LNet: Removed LNI 192.168.202.54@tcp [ 6003.781858] Key type .llcrypt unregistered [ 6003.782971] Key type ._llcrypt unregistered [ 6008.557746] Key type ._llcrypt registered [ 6008.558639] Key type .llcrypt registered [ 6008.993728] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 02:54:06 (1789714446) [ 6009.748074] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6009.795043] alg: No test for adler32 (adler32-zlib) [ 6010.666306] Lustre: Lustre: Build Version: 2.17.58_39_g8752880 [ 6010.752931] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 6012.328168] Key type lgssc registered [ 6012.693554] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6030.873198] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6032.730985] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6041.394195] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6051.823795] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 6056.930953] Lustre: lustre-MDT0000-mdc-ffff8d1184461000: Connection to lustre-MDT0000 (at 192.168.202.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6062.050882] Lustre: lustre-MDT0001-mdc-ffff8d1184461000: Connection to lustre-MDT0001 (at 192.168.202.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6072.289879] LustreError: MGC192.168.202.154@tcp: Connection to MGS (at 192.168.202.154@tcp) was lost; in progress operations using this service will fail [ 6072.295204] Lustre: Evicted from MGS (at 192.168.202.154@tcp) after server handle changed from 0xeacfb8a527977de8 to 0xeacfb8a52797972c [ 6072.299269] Lustre: MGC192.168.202.154@tcp: Connection restored to 192.168.202.154@tcp (at 192.168.202.154@tcp) [ 6082.019325] Lustre: 205605:0:(mgc_request.c:1900:mgc_process_log()) MGC192.168.202.154@tcp: IR log lustre-cliir failed, not fatal: rc = -2 [ 6091.746860] LustreError: lustre-MDT0000-mdc-ffff8d1184461000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 6091.762338] Lustre: lustre-MDT0000-mdc-ffff8d1184461000: Connection restored to 192.168.202.154@tcp (at 192.168.202.154@tcp) [ 6094.133627] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6094.607949] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 6096.395533] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6102.971885] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 6 sec [ 6103.582094] Lustre: Unmounted lustre-client [ 6128.876719] Key type lgssc unregistered [ 6128.991464] LNet: 207474:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6128.994026] LNetError: 207474:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6129.000629] LNet: Removed LNI 192.168.202.54@tcp [ 6129.233178] Key type .llcrypt unregistered [ 6129.234236] Key type ._llcrypt unregistered [ 6133.989051] Key type ._llcrypt registered [ 6133.989944] Key type .llcrypt registered [ 6134.388080] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 02:56:11 (1789714571) [ 6135.124407] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6135.130920] alg: No test for adler32 (adler32-zlib) [ 6135.966983] Lustre: Lustre: Build Version: 2.17.58_39_g8752880 [ 6136.049781] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 6137.624285] Key type lgssc registered [ 6138.020735] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6155.679097] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6157.519654] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6179.287101] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6181.122085] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6195.403667] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6202.111835] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6229.074605] Key type lgssc unregistered [ 6229.208479] LNet: 212741:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6229.212761] LNetError: 212741:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6229.223422] LNet: Removed LNI 192.168.202.54@tcp [ 6229.498946] Key type .llcrypt unregistered [ 6229.500668] Key type ._llcrypt unregistered [ 6250.243251] Key type ._llcrypt registered [ 6250.244506] Key type .llcrypt registered [ 6250.720958] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 02:58:07 (1789714687) [ 6262.966390] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6263.014223] alg: No test for adler32 (adler32-zlib) [ 6263.868165] Lustre: Lustre: Build Version: 2.17.58_39_g8752880 [ 6263.957641] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 6265.544201] Key type lgssc registered [ 6265.939061] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6295.407300] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6297.265915] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6305.355127] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6335.046095] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 02:59:32 (1789714772) [ 6337.468672] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 02:59:34 (1789714774) [ 6348.191900] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6349.943492] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6356.589547] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6367.217295] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 6370.405417] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [ 6371.222414] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 6371.750759] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 6372.328217] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [ 6377.443735] Lustre: lustre-MDT0000-mdc-ffff8d1184997800: Connection to lustre-MDT0000 (at 192.168.202.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6387.682187] LustreError: MGC192.168.202.154@tcp: Connection to MGS (at 192.168.202.154@tcp) was lost; in progress operations using this service will fail [ 6387.689362] Lustre: Evicted from MGS (at 192.168.202.154@tcp) after server handle changed from 0xd767c27e2ed3ef2b to 0xd767c27e2ed3f1bd [ 6387.692661] Lustre: MGC192.168.202.154@tcp: Connection restored to 192.168.202.154@tcp (at 192.168.202.154@tcp) [ 6391.483210] Lustre: lustre-MDT0000-mdc-ffff8d1184997800: Connection restored to 192.168.202.154@tcp (at 192.168.202.154@tcp) [ 6393.343227] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6393.873044] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 6395.766780] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [ 6397.922799] Lustre: lustre-MDT0000-mdc-ffff8d1184997800: Connection to lustre-MDT0000 (at 192.168.202.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6413.280139] Lustre: 214623:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789714835/real 1789714835] req@00000000781bb06e x1876651882331392/t0(0) o400->MGC192.168.202.154@tcp@192.168.202.154@tcp:26/25 lens 224/224 e 0 to 1 dl 1789714851 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 6413.282806] LustreError: MGC192.168.202.154@tcp: Connection to MGS (at 192.168.202.154@tcp) was lost; in progress operations using this service will fail [ 6413.293155] Lustre: Evicted from MGS (at 192.168.202.154@tcp) after server handle changed from 0xd767c27e2ed3f1bd to 0xd767c27e2ed3f4cd [ 6413.295823] Lustre: MGC192.168.202.154@tcp: Connection restored to 192.168.202.154@tcp (at 192.168.202.154@tcp) [ 6416.668340] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6417.171381] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 6418.688756] Lustre: Unmounted lustre-client [ 6451.044245] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6452.898298] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6459.856117] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6470.643097] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 6471.030539] Lustre: Unmounted lustre-client [ 6488.586599] Key type lgssc unregistered [ 6488.710440] LNet: 225175:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6488.712611] LNetError: 225175:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6488.719412] LNet: Removed LNI 192.168.202.54@tcp [ 6489.009989] Key type .llcrypt unregistered [ 6489.011381] Key type ._llcrypt unregistered [ 6494.305974] Key type ._llcrypt registered [ 6494.307113] Key type .llcrypt registered [ 6494.777589] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 03:02:11 (1789714931) [ 6496.160847] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6496.211082] alg: No test for adler32 (adler32-zlib) [ 6497.070135] Lustre: Lustre: Build Version: 2.17.58_39_g8752880 [ 6497.162951] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 6498.744187] Key type lgssc registered [ 6499.099056] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6516.794778] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6518.625928] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6525.381314] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6536.177663] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 6539.157822] Lustre: Unmounted lustre-client [ 6572.218432] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6574.169958] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6581.176525] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6591.991733] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 6598.076795] Lustre: Unmounted lustre-client [ 6629.095532] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6630.739252] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6636.710404] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6647.282459] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 6647.653136] Lustre: Unmounted lustre-client [ 6673.701193] Key type lgssc unregistered [ 6673.822606] LNet: 232188:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6673.826356] LNetError: 232188:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6673.838639] LNet: Removed LNI 192.168.202.54@tcp [ 6674.134715] Key type .llcrypt unregistered [ 6674.135802] Key type ._llcrypt unregistered [ 6679.574782] Key type ._llcrypt registered [ 6679.575830] Key type .llcrypt registered [ 6680.029398] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 03:05:17 (1789715117) [ 6681.231957] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6681.247286] alg: No test for adler32 (adler32-zlib) [ 6682.110160] Lustre: Lustre: Build Version: 2.17.58_39_g8752880 [ 6682.206742] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 6683.792247] Key type lgssc registered [ 6684.197172] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6700.063658] Key type lgssc unregistered [ 6700.169395] LNet: 234498:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6700.172030] LNetError: 234498:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6700.182310] LNet: Removed LNI 192.168.202.54@tcp [ 6700.424657] Key type .llcrypt unregistered [ 6700.425757] Key type ._llcrypt unregistered [ 6709.739787] Key type ._llcrypt registered [ 6709.748056] Key type .llcrypt registered [ 6709.885862] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6709.892266] alg: No test for adler32 (adler32-zlib) [ 6710.753669] Lustre: Lustre: Build Version: 2.17.58_39_g8752880 [ 6710.848460] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 6712.440217] Key type lgssc registered [ 6712.850845] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6742.638591] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6744.584797] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6752.778833] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6778.540185] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [ 6779.087766] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 03:06:56 (1789715216) [ 6789.118357] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6790.781810] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6797.025295] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6804.226223] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6805.382112] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 6810.596469] Lustre: lustre-OST0001-osc-ffff8d119062a800: Connection to lustre-OST0001 (at 192.168.202.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6812.945368] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 6823.225728] Lustre: Unmounted lustre-client [ 6855.129893] Key type lgssc unregistered [ 6855.244601] LNet: 241580:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6855.246850] LNetError: 241580:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6855.254798] LNet: Removed LNI 192.168.202.54@tcp [ 6855.494800] Key type .llcrypt unregistered [ 6855.496104] Key type ._llcrypt unregistered [ 6865.757229] Key type ._llcrypt registered [ 6865.758140] Key type .llcrypt registered [ 6866.209735] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 03:08:23 (1789715303) [ 6870.794527] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6870.819574] alg: No test for adler32 (adler32-zlib) [ 6871.685870] Lustre: Lustre: Build Version: 2.17.58_39_g8752880 [ 6871.776864] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 6873.360236] Key type lgssc registered [ 6873.752613] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6891.833350] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6893.602395] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6904.810395] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 03:09:02 (1789715342) [ 6914.890526] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6916.544412] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6922.647708] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6923.799459] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 6928.268686] Lustre: Unmounted lustre-client [ 6977.951638] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6979.794324] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7008.930946] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7019.507132] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 7019.947949] Lustre: Unmounted lustre-client [ 7033.304145] Key type lgssc unregistered [ 7033.424709] LNet: 249233:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7033.428200] LNetError: 249233:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7033.437887] LNet: Removed LNI 192.168.202.54@tcp [ 7033.712640] Key type .llcrypt unregistered [ 7033.714058] Key type ._llcrypt unregistered [ 7043.043656] Key type ._llcrypt registered [ 7043.045683] Key type .llcrypt registered [ 7043.257753] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7043.262686] alg: No test for adler32 (adler32-zlib) [ 7044.130309] Lustre: Lustre: Build Version: 2.17.58_39_g8752880 [ 7044.225323] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 7045.808193] Key type lgssc registered [ 7046.210908] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7075.822939] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7077.744281] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7086.122535] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7107.606086] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [ 7108.141940] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 03:12:25 (1789715545) [ 7118.699510] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7120.617323] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7127.638497] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7138.810757] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 7139.704910] Lustre: Unmounted lustre-client [ 7156.874889] Key type lgssc unregistered [ 7156.988401] LNet: 255836:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7156.990806] LNetError: 255836:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7156.999749] LNet: Removed LNI 192.168.202.54@tcp [ 7157.250505] Key type .llcrypt unregistered [ 7157.251509] Key type ._llcrypt unregistered [ 7162.557486] Key type ._llcrypt registered [ 7162.558611] Key type .llcrypt registered [ 7163.000798] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [ 7163.540785] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 03:13:20 (1789715600) [ 7166.451187] Key type .llcrypt unregistered [ 7166.452310] Key type ._llcrypt unregistered [ 7169.177416] Key type ._llcrypt registered [ 7169.178357] Key type .llcrypt registered [ 7169.393157] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7169.408824] alg: No test for adler32 (adler32-zlib) [ 7170.267653] Lustre: Lustre: Build Version: 2.17.58_39_g8752880 [ 7170.365040] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 7171.952248] Key type lgssc registered [ 7172.383191] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7191.031135] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7200.753704] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 7201.250150] Lustre: Unmounted lustre-client [ 7218.459678] Key type lgssc unregistered [ 7218.566495] LNet: 259270:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7218.568610] LNetError: 259270:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7218.576691] LNet: Removed LNI 192.168.202.54@tcp [ 7218.798872] Key type .llcrypt unregistered [ 7218.800149] Key type ._llcrypt unregistered [ 7223.380009] Key type ._llcrypt registered [ 7223.380846] Key type .llcrypt registered [ 7223.826650] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 03:14:21 (1789715661) [ 7224.448743] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7224.458691] alg: No test for adler32 (adler32-zlib) [ 7225.315166] Lustre: Lustre: Build Version: 2.17.58_39_g8752880 [ 7225.432681] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 7227.024207] Key type lgssc registered [ 7227.391482] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7307.279633] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7343.000489] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7347.158595] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7349.658691] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 7351.936668] Lustre: Unmounted lustre-client [ 7370.025090] Key type lgssc unregistered [ 7370.202072] LNet: 262817:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7370.207605] LNetError: 262817:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7370.218967] LNet: Removed LNI 192.168.202.54@tcp [ 7370.657386] Key type .llcrypt unregistered [ 7370.659318] Key type ._llcrypt unregistered [ 7380.176632] Key type ._llcrypt registered [ 7380.178948] Key type .llcrypt registered [ 7381.095669] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 03:16:57 (1789715817) [ 7382.378031] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7382.406430] alg: No test for adler32 (adler32-zlib) [ 7383.343753] Lustre: Lustre: Build Version: 2.17.58_39_g8752880 [ 7383.446439] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 7385.056213] Key type lgssc registered [ 7385.794347] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7417.464294] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7420.668246] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7432.480642] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7433.931064] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 7439.335778] Lustre: lustre-MDT0000-mdc-ffff8d1185519800: Connection to lustre-MDT0000 (at 192.168.202.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7442.691132] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7442.721811] LustreError: 265964:0:(file.c:6167:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [ 7442.959818] LustreError: MGC192.168.202.154@tcp: Connection to MGS (at 192.168.202.154@tcp) was lost; in progress operations using this service will fail [ 7443.020057] Lustre: Unmounted lustre-client [ 7458.380590] Key type lgssc unregistered [ 7458.545983] LNet: 266376:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7458.555408] LNetError: 266376:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7458.568904] LNet: Removed LNI 192.168.202.54@tcp [ 7459.033452] Key type .llcrypt unregistered [ 7459.036435] Key type ._llcrypt unregistered [ 7470.697430] Key type ._llcrypt registered [ 7470.699315] Key type .llcrypt registered [ 7471.640324] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 03:18:28 (1789715908) [ 7473.127444] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7473.156774] alg: No test for adler32 (adler32-zlib) [ 7474.195527] Lustre: Lustre: Build Version: 2.17.58_39_g8752880 [ 7474.389926] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 7476.024291] Key type lgssc registered [ 7476.932665] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7510.691798] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7514.849791] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7528.133587] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7539.711561] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 7544.807560] Lustre: lustre-MDT0001-mdc-ffff8d119062b000: Connection to lustre-MDT0001 (at 192.168.202.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7548.887899] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 7551.040214] Lustre: Unmounted lustre-client [ 7566.176367] Key type lgssc unregistered [ 7566.303561] LNet: 269950:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7566.308056] LNetError: 269950:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7566.316675] LNet: Removed LNI 192.168.202.54@tcp [ 7566.629237] Key type .llcrypt unregistered [ 7566.630706] Key type ._llcrypt unregistered [ 7575.314718] Key type ._llcrypt registered [ 7575.315760] Key type .llcrypt registered [ 7576.149318] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 03:20:12 (1789716012) [ 7586.751201] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7586.779814] alg: No test for adler32 (adler32-zlib) [ 7587.728538] Lustre: Lustre: Build Version: 2.17.58_39_g8752880 [ 7587.863044] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 7589.472215] Key type lgssc registered [ 7590.284506] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7655.084759] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7656.393559] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 7685.092950] Lustre: lustre-MDT0001-mdc-ffff8d1185166800: Connection to lustre-MDT0001 (at 192.168.202.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7689.294218] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7699.606904] Lustre: Unmounted lustre-client [ 7737.475956] Key type lgssc unregistered [ 7737.685085] LNet: 275722:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7737.689336] LNetError: 275722:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7737.704847] LNet: Removed LNI 192.168.202.54@tcp [ 7738.250537] Key type .llcrypt unregistered [ 7738.252867] Key type ._llcrypt unregistered [ 7750.180517] Key type ._llcrypt registered [ 7750.183258] Key type .llcrypt registered [ 7751.328332] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 03:23:07 (1789716187) [ 7752.845665] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [ 7754.150559] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 03:23:10 (1789716190) [ 7755.312957] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [ 7756.626442] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 03:23:13 (1789716193) [ 7757.710478] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [ 7758.719434] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 03:23:15 (1789716195) [ 7759.707886] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [ 7760.893450] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 03:23:17 (1789716197) [ 7761.914426] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [ 7763.191194] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 03:23:19 (1789716199) [ 7769.092448] Key type .llcrypt unregistered [ 7769.094357] Key type ._llcrypt unregistered [ 7774.205406] Key type ._llcrypt registered [ 7774.208077] Key type .llcrypt registered [ 7774.480800] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7774.489533] alg: No test for adler32 (adler32-zlib) [ 7775.464858] Lustre: Lustre: Build Version: 2.17.58_39_g8752880 [ 7775.628332] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 7777.266076] Key type lgssc registered [ 7778.177408] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7825.993989] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7829.243515] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7842.889253] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7844.246042] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 7845.514672] Lustre: Unmounted lustre-client [ 7873.944360] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 03:25:10 (1789716310) [ 7895.868649] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7899.709144] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7925.719391] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7926.062951] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 7926.880844] Lustre: Unmounted lustre-client [ 7962.990254] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 03:26:39 (1789716399) [ 7988.640329] LNet: 1 local NIs in recovery (showing 1): 192.168.202.54@tcp [ 8571.210413] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8574.699710] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8597.535446] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8649.059958] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 03:38:06 (1789717086) [ 8654.204460] Key type lgssc unregistered [ 8654.347653] LNet: 289227:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8654.351548] LNetError: 289227:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8654.360212] LNet: Removed LNI 192.168.202.54@tcp [ 8654.657137] Key type .llcrypt unregistered [ 8654.658467] Key type ._llcrypt unregistered [ 8722.854719] Key type ._llcrypt registered [ 8722.873666] Key type .llcrypt registered [ 8723.010380] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8723.020803] alg: No test for adler32 (adler32-zlib) [ 8723.943931] Lustre: Lustre: Build Version: 2.17.58_39_g8752880 [ 8724.069436] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 8725.672719] Key type lgssc registered [ 8726.196292] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8767.841228] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8770.194591] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8780.283597] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8810.031476] Key type lgssc unregistered [ 8810.195919] LNet: 292917:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8810.202276] LNetError: 292917:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8810.211676] LNet: Removed LNI 192.168.202.54@tcp [ 8810.559420] Key type .llcrypt unregistered [ 8810.562272] Key type ._llcrypt unregistered [ 8841.067612] Key type ._llcrypt registered [ 8841.068979] Key type .llcrypt registered [ 8841.610514] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 03:41:18 (1789717278) [ 8849.861297] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8849.871625] alg: No test for adler32 (adler32-zlib) [ 8850.765227] Lustre: Lustre: Build Version: 2.17.58_39_g8752880 [ 8850.885369] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 8852.504779] Key type lgssc registered [ 8853.050406] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8872.741063] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 03:41:49 (1789717309) [ 8877.301324] Key type lgssc unregistered [ 8877.442520] LNet: 296459:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8877.446077] LNetError: 296459:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8877.455609] LNet: Removed LNI 192.168.202.54@tcp [ 8877.761732] Key type .llcrypt unregistered [ 8877.763345] Key type ._llcrypt unregistered [ 8882.226058] Key type ._llcrypt registered [ 8882.227619] Key type .llcrypt registered [ 8882.363829] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8882.369241] alg: No test for adler32 (adler32-zlib) [ 8883.240087] Lustre: Lustre: Build Version: 2.17.58_39_g8752880 [ 8883.345517] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 8884.952164] Key type lgssc registered [ 8885.404569] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8909.339934] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8911.326504] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8920.692567] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9017.989317] LNet: Added LNI 192.168.202.54@tcp50 [8/256/0/180] [ 9018.919386] LNet: Removed LNI 192.168.202.54@tcp [ 9020.465971] Lustre: 300810:0:(ldlm_lib.c:561:client_obd_setup()) lustre-MDT0000-mdc-ffff8d118a74a800: config has no valid NIDs, force dynamic NIDs [ 9021.567713] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 9021.646182] Lustre: Unmounted lustre-client [ 9022.819503] Key type lgssc unregistered [ 9023.008360] LNet: 301069:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9023.013121] LNetError: 301069:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9023.021412] LNet: Removed LNI 192.168.202.54@tcp50 [ 9023.294623] Key type .llcrypt unregistered [ 9023.295877] Key type ._llcrypt unregistered [ 9033.975360] Key type ._llcrypt registered [ 9033.976442] Key type .llcrypt registered [ 9034.177598] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9034.184305] alg: No test for adler32 (adler32-zlib) [ 9035.075339] Lustre: Lustre: Build Version: 2.17.58_39_g8752880 [ 9035.192756] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 9036.808247] Key type lgssc registered [ 9037.287235] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9066.353752] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9068.564569] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9071.380049] LNet: Added LNI 192.168.202.54@tcp50 [8/256/0/180] [ 9071.694670] LNet: Removed LNI 192.168.202.54@tcp [ 9072.752101] Lustre: 303746:0:(ldlm_lib.c:561:client_obd_setup()) lustre-MDT0000-mdc-ffff8d1185161800: config has no valid NIDs, force dynamic NIDs [ 9073.814592] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 9074.023189] Lustre: Unmounted lustre-client [ 9101.756756] Key type lgssc unregistered [ 9101.915566] LNet: 304614:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9101.920474] LNetError: 304614:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9101.930597] LNet: Removed LNI 192.168.202.54@tcp50 [ 9102.177793] Key type .llcrypt unregistered [ 9102.179832] Key type ._llcrypt unregistered [ 9106.033717] Key type ._llcrypt registered [ 9106.035311] Key type .llcrypt registered [ 9106.523962] Lustre: DEBUG MARKER: == conf-sanity test 73f: mgc register before connect ===== 03:45:43 (1789717543) [ 9113.318687] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9113.361057] alg: No test for adler32 (adler32-zlib) [ 9114.242292] Lustre: Lustre: Build Version: 2.17.58_39_g8752880 [ 9114.341880] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 9115.936257] Key type lgssc registered [ 9116.389040] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9151.512052] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9153.544725] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9162.674419] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9182.161814] Key type lgssc unregistered [ 9182.286638] LNet: 309345:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9182.289598] LNetError: 309345:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9182.297458] LNet: Removed LNI 192.168.202.54@tcp [ 9182.554596] Key type .llcrypt unregistered [ 9182.556036] Key type ._llcrypt unregistered [ 9186.608374] Key type ._llcrypt registered [ 9186.610021] Key type .llcrypt registered [ 9187.188278] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 03:47:04 (1789717624) [ 9188.175845] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9188.204115] alg: No test for adler32 (adler32-zlib) [ 9189.065903] Lustre: Lustre: Build Version: 2.17.58_39_g8752880 [ 9189.163380] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 9190.744244] Key type lgssc registered [ 9191.154660] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9212.112422] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9214.181964] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9222.073635] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9227.779547] Lustre: Mounted lustre-client - version 2.17.58_39_g8752880 [ 9251.173708] Lustre: Mounted fs15246-client - version 2.17.58_39_g8752880 [ 9256.730133] Lustre: Unmounted fs15246-client [ 9270.401082] Lustre: Unmounted lustre-client [ 9288.731305] Key type lgssc unregistered [ 9288.856391] LNet: 314179:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9288.860409] LNetError: 314179:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9288.868909] LNet: Removed LNI 192.168.202.54@tcp [ 9289.162869] Key type .llcrypt unregistered [ 9289.164229] Key type ._llcrypt unregistered [ 9293.301622] Key type ._llcrypt registered [ 9293.302585] Key type .llcrypt registered [ 9293.855150] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 03:48:50 (1789717730) [ 9294.653035] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9294.666798] alg: No test for adler32 (adler32-zlib) [ 9295.596307] Lustre: Lustre: Build Version: 2.17.58_39_g8752880 [ 9295.721566] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 9297.328332] Key type lgssc registered [ 9297.847464] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9352.662257] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9354.626737] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9363.403323] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9406.381760] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 9175 sec ========== 03:50:43 (1789717843) [ 9406.990982] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 03:50:44 (1789717844) === [ 9408.268170] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 03:50:45 (1789717845) === [ 9421.231647] Key type lgssc unregistered [ 9421.370405] LNet: 323403:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9421.372733] LNetError: 323403:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9421.381479] LNet: Removed LNI 192.168.202.54@tcp [ 9421.673804] Key type .llcrypt unregistered [ 9421.675423] Key type ._llcrypt unregistered