[ 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 3.0.0 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.16.3-2.fc40 04/01/2014 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 396269333 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 0x000f5410-0x000f541f] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F5220 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE1D6F 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE1C0B 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 001BCB (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE1C7F 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE1D0F 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE1D47 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe1c0b-0xbffe1c7e] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe1c0a] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe1c7f-0xbffe1d0e] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe1d0f-0xbffe1d46] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe1d47-0xbffe1d6e] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001013] APIC: Switch to symmetric I/O mode setup [ 0.003130] x2apic enabled [ 0.004008] Switched APIC routing to physical x2apic. [ 0.005009] kvm-guest: setup PV IPIs [ 0.008371] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.009000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.009021] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.010012] pid_max: default: 32768 minimum: 301 [ 0.011215] LSM: Security Framework initializing [ 0.012082] Yama: becoming mindful. [ 0.014036] SELinux: Initializing. [ 0.015092] *** VALIDATE selinux *** [ 0.023432] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.027456] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.028129] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029097] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.030104] *** VALIDATE tmpfs *** [ 0.031434] *** VALIDATE proc *** [ 0.032170] *** VALIDATE cgroup *** [ 0.033006] *** VALIDATE cgroup2 *** [ 0.034225] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.035135] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.036004] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.037020] Spectre V2 : User space: Vulnerable [ 0.038005] Speculative Store Bypass: Vulnerable [ 0.040279] debug: unmapping init [mem 0xffffffffacc59000-0xffffffffacc60fff] [ 0.042155] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.043662] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.044016] ... version: 2 [ 0.045008] ... bit width: 48 [ 0.046009] ... generic registers: 4 [ 0.047008] ... value mask: 0000ffffffffffff [ 0.048006] ... max period: 00007fffffffffff [ 0.049007] ... fixed-purpose events: 3 [ 0.050006] ... event mask: 000000070000000f [ 0.051242] rcu: Hierarchical SRCU implementation. [ 0.053156] smp: Bringing up secondary CPUs ... [ 0.054503] x86: Booting SMP configuration: [ 0.055025] .... node #0, CPUs: #1 #2 #3 [ 0.058107] smp: Brought up 1 node, 4 CPUs [ 0.059972] smpboot: Max logical packages: 1 [ 0.060013] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.165162] node 0 deferred pages initialised in 104ms [ 0.168150] devtmpfs: initialized [ 0.169275] x86/mm: Memory block size: 128MB [ 0.171902] gcov: version magic: 0x41383552 [ 0.173301] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.176109] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.178428] pinctrl core: initialized pinctrl subsystem [ 0.181203] [ 0.181807] ************************************************************* [ 0.183011] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.185011] ** ** [ 0.187015] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.189012] ** ** [ 0.191014] ** This means that this kernel is built to expose internal ** [ 0.193013] ** IOMMU data structures, which may compromise security on ** [ 0.196016] ** your system. ** [ 0.198017] ** ** [ 0.200018] ** If you see this message and you are not debugging the ** [ 0.202010] ** kernel, report this immediately to your vendor! ** [ 0.203012] ** ** [ 0.205012] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.207013] ************************************************************* [ 0.209768] NET: Registered protocol family 16 [ 0.211422] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.213057] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.216084] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.220168] cpuidle: using governor menu [ 0.221579] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.223344] PCI: Using configuration type 1 for base access [ 0.225127] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.233136] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.235027] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.238136] cryptd: max_cpu_qlen set to 1000 [ 0.239170] ACPI: Added _OSI(Module Device) [ 0.240008] ACPI: Added _OSI(Processor Device) [ 0.241019] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.242000] ACPI: Added _OSI(Processor Aggregator Device) [ 0.245787] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.251340] ACPI: Interpreter enabled [ 0.253060] ACPI: PM: (supports S0 S3 S4 S5) [ 0.254027] ACPI: Using IOAPIC for interrupt routing [ 0.256144] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.259448] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.268885] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.270031] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.273016] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.275088] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.280332] acpiphp: Slot [2] registered [ 0.281139] acpiphp: Slot [5] registered [ 0.283129] acpiphp: Slot [6] registered [ 0.284056] acpiphp: Slot [3] registered [ 0.285037] acpiphp: Slot [4] registered [ 0.286082] acpiphp: Slot [7] registered [ 0.287075] acpiphp: Slot [8] registered [ 0.288067] acpiphp: Slot [9] registered [ 0.289052] acpiphp: Slot [10] registered [ 0.290060] acpiphp: Slot [11] registered [ 0.292099] acpiphp: Slot [12] registered [ 0.293119] acpiphp: Slot [13] registered [ 0.294093] acpiphp: Slot [14] registered [ 0.296079] acpiphp: Slot [15] registered [ 0.297074] acpiphp: Slot [16] registered [ 0.299139] acpiphp: Slot [17] registered [ 0.301092] acpiphp: Slot [18] registered [ 0.302131] acpiphp: Slot [19] registered [ 0.304097] acpiphp: Slot [20] registered [ 0.305080] acpiphp: Slot [21] registered [ 0.307091] acpiphp: Slot [22] registered [ 0.308081] acpiphp: Slot [23] registered [ 0.309095] acpiphp: Slot [24] registered [ 0.311129] acpiphp: Slot [25] registered [ 0.312099] acpiphp: Slot [26] registered [ 0.314114] acpiphp: Slot [27] registered [ 0.316144] acpiphp: Slot [28] registered [ 0.317135] acpiphp: Slot [29] registered [ 0.319097] acpiphp: Slot [30] registered [ 0.320076] acpiphp: Slot [31] registered [ 0.322076] PCI host bridge to bus 0000:00 [ 0.323015] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.325017] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.327017] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.330020] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.332017] pci_bus 0000:00: root bus resource [mem 0x380000000000-0x38007fffffff window] [ 0.335028] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.338178] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.340864] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.342815] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.348536] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.351059] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.353010] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.354028] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.356011] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.359517] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.361604] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.363029] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.364707] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.369013] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.376012] pci 0000:00:02.0: reg 0x20: [mem 0x380000000000-0x380000003fff 64bit pref] [ 0.380012] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.384432] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.391023] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.397022] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.409024] pci 0000:00:05.0: reg 0x20: [mem 0x380000004000-0x380000007fff 64bit pref] [ 0.418276] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.422014] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.426016] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.438015] pci 0000:00:06.0: reg 0x20: [mem 0x380000008000-0x38000000bfff 64bit pref] [ 0.445703] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.449370] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.451301] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.453193] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.454170] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.459013] iommu: Default domain type: Passthrough [ 0.460389] SCSI subsystem initialized [ 0.461128] ACPI: bus type USB registered [ 0.463111] usbcore: registered new interface driver usbfs [ 0.465062] usbcore: registered new interface driver hub [ 0.466085] usbcore: registered new device driver usb [ 0.468150] pps_core: LinuxPPS API ver. 1 registered [ 0.470010] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.472079] PTP clock support registered [ 0.475075] EDAC MC: Ver: 3.0.0 [ 0.477192] PCI: Using ACPI for IRQ routing [ 0.478681] NetLabel: Initializing [ 0.480014] NetLabel: domain hash size = 128 [ 0.481006] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.483060] NetLabel: unlabeled traffic allowed by default [ 0.485112] vgaarb: loaded [ 0.486185] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.488014] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.494426] clocksource: Switched to clocksource kvm-clock [ 0.588117] VFS: Disk quotas dquot_6.6.0 [ 0.589422] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.592431] *** VALIDATE ramfs *** [ 0.593928] *** VALIDATE hugetlbfs *** [ 0.596585] pnp: PnP ACPI init [ 0.599088] pnp: PnP ACPI: found 6 devices [ 0.614231] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.616886] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.618674] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.620401] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.623593] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.625990] pci_bus 0000:00: resource 8 [mem 0x380000000000-0x38007fffffff window] [ 0.628732] NET: Registered protocol family 2 [ 0.631184] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.635910] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.639389] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.643744] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.647606] TCP: Hash tables configured (established 65536 bind 65536) [ 0.649820] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.652071] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.654135] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.656320] NET: Registered protocol family 1 [ 0.658444] RPC: Registered named UNIX socket transport module. [ 0.660058] RPC: Registered udp transport module. [ 0.661260] RPC: Registered tcp transport module. [ 0.662523] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.664198] NET: Registered protocol family 44 [ 0.665332] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.666904] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.668463] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.670113] PCI: CLS 0 bytes, default 64 [ 0.671306] Unpacking initramfs... [ 2.045512] debug: unmapping init [mem 0xffff8d907cc64000-0xffff8d907ffcffff] [ 2.051267] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.053332] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.056214] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.544339] Initialise system trusted keyrings [ 2.545360] Key type blacklist registered [ 2.546990] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.556091] zbud: loaded [ 2.559049] *** VALIDATE nfs *** [ 2.560235] *** VALIDATE nfs4 *** [ 2.561828] pstore: using deflate compression [ 2.564908] Platform Keyring initialized [ 2.670430] NET: Registered protocol family 38 [ 2.672028] Key type asymmetric registered [ 2.673367] Asymmetric key parser 'x509' registered [ 2.674941] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.677684] io scheduler mq-deadline registered [ 2.680179] io scheduler kyber registered [ 2.682544] io scheduler bfq registered [ 2.684428] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.687224] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.690146] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.693258] ACPI: Power Button [PWRF] [ 2.790884] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.881018] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.975297] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.004526] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.034391] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.038366] Non-volatile memory driver v1.3 [ 3.040543] Linux agpgart interface v0.103 [ 3.076307] virtio_blk virtio1: [vda] 67992 512-byte logical blocks (34.8 MB/33.2 MiB) [ 3.078628] vda: detected capacity change from 0 to 34811904 [ 3.096397] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.099036] vdb: detected capacity change from 0 to 1073741824 [ 3.107321] libphy: Fixed MDIO Bus: probed [ 3.114586] usbcore: registered new interface driver usbserial_generic [ 3.116969] usbserial: USB Serial support registered for generic [ 3.119454] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.123513] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.125431] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.128059] mousedev: PS/2 mouse device common for all mice [ 3.132098] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.132789] rtc_cmos 00:05: RTC can wake from S4 [ 3.137962] rtc_cmos 00:05: registered as rtc0 [ 3.140953] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.141572] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.145673] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.147323] intel_pstate: CPU model not supported [ 3.154067] hid: raw HID events driver (C) Jiri Kosina [ 3.156621] usbcore: registered new interface driver usbhid [ 3.158346] usbhid: USB HID core driver [ 3.159891] drop_monitor: Initializing network drop monitor service [ 3.162369] Initializing XFRM netlink socket [ 3.164639] NET: Registered protocol family 10 [ 3.169532] Segment Routing with IPv6 [ 3.172133] NET: Registered protocol family 17 [ 3.174203] mpls_gso: MPLS GSO support [ 3.179823] RAS: Correctable Errors collector initialized. [ 3.181807] AVX version of gcm_enc/dec engaged. [ 3.183183] AES CTR mode by8 optimization enabled [ 3.268301] sched_clock: Marking stable (3268279623, 0)->(4064792291, -796512668) [ 3.271148] registered taskstats version 1 [ 3.273672] Loading compiled-in X.509 certificates [ 3.275521] zswap: loaded using pool lzo/zbud [ 3.300482] Key type big_key registered [ 3.313423] Key type encrypted registered [ 3.314413] ima: No TPM chip found, activating TPM-bypass! [ 3.315584] ima: Allocated hash algorithm: sha1 [ 3.317301] ima: No architecture policies found [ 3.319256] evm: Initialising EVM extended attributes: [ 3.320820] evm: security.selinux [ 3.321637] evm: security.ima [ 3.322569] evm: security.capability [ 3.323479] evm: HMAC attrs: 0x1 [ 3.325656] rtc_cmos 00:05: setting system clock to 2025-10-24 16:02:42 UTC (1761321762) [ 3.334333] debug: unmapping init [mem 0xffffffffadc03000-0xffffffffaddfffff] [ 3.337536] debug: unmapping init [mem 0xffffffffac982000-0xffffffffacc58fff] [ 3.346106] Write protecting the kernel read-only data: 28672k [ 3.349030] debug: unmapping init [mem 0xffffffffab003000-0xffffffffab1fffff] [ 3.351509] debug: unmapping init [mem 0xffffffffab914000-0xffffffffab9fffff] [ 3.383426] 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.391649] systemd[1]: Detected virtualization kvm. [ 3.393572] systemd[1]: Detected architecture x86-64. [ 3.395489] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.423123] systemd[1]: No hostname configured. [ 3.424559] systemd[1]: Set hostname to . [ 3.427549] random: systemd: uninitialized urandom read (16 bytes read) [ 3.430053] systemd[1]: Initializing machine ID from random generator. [ 3.485189] random: ln: uninitialized urandom read (6 bytes read) [ 3.575259] random: systemd: uninitialized urandom read (16 bytes read) [ 3.578027] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ 3.583851] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ 3.592073] systemd[1]: Reached target Paths. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Initrd Root Device. [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Local File Systems. [ OK ] Reached target Timers. [ OK ] Listening on udev Control Socket. [ OK ] Listening on Journal Socket. Starting Setup Virtual Console... [ OK ] Started Memstrack Anylazing Service. Starting Create Volatile Files and Directories... Starting Journal Service... [ OK ] Reached target Slices. [ OK ] Reached target Swap. [ OK ] Reached target Sockets. Starting Create list of required st…ce nodes for the current kernel... Starting Apply Kernel Variables... [ OK ] Started Setup Virtual Console. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. 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... [ 4.362121] device-mapper: uevent: version 1.0.3 [ 4.364496] 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. [ 5.258409] virtio_net virtio0 ens2: renamed from eth0 [ 5.333058] scsi host0: ata_piix [ 5.351116] scsi host1: ata_piix [ 5.352494] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.354712] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 6.269858] random: fast init done [ 9.846269] random: crng init done [ 9.847397] random: 7 urandom warning(s) missed due to ratelimiting [ 9.916908] dracut-initqueue[586]: RTNETLINK answers: File exists [ 11.025744] hrtimer: interrupt took 95369799 ns 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... [ 12.253423] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Timers. Stopping Hardware RNG Entropy Gatherer Daemon... [ 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 Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Swap. [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ 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... [ 14.295352] printk: systemd: 26 output lines suppressed due to ratelimiting [ 14.682921] SELinux: Disabled at runtime. [ 14.757857] 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) [ 14.769249] systemd[1]: Detected virtualization kvm. [ 14.771684] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 15.518218] systemd[1]: initrd-switch-root.service: Succeeded. [ 15.522386] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 15.531467] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 15.541353] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 15.550839] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 15.565841] systemd[1]: Starting Journal Service... Starting Journal Service... [ 15.582740] systemd[1]: Mounting Huge Pages File System... Mounting Huge Pages File System... Starting Create list of required st…ce nodes for the current kernel... Activating swap /dev/disk/by-label/SWAP... [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Created slice system-getty.slice. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Listening on RPCbind Server Activation Socket. [ 15.702789] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Reached target RPC Port Mapper. Mounting Kernel Debug File System... [ OK ] Listening on initctl Compatibility Named Pipe. Mounting POSIX Message Queue File System... [ OK ] Created slice User and Session Slice. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target rpc_pipefs.target. [ OK ] Reached target Slices. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Listening on udev Control Socket. [ OK ] Listening on Process Core Dump Socket. [ OK ] Reached target Local Encrypted Volumes. Starting udev Coldplug all Devices... [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Stopped target Initrd Root File System. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. Starting Apply Kernel Variables... Starting Remount Root and Kernel File Systems... [ OK ] Started Journal Service. [ OK ] Mounted Huge Pages File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted Kernel Debug File System. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Started Apply Kernel Variables. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. Starting Configure read-only root support... [ OK ] Reached target Swap. Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... Starting udev Kernel Device Manager... [ OK ] Started udev Coldplug all Devices. [ OK ] Mounted /mnt. [ 16.572813] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 17.336721] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 17.363416] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 17.807793] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 17.900118] EDAC sbridge: Ver: 1.1.2 [ 20.047294] Key type dns_resolver registered [ 20.403577] NFS: Registering the id_resolver key type [ 20.405731] Key type id_resolver registered [ 20.409481] Key type id_legacy registered [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started D-Bus System Message Bus. [ OK ] Started irqbalance daemon. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Restore /run/initramfs on shutdown... Starting Network Manager... Starting Login Service... [ OK ] Reached target sshd-keygen.target. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting OpenSSH server daemon... Starting Dynamic System Tuning Daemon... Starting Network Manager Wait Online... [ OK ] Started OpenSSH server daemon. [ OK ] Started GSSAPI Proxy Daemon. Starting Hostname Service... [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Command Scheduler. [ OK ] Started Getty on tty1. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Crash recovery kernel arming... Starting Notify NFS peers of a restart... Starting System Logging Service... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg132-client login: [ 58.849236] libcfs: loading out-of-tree module taints kernel. [ 58.911551] alg: No test for adler32 (adler32-zlib) [ 59.665372] Key type ._llcrypt registered [ 59.666921] Key type .llcrypt registered [ 59.825355] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 60.150682] Lustre: Lustre: Build Version: 2.15.7_12_gd220bd5 [ 60.472322] LNet: Added LNI 192.168.201.32@tcp [8/256/0/180] [ 60.474808] LNet: Accept secure, port 988 [ 62.103113] Key type lgssc registered [ 62.679723] Lustre: Echo OBD driver; http://www.lustre.org/ [ 121.114240] Lustre: Mounted lustre-client [ 123.681664] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 135.081823] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing check_logdir /tmp/testlogs/ [ 136.924033] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing yml_node [ 139.274173] Lustre: DEBUG MARKER: Client: 2.15.7.12 [ 140.459213] Lustre: DEBUG MARKER: MDS: 2.15.7.12 [ 141.614867] Lustre: DEBUG MARKER: OSS: 2.15.7.12 [ 142.312782] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Fri Oct 24 12:05:00 EDT 2025 [ 145.448802] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball 110 [ 146.014810] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 [ 146.525820] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 156.784201] Lustre: Unmounted lustre-client [ 214.663069] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 223.956697] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 243.151257] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 12:06:41 (1761322001) [ 244.408510] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 245.303236] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 12:06:43 (1761322003) [ 253.510155] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 261.927364] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 267.247377] Lustre: Mounted lustre-client [ 269.231741] Lustre: Unmounted lustre-client [ 286.923248] Key type lgssc unregistered [ 287.064029] LNet: 12126:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 288.096715] LNet: Removed LNI 192.168.201.32@tcp [ 288.447246] Key type .llcrypt unregistered [ 288.449346] Key type ._llcrypt unregistered [ 295.112056] alg: No test for adler32 (adler32-zlib) [ 295.864381] Key type ._llcrypt registered [ 295.865468] Key type .llcrypt registered [ 296.428282] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 12:07:34 (1761322054) [ 297.402492] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 297.530612] Lustre: Lustre: Build Version: 2.15.7_12_gd220bd5 [ 297.619336] LNet: Added LNI 192.168.201.32@tcp [8/256/0/180] [ 297.621350] LNet: Accept secure, port 988 [ 299.215385] Key type lgssc registered [ 299.694322] Lustre: Echo OBD driver; http://www.lustre.org/ [ 316.069878] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 323.802648] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 330.206175] Lustre: Mounted lustre-client [ 333.368822] Lustre: Unmounted lustre-client [ 350.024305] Key type lgssc unregistered [ 350.132518] LNet: 15086:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 351.199565] LNet: Removed LNI 192.168.201.32@tcp [ 351.458044] Key type .llcrypt unregistered [ 351.459651] Key type ._llcrypt unregistered [ 357.134939] alg: No test for adler32 (adler32-zlib) [ 357.886388] Key type ._llcrypt registered [ 357.888291] Key type .llcrypt registered [ 358.418138] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 12:08:36 (1761322116) [ 359.285271] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 359.511705] Lustre: Lustre: Build Version: 2.15.7_12_gd220bd5 [ 359.594919] LNet: Added LNI 192.168.201.32@tcp [8/256/0/180] [ 359.597421] LNet: Accept secure, port 988 [ 361.183162] Key type lgssc registered [ 361.591918] Lustre: Echo OBD driver; http://www.lustre.org/ [ 377.672952] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 385.604646] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 392.148160] Lustre: Mounted lustre-client [ 397.286402] Lustre: lustre-MDT0000-mdc-ffff8d90c7f91800: Connection to lustre-MDT0000 (at 192.168.201.132@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 401.703889] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 409.503171] Lustre: 16015:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761322161/real 1761322161] req@0000000018f53c89 x1846880101206336/t0(0) o400->MGC192.168.201.132@tcp@192.168.201.132@tcp:26/25 lens 224/224 e 0 to 1 dl 1761322168 ref 1 fl Rpc:XNQr/0/ffffffff rc 0/-1 job:'' [ 409.513119] LustreError: 166-1: MGC192.168.201.132@tcp: Connection to MGS (at 192.168.201.132@tcp) was lost; in progress operations using this service will fail [ 411.954127] Lustre: Unmounted lustre-client [ 422.249937] Key type lgssc unregistered [ 422.378434] LNet: 18048:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 423.395240] LNet: Removed LNI 192.168.201.32@tcp [ 423.663973] Key type .llcrypt unregistered [ 423.665995] Key type ._llcrypt unregistered [ 429.647867] alg: No test for adler32 (adler32-zlib) [ 430.399398] Key type ._llcrypt registered [ 430.400400] Key type .llcrypt registered [ 431.013209] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 12:09:48 (1761322188) [ 431.973759] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 432.106691] Lustre: Lustre: Build Version: 2.15.7_12_gd220bd5 [ 432.199898] LNet: Added LNI 192.168.201.32@tcp [8/256/0/180] [ 432.202769] LNet: Accept secure, port 988 [ 433.799129] Key type lgssc registered [ 434.234545] Lustre: Echo OBD driver; http://www.lustre.org/ [ 448.624287] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 455.614216] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 462.296198] Lustre: Mounted lustre-client [ 467.426718] Lustre: lustre-OST0000-osc-ffff8d90c87f4800: Connection to lustre-OST0000 (at 192.168.201.132@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 471.339180] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 481.589900] Lustre: Unmounted lustre-client [ 498.647845] Key type lgssc unregistered [ 498.778914] LNet: 21010:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 499.807503] LNet: Removed LNI 192.168.201.32@tcp [ 500.079776] Key type .llcrypt unregistered [ 500.081203] Key type ._llcrypt unregistered [ 505.802428] alg: No test for adler32 (adler32-zlib) [ 506.554513] Key type ._llcrypt registered [ 506.556057] Key type .llcrypt registered [ 507.113787] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 12:11:04 (1761322264) [ 507.984973] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 508.244272] Lustre: Lustre: Build Version: 2.15.7_12_gd220bd5 [ 508.338948] LNet: Added LNI 192.168.201.32@tcp [8/256/0/180] [ 508.341031] LNet: Accept secure, port 988 [ 509.935133] Key type lgssc registered [ 510.411050] Lustre: Echo OBD driver; http://www.lustre.org/ [ 525.525124] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 533.338519] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 539.615375] Lustre: Mounted lustre-client [ 541.750210] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 543.065673] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 547.814681] LNetError: 120-3: Refusing connection from 192.168.201.32 for 127.0.0.2@tcp: No matching NI [ 547.817667] LNetError: 21925:0:(socklnd_cb.c:1771:ksocknal_recv_hello()) Error -104 reading HELLO from 127.0.0.2 [ 547.821096] LNetError: 11b-b: Connection to 127.0.0.2@tcp at host 127.0.0.2:988 was reset: is it running a compatible version of Lustre and is 127.0.0.2@tcp one of its NIDs? [ 553.705548] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2025-10-2412h11m52s [ 554.544597] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 560.097984] Lustre: lustre-MDT0000-mdc-ffff8d90c84b3000: Connection to lustre-MDT0000 (at 192.168.201.132@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 560.111360] LNetError: 120-3: Refusing connection from 192.168.201.32 for 127.0.0.2@tcp: No matching NI [ 560.114683] LNetError: 21926:0:(socklnd_cb.c:1771:ksocknal_recv_hello()) Error -104 reading HELLO from 127.0.0.2 [ 560.119508] LNetError: 11b-b: Connection to 127.0.0.2@tcp at host 127.0.0.2:988 was reset: is it running a compatible version of Lustre and is 127.0.0.2@tcp one of its NIDs? [ 565.223954] LNetError: 120-3: Refusing connection from 192.168.201.32 for 127.0.0.2@tcp: No matching NI [ 565.228772] LNetError: 21927:0:(socklnd_cb.c:1771:ksocknal_recv_hello()) Error -104 reading HELLO from 127.0.0.2 [ 565.233244] LNetError: 11b-b: Connection to 127.0.0.2@tcp at host 127.0.0.2:988 was reset: is it running a compatible version of Lustre and is 127.0.0.2@tcp one of its NIDs? [ 570.516674] LustreError: 24187:0:(lmv_obd.c:1273:lmv_statfs()) lustre-MDT0000-mdc-ffff8d90c84b3000: can't stat MDS #0: rc = -110 [ 571.085059] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 575.457379] LustreError: 166-1: MGC192.168.201.132@tcp: Connection to MGS (at 192.168.201.132@tcp) was lost; in progress operations using this service will fail [ 575.465263] Lustre: Evicted from MGS (at 192.168.201.132@tcp) after server handle changed from 0x48f5246270806566 to 0x48f52462708068a7 [ 575.470768] Lustre: MGC192.168.201.132@tcp: Connection restored to 192.168.201.132@tcp (at 192.168.201.132@tcp) [ 576.979288] Lustre: DEBUG MARKER: Wait for df (24187) ... [ 577.547450] Lustre: DEBUG MARKER: done [ 577.895091] Lustre: Unmounted lustre-client [ 590.640477] Key type lgssc unregistered [ 590.748772] LNet: 25128:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 591.775952] LNet: Removed LNI 192.168.201.32@tcp [ 592.062611] Key type .llcrypt unregistered [ 592.064261] Key type ._llcrypt unregistered [ 608.027071] alg: No test for adler32 (adler32-zlib) [ 608.781431] Key type ._llcrypt registered [ 608.783122] Key type .llcrypt registered [ 608.867656] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 609.008882] Lustre: Lustre: Build Version: 2.15.7_12_gd220bd5 [ 609.107309] LNet: Added LNI 192.168.201.32@tcp [8/256/0/180] [ 609.109830] LNet: Accept secure, port 988 [ 610.711318] Key type lgssc registered [ 611.195643] Lustre: Echo OBD driver; http://www.lustre.org/ [ 635.423621] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 644.655797] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 672.357417] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 12:13:50 (1761322430) [ 679.729813] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 687.796462] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 694.228492] Lustre: Mounted lustre-client [ 696.589725] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2025-10-2412h14m15s [ 697.234757] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 700.038452] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 701.066193] Lustre: DEBUG MARKER: Creating a test file and stat it [ 705.510711] LNetError: 120-3: Refusing connection from 192.168.201.32 for 127.0.0.2@tcp: No matching NI [ 705.514637] LNetError: 25805:0:(socklnd_cb.c:1771:ksocknal_recv_hello()) Error -104 reading HELLO from 127.0.0.2 [ 705.518263] LNetError: 11b-b: Connection to 127.0.0.2@tcp at host 127.0.0.2:988 was reset: is it running a compatible version of Lustre and is 127.0.0.2@tcp one of its NIDs? [ 721.375211] Lustre: 30604:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761322460/real 1761322460] req@0000000022383191 x1846880363350080/t0(0) o101->lustre-MDT0000-mdc-ffff8d90e09d8000@192.168.201.132@tcp:12/10 lens 576/1152 e 0 to 1 dl 1761322480 ref 2 fl Rpc:XQr/0/ffffffff rc 0/-1 job:'' [ 721.383645] Lustre: lustre-MDT0000-mdc-ffff8d90e09d8000: Connection to lustre-MDT0000 (at 192.168.201.132@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 721.395927] Lustre: lustre-MDT0000-mdc-ffff8d90e09d8000: Connection restored to (at 192.168.201.132@tcp) [ 741.756223] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 742.933920] Lustre: DEBUG MARKER: done [ 744.053172] Lustre: Unmounted lustre-client [ 760.559890] Key type lgssc unregistered [ 760.680507] LNet: 31420:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 761.696054] LNet: Removed LNI 192.168.201.32@tcp [ 761.967489] Key type .llcrypt unregistered [ 761.969097] Key type ._llcrypt unregistered [ 776.682052] alg: No test for adler32 (adler32-zlib) [ 777.434403] Key type ._llcrypt registered [ 777.435636] Key type .llcrypt registered [ 777.520179] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 777.635438] Lustre: Lustre: Build Version: 2.15.7_12_gd220bd5 [ 777.720333] LNet: Added LNI 192.168.201.32@tcp [8/256/0/180] [ 777.722758] LNet: Accept secure, port 988 [ 779.311145] Key type lgssc registered [ 779.706073] Lustre: Echo OBD driver; http://www.lustre.org/ [ 802.631324] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 811.273790] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 830.819309] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 12:16:29 (1761322589) [ 831.365133] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 831.977581] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 12:16:30 (1761322590) [ 847.277240] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 12:16:45 (1761322605) [ 854.888767] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 862.220993] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 868.821749] Lustre: Mounted lustre-client [ 870.773784] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 871.589703] Lustre: Unmounted lustre-client [ 880.029061] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 889.269694] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 889.440730] Lustre: Mounted lustre-client [ 890.406980] Lustre: Unmounted lustre-client [ 908.501786] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 908.684920] Lustre: Mounted lustre-client [ 909.753445] Lustre: Unmounted lustre-client [ 920.430106] Lustre: DEBUG MARKER: files compared the same [ 924.004059] Key type lgssc unregistered [ 924.103355] LNet: 39847:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 925.152120] LNet: Removed LNI 192.168.201.32@tcp [ 925.426731] Key type .llcrypt unregistered [ 925.428052] Key type ._llcrypt unregistered [ 931.049364] alg: No test for adler32 (adler32-zlib) [ 931.801485] Key type ._llcrypt registered [ 931.802938] Key type .llcrypt registered [ 932.329904] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 12:18:10 (1761322690) [ 933.218847] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 933.357388] Lustre: Lustre: Build Version: 2.15.7_12_gd220bd5 [ 933.450576] LNet: Added LNI 192.168.201.32@tcp [8/256/0/180] [ 933.452875] LNet: Accept secure, port 988 [ 935.055272] Key type lgssc registered [ 935.547847] Lustre: Echo OBD driver; http://www.lustre.org/ [ 950.332702] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 957.162408] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 963.539307] Lustre: Mounted lustre-client [ 965.231440] Lustre: Unmounted lustre-client [ 977.553849] Key type lgssc unregistered [ 977.656647] LNet: 42775:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 978.720064] LNet: Removed LNI 192.168.201.32@tcp [ 978.982891] Key type .llcrypt unregistered [ 978.984875] Key type ._llcrypt unregistered [ 984.374925] alg: No test for adler32 (adler32-zlib) [ 985.126266] Key type ._llcrypt registered [ 985.127444] Key type .llcrypt registered [ 985.601395] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 12:19:03 (1761322743) [ 986.357745] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 986.553661] Lustre: Lustre: Build Version: 2.15.7_12_gd220bd5 [ 986.630408] LNet: Added LNI 192.168.201.32@tcp [8/256/0/180] [ 986.632805] LNet: Accept secure, port 988 [ 988.223141] Key type lgssc registered [ 988.625246] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1090.403230] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1130.104669] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1158.014125] Key type lgssc unregistered [ 1158.179272] LNet: 45621:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1158.184754] LNet: Removed LNI 192.168.201.32@tcp [ 1158.544787] Key type .llcrypt unregistered [ 1158.546626] Key type ._llcrypt unregistered [ 1167.079942] alg: No test for adler32 (adler32-zlib) [ 1167.834286] Key type ._llcrypt registered [ 1167.835955] Key type .llcrypt registered [ 1168.633934] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 12:22:06 (1761322926) [ 1170.432630] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1170.699952] Lustre: Lustre: Build Version: 2.15.7_12_gd220bd5 [ 1170.836463] LNet: Added LNI 192.168.201.32@tcp [8/256/0/180] [ 1170.839880] LNet: Accept secure, port 988 [ 1172.479304] Key type lgssc registered [ 1173.179680] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1210.432393] Lustre: Mounted lustre-client [ 1216.653021] Lustre: Unmounted lustre-client [ 1240.391650] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 12:23:18 (1761322998) [ 1298.822816] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1309.616527] Lustre: Mounted lustre-client [ 1315.752252] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1315.756088] LustreError: 50819:0:(file.c:246:ll_close_inode_openhandle()) lustre-clilmv-ffff8d90e0031800: inode [0x200000401:0x1:0x0] mdc close failed: rc = -108 [ 1315.764198] Lustre: 46587:0:(llite_lib.c:3595:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.201.132@tcp:/lustre/fid: [0x200000401:0x1:0x0]/ may get corrupted (rc -108) [ 1326.076217] Lustre: Unmounted lustre-client [ 1355.967129] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 12:25:14 (1761323114) [ 1362.146709] Key type lgssc unregistered [ 1362.312588] LNet: 51888:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1363.362941] LNet: Removed LNI 192.168.201.32@tcp [ 1363.827661] Key type .llcrypt unregistered [ 1363.829667] Key type ._llcrypt unregistered [ 1371.084054] alg: No test for adler32 (adler32-zlib) [ 1371.838305] Key type ._llcrypt registered [ 1371.839856] Key type .llcrypt registered [ 1371.995075] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1372.153551] Lustre: Lustre: Build Version: 2.15.7_12_gd220bd5 [ 1372.277952] LNet: Added LNI 192.168.201.32@tcp [8/256/0/180] [ 1372.281059] LNet: Accept secure, port 988 [ 1373.903178] Key type lgssc registered [ 1374.627548] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1396.574825] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1408.397510] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1409.740908] Lustre: Mounted lustre-client [ 1412.040401] Lustre: Unmounted lustre-client [ 1430.079462] Key type lgssc unregistered [ 1430.233107] LNet: 54259:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1431.265328] LNet: Removed LNI 192.168.201.32@tcp [ 1431.643734] Key type .llcrypt unregistered [ 1431.645942] Key type ._llcrypt unregistered [ 1437.806230] alg: No test for adler32 (adler32-zlib) [ 1438.562357] Key type ._llcrypt registered [ 1438.564031] Key type .llcrypt registered [ 1438.705241] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1438.897136] Lustre: Lustre: Build Version: 2.15.7_12_gd220bd5 [ 1439.018843] LNet: Added LNI 192.168.201.32@tcp [8/256/0/180] [ 1439.025994] LNet: Accept secure, port 988 [ 1440.639095] Key type lgssc registered [ 1441.419650] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1482.815956] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1493.710735] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1495.091930] Lustre: Mounted lustre-client [ 1495.949202] Lustre: Unmounted lustre-client [ 1512.960159] Key type lgssc unregistered [ 1513.087708] LNet: 56812:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1514.145421] LNet: Removed LNI 192.168.201.32@tcp [ 1514.527573] Key type .llcrypt unregistered [ 1514.529879] Key type ._llcrypt unregistered [ 1524.160207] alg: No test for adler32 (adler32-zlib) [ 1524.909389] Key type ._llcrypt registered [ 1524.911866] Key type .llcrypt registered [ 1525.746393] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 12:28:03 (1761323283) [ 1527.273254] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1527.492176] Lustre: Lustre: Build Version: 2.15.7_12_gd220bd5 [ 1527.651564] LNet: Added LNI 192.168.201.32@tcp [8/256/0/180] [ 1527.656299] LNet: Accept secure, port 988 [ 1529.287152] Key type lgssc registered [ 1529.876357] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1550.491551] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1561.571557] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1562.913117] Lustre: Mounted lustre-client [ 1565.968315] Lustre: Unmounted lustre-client [ 1566.173021] Lustre: Mounted lustre-client [ 1567.383147] Lustre: Unmounted lustre-client [ 1585.808196] Key type lgssc unregistered [ 1585.979150] LNet: 59844:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1587.041496] LNet: Removed LNI 192.168.201.32@tcp [ 1587.368540] Key type .llcrypt unregistered [ 1587.370161] Key type ._llcrypt unregistered [ 1594.655222] alg: No test for adler32 (adler32-zlib) [ 1595.421172] Key type ._llcrypt registered [ 1595.428835] Key type .llcrypt registered [ 1595.575318] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1595.774343] Lustre: Lustre: Build Version: 2.15.7_12_gd220bd5 [ 1595.929412] LNet: Added LNI 192.168.201.32@tcp [8/256/0/180] [ 1595.932272] LNet: Accept secure, port 988 [ 1597.559219] Key type lgssc registered [ 1598.226321] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1619.595622] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1630.791661] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1632.139119] Lustre: Mounted lustre-client [ 1634.669654] Lustre: Unmounted lustre-client [ 1652.828621] Key type lgssc unregistered [ 1652.975322] LNet: 62234:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1653.986041] LNet: Removed LNI 192.168.201.32@tcp [ 1654.410516] Key type .llcrypt unregistered [ 1654.414230] Key type ._llcrypt unregistered [ 1662.941764] alg: No test for adler32 (adler32-zlib) [ 1663.694372] Key type ._llcrypt registered [ 1663.695689] Key type .llcrypt registered [ 1664.399113] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 12:30:22 (1761323422) [ 1665.545917] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1665.730982] Lustre: Lustre: Build Version: 2.15.7_12_gd220bd5 [ 1665.852668] LNet: Added LNI 192.168.201.32@tcp [8/256/0/180] [ 1665.855596] LNet: Accept secure, port 988 [ 1667.471360] Key type lgssc registered [ 1668.035595] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1688.912676] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1699.545774] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1700.886760] Lustre: Mounted lustre-client [ 1706.259582] Lustre: lustre: root_squash is set to 0:0 [ 1714.695841] Lustre: lustre: nosquash_nids is cleared [ 1714.715290] Lustre: lustre: root_squash is set to 500:500 [ 1722.876457] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.201.32@tcp 192.168.0.[2,10]@tcp [ 1724.877647] Lustre: Unmounted lustre-client [ 1742.746383] Key type lgssc unregistered [ 1742.904140] LNet: 65445:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1743.968776] LNet: Removed LNI 192.168.201.32@tcp [ 1744.397336] Key type .llcrypt unregistered [ 1744.401221] Key type ._llcrypt unregistered [ 1752.728578] alg: No test for adler32 (adler32-zlib) [ 1753.480275] Key type ._llcrypt registered [ 1753.481720] Key type .llcrypt registered [ 1754.268425] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 12:31:51 (1761323511) [ 1755.764548] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 1756.673987] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 12:31:55 (1761323515) [ 1758.084094] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1758.294786] Lustre: Lustre: Build Version: 2.15.7_12_gd220bd5 [ 1758.431932] LNet: Added LNI 192.168.201.32@tcp [8/256/0/180] [ 1758.434877] LNet: Accept secure, port 988 [ 1760.056431] Key type lgssc registered [ 1760.618058] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1780.280409] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1789.529684] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1789.752052] Lustre: lustre: root_squash is set to 500:500 [ 1789.765937] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.201.32@tcp 192.168.0.[2,10]@tcp [ 1796.059975] Lustre: Mounted lustre-client [ 1798.493956] Lustre: Unmounted lustre-client [ 1816.053526] Key type lgssc unregistered [ 1816.179867] LNet: 68738:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1817.248168] LNet: Removed LNI 192.168.201.32@tcp [ 1817.549495] Key type .llcrypt unregistered [ 1817.551361] Key type ._llcrypt unregistered [ 1824.581818] alg: No test for adler32 (adler32-zlib) [ 1825.334353] Key type ._llcrypt registered [ 1825.336035] Key type .llcrypt registered [ 1825.977328] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 1826.832053] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 12:33:05 (1761323585) [ 1835.578592] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1835.794729] Lustre: Lustre: Build Version: 2.15.7_12_gd220bd5 [ 1835.908516] LNet: Added LNI 192.168.201.32@tcp [8/256/0/180] [ 1835.914191] LNet: Accept secure, port 988 [ 1837.519174] Key type lgssc registered [ 1838.072851] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1870.532359] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1882.430164] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1911.792579] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1921.511305] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1925.154483] Lustre: Mounted lustre-client [ 1939.116246] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8d90c2b9d000.ost_server_uuid 40 [ 1940.042793] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8d90c2b9d000.ost_server_uuid in FULL state after 0 sec [ 1940.322065] Lustre: Mounted lustre-client [ 1942.026294] Lustre: Unmounted lustre-client [ 1942.673120] Lustre: Unmounted lustre-client [ 1971.634365] Key type lgssc unregistered [ 1971.766849] LNet: 74720:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1972.832200] LNet: Removed LNI 192.168.201.32@tcp [ 1973.254191] Key type .llcrypt unregistered [ 1973.256056] Key type ._llcrypt unregistered [ 1992.783334] alg: No test for adler32 (adler32-zlib) [ 1993.537412] Key type ._llcrypt registered [ 1993.538342] Key type .llcrypt registered [ 1993.646844] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1993.784517] Lustre: Lustre: Build Version: 2.15.7_12_gd220bd5 [ 1993.878249] LNet: Added LNI 192.168.201.32@tcp [8/256/0/180] [ 1993.880767] LNet: Accept secure, port 988 [ 1995.479151] Key type lgssc registered [ 1996.025402] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2023.644905] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2033.021156] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2053.252027] Lustre: DEBUG MARKER: == conf-sanity test 47: server restart does not make client loss lru_resize settings ========================================================== 12:36:51 (1761323811) [ 2080.852357] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2090.689689] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2091.954876] Lustre: Mounted lustre-client [ 2104.287171] Lustre: 75411:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761323856/real 1761323856] req@00000000fb329c86 x1846881815628160/t0(0) o400->lustre-OST0000-osc-ffff8d90e0990000@192.168.201.132@tcp:28/4 lens 224/224 e 0 to 1 dl 1761323863 ref 1 fl Rpc:XNQr/0/ffffffff rc 0/-1 job:'' [ 2104.297746] Lustre: lustre-OST0000-osc-ffff8d90e0990000: Connection to lustre-OST0000 (at 192.168.201.132@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2109.279516] Lustre: 75410:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761323861/real 1761323861] req@000000002bb12923 x1846881815628352/t0(0) o400->lustre-OST0000-osc-ffff8d90e0990000@192.168.201.132@tcp:28/4 lens 224/224 e 0 to 1 dl 1761323868 ref 1 fl Rpc:XNQr/0/ffffffff rc 0/-1 job:'' [ 2110.446291] Lustre: lustre-OST0000-osc-ffff8d90e0990000: Connection restored to (at 192.168.201.132@tcp) [ 2122.721098] Lustre: 75413:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761323874/real 1761323874] req@00000000b67d7b44 x1846881815629056/t0(0) o400->MGC192.168.201.132@tcp@192.168.201.132@tcp:26/25 lens 224/224 e 0 to 1 dl 1761323881 ref 1 fl Rpc:XNQr/0/ffffffff rc 0/-1 job:'' [ 2122.734857] LustreError: 166-1: MGC192.168.201.132@tcp: Connection to MGS (at 192.168.201.132@tcp) was lost; in progress operations using this service will fail [ 2127.844242] Lustre: lustre-MDT0000-mdc-ffff8d90e0990000: Connection to lustre-MDT0000 (at 192.168.201.132@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2127.845485] Lustre: Evicted from MGS (at 192.168.201.132@tcp) after server handle changed from 0xe0fa40ded1b89f4a to 0xe0fa40ded1b8a25a [ 2127.856896] Lustre: MGC192.168.201.132@tcp: Connection restored to (at 192.168.201.132@tcp) [ 2132.234917] Lustre: Unmounted lustre-client [ 2151.669837] Key type lgssc unregistered [ 2151.775603] LNet: 81775:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2152.801334] LNet: Removed LNI 192.168.201.32@tcp [ 2153.076657] Key type .llcrypt unregistered [ 2153.078363] Key type ._llcrypt unregistered [ 2159.051435] alg: No test for adler32 (adler32-zlib) [ 2159.802344] Key type ._llcrypt registered [ 2159.803970] Key type .llcrypt registered [ 2160.422679] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 12:38:38 (1761323918) [ 2161.496772] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2161.635224] Lustre: Lustre: Build Version: 2.15.7_12_gd220bd5 [ 2161.729674] LNet: Added LNI 192.168.201.32@tcp [8/256/0/180] [ 2161.731418] LNet: Accept secure, port 988 [ 2163.319102] Key type lgssc registered [ 2163.758871] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2180.165325] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2188.809789] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2195.420298] Lustre: Mounted lustre-client [ 2307.039143] Lustre: 86677:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761324019/real 1761324019] req@00000000c6a7cddd x1846881991480000/t0(0) o101->lustre-MDT0000-mdc-ffff8d90e0976000@192.168.201.132@tcp:12/10 lens 584/19608 e 0 to 1 dl 1761324063 ref 2 fl Rpc:XQr/2/ffffffff rc 0/-1 job:'' [ 2307.049895] Lustre: lustre-MDT0000-mdc-ffff8d90e0976000: Connection to lustre-MDT0000 (at 192.168.201.132@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2307.069166] Lustre: lustre-MDT0000-mdc-ffff8d90e0976000: Connection restored to 192.168.201.132@tcp (at 192.168.201.132@tcp) [ 2375.647151] Lustre: 82715:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761324127/real 1761324127] req@000000004467a7d6 x1846881992177728/t0(0) o400->MGC192.168.201.132@tcp@192.168.201.132@tcp:26/25 lens 224/224 e 0 to 1 dl 1761324134 ref 1 fl Rpc:XNQr/0/ffffffff rc 0/-1 job:'' [ 2375.655662] LustreError: 166-1: MGC192.168.201.132@tcp: Connection to MGS (at 192.168.201.132@tcp) was lost; in progress operations using this service will fail [ 2381.794839] Lustre: Evicted from MGS (at 192.168.201.132@tcp) after server handle changed from 0x52653dcac4577936 to 0x52653dcac459e2b7 [ 2381.798392] Lustre: MGC192.168.201.132@tcp: Connection restored to 192.168.201.132@tcp (at 192.168.201.132@tcp) [ 2386.126858] LustreError: 11-0: lustre-MDT0000-mdc-ffff8d90e0976000: operation mds_statfs to node 192.168.201.132@tcp failed: rc = -107 [ 2386.131919] Lustre: lustre-MDT0000-mdc-ffff8d90e0976000: Connection to lustre-MDT0000 (at 192.168.201.132@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2386.161357] Lustre: lustre-MDT0000-mdc-ffff8d90e0976000: Connection restored to 192.168.201.132@tcp (at 192.168.201.132@tcp) [ 2388.593053] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2389.224858] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 2390.944175] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2401.199758] Lustre: Unmounted lustre-client [ 2449.868413] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2458.430620] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2478.013487] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 12:43:56 (1761324236) [ 2503.221401] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2511.524208] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2516.973344] Lustre: Mounted lustre-client [ 2518.183219] Lustre: Unmounted lustre-client [ 2536.075652] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 12:44:54 (1761324294) [ 2559.579212] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2567.548642] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2567.713897] Lustre: Mounted lustre-client [ 2568.840639] Lustre: Unmounted lustre-client [ 2584.334781] Key type lgssc unregistered [ 2584.459786] LNet: 98773:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2585.504347] LNet: Removed LNI 192.168.201.32@tcp [ 2585.755364] Key type .llcrypt unregistered [ 2585.756468] Key type ._llcrypt unregistered [ 2591.019890] alg: No test for adler32 (adler32-zlib) [ 2591.772342] Key type ._llcrypt registered [ 2591.774033] Key type .llcrypt registered [ 2592.324306] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 12:45:50 (1761324350) [ 2593.293790] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2593.463853] Lustre: Lustre: Build Version: 2.15.7_12_gd220bd5 [ 2593.557582] LNet: Added LNI 192.168.201.32@tcp [8/256/0/180] [ 2593.559731] LNet: Accept secure, port 988 [ 2595.159125] Key type lgssc registered [ 2595.579610] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2610.117484] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2616.367677] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2622.930700] Lustre: Mounted lustre-client [ 2633.653678] Lustre: Unmounted lustre-client [ 2650.158099] Key type lgssc unregistered [ 2650.250617] LNet: 101711:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2651.295986] LNet: Removed LNI 192.168.201.32@tcp [ 2651.523820] Key type .llcrypt unregistered [ 2651.525332] Key type ._llcrypt unregistered [ 2656.843101] alg: No test for adler32 (adler32-zlib) [ 2657.594393] Key type ._llcrypt registered [ 2657.595651] Key type .llcrypt registered [ 2658.051696] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 12:46:55 (1761324415) [ 2658.805083] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2658.931213] Lustre: Lustre: Build Version: 2.15.7_12_gd220bd5 [ 2659.021480] LNet: Added LNI 192.168.201.32@tcp [8/256/0/180] [ 2659.023712] LNet: Accept secure, port 988 [ 2660.615115] Key type lgssc registered [ 2660.965326] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2674.543774] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2680.673429] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2692.060575] Lustre: Mounted lustre-client [ 2697.187058] Lustre: lustre-OST0000-osc-ffff8d90d86ec000: Connection to lustre-OST0000 (at 192.168.201.132@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2703.375747] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff8d90d86ec000.ost_server_uuid 40 [ 2703.954461] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 2713.331883] Lustre: Unmounted lustre-client [ 2729.683384] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 12:48:08 (1761324488) [ 2736.385457] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2742.928631] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2750.826684] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 2750.995630] Lustre: Mounted lustre-client [ 2756.066355] Lustre: lustre-OST0000-osc-ffff8d90e0030800: Connection to lustre-OST0000 (at 192.168.201.132@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2769.467130] Lustre: Unmounted lustre-client [ 2790.446255] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 12:49:09 (1761324549) [ 2796.933297] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2803.002201] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2809.021516] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 2809.187382] Lustre: Mounted lustre-client [ 2814.434234] Lustre: lustre-OST0000-osc-ffff8d90d8716000: Connection to lustre-OST0000 (at 192.168.201.132@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2825.965872] Lustre: Unmounted lustre-client [ 2846.341830] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 12:50:05 (1761324605) [ 2868.786762] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2876.416114] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2897.958976] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2904.045952] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2918.679712] Lustre: Mounted lustre-client [ 2946.254680] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2950.845659] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 4 sec [ 2951.174104] Lustre: Unmounted lustre-client [ 2968.296620] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 12:52:06 (1761324726) [ 2974.484222] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2980.974414] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2994.319775] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3002.136407] Lustre: Mounted lustre-client [ 3029.465856] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3030.029911] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 3033.059051] Lustre: lustre-OST0001-osc-ffff8d90e0030000: Connection to lustre-OST0001 (at 192.168.201.132@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3037.735560] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3047.984146] Lustre: Unmounted lustre-client [ 3083.801268] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 12:54:02 (1761324842) [ 3090.726911] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3097.672991] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3097.887198] Lustre: Mounted lustre-client [ 3106.583706] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3107.283989] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 3111.081648] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8d90c423c000.ost_server_uuid 40 [ 3111.626743] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8d90c423c000.ost_server_uuid in FULL state after 0 sec [ 3112.252616] Lustre: Unmounted lustre-client [ 3112.336637] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 3112.338132] Lustre: Skipped 2 previous similar messages [ 3112.741418] LustreError: 120665:0:(sec.c:411:import_sec_validate_get()) import 00000000e595aa54 (NEW) with no sec [ 3141.382571] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 12:55:00 (1761324900) [ 3147.967483] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3154.466268] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3160.661509] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3160.804326] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 3160.840413] Lustre: Mounted lustre-client [ 3160.841780] Lustre: Skipped 1 previous similar message [ 3167.719702] LustreError: 123076:0:(obd_config.c:1999:class_config_llog_handler()) MGC192.168.201.132@tcp: cfg command failed: rc = -114 [ 3167.724327] Lustre: cmd=cf00f 0:lustre-OST0000-osc 1:osc.active=1 [ 3167.724327] [ 3167.727318] LustreError: 122977:0:(mgc_request.c:614:do_requeue()) failed processing log: -114 [ 3170.486063] Lustre: Unmounted lustre-client [ 3170.487373] Lustre: Skipped 1 previous similar message [ 3192.952200] Key type lgssc unregistered [ 3193.060463] LNet: 123507:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3194.080559] LNet: Removed LNI 192.168.201.32@tcp [ 3194.309198] Key type .llcrypt unregistered [ 3194.310273] Key type ._llcrypt unregistered [ 3199.125980] alg: No test for adler32 (adler32-zlib) [ 3199.877351] Key type ._llcrypt registered [ 3199.878253] Key type .llcrypt registered [ 3200.333584] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 12:55:58 (1761324958) [ 3200.838406] Lustre: DEBUG MARKER: SKIP: conf-sanity test_50i needs >= 2 MDTs [ 3201.400127] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 12:56:00 (1761324960) [ 3207.284060] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3207.440404] Lustre: Lustre: Build Version: 2.15.7_12_gd220bd5 [ 3207.515747] LNet: Added LNI 192.168.201.32@tcp [8/256/0/180] [ 3207.517224] LNet: Accept secure, port 988 [ 3209.103176] Key type lgssc registered [ 3209.520169] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3230.338827] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3238.019275] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3244.497944] Lustre: Mounted lustre-client [ 3254.492117] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3255.046838] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 3269.603182] Lustre: lustre-OST0001-osc-ffff8d90c8c68800: Connection to lustre-OST0001 (at 192.168.201.132@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3273.108090] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3283.378274] Lustre: Unmounted lustre-client [ 3314.195893] Key type lgssc unregistered [ 3314.301607] LNet: 128316:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3315.360666] LNet: Removed LNI 192.168.201.32@tcp [ 3315.621970] Key type .llcrypt unregistered [ 3315.623994] Key type ._llcrypt unregistered [ 3328.896877] alg: No test for adler32 (adler32-zlib) [ 3329.651384] Key type ._llcrypt registered [ 3329.652863] Key type .llcrypt registered [ 3329.731920] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3329.857469] Lustre: Lustre: Build Version: 2.15.7_12_gd220bd5 [ 3329.953532] LNet: Added LNI 192.168.201.32@tcp [8/256/0/180] [ 3329.955546] LNet: Accept secure, port 988 [ 3331.543131] Key type lgssc registered [ 3331.885628] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3352.360890] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3360.080517] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3379.047991] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 12:58:57 (1761325137) [ 3385.702577] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3392.512355] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3399.121047] Lustre: Mounted lustre-client [ 3403.428235] Lustre: Unmounted lustre-client [ 3419.546076] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3419.697784] Lustre: Mounted lustre-client [ 3421.819557] Lustre: Unmounted lustre-client [ 3437.245521] Key type lgssc unregistered [ 3437.356594] LNet: 134066:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3438.368253] LNet: Removed LNI 192.168.201.32@tcp [ 3438.633273] Key type .llcrypt unregistered [ 3438.635437] Key type ._llcrypt unregistered [ 3444.114219] alg: No test for adler32 (adler32-zlib) [ 3444.865410] Key type ._llcrypt registered [ 3444.867221] Key type .llcrypt registered [ 3445.372440] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 13:00:03 (1761325203) [ 3446.191869] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3446.325680] Lustre: Lustre: Build Version: 2.15.7_12_gd220bd5 [ 3446.409898] LNet: Added LNI 192.168.201.32@tcp [8/256/0/180] [ 3446.412691] LNet: Accept secure, port 988 [ 3448.007087] Key type lgssc registered [ 3448.422359] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3462.604542] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3468.614952] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3474.898857] Lustre: Mounted lustre-client [ 3481.558635] Lustre: Unmounted lustre-client [ 3497.523825] Key type lgssc unregistered [ 3497.629525] LNet: 137163:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3498.656373] LNet: Removed LNI 192.168.201.32@tcp [ 3498.912083] Key type .llcrypt unregistered [ 3498.913708] Key type ._llcrypt unregistered [ 3503.070084] alg: No test for adler32 (adler32-zlib) [ 3503.822405] Key type ._llcrypt registered [ 3503.823474] Key type .llcrypt registered [ 3503.897450] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3504.022453] Lustre: Lustre: Build Version: 2.15.7_12_gd220bd5 [ 3504.105726] LNet: Added LNI 192.168.201.32@tcp [8/256/0/180] [ 3504.108623] LNet: Accept secure, port 988 [ 3505.695103] Key type lgssc registered [ 3506.065679] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3519.776676] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3525.988565] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3537.360380] Lustre: Mounted lustre-client [ 3540.338204] Lustre: Unmounted lustre-client [ 3555.969761] Key type lgssc unregistered [ 3556.069468] LNet: 139596:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3557.087927] LNet: Removed LNI 192.168.201.32@tcp [ 3557.323915] Key type .llcrypt unregistered [ 3557.324906] Key type ._llcrypt unregistered [ 3561.460063] alg: No test for adler32 (adler32-zlib) [ 3562.211342] Key type ._llcrypt registered [ 3562.212316] Key type .llcrypt registered [ 3562.286929] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3562.398463] Lustre: Lustre: Build Version: 2.15.7_12_gd220bd5 [ 3562.486753] LNet: Added LNI 192.168.201.32@tcp [8/256/0/180] [ 3562.488367] LNet: Accept secure, port 988 [ 3564.071097] Key type lgssc registered [ 3564.440294] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3578.620610] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3585.221581] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3591.636250] Lustre: Mounted lustre-client [ 3593.319127] Lustre: Unmounted lustre-client [ 3605.670353] Key type lgssc unregistered [ 3605.774587] LNet: 141966:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3606.816223] LNet: Removed LNI 192.168.201.32@tcp [ 3607.051798] Key type .llcrypt unregistered [ 3607.053765] Key type ._llcrypt unregistered [ 3612.550365] alg: No test for adler32 (adler32-zlib) [ 3613.302320] Key type ._llcrypt registered [ 3613.303334] Key type .llcrypt registered [ 3613.781802] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 13:02:51 (1761325371) [ 3614.596724] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3614.707911] Lustre: Lustre: Build Version: 2.15.7_12_gd220bd5 [ 3614.799117] LNet: Added LNI 192.168.201.32@tcp [8/256/0/180] [ 3614.801684] LNet: Accept secure, port 988 [ 3616.383120] Key type lgssc registered [ 3616.760771] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3630.756162] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3637.115079] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3643.347183] Lustre: Mounted lustre-client [ 3650.459604] Lustre: Unmounted lustre-client [ 3666.544775] Key type lgssc unregistered [ 3666.661747] LNet: 145072:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3667.679936] LNet: Removed LNI 192.168.201.32@tcp [ 3667.946179] Key type .llcrypt unregistered [ 3667.947925] Key type ._llcrypt unregistered [ 3671.837425] alg: No test for adler32 (adler32-zlib) [ 3672.589380] Key type ._llcrypt registered [ 3672.590540] Key type .llcrypt registered [ 3672.667965] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3672.781117] Lustre: Lustre: Build Version: 2.15.7_12_gd220bd5 [ 3672.869183] LNet: Added LNI 192.168.201.32@tcp [8/256/0/180] [ 3672.871385] LNet: Accept secure, port 988 [ 3674.463099] Key type lgssc registered [ 3674.872744] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3688.125223] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3694.298327] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3705.807984] Lustre: Mounted lustre-client [ 3708.701397] Lustre: Unmounted lustre-client [ 3720.860220] Key type lgssc unregistered [ 3720.977450] LNet: 147488:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3722.015672] LNet: Removed LNI 192.168.201.32@tcp [ 3722.295066] Key type .llcrypt unregistered [ 3722.297065] Key type ._llcrypt unregistered [ 3727.167983] alg: No test for adler32 (adler32-zlib) [ 3727.919312] Key type ._llcrypt registered [ 3727.920582] Key type .llcrypt registered [ 3727.985542] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3728.088518] Lustre: Lustre: Build Version: 2.15.7_12_gd220bd5 [ 3728.167670] LNet: Added LNI 192.168.201.32@tcp [8/256/0/180] [ 3728.169778] LNet: Accept secure, port 988 [ 3729.759111] Key type lgssc registered [ 3730.089550] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3743.931682] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3750.096905] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3761.616850] Lustre: Mounted lustre-client [ 3763.268239] Lustre: Unmounted lustre-client [ 3779.621549] Key type lgssc unregistered [ 3779.717512] LNet: 149859:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3780.767779] LNet: Removed LNI 192.168.201.32@tcp [ 3781.001718] Key type .llcrypt unregistered [ 3781.003077] Key type ._llcrypt unregistered [ 3785.856816] alg: No test for adler32 (adler32-zlib) [ 3786.607307] Key type ._llcrypt registered [ 3786.608184] Key type .llcrypt registered [ 3787.038218] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 13:05:44 (1761325544) [ 3794.129700] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3794.256322] Lustre: Lustre: Build Version: 2.15.7_12_gd220bd5 [ 3794.326084] LNet: Added LNI 192.168.201.32@tcp [8/256/0/180] [ 3794.327576] LNet: Accept secure, port 988 [ 3795.903161] Key type lgssc registered [ 3796.278125] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3817.209856] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3825.077804] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3843.160441] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 13:06:41 (1761325601) [ 3849.655537] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3856.281664] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3862.483829] Lustre: Mounted lustre-client [ 3864.410137] Lustre: Unmounted lustre-client [ 3880.469825] Key type lgssc unregistered [ 3880.580284] LNet: 155410:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3881.631626] LNet: Removed LNI 192.168.201.32@tcp [ 3881.867185] Key type .llcrypt unregistered [ 3881.869086] Key type ._llcrypt unregistered [ 3886.709137] alg: No test for adler32 (adler32-zlib) [ 3887.460313] Key type ._llcrypt registered [ 3887.461316] Key type .llcrypt registered [ 3887.929993] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 3888.480859] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 13:07:27 (1761325647) [ 3894.757125] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3894.946892] Lustre: Lustre: Build Version: 2.15.7_12_gd220bd5 [ 3895.038803] LNet: Added LNI 192.168.201.32@tcp [8/256/0/180] [ 3895.041133] LNet: Accept secure, port 988 [ 3896.631112] Key type lgssc registered [ 3897.063993] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3922.025623] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3929.317957] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid [ 3935.482567] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid [ 3936.649722] Lustre: Mounted lustre-client [ 3939.835154] Lustre: Unmounted lustre-client [ 3971.975910] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3979.728930] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4002.155587] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 13:09:20 (1761325760) [ 4002.737855] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 4003.358720] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 13:09:22 (1761325762) [ 4016.322285] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4028.190175] Key type lgssc unregistered [ 4028.308709] LNet: 164619:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4029.344872] LNet: Removed LNI 192.168.201.32@tcp [ 4029.598473] Key type .llcrypt unregistered [ 4029.599525] Key type ._llcrypt unregistered [ 4034.935533] alg: No test for adler32 (adler32-zlib) [ 4035.686399] Key type ._llcrypt registered [ 4035.687352] Key type .llcrypt registered [ 4036.114903] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 13:09:54 (1761325794) [ 4046.349737] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4046.633618] Lustre: Lustre: Build Version: 2.15.7_12_gd220bd5 [ 4046.731395] LNet: Added LNI 192.168.201.32@tcp [8/256/0/180] [ 4046.733647] LNet: Accept secure, port 988 [ 4048.327164] Key type lgssc registered [ 4048.734161] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4059.160807] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4065.451972] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4081.888187] Key type lgssc unregistered [ 4081.998377] LNet: 167640:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4082.002853] LNet: Removed LNI 192.168.201.32@tcp [ 4082.266910] Key type .llcrypt unregistered [ 4082.268418] Key type ._llcrypt unregistered [ 4087.953254] alg: No test for adler32 (adler32-zlib) [ 4088.705294] Key type ._llcrypt registered [ 4088.706456] Key type .llcrypt registered [ 4089.225132] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 13:10:47 (1761325847) [ 4090.284461] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4090.433624] Lustre: Lustre: Build Version: 2.15.7_12_gd220bd5 [ 4090.529293] LNet: Added LNI 192.168.201.32@tcp [8/256/0/180] [ 4090.530912] LNet: Accept secure, port 988 [ 4092.119102] Key type lgssc registered [ 4092.531930] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4106.426162] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4112.755157] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4118.994919] Lustre: Mounted lustre-client [ 4124.129391] Lustre: lustre-MDT0000-mdc-ffff8d90c7e6a800: Connection to lustre-MDT0000 (at 192.168.201.132@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4134.369883] LustreError: 166-1: MGC192.168.201.132@tcp: Connection to MGS (at 192.168.201.132@tcp) was lost; in progress operations using this service will fail [ 4134.376961] Lustre: Evicted from MGS (at 192.168.201.132@tcp) after server handle changed from 0xeaf8130d55346a7d to 0xeaf8130d55348542 [ 4134.380237] Lustre: MGC192.168.201.132@tcp: Connection restored to 192.168.201.132@tcp (at 192.168.201.132@tcp) [ 4136.958926] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4140.516780] LustreError: lustre-MDT0000-mdc-ffff8d90c7e6a800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 4140.529202] Lustre: lustre-MDT0000-mdc-ffff8d90c7e6a800: Connection restored to 192.168.201.132@tcp (at 192.168.201.132@tcp) [ 4141.591311] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 4 sec [ 4142.191665] Lustre: Unmounted lustre-client [ 4158.571675] Key type lgssc unregistered [ 4158.690874] LNet: 171345:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4159.711826] LNet: Removed LNI 192.168.201.32@tcp [ 4159.978242] Key type .llcrypt unregistered [ 4159.979455] Key type ._llcrypt unregistered [ 4165.217431] alg: No test for adler32 (adler32-zlib) [ 4165.969349] Key type ._llcrypt registered [ 4165.971239] Key type .llcrypt registered [ 4166.462595] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 13:12:04 (1761325924) [ 4167.343422] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4167.503777] Lustre: Lustre: Build Version: 2.15.7_12_gd220bd5 [ 4167.584781] LNet: Added LNI 192.168.201.32@tcp [8/256/0/180] [ 4167.586899] LNet: Accept secure, port 988 [ 4169.183142] Key type lgssc registered [ 4169.601469] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4183.034789] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4199.480486] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4208.612347] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4215.294444] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 4237.891351] Key type lgssc unregistered [ 4238.004597] LNet: 175483:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4238.008732] LNet: Removed LNI 192.168.201.32@tcp [ 4238.226411] Key type .llcrypt unregistered [ 4238.228105] Key type ._llcrypt unregistered [ 4251.286647] alg: No test for adler32 (adler32-zlib) [ 4252.040353] Key type ._llcrypt registered [ 4252.041713] Key type .llcrypt registered [ 4252.109311] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4252.218124] Lustre: Lustre: Build Version: 2.15.7_12_gd220bd5 [ 4252.295529] LNet: Added LNI 192.168.201.32@tcp [8/256/0/180] [ 4252.296942] LNet: Accept secure, port 988 [ 4253.879138] Key type lgssc registered [ 4254.219967] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4274.897554] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4281.996465] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4301.015337] Lustre: DEBUG MARKER: == conf-sanity test 60: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 13:14:19 (1761326059) [ 4327.509532] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4335.573028] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4355.214543] Lustre: DEBUG MARKER: == conf-sanity test 61: large xattr ====================== 13:15:13 (1761326113) [ 4362.190901] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4368.405795] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4374.993331] Lustre: Mounted lustre-client [ 4378.231172] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61.conf-sanity [ 4378.814568] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61.conf-sanity [ 4379.380573] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61.conf-sanity [ 4379.986048] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61.conf-sanity after remounting MDS [ 4392.415194] Lustre: 176174:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761326144/real 1761326144] req@000000005c434d21 x1846884183314432/t0(0) o400->MGC192.168.201.132@tcp@192.168.201.132@tcp:26/25 lens 224/224 e 0 to 1 dl 1761326151 ref 1 fl Rpc:XNQr/0/ffffffff rc 0/-1 job:'' [ 4392.415316] Lustre: lustre-MDT0000-mdc-ffff8d90c5e12800: Connection to lustre-MDT0000 (at 192.168.201.132@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4392.425491] Lustre: 176174:0:(client.c:2295:ptlrpc_expire_one_request()) Skipped 1 previous similar message [ 4392.425567] LustreError: 166-1: MGC192.168.201.132@tcp: Connection to MGS (at 192.168.201.132@tcp) was lost; in progress operations using this service will fail [ 4397.471083] Lustre: 176175:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761326149/real 1761326149] req@000000004fdf6915 x1846884183314688/t0(0) o400->lustre-MDT0000-mdc-ffff8d90c5e12800@192.168.201.132@tcp:12/10 lens 224/224 e 0 to 1 dl 1761326156 ref 1 fl Rpc:XNQr/0/ffffffff rc 0/-1 job:'' [ 4398.562961] Lustre: Evicted from MGS (at 192.168.201.132@tcp) after server handle changed from 0x25f840717b2577d1 to 0x25f840717b2579e5 [ 4398.568485] Lustre: MGC192.168.201.132@tcp: Connection restored to 192.168.201.132@tcp (at 192.168.201.132@tcp) [ 4402.961148] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4403.452534] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 4405.328831] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61.conf-sanity [ 4414.943211] Lustre: 176174:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761326167/real 1761326167] req@000000008b1fd6a8 x1846884183316544/t0(0) o400->lustre-MDT0000-mdc-ffff8d90c5e12800@192.168.201.132@tcp:12/10 lens 224/224 e 0 to 1 dl 1761326174 ref 1 fl Rpc:XNQr/0/ffffffff rc 0/-1 job:'' [ 4414.955453] Lustre: lustre-MDT0000-mdc-ffff8d90c5e12800: Connection to lustre-MDT0000 (at 192.168.201.132@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4415.455228] LustreError: 166-1: MGC192.168.201.132@tcp: Connection to MGS (at 192.168.201.132@tcp) was lost; in progress operations using this service will fail [ 4421.535094] Lustre: 176173:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761326173/real 1761326173] req@00000000fb049ea2 x1846884183316736/t0(0) o400->lustre-MDT0000-mdc-ffff8d90c5e12800@192.168.201.132@tcp:12/10 lens 224/224 e 0 to 1 dl 1761326180 ref 1 fl Rpc:XNQr/0/ffffffff rc 0/-1 job:'' [ 4421.541343] Lustre: 176173:0:(client.c:2295:ptlrpc_expire_one_request()) Skipped 1 previous similar message [ 4421.603308] Lustre: Evicted from MGS (at 192.168.201.132@tcp) after server handle changed from 0x25f840717b2579e5 to 0x25f840717b257c5b [ 4421.607465] Lustre: MGC192.168.201.132@tcp: Connection restored to 192.168.201.132@tcp (at 192.168.201.132@tcp) [ 4421.611102] Lustre: Skipped 1 previous similar message [ 4425.204780] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4425.766924] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 4427.376539] Lustre: Unmounted lustre-client [ 4444.460609] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4451.051617] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4456.426063] Lustre: Mounted lustre-client [ 4456.797665] Lustre: Unmounted lustre-client [ 4471.959952] Key type lgssc unregistered [ 4472.081551] LNet: 187556:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4473.120050] LNet: Removed LNI 192.168.201.32@tcp [ 4473.376363] Key type .llcrypt unregistered [ 4473.377388] Key type ._llcrypt unregistered [ 4478.708162] alg: No test for adler32 (adler32-zlib) [ 4479.460452] Key type ._llcrypt registered [ 4479.461516] Key type .llcrypt registered [ 4479.920362] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 13:17:17 (1761326237) [ 4481.083804] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4481.224464] Lustre: Lustre: Build Version: 2.15.7_12_gd220bd5 [ 4481.311178] LNet: Added LNI 192.168.201.32@tcp [8/256/0/180] [ 4481.313746] LNet: Accept secure, port 988 [ 4482.903108] Key type lgssc registered [ 4483.271935] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4497.572840] Key type lgssc unregistered [ 4497.691531] LNet: 189603:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4497.693963] LNet: Removed LNI 192.168.201.32@tcp [ 4497.946451] Key type .llcrypt unregistered [ 4497.947715] Key type ._llcrypt unregistered [ 4507.962249] alg: No test for adler32 (adler32-zlib) [ 4508.717301] Key type ._llcrypt registered [ 4508.718259] Key type .llcrypt registered [ 4508.794388] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4508.927937] Lustre: Lustre: Build Version: 2.15.7_12_gd220bd5 [ 4509.034506] LNet: Added LNI 192.168.201.32@tcp [8/256/0/180] [ 4509.036165] LNet: Accept secure, port 988 [ 4510.623134] Key type lgssc registered [ 4511.027813] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4533.586323] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4541.720531] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4560.110932] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [ 4560.728662] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 13:18:39 (1761326319) [ 4567.685136] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4573.876647] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4581.502918] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 4582.664619] Lustre: Mounted lustre-client [ 4586.086227] LustreError: 11-0: lustre-OST0001-osc-ffff8d90c45ed800: operation ost_statfs to node 192.168.201.132@tcp failed: rc = -107 [ 4586.090634] Lustre: lustre-OST0001-osc-ffff8d90c45ed800: Connection to lustre-OST0001 (at 192.168.201.132@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4586.367347] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4596.658169] Lustre: Unmounted lustre-client [ 4619.391794] Key type lgssc unregistered [ 4619.491344] LNet: 195175:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4620.512719] LNet: Removed LNI 192.168.201.32@tcp [ 4620.771990] Key type .llcrypt unregistered [ 4620.773884] Key type ._llcrypt unregistered [ 4633.759709] alg: No test for adler32 (adler32-zlib) [ 4634.511355] Key type ._llcrypt registered [ 4634.512632] Key type .llcrypt registered [ 4634.594249] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4634.705266] Lustre: Lustre: Build Version: 2.15.7_12_gd220bd5 [ 4634.783804] LNet: Added LNI 192.168.201.32@tcp [8/256/0/180] [ 4634.785335] LNet: Accept secure, port 988 [ 4636.367147] Key type lgssc registered [ 4636.733562] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4656.794609] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4664.339575] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4682.855992] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 13:20:41 (1761326441) [ 4691.456516] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4701.618740] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 13:21:00 (1761326460) [ 4707.853856] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4714.041069] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4720.594858] Lustre: Mounted lustre-client [ 4731.879157] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 4733.044502] Lustre: Unmounted lustre-client [ 4774.045937] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4784.755700] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4790.250154] Lustre: Mounted lustre-client [ 4790.743414] Lustre: Unmounted lustre-client [ 4806.819258] Key type lgssc unregistered [ 4806.929440] LNet: 203052:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4807.968872] LNet: Removed LNI 192.168.201.32@tcp [ 4808.227407] Key type .llcrypt unregistered [ 4808.228475] Key type ._llcrypt unregistered [ 4817.969029] alg: No test for adler32 (adler32-zlib) [ 4818.738392] Key type ._llcrypt registered [ 4818.739412] Key type .llcrypt registered [ 4818.814953] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4818.924642] Lustre: Lustre: Build Version: 2.15.7_12_gd220bd5 [ 4819.004217] LNet: Added LNI 192.168.201.32@tcp [8/256/0/180] [ 4819.006825] LNet: Accept secure, port 988 [ 4820.591122] Key type lgssc registered [ 4820.992150] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4843.093699] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4851.053346] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4869.819258] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [ 4870.452760] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 13:23:49 (1761326629) [ 4876.962179] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4883.638938] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4891.089826] Lustre: Mounted lustre-client [ 4891.787520] Lustre: Unmounted lustre-client [ 4907.442298] Key type lgssc unregistered [ 4907.530658] LNet: 208141:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4908.576721] LNet: Removed LNI 192.168.201.32@tcp [ 4908.804548] Key type .llcrypt unregistered [ 4908.805625] Key type ._llcrypt unregistered [ 4914.132787] alg: No test for adler32 (adler32-zlib) [ 4914.885307] Key type ._llcrypt registered [ 4914.886294] Key type .llcrypt registered [ 4915.341105] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [ 4915.877934] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 13:24:34 (1761326674) [ 4916.379260] Lustre: DEBUG MARKER: SKIP: conf-sanity test_70a needs >= 2 MDTs [ 4916.952562] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 13:24:35 (1761326675) [ 4917.404377] Lustre: DEBUG MARKER: SKIP: conf-sanity test_70b needs >= 2 MDTs [ 4917.938397] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 13:24:36 (1761326676) [ 4918.456189] Lustre: DEBUG MARKER: SKIP: conf-sanity test_70c needs >= 2 MDTs [ 4919.014705] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 13:24:37 (1761326677) [ 4919.537934] Lustre: DEBUG MARKER: SKIP: conf-sanity test_70d needs >= 2 MDTs [ 4920.133971] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 13:24:38 (1761326678) [ 4920.658714] Lustre: DEBUG MARKER: SKIP: conf-sanity test_70e needs >= 2 MDTs [ 4921.205419] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 13:24:39 (1761326679) [ 4921.668249] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs >= 2 MDTs [ 4922.179308] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 13:24:40 (1761326680) [ 4922.651225] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs >= 2 MDTs [ 4923.173696] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 13:24:41 (1761326681) [ 4923.689191] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs >= 2 MDTs [ 4924.280743] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 13:24:42 (1761326682) [ 4924.766378] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs >= 2 MDTs [ 4925.275667] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 13:24:44 (1761326684) [ 4925.807362] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs >= 2 MDTs [ 4926.410789] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 13:24:45 (1761326685) [ 4928.453330] Key type .llcrypt unregistered [ 4928.454498] Key type ._llcrypt unregistered [ 4931.655690] alg: No test for adler32 (adler32-zlib) [ 4932.406276] Key type ._llcrypt registered [ 4932.407172] Key type .llcrypt registered [ 4932.468876] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4932.560780] Lustre: Lustre: Build Version: 2.15.7_12_gd220bd5 [ 4932.629119] LNet: Added LNI 192.168.201.32@tcp [8/256/0/180] [ 4932.630592] LNet: Accept secure, port 988 [ 4934.207117] Key type lgssc registered [ 4934.627403] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4953.606136] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4961.342550] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4962.523235] Lustre: Mounted lustre-client [ 4962.963583] Lustre: Unmounted lustre-client [ 4974.756095] Lustre: DEBUG MARKER: == conf-sanity test 73: failnode to update from mountdata properly ========================================================== 13:25:33 (1761326733) [ 4981.146664] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4987.381719] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4993.003184] Lustre: Mounted lustre-client [ 4993.383286] Lustre: Unmounted lustre-client [ 5012.105567] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 13:26:10 (1761326770) [ 5040.815471] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5048.209707] Lustre: DEBUG MARKER: oleg132-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5084.722981] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 4942 sec ========== 13:27:23 (1761326843) [ 5093.351396] Key type lgssc unregistered [ 5093.450599] LNet: 222531:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5117.023971] LNet: Removed LNI 192.168.201.32@tcp [ 5117.278432] Key type .llcrypt unregistered [ 5117.279455] Key type ._llcrypt unregistered