[ 0.000000] Linux version 4.18.0rh8.10-debug (green@maintenance) (gcc version 8.5.0 20210514 (Red Hat 8.5.0-26) (GCC)) #2 SMP Mon Jul 14 01:24:22 EDT 2025 [ 0.000000] Command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' [ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 [ 0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. [ 0.000000] signal: max sigframe size: 1776 [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bffd9fff] usable [ 0.000000] BIOS-e820: [mem 0x00000000bffda000-0x00000000bfffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x0000000146dfffff] usable [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] SMBIOS 2.8 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.17.0-10.fc44 06/10/2025 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 441530382 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2464 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE2300 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 0022C0 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2374 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE2404 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE243C 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe2300-0xbffe2373] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22ff] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2374-0xbffe2403] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe2404-0xbffe243b] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe243c-0xbffe2463] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001012] APIC: Switch to symmetric I/O mode setup [ 0.003067] x2apic enabled [ 0.004009] Switched APIC routing to physical x2apic. [ 0.005016] kvm-guest: setup PV IPIs [ 0.007885] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.008000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.008031] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009016] pid_max: default: 32768 minimum: 301 [ 0.011023] LSM: Security Framework initializing [ 0.012073] Yama: becoming mindful. [ 0.013039] SELinux: Initializing. [ 0.015058] *** VALIDATE selinux *** [ 0.023600] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.028410] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.029178] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.031122] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.032156] *** VALIDATE tmpfs *** [ 0.034080] *** VALIDATE proc *** [ 0.035279] *** VALIDATE cgroup *** [ 0.036013] *** VALIDATE cgroup2 *** [ 0.038172] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.039173] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.040011] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.041039] Spectre V2 : User space: Vulnerable [ 0.042013] Speculative Store Bypass: Vulnerable [ 0.045102] debug: unmapping init [mem 0xffffffff9ee59000-0xffffffff9ee60fff] [ 0.047193] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.048732] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.049032] ... version: 2 [ 0.050020] ... bit width: 48 [ 0.051015] ... generic registers: 4 [ 0.052018] ... value mask: 0000ffffffffffff [ 0.053017] ... max period: 00007fffffffffff [ 0.054028] ... fixed-purpose events: 3 [ 0.055014] ... event mask: 000000070000000f [ 0.056373] rcu: Hierarchical SRCU implementation. [ 0.058562] smp: Bringing up secondary CPUs ... [ 0.059672] x86: Booting SMP configuration: [ 0.060031] .... node #0, CPUs: #1 #2 #3 [ 0.063462] smp: Brought up 1 node, 4 CPUs [ 0.065018] smpboot: Max logical packages: 1 [ 0.066022] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.153645] node 0 deferred pages initialised in 85ms [ 0.157176] devtmpfs: initialized [ 0.158280] x86/mm: Memory block size: 128MB [ 0.160862] gcov: version magic: 0x41383552 [ 0.162396] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.166084] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.168371] pinctrl core: initialized pinctrl subsystem [ 0.170273] [ 0.170720] ************************************************************* [ 0.173014] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.174015] ** ** [ 0.176014] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.178011] ** ** [ 0.180015] ** This means that this kernel is built to expose internal ** [ 0.182014] ** IOMMU data structures, which may compromise security on ** [ 0.184011] ** your system. ** [ 0.186016] ** ** [ 0.189014] ** If you see this message and you are not debugging the ** [ 0.191011] ** kernel, report this immediately to your vendor! ** [ 0.193015] ** ** [ 0.195022] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.198016] ************************************************************* [ 0.200796] NET: Registered protocol family 16 [ 0.203498] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.206089] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.208078] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.213007] cpuidle: using governor menu [ 0.214400] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.216544] PCI: Using configuration type 1 for base access [ 0.219157] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.228132] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.229040] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.231087] cryptd: max_cpu_qlen set to 1000 [ 0.234337] ACPI: Added _OSI(Module Device) [ 0.236018] ACPI: Added _OSI(Processor Device) [ 0.238021] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.239031] ACPI: Added _OSI(Processor Aggregator Device) [ 0.244164] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.251554] ACPI: Interpreter enabled [ 0.253063] ACPI: PM: (supports S0 S3 S4 S5) [ 0.254013] ACPI: Using IOAPIC for interrupt routing [ 0.255104] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.256535] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.264874] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.267048] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.270029] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.274094] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.279368] acpiphp: Slot [2] registered [ 0.281174] acpiphp: Slot [5] registered [ 0.283134] acpiphp: Slot [6] registered [ 0.286159] acpiphp: Slot [3] registered [ 0.287126] acpiphp: Slot [4] registered [ 0.289118] acpiphp: Slot [7] registered [ 0.290143] acpiphp: Slot [8] registered [ 0.291132] acpiphp: Slot [9] registered [ 0.293126] acpiphp: Slot [10] registered [ 0.295118] acpiphp: Slot [11] registered [ 0.296114] acpiphp: Slot [12] registered [ 0.298123] acpiphp: Slot [13] registered [ 0.299117] acpiphp: Slot [14] registered [ 0.301143] acpiphp: Slot [15] registered [ 0.302128] acpiphp: Slot [16] registered [ 0.304124] acpiphp: Slot [17] registered [ 0.306106] acpiphp: Slot [18] registered [ 0.307155] acpiphp: Slot [19] registered [ 0.309114] acpiphp: Slot [20] registered [ 0.310117] acpiphp: Slot [21] registered [ 0.312190] acpiphp: Slot [22] registered [ 0.314121] acpiphp: Slot [23] registered [ 0.315113] acpiphp: Slot [24] registered [ 0.317136] acpiphp: Slot [25] registered [ 0.318137] acpiphp: Slot [26] registered [ 0.320109] acpiphp: Slot [27] registered [ 0.321132] acpiphp: Slot [28] registered [ 0.323119] acpiphp: Slot [29] registered [ 0.325117] acpiphp: Slot [30] registered [ 0.326162] acpiphp: Slot [31] registered [ 0.328054] PCI host bridge to bus 0000:00 [ 0.330033] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.332028] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.334028] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.337029] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.339026] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.342042] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.344206] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.347132] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.350416] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.358022] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.363059] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.366019] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.368018] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.371023] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.374062] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.376851] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.380052] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.382934] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.388016] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.397742] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.402013] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.407678] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.425020] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.434017] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.452029] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.462755] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.475020] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.487018] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.499018] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.510977] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.512221] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.513214] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.515228] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.516198] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.522040] iommu: Default domain type: Passthrough [ 0.524448] SCSI subsystem initialized [ 0.525114] ACPI: bus type USB registered [ 0.525937] usbcore: registered new interface driver usbfs [ 0.527068] usbcore: registered new interface driver hub [ 0.529071] usbcore: registered new device driver usb [ 0.530081] pps_core: LinuxPPS API ver. 1 registered [ 0.531007] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.533041] PTP clock support registered [ 0.535043] EDAC MC: Ver: 3.0.0 [ 0.536151] PCI: Using ACPI for IRQ routing [ 0.537571] NetLabel: Initializing [ 0.538014] NetLabel: domain hash size = 128 [ 0.538856] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.539067] NetLabel: unlabeled traffic allowed by default [ 0.540113] vgaarb: loaded [ 0.541269] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.543014] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.550346] clocksource: Switched to clocksource kvm-clock [ 0.670146] VFS: Disk quotas dquot_6.6.0 [ 0.671767] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.674133] *** VALIDATE ramfs *** [ 0.674989] *** VALIDATE hugetlbfs *** [ 0.676492] pnp: PnP ACPI init [ 0.679091] pnp: PnP ACPI: found 6 devices [ 0.712827] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.716122] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.718271] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.720750] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.722790] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.724536] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.726283] NET: Registered protocol family 2 [ 0.728093] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.731583] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.734088] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.741118] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.745291] TCP: Hash tables configured (established 65536 bind 65536) [ 0.748673] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.752125] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.755240] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.758577] NET: Registered protocol family 1 [ 0.761422] RPC: Registered named UNIX socket transport module. [ 0.763619] RPC: Registered udp transport module. [ 0.765336] RPC: Registered tcp transport module. [ 0.767154] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.769625] NET: Registered protocol family 44 [ 0.771430] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.773586] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.775701] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.778158] PCI: CLS 0 bytes, default 64 [ 0.779994] Unpacking initramfs... [ 2.266764] debug: unmapping init [mem 0xffff8c51bcc64000-0xffff8c51bffcffff] [ 2.273540] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.275866] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.280118] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.795297] Initialise system trusted keyrings [ 2.797199] Key type blacklist registered [ 2.799376] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.808659] zbud: loaded [ 2.812183] *** VALIDATE nfs *** [ 2.813715] *** VALIDATE nfs4 *** [ 2.815781] pstore: using deflate compression [ 2.820323] Platform Keyring initialized [ 2.922178] NET: Registered protocol family 38 [ 2.924144] Key type asymmetric registered [ 2.925769] Asymmetric key parser 'x509' registered [ 2.927764] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.931142] io scheduler mq-deadline registered [ 2.932990] io scheduler kyber registered [ 2.935064] io scheduler bfq registered [ 2.937231] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.940949] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.944518] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.946692] ACPI: Power Button [PWRF] [ 2.952907] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.958565] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.965996] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.993362] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.020929] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.025632] Non-volatile memory driver v1.3 [ 3.026886] Linux agpgart interface v0.103 [ 3.051181] virtio_blk virtio1: [vda] 149408 512-byte logical blocks (76.5 MB/73.0 MiB) [ 3.053637] vda: detected capacity change from 0 to 76496896 [ 3.070752] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.073043] vdb: detected capacity change from 0 to 1073741824 [ 3.082289] libphy: Fixed MDIO Bus: probed [ 3.102065] usbcore: registered new interface driver usbserial_generic [ 3.104831] usbserial: USB Serial support registered for generic [ 3.108752] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.112407] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.113839] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.119199] mousedev: PS/2 mouse device common for all mice [ 3.123473] rtc_cmos 00:05: RTC can wake from S4 [ 3.126710] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.129651] rtc_cmos 00:05: registered as rtc0 [ 3.134405] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.139721] intel_pstate: CPU model not supported [ 3.140414] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.146163] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.146372] hid: raw HID events driver (C) Jiri Kosina [ 3.150158] usbcore: registered new interface driver usbhid [ 3.152215] usbhid: USB HID core driver [ 3.153708] drop_monitor: Initializing network drop monitor service [ 3.155782] Initializing XFRM netlink socket [ 3.157462] NET: Registered protocol family 10 [ 3.160490] Segment Routing with IPv6 [ 3.161630] NET: Registered protocol family 17 [ 3.163335] mpls_gso: MPLS GSO support [ 3.168320] RAS: Correctable Errors collector initialized. [ 3.169735] AVX version of gcm_enc/dec engaged. [ 3.170826] AES CTR mode by8 optimization enabled [ 3.254121] sched_clock: Marking stable (3254090740, 0)->(4171241401, -917150661) [ 3.258463] registered taskstats version 1 [ 3.260708] Loading compiled-in X.509 certificates [ 3.263054] zswap: loaded using pool lzo/zbud [ 3.290677] Key type big_key registered [ 3.302578] Key type encrypted registered [ 3.303762] ima: No TPM chip found, activating TPM-bypass! [ 3.306100] ima: Allocated hash algorithm: sha1 [ 3.308157] ima: No architecture policies found [ 3.309418] evm: Initialising EVM extended attributes: [ 3.310818] evm: security.selinux [ 3.312131] evm: security.ima [ 3.313122] evm: security.capability [ 3.314525] evm: HMAC attrs: 0x1 [ 3.317428] rtc_cmos 00:05: setting system clock to 2026-08-22 09:44:10 UTC (1787391850) [ 3.324308] debug: unmapping init [mem 0xffffffff9fe03000-0xffffffff9fffffff] [ 3.327526] debug: unmapping init [mem 0xffffffff9eb82000-0xffffffff9ee58fff] [ 3.336098] Write protecting the kernel read-only data: 28672k [ 3.339968] debug: unmapping init [mem 0xffffffff9d203000-0xffffffff9d3fffff] [ 3.343166] debug: unmapping init [mem 0xffffffff9db14000-0xffffffff9dbfffff] [ 3.390171] 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.399383] systemd[1]: Detected virtualization kvm. [ 3.400954] systemd[1]: Detected architecture x86-64. [ 3.403098] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.433182] systemd[1]: No hostname configured. [ 3.435314] systemd[1]: Set hostname to . [ 3.437778] random: systemd: uninitialized urandom read (16 bytes read) [ 3.440697] systemd[1]: Initializing machine ID from random generator. [ 3.495907] random: ln: uninitialized urandom read (6 bytes read) [ 3.580359] random: systemd: uninitialized urandom read (16 bytes read) [ 3.583233] systemd[1]: Reached target Timers. [ OK ] Reached target Timers. [ 3.588841] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ 3.594276] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Listening on Journal Socket. Starting Create Volatile Files and Directories... Starting Setup Virtual Console... [ OK ] Started Memstrack Anylazing Service. [ OK ] Listening on udev Control Socket. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Sockets. Starting Journal Service... [ OK ] Reached target Swap. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Initrd Root Device. Starting Apply Kernel Variables... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Slices. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Setup Virtual Console. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create list of required sta…vice nodes for the current kernel. 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.204439] device-mapper: uevent: version 1.0.3 [ 4.206353] 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... [ 4.732010] random: fast init done 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. [ 4.893181] virtio_net virtio0 ens2: renamed from eth0 [ 4.961883] scsi host0: ata_piix [ 4.971811] scsi host1: ata_piix [ 4.973565] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 4.976217] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 9.154611] dracut-initqueue[589]: RTNETLINK answers: File exists [ 9.843959] random: crng init done [ 9.845694] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 10.152069] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped target Timers. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Paths. [ OK ] Stopped target Sockets. [ OK ] Stopped target Slices. [ OK ] Stopped target System Initialization. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Swap. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 11.323063] printk: systemd: 26 output lines suppressed due to ratelimiting [ 11.972704] SELinux: Disabled at runtime. [ 12.111901] 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) [ 12.125754] systemd[1]: Detected virtualization kvm. [ 12.128297] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 13.807867] systemd[1]: initrd-switch-root.service: Succeeded. [ 13.826137] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 13.853398] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 13.858249] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 13.865273] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 13.896983] systemd[1]: Starting Journal Service... Starting Journal Service... [ 13.932191] systemd[1]: Listening on RPCbind Server Activation Socket. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. Starting Remount Root and Kernel File Systems... [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Started Dispatch Password Requests to Console Directory Watch. Mounting Kernel Debug File System... Mounting POSIX Message Queue File System... [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd Root File System. Mounting Huge Pages File System... [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on initctl Compatibility Named Pipe. Starting Apply Kernel Variables... [ OK ] Listening on Process Core Dump Socket. Activating swap /dev/disk/by-label/SWAP... [ OK ] Reached target Paths. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Reached target rpc_pipefs.target. [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... [ 14.506136] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Created slice system-getty.slice. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Stopped target Initrd File Systems. [ OK ] Started Journal Service. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted Kernel Debug File System. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Mounted Huge Pages File System. [ OK ] Started Apply Kernel Variables. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Reached target Swap. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... [ OK ] Started udev Coldplug all Devices. [ OK ] Mounted /mnt. [ 15.757336] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 16.667558] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 16.694751] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 17.319888] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 17.505304] EDAC sbridge: Ver: 1.1.2 [* ] A start job is running for Configur…-only root support (8s / no limit) [** ] A start job is running for Configur…-only root support (8s / no limit)[ 22.817942] Key type dns_resolver registered [*** ] A start job is running for Configur…-only root support (9s / no limit) [ *** ] A start job is running for Configur…-only root support (9s / no limit)[ 24.069331] NFS: Registering the id_resolver key type [ 24.090519] Key type id_resolver registered [ 24.099563] Key type id_legacy registered [ *** ] A start job is running for Configur…only root support (10s / no limit) [ ***] A start job is running for Configur…only root support (11s / no limit) [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... Starting Load/Save Random Seed... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Load/Save Random Seed. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started dnf makecache --timer. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Timers. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started irqbalance daemon. Starting Login Service... [ OK ] Reached target sshd-keygen.target. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... Starting Restore /run/initramfs on shutdown... [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. [ OK ] Started Login Service. Starting Hostname Service... Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting Dynamic System Tuning Daemon... Starting OpenSSH server daemon... Starting GSSAPI Proxy Daemon... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started OpenSSH server daemon. [ OK ] Started Permit User Sessions. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Getty on tty1. [ OK ] Reached target Login Prompts. [ OK ] Started Command Scheduler. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting 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 oleg142-client login: [ 96.933916] libcfs: loading out-of-tree module taints kernel. [ 97.121046] Key type ._llcrypt registered [ 97.122559] Key type .llcrypt registered [ 97.943934] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 97.964421] alg: No test for adler32 (adler32-zlib) [ 99.653424] Lustre: Lustre: Build Version: 2.17.54_110_g2f98931 [ 100.708351] LNet: Added LNI 192.168.201.42@tcp [8/256/0/180] [ 102.647404] Key type lgssc registered [ 104.087253] hrtimer: interrupt took 4263700 ns [ 104.656939] Lustre: Echo OBD driver; http://www.lustre.org/ [ 292.912832] Lustre: Mounted lustre-client [ 297.954709] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 315.193809] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing check_logdir /tmp/testlogs/ [ 318.450622] Lustre: lustre-OST0000-osc-ffff8c5220ce6000: disconnect after 23s idle [ 320.853789] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing yml_node [ 325.844095] Lustre: DEBUG MARKER: Client: 2.17.54.110 [ 329.423932] Lustre: DEBUG MARKER: MDS: 2.17.54.110 [ 332.555318] Lustre: DEBUG MARKER: OSS: 2.17.54.110 [ 334.757510] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Sat Aug 22 05:49:40 EDT 2026 [ 354.758626] Lustre: DEBUG MARKER: excepting tests: 102 106 115 32newtarball [ 356.821312] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 359.922824] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 370.278668] Lustre: Unmounted lustre-client [ 498.585950] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 505.727689] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 537.995284] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 587.112726] Lustre: DEBUG MARKER: == conf-sanity test 76a: set permanent params with lctl across mounts ========================================================== 05:53:53 (1787392433) [ 621.714597] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 628.155429] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 651.290743] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 652.354872] Lustre: Mounted lustre-client [ 663.524414] Lustre: Unmounted lustre-client [ 781.516216] Lustre: Mounted lustre-client [ 786.662309] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 800.001814] Lustre: Unmounted lustre-client [ 926.317260] Lustre: Mounted lustre-client [ 932.451466] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 942.239913] Lustre: Unmounted lustre-client [ 987.190907] Lustre: DEBUG MARKER: == conf-sanity test 76b: verify params log setup correctly ========================================================== 06:00:33 (1787392833) [ 1086.780055] Lustre: Mounted lustre-client [ 1092.253463] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 1098.479190] Lustre: Unmounted lustre-client [ 1150.774690] Lustre: DEBUG MARKER: == conf-sanity test 76c: verify changelog_mask is applied with lctl set_param -P ========================================================== 06:03:16 (1787392996) [ 1236.860984] Lustre: Mounted lustre-client [ 1241.669728] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 1252.342201] Lustre: lustre-MDT0000-mdc-ffff8c5220ce4000: Connection to lustre-MDT0000 (at 192.168.201.142@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1262.562318] Lustre: lustre-OST0000-osc-ffff8c5220ce4000: disconnect after 23s idle [ 1262.572559] Lustre: Skipped 1 previous similar message [ 1262.584954] Lustre: lustre-MDT0001-mdc-ffff8c5220ce4000: Connection to lustre-MDT0001 (at 192.168.201.142@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1273.823293] Lustre: 2402:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787393104/real 1787393104] req@ffff8c52118e5f80 x1874216298266624/t0(0) o400->MGC192.168.201.142@tcp@192.168.201.142@tcp:26/25 lens 224/224 e 0 to 1 dl 1787393120 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'kworker.0' uid:0 gid:0 projid:4294967295 [ 1273.845318] LustreError: MGC192.168.201.142@tcp: Connection to MGS (at 192.168.201.142@tcp) was lost; in progress operations using this service will fail [ 1273.873558] Lustre: Evicted from MGS (at 192.168.201.142@tcp) after server handle changed from 0xc9a5f777e1a12fd7 to 0xc9a5f777e1a1308d [ 1273.902643] Lustre: MGC192.168.201.142@tcp: Connection restored to 192.168.201.142@tcp (at 192.168.201.142@tcp) [ 1275.433145] Lustre: 24974:0:(mgc_request.c:1899:mgc_process_log()) MGC192.168.201.142@tcp: IR log lustre-cliir failed, not fatal: rc = -2 [ 1307.629140] LustreError: lustre-MDT0000-mdc-ffff8c5220ce4000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 1307.661300] Lustre: lustre-MDT0000-mdc-ffff8c5220ce4000: Connection restored to 192.168.201.142@tcp (at 192.168.201.142@tcp) [ 1309.316796] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1311.316795] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 1317.589776] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1319.763550] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 1324.377818] Lustre: Unmounted lustre-client [ 1373.871427] Lustre: DEBUG MARKER: == conf-sanity test 76d: verify llite.*.xattr_cache can be set by 'lctl set_param -P' correctly ========================================================== 06:06:59 (1787393219) [ 1464.203941] Lustre: Mounted lustre-client [ 1469.484737] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 1477.251873] Lustre: Unmounted lustre-client [ 1530.271887] Lustre: DEBUG MARKER: == conf-sanity test 77: comma-separated MGS NIDs and failover node NIDs ========================================================== 06:09:36 (1787393376) [ 1532.530683] Lustre: DEBUG MARKER: SKIP: conf-sanity test_77 mixed loopback and real device not working [ 1533.957585] Lustre: DEBUG MARKER: == conf-sanity test 78: run resize2fs on MDT and OST filesystems ========================================================== 06:09:40 (1787393380) [ 1595.641369] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1621.807127] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1622.211169] Lustre: Mounted lustre-client [ 1622.216702] Lustre: Skipped 2 previous similar messages [ 1623.914972] Lustre: DEBUG MARKER: create test files [ 1652.946531] Lustre: Unmounted lustre-client [ 1652.949878] Lustre: Skipped 2 previous similar messages [ 1671.798593] Key type lgssc unregistered [ 1672.056886] LNet: 33829:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1672.067212] LNetError: 33829:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1672.090354] LNet: Removed LNI 192.168.201.42@tcp [ 1672.730282] Key type .llcrypt unregistered [ 1672.733641] Key type ._llcrypt unregistered [ 1698.263453] Key type ._llcrypt registered [ 1698.266635] Key type .llcrypt registered [ 1698.917735] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1698.938849] alg: No test for adler32 (adler32-zlib) [ 1700.366158] Lustre: Lustre: Build Version: 2.17.54_110_g2f98931 [ 1700.718680] LNet: Added LNI 192.168.201.42@tcp [8/256/0/180] [ 1702.455385] Key type lgssc registered [ 1704.533275] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1739.557429] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1759.960330] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1762.589622] Lustre: Mounted lustre-client [ 1765.619871] Lustre: DEBUG MARKER: check files after expanding the MDT and OST filesystems [ 1770.774596] Lustre: DEBUG MARKER: create more files after expanding the MDT and OST filesystems [ 1774.334673] Lustre: Unmounted lustre-client [ 1797.792864] Key type lgssc unregistered [ 1798.107337] LNet: 36697:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1798.124795] LNetError: 36697:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1798.158372] LNet: Removed LNI 192.168.201.42@tcp [ 1799.072265] Key type .llcrypt unregistered [ 1799.074666] Key type ._llcrypt unregistered [ 1825.975824] Key type ._llcrypt registered [ 1825.980556] Key type .llcrypt registered [ 1826.405588] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1826.417339] alg: No test for adler32 (adler32-zlib) [ 1827.477726] Lustre: Lustre: Build Version: 2.17.54_110_g2f98931 [ 1827.711734] LNet: Added LNI 192.168.201.42@tcp [8/256/0/180] [ 1829.399255] Key type lgssc registered [ 1831.102908] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1866.472082] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1886.849931] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1888.546232] Lustre: Mounted lustre-client [ 1891.659494] Lustre: DEBUG MARKER: check files after shrinking the MDT and OST filesystems [ 1898.025786] Lustre: Unmounted lustre-client [ 1916.497804] Key type lgssc unregistered [ 1916.691723] LNet: 39458:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1916.695819] LNetError: 39458:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1917.734966] LNet: Removed LNI 192.168.201.42@tcp [ 1918.924523] Key type .llcrypt unregistered [ 1918.929710] Key type ._llcrypt unregistered [ 1947.456562] Key type ._llcrypt registered [ 1947.470068] Key type .llcrypt registered [ 1947.675081] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1947.691338] alg: No test for adler32 (adler32-zlib) [ 1948.797673] Lustre: Lustre: Build Version: 2.17.54_110_g2f98931 [ 1949.060432] LNet: Added LNI 192.168.201.42@tcp [8/256/0/180] [ 1950.735180] Key type lgssc registered [ 1951.809477] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2030.500902] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2036.578048] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2058.949874] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2099.806141] Lustre: DEBUG MARKER: == conf-sanity test 79: format MDT/OST without mgs option (should return errors) ========================================================== 06:19:05 (1787393945) [ 2230.328584] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2236.147043] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2258.174735] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2294.785772] Lustre: DEBUG MARKER: == conf-sanity test 80: mgc import reconnect race ======== 06:22:21 (1787394141) [ 2320.171831] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2324.938939] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2344.807686] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2401.853594] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 2463.226655] Lustre: DEBUG MARKER: == conf-sanity test 81: sparse OST indexing ============== 06:25:08 (1787394308) [ 2464.707618] Lustre: DEBUG MARKER: SKIP: conf-sanity test_81 needs >= 3 OSTs [ 2466.605180] Lustre: DEBUG MARKER: == conf-sanity test 82a: specify OSTs for file (succeed) or directory (succeed) ========================================================== 06:25:12 (1787394312) [ 2468.510648] Lustre: DEBUG MARKER: SKIP: conf-sanity test_82a needs >= 3 OSTs [ 2470.156364] Lustre: DEBUG MARKER: == conf-sanity test 82b: specify OSTs for file with --pool and --ost-list options ========================================================== 06:25:16 (1787394316) [ 2472.152995] Lustre: DEBUG MARKER: SKIP: conf-sanity test_82b needs >= 4 OSTs [ 2474.295524] Lustre: DEBUG MARKER: == conf-sanity test 82c: specify sparse OSTs for setstripe (should not crash) ========================================================== 06:25:19 (1787394319) [ 2475.926527] Lustre: DEBUG MARKER: SKIP: conf-sanity test_82c needs >= 4 OSTs [ 2477.352133] Lustre: DEBUG MARKER: == conf-sanity test 83: ENOSPACE on OST doesn't cause message VFS: Busy inodes after unmount ... ========================================================== 06:25:23 (1787394323) [ 2479.133749] Lustre: DEBUG MARKER: mount the OST /dev/mapper/ost1_flakey as a ldiskfs filesystem [ 2488.295857] Lustre: DEBUG MARKER: run llverfs in partial mode on the OST ldiskfs /mnt/lustre-ost1 [ 2497.671319] Lustre: DEBUG MARKER: unmount the OST /dev/mapper/ost1_flakey [ 2637.336225] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2642.574632] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2668.094837] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2714.662930] Lustre: DEBUG MARKER: == conf-sanity test 84: check recovery_hard_time ========= 06:29:20 (1787394560) [ 2752.523349] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2759.786440] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2785.388898] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2823.273826] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 2835.980911] Lustre: Mounted lustre-client [ 2836.564244] Lustre: Mounted lustre-client [ 2855.441566] Lustre: DEBUG MARKER: local REPLAY BARRIER on lustre-MDT0000 [ 2882.540625] Lustre: lustre-MDT0000-mdc-ffff8c5211fd9800: Connection to lustre-MDT0000 (at 192.168.201.142@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2897.889621] Lustre: 40075:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787394729/real 1787394729] req@0000000068dfab39 x1874218237691136/t0(0) o400->MGC192.168.201.142@tcp@192.168.201.142@tcp:26/25 lens 224/224 e 0 to 1 dl 1787394745 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2897.918324] LustreError: MGC192.168.201.142@tcp: Connection to MGS (at 192.168.201.142@tcp) was lost; in progress operations using this service will fail [ 2908.143209] Lustre: Evicted from MGS (at 192.168.201.142@tcp) after server handle changed from 0xe15fe616ceb0f977 to 0xe15fe616ceb1ba2f [ 2908.157202] Lustre: MGC192.168.201.142@tcp: Connection restored to 192.168.201.142@tcp (at 192.168.201.142@tcp) [ 2915.958778] LustreError: 40071:0:(client.c:3448:ptlrpc_replay_interpret()) @@@ status 301, old was 0 req@00000000ef403734 x1874218237048448/t8589934597(8589934597) o101->lustre-MDT0000-mdc-ffff8c5211fd9800@192.168.201.142@tcp:12/10 lens 592/608 e 0 to 0 dl 1787394779 ref 2 fl Interpret:RQU/604/0 rc 301/301 job:'' uid:0 gid:0 projid:0 [ 2916.653265] LustreError: 40071:0:(client.c:3448:ptlrpc_replay_interpret()) @@@ status 301, old was 0 req@00000000344fc474 x1874218237049088/t8589934599(8589934599) o101->lustre-MDT0000-mdc-ffff8c5211fd9800@192.168.201.142@tcp:12/10 lens 592/608 e 0 to 0 dl 1787394779 ref 2 fl Interpret:RQU/604/0 rc 301/301 job:'' uid:0 gid:0 projid:0 [ 2918.018548] LustreError: 40071:0:(client.c:3448:ptlrpc_replay_interpret()) @@@ status 301, old was 0 req@00000000d274e7f7 x1874218237050368/t8589934603(8589934603) o101->lustre-MDT0000-mdc-ffff8c5211fd9800@192.168.201.142@tcp:12/10 lens 592/608 e 0 to 0 dl 1787394780 ref 2 fl Interpret:RQU/604/0 rc 301/301 job:'' uid:0 gid:0 projid:0 [ 2918.052294] LustreError: 40071:0:(client.c:3448:ptlrpc_replay_interpret()) Skipped 1 previous similar message [ 2920.654270] LustreError: 40071:0:(client.c:3448:ptlrpc_replay_interpret()) @@@ status 301, old was 0 req@00000000bdc54eb0 x1874218237052928/t8589934611(8589934611) o101->lustre-MDT0000-mdc-ffff8c5211fd9800@192.168.201.142@tcp:12/10 lens 592/608 e 0 to 0 dl 1787394783 ref 2 fl Interpret:RQU/604/0 rc 301/301 job:'' uid:0 gid:0 projid:0 [ 2920.672847] LustreError: 40071:0:(client.c:3448:ptlrpc_replay_interpret()) Skipped 3 previous similar messages [ 2925.195504] LustreError: 40071:0:(client.c:3448:ptlrpc_replay_interpret()) @@@ status 301, old was 0 req@00000000255a80eb x1874218237057408/t8589934625(8589934625) o101->lustre-MDT0000-mdc-ffff8c5211fd9800@192.168.201.142@tcp:12/10 lens 592/608 e 0 to 0 dl 1787394788 ref 2 fl Interpret:RQU/604/0 rc 301/301 job:'' uid:0 gid:0 projid:0 [ 2925.222939] LustreError: 40071:0:(client.c:3448:ptlrpc_replay_interpret()) Skipped 6 previous similar messages [ 2933.442972] LustreError: 40071:0:(client.c:3448:ptlrpc_replay_interpret()) @@@ status 301, old was 0 req@00000000d2d0d306 x1874218237065728/t8589934651(8589934651) o101->lustre-MDT0000-mdc-ffff8c5211fd9800@192.168.201.142@tcp:12/10 lens 592/608 e 0 to 0 dl 1787394796 ref 2 fl Interpret:RQU/604/0 rc 301/301 job:'' uid:0 gid:0 projid:0 [ 2933.471363] LustreError: 40071:0:(client.c:3448:ptlrpc_replay_interpret()) Skipped 12 previous similar messages [ 2949.978294] LustreError: 40071:0:(client.c:3448:ptlrpc_replay_interpret()) @@@ status 301, old was 0 req@00000000c8a3d0dc x1874218237082368/t8589934703(8589934703) o101->lustre-MDT0000-mdc-ffff8c5211fd9800@192.168.201.142@tcp:12/10 lens 592/608 e 0 to 0 dl 1787394812 ref 2 fl Interpret:RQU/604/0 rc 301/301 job:'' uid:0 gid:0 projid:0 [ 2950.017770] LustreError: 40071:0:(client.c:3448:ptlrpc_replay_interpret()) Skipped 25 previous similar messages [ 2958.885954] LustreError: 40071:0:(mdc_request.c:668:mdc_replay_open()) @@@ cannot properly replay without open data req@000000003ef66756 x1874218237091328/t8589934731(8589934731) o101->lustre-MDT0000-mdc-ffff8c5211fd9800@192.168.201.142@tcp:12/10 lens 592/608 e 0 to 0 dl 1787394821 ref 1 fl Interpret:RMQU/604/0 rc 301/301 job:'' uid:0 gid:0 projid:0 [ 2973.172383] LustreError: lustre-MDT0000-mdc-ffff8c5211fd9800: operation mds_close to node 192.168.201.142@tcp failed: rc = -107 [ 2973.300085] Lustre: lustre-MDT0000-mdc-ffff8c5205448000: Connection restored to 192.168.201.142@tcp (at 192.168.201.142@tcp) [ 2975.724805] LustreError: lustre-MDT0000-mdc-ffff8c5211fd9800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 2975.924749] LustreError: 61630:0:(file.c:6088:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -5 [ 2976.489467] Lustre: lustre-MDT0000-mdc-ffff8c5211fd9800: Connection restored to 192.168.201.142@tcp (at 192.168.201.142@tcp) [ 2983.418084] Lustre: Unmounted lustre-client [ 2985.305635] Lustre: Unmounted lustre-client [ 3037.891489] Lustre: DEBUG MARKER: == conf-sanity test 85: osd_ost init: fail ea_fid_set ==== 06:34:43 (1787394883) [ 3108.162906] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3120.537538] Lustre: DEBUG MARKER: == conf-sanity test 86: Replacing mkfs.lustre -G option == 06:36:05 (1787394965) [ 3158.491359] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3289.259928] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3295.615797] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3324.283674] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3378.217062] Lustre: DEBUG MARKER: == conf-sanity test 87: check if MDT inode can hold EAs with N stripes properly ========================================================== 06:40:23 (1787395223) [ 3380.866250] Key type lgssc unregistered [ 3381.069633] LNet: 69036:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3381.073481] LNetError: 69036:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3381.093391] LNet: Removed LNI 192.168.201.42@tcp [ 3381.756782] Key type .llcrypt unregistered [ 3381.758359] Key type ._llcrypt unregistered [ 3411.796964] Key type ._llcrypt registered [ 3411.798950] Key type .llcrypt registered [ 3412.063927] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3412.079421] alg: No test for adler32 (adler32-zlib) [ 3413.170348] Lustre: Lustre: Build Version: 2.17.54_110_g2f98931 [ 3413.500751] LNet: Added LNI 192.168.201.42@tcp [8/256/0/180] [ 3415.191192] Key type lgssc registered [ 3416.455506] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3528.388119] Lustre: Mounted lustre-client [ 3529.903662] Lustre: Unmounted lustre-client [ 3563.702937] Lustre: DEBUG MARKER: == conf-sanity test 88a: tunefs.lustre persists a --mountfsoptions-only change ========================================================== 06:43:29 (1787395409) [ 3587.279508] Lustre: DEBUG MARKER: == conf-sanity test 88b: check the default mount options can be overridden ========================================================== 06:43:52 (1787395432) [ 3618.999529] Lustre: DEBUG MARKER: == conf-sanity test 89: check tunefs --param and --erase-param{s} options ========================================================== 06:44:24 (1787395464) [ 3744.670348] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL failover.node=192.0.2.254@tcp0,mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3750.816554] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL failover.node=192.0.2.254@tcp0,mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3776.059314] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount (FULL|IDLE) failover.node=192.0.2.254@tcp0,osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3817.668160] Lustre: DEBUG MARKER: == conf-sanity test 90a: check max_mod_rpcs_in_flight is enforced ========================================================== 06:47:43 (1787395663) [ 3856.949451] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3863.261975] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3882.039785] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3882.685275] Lustre: Mounted lustre-client [ 3904.991225] Lustre: 81456:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787395736/real 1787395736] req@00000000e2a74883 x1874219772177664/t0(0) o36->lustre-MDT0000-mdc-ffff8c5207f23000@192.168.201.142@tcp:12/10 lens 488/512 e 0 to 1 dl 1787395752 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3904.993104] Lustre: lustre-MDT0000-mdc-ffff8c5207f23000: Connection to lustre-MDT0000 (at 192.168.201.142@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3905.011342] Lustre: 81456:0:(client.c:2490:ptlrpc_expire_one_request()) Skipped 3 previous similar messages [ 3905.068154] Lustre: lustre-MDT0000-mdc-ffff8c5207f23000: Connection restored to 192.168.201.142@tcp (at 192.168.201.142@tcp) [ 3921.887346] Lustre: 81493:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787395753/real 1787395753] req@000000009f4f4a57 x1874219772185728/t0(0) o36->lustre-MDT0000-mdc-ffff8c5207f23000@192.168.201.142@tcp:12/10 lens 488/512 e 0 to 1 dl 1787395769 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3921.887941] Lustre: lustre-MDT0000-mdc-ffff8c5207f23000: Connection to lustre-MDT0000 (at 192.168.201.142@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3921.907357] Lustre: 81493:0:(client.c:2490:ptlrpc_expire_one_request()) Skipped 5 previous similar messages [ 3921.953647] Lustre: lustre-MDT0000-mdc-ffff8c5207f23000: Connection restored to 192.168.201.142@tcp (at 192.168.201.142@tcp) [ 3937.647990] Lustre: Unmounted lustre-client [ 3963.800456] Key type lgssc unregistered [ 3964.022123] LNet: 81951:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3964.027292] LNetError: 81951:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3964.049590] LNet: Removed LNI 192.168.201.42@tcp [ 3964.621815] Key type .llcrypt unregistered [ 3964.624210] Key type ._llcrypt unregistered [ 3978.580332] Key type ._llcrypt registered [ 3978.588391] Key type .llcrypt registered [ 3979.785720] Lustre: DEBUG MARKER: == conf-sanity test 90b: check max_mod_rpcs_in_flight is enforced after update ========================================================== 06:50:25 (1787395825) [ 3981.604212] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3981.632323] alg: No test for adler32 (adler32-zlib) [ 3982.652987] Lustre: Lustre: Build Version: 2.17.54_110_g2f98931 [ 3982.986789] LNet: Added LNI 192.168.201.42@tcp [8/256/0/180] [ 3984.736283] Key type lgssc registered [ 3985.721946] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4028.099371] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4033.698042] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4054.034310] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4055.480445] Lustre: Mounted lustre-client [ 4078.047424] Lustre: 85126:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787395909/real 1787395909] req@00000000e96dcb63 x1874220369847168/t0(0) o36->lustre-MDT0000-mdc-ffff8c520785e800@192.168.201.142@tcp:12/10 lens 488/512 e 0 to 1 dl 1787395925 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4078.079597] Lustre: lustre-MDT0000-mdc-ffff8c520785e800: Connection to lustre-MDT0000 (at 192.168.201.142@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4078.121454] Lustre: lustre-MDT0000-mdc-ffff8c520785e800: Connection restored to 192.168.201.142@tcp (at 192.168.201.142@tcp) [ 4095.967250] Lustre: 85174:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787395927/real 1787395927] req@000000002b652a17 x1874220369860864/t0(0) o36->lustre-MDT0001-mdc-ffff8c520785e800@192.168.201.142@tcp:12/10 lens 488/512 e 0 to 1 dl 1787395943 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4095.967531] Lustre: lustre-MDT0001-mdc-ffff8c520785e800: Connection to lustre-MDT0001 (at 192.168.201.142@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4095.976916] Lustre: 85174:0:(client.c:2490:ptlrpc_expire_one_request()) Skipped 3 previous similar messages [ 4096.021434] Lustre: lustre-MDT0001-mdc-ffff8c520785e800: Connection restored to 192.168.201.142@tcp (at 192.168.201.142@tcp) [ 4114.399206] Lustre: 85205:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787395945/real 1787395945] req@00000000573b7bb6 x1874220369866368/t0(0) o36->lustre-MDT0001-mdc-ffff8c520785e800@192.168.201.142@tcp:12/10 lens 488/512 e 0 to 1 dl 1787395961 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4114.399334] Lustre: lustre-MDT0001-mdc-ffff8c520785e800: Connection to lustre-MDT0001 (at 192.168.201.142@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4114.415508] Lustre: 85205:0:(client.c:2490:ptlrpc_expire_one_request()) Skipped 3 previous similar messages [ 4114.477611] Lustre: lustre-MDT0001-mdc-ffff8c520785e800: Connection restored to 192.168.201.142@tcp (at 192.168.201.142@tcp) [ 4119.986897] Lustre: Unmounted lustre-client [ 4121.484760] Lustre: Mounted lustre-client [ 4140.511268] Lustre: 85358:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787395971/real 1787395971] req@0000000088241706 x1874220369906304/t0(0) o36->lustre-MDT0000-mdc-ffff8c5220cd3000@192.168.201.142@tcp:12/10 lens 496/512 e 0 to 1 dl 1787395987 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4140.512307] Lustre: lustre-MDT0000-mdc-ffff8c5220cd3000: Connection to lustre-MDT0000 (at 192.168.201.142@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4140.537785] Lustre: 85358:0:(client.c:2490:ptlrpc_expire_one_request()) Skipped 4 previous similar messages [ 4140.588181] Lustre: lustre-MDT0000-mdc-ffff8c5220cd3000: Connection restored to 192.168.201.142@tcp (at 192.168.201.142@tcp) [ 4157.919207] Lustre: 85412:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787395989/real 1787395989] req@000000007353b6cc x1874220369920896/t0(0) o36->lustre-MDT0000-mdc-ffff8c5220cd3000@192.168.201.142@tcp:12/10 lens 488/512 e 0 to 1 dl 1787396005 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4157.922317] Lustre: lustre-MDT0000-mdc-ffff8c5220cd3000: Connection to lustre-MDT0000 (at 192.168.201.142@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4157.960636] Lustre: 85412:0:(client.c:2490:ptlrpc_expire_one_request()) Skipped 14 previous similar messages [ 4158.010264] Lustre: lustre-MDT0000-mdc-ffff8c5220cd3000: Connection restored to 192.168.201.142@tcp (at 192.168.201.142@tcp) [ 4163.269217] Lustre: Unmounted lustre-client [ 4187.752526] Key type lgssc unregistered [ 4188.090539] LNet: 85878:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4188.107667] LNetError: 85878:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4188.140199] LNet: Removed LNI 192.168.201.42@tcp [ 4188.747159] Key type .llcrypt unregistered [ 4188.749131] Key type ._llcrypt unregistered [ 4202.564336] Key type ._llcrypt registered [ 4202.566761] Key type .llcrypt registered [ 4203.570746] Lustre: DEBUG MARKER: == conf-sanity test 90c: check max_mod_rpcs_in_flight update limits ========================================================== 06:54:09 (1787396049) [ 4205.684141] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4205.702136] alg: No test for adler32 (adler32-zlib) [ 4206.872361] Lustre: Lustre: Build Version: 2.17.54_110_g2f98931 [ 4207.137354] LNet: Added LNI 192.168.201.42@tcp [8/256/0/180] [ 4208.879411] Key type lgssc registered [ 4209.955577] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4257.550938] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4262.567704] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4283.088454] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4285.741506] Lustre: Mounted lustre-client [ 4291.483404] Lustre: Unmounted lustre-client [ 4292.804078] Lustre: Mounted lustre-client [ 4293.025341] LustreError: 89128:0:(genops.c:2263:obd_set_max_mod_rpcs_in_flight()) lustre-MDT0000-mdc-ffff8c5203ead000: can't set max_mod_rpcs_in_flight=9 higher than mdt.*.max_mod_rpcs_in_flight=8 returned by the MDT server at connection. [ 4297.241427] Lustre: Unmounted lustre-client [ 4297.706064] Lustre: Mounted lustre-client [ 4302.819860] Lustre: Unmounted lustre-client [ 4340.329480] Key type lgssc unregistered [ 4340.604319] LNet: 89676:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4340.613709] LNetError: 89676:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4340.645154] LNet: Removed LNI 192.168.201.42@tcp [ 4341.317958] Key type .llcrypt unregistered [ 4341.323278] Key type ._llcrypt unregistered [ 4356.262546] Key type ._llcrypt registered [ 4356.265419] Key type .llcrypt registered [ 4357.644739] Lustre: DEBUG MARKER: == conf-sanity test 90d: check one close RPC is allowed above max_mod_rpcs_in_flight ========================================================== 06:56:43 (1787396203) [ 4360.447731] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4360.488561] alg: No test for adler32 (adler32-zlib) [ 4361.699734] Lustre: Lustre: Build Version: 2.17.54_110_g2f98931 [ 4361.888987] LNet: Added LNI 192.168.201.42@tcp [8/256/0/180] [ 4363.536982] Key type lgssc registered [ 4364.917800] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4416.521231] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4423.620870] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4444.897527] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4447.748141] Lustre: Mounted lustre-client [ 4471.263397] Lustre: 92855:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787396302/real 1787396302] req@0000000016a8dd95 x1874220767266304/t0(0) o36->lustre-MDT0000-mdc-ffff8c5210cd4800@192.168.201.142@tcp:12/10 lens 488/512 e 0 to 1 dl 1787396318 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4471.263751] Lustre: lustre-MDT0000-mdc-ffff8c5210cd4800: Connection to lustre-MDT0000 (at 192.168.201.142@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4471.275953] Lustre: 92855:0:(client.c:2490:ptlrpc_expire_one_request()) Skipped 3 previous similar messages [ 4471.310615] Lustre: lustre-MDT0000-mdc-ffff8c5210cd4800: Connection restored to 192.168.201.142@tcp (at 192.168.201.142@tcp) [ 4485.422196] Lustre: Unmounted lustre-client [ 4517.057302] Key type lgssc unregistered [ 4517.441902] LNet: 93305:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4517.451487] LNetError: 93305:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4517.492418] LNet: Removed LNI 192.168.201.42@tcp [ 4518.461538] Key type .llcrypt unregistered [ 4518.464159] Key type ._llcrypt unregistered [ 4535.817662] Key type ._llcrypt registered [ 4535.820807] Key type .llcrypt registered [ 4537.599806] Lustre: DEBUG MARKER: == conf-sanity test 91: evict-by-nid support ============= 06:59:42 (1787396382) [ 4540.598336] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4540.928114] alg: No test for adler32 (adler32-zlib) [ 4542.169517] Lustre: Lustre: Build Version: 2.17.54_110_g2f98931 [ 4542.524403] LNet: Added LNI 192.168.201.42@tcp [8/256/0/180] [ 4544.383696] Key type lgssc registered [ 4545.868546] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4598.546711] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4606.867201] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4633.102937] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4644.472225] Lustre: Mounted lustre-client [ 4651.525236] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4661.988273] Lustre: Unmounted lustre-client [ 4718.150196] Key type lgssc unregistered [ 4718.413476] LNet: 96994:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4718.424725] LNetError: 96994:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4718.449762] LNet: Removed LNI 192.168.201.42@tcp [ 4719.177754] Key type .llcrypt unregistered [ 4719.182722] Key type ._llcrypt unregistered [ 4733.420805] Key type ._llcrypt registered [ 4733.423588] Key type .llcrypt registered [ 4734.683785] Lustre: DEBUG MARKER: == conf-sanity test 92: ldev returns MGS NID correctly in command substitution ========================================================== 07:03:00 (1787396580) [ 4745.500695] Lustre: DEBUG MARKER: == conf-sanity test 93: register mulitple MDT at the same time ========================================================== 07:03:11 (1787396591) [ 4747.128284] Lustre: DEBUG MARKER: SKIP: conf-sanity test_93 needs >= 3 MDTs [ 4748.972246] Lustre: DEBUG MARKER: == conf-sanity test 94: ldev outputs correct labels for file system name query ========================================================== 07:03:14 (1787396594) [ 4757.877431] Lustre: DEBUG MARKER: == conf-sanity test 95: ldev should only allow one label filter ========================================================== 07:03:23 (1787396603) [ 4767.952405] Lustre: DEBUG MARKER: == conf-sanity test 96: ldev returns hostname and backend fs correctly in command sub ========================================================== 07:03:33 (1787396613) [ 4777.520546] Lustre: DEBUG MARKER: == conf-sanity test 97: ldev returns correct ouput when querying based on role ========================================================== 07:03:43 (1787396623) [ 4788.362714] Lustre: DEBUG MARKER: == conf-sanity test 98: Buffer-overflow check while parsing mount_opts ========================================================== 07:03:54 (1787396634) [ 4791.060390] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4791.119334] alg: No test for adler32 (adler32-zlib) [ 4792.362286] Lustre: Lustre: Build Version: 2.17.54_110_g2f98931 [ 4792.610533] LNet: Added LNI 192.168.201.42@tcp [8/256/0/180] [ 4794.272859] Key type lgssc registered [ 4795.894252] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4857.101634] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4863.394531] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4887.465910] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4894.808734] Lustre: Mounted lustre-client [ 4901.013732] Lustre: Unmounted lustre-client [ 4933.983765] Key type lgssc unregistered [ 4934.480040] LNet: 104798:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4934.491460] LNetError: 104798:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4935.528162] LNet: Removed LNI 192.168.201.42@tcp [ 4936.793255] Key type .llcrypt unregistered [ 4936.801297] Key type ._llcrypt unregistered [ 4958.274507] Key type ._llcrypt registered [ 4958.277850] Key type .llcrypt registered [ 4959.845965] Lustre: DEBUG MARKER: == conf-sanity test 99: Adding meta_bg option ============ 07:06:45 (1787396805) [ 4963.078368] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4963.134320] alg: No test for adler32 (adler32-zlib) [ 4964.401572] Lustre: Lustre: Build Version: 2.17.54_110_g2f98931 [ 4964.717471] LNet: Added LNI 192.168.201.42@tcp [8/256/0/180] [ 4966.567203] Key type lgssc registered [ 4968.397907] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5000.667892] Lustre: DEBUG MARKER: == conf-sanity test 100: check lshowmount lists MGS, MDT, OST and 0@lo ========================================================== 07:07:26 (1787396846) [ 5113.661080] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5121.246224] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5149.553592] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5152.441163] Lustre: Mounted lustre-client [ 5160.199217] Lustre: Unmounted lustre-client [ 5190.983480] Key type lgssc unregistered [ 5191.580080] LNet: 111093:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5191.590119] LNetError: 111093:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5192.615632] LNet: Removed LNI 192.168.201.42@tcp [ 5193.842877] Key type .llcrypt unregistered [ 5193.844994] Key type ._llcrypt unregistered [ 5217.495568] Key type ._llcrypt registered [ 5217.498116] Key type .llcrypt registered [ 5218.803816] Lustre: DEBUG MARKER: == conf-sanity test 101a: Race MDT->OST reconnection with create ========================================================== 07:11:04 (1787397064) [ 5221.888175] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5221.944091] alg: No test for adler32 (adler32-zlib) [ 5223.090907] Lustre: Lustre: Build Version: 2.17.54_110_g2f98931 [ 5223.363069] LNet: Added LNI 192.168.201.42@tcp [8/256/0/180] [ 5225.100696] Key type lgssc registered [ 5226.588546] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5287.495412] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5294.082748] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5319.443526] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5325.361354] Lustre: Mounted lustre-client [ 7476.646821] Lustre: Unmounted lustre-client [ 7512.274939] Key type lgssc unregistered [ 7512.584260] LNet: 123627:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7512.599765] LNetError: 123627:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7512.620407] LNet: Removed LNI 192.168.201.42@tcp [ 7513.216753] Key type .llcrypt unregistered [ 7513.218907] Key type ._llcrypt unregistered [ 7530.064370] Key type ._llcrypt registered [ 7530.070398] Key type .llcrypt registered [ 7531.566499] Lustre: DEBUG MARKER: == conf-sanity test 101b: Race events DISCONNECT and ACTIVE in osp ========================================================== 07:49:37 (1787399377) [ 7533.784433] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7533.825106] alg: No test for adler32 (adler32-zlib) [ 7534.879567] Lustre: Lustre: Build Version: 2.17.54_110_g2f98931 [ 7535.128054] LNet: Added LNI 192.168.201.42@tcp [8/256/0/180] [ 7536.775211] Key type lgssc registered [ 7537.950952] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7586.350503] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7591.609809] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7610.288242] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7613.179634] Lustre: Mounted lustre-client [ 7623.662915] Lustre: lustre-OST0000-osc-ffff8c520785a800: Connection to lustre-OST0000 (at 192.168.201.142@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7675.422739] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7676.949969] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7682.500993] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8c520785a800.ost_server_uuid 50 [ 7683.961633] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8c520785a800.ost_server_uuid in FULL state after 0 sec [ 7685.659667] Lustre: Unmounted lustre-client [ 7713.279203] Key type lgssc unregistered [ 7713.470380] LNet: 128283:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7713.482655] LNetError: 128283:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7713.501060] LNet: Removed LNI 192.168.201.42@tcp [ 7714.115829] Key type .llcrypt unregistered [ 7714.117565] Key type ._llcrypt unregistered [ 7732.142953] Key type ._llcrypt registered [ 7732.146180] Key type .llcrypt registered [ 7733.789206] Lustre: DEBUG MARKER: SKIP: conf-sanity test_102 skipping excluded test 102 [ 7736.023767] Lustre: DEBUG MARKER: == conf-sanity test 103: rename filesystem name ========== 07:53:01 (1787399581) [ 7744.871531] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7744.884713] alg: No test for adler32 (adler32-zlib) [ 7746.192469] Lustre: Lustre: Build Version: 2.17.54_110_g2f98931 [ 7746.733809] LNet: Added LNI 192.168.201.42@tcp [8/256/0/180] [ 7748.455124] Key type lgssc registered [ 7750.428707] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7842.973876] Lustre: Mounted lustre-client [ 7848.430641] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 7868.902915] Lustre: lustre-OST0001-osc-ffff8c5207168800: disconnect after 24s idle [ 7871.635467] Lustre: Unmounted lustre-client [ 7996.684585] Lustre: Mounted mylustre-client [ 8001.211440] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 8014.954957] Lustre: Unmounted mylustre-client [ 8155.862174] Lustre: Mounted tfs-client [ 8160.824627] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 8170.094609] Lustre: Unmounted tfs-client [ 8307.585645] Lustre: Mounted lustre-client [ 8313.714672] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 8325.085951] Lustre: DEBUG MARKER: == conf-sanity test 104a: Make sure user defined options are reflected in mount ========================================================== 08:02:50 (1787400170) [ 8329.500406] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 8339.937305] Lustre: Unmounted lustre-client [ 8507.058109] Lustre: client wants to enable acl, but mdt not! [ 8507.101067] Lustre: Mounted lustre-client [ 8515.185557] Lustre: DEBUG MARKER: == conf-sanity test 104b: Mount uses last flock argument ========================================================== 08:06:00 (1787400360) [ 8515.568952] Lustre: client wants to enable acl, but mdt not! [ 8515.589098] Lustre: Mounted lustre-client [ 8518.391993] Lustre: Unmounted lustre-client [ 8518.897699] Lustre: client wants to enable acl, but mdt not! [ 8521.493477] Lustre: client wants to enable acl, but mdt not! [ 8528.486502] Lustre: Unmounted lustre-client [ 8528.489315] Lustre: Skipped 1 previous similar message [ 8530.585170] Lustre: DEBUG MARKER: == conf-sanity test 105: check file creation for ro and rw bind mnt pt ========================================================== 08:06:15 (1787400375) [ 8532.847409] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 8532.853774] Lustre: Skipped 3 previous similar messages [ 8580.296094] Key type lgssc unregistered [ 8580.618830] LNet: 143817:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8580.632991] LNetError: 143817:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8581.705343] LNet: Removed LNI 192.168.201.42@tcp [ 8582.964227] Key type .llcrypt unregistered [ 8582.967824] Key type ._llcrypt unregistered [ 8611.114634] Key type ._llcrypt registered [ 8611.127568] Key type .llcrypt registered [ 8611.735425] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8611.749704] alg: No test for adler32 (adler32-zlib) [ 8612.846497] Lustre: Lustre: Build Version: 2.17.54_110_g2f98931 [ 8613.095919] LNet: Added LNI 192.168.201.42@tcp [8/256/0/180] [ 8614.847200] Key type lgssc registered [ 8616.182543] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8705.875597] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8712.561790] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8738.437606] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8740.170747] Lustre: Mounted lustre-client [ 8740.599682] Lustre: Remounted lustre-client read-only [ 8743.525553] Lustre: Unmounted lustre-client [ 8770.211657] Key type lgssc unregistered [ 8770.429854] LNet: 148040:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8770.437159] LNetError: 148040:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8770.459942] LNet: Removed LNI 192.168.201.42@tcp [ 8770.975860] Key type .llcrypt unregistered [ 8770.979517] Key type ._llcrypt unregistered [ 8788.158023] Key type ._llcrypt registered [ 8788.160345] Key type .llcrypt registered [ 8789.536898] Lustre: DEBUG MARKER: SKIP: conf-sanity test_106 skipping SLOW test 106 [ 8791.301634] Lustre: DEBUG MARKER: == conf-sanity test 107: Unknown config param should not fail target mounting ========================================================== 08:10:37 (1787400637) [ 8794.163582] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8794.216496] alg: No test for adler32 (adler32-zlib) [ 8795.313240] Lustre: Lustre: Build Version: 2.17.54_110_g2f98931 [ 8795.643788] LNet: Added LNI 192.168.201.42@tcp [8/256/0/180] [ 8797.369148] Key type lgssc registered [ 8798.627976] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8857.229795] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8863.926280] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8884.908024] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8915.688705] Key type lgssc unregistered [ 8916.015791] LNet: 151653:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8916.025754] LNetError: 151653:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8916.073273] LNet: Removed LNI 192.168.201.42@tcp [ 8917.277674] Key type .llcrypt unregistered [ 8917.280493] Key type ._llcrypt unregistered [ 8932.263458] Key type ._llcrypt registered [ 8932.299090] Key type .llcrypt registered [ 8932.758936] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8932.778208] alg: No test for adler32 (adler32-zlib) [ 8933.986138] Lustre: Lustre: Build Version: 2.17.54_110_g2f98931 [ 8934.453450] LNet: Added LNI 192.168.201.42@tcp [8/256/0/180] [ 8936.220568] Key type lgssc registered [ 8937.700479] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9035.128747] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9052.994886] Key type lgssc unregistered [ 9053.307268] LNet: 153629:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9053.315600] LNetError: 153629:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9053.337374] LNet: Removed LNI 192.168.201.42@tcp [ 9054.283205] Key type .llcrypt unregistered [ 9054.285814] Key type ._llcrypt unregistered [ 9073.766249] Key type ._llcrypt registered [ 9073.771603] Key type .llcrypt registered [ 9075.096515] Lustre: DEBUG MARKER: == conf-sanity test 108a: migrate from ldiskfs to ZFS ==== 08:15:20 (1787400920) [ 9076.861347] Lustre: DEBUG MARKER: SKIP: conf-sanity test_108a zfs only test [ 9080.264607] Lustre: DEBUG MARKER: == conf-sanity test 108b: migrate from ZFS to ldiskfs ==== 08:15:25 (1787400925) [ 9096.870791] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9096.909875] alg: No test for adler32 (adler32-zlib) [ 9098.292581] Lustre: Lustre: Build Version: 2.17.54_110_g2f98931 [ 9098.652740] LNet: Added LNI 192.168.201.42@tcp [8/256/0/180] [ 9100.457464] Key type lgssc registered [ 9102.362861] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9183.916759] Lustre: Mounted lustre-client [ 9185.380542] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 9185.398324] LustreError: 156212:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff8c52327a7800: inode [0x200000bd1:0x2:0x0] mdc close failed: rc = -108 [ 9185.430327] Lustre: 155020:0:(llite_lib.c:4202:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.201.142@tcp:/lustre/fid: [0x200000bd1:0x2:0x0]/ may get corrupted (rc -108) [ 9185.470672] Lustre: 155021:0:(llite_lib.c:4202:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.201.142@tcp:/lustre/fid: [0x240000402:0x3:0x0]/ may get corrupted (rc -108) [ 9195.905151] Lustre: Unmounted lustre-client [ 9249.976411] Lustre: DEBUG MARKER: == conf-sanity test 109a: test lctl clear_conf fsname ==== 08:18:14 (1787401094) [ 9388.985863] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9395.355828] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9429.460666] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9430.203027] Lustre: Mounted lustre-client [ 9491.883417] Lustre: Unmounted lustre-client [ 9581.713797] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9588.751402] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9614.942849] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9626.260340] Lustre: Mounted lustre-client [ 9654.507950] Lustre: Unmounted lustre-client [ 9686.209443] Key type lgssc unregistered [ 9686.521079] LNet: 163130:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9686.542410] LNetError: 163130:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9686.569637] LNet: Removed LNI 192.168.201.42@tcp [ 9687.389282] Key type .llcrypt unregistered [ 9687.391216] Key type ._llcrypt unregistered [ 9706.852508] Key type ._llcrypt registered [ 9706.854687] Key type .llcrypt registered [ 9708.666893] Lustre: DEBUG MARKER: == conf-sanity test 109b: test lctl clear_conf one config ========================================================== 08:25:53 (1787401553) [ 9740.318744] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9740.447979] alg: No test for adler32 (adler32-zlib) [ 9741.613768] Lustre: Lustre: Build Version: 2.17.54_110_g2f98931 [ 9742.030475] LNet: Added LNI 192.168.201.42@tcp [8/256/0/180] [ 9743.799694] Key type lgssc registered [ 9745.776271] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9841.130865] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9848.399362] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9877.952507] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9879.715573] Lustre: Mounted lustre-client [ 9939.368364] Lustre: Unmounted lustre-client [10034.211513] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10041.010468] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10067.303533] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10073.742661] Lustre: Mounted lustre-client [10103.904790] Lustre: Unmounted lustre-client [10134.818317] Key type lgssc unregistered [10135.075973] LNet: 170444:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10135.084812] LNetError: 170444:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [10135.126505] LNet: Removed LNI 192.168.201.42@tcp [10136.243749] Key type .llcrypt unregistered [10136.246896] Key type ._llcrypt unregistered [10158.766292] Key type ._llcrypt registered [10158.770685] Key type .llcrypt registered [10159.929724] Lustre: DEBUG MARKER: == conf-sanity test 110: Adding large_dir with 3-level htree ========================================================== 08:33:25 (1787402005) [10176.957675] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10177.018212] alg: No test for adler32 (adler32-zlib) [10178.219168] Lustre: Lustre: Build Version: 2.17.54_110_g2f98931 [10178.650367] LNet: Added LNI 192.168.201.42@tcp [8/256/0/180] [10180.367629] Key type lgssc registered [10182.099367] Lustre: Echo OBD driver; http://www.lustre.org/ [10276.305871] Lustre: Mounted lustre-client [10279.440785] Lustre: Mounted lustre-client [11235.381685] Lustre: Unmounted lustre-client [11237.658533] Lustre: Unmounted lustre-client [11362.361242] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11367.698177] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11392.397982] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11431.288161] Lustre: DEBUG MARKER: SKIP: conf-sanity test_111 skipping SLOW test 111 [11432.848292] Lustre: DEBUG MARKER: == conf-sanity test 112a: mount OST with no_create option ========================================================== 08:54:38 (1787403278) [11468.955994] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11475.517437] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11503.211421] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11525.052720] Lustre: Mounted lustre-client [11536.327563] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8c5208eee000.ost_server_uuid 50 [11538.195428] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8c5208eee000.ost_server_uuid in FULL state after 0 sec [11545.650792] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8c5208eee000.ost_server_uuid 50 [11547.338948] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8c5208eee000.ost_server_uuid in IDLE state after 0 sec [11566.077069] Lustre: lustre-OST0001-osc-ffff8c5208eee000: Connection to lustre-OST0001 (at 192.168.201.142@tcp) was lost; in progress operations using this service will wait for recovery to complete [11574.035154] Lustre: Unmounted lustre-client [11603.743646] Key type lgssc unregistered [11603.969635] LNet: 181954:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11603.976720] LNetError: 181954:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [11604.000329] LNet: Removed LNI 192.168.201.42@tcp [11604.661760] Key type .llcrypt unregistered [11604.664363] Key type ._llcrypt unregistered [11621.176642] Key type ._llcrypt registered [11621.179456] Key type .llcrypt registered [11622.799057] Lustre: DEBUG MARKER: == conf-sanity test 112b: mount MDT with no_create option ========================================================== 08:57:48 (1787403468) [11625.167886] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11625.298771] alg: No test for adler32 (adler32-zlib) [11626.571693] Lustre: Lustre: Build Version: 2.17.54_110_g2f98931 [11626.950789] LNet: Added LNI 192.168.201.42@tcp [8/256/0/180] [11628.687220] Key type lgssc registered [11630.199585] Lustre: Echo OBD driver; http://www.lustre.org/ [11670.164394] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11708.070703] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11730.613481] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [11732.235779] Lustre: Mounted lustre-client [11851.569922] Lustre: Unmounted lustre-client [11878.970538] Key type lgssc unregistered [11879.277951] LNet: 185845:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11879.293653] LNetError: 185845:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [11879.327734] LNet: Removed LNI 192.168.201.42@tcp [11880.065749] Key type .llcrypt unregistered [11880.068736] Key type ._llcrypt unregistered [11895.921516] Key type ._llcrypt registered [11895.923342] Key type .llcrypt registered [11897.105948] Lustre: DEBUG MARKER: == conf-sanity test 113: Shadow mountpoint correctly report ro/rw for mounts ========================================================== 09:02:22 (1787403742) [11917.329234] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11917.373827] alg: No test for adler32 (adler32-zlib) [11918.404177] Lustre: Lustre: Build Version: 2.17.54_110_g2f98931 [11918.670410] LNet: Added LNI 192.168.201.42@tcp [8/256/0/180] [11920.330144] Key type lgssc registered [11921.428371] Lustre: Echo OBD driver; http://www.lustre.org/ [11994.577278] Lustre: Mounted lustre-client [11998.840590] Lustre: DEBUG MARKER: Using TIMEOUT=20 [12015.612984] Lustre: Unmounted lustre-client [12148.805194] Lustre: Mounted lustre-client [12154.099592] Lustre: DEBUG MARKER: Using TIMEOUT=20 [12161.456034] Lustre: Unmounted lustre-client [12198.191356] Lustre: DEBUG MARKER: SKIP: conf-sanity test_114 skipping SLOW test 114 [12199.995947] Lustre: DEBUG MARKER: SKIP: conf-sanity test_115 skipping excluded test 115 [12202.195821] Lustre: DEBUG MARKER: == conf-sanity test 116: big size MDT support ============ 09:07:27 (1787404047) [12443.775212] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12449.724868] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12476.523958] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12526.516768] Lustre: DEBUG MARKER: == conf-sanity test 117: lctl get_param return errors properly ========================================================== 09:12:51 (1787404371) [12562.692291] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12569.207745] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12590.826560] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12591.517746] Lustre: Mounted lustre-client [12601.498096] Lustre: Unmounted lustre-client [12630.479749] Key type lgssc unregistered [12630.740134] LNet: 199682:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12630.751034] LNetError: 199682:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [12630.789619] LNet: Removed LNI 192.168.201.42@tcp [12631.734462] Key type .llcrypt unregistered [12631.737951] Key type ._llcrypt unregistered [12650.781849] Key type ._llcrypt registered [12650.787727] Key type .llcrypt registered [12652.298146] Lustre: DEBUG MARKER: == conf-sanity test 119: writeconf on slave mdt shouldn't duplicate mdc/osp and crash ========================================================== 09:14:57 (1787404497) [12657.562815] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12657.645975] alg: No test for adler32 (adler32-zlib) [12658.928599] Lustre: Lustre: Build Version: 2.17.54_110_g2f98931 [12659.247260] LNet: Added LNI 192.168.201.42@tcp [8/256/0/180] [12661.007645] Key type lgssc registered [12662.241804] Lustre: Echo OBD driver; http://www.lustre.org/ [12727.411387] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12734.474200] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12760.630739] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12766.814033] Lustre: Mounted lustre-client [12771.828569] Lustre: lustre-MDT0001-mdc-ffff8c5220ce1800: Connection to lustre-MDT0001 (at 192.168.201.142@tcp) was lost; in progress operations using this service will wait for recovery to complete [12795.969659] LustreError: 203119:0:(obd_config.c:2103:class_config_llog_handler()) MGC192.168.201.142@tcp: cfg command failed: rc = -17 [12795.977654] Lustre: cmd=cf001 0:lustre-MDT0001-mdc 1:mdc 2:lustre-clilmv_UUID [12795.977654] [12795.983807] LustreError: 202770:0:(mgc_request.c:614:do_requeue()) failed processing log: -17 [12807.679470] Lustre: lustre-MDT0001-mdc-ffff8c5220ce1800: Connection to lustre-MDT0001 (at 192.168.201.142@tcp) was lost; in progress operations using this service will wait for recovery to complete [12828.247546] LustreError: 203363:0:(obd_config.c:761:class_setup()) mdc: device 4 already setup: rc = -17 [12828.256140] LustreError: 203363:0:(obd_config.c:866:class_detach()) OBD device 4 still set up [12828.261060] LustreError: 203363:0:(obd_config.c:2103:class_config_llog_handler()) MGC192.168.201.142@tcp: cfg command failed: rc = -17 [12828.275094] Lustre: cmd=cf003 0:lustre-MDT0001-mdc 1:lustre-MDT0001_UUID 2:192.168.201.142@tcp [12828.275094] [12828.290194] LustreError: 202770:0:(mgc_request.c:614:do_requeue()) failed processing log: -17 [13135.346932] Lustre: lustre-MDT0001-mdc-ffff8c5220ce1800: Connection to lustre-MDT0001 (at 192.168.201.142@tcp) was lost; in progress operations using this service will wait for recovery to complete [13161.527988] LustreError: 204883:0:(obd_config.c:2103:class_config_llog_handler()) MGC192.168.201.142@tcp: cfg command failed: rc = -17 [13161.545261] Lustre: cmd=cf014 0:lustre-clilmv 1:lustre-MDT0001_UUID 2:1 3:1 4:lustre-MDT0001-mdc_UUID [13161.545261] [13161.573129] LustreError: 202770:0:(mgc_request.c:614:do_requeue()) failed processing log: -17 [13464.834816] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [13475.203628] Lustre: Unmounted lustre-client [13572.210726] Lustre: DEBUG MARKER: == conf-sanity test 120: cross-target rename should not create bad symlinks ========================================================== 09:30:17 (1787405417) [13615.423677] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [13620.728622] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [13646.048579] Lustre: DEBUG MARKER: oleg142-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [13646.654372] Lustre: Mounted lustre-client [13651.689100] Lustre: Unmounted lustre-client [13744.922187] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 13408 sec ========= 09:33:10 (1787405590) [13746.790394] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 09:33:12 (1787405592) === [13750.432855] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 09:33:16 (1787405596) === [13791.064845] Key type lgssc unregistered [13791.417770] LNet: 214143:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13791.445788] LNetError: 214143:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [13791.499791] LNet: Removed LNI 192.168.201.42@tcp [13792.912110] Key type .llcrypt unregistered [13792.915606] Key type ._llcrypt unregistered