[ 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 520556411 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 2608MB 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.001016] APIC: Switch to symmetric I/O mode setup [ 0.002299] x2apic enabled [ 0.003007] Switched APIC routing to physical x2apic. [ 0.005010] kvm-guest: setup PV IPIs [ 0.007384] ..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.008028] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009014] pid_max: default: 32768 minimum: 301 [ 0.010139] LSM: Security Framework initializing [ 0.011044] Yama: becoming mindful. [ 0.012029] SELinux: Initializing. [ 0.013058] *** VALIDATE selinux *** [ 0.020653] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.025180] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.026170] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.027094] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.028117] *** VALIDATE tmpfs *** [ 0.029428] *** VALIDATE proc *** [ 0.030216] *** VALIDATE cgroup *** [ 0.031008] *** VALIDATE cgroup2 *** [ 0.033091] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.034139] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.035006] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.036028] Spectre V2 : User space: Vulnerable [ 0.037005] Speculative Store Bypass: Vulnerable [ 0.039765] debug: unmapping init [mem 0xffffffff85859000-0xffffffff85860fff] [ 0.041885] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.042685] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.043017] ... version: 2 [ 0.043983] ... bit width: 48 [ 0.044011] ... generic registers: 4 [ 0.044871] ... value mask: 0000ffffffffffff [ 0.045009] ... max period: 00007fffffffffff [ 0.046008] ... fixed-purpose events: 3 [ 0.046991] ... event mask: 000000070000000f [ 0.047364] rcu: Hierarchical SRCU implementation. [ 0.049492] smp: Bringing up secondary CPUs ... [ 0.050599] x86: Booting SMP configuration: [ 0.051025] .... node #0, CPUs: #1 #2 #3 [ 0.061213] smp: Brought up 1 node, 4 CPUs [ 0.063009] smpboot: Max logical packages: 1 [ 0.064009] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.095054] node 0 deferred pages initialised in 29ms [ 0.098441] devtmpfs: initialized [ 0.099294] x86/mm: Memory block size: 128MB [ 0.101718] gcov: version magic: 0x41383552 [ 0.103302] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.104073] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.105345] pinctrl core: initialized pinctrl subsystem [ 0.106174] [ 0.106746] ************************************************************* [ 0.107011] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.108011] ** ** [ 0.109009] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.110010] ** ** [ 0.111011] ** This means that this kernel is built to expose internal ** [ 0.112008] ** IOMMU data structures, which may compromise security on ** [ 0.113011] ** your system. ** [ 0.114011] ** ** [ 0.115009] ** If you see this message and you are not debugging the ** [ 0.116008] ** kernel, report this immediately to your vendor! ** [ 0.117008] ** ** [ 0.118009] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.119009] ************************************************************* [ 0.120556] NET: Registered protocol family 16 [ 0.122503] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.124058] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.127049] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.130217] cpuidle: using governor menu [ 0.131879] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.135842] PCI: Using configuration type 1 for base access [ 0.138169] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.149138] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.153028] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.160071] cryptd: max_cpu_qlen set to 1000 [ 0.161239] ACPI: Added _OSI(Module Device) [ 0.162015] ACPI: Added _OSI(Processor Device) [ 0.163008] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.164009] ACPI: Added _OSI(Processor Aggregator Device) [ 0.168728] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.173264] ACPI: Interpreter enabled [ 0.174052] ACPI: PM: (supports S0 S3 S4 S5) [ 0.175010] ACPI: Using IOAPIC for interrupt routing [ 0.177278] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.180979] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.192349] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.194035] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.196016] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.199088] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.203655] acpiphp: Slot [2] registered [ 0.204117] acpiphp: Slot [5] registered [ 0.205122] acpiphp: Slot [6] registered [ 0.207157] acpiphp: Slot [3] registered [ 0.208085] acpiphp: Slot [4] registered [ 0.209240] acpiphp: Slot [7] registered [ 0.211069] acpiphp: Slot [8] registered [ 0.212062] acpiphp: Slot [9] registered [ 0.213063] acpiphp: Slot [10] registered [ 0.214153] acpiphp: Slot [11] registered [ 0.216070] acpiphp: Slot [12] registered [ 0.217070] acpiphp: Slot [13] registered [ 0.218182] acpiphp: Slot [14] registered [ 0.219145] acpiphp: Slot [15] registered [ 0.221062] acpiphp: Slot [16] registered [ 0.222141] acpiphp: Slot [17] registered [ 0.223082] acpiphp: Slot [18] registered [ 0.225075] acpiphp: Slot [19] registered [ 0.226076] acpiphp: Slot [20] registered [ 0.227073] acpiphp: Slot [21] registered [ 0.228133] acpiphp: Slot [22] registered [ 0.230161] acpiphp: Slot [23] registered [ 0.231081] acpiphp: Slot [24] registered [ 0.232000] acpiphp: Slot [25] registered [ 0.232073] acpiphp: Slot [26] registered [ 0.234073] acpiphp: Slot [27] registered [ 0.235114] acpiphp: Slot [28] registered [ 0.236111] acpiphp: Slot [29] registered [ 0.238065] acpiphp: Slot [30] registered [ 0.239119] acpiphp: Slot [31] registered [ 0.240055] PCI host bridge to bus 0000:00 [ 0.242016] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.244043] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.246017] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.248047] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.250016] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.253047] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.254234] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.258079] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.260365] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.268029] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.272052] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.274018] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.276019] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.279031] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.281612] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.283868] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.285053] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.286000] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.291014] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.300014] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.304014] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.309290] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.317016] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.322013] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.332017] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.341425] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.358020] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.365018] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.383018] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.395425] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.398468] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.402436] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.403335] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.405204] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.408041] iommu: Default domain type: Passthrough [ 0.409188] SCSI subsystem initialized [ 0.410225] ACPI: bus type USB registered [ 0.411100] usbcore: registered new interface driver usbfs [ 0.412055] usbcore: registered new interface driver hub [ 0.414123] usbcore: registered new device driver usb [ 0.415147] pps_core: LinuxPPS API ver. 1 registered [ 0.416006] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.419066] PTP clock support registered [ 0.423140] EDAC MC: Ver: 3.0.0 [ 0.425647] PCI: Using ACPI for IRQ routing [ 0.427919] NetLabel: Initializing [ 0.428007] NetLabel: domain hash size = 128 [ 0.430015] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.432180] NetLabel: unlabeled traffic allowed by default [ 0.435126] vgaarb: loaded [ 0.436324] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.439018] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.445101] clocksource: Switched to clocksource kvm-clock [ 0.588687] VFS: Disk quotas dquot_6.6.0 [ 0.590412] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.593026] *** VALIDATE ramfs *** [ 0.594058] *** VALIDATE hugetlbfs *** [ 0.595472] pnp: PnP ACPI init [ 0.598473] pnp: PnP ACPI: found 6 devices [ 0.613421] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.617187] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.619738] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.622276] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.624215] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.626077] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.628567] NET: Registered protocol family 2 [ 0.631117] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.636874] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.640723] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.646178] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.649877] TCP: Hash tables configured (established 65536 bind 65536) [ 0.652809] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.656372] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.658655] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.661155] NET: Registered protocol family 1 [ 0.663608] RPC: Registered named UNIX socket transport module. [ 0.667053] RPC: Registered udp transport module. [ 0.668625] RPC: Registered tcp transport module. [ 0.670428] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.672786] NET: Registered protocol family 44 [ 0.674484] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.676287] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.678078] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.680672] PCI: CLS 0 bytes, default 64 [ 0.682452] Unpacking initramfs... [ 2.337179] debug: unmapping init [mem 0xffff90ccbcc64000-0xffff90ccbffcffff] [ 2.341216] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.342930] software IO TLB: mapped [mem 0x00000000b8c64000-0x00000000bcc64000] (64MB) [ 2.345597] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.885912] Initialise system trusted keyrings [ 2.888227] Key type blacklist registered [ 2.891620] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.905095] zbud: loaded [ 2.907805] *** VALIDATE nfs *** [ 2.908803] *** VALIDATE nfs4 *** [ 2.910206] pstore: using deflate compression [ 2.913244] Platform Keyring initialized [ 3.038520] NET: Registered protocol family 38 [ 3.040145] Key type asymmetric registered [ 3.041563] Asymmetric key parser 'x509' registered [ 3.043589] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 3.047240] io scheduler mq-deadline registered [ 3.048724] io scheduler kyber registered [ 3.050614] io scheduler bfq registered [ 3.052583] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 3.055747] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 3.058459] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 3.063361] ACPI: Power Button [PWRF] [ 3.069478] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.080819] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.095183] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.125847] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.155594] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.160721] Non-volatile memory driver v1.3 [ 3.162444] Linux agpgart interface v0.103 [ 3.214582] virtio_blk virtio1: [vda] 145160 512-byte logical blocks (74.3 MB/70.9 MiB) [ 3.220506] vda: detected capacity change from 0 to 74321920 [ 3.252232] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.254583] vdb: detected capacity change from 0 to 1073741824 [ 3.260296] libphy: Fixed MDIO Bus: probed [ 3.264568] usbcore: registered new interface driver usbserial_generic [ 3.266574] usbserial: USB Serial support registered for generic [ 3.268655] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.273264] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.275300] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.277977] mousedev: PS/2 mouse device common for all mice [ 3.280827] rtc_cmos 00:05: RTC can wake from S4 [ 3.284321] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.284990] rtc_cmos 00:05: registered as rtc0 [ 3.292206] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.295590] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.298910] intel_pstate: CPU model not supported [ 3.304408] hid: raw HID events driver (C) Jiri Kosina [ 3.307484] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.310228] usbcore: registered new interface driver usbhid [ 3.316385] usbhid: USB HID core driver [ 3.318596] drop_monitor: Initializing network drop monitor service [ 3.321438] Initializing XFRM netlink socket [ 3.323362] NET: Registered protocol family 10 [ 3.326492] Segment Routing with IPv6 [ 3.328055] NET: Registered protocol family 17 [ 3.329847] mpls_gso: MPLS GSO support [ 3.334458] RAS: Correctable Errors collector initialized. [ 3.336286] AVX version of gcm_enc/dec engaged. [ 3.337570] AES CTR mode by8 optimization enabled [ 3.421740] sched_clock: Marking stable (3421595950, 0)->(4396636086, -975040136) [ 3.425445] registered taskstats version 1 [ 3.427307] Loading compiled-in X.509 certificates [ 3.429341] zswap: loaded using pool lzo/zbud [ 3.464827] Key type big_key registered [ 3.484320] Key type encrypted registered [ 3.486306] ima: No TPM chip found, activating TPM-bypass! [ 3.488658] ima: Allocated hash algorithm: sha1 [ 3.490527] ima: No architecture policies found [ 3.492400] evm: Initialising EVM extended attributes: [ 3.494533] evm: security.selinux [ 3.495821] evm: security.ima [ 3.497143] evm: security.capability [ 3.498540] evm: HMAC attrs: 0x1 [ 3.501320] rtc_cmos 00:05: setting system clock to 2026-06-25 04:15:39 UTC (1782360939) [ 3.508626] debug: unmapping init [mem 0xffffffff86803000-0xffffffff869fffff] [ 3.512420] debug: unmapping init [mem 0xffffffff85582000-0xffffffff85858fff] [ 3.523234] Write protecting the kernel read-only data: 28672k [ 3.534022] debug: unmapping init [mem 0xffffffff83c03000-0xffffffff83dfffff] [ 3.537842] debug: unmapping init [mem 0xffffffff84514000-0xffffffff845fffff] [ 3.580300] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 3.590392] systemd[1]: Detected virtualization kvm. [ 3.592356] systemd[1]: Detected architecture x86-64. [ 3.594714] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.671981] systemd[1]: No hostname configured. [ 3.673618] systemd[1]: Set hostname to . [ 3.675589] random: systemd: uninitialized urandom read (16 bytes read) [ 3.677983] systemd[1]: Initializing machine ID from random generator. [ 3.728743] random: ln: uninitialized urandom read (6 bytes read) [ 3.928451] random: systemd: uninitialized urandom read (16 bytes read) [ 3.931577] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ 3.936495] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. [ 3.942970] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. Starting Setup Virtual Console... [ OK ] Started Memstrack Anylazing Service. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on Journal Socket (/dev/log). Starting Journal Service... [ OK ] Listening on udev Control Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Timers. [ OK ] Reached target Initrd Root Device. Starting Apply Kernel Variables... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Slices. Starting Create Volatile Files and Directories... [ OK ] Reached target Local Encrypted Volumes. [ OK ] Started Setup Virtual Console. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create Volatile Files and Directories. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 5.173908] device-mapper: uevent: version 1.0.3 [ 5.176477] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 6.253472] virtio_net virtio0 ens2: renamed from eth0 [ 6.348099] scsi host0: ata_piix [ 6.358096] scsi host1: ata_piix [ 6.359275] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 6.361869] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 7.066754] hrtimer: interrupt took 5634541 ns [ 12.007889] random: crng init done [ 12.010805] random: 7 urandom warning(s) missed due to ratelimiting [ 15.000987] dracut-initqueue[592]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 15.870153] 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... Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Timers. [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped target Swap. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped target Paths. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped target Slices. [ OK ] Stopped target Sockets. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 17.660346] printk: systemd: 26 output lines suppressed due to ratelimiting [ 18.073419] SELinux: Disabled at runtime. [ 18.139210] 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) [ 18.148457] systemd[1]: Detected virtualization kvm. [ 18.150725] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 18.737179] systemd[1]: initrd-switch-root.service: Succeeded. [ 18.740172] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 18.748470] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 18.752869] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 18.755577] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 18.763499] systemd[1]: Starting Journal Service... Starting Journal Service... [ 18.782081] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on udev Control Socket. Mounting Kernel Debug File System... [ OK ] Created slice system-getty.slice. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Created slice system-serial\x2dgetty.slice. Mounting POSIX Message Queue File System... [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd Root File System. Starting Remount Root and Kernel File Systems... Mounting Huge Pages File System... Starting Apply Kernel Variables... Activating swap /dev/disk/by-label/SWAP... Starting Create list of required st…ce nodes for the current kernel... Starting udev Coldplug all Devices... [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ 18.978573] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Reached target rpc_pipefs.target. [ OK ] Stopped target Initrd File Systems. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Listening on Process Core Dump Socket. [ OK ] Started Journal Service. [ OK ] Mounted Kernel Debug File System. [ OK ] Mounted POSIX Message Queue File System. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted Huge Pages File System. [ OK ] Started Apply Kernel Variables. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Reached target Swap. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started udev Coldplug all Devices. [ OK ] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... [ OK ] Mounted /mnt. [ 19.479455] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 20.038813] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 20.085203] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 20.343539] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 20.425235] EDAC sbridge: Ver: 1.1.2 [ 23.503114] Key type dns_resolver registered [ 24.155406] NFS: Registering the id_resolver key type [ 24.157868] Key type id_resolver registered [ 24.163824] Key type id_legacy registered [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Mark the need to relabel after reboot... Starting Rebuild Dynamic Linker Cache... Starting Create Volatile Files and Directories... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting Update UTMP about System Boot/Shutdown... Starting RPC Bind... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. Starting Restore /run/initramfs on shutdown... [ OK ] Started irqbalance daemon. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Reached target sshd-keygen.target. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Login Service... [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting Dynamic System Tuning Daemon... Starting GSSAPI Proxy Daemon... Starting OpenSSH server daemon... Starting Hostname Service... [ OK ] Started Login Service. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started OpenSSH server daemon. [ OK ] Started Permit User Sessions. [ OK ] Started Command Scheduler. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Serial Getty on ttyS1. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting System Logging Service... Starting Crash recovery kernel arming... Starting Notify NFS peers of a restart... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg141-client login: [ 54.126987] libcfs: loading out-of-tree module taints kernel. [ 54.153318] Key type ._llcrypt registered [ 54.154482] Key type .llcrypt registered [ 54.439595] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 54.444880] alg: No test for adler32 (adler32-zlib) [ 55.399801] Lustre: Lustre: Build Version: 2.17.54_83_gdfe0eaa [ 55.676449] LNet: Added LNI 192.168.201.41@tcp [8/256/0/180] [ 57.287165] Key type lgssc registered [ 57.807879] Lustre: Echo OBD driver; http://www.lustre.org/ [ 115.889874] Lustre: Mounted lustre-client [ 118.318747] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 126.533756] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing check_logdir /tmp/testlogs/ [ 128.088199] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing yml_node [ 129.657337] Lustre: DEBUG MARKER: Client: 2.17.54.83 [ 130.594163] Lustre: DEBUG MARKER: MDS: 2.17.54.83 [ 131.428337] Lustre: DEBUG MARKER: OSS: 2.17.54.83 [ 131.980551] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Thu Jun 25 00:17:47 EDT 2026 [ 137.661686] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 138.179490] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 138.705104] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 148.982281] Lustre: Unmounted lustre-client [ 212.080995] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 213.917072] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 222.100735] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 241.328256] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 00:19:36 (1782361176) [ 242.107725] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 242.613294] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 00:19:38 (1782361178) [ 252.282550] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 253.955406] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 260.170941] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 270.834213] Lustre: Mounted lustre-client [ 272.531794] Lustre: Unmounted lustre-client [ 289.805573] Key type lgssc unregistered [ 289.925637] LNet: 14942:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 289.929112] LNetError: 14942:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 289.936888] LNet: Removed LNI 192.168.201.41@tcp [ 290.209115] Key type .llcrypt unregistered [ 290.210683] Key type ._llcrypt unregistered [ 294.789069] Key type ._llcrypt registered [ 294.789983] Key type .llcrypt registered [ 295.175984] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 00:20:30 (1782361230) [ 295.637630] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 295.651894] alg: No test for adler32 (adler32-zlib) [ 296.506773] Lustre: Lustre: Build Version: 2.17.54_83_gdfe0eaa [ 296.591406] LNet: Added LNI 192.168.201.41@tcp [8/256/0/180] [ 298.167147] Key type lgssc registered [ 298.589978] Lustre: Echo OBD driver; http://www.lustre.org/ [ 322.726901] Lustre: Mounted lustre-client [ 398.036149] Lustre: Unmounted lustre-client [ 411.651573] Key type lgssc unregistered [ 411.767594] LNet: 18710:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 411.770957] LNetError: 18710:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 411.780802] LNet: Removed LNI 192.168.201.41@tcp [ 412.055909] Key type .llcrypt unregistered [ 412.057882] Key type ._llcrypt unregistered [ 415.460448] Key type ._llcrypt registered [ 415.461578] Key type .llcrypt registered [ 415.909521] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 00:22:31 (1782361351) [ 416.659328] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 416.668498] alg: No test for adler32 (adler32-zlib) [ 417.534664] Lustre: Lustre: Build Version: 2.17.54_83_gdfe0eaa [ 417.629503] LNet: Added LNI 192.168.201.41@tcp [8/256/0/180] [ 419.215163] Key type lgssc registered [ 419.594510] Lustre: Echo OBD driver; http://www.lustre.org/ [ 437.115021] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 438.887564] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 445.651615] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 456.179370] Lustre: Mounted lustre-client [ 460.107123] Lustre: Unmounted lustre-client [ 477.422612] Key type lgssc unregistered [ 477.543434] LNet: 22270:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 477.546665] LNetError: 22270:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 477.557577] LNet: Removed LNI 192.168.201.41@tcp [ 477.811732] Key type .llcrypt unregistered [ 477.812865] Key type ._llcrypt unregistered [ 481.283807] Key type ._llcrypt registered [ 481.284945] Key type .llcrypt registered [ 481.753981] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 00:23:37 (1782361417) [ 482.495621] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 482.525562] alg: No test for adler32 (adler32-zlib) [ 483.380313] Lustre: Lustre: Build Version: 2.17.54_83_gdfe0eaa [ 483.478714] LNet: Added LNI 192.168.201.41@tcp [8/256/0/180] [ 485.063157] Key type lgssc registered [ 485.458473] Lustre: Echo OBD driver; http://www.lustre.org/ [ 503.030828] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 504.802778] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 511.429137] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 522.226940] Lustre: Mounted lustre-client [ 525.125106] Lustre: Unmounted lustre-client [ 550.621881] Key type lgssc unregistered [ 550.749639] LNet: 25424:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 550.752237] LNetError: 25424:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 550.766593] LNet: Removed LNI 192.168.201.41@tcp [ 551.006139] Key type .llcrypt unregistered [ 551.008176] Key type ._llcrypt unregistered [ 555.927492] Key type ._llcrypt registered [ 555.928565] Key type .llcrypt registered [ 556.379691] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 00:24:51 (1782361491) [ 557.104088] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 557.130120] alg: No test for adler32 (adler32-zlib) [ 557.986880] Lustre: Lustre: Build Version: 2.17.54_83_gdfe0eaa [ 558.074547] LNet: Added LNI 192.168.201.41@tcp [8/256/0/180] [ 559.663157] Key type lgssc registered [ 560.057090] Lustre: Echo OBD driver; http://www.lustre.org/ [ 577.769761] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 579.553413] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 586.265605] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 596.977601] Lustre: Mounted lustre-client [ 602.082272] Lustre: lustre-MDT0000-mdc-ffff90cd04a65000: Connection to lustre-MDT0000 (at 192.168.201.141@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 607.201600] Lustre: lustre-MDT0001-mdc-ffff90cd04a65000: Connection to lustre-MDT0001 (at 192.168.201.141@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 613.388903] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 623.327164] Lustre: 26381:0:(client.c:2489:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1782361543/real 1782361543] req@00000000af8f668a x1868941485938688/t0(0) o400->MGC192.168.201.141@tcp@192.168.201.141@tcp:26/25 lens 224/224 e 0 to 1 dl 1782361559 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 623.338815] LustreError: MGC192.168.201.141@tcp: Connection to MGS (at 192.168.201.141@tcp) was lost; in progress operations using this service will fail [ 623.673970] Lustre: Unmounted lustre-client [ 634.121786] Key type lgssc unregistered [ 634.242350] LNet: 29043:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 634.245389] LNetError: 29043:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 634.256280] LNet: Removed LNI 192.168.201.41@tcp [ 634.506679] Key type .llcrypt unregistered [ 634.507789] Key type ._llcrypt unregistered [ 639.449777] Key type ._llcrypt registered [ 639.450810] Key type .llcrypt registered [ 639.907357] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 00:26:15 (1782361575) [ 640.673956] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 640.689267] alg: No test for adler32 (adler32-zlib) [ 641.549247] Lustre: Lustre: Build Version: 2.17.54_83_gdfe0eaa [ 641.630832] LNet: Added LNI 192.168.201.41@tcp [8/256/0/180] [ 643.207182] Key type lgssc registered [ 643.638523] Lustre: Echo OBD driver; http://www.lustre.org/ [ 661.476639] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 663.149373] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 669.649773] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 680.439655] Lustre: Mounted lustre-client [ 685.538847] Lustre: lustre-OST0000-osc-ffff90cd03673800: Connection to lustre-OST0000 (at 192.168.201.141@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 689.365279] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 699.642211] Lustre: Unmounted lustre-client [ 716.619842] Key type lgssc unregistered [ 716.751483] LNet: 32615:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 716.753770] LNetError: 32615:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 716.763412] LNet: Removed LNI 192.168.201.41@tcp [ 717.035555] Key type .llcrypt unregistered [ 717.036800] Key type ._llcrypt unregistered [ 721.980073] Key type ._llcrypt registered [ 721.981041] Key type .llcrypt registered [ 722.462229] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 00:27:37 (1782361657) [ 723.163433] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 723.204068] alg: No test for adler32 (adler32-zlib) [ 724.046379] Lustre: Lustre: Build Version: 2.17.54_83_gdfe0eaa [ 724.126138] LNet: Added LNI 192.168.201.41@tcp [8/256/0/180] [ 725.703139] Key type lgssc registered [ 726.098779] Lustre: Echo OBD driver; http://www.lustre.org/ [ 743.789742] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 745.510353] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 752.158046] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 762.865073] Lustre: Mounted lustre-client [ 764.660335] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 765.914150] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 776.426229] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2026-06-25 0h28m31s [ 777.095341] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 778.209952] Lustre: lustre-MDT0000-mdc-ffff90cd038b4800: Connection to lustre-MDT0000 (at 192.168.201.141@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 792.765952] LustreError: 36368:0:(lmv_obd.c:1466:lmv_statfs()) lustre-MDT0000-mdc-ffff90cd038b4800: can't stat MDS #0: rc = -19 [ 793.276909] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 795.618162] LustreError: MGC192.168.201.141@tcp: Connection to MGS (at 192.168.201.141@tcp) was lost; in progress operations using this service will fail [ 795.623362] Lustre: Evicted from MGS (at 192.168.201.141@tcp) after server handle changed from 0xa0b85363d8ff78d9 to 0xa0b85363d8ff7c60 [ 795.629211] Lustre: MGC192.168.201.141@tcp: Connection restored to 192.168.201.141@tcp (at 192.168.201.141@tcp) [ 796.641674] LustreError: lustre-MDT0000-mdc-ffff90cd038b4800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 796.648732] Lustre: lustre-MDT0000-mdc-ffff90cd038b4800: Connection restored to 192.168.201.141@tcp (at 192.168.201.141@tcp) [ 797.984771] Lustre: DEBUG MARKER: Wait for df (36368) ... [ 798.505792] Lustre: DEBUG MARKER: done [ 798.874154] Lustre: Unmounted lustre-client [ 821.884325] Key type lgssc unregistered [ 822.009647] LNet: 37378:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 822.011972] LNetError: 37378:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 822.019497] LNet: Removed LNI 192.168.201.41@tcp [ 822.264855] Key type .llcrypt unregistered [ 822.265978] Key type ._llcrypt unregistered [ 832.438930] Key type ._llcrypt registered [ 832.440078] Key type .llcrypt registered [ 832.912773] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 00:29:28 (1782361768) [ 833.943794] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 833.964856] alg: No test for adler32 (adler32-zlib) [ 834.828877] Lustre: Lustre: Build Version: 2.17.54_83_gdfe0eaa [ 834.921210] LNet: Added LNI 192.168.201.41@tcp [8/256/0/180] [ 836.511111] Key type lgssc registered [ 836.870701] Lustre: Echo OBD driver; http://www.lustre.org/ [ 854.591543] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 856.390149] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 862.964441] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 864.141367] Lustre: Mounted lustre-client [ 866.271145] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2026-06-25 0h30m01s [ 866.792261] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 869.077669] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 869.857519] Lustre: DEBUG MARKER: Creating a test file and stat it [ 890.335080] Lustre: 41482:0:(client.c:2489:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1782361805/real 1782361805] req@00000000261a56dc x1868941775345152/t0(0) o101->lustre-MDT0000-mdc-ffff90cd06835000@192.168.201.141@tcp:12/10 lens 576/1152 e 0 to 1 dl 1782361825 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:1 [ 890.343794] Lustre: lustre-MDT0000-mdc-ffff90cd06835000: Connection to lustre-MDT0000 (at 192.168.201.141@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 890.354062] Lustre: lustre-MDT0000-mdc-ffff90cd06835000: Connection restored to 192.168.201.141@tcp (at 192.168.201.141@tcp) [ 910.456593] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 911.493695] Lustre: DEBUG MARKER: done [ 912.423872] Lustre: Unmounted lustre-client [ 927.416926] Key type lgssc unregistered [ 927.530418] LNet: 42366:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 927.534285] LNetError: 42366:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 927.542646] LNet: Removed LNI 192.168.201.41@tcp [ 927.778388] Key type .llcrypt unregistered [ 927.779464] Key type ._llcrypt unregistered [ 937.836729] Key type ._llcrypt registered [ 937.838569] Key type .llcrypt registered [ 938.281252] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 00:31:13 (1782361873) [ 938.770714] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 939.285833] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 00:31:14 (1782361874) [ 940.636862] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 940.660734] alg: No test for adler32 (adler32-zlib) [ 941.521230] Lustre: Lustre: Build Version: 2.17.54_83_gdfe0eaa [ 941.609914] LNet: Added LNI 192.168.201.41@tcp [8/256/0/180] [ 943.199199] Key type lgssc registered [ 943.579624] Lustre: Echo OBD driver; http://www.lustre.org/ [ 954.120938] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 00:31:29 (1782361889) [ 964.199689] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 965.918296] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 972.714258] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 978.905863] Lustre: Mounted lustre-client [ 980.690088] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 981.354548] Lustre: Unmounted lustre-client [ 990.711616] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 1007.851229] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1009.600127] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1009.779567] Lustre: Mounted lustre-client [ 1010.646171] Lustre: Unmounted lustre-client [ 1037.055100] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1038.879218] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1039.056663] Lustre: Mounted lustre-client [ 1039.934122] Lustre: Unmounted lustre-client [ 1051.313666] Lustre: DEBUG MARKER: files compared the same [ 1064.641151] Key type lgssc unregistered [ 1064.755371] LNet: 49899:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1064.757974] LNetError: 49899:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1064.764859] LNet: Removed LNI 192.168.201.41@tcp [ 1065.012049] Key type .llcrypt unregistered [ 1065.013819] Key type ._llcrypt unregistered [ 1069.974401] Key type ._llcrypt registered [ 1069.976082] Key type .llcrypt registered [ 1070.419234] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 00:33:25 (1782362005) [ 1071.173199] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1071.197504] alg: No test for adler32 (adler32-zlib) [ 1072.044205] Lustre: Lustre: Build Version: 2.17.54_83_gdfe0eaa [ 1072.137628] LNet: Added LNI 192.168.201.41@tcp [8/256/0/180] [ 1073.727129] Key type lgssc registered [ 1074.104138] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1091.180554] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1092.895431] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1099.484054] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1110.000220] Lustre: Mounted lustre-client [ 1111.657106] Lustre: Unmounted lustre-client [ 1131.377168] Key type lgssc unregistered [ 1131.566764] LNet: 53441:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1131.572412] LNetError: 53441:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1131.584409] LNet: Removed LNI 192.168.201.41@tcp [ 1131.961080] Key type .llcrypt unregistered [ 1131.963157] Key type ._llcrypt unregistered [ 1137.315583] Key type ._llcrypt registered [ 1137.316603] Key type .llcrypt registered [ 1137.816686] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 00:34:33 (1782362073) [ 1138.611489] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1138.645101] alg: No test for adler32 (adler32-zlib) [ 1139.514993] Lustre: Lustre: Build Version: 2.17.54_83_gdfe0eaa [ 1139.616887] LNet: Added LNI 192.168.201.41@tcp [8/256/0/180] [ 1141.207120] Key type lgssc registered [ 1141.727118] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1203.307281] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1216.011540] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1217.696522] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1235.543965] Key type lgssc unregistered [ 1235.655506] LNet: 56898:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1235.659610] LNetError: 56898:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1235.678399] LNet: Removed LNI 192.168.201.41@tcp [ 1235.920436] Key type .llcrypt unregistered [ 1235.921512] Key type ._llcrypt unregistered [ 1241.471749] Key type ._llcrypt registered [ 1241.472848] Key type .llcrypt registered [ 1241.944816] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 00:36:17 (1782362177) [ 1243.105735] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1243.112357] alg: No test for adler32 (adler32-zlib) [ 1243.984980] Lustre: Lustre: Build Version: 2.17.54_83_gdfe0eaa [ 1244.084506] LNet: Added LNI 192.168.201.41@tcp [8/256/0/180] [ 1245.679307] Key type lgssc registered [ 1246.183973] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1273.633527] Lustre: Mounted lustre-client [ 1279.003181] Lustre: Unmounted lustre-client [ 1300.457908] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 00:37:15 (1782362235) [ 1341.877236] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1354.222538] Lustre: Mounted lustre-client [ 1359.594984] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1359.597917] LustreError: 63335:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff90cd04ac1000: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 1359.607387] Lustre: 57881:0:(llite_lib.c:4202:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.201.141@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 1369.923117] Lustre: Unmounted lustre-client [ 1405.863962] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 00:39:01 (1782362341) [ 1410.352743] Key type lgssc unregistered [ 1410.513326] LNet: 64546:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1410.517950] LNetError: 64546:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1410.528696] LNet: Removed LNI 192.168.201.41@tcp [ 1410.828194] Key type .llcrypt unregistered [ 1410.829750] Key type ._llcrypt unregistered [ 1415.152414] Key type ._llcrypt registered [ 1415.153743] Key type .llcrypt registered [ 1415.386809] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1415.391988] alg: No test for adler32 (adler32-zlib) [ 1416.271639] Lustre: Lustre: Build Version: 2.17.54_83_gdfe0eaa [ 1416.359830] LNet: Added LNI 192.168.201.41@tcp [8/256/0/180] [ 1417.951207] Key type lgssc registered [ 1418.379700] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1436.528896] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1438.378395] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1445.337816] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1456.117501] Lustre: Mounted lustre-client [ 1457.890222] Lustre: Unmounted lustre-client [ 1467.336136] Key type lgssc unregistered [ 1467.440498] LNet: 67520:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1467.442991] LNetError: 67520:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1467.451716] LNet: Removed LNI 192.168.201.41@tcp [ 1467.679690] Key type .llcrypt unregistered [ 1467.681039] Key type ._llcrypt unregistered [ 1471.371677] Key type ._llcrypt registered [ 1471.376914] Key type .llcrypt registered [ 1471.535789] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1471.542173] alg: No test for adler32 (adler32-zlib) [ 1472.406311] Lustre: Lustre: Build Version: 2.17.54_83_gdfe0eaa [ 1472.496068] LNet: Added LNI 192.168.201.41@tcp [8/256/0/180] [ 1474.079161] Key type lgssc registered [ 1474.444161] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1523.226479] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1525.039687] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1531.550179] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1542.127843] Lustre: Mounted lustre-client [ 1542.562517] Lustre: Unmounted lustre-client [ 1559.555214] Key type lgssc unregistered [ 1559.672462] LNet: 70914:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1559.675486] LNetError: 70914:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1559.684731] LNet: Removed LNI 192.168.201.41@tcp [ 1559.913792] Key type .llcrypt unregistered [ 1559.914925] Key type ._llcrypt unregistered [ 1564.817800] Key type ._llcrypt registered [ 1564.818720] Key type .llcrypt registered [ 1565.326976] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 00:41:40 (1782362500) [ 1566.110827] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1566.142658] alg: No test for adler32 (adler32-zlib) [ 1567.019392] Lustre: Lustre: Build Version: 2.17.54_83_gdfe0eaa [ 1567.111270] LNet: Added LNI 192.168.201.41@tcp [8/256/0/180] [ 1568.695125] Key type lgssc registered [ 1569.027925] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1586.143978] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1587.874856] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1594.431843] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1605.106606] Lustre: Mounted lustre-client [ 1607.386976] Lustre: Unmounted lustre-client [ 1607.500590] Lustre: Mounted lustre-client [ 1608.089156] Lustre: Unmounted lustre-client [ 1633.555161] Key type lgssc unregistered [ 1633.667506] LNet: 74559:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1633.669724] LNetError: 74559:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1633.679607] LNet: Removed LNI 192.168.201.41@tcp [ 1633.913249] Key type .llcrypt unregistered [ 1633.914210] Key type ._llcrypt unregistered [ 1638.133338] Key type ._llcrypt registered [ 1638.135088] Key type .llcrypt registered [ 1638.279268] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1638.284603] alg: No test for adler32 (adler32-zlib) [ 1639.138496] Lustre: Lustre: Build Version: 2.17.54_83_gdfe0eaa [ 1639.228742] LNet: Added LNI 192.168.201.41@tcp [8/256/0/180] [ 1640.815259] Key type lgssc registered [ 1641.191862] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1660.319522] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1662.453651] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1669.330887] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1679.861082] Lustre: Mounted lustre-client [ 1681.779122] Lustre: Unmounted lustre-client [ 1699.095780] Key type lgssc unregistered [ 1699.207487] LNet: 77558:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1699.209920] LNetError: 77558:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1699.216660] LNet: Removed LNI 192.168.201.41@tcp [ 1699.454202] Key type .llcrypt unregistered [ 1699.456134] Key type ._llcrypt unregistered [ 1704.560110] Key type ._llcrypt registered [ 1704.561162] Key type .llcrypt registered [ 1704.983916] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 00:44:00 (1782362640) [ 1705.727206] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1705.777865] alg: No test for adler32 (adler32-zlib) [ 1706.636606] Lustre: Lustre: Build Version: 2.17.54_83_gdfe0eaa [ 1706.731339] LNet: Added LNI 192.168.201.41@tcp [8/256/0/180] [ 1708.311176] Key type lgssc registered [ 1708.709472] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1726.446404] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1728.122050] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1734.586658] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1744.913645] Lustre: Mounted lustre-client [ 1749.039227] Lustre: lustre: root_squash is set to 0:0 [ 1749.047658] Lustre: lustre: nosquash_nids is cleared [ 1755.629206] Lustre: lustre: root_squash is set to 500:500 [ 1763.436949] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.201.41@tcp 192.168.0.[2,10]@tcp [ 1772.525153] Lustre: lustre: nosquash_nids set to 192.168.201.41/24@tcp [ 1774.658162] Lustre: Unmounted lustre-client [ 1791.658681] Key type lgssc unregistered [ 1791.771487] LNet: 81537:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1791.773933] LNetError: 81537:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1791.783241] LNet: Removed LNI 192.168.201.41@tcp [ 1792.013858] Key type .llcrypt unregistered [ 1792.014916] Key type ._llcrypt unregistered [ 1797.173210] Key type ._llcrypt registered [ 1797.174335] Key type .llcrypt registered [ 1797.629304] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 00:45:33 (1782362733) [ 1798.425505] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 1798.955799] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 00:45:34 (1782362734) [ 1799.762932] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1799.771347] alg: No test for adler32 (adler32-zlib) [ 1800.624694] Lustre: Lustre: Build Version: 2.17.54_83_gdfe0eaa [ 1800.708665] LNet: Added LNI 192.168.201.41@tcp [8/256/0/180] [ 1802.287124] Key type lgssc registered [ 1802.637788] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1820.062802] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1821.852594] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1828.797149] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1828.971677] Lustre: lustre: root_squash is set to 500:500 [ 1828.981398] Lustre: lustre: nosquash_nids set to 192.168.201.41/24@tcp [ 1839.602286] Lustre: Mounted lustre-client [ 1841.999157] Lustre: Unmounted lustre-client [ 1859.254063] Key type lgssc unregistered [ 1859.369333] LNet: 85459:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1859.371808] LNetError: 85459:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1859.379428] LNet: Removed LNI 192.168.201.41@tcp [ 1859.626805] Key type .llcrypt unregistered [ 1859.627888] Key type ._llcrypt unregistered [ 1864.603525] Key type ._llcrypt registered [ 1864.604513] Key type .llcrypt registered [ 1865.062308] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 1865.652304] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 00:46:41 (1782362801) [ 1871.451631] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1871.459385] alg: No test for adler32 (adler32-zlib) [ 1872.310062] Lustre: Lustre: Build Version: 2.17.54_83_gdfe0eaa [ 1872.403773] LNet: Added LNI 192.168.201.41@tcp [8/256/0/180] [ 1873.991228] Key type lgssc registered [ 1874.408822] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1902.683785] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1904.454404] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1912.084644] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1951.187657] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1953.062837] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1959.846031] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1972.030620] Lustre: Mounted lustre-client [ 1988.687286] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff90cd080ec800.ost_server_uuid 50 [ 1989.147692] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff90cd080ec800.ost_server_uuid in FULL state after 0 sec [ 1989.266787] Lustre: Mounted lustre-client [ 1989.980190] Lustre: Unmounted lustre-client [ 2017.467039] Key type lgssc unregistered [ 2017.593663] LNet: 93042:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2017.595929] LNetError: 93042:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2017.607734] LNet: Removed LNI 192.168.201.41@tcp [ 2017.882340] Key type .llcrypt unregistered [ 2017.883394] Key type ._llcrypt unregistered [ 2027.720371] Key type ._llcrypt registered [ 2027.722039] Key type .llcrypt registered [ 2028.148847] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 00:49:23 (1782362963) [ 2033.665372] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2033.707127] alg: No test for adler32 (adler32-zlib) [ 2034.556485] Lustre: Lustre: Build Version: 2.17.54_83_gdfe0eaa [ 2034.647092] LNet: Added LNI 192.168.201.41@tcp [8/256/0/180] [ 2036.231141] Key type lgssc registered [ 2036.590494] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2064.235175] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2065.941355] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2073.378190] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2074.531470] Lustre: Mounted lustre-client [ 2088.499348] Lustre: lustre-OST0000-osc-ffff90cd048c7000: Connection to lustre-OST0000 (at 192.168.201.141@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2093.985452] Lustre: lustre-MDT0000-mdc-ffff90cd048c7000: Connection to lustre-MDT0000 (at 192.168.201.141@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2095.071131] Lustre: 94399:0:(client.c:2489:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1782363015/real 1782363015] req@000000008b81851c x1868943033635968/t0(0) o400->lustre-OST0000-osc-ffff90cd048c7000@192.168.201.141@tcp:28/4 lens 224/224 e 0 to 1 dl 1782363031 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2100.191097] Lustre: 94399:0:(client.c:2489:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1782363020/real 1782363020] req@0000000088fda1b9 x1868943033636480/t0(0) o400->lustre-OST0000-osc-ffff90cd048c7000@192.168.201.141@tcp:28/4 lens 224/224 e 0 to 1 dl 1782363036 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2104.289086] LustreError: MGC192.168.201.141@tcp: Connection to MGS (at 192.168.201.141@tcp) was lost; in progress operations using this service will fail [ 2104.296758] Lustre: Evicted from MGS (at 192.168.201.141@tcp) after server handle changed from 0x277a5fc750bb7464 to 0x277a5fc750bb7712 [ 2104.300469] Lustre: MGC192.168.201.141@tcp: Connection restored to 192.168.201.141@tcp (at 192.168.201.141@tcp) [ 2109.473860] Lustre: lustre-MDT0000-mdc-ffff90cd048c7000: Connection restored to 192.168.201.141@tcp (at 192.168.201.141@tcp) [ 2109.848138] Lustre: Unmounted lustre-client [ 2135.067973] Key type lgssc unregistered [ 2135.184438] LNet: 98652:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2135.187323] LNetError: 98652:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2135.196448] LNet: Removed LNI 192.168.201.41@tcp [ 2135.428767] Key type .llcrypt unregistered [ 2135.429907] Key type ._llcrypt unregistered [ 2140.326800] Key type ._llcrypt registered [ 2140.327819] Key type .llcrypt registered [ 2140.731680] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 00:51:16 (1782363076) [ 2152.248988] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2152.264140] alg: No test for adler32 (adler32-zlib) [ 2153.137715] Lustre: Lustre: Build Version: 2.17.54_83_gdfe0eaa [ 2153.247500] LNet: Added LNI 192.168.201.41@tcp [8/256/0/180] [ 2154.839168] Key type lgssc registered [ 2155.200194] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2170.664878] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2202.737661] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2204.369729] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2212.125590] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2213.276044] Lustre: Mounted lustre-client [ 2213.895720] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 2214.453627] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 00:52:29 (1782363149) [ 2265.027821] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2275.316104] Lustre: Unmounted lustre-client [ 2324.782127] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2326.438725] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2334.168585] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2363.299229] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 00:54:58 (1782363298) [ 2392.705920] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2394.490815] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2402.174801] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2407.408908] Lustre: Mounted lustre-client [ 2408.415602] Lustre: Unmounted lustre-client [ 2426.485975] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 00:56:02 (1782363362) [ 2455.161872] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2456.813769] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2465.243884] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2465.449931] Lustre: Mounted lustre-client [ 2466.872535] Lustre: Unmounted lustre-client [ 2493.036112] Key type lgssc unregistered [ 2493.150638] LNet: 121465:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2493.152991] LNetError: 121465:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2493.160501] LNet: Removed LNI 192.168.201.41@tcp [ 2493.374195] Key type .llcrypt unregistered [ 2493.375242] Key type ._llcrypt unregistered [ 2498.383395] Key type ._llcrypt registered [ 2498.384366] Key type .llcrypt registered [ 2498.782713] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 00:57:14 (1782363434) [ 2499.435402] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2499.479065] alg: No test for adler32 (adler32-zlib) [ 2500.327422] Lustre: Lustre: Build Version: 2.17.54_83_gdfe0eaa [ 2500.409089] LNet: Added LNI 192.168.201.41@tcp [8/256/0/180] [ 2501.983143] Key type lgssc registered [ 2502.314581] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2518.960840] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2520.525802] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2526.548698] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2542.066637] Lustre: Mounted lustre-client [ 2552.778900] Lustre: Unmounted lustre-client [ 2578.295406] Key type lgssc unregistered [ 2578.407624] LNet: 125022:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2578.410287] LNetError: 125022:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2578.420902] LNet: Removed LNI 192.168.201.41@tcp [ 2578.662455] Key type .llcrypt unregistered [ 2578.664085] Key type ._llcrypt unregistered [ 2583.535896] Key type ._llcrypt registered [ 2583.536932] Key type .llcrypt registered [ 2583.915969] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 00:58:39 (1782363519) [ 2584.626402] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2584.632361] alg: No test for adler32 (adler32-zlib) [ 2585.484435] Lustre: Lustre: Build Version: 2.17.54_83_gdfe0eaa [ 2585.568878] LNet: Added LNI 192.168.201.41@tcp [8/256/0/180] [ 2587.143140] Key type lgssc registered [ 2587.467478] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2603.927940] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2605.543227] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2611.633055] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2627.055769] Lustre: Mounted lustre-client [ 2632.162825] Lustre: lustre-OST0000-osc-ffff90cd042db000: Connection to lustre-OST0000 (at 192.168.201.141@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2637.569420] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff90cd042db000.ost_server_uuid 50 [ 2638.079898] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 2647.996310] Lustre: Unmounted lustre-client [ 2660.397780] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 00:59:55 (1782363595) [ 2670.980195] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2672.633858] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2679.095049] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2686.695785] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 2686.854633] Lustre: Mounted lustre-client [ 2692.067522] Lustre: lustre-OST0000-osc-ffff90cd0498d800: Connection to lustre-OST0000 (at 192.168.201.141@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2706.380215] Lustre: Unmounted lustre-client [ 2728.827549] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 01:01:04 (1782363664) [ 2738.620711] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2740.287343] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2746.789705] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2756.733884] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 2757.088278] Lustre: Mounted lustre-client [ 2762.217043] Lustre: lustre-OST0000-osc-ffff90cd06e53000: Connection to lustre-OST0000 (at 192.168.201.141@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2774.528167] Lustre: Unmounted lustre-client [ 2797.354076] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 01:02:12 (1782363732) [ 2828.464555] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2830.362046] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2838.328858] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2867.939070] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2869.732700] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2876.544413] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2897.769916] Lustre: Mounted lustre-client [ 2925.783892] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2926.323095] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 2926.645261] Lustre: Unmounted lustre-client [ 2936.868909] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 01:04:32 (1782363872) [ 2946.771585] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2948.518741] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2955.019744] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2973.795206] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 2987.116404] Lustre: Mounted lustre-client [ 3014.877767] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3015.397500] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 3018.211303] Lustre: lustre-OST0001-osc-ffff90cd04dec800: Connection to lustre-OST0001 (at 192.168.201.141@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3022.958879] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3033.211941] Lustre: Unmounted lustre-client [ 3070.696710] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 01:06:46 (1782364006) [ 3080.977438] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3082.726258] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3089.095285] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3089.240337] Lustre: Mounted lustre-client [ 3097.005229] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3098.532651] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 1 sec [ 3102.973675] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff90cd04977000.ost_server_uuid 50 [ 3103.493638] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff90cd04977000.ost_server_uuid in FULL state after 0 sec [ 3104.178177] Lustre: Unmounted lustre-client [ 3104.268742] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 3104.272124] Lustre: Skipped 3 previous similar messages [ 3104.644733] LustreError: 148161:0:(sec.c:413:import_sec_validate_get()) import 0000000010b8dba2 (NEW) with no sec [ 3154.083740] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 01:08:09 (1782364089) [ 3167.573037] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3169.703470] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3177.630727] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3186.793686] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3186.991129] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 3187.048839] Lustre: Mounted lustre-client [ 3187.051095] Lustre: Skipped 1 previous similar message [ 3200.177357] Lustre: Unmounted lustre-client [ 3200.178383] Lustre: Skipped 1 previous similar message [ 3225.918985] Key type lgssc unregistered [ 3226.082816] LNet: 151558:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3226.088592] LNetError: 151558:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3226.100766] LNet: Removed LNI 192.168.201.41@tcp [ 3226.442866] Key type .llcrypt unregistered [ 3226.445046] Key type ._llcrypt unregistered [ 3232.049117] Key type ._llcrypt registered [ 3232.050251] Key type .llcrypt registered [ 3232.737366] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 01:09:27 (1782364167) [ 3233.088432] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3233.124108] alg: No test for adler32 (adler32-zlib) [ 3233.979402] Lustre: Lustre: Build Version: 2.17.54_83_gdfe0eaa [ 3234.062318] LNet: Added LNI 192.168.201.41@tcp [8/256/0/180] [ 3235.639224] Key type lgssc registered [ 3236.155100] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3257.667634] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3259.416802] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3266.787881] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3274.549352] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3274.697515] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 3275.739786] Lustre: Mounted lustre-client [ 3280.880285] LustreError: lustre-MDT0001-mdc-ffff90cd0306a000: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 3280.888449] Lustre: lustre-MDT0001-mdc-ffff90cd0306a000: Connection restored to 192.168.201.141@tcp (at 192.168.201.141@tcp) [ 3283.574452] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3284.183060] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 3295.218629] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 3302.322193] Lustre: Unmounted lustre-client [ 3323.934455] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 01:10:59 (1782364259) [ 3362.329873] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3364.303113] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3374.203376] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3379.719783] Lustre: Mounted lustre-client [ 3392.152693] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3392.847850] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 3405.799529] Lustre: lustre-OST0001-osc-ffff90cd05895800: Connection to lustre-OST0001 (at 192.168.201.141@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3408.728689] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3419.005354] Lustre: Unmounted lustre-client [ 3452.440391] Key type lgssc unregistered [ 3452.553374] LNet: 161084:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3452.555608] LNetError: 161084:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3452.563726] LNet: Removed LNI 192.168.201.41@tcp [ 3452.799741] Key type .llcrypt unregistered [ 3452.800739] Key type ._llcrypt unregistered [ 3463.018643] Key type ._llcrypt registered [ 3463.019738] Key type .llcrypt registered [ 3463.531632] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 01:13:18 (1782364398) [ 3464.575406] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3464.581307] alg: No test for adler32 (adler32-zlib) [ 3465.452597] Lustre: Lustre: Build Version: 2.17.54_83_gdfe0eaa [ 3465.542326] LNet: Added LNI 192.168.201.41@tcp [8/256/0/180] [ 3467.119165] Key type lgssc registered [ 3467.571246] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3488.774927] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3491.297205] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3499.501556] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3506.145784] Lustre: Mounted lustre-client [ 3543.439118] Lustre: Unmounted lustre-client [ 3564.151106] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3564.369739] Lustre: Mounted lustre-client [ 3596.620611] Lustre: Unmounted lustre-client [ 3616.139981] Key type lgssc unregistered [ 3616.304145] LNet: 165481:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3616.309271] LNetError: 165481:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3616.322218] LNet: Removed LNI 192.168.201.41@tcp [ 3616.699766] Key type .llcrypt unregistered [ 3616.701023] Key type ._llcrypt unregistered [ 3622.766186] Key type ._llcrypt registered [ 3622.767079] Key type .llcrypt registered [ 3623.207700] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 01:15:58 (1782364558) [ 3624.007602] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3624.065515] alg: No test for adler32 (adler32-zlib) [ 3624.908806] Lustre: Lustre: Build Version: 2.17.54_83_gdfe0eaa [ 3624.990138] LNet: Added LNI 192.168.201.41@tcp [8/256/0/180] [ 3626.567264] Key type lgssc registered [ 3627.067552] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3649.933316] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3652.480052] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3661.067129] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3666.425408] Lustre: Mounted lustre-client [ 3673.709163] Lustre: Unmounted lustre-client [ 3699.610962] Key type lgssc unregistered [ 3699.716511] LNet: 169198:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3699.720247] LNetError: 169198:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3699.730668] LNet: Removed LNI 192.168.201.41@tcp [ 3700.023737] Key type .llcrypt unregistered [ 3700.025342] Key type ._llcrypt unregistered [ 3705.066684] Key type ._llcrypt registered [ 3705.067914] Key type .llcrypt registered [ 3705.317581] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3705.322197] alg: No test for adler32 (adler32-zlib) [ 3706.204413] Lustre: Lustre: Build Version: 2.17.54_83_gdfe0eaa [ 3706.310547] LNet: Added LNI 192.168.201.41@tcp [8/256/0/180] [ 3707.895197] Key type lgssc registered [ 3708.583800] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3731.198538] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3733.686923] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3742.895660] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3749.343730] Lustre: Mounted lustre-client [ 3756.620176] Lustre: Unmounted lustre-client [ 3775.803000] Key type lgssc unregistered [ 3775.947620] LNet: 172239:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3775.952134] LNetError: 172239:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3775.963175] LNet: Removed LNI 192.168.201.41@tcp [ 3776.249617] Key type .llcrypt unregistered [ 3776.251730] Key type ._llcrypt unregistered [ 3782.503973] Key type ._llcrypt registered [ 3782.505122] Key type .llcrypt registered [ 3782.907390] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 01:18:38 (1782364718) [ 3783.843651] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3783.880646] alg: No test for adler32 (adler32-zlib) [ 3784.750364] Lustre: Lustre: Build Version: 2.17.54_83_gdfe0eaa [ 3784.845617] LNet: Added LNI 192.168.201.41@tcp [8/256/0/180] [ 3786.431293] Key type lgssc registered [ 3786.974110] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3808.783244] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3811.100062] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3820.410987] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3826.668487] Lustre: Mounted lustre-client [ 3839.103264] Lustre: Unmounted lustre-client [ 3858.449336] Key type lgssc unregistered [ 3858.575811] LNet: 175954:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3858.578526] LNetError: 175954:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3858.586400] LNet: Removed LNI 192.168.201.41@tcp [ 3858.872444] Key type .llcrypt unregistered [ 3858.874829] Key type ._llcrypt unregistered [ 3863.502625] Key type ._llcrypt registered [ 3863.505389] Key type .llcrypt registered [ 3863.714637] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3863.719519] alg: No test for adler32 (adler32-zlib) [ 3864.618142] Lustre: Lustre: Build Version: 2.17.54_83_gdfe0eaa [ 3864.729812] LNet: Added LNI 192.168.201.41@tcp [8/256/0/180] [ 3866.335238] Key type lgssc registered [ 3867.029167] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3890.190417] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3892.362556] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3901.602006] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3907.076171] Lustre: Mounted lustre-client [ 3910.136224] Lustre: Unmounted lustre-client [ 3937.523823] Key type lgssc unregistered [ 3937.687875] LNet: 178986:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3937.693579] LNetError: 178986:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3937.708106] LNet: Removed LNI 192.168.201.41@tcp [ 3938.081488] Key type .llcrypt unregistered [ 3938.083171] Key type ._llcrypt unregistered [ 3944.696423] Key type ._llcrypt registered [ 3944.698191] Key type .llcrypt registered [ 3945.457860] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 01:21:20 (1782364880) [ 3955.573909] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3955.578758] alg: No test for adler32 (adler32-zlib) [ 3956.445801] Lustre: Lustre: Build Version: 2.17.54_83_gdfe0eaa [ 3956.538475] LNet: Added LNI 192.168.201.41@tcp [8/256/0/180] [ 3958.119195] Key type lgssc registered [ 3958.737771] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3994.312836] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3996.325441] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4005.979244] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4037.954723] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 01:22:53 (1782364973) [ 4050.915566] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4053.662205] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4063.088647] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4069.367926] Lustre: Mounted lustre-client [ 4075.337164] Lustre: Unmounted lustre-client [ 4095.118736] Key type lgssc unregistered [ 4095.268838] LNet: 186023:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4095.273707] LNetError: 186023:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4095.286570] LNet: Removed LNI 192.168.201.41@tcp [ 4095.634726] Key type .llcrypt unregistered [ 4095.636703] Key type ._llcrypt unregistered [ 4101.295487] Key type ._llcrypt registered [ 4101.296852] Key type .llcrypt registered [ 4101.842381] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 4102.663526] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 01:23:57 (1782365037) [ 4110.388752] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4110.394765] alg: No test for adler32 (adler32-zlib) [ 4111.282763] Lustre: Lustre: Build Version: 2.17.54_83_gdfe0eaa [ 4111.407147] LNet: Added LNI 192.168.201.41@tcp [8/256/0/180] [ 4113.023160] Key type lgssc registered [ 4113.639372] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4158.276434] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4160.831477] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4171.443637] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid [ 4181.141598] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid [ 4182.362176] Lustre: Mounted lustre-client [ 4191.104164] Lustre: Unmounted lustre-client [ 4269.192235] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4271.976120] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4282.311648] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4305.600762] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 01:27:20 (1782365240) [ 4306.268593] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 4306.800813] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 01:27:22 (1782365242) [ 4360.131938] Key type lgssc unregistered [ 4360.275488] LNet: 197372:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4360.280276] LNetError: 197372:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4360.291904] LNet: Removed LNI 192.168.201.41@tcp [ 4360.693104] Key type .llcrypt unregistered [ 4360.695340] Key type ._llcrypt unregistered [ 4367.767498] Key type ._llcrypt registered [ 4367.768337] Key type .llcrypt registered [ 4368.392471] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 01:28:23 (1782365303) [ 4393.424499] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4393.433509] alg: No test for adler32 (adler32-zlib) [ 4394.352572] Lustre: Lustre: Build Version: 2.17.54_83_gdfe0eaa [ 4394.478596] LNet: Added LNI 192.168.201.41@tcp [8/256/0/180] [ 4396.063164] Key type lgssc registered [ 4396.415170] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4420.422933] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4424.909508] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4448.111762] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4458.914115] Key type lgssc unregistered [ 4459.017332] LNet: 201067:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4459.019572] LNetError: 201067:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4459.031318] LNet: Removed LNI 192.168.201.41@tcp [ 4459.271585] Key type .llcrypt unregistered [ 4459.272535] Key type ._llcrypt unregistered [ 4464.326065] Key type ._llcrypt registered [ 4464.327112] Key type .llcrypt registered [ 4464.736100] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 01:30:00 (1782365400) [ 4465.562438] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4465.598891] alg: No test for adler32 (adler32-zlib) [ 4466.458074] Lustre: Lustre: Build Version: 2.17.54_83_gdfe0eaa [ 4466.540637] LNet: Added LNI 192.168.201.41@tcp [8/256/0/180] [ 4468.119123] Key type lgssc registered [ 4468.490458] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4486.066285] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4487.855637] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4496.238888] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4506.608580] Lustre: Mounted lustre-client [ 4511.713192] Lustre: lustre-MDT0000-mdc-ffff90cd07b00800: Connection to lustre-MDT0000 (at 192.168.201.141@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4516.833114] Lustre: lustre-MDT0001-mdc-ffff90cd07b00800: Connection to lustre-MDT0001 (at 192.168.201.141@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4532.191201] Lustre: 202055:0:(client.c:2489:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1782365452/real 1782365452] req@00000000fffb99f0 x1868945583849600/t0(0) o400->MGC192.168.201.141@tcp@192.168.201.141@tcp:26/25 lens 224/224 e 0 to 1 dl 1782365468 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4532.192842] LustreError: MGC192.168.201.141@tcp: Connection to MGS (at 192.168.201.141@tcp) was lost; in progress operations using this service will fail [ 4532.206113] Lustre: Evicted from MGS (at 192.168.201.141@tcp) after server handle changed from 0x3705b4b552233303 to 0x3705b4b552234c63 [ 4532.210820] Lustre: MGC192.168.201.141@tcp: Connection restored to 192.168.201.141@tcp (at 192.168.201.141@tcp) [ 4538.850781] Lustre: 204145:0:(mgc_request.c:1901:mgc_process_log()) MGC192.168.201.141@tcp: IR log lustre-cliir failed, not fatal: rc = -2 [ 4547.881602] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4552.674439] LustreError: lustre-MDT0000-mdc-ffff90cd07b00800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 4552.692959] Lustre: lustre-MDT0000-mdc-ffff90cd07b00800: Connection restored to 192.168.201.141@tcp (at 192.168.201.141@tcp) [ 4553.469092] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 5 sec [ 4555.063646] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4555.620444] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 4556.189715] Lustre: Unmounted lustre-client [ 4581.553713] Key type lgssc unregistered [ 4581.677379] LNet: 206006:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4581.679806] LNetError: 206006:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4581.690689] LNet: Removed LNI 192.168.201.41@tcp [ 4581.922331] Key type .llcrypt unregistered [ 4581.923357] Key type ._llcrypt unregistered [ 4587.208278] Key type ._llcrypt registered [ 4587.209993] Key type .llcrypt registered [ 4587.648709] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 01:32:03 (1782365523) [ 4588.397646] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4588.428962] alg: No test for adler32 (adler32-zlib) [ 4589.296976] Lustre: Lustre: Build Version: 2.17.54_83_gdfe0eaa [ 4589.386878] LNet: Added LNI 192.168.201.41@tcp [8/256/0/180] [ 4590.967158] Key type lgssc registered [ 4591.360851] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4609.365524] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4611.265981] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4634.303164] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4636.397766] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4650.598140] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4657.903731] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 4682.669895] Key type lgssc unregistered [ 4682.789357] LNet: 211234:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4682.791427] LNetError: 211234:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4682.799345] LNet: Removed LNI 192.168.201.41@tcp [ 4683.016633] Key type .llcrypt unregistered [ 4683.017738] Key type ._llcrypt unregistered [ 4703.862787] Key type ._llcrypt registered [ 4703.863906] Key type .llcrypt registered [ 4704.349474] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 01:33:59 (1782365639) [ 4716.490137] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4716.504986] alg: No test for adler32 (adler32-zlib) [ 4717.371220] Lustre: Lustre: Build Version: 2.17.54_83_gdfe0eaa [ 4717.465390] LNet: Added LNI 192.168.201.41@tcp [8/256/0/180] [ 4719.047197] Key type lgssc registered [ 4719.490045] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4749.452674] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4751.124802] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4758.867429] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4776.119834] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 01:35:11 (1782365711) [ 4778.498389] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 01:35:14 (1782365714) [ 4789.376853] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4791.105517] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4797.731111] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4808.185806] Lustre: Mounted lustre-client [ 4811.387769] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [ 4812.224710] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 4812.879982] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 4813.487813] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [ 4818.403287] Lustre: lustre-MDT0000-mdc-ffff90cd045fc000: Connection to lustre-MDT0000 (at 192.168.201.141@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4828.642237] LustreError: MGC192.168.201.141@tcp: Connection to MGS (at 192.168.201.141@tcp) was lost; in progress operations using this service will fail [ 4828.653165] Lustre: Evicted from MGS (at 192.168.201.141@tcp) after server handle changed from 0x98d05bafcd6ea593 to 0x98d05bafcd6ea809 [ 4828.660631] Lustre: MGC192.168.201.141@tcp: Connection restored to 192.168.201.141@tcp (at 192.168.201.141@tcp) [ 4833.313820] Lustre: lustre-MDT0000-mdc-ffff90cd045fc000: Connection restored to 192.168.201.141@tcp (at 192.168.201.141@tcp) [ 4835.377654] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4836.028816] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 4838.110167] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [ 4844.003827] Lustre: lustre-MDT0000-mdc-ffff90cd045fc000: Connection to lustre-MDT0000 (at 192.168.201.141@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4854.240946] LustreError: MGC192.168.201.141@tcp: Connection to MGS (at 192.168.201.141@tcp) was lost; in progress operations using this service will fail [ 4854.246365] Lustre: Evicted from MGS (at 192.168.201.141@tcp) after server handle changed from 0x98d05bafcd6ea809 to 0x98d05bafcd6eab19 [ 4854.250101] Lustre: MGC192.168.201.141@tcp: Connection restored to 192.168.201.141@tcp (at 192.168.201.141@tcp) [ 4857.893047] Lustre: lustre-MDT0000-mdc-ffff90cd045fc000: Connection restored to 192.168.201.141@tcp (at 192.168.201.141@tcp) [ 4859.653203] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4860.176397] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 4861.777600] Lustre: Unmounted lustre-client [ 4894.372614] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4896.136043] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4902.953630] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4913.649231] Lustre: Mounted lustre-client [ 4913.971902] Lustre: Unmounted lustre-client [ 4930.968677] Key type lgssc unregistered [ 4931.068539] LNet: 223587:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4931.070941] LNetError: 223587:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4931.079488] LNet: Removed LNI 192.168.201.41@tcp [ 4931.299113] Key type .llcrypt unregistered [ 4931.300126] Key type ._llcrypt unregistered [ 4936.431246] Key type ._llcrypt registered [ 4936.432244] Key type .llcrypt registered [ 4936.901188] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 01:37:52 (1782365872) [ 4938.261970] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4938.292538] alg: No test for adler32 (adler32-zlib) [ 4939.135938] Lustre: Lustre: Build Version: 2.17.54_83_gdfe0eaa [ 4939.217237] LNet: Added LNI 192.168.201.41@tcp [8/256/0/180] [ 4940.791125] Key type lgssc registered [ 4941.116720] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4960.765266] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4962.472288] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4968.861945] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4979.191773] Lustre: Mounted lustre-client [ 4982.142138] Lustre: Unmounted lustre-client [ 5013.914275] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5015.615832] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5023.760560] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5034.492568] Lustre: Mounted lustre-client [ 5040.814160] Lustre: Unmounted lustre-client [ 5077.447406] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5079.446229] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5086.660318] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5092.335907] Lustre: Mounted lustre-client [ 5092.680167] Lustre: Unmounted lustre-client [ 5109.727419] Key type lgssc unregistered [ 5109.845633] LNet: 230550:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5109.849109] LNetError: 230550:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5109.860658] LNet: Removed LNI 192.168.201.41@tcp [ 5110.103508] Key type .llcrypt unregistered [ 5110.104677] Key type ._llcrypt unregistered [ 5115.461053] Key type ._llcrypt registered [ 5115.462017] Key type .llcrypt registered [ 5115.925285] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 01:40:51 (1782366051) [ 5116.937741] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5116.970061] alg: No test for adler32 (adler32-zlib) [ 5117.830069] Lustre: Lustre: Build Version: 2.17.54_83_gdfe0eaa [ 5117.919885] LNet: Added LNI 192.168.201.41@tcp [8/256/0/180] [ 5119.503200] Key type lgssc registered [ 5119.858588] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5135.681132] Key type lgssc unregistered [ 5135.801337] LNet: 232864:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5135.803653] LNetError: 232864:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5135.811278] LNet: Removed LNI 192.168.201.41@tcp [ 5136.072912] Key type .llcrypt unregistered [ 5136.074110] Key type ._llcrypt unregistered [ 5145.191360] Key type ._llcrypt registered [ 5145.193633] Key type .llcrypt registered [ 5145.446062] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5145.453530] alg: No test for adler32 (adler32-zlib) [ 5146.325531] Lustre: Lustre: Build Version: 2.17.54_83_gdfe0eaa [ 5146.413718] LNet: Added LNI 192.168.201.41@tcp [8/256/0/180] [ 5147.999198] Key type lgssc registered [ 5148.408644] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5177.839643] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5179.768080] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5187.788237] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5218.729539] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [ 5219.238241] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 01:42:34 (1782366154) [ 5230.304346] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5231.931840] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5237.913464] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5245.568242] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 5251.058875] Lustre: Mounted lustre-client [ 5256.162437] Lustre: lustre-OST0001-osc-ffff90cd02b9d000: Connection to lustre-OST0001 (at 192.168.201.141@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5258.648457] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5268.920135] Lustre: Unmounted lustre-client [ 5292.153866] Key type lgssc unregistered [ 5292.311245] LNet: 239891:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5292.316635] LNetError: 239891:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5292.331217] LNet: Removed LNI 192.168.201.41@tcp [ 5292.645190] Key type .llcrypt unregistered [ 5292.646383] Key type ._llcrypt unregistered [ 5302.734917] Key type ._llcrypt registered [ 5302.736407] Key type .llcrypt registered [ 5303.193908] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 01:43:58 (1782366238) [ 5307.282480] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5307.289669] alg: No test for adler32 (adler32-zlib) [ 5308.133795] Lustre: Lustre: Build Version: 2.17.54_83_gdfe0eaa [ 5308.218709] LNet: Added LNI 192.168.201.41@tcp [8/256/0/180] [ 5309.807129] Key type lgssc registered [ 5310.185796] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5327.138618] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5328.861489] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5339.830988] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 01:44:35 (1782366275) [ 5349.400770] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5351.027273] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5358.381767] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5359.577797] Lustre: Mounted lustre-client [ 5365.990439] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 5367.371288] Lustre: Unmounted lustre-client [ 5419.669918] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5421.474770] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5456.593359] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5467.126357] Lustre: Mounted lustre-client [ 5467.760190] Lustre: Unmounted lustre-client [ 5494.295055] Key type lgssc unregistered [ 5494.435839] LNet: 247542:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5494.439297] LNetError: 247542:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5494.450060] LNet: Removed LNI 192.168.201.41@tcp [ 5494.709392] Key type .llcrypt unregistered [ 5494.710595] Key type ._llcrypt unregistered [ 5504.674056] Key type ._llcrypt registered [ 5504.675366] Key type .llcrypt registered [ 5504.941931] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5504.953518] alg: No test for adler32 (adler32-zlib) [ 5505.825618] Lustre: Lustre: Build Version: 2.17.54_83_gdfe0eaa [ 5505.927193] LNet: Added LNI 192.168.201.41@tcp [8/256/0/180] [ 5507.519261] Key type lgssc registered [ 5508.001076] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5539.346598] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5541.403594] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5550.058536] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5567.636955] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [ 5568.256858] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 01:48:23 (1782366503) [ 5579.934740] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5582.159934] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5590.838905] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5597.176955] Lustre: Mounted lustre-client [ 5597.904984] Lustre: Unmounted lustre-client [ 5616.584778] Key type lgssc unregistered [ 5616.750822] LNet: 254099:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5616.757704] LNetError: 254099:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5616.770605] LNet: Removed LNI 192.168.201.41@tcp [ 5617.086157] Key type .llcrypt unregistered [ 5617.087267] Key type ._llcrypt unregistered [ 5622.652308] Key type ._llcrypt registered [ 5622.653227] Key type .llcrypt registered [ 5623.168026] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [ 5623.902673] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 01:49:19 (1782366559) [ 5627.668249] Key type .llcrypt unregistered [ 5627.669481] Key type ._llcrypt unregistered [ 5630.405680] Key type ._llcrypt registered [ 5630.406792] Key type .llcrypt registered [ 5630.575625] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5630.581949] alg: No test for adler32 (adler32-zlib) [ 5631.449238] Lustre: Lustre: Build Version: 2.17.54_83_gdfe0eaa [ 5631.542249] LNet: Added LNI 192.168.201.41@tcp [8/256/0/180] [ 5633.127255] Key type lgssc registered [ 5633.700660] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5656.213701] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5667.318095] Lustre: Mounted lustre-client [ 5667.875210] Lustre: Unmounted lustre-client [ 5686.592548] Key type lgssc unregistered [ 5686.705813] LNet: 257535:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5686.710263] LNetError: 257535:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5686.723968] LNet: Removed LNI 192.168.201.41@tcp [ 5687.052981] Key type .llcrypt unregistered [ 5687.054883] Key type ._llcrypt unregistered [ 5692.643947] Key type ._llcrypt registered [ 5692.644828] Key type .llcrypt registered [ 5693.247663] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 01:50:28 (1782366628) [ 5694.289758] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5694.299244] alg: No test for adler32 (adler32-zlib) [ 5695.172613] Lustre: Lustre: Build Version: 2.17.54_83_gdfe0eaa [ 5695.269827] LNet: Added LNI 192.168.201.41@tcp [8/256/0/180] [ 5696.855155] Key type lgssc registered [ 5697.346491] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5761.430666] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5773.340030] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5775.915331] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5777.171097] Lustre: Mounted lustre-client [ 5777.846214] Lustre: Unmounted lustre-client [ 5796.601710] Key type lgssc unregistered [ 5796.775280] LNet: 261042:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5796.781149] LNetError: 261042:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5796.794246] LNet: Removed LNI 192.168.201.41@tcp [ 5797.162874] Key type .llcrypt unregistered [ 5797.164993] Key type ._llcrypt unregistered [ 5802.339812] Key type ._llcrypt registered [ 5802.341345] Key type .llcrypt registered [ 5802.856251] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 01:52:18 (1782366738) [ 5803.735931] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5803.743866] alg: No test for adler32 (adler32-zlib) [ 5804.614573] Lustre: Lustre: Build Version: 2.17.54_83_gdfe0eaa [ 5804.712246] LNet: Added LNI 192.168.201.41@tcp [8/256/0/180] [ 5806.303216] Key type lgssc registered [ 5806.883865] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5829.226679] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5831.837179] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5840.448674] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5848.029372] Lustre: Mounted lustre-client [ 5853.154398] Lustre: lustre-MDT0000-mdc-ffff90cd02ecd000: Connection to lustre-MDT0000 (at 192.168.201.141@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5855.800776] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5855.817330] LustreError: 264163:0:(file.c:6088:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [ 5855.988477] LustreError: MGC192.168.201.141@tcp: Connection to MGS (at 192.168.201.141@tcp) was lost; in progress operations using this service will fail [ 5856.025542] Lustre: Unmounted lustre-client [ 5868.087099] Key type lgssc unregistered [ 5868.245331] LNet: 264577:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5868.247450] LNetError: 264577:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5868.255261] LNet: Removed LNI 192.168.201.41@tcp [ 5868.458965] Key type .llcrypt unregistered [ 5868.459944] Key type ._llcrypt unregistered [ 5872.994071] Key type ._llcrypt registered [ 5872.995022] Key type .llcrypt registered [ 5873.430335] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 01:53:28 (1782366808) [ 5874.262972] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5874.317816] alg: No test for adler32 (adler32-zlib) [ 5875.182941] Lustre: Lustre: Build Version: 2.17.54_83_gdfe0eaa [ 5875.278613] LNet: Added LNI 192.168.201.41@tcp [8/256/0/180] [ 5876.863156] Key type lgssc registered [ 5877.236482] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5895.764426] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5897.382204] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5904.254686] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5914.624995] Lustre: Mounted lustre-client [ 5919.715553] Lustre: lustre-MDT0001-mdc-ffff90cd075f3800: Connection to lustre-MDT0001 (at 192.168.201.141@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5923.017236] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 5923.441158] Lustre: Unmounted lustre-client [ 5935.939300] Key type lgssc unregistered [ 5936.065455] LNet: 268129:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5936.069519] LNetError: 268129:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5936.081595] LNet: Removed LNI 192.168.201.41@tcp [ 5936.435918] Key type .llcrypt unregistered [ 5936.438044] Key type ._llcrypt unregistered [ 5942.358801] Key type ._llcrypt registered [ 5942.360440] Key type .llcrypt registered [ 5942.930709] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 01:54:38 (1782366878) [ 5951.026562] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5951.036520] alg: No test for adler32 (adler32-zlib) [ 5951.904333] Lustre: Lustre: Build Version: 2.17.54_83_gdfe0eaa [ 5952.002585] LNet: Added LNI 192.168.201.41@tcp [8/256/0/180] [ 5953.591215] Key type lgssc registered [ 5954.278621] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5992.947226] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5994.115819] Lustre: Mounted lustre-client [ 6007.780326] Lustre: lustre-MDT0001-mdc-ffff90cd0a64a800: Connection to lustre-MDT0001 (at 192.168.201.141@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6013.178169] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 6023.500625] Lustre: Unmounted lustre-client [ 6042.775971] Key type lgssc unregistered [ 6042.936423] LNet: 273885:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6042.941520] LNetError: 273885:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6042.953894] LNet: Removed LNI 192.168.201.41@tcp [ 6043.376068] Key type .llcrypt unregistered [ 6043.378274] Key type ._llcrypt unregistered [ 6050.004123] Key type ._llcrypt registered [ 6050.005261] Key type .llcrypt registered [ 6050.644476] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 01:56:25 (1782366985) [ 6051.281183] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [ 6051.874741] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 01:56:27 (1782366987) [ 6052.442942] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [ 6053.097178] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 01:56:28 (1782366988) [ 6053.853243] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [ 6054.643626] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 01:56:30 (1782366990) [ 6055.254386] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [ 6055.808581] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 01:56:31 (1782366991) [ 6056.392317] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [ 6056.974616] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 01:56:32 (1782366992) [ 6061.378603] Key type .llcrypt unregistered [ 6061.380906] Key type ._llcrypt unregistered [ 6063.999255] Key type ._llcrypt registered [ 6064.002359] Key type .llcrypt registered [ 6064.238268] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6064.251101] alg: No test for adler32 (adler32-zlib) [ 6065.163111] Lustre: Lustre: Build Version: 2.17.54_83_gdfe0eaa [ 6065.269373] LNet: Added LNI 192.168.201.41@tcp [8/256/0/180] [ 6066.855160] Key type lgssc registered [ 6067.528047] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6100.236978] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6102.383953] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6112.648549] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6113.901124] Lustre: Mounted lustre-client [ 6114.542683] Lustre: Unmounted lustre-client [ 6139.078778] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 01:57:54 (1782367074) [ 6151.990330] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6153.843384] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6173.662695] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6189.558614] Lustre: Mounted lustre-client [ 6189.902503] Lustre: Unmounted lustre-client [ 6219.747593] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 01:59:15 (1782367155) [ 6226.079116] LNet: 1 local NIs in recovery (showing 1): 192.168.201.41@tcp [ 6815.983367] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6818.367901] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6840.425213] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6897.223526] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 02:10:32 (1782367832) [ 6902.732065] Key type lgssc unregistered [ 6902.872706] LNet: 287306:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6902.877277] LNetError: 287306:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6902.889591] LNet: Removed LNI 192.168.201.41@tcp [ 6903.260269] Key type .llcrypt unregistered [ 6903.262295] Key type ._llcrypt unregistered [ 6968.361237] Key type ._llcrypt registered [ 6968.362557] Key type .llcrypt registered [ 6968.555310] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6968.560758] alg: No test for adler32 (adler32-zlib) [ 6969.417588] Lustre: Lustre: Build Version: 2.17.54_83_gdfe0eaa [ 6969.508036] LNet: Added LNI 192.168.201.41@tcp [8/256/0/180] [ 6971.087190] Key type lgssc registered [ 6971.748518] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7011.644114] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7013.829921] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7024.107104] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7037.682429] Key type lgssc unregistered [ 7037.786519] LNet: 290953:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7037.788633] LNetError: 290953:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7037.798522] LNet: Removed LNI 192.168.201.41@tcp [ 7038.019922] Key type .llcrypt unregistered [ 7038.020974] Key type ._llcrypt unregistered [ 7066.739257] Key type ._llcrypt registered [ 7066.740287] Key type .llcrypt registered [ 7067.415362] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 02:13:22 (1782368002) [ 7076.515749] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7076.548095] alg: No test for adler32 (adler32-zlib) [ 7077.405984] Lustre: Lustre: Build Version: 2.17.54_83_gdfe0eaa [ 7077.492697] LNet: Added LNI 192.168.201.41@tcp [8/256/0/180] [ 7079.071337] Key type lgssc registered [ 7079.692515] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7103.941982] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 02:13:59 (1782368039) [ 7108.996155] Key type lgssc unregistered [ 7109.126570] LNet: 294498:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7109.131247] LNetError: 294498:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7109.144815] LNet: Removed LNI 192.168.201.41@tcp [ 7109.404886] Key type .llcrypt unregistered [ 7109.405809] Key type ._llcrypt unregistered [ 7113.123507] Key type ._llcrypt registered [ 7113.124524] Key type .llcrypt registered [ 7113.330727] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7113.335389] alg: No test for adler32 (adler32-zlib) [ 7114.203212] Lustre: Lustre: Build Version: 2.17.54_83_gdfe0eaa [ 7114.292877] LNet: Added LNI 192.168.201.41@tcp [8/256/0/180] [ 7115.871211] Key type lgssc registered [ 7116.412978] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7139.057717] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7141.378575] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7151.503881] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7235.986796] LNet: Added LNI 192.168.201.41@tcp50 [8/256/0/180] [ 7236.684552] LNet: Removed LNI 192.168.201.41@tcp [ 7238.061746] Lustre: 298829:0:(ldlm_lib.c:563:client_obd_setup()) lustre-MDT0000-mdc-ffff90cd02ecd800: config has no valid NIDs, force dynamic NIDs [ 7239.105775] Lustre: Mounted lustre-client [ 7239.151536] Lustre: Unmounted lustre-client [ 7239.937747] Key type lgssc unregistered [ 7240.077895] LNet: 299088:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7240.080147] LNetError: 299088:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7240.089321] LNet: Removed LNI 192.168.201.41@tcp50 [ 7240.275434] Key type .llcrypt unregistered [ 7240.276482] Key type ._llcrypt unregistered [ 7249.985200] Key type ._llcrypt registered [ 7249.986231] Key type .llcrypt registered [ 7250.110986] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7250.116156] alg: No test for adler32 (adler32-zlib) [ 7250.960208] Lustre: Lustre: Build Version: 2.17.54_83_gdfe0eaa [ 7251.047169] LNet: Added LNI 192.168.201.41@tcp [8/256/0/180] [ 7252.623142] Key type lgssc registered [ 7252.962954] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7280.867541] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7282.461108] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7284.835449] LNet: Added LNI 192.168.201.41@tcp50 [8/256/0/180] [ 7285.104830] LNet: Removed LNI 192.168.201.41@tcp [ 7286.190494] Lustre: 301755:0:(ldlm_lib.c:563:client_obd_setup()) lustre-MDT0000-mdc-ffff90cd0413c000: config has no valid NIDs, force dynamic NIDs [ 7287.239878] Lustre: Mounted lustre-client [ 7287.407187] Lustre: Unmounted lustre-client [ 7307.413766] Key type lgssc unregistered [ 7307.587220] LNet: 302624:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7307.593546] LNetError: 302624:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7307.612864] LNet: Removed LNI 192.168.201.41@tcp50 [ 7307.904722] Key type .llcrypt unregistered [ 7307.906515] Key type ._llcrypt unregistered [ 7312.800532] Key type ._llcrypt registered [ 7312.801816] Key type .llcrypt registered [ 7313.420749] Lustre: DEBUG MARKER: == conf-sanity test 73f: mgc register before connect ===== 02:17:28 (1782368248) [ 7321.103556] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7321.117870] alg: No test for adler32 (adler32-zlib) [ 7322.025644] Lustre: Lustre: Build Version: 2.17.54_83_gdfe0eaa [ 7322.149318] LNet: Added LNI 192.168.201.41@tcp [8/256/0/180] [ 7323.743150] Key type lgssc registered [ 7324.173436] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7355.245698] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7357.158750] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7365.615121] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7384.330543] Key type lgssc unregistered [ 7384.438274] LNet: 307326:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7384.440521] LNetError: 307326:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7384.447297] LNet: Removed LNI 192.168.201.41@tcp [ 7384.666542] Key type .llcrypt unregistered [ 7384.667493] Key type ._llcrypt unregistered [ 7388.308565] Key type ._llcrypt registered [ 7388.309660] Key type .llcrypt registered [ 7388.728586] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 02:18:44 (1782368324) [ 7389.406990] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7389.443228] alg: No test for adler32 (adler32-zlib) [ 7390.286850] Lustre: Lustre: Build Version: 2.17.54_83_gdfe0eaa [ 7390.364225] LNet: Added LNI 192.168.201.41@tcp [8/256/0/180] [ 7391.943198] Key type lgssc registered [ 7392.364039] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7410.149770] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7411.750929] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7418.088269] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7428.592804] Lustre: Mounted lustre-client [ 7449.237136] Lustre: Mounted fs15246-client [ 7454.261201] Lustre: Unmounted fs15246-client [ 7467.270285] Lustre: Unmounted lustre-client [ 7501.343084] Key type lgssc unregistered [ 7501.457602] LNet: 312139:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7501.460041] LNetError: 312139:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7501.466857] LNet: Removed LNI 192.168.201.41@tcp [ 7501.715596] Key type .llcrypt unregistered [ 7501.716897] Key type ._llcrypt unregistered [ 7505.386143] Key type ._llcrypt registered [ 7505.387243] Key type .llcrypt registered [ 7505.859348] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 02:20:41 (1782368441) [ 7506.538420] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7506.548893] alg: No test for adler32 (adler32-zlib) [ 7507.415668] Lustre: Lustre: Build Version: 2.17.54_83_gdfe0eaa [ 7507.510956] LNet: Added LNI 192.168.201.41@tcp [8/256/0/180] [ 7509.095153] Key type lgssc registered [ 7509.478152] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7557.138551] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7559.087264] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7567.296181] Lustre: DEBUG MARKER: oleg141-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7616.053400] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 7483 sec ========== 02:22:31 (1782368551) [ 7616.650186] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 02:22:32 (1782368552) === [ 7617.793826] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 02:22:33 (1782368553) === [ 7629.914714] Key type lgssc unregistered [ 7630.024546] LNet: 321342:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7630.027642] LNetError: 321342:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7630.036665] LNet: Removed LNI 192.168.201.41@tcp [ 7630.311657] Key type .llcrypt unregistered [ 7630.313096] Key type ._llcrypt unregistered