[ 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 530594065 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2464 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE2300 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 0022C0 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2374 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE2404 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE243C 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe2300-0xbffe2373] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22ff] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2374-0xbffe2403] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe2404-0xbffe243b] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe243c-0xbffe2463] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524584K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001014] APIC: Switch to symmetric I/O mode setup [ 0.002673] x2apic enabled [ 0.003009] Switched APIC routing to physical x2apic. [ 0.004013] kvm-guest: setup PV IPIs [ 0.007726] ..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.008025] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009018] pid_max: default: 32768 minimum: 301 [ 0.010158] LSM: Security Framework initializing [ 0.012005] Yama: becoming mindful. [ 0.013038] SELinux: Initializing. [ 0.014076] *** VALIDATE selinux *** [ 0.023398] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.028162] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.030119] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.031098] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.032143] *** VALIDATE tmpfs *** [ 0.034021] *** VALIDATE proc *** [ 0.035312] *** VALIDATE cgroup *** [ 0.036010] *** VALIDATE cgroup2 *** [ 0.038218] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.039142] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.040005] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.041034] Spectre V2 : User space: Vulnerable [ 0.042021] Speculative Store Bypass: Vulnerable [ 0.045846] debug: unmapping init [mem 0xffffffff8ae59000-0xffffffff8ae60fff] [ 0.048918] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.049887] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.050024] ... version: 2 [ 0.051012] ... bit width: 48 [ 0.052009] ... generic registers: 4 [ 0.053008] ... value mask: 0000ffffffffffff [ 0.054060] ... max period: 00007fffffffffff [ 0.055012] ... fixed-purpose events: 3 [ 0.056010] ... event mask: 000000070000000f [ 0.058318] rcu: Hierarchical SRCU implementation. [ 0.060758] smp: Bringing up secondary CPUs ... [ 0.061630] x86: Booting SMP configuration: [ 0.062022] .... node #0, CPUs: #1 #2 #3 [ 0.066760] smp: Brought up 1 node, 4 CPUs [ 0.068014] smpboot: Max logical packages: 1 [ 0.069014] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.219000] node 0 deferred pages initialised in 146ms [ 0.221227] devtmpfs: initialized [ 0.222225] x86/mm: Memory block size: 128MB [ 0.225247] gcov: version magic: 0x41383552 [ 0.227490] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.228112] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.229392] pinctrl core: initialized pinctrl subsystem [ 0.230278] [ 0.230818] ************************************************************* [ 0.231013] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.232008] ** ** [ 0.233008] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.234009] ** ** [ 0.235010] ** This means that this kernel is built to expose internal ** [ 0.236008] ** IOMMU data structures, which may compromise security on ** [ 0.237009] ** your system. ** [ 0.238009] ** ** [ 0.239011] ** If you see this message and you are not debugging the ** [ 0.240010] ** kernel, report this immediately to your vendor! ** [ 0.241012] ** ** [ 0.242010] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.243011] ************************************************************* [ 0.244771] NET: Registered protocol family 16 [ 0.245720] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.246052] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.247052] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.248750] cpuidle: using governor menu [ 0.250021] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.251384] PCI: Using configuration type 1 for base access [ 0.252117] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.260059] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.261014] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.263052] cryptd: max_cpu_qlen set to 1000 [ 0.264241] ACPI: Added _OSI(Module Device) [ 0.265010] ACPI: Added _OSI(Processor Device) [ 0.266009] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.267010] ACPI: Added _OSI(Processor Aggregator Device) [ 0.271945] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.275306] ACPI: Interpreter enabled [ 0.276078] ACPI: PM: (supports S0 S3 S4 S5) [ 0.277009] ACPI: Using IOAPIC for interrupt routing [ 0.278085] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.279490] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.291779] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.292037] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.293017] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.294075] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.296379] acpiphp: Slot [2] registered [ 0.297091] acpiphp: Slot [5] registered [ 0.298086] acpiphp: Slot [6] registered [ 0.299069] acpiphp: Slot [3] registered [ 0.300061] acpiphp: Slot [4] registered [ 0.301090] acpiphp: Slot [7] registered [ 0.302059] acpiphp: Slot [8] registered [ 0.303079] acpiphp: Slot [9] registered [ 0.304072] acpiphp: Slot [10] registered [ 0.305062] acpiphp: Slot [11] registered [ 0.307071] acpiphp: Slot [12] registered [ 0.308077] acpiphp: Slot [13] registered [ 0.310097] acpiphp: Slot [14] registered [ 0.312077] acpiphp: Slot [15] registered [ 0.313122] acpiphp: Slot [16] registered [ 0.315080] acpiphp: Slot [17] registered [ 0.316130] acpiphp: Slot [18] registered [ 0.318067] acpiphp: Slot [19] registered [ 0.319068] acpiphp: Slot [20] registered [ 0.321219] acpiphp: Slot [21] registered [ 0.322109] acpiphp: Slot [22] registered [ 0.324062] acpiphp: Slot [23] registered [ 0.326080] acpiphp: Slot [24] registered [ 0.327068] acpiphp: Slot [25] registered [ 0.329065] acpiphp: Slot [26] registered [ 0.331198] acpiphp: Slot [27] registered [ 0.333088] acpiphp: Slot [28] registered [ 0.334173] acpiphp: Slot [29] registered [ 0.336066] acpiphp: Slot [30] registered [ 0.338138] acpiphp: Slot [31] registered [ 0.340074] PCI host bridge to bus 0000:00 [ 0.341046] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.343013] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.345012] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.347055] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.350015] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.354043] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.356264] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.358908] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.362041] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.368051] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.371018] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.373008] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.376017] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.379013] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.382805] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.385916] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.388035] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.390632] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.395009] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.404684] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.408013] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.419581] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.424011] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.430024] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.454959] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.463695] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.471013] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.476015] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.489017] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.498318] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.501327] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.503370] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.505311] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.507169] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.512171] iommu: Default domain type: Passthrough [ 0.516658] SCSI subsystem initialized [ 0.517128] ACPI: bus type USB registered [ 0.518193] usbcore: registered new interface driver usbfs [ 0.519179] usbcore: registered new interface driver hub [ 0.520080] usbcore: registered new device driver usb [ 0.522273] pps_core: LinuxPPS API ver. 1 registered [ 0.523008] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.526057] PTP clock support registered [ 0.528020] EDAC MC: Ver: 3.0.0 [ 0.529394] PCI: Using ACPI for IRQ routing [ 0.531968] NetLabel: Initializing [ 0.533013] NetLabel: domain hash size = 128 [ 0.535010] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.537090] NetLabel: unlabeled traffic allowed by default [ 0.539432] vgaarb: loaded [ 0.541060] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.543010] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.548300] clocksource: Switched to clocksource kvm-clock [ 0.678869] VFS: Disk quotas dquot_6.6.0 [ 0.680311] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.682608] *** VALIDATE ramfs *** [ 0.683700] *** VALIDATE hugetlbfs *** [ 0.685266] pnp: PnP ACPI init [ 0.687646] pnp: PnP ACPI: found 6 devices [ 0.707164] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.710957] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.713666] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.716238] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.718715] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.721263] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.724456] NET: Registered protocol family 2 [ 0.727127] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.732619] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.736623] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.740863] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.745033] TCP: Hash tables configured (established 65536 bind 65536) [ 0.747888] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.750390] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.752752] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.756157] NET: Registered protocol family 1 [ 0.758523] RPC: Registered named UNIX socket transport module. [ 0.760552] RPC: Registered udp transport module. [ 0.762221] RPC: Registered tcp transport module. [ 0.764119] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.766186] NET: Registered protocol family 44 [ 0.767357] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.768896] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.770768] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.773325] PCI: CLS 0 bytes, default 64 [ 0.775090] Unpacking initramfs... [ 2.398193] debug: unmapping init [mem 0xffffa0f1bcc64000-0xffffa0f1bffcffff] [ 2.402912] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.405982] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.409497] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 3.111884] Initialise system trusted keyrings [ 3.113121] Key type blacklist registered [ 3.115712] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 3.127029] zbud: loaded [ 3.131437] *** VALIDATE nfs *** [ 3.132562] *** VALIDATE nfs4 *** [ 3.134809] pstore: using deflate compression [ 3.142731] Platform Keyring initialized [ 3.258692] NET: Registered protocol family 38 [ 3.260351] Key type asymmetric registered [ 3.262590] Asymmetric key parser 'x509' registered [ 3.264593] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 3.267896] io scheduler mq-deadline registered [ 3.269496] io scheduler kyber registered [ 3.271374] io scheduler bfq registered [ 3.273423] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 3.276691] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 3.280037] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 3.283231] ACPI: Power Button [PWRF] [ 3.289823] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.297492] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.319868] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.350838] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.385239] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.390812] Non-volatile memory driver v1.3 [ 3.392417] Linux agpgart interface v0.103 [ 3.424454] virtio_blk virtio1: [vda] 145800 512-byte logical blocks (74.6 MB/71.2 MiB) [ 3.428276] vda: detected capacity change from 0 to 74649600 [ 3.464511] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.468623] vdb: detected capacity change from 0 to 1073741824 [ 3.478626] libphy: Fixed MDIO Bus: probed [ 3.484865] usbcore: registered new interface driver usbserial_generic [ 3.487797] usbserial: USB Serial support registered for generic [ 3.489879] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.494111] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.496117] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.498199] mousedev: PS/2 mouse device common for all mice [ 3.502507] rtc_cmos 00:05: RTC can wake from S4 [ 3.506422] rtc_cmos 00:05: registered as rtc0 [ 3.506915] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.508528] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.508568] intel_pstate: CPU model not supported [ 3.512979] hid: raw HID events driver (C) Jiri Kosina [ 3.520940] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.524297] usbcore: registered new interface driver usbhid [ 3.524303] usbhid: USB HID core driver [ 3.524407] drop_monitor: Initializing network drop monitor service [ 3.524538] Initializing XFRM netlink socket [ 3.526030] NET: Registered protocol family 10 [ 3.531458] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.534374] Segment Routing with IPv6 [ 3.545577] NET: Registered protocol family 17 [ 3.547904] mpls_gso: MPLS GSO support [ 3.555959] RAS: Correctable Errors collector initialized. [ 3.558142] AVX version of gcm_enc/dec engaged. [ 3.559808] AES CTR mode by8 optimization enabled [ 3.642670] sched_clock: Marking stable (3642592434, 0)->(4825746828, -1183154394) [ 3.646272] registered taskstats version 1 [ 3.648268] Loading compiled-in X.509 certificates [ 3.650397] zswap: loaded using pool lzo/zbud [ 3.674665] Key type big_key registered [ 3.687190] Key type encrypted registered [ 3.688451] ima: No TPM chip found, activating TPM-bypass! [ 3.690137] ima: Allocated hash algorithm: sha1 [ 3.691680] ima: No architecture policies found [ 3.693392] evm: Initialising EVM extended attributes: [ 3.695074] evm: security.selinux [ 3.696124] evm: security.ima [ 3.697225] evm: security.capability [ 3.698531] evm: HMAC attrs: 0x1 [ 3.701182] rtc_cmos 00:05: setting system clock to 2026-07-17 11:37:46 UTC (1784288266) [ 3.707047] debug: unmapping init [mem 0xffffffff8be03000-0xffffffff8bffffff] [ 3.710073] debug: unmapping init [mem 0xffffffff8ab82000-0xffffffff8ae58fff] [ 3.719061] Write protecting the kernel read-only data: 28672k [ 3.722317] debug: unmapping init [mem 0xffffffff89203000-0xffffffff893fffff] [ 3.725278] debug: unmapping init [mem 0xffffffff89b14000-0xffffffff89bfffff] [ 3.756707] 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.765328] systemd[1]: Detected virtualization kvm. [ 3.767227] systemd[1]: Detected architecture x86-64. [ 3.769236] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.807644] systemd[1]: No hostname configured. [ 3.810156] systemd[1]: Set hostname to . [ 3.812290] random: systemd: uninitialized urandom read (16 bytes read) [ 3.814474] systemd[1]: Initializing machine ID from random generator. [ 4.085876] random: systemd: uninitialized urandom read (16 bytes read) [ 4.089492] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. [ 4.104462] random: systemd: uninitialized urandom read (16 bytes read) [ 4.108754] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ 4.117651] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. Starting Journal Service... Starting Setup Virtual Console... [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Timers. Starting Apply Kernel Variables... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Paths. Starting Create Volatile Files and Directories... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Sockets. [ OK ] Reached target Swap. [ OK ] Reached target Initrd Root Device. [ OK ] Started Memstrack Anylazing Service. [ OK ] Reached target Slices. [ OK ] Started Journal Service. [ OK ] Started Setup Virtual Console. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create Volatile Files and Directories. [ 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 dracut cmdline hook. Starting dracut pre-udev hook... [ 5.486459] device-mapper: uevent: version 1.0.3 [ 5.488745] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting dracut initqueue hook... [ 6.502209] virtio_net virtio0 ens2: renamed from eth0 [ 6.533079] random: fast init done [ 6.676919] scsi host0: ata_piix [ 6.716889] scsi host1: ata_piix [ 6.721456] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 6.730382] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 11.212220] random: crng init done [ 11.213620] random: 7 urandom warning(s) missed due to ratelimiting [ 13.268352] dracut-initqueue[592]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 14.411774] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Timers. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Slices. [ OK ] Stopped target Sockets. [ OK ] Stopped target Paths. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Swap. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped Apply Kernel Variables. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 16.531714] printk: systemd: 17 output lines suppressed due to ratelimiting [ 17.069678] SELinux: Disabled at runtime. [ 17.137947] 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) [ 17.152312] systemd[1]: Detected virtualization kvm. [ 17.154722] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 18.021355] systemd[1]: initrd-switch-root.service: Succeeded. [ 18.026865] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 18.050152] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 18.053831] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 18.057325] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 18.064229] systemd[1]: Starting Journal Service... Starting Journal Service... [ 18.075668] systemd[1]: Created slice system-sshd\x2dkeygen.slice. [ OK ] Created slice system-sshd\x2dkeygen.slice. Mounting Kernel Debug File System... [ OK ] Created slice User and Session Slice. [ OK ] Listening on udev Control Socket. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target rpc_pipefs.target. Mounting Huge Pages File System... [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on initctl Compatibility Named Pipe. Activating swap /dev/disk/by-label/SWAP... [ OK ] Listening on Process Core Dump Socket. [ 18.226329] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS Mounting POSIX Message Queue File System... [ OK ] Listening on RPCbind Server Activation Socket. Starting Remount Root and Kernel File Systems... [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Slices. [ OK ] Created slice system-getty.slice. Starting udev Coldplug all Devices... [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Reached target RPC Port Mapper. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. [ OK ] Stopped target Initrd Root File System. [ OK ] Created slice system-serial\x2dgetty.slice. Starting Apply Kernel Variables... [ OK ] Started Journal Service. [ OK ] Mounted Kernel Debug File System. [ OK ] Mounted Huge Pages File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted POSIX Message Queue File System. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. Starting Create Static Device Nodes in /dev... Starting Configure read-only root support... [ OK ] Reached target Swap. Starting Flush Journal to Persistent Storage... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... Starting udev Kernel Device Manager... [ OK ] Mounted /mnt. [ OK ] Started Flush Journal to Persistent Storage. [ 19.322916] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 20.308605] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 20.528405] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 21.767464] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 21.903660] EDAC sbridge: Ver: 1.1.2 [ 24.130170] Key type dns_resolver registered [ 24.576510] NFS: Registering the id_resolver key type [ 24.578784] Key type id_resolver registered [ 24.581701] Key type id_legacy registered [* ] A start job is running for Configur…-only root support (7s / 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 Load/Save Random Seed. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Basic System. [ OK ] Started irqbalance daemon. Starting Restore /run/initramfs on shutdown... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... Starting Login Service... [ OK ] Reached target sshd-keygen.target. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting Dynamic System Tuning Daemon... Starting GSSAPI Proxy Daemon... Starting OpenSSH server 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... Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ OK ] Started Permit User Sessions. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Serial Getty on ttyS0. [ 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 System Logging Service... Starting Notify NFS peers of a restart... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. [ OK ] Started Authorization Manager. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg433-client login: [ 49.854990] libcfs: loading out-of-tree module taints kernel. [ 49.898577] Key type ._llcrypt registered [ 49.899532] Key type .llcrypt registered [ 50.096190] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 50.100959] alg: No test for adler32 (adler32-zlib) [ 51.050541] Lustre: Lustre: Build Version: 2.17.54_173_g0a24909 [ 51.307686] LNet: Added LNI 192.168.204.33@tcp [8/256/0/180] [ 52.912136] Key type lgssc registered [ 53.473793] Lustre: Echo OBD driver; http://www.lustre.org/ [ 109.918727] Lustre: Mounted lustre-client [ 112.151154] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 117.807411] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing check_logdir /tmp/testlogs/ [ 119.346977] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing yml_node [ 120.880920] Lustre: DEBUG MARKER: Client: 2.17.54.173 [ 121.731467] Lustre: DEBUG MARKER: MDS: 2.17.54.173 [ 122.587631] Lustre: DEBUG MARKER: OSS: 2.17.54.173 [ 123.153340] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Fri Jul 17 07:39:45 EDT 2026 [ 128.485682] Lustre: DEBUG MARKER: excepting tests: 102 106 115 32newtarball [ 128.958614] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 129.415095] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 139.702153] Lustre: Unmounted lustre-client [ 196.163806] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 197.927804] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 205.833482] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 234.675421] Lustre: DEBUG MARKER: == conf-sanity test 76a: set permanent params with lctl across mounts ========================================================== 07:41:36 (1784288496) [ 246.142051] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 248.157790] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 255.745803] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 266.233576] Lustre: Mounted lustre-client [ 270.614777] Lustre: Unmounted lustre-client [ 322.566785] Lustre: Mounted lustre-client [ 324.972282] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 329.861136] Lustre: Unmounted lustre-client [ 400.892992] Lustre: Mounted lustre-client [ 403.627559] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 406.030926] Lustre: Unmounted lustre-client [ 441.209438] Lustre: DEBUG MARKER: == conf-sanity test 76b: verify params log setup correctly ========================================================== 07:45:03 (1784288703) [ 472.808564] Lustre: Mounted lustre-client [ 475.338561] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 477.368831] Lustre: Unmounted lustre-client [ 510.882261] Lustre: DEBUG MARKER: == conf-sanity test 76c: verify changelog_mask is applied with lctl set_param -P ========================================================== 07:46:13 (1784288773) [ 537.275770] Lustre: Mounted lustre-client [ 539.713356] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 542.690587] Lustre: lustre-MDT0000-mdc-ffffa0f202ee2800: Connection to lustre-MDT0000 (at 192.168.204.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 547.811189] Lustre: lustre-MDT0001-mdc-ffffa0f202ee2800: Connection to lustre-MDT0001 (at 192.168.204.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 558.051634] LustreError: MGC192.168.204.133@tcp: Connection to MGS (at 192.168.204.133@tcp) was lost; in progress operations using this service will fail [ 558.058659] Lustre: Evicted from MGS (at 192.168.204.133@tcp) after server handle changed from 0xb7829d1bafbfc4eb to 0xb7829d1bafbfc71b [ 558.062424] Lustre: MGC192.168.204.133@tcp: Connection restored to 192.168.204.133@tcp (at 192.168.204.133@tcp) [ 563.168407] Lustre: lustre-OST0000-osc-ffffa0f202ee2800: disconnect after 24s idle [ 564.524984] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 572.388612] LustreError: lustre-MDT0000-mdc-ffffa0f202ee2800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 572.395317] Lustre: lustre-MDT0000-mdc-ffffa0f202ee2800: Connection restored to 192.168.204.133@tcp (at 192.168.204.133@tcp) [ 573.258515] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 8 sec [ 575.175296] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 575.698797] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 576.319458] Lustre: Unmounted lustre-client [ 611.009596] Lustre: DEBUG MARKER: == conf-sanity test 76d: verify llite.*.xattr_cache can be set by 'lctl set_param -P' correctly ========================================================== 07:47:53 (1784288873) [ 635.068519] Lustre: Mounted lustre-client [ 637.264233] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 650.119975] Lustre: Unmounted lustre-client [ 685.553090] Lustre: DEBUG MARKER: == conf-sanity test 77: comma-separated MGS NIDs and failover node NIDs ========================================================== 07:49:07 (1784288947) [ 686.348762] Lustre: DEBUG MARKER: SKIP: conf-sanity test_77 mixed loopback and real device not working [ 686.868901] Lustre: DEBUG MARKER: == conf-sanity test 78: run resize2fs on MDT and OST filesystems ========================================================== 07:49:09 (1784288949) [ 704.481252] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 712.093246] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 712.244261] Lustre: Mounted lustre-client [ 712.245164] Lustre: Skipped 2 previous similar messages [ 712.714312] Lustre: DEBUG MARKER: create test files [ 721.412188] Lustre: Unmounted lustre-client [ 721.413248] Lustre: Skipped 2 previous similar messages [ 730.944741] Key type lgssc unregistered [ 731.059676] LNet: 33748:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 731.062679] LNetError: 33748:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 731.069693] LNet: Removed LNI 192.168.204.33@tcp [ 731.319099] Key type .llcrypt unregistered [ 731.320455] Key type ._llcrypt unregistered [ 738.390718] Key type ._llcrypt registered [ 738.395270] Key type .llcrypt registered [ 738.560068] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 738.564499] alg: No test for adler32 (adler32-zlib) [ 739.405312] Lustre: Lustre: Build Version: 2.17.54_173_g0a24909 [ 739.482164] LNet: Added LNI 192.168.204.33@tcp [8/256/0/180] [ 741.056147] Key type lgssc registered [ 741.412147] Lustre: Echo OBD driver; http://www.lustre.org/ [ 753.474352] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 759.447175] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 765.912894] Lustre: Mounted lustre-client [ 767.694618] Lustre: DEBUG MARKER: check files after expanding the MDT and OST filesystems [ 769.192395] Lustre: DEBUG MARKER: create more files after expanding the MDT and OST filesystems [ 770.347458] Lustre: Unmounted lustre-client [ 779.566204] Key type lgssc unregistered [ 779.671417] LNet: 36614:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 779.673494] LNetError: 36614:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 779.681559] LNet: Removed LNI 192.168.204.33@tcp [ 779.901099] Key type .llcrypt unregistered [ 779.902161] Key type ._llcrypt unregistered [ 788.427223] Key type ._llcrypt registered [ 788.430892] Key type .llcrypt registered [ 788.612813] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 788.628836] alg: No test for adler32 (adler32-zlib) [ 789.492696] Lustre: Lustre: Build Version: 2.17.54_173_g0a24909 [ 789.580375] LNet: Added LNI 192.168.204.33@tcp [8/256/0/180] [ 791.168154] Key type lgssc registered [ 791.563463] Lustre: Echo OBD driver; http://www.lustre.org/ [ 804.372276] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 810.449099] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 817.113086] Lustre: Mounted lustre-client [ 818.875256] Lustre: DEBUG MARKER: check files after shrinking the MDT and OST filesystems [ 820.369104] Lustre: Unmounted lustre-client [ 829.940909] Key type lgssc unregistered [ 830.047427] LNet: 39362:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 830.050605] LNetError: 39362:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 830.058746] LNet: Removed LNI 192.168.204.33@tcp [ 830.302802] Key type .llcrypt unregistered [ 830.303980] Key type ._llcrypt unregistered [ 838.970860] Key type ._llcrypt registered [ 838.971769] Key type .llcrypt registered [ 839.113358] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 839.119184] alg: No test for adler32 (adler32-zlib) [ 839.969229] Lustre: Lustre: Build Version: 2.17.54_173_g0a24909 [ 840.052658] LNet: Added LNI 192.168.204.33@tcp [8/256/0/180] [ 841.632158] Key type lgssc registered [ 842.003367] Lustre: Echo OBD driver; http://www.lustre.org/ [ 869.381067] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 871.047889] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 878.712800] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 914.218352] Lustre: DEBUG MARKER: == conf-sanity test 79: format MDT/OST without mgs option (should return errors) ========================================================== 07:52:56 (1784289176) [ 953.336259] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 955.003081] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 962.414898] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 983.254082] Lustre: DEBUG MARKER: == conf-sanity test 80: mgc import reconnect race ======== 07:54:05 (1784289245) [ 993.436611] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 995.141404] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1001.252500] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1040.999301] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1069.285149] Lustre: DEBUG MARKER: == conf-sanity test 81: sparse OST indexing ============== 07:55:31 (1784289331) [ 1069.782914] Lustre: DEBUG MARKER: SKIP: conf-sanity test_81 needs >= 3 OSTs [ 1070.395593] Lustre: DEBUG MARKER: == conf-sanity test 82a: specify OSTs for file (succeed) or directory (succeed) ========================================================== 07:55:32 (1784289332) [ 1070.938332] Lustre: DEBUG MARKER: SKIP: conf-sanity test_82a needs >= 3 OSTs [ 1071.532064] Lustre: DEBUG MARKER: == conf-sanity test 82b: specify OSTs for file with --pool and --ost-list options ========================================================== 07:55:33 (1784289333) [ 1072.132468] Lustre: DEBUG MARKER: SKIP: conf-sanity test_82b needs >= 4 OSTs [ 1072.719759] Lustre: DEBUG MARKER: == conf-sanity test 82c: specify sparse OSTs for setstripe (should not crash) ========================================================== 07:55:35 (1784289335) [ 1073.233536] Lustre: DEBUG MARKER: SKIP: conf-sanity test_82c needs >= 4 OSTs [ 1073.797943] Lustre: DEBUG MARKER: == conf-sanity test 83: ENOSPACE on OST doesn't cause message VFS: Busy inodes after unmount ... ========================================================== 07:55:36 (1784289336) [ 1074.336526] Lustre: DEBUG MARKER: mount the OST /dev/mapper/ost1_flakey as a ldiskfs filesystem [ 1076.850164] Lustre: DEBUG MARKER: run llverfs in partial mode on the OST ldiskfs /mnt/lustre-ost1 [ 1079.213701] Lustre: DEBUG MARKER: unmount the OST /dev/mapper/ost1_flakey [ 1120.193365] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1122.098172] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1130.191744] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1151.368207] Lustre: DEBUG MARKER: == conf-sanity test 84: check recovery_hard_time ========= 07:56:53 (1784289413) [ 1161.836760] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1163.726916] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1170.719365] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1190.276662] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1194.455693] Lustre: Mounted lustre-client [ 1203.631111] Lustre: DEBUG MARKER: local REPLAY BARRIER on lustre-MDT0000 [ 1210.340060] Lustre: lustre-MDT0000-mdc-ffffa0f220761800: Connection to lustre-MDT0000 (at 192.168.204.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1225.702508] LustreError: MGC192.168.204.133@tcp: Connection to MGS (at 192.168.204.133@tcp) was lost; in progress operations using this service will fail [ 1225.709403] Lustre: Evicted from MGS (at 192.168.204.133@tcp) after server handle changed from 0xe3a46d4ca8ed84a9 to 0xe3a46d4ca8ee4687 [ 1225.713521] Lustre: MGC192.168.204.133@tcp: Connection restored to 192.168.204.133@tcp (at 192.168.204.133@tcp) [ 1228.905991] LustreError: 39972:0:(client.c:3448:ptlrpc_replay_interpret()) @@@ status 301, old was 0 req@00000000d41256bf x1870962729428736/t8589934597(8589934597) o101->lustre-MDT0000-mdc-ffffa0f220761800@192.168.204.133@tcp:12/10 lens 592/608 e 0 to 0 dl 1784289507 ref 2 fl Interpret:RQU/604/0 rc 301/301 job:'' uid:0 gid:0 projid:0 [ 1229.535495] LustreError: 39972:0:(client.c:3448:ptlrpc_replay_interpret()) @@@ status 301, old was 0 req@00000000566ff692 x1870962729429376/t8589934599(8589934599) o101->lustre-MDT0000-mdc-ffffa0f220761800@192.168.204.133@tcp:12/10 lens 592/608 e 0 to 0 dl 1784289508 ref 2 fl Interpret:RQU/604/0 rc 301/301 job:'' uid:0 gid:0 projid:0 [ 1230.807295] LustreError: 39972:0:(client.c:3448:ptlrpc_replay_interpret()) @@@ status 301, old was 0 req@00000000b39c98f2 x1870962729430656/t8589934603(8589934603) o101->lustre-MDT0000-mdc-ffffa0f220761800@192.168.204.133@tcp:12/10 lens 592/608 e 0 to 0 dl 1784289509 ref 2 fl Interpret:RQU/604/0 rc 301/301 job:'' uid:0 gid:0 projid:0 [ 1230.813499] LustreError: 39972:0:(client.c:3448:ptlrpc_replay_interpret()) Skipped 1 previous similar message [ 1233.339419] LustreError: 39972:0:(client.c:3448:ptlrpc_replay_interpret()) @@@ status 301, old was 0 req@000000007edc780a x1870962729433216/t8589934611(8589934611) o101->lustre-MDT0000-mdc-ffffa0f220761800@192.168.204.133@tcp:12/10 lens 592/608 e 0 to 0 dl 1784289511 ref 2 fl Interpret:RQU/604/0 rc 301/301 job:'' uid:0 gid:0 projid:0 [ 1233.351573] LustreError: 39972:0:(client.c:3448:ptlrpc_replay_interpret()) Skipped 3 previous similar messages [ 1237.735411] LustreError: 39972:0:(client.c:3448:ptlrpc_replay_interpret()) @@@ status 301, old was 0 req@00000000fa1899ce x1870962729437696/t8589934625(8589934625) o101->lustre-MDT0000-mdc-ffffa0f220761800@192.168.204.133@tcp:12/10 lens 592/608 e 0 to 0 dl 1784289516 ref 2 fl Interpret:RQU/604/0 rc 301/301 job:'' uid:0 gid:0 projid:0 [ 1237.741620] LustreError: 39972:0:(client.c:3448:ptlrpc_replay_interpret()) Skipped 6 previous similar messages [ 1245.870709] LustreError: 39972:0:(client.c:3448:ptlrpc_replay_interpret()) @@@ status 301, old was 0 req@00000000991e9f1a x1870962729446016/t8589934651(8589934651) o101->lustre-MDT0000-mdc-ffffa0f220761800@192.168.204.133@tcp:12/10 lens 592/608 e 0 to 0 dl 1784289524 ref 2 fl Interpret:RQU/604/0 rc 301/301 job:'' uid:0 gid:0 projid:0 [ 1245.877419] LustreError: 39972:0:(client.c:3448:ptlrpc_replay_interpret()) Skipped 12 previous similar messages [ 1262.126962] LustreError: 39972:0:(client.c:3448:ptlrpc_replay_interpret()) @@@ status 301, old was 0 req@0000000059008895 x1870962729462656/t8589934703(8589934703) o101->lustre-MDT0000-mdc-ffffa0f220761800@192.168.204.133@tcp:12/10 lens 592/608 e 0 to 0 dl 1784289540 ref 2 fl Interpret:RQU/604/0 rc 301/301 job:'' uid:0 gid:0 projid:0 [ 1262.133908] LustreError: 39972:0:(client.c:3448:ptlrpc_replay_interpret()) Skipped 25 previous similar messages [ 1285.871451] LustreError: lustre-MDT0000-mdc-ffffa0f220761800: operation ldlm_enqueue to node 192.168.204.133@tcp failed: rc = -107 [ 1285.897271] Lustre: lustre-MDT0000-mdc-ffffa0f21107d800: Connection restored to 192.168.204.133@tcp (at 192.168.204.133@tcp) [ 1288.102214] LustreError: lustre-MDT0000-mdc-ffffa0f220761800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 1288.161310] LustreError: 61573:0:(file.c:6088:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -5 [ 1288.322193] Lustre: lustre-MDT0000-mdc-ffffa0f220761800: Connection restored to 192.168.204.133@tcp (at 192.168.204.133@tcp) [ 1290.128291] Lustre: Unmounted lustre-client [ 1324.680561] Lustre: DEBUG MARKER: == conf-sanity test 85: osd_ost init: fail ea_fid_set ==== 07:59:46 (1784289586) [ 1377.575634] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1380.850345] Lustre: DEBUG MARKER: == conf-sanity test 86: Replacing mkfs.lustre -G option == 08:00:43 (1784289643) [ 1399.774331] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1434.590651] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1436.179277] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1443.634865] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1473.142834] Lustre: DEBUG MARKER: == conf-sanity test 87: check if MDT inode can hold EAs with N stripes properly ========================================================== 08:02:15 (1784289735) [ 1474.158372] Key type lgssc unregistered [ 1474.274522] LNet: 69011:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1474.276715] LNetError: 69011:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1474.287854] LNet: Removed LNI 192.168.204.33@tcp [ 1474.537316] Key type .llcrypt unregistered [ 1474.538397] Key type ._llcrypt unregistered [ 1483.641732] Key type ._llcrypt registered [ 1483.643197] Key type .llcrypt registered [ 1483.838078] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1483.844387] alg: No test for adler32 (adler32-zlib) [ 1484.698084] Lustre: Lustre: Build Version: 2.17.54_173_g0a24909 [ 1484.791336] LNet: Added LNI 192.168.204.33@tcp [8/256/0/180] [ 1486.376197] Key type lgssc registered [ 1486.830476] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1523.260411] Lustre: Mounted lustre-client [ 1523.559134] Lustre: Unmounted lustre-client [ 1537.810789] Lustre: DEBUG MARKER: == conf-sanity test 88a: tunefs.lustre persists a --mountfsoptions-only change ========================================================== 08:03:20 (1784289800) [ 1544.783374] Lustre: DEBUG MARKER: == conf-sanity test 88b: check the default mount options can be overridden ========================================================== 08:03:27 (1784289807) [ 1555.032433] Lustre: DEBUG MARKER: == conf-sanity test 89: check tunefs --param and --erase-param{s} options ========================================================== 08:03:37 (1784289817) [ 1592.934174] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL failover.node=192.0.2.254@tcp0,mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1594.610169] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL failover.node=192.0.2.254@tcp0,mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1602.220536] Lustre: DEBUG MARKER: oleg433-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 1475 0 [ 1631.669919] Lustre: DEBUG MARKER: == conf-sanity test 90a: check max_mod_rpcs_in_flight is enforced ========================================================== 08:04:53 (1784289893) [ 1642.252467] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1644.136293] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1650.914277] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1661.426995] Lustre: Mounted lustre-client [ 1679.840133] Lustre: 81439:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1784289926/real 1784289926] req@000000000284848f x1870963405778304/t0(0) o36->lustre-MDT0000-mdc-ffffa0f206c9f000@192.168.204.133@tcp:12/10 lens 488/512 e 0 to 1 dl 1784289942 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1679.840270] Lustre: lustre-MDT0000-mdc-ffffa0f206c9f000: Connection to lustre-MDT0000 (at 192.168.204.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1679.849149] Lustre: 81439:0:(client.c:2490:ptlrpc_expire_one_request()) Skipped 3 previous similar messages [ 1679.861577] Lustre: lustre-MDT0000-mdc-ffffa0f206c9f000: Connection restored to 192.168.204.133@tcp (at 192.168.204.133@tcp) [ 1696.736165] Lustre: 81477:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1784289943/real 1784289943] req@0000000034601c95 x1870963405787392/t0(0) o36->lustre-MDT0000-mdc-ffffa0f206c9f000@192.168.204.133@tcp:12/10 lens 488/512 e 0 to 1 dl 1784289959 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1696.736272] Lustre: lustre-MDT0000-mdc-ffffa0f206c9f000: Connection to lustre-MDT0000 (at 192.168.204.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1696.744414] Lustre: 81477:0:(client.c:2490:ptlrpc_expire_one_request()) Skipped 3 previous similar messages [ 1696.758674] Lustre: lustre-MDT0000-mdc-ffffa0f206c9f000: Connection restored to 192.168.204.133@tcp (at 192.168.204.133@tcp) [ 1697.078135] Lustre: Unmounted lustre-client [ 1714.385506] Key type lgssc unregistered [ 1714.504456] LNet: 81921:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1714.506719] LNetError: 81921:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1714.515865] LNet: Removed LNI 192.168.204.33@tcp [ 1714.757268] Key type .llcrypt unregistered [ 1714.758322] Key type ._llcrypt unregistered [ 1719.676739] Key type ._llcrypt registered [ 1719.677809] Key type .llcrypt registered [ 1720.090796] Lustre: DEBUG MARKER: == conf-sanity test 90b: check max_mod_rpcs_in_flight is enforced after update ========================================================== 08:06:22 (1784289982) [ 1720.846856] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1720.858321] alg: No test for adler32 (adler32-zlib) [ 1721.708489] Lustre: Lustre: Build Version: 2.17.54_173_g0a24909 [ 1721.790675] LNet: Added LNI 192.168.204.33@tcp [8/256/0/180] [ 1723.368140] Key type lgssc registered [ 1723.723866] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1740.930273] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1742.640503] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1748.984302] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1759.731374] Lustre: Mounted lustre-client [ 1780.704116] Lustre: 85112:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1784290027/real 1784290027] req@0000000019153829 x1870963654272000/t0(0) o36->lustre-MDT0000-mdc-ffffa0f205ddb000@192.168.204.133@tcp:12/10 lens 488/512 e 0 to 1 dl 1784290043 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1780.711113] Lustre: lustre-MDT0000-mdc-ffffa0f205ddb000: Connection to lustre-MDT0000 (at 192.168.204.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1780.719722] Lustre: lustre-MDT0000-mdc-ffffa0f205ddb000: Connection restored to 192.168.204.133@tcp (at 192.168.204.133@tcp) [ 1797.600115] Lustre: 85157:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1784290044/real 1784290044] req@00000000034b6056 x1870963654284928/t0(0) o36->lustre-MDT0001-mdc-ffffa0f205ddb000@192.168.204.133@tcp:12/10 lens 488/512 e 0 to 1 dl 1784290060 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1797.600270] Lustre: lustre-MDT0001-mdc-ffffa0f205ddb000: Connection to lustre-MDT0001 (at 192.168.204.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1797.607494] Lustre: 85157:0:(client.c:2490:ptlrpc_expire_one_request()) Skipped 3 previous similar messages [ 1797.617079] Lustre: lustre-MDT0001-mdc-ffffa0f205ddb000: Connection restored to 192.168.204.133@tcp (at 192.168.204.133@tcp) [ 1813.472126] Lustre: 85191:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1784290060/real 1784290060] req@000000006b63cdbe x1870963654291840/t0(0) o36->lustre-MDT0001-mdc-ffffa0f205ddb000@192.168.204.133@tcp:12/10 lens 488/512 e 0 to 1 dl 1784290076 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1813.472296] Lustre: lustre-MDT0001-mdc-ffffa0f205ddb000: Connection to lustre-MDT0001 (at 192.168.204.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1813.479622] Lustre: 85191:0:(client.c:2490:ptlrpc_expire_one_request()) Skipped 3 previous similar messages [ 1813.493393] Lustre: lustre-MDT0001-mdc-ffffa0f205ddb000: Connection restored to 192.168.204.133@tcp (at 192.168.204.133@tcp) [ 1814.355373] Lustre: Unmounted lustre-client [ 1814.812922] Lustre: Mounted lustre-client [ 1831.904148] Lustre: 85336:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1784290078/real 1784290078] req@000000006fc7bf04 x1870963654326272/t0(0) o36->lustre-MDT0000-mdc-ffffa0f21063d000@192.168.204.133@tcp:12/10 lens 488/512 e 0 to 1 dl 1784290094 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1831.904289] Lustre: lustre-MDT0000-mdc-ffffa0f21063d000: Connection to lustre-MDT0000 (at 192.168.204.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1831.911246] Lustre: 85336:0:(client.c:2490:ptlrpc_expire_one_request()) Skipped 3 previous similar messages [ 1831.925974] Lustre: lustre-MDT0000-mdc-ffffa0f21063d000: Connection restored to 192.168.204.133@tcp (at 192.168.204.133@tcp) [ 1848.800185] Lustre: 85404:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1784290095/real 1784290095] req@00000000ba1654ff x1870963654349824/t0(0) o36->lustre-MDT0000-mdc-ffffa0f21063d000@192.168.204.133@tcp:12/10 lens 488/512 e 0 to 1 dl 1784290111 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1848.800344] Lustre: lustre-MDT0000-mdc-ffffa0f21063d000: Connection to lustre-MDT0000 (at 192.168.204.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1848.810583] Lustre: 85404:0:(client.c:2490:ptlrpc_expire_one_request()) Skipped 2 previous similar messages [ 1848.820840] Lustre: lustre-MDT0000-mdc-ffffa0f21063d000: Connection restored to 192.168.204.133@tcp (at 192.168.204.133@tcp) [ 1865.367132] Lustre: Unmounted lustre-client [ 1882.978603] Key type lgssc unregistered [ 1883.100451] LNet: 85865:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1883.103659] LNetError: 85865:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1883.111485] LNet: Removed LNI 192.168.204.33@tcp [ 1883.360751] Key type .llcrypt unregistered [ 1883.361802] Key type ._llcrypt unregistered [ 1888.287044] Key type ._llcrypt registered [ 1888.288038] Key type .llcrypt registered [ 1888.726598] Lustre: DEBUG MARKER: == conf-sanity test 90c: check max_mod_rpcs_in_flight update limits ========================================================== 08:09:11 (1784290151) [ 1889.465099] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1889.480100] alg: No test for adler32 (adler32-zlib) [ 1890.358665] Lustre: Lustre: Build Version: 2.17.54_173_g0a24909 [ 1890.447142] LNet: Added LNI 192.168.204.33@tcp [8/256/0/180] [ 1892.040228] Key type lgssc registered [ 1892.415678] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1910.391657] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1912.231685] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1918.892647] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1929.201735] Lustre: Mounted lustre-client [ 1931.648070] Lustre: Unmounted lustre-client [ 1932.053790] Lustre: Mounted lustre-client [ 1932.109559] LustreError: 89125:0:(genops.c:2263:obd_set_max_mod_rpcs_in_flight()) lustre-MDT0000-mdc-ffffa0f210c5b800: 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. [ 1932.953103] Lustre: Unmounted lustre-client [ 1933.062875] Lustre: Mounted lustre-client [ 1934.354734] Lustre: Unmounted lustre-client [ 1959.840526] Key type lgssc unregistered [ 1959.956590] LNet: 89667:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1959.959278] LNetError: 89667:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1959.969532] LNet: Removed LNI 192.168.204.33@tcp [ 1960.197221] Key type .llcrypt unregistered [ 1960.198718] Key type ._llcrypt unregistered [ 1965.020022] Key type ._llcrypt registered [ 1965.021459] Key type .llcrypt registered [ 1965.441368] Lustre: DEBUG MARKER: == conf-sanity test 90d: check one close RPC is allowed above max_mod_rpcs_in_flight ========================================================== 08:10:27 (1784290227) [ 1966.195968] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1966.201828] alg: No test for adler32 (adler32-zlib) [ 1967.055341] Lustre: Lustre: Build Version: 2.17.54_173_g0a24909 [ 1967.147468] LNet: Added LNI 192.168.204.33@tcp [8/256/0/180] [ 1968.728132] Key type lgssc registered [ 1969.097984] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1986.270514] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1988.045954] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1994.364300] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2004.978810] Lustre: Mounted lustre-client [ 2022.368176] Lustre: 92849:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1784290269/real 1784290269] req@0000000064275bfe x1870963911179520/t0(0) o36->lustre-MDT0000-mdc-ffffa0f207536000@192.168.204.133@tcp:12/10 lens 488/512 e 0 to 1 dl 1784290285 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2022.368275] Lustre: lustre-MDT0000-mdc-ffffa0f207536000: Connection to lustre-MDT0000 (at 192.168.204.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2022.381501] Lustre: 92849:0:(client.c:2490:ptlrpc_expire_one_request()) Skipped 3 previous similar messages [ 2022.400408] Lustre: lustre-MDT0000-mdc-ffffa0f207536000: Connection restored to 192.168.204.133@tcp (at 192.168.204.133@tcp) [ 2037.398664] Lustre: Unmounted lustre-client [ 2062.948540] Key type lgssc unregistered [ 2063.061625] LNet: 93301:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2063.064223] LNetError: 93301:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2063.074816] LNet: Removed LNI 192.168.204.33@tcp [ 2063.331766] Key type .llcrypt unregistered [ 2063.333100] Key type ._llcrypt unregistered [ 2068.513957] Key type ._llcrypt registered [ 2068.514987] Key type .llcrypt registered [ 2069.017909] Lustre: DEBUG MARKER: == conf-sanity test 91: evict-by-nid support ============= 08:12:11 (1784290331) [ 2069.911621] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2069.918275] alg: No test for adler32 (adler32-zlib) [ 2070.764097] Lustre: Lustre: Build Version: 2.17.54_173_g0a24909 [ 2070.858743] LNet: Added LNI 192.168.204.33@tcp [8/256/0/180] [ 2072.448188] Key type lgssc registered [ 2072.876494] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2091.324877] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2093.262250] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2100.420039] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2110.976381] Lustre: Mounted lustre-client [ 2112.866291] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2123.133611] Lustre: Unmounted lustre-client [ 2172.748951] Key type lgssc unregistered [ 2172.859354] LNet: 96985:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2172.862197] LNetError: 96985:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2172.870289] LNet: Removed LNI 192.168.204.33@tcp [ 2173.083744] Key type .llcrypt unregistered [ 2173.084853] Key type ._llcrypt unregistered [ 2177.806574] Key type ._llcrypt registered [ 2177.807726] Key type .llcrypt registered [ 2178.247522] Lustre: DEBUG MARKER: == conf-sanity test 92: ldev returns MGS NID correctly in command substitution ========================================================== 08:14:00 (1784290440) [ 2181.172746] Lustre: DEBUG MARKER: == conf-sanity test 93: register mulitple MDT at the same time ========================================================== 08:14:03 (1784290443) [ 2181.648528] Lustre: DEBUG MARKER: SKIP: conf-sanity test_93 needs >= 3 MDTs [ 2182.184686] Lustre: DEBUG MARKER: == conf-sanity test 94: ldev outputs correct labels for file system name query ========================================================== 08:14:04 (1784290444) [ 2184.556814] Lustre: DEBUG MARKER: == conf-sanity test 95: ldev should only allow one label filter ========================================================== 08:14:06 (1784290446) [ 2187.331037] Lustre: DEBUG MARKER: == conf-sanity test 96: ldev returns hostname and backend fs correctly in command sub ========================================================== 08:14:09 (1784290449) [ 2189.829699] Lustre: DEBUG MARKER: == conf-sanity test 97: ldev returns correct ouput when querying based on role ========================================================== 08:14:12 (1784290452) [ 2192.378988] Lustre: DEBUG MARKER: == conf-sanity test 98: Buffer-overflow check while parsing mount_opts ========================================================== 08:14:14 (1784290454) [ 2193.153731] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2193.167527] alg: No test for adler32 (adler32-zlib) [ 2194.010914] Lustre: Lustre: Build Version: 2.17.54_173_g0a24909 [ 2194.092965] LNet: Added LNI 192.168.204.33@tcp [8/256/0/180] [ 2195.672188] Key type lgssc registered [ 2196.059867] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2213.506547] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2215.575193] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2224.749593] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2231.307058] Lustre: Mounted lustre-client [ 2233.764381] Lustre: Unmounted lustre-client [ 2245.198914] Key type lgssc unregistered [ 2245.351726] LNet: 104788:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2245.355614] LNetError: 104788:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2245.365851] LNet: Removed LNI 192.168.204.33@tcp [ 2245.694496] Key type .llcrypt unregistered [ 2245.699141] Key type ._llcrypt unregistered [ 2252.710725] Key type ._llcrypt registered [ 2252.711912] Key type .llcrypt registered [ 2253.280082] Lustre: DEBUG MARKER: == conf-sanity test 99: Adding meta_bg option ============ 08:15:15 (1784290515) [ 2254.155680] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2254.165342] alg: No test for adler32 (adler32-zlib) [ 2255.050702] Lustre: Lustre: Build Version: 2.17.54_173_g0a24909 [ 2255.159254] LNet: Added LNI 192.168.204.33@tcp [8/256/0/180] [ 2256.760172] Key type lgssc registered [ 2257.221561] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2269.463582] Lustre: DEBUG MARKER: == conf-sanity test 100: check lshowmount lists MGS, MDT, OST and 0@lo ========================================================== 08:15:31 (1784290531) [ 2302.911171] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2304.787250] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2312.784315] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2319.328370] Lustre: Mounted lustre-client [ 2320.686152] Lustre: Unmounted lustre-client [ 2346.228706] Key type lgssc unregistered [ 2346.345547] LNet: 111078:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2346.348532] LNetError: 111078:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2346.358953] LNet: Removed LNI 192.168.204.33@tcp [ 2346.627551] Key type .llcrypt unregistered [ 2346.628700] Key type ._llcrypt unregistered [ 2351.955934] Key type ._llcrypt registered [ 2351.957507] Key type .llcrypt registered [ 2352.464675] Lustre: DEBUG MARKER: == conf-sanity test 101a: Race MDT->OST reconnection with create ========================================================== 08:16:54 (1784290614) [ 2353.304431] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2353.343718] alg: No test for adler32 (adler32-zlib) [ 2354.244140] Lustre: Lustre: Build Version: 2.17.54_173_g0a24909 [ 2354.356237] LNet: Added LNI 192.168.204.33@tcp [8/256/0/180] [ 2355.960190] Key type lgssc registered [ 2356.441785] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2376.564899] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2378.471673] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2385.944076] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2396.669990] Lustre: Mounted lustre-client [ 2450.248799] Lustre: Unmounted lustre-client [ 2467.357433] Key type lgssc unregistered [ 2467.480552] LNet: 116331:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2467.482598] LNetError: 116331:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2467.493845] LNet: Removed LNI 192.168.204.33@tcp [ 2467.744930] Key type .llcrypt unregistered [ 2467.746930] Key type ._llcrypt unregistered [ 2472.783635] Key type ._llcrypt registered [ 2472.784808] Key type .llcrypt registered [ 2473.255708] Lustre: DEBUG MARKER: == conf-sanity test 101b: Race events DISCONNECT and ACTIVE in osp ========================================================== 08:18:55 (1784290735) [ 2473.993610] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2474.052298] alg: No test for adler32 (adler32-zlib) [ 2474.918279] Lustre: Lustre: Build Version: 2.17.54_173_g0a24909 [ 2475.014320] LNet: Added LNI 192.168.204.33@tcp [8/256/0/180] [ 2476.600180] Key type lgssc registered [ 2477.018029] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2495.705765] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2497.619552] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2504.982138] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2515.446854] Lustre: Mounted lustre-client [ 2520.548699] Lustre: lustre-OST0000-osc-ffffa0f210c5c800: Connection to lustre-OST0000 (at 192.168.204.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2558.073730] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2561.632437] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 3 sec [ 2563.438319] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffffa0f210c5c800.ost_server_uuid 50 [ 2563.979353] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffffa0f210c5c800.ost_server_uuid in FULL state after 0 sec [ 2564.328127] Lustre: Unmounted lustre-client [ 2581.259141] Key type lgssc unregistered [ 2581.374477] LNet: 121012:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2581.376563] LNetError: 121012:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2581.386537] LNet: Removed LNI 192.168.204.33@tcp [ 2581.610898] Key type .llcrypt unregistered [ 2581.611882] Key type ._llcrypt unregistered [ 2586.256414] Key type ._llcrypt registered [ 2586.258020] Key type .llcrypt registered [ 2586.716601] Lustre: DEBUG MARKER: SKIP: conf-sanity test_102 skipping excluded test 102 [ 2587.262207] Lustre: DEBUG MARKER: == conf-sanity test 103: rename filesystem name ========== 08:20:49 (1784290849) [ 2589.478215] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2589.527395] alg: No test for adler32 (adler32-zlib) [ 2590.393501] Lustre: Lustre: Build Version: 2.17.54_173_g0a24909 [ 2590.478936] LNet: Added LNI 192.168.204.33@tcp [8/256/0/180] [ 2592.064159] Key type lgssc registered [ 2592.455421] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2620.764147] Lustre: Mounted lustre-client [ 2623.037289] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 2635.210399] Lustre: Unmounted lustre-client [ 2693.708093] Lustre: Mounted mylustre-client [ 2696.020643] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 2699.743529] Lustre: Unmounted mylustre-client [ 2755.358264] Lustre: Mounted tfs-client [ 2757.640703] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 2759.969391] Lustre: Unmounted tfs-client [ 2815.726247] Lustre: Mounted lustre-client [ 2818.007963] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 2821.137281] Lustre: DEBUG MARKER: == conf-sanity test 104a: Make sure user defined options are reflected in mount ========================================================== 08:24:43 (1784291083) [ 2821.675704] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2831.927148] Lustre: Unmounted lustre-client [ 2897.234365] Lustre: client wants to enable acl, but mdt not! [ 2897.245888] Lustre: Mounted lustre-client [ 2899.367648] Lustre: DEBUG MARKER: == conf-sanity test 104b: Mount uses last flock argument ========================================================== 08:26:01 (1784291161) [ 2899.470361] Lustre: client wants to enable acl, but mdt not! [ 2899.823373] Lustre: Unmounted lustre-client [ 2902.568349] Lustre: DEBUG MARKER: == conf-sanity test 105: check file creation for ro and rw bind mnt pt ========================================================== 08:26:04 (1784291164) [ 2902.831370] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2902.833456] Lustre: Skipped 3 previous similar messages [ 2913.080151] Lustre: Unmounted lustre-client [ 2913.081181] Lustre: Skipped 2 previous similar messages [ 2936.378383] Key type lgssc unregistered [ 2936.499510] LNet: 136458:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2936.502565] LNetError: 136458:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2936.510486] LNet: Removed LNI 192.168.204.33@tcp [ 2936.774101] Key type .llcrypt unregistered [ 2936.775050] Key type ._llcrypt unregistered [ 2950.124235] Key type ._llcrypt registered [ 2950.125695] Key type .llcrypt registered [ 2950.270639] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2950.275797] alg: No test for adler32 (adler32-zlib) [ 2951.142325] Lustre: Lustre: Build Version: 2.17.54_173_g0a24909 [ 2951.225874] LNet: Added LNI 192.168.204.33@tcp [8/256/0/180] [ 2952.800183] Key type lgssc registered [ 2953.210147] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2979.135501] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2981.070925] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2989.276876] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2990.470577] Lustre: Mounted lustre-client [ 2990.593820] Lustre: Remounted lustre-client read-only [ 2991.033206] Lustre: Unmounted lustre-client [ 3008.185119] Key type lgssc unregistered [ 3008.303554] LNet: 140696:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3008.306819] LNetError: 140696:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3008.317460] LNet: Removed LNI 192.168.204.33@tcp [ 3008.585539] Key type .llcrypt unregistered [ 3008.587189] Key type ._llcrypt unregistered [ 3013.868732] Key type ._llcrypt registered [ 3013.869922] Key type .llcrypt registered [ 3014.362139] Lustre: DEBUG MARKER: SKIP: conf-sanity test_106 skipping SLOW test 106 [ 3014.953120] Lustre: DEBUG MARKER: == conf-sanity test 107: Unknown config param should not fail target mounting ========================================================== 08:27:57 (1784291277) [ 3015.851977] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3015.868068] alg: No test for adler32 (adler32-zlib) [ 3016.727945] Lustre: Lustre: Build Version: 2.17.54_173_g0a24909 [ 3016.814676] LNet: Added LNI 192.168.204.33@tcp [8/256/0/180] [ 3018.400159] Key type lgssc registered [ 3018.806465] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3036.998138] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3038.812809] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3045.251509] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3070.904086] Key type lgssc unregistered [ 3071.024344] LNet: 144316:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3071.026468] LNetError: 144316:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3071.038332] LNet: Removed LNI 192.168.204.33@tcp [ 3071.287889] Key type .llcrypt unregistered [ 3071.288913] Key type ._llcrypt unregistered [ 3074.970244] Key type ._llcrypt registered [ 3074.972102] Key type .llcrypt registered [ 3075.208222] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3075.216976] alg: No test for adler32 (adler32-zlib) [ 3076.073103] Lustre: Lustre: Build Version: 2.17.54_173_g0a24909 [ 3076.173280] LNet: Added LNI 192.168.204.33@tcp [8/256/0/180] [ 3077.760186] Key type lgssc registered [ 3078.183496] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3138.821912] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3142.989544] Key type lgssc unregistered [ 3143.096466] LNet: 146286:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3143.098459] LNetError: 146286:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3143.107534] LNet: Removed LNI 192.168.204.33@tcp [ 3143.328745] Key type .llcrypt unregistered [ 3143.330388] Key type ._llcrypt unregistered [ 3148.109583] Key type ._llcrypt registered [ 3148.110911] Key type .llcrypt registered [ 3148.593141] Lustre: DEBUG MARKER: == conf-sanity test 108a: migrate from ldiskfs to ZFS ==== 08:30:10 (1784291410) [ 3149.082327] Lustre: DEBUG MARKER: SKIP: conf-sanity test_108a zfs only test [ 3149.652495] Lustre: DEBUG MARKER: == conf-sanity test 108b: migrate from ZFS to ldiskfs ==== 08:30:11 (1784291411) [ 3153.805482] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3153.827302] alg: No test for adler32 (adler32-zlib) [ 3154.686381] Lustre: Lustre: Build Version: 2.17.54_173_g0a24909 [ 3154.771072] LNet: Added LNI 192.168.204.33@tcp [8/256/0/180] [ 3156.352171] Key type lgssc registered [ 3156.732646] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3189.214625] Lustre: Mounted lustre-client [ 3189.487100] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3189.492239] LustreError: 148849:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffffa0f211161000: inode [0x200000bd1:0x2:0x0] mdc close failed: rc = -108 [ 3189.500439] Lustre: 147669:0:(llite_lib.c:4327:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.204.133@tcp:/lustre/fid: [0x200000bd1:0x2:0x0]/ may get corrupted (rc -108) [ 3189.513932] Lustre: 147666:0:(llite_lib.c:4327:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.204.133@tcp:/lustre/fid: [0x240000402:0x3:0x0]/ may get corrupted (rc -108) [ 3199.802129] Lustre: Unmounted lustre-client [ 3237.010997] Lustre: DEBUG MARKER: == conf-sanity test 109a: test lctl clear_conf fsname ==== 08:31:39 (1784291499) [ 3270.777018] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3272.601458] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3280.596942] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3280.754525] Lustre: Mounted lustre-client [ 3333.093138] Lustre: Unmounted lustre-client [ 3365.442572] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3367.143600] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3373.567414] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3384.302426] Lustre: Mounted lustre-client [ 3403.218351] Lustre: Unmounted lustre-client [ 3420.284169] Key type lgssc unregistered [ 3420.405381] LNet: 155797:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3420.409100] LNetError: 155797:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3420.416886] LNet: Removed LNI 192.168.204.33@tcp [ 3420.686872] Key type .llcrypt unregistered [ 3420.688597] Key type ._llcrypt unregistered [ 3425.906776] Key type ._llcrypt registered [ 3425.907990] Key type .llcrypt registered [ 3426.334843] Lustre: DEBUG MARKER: == conf-sanity test 109b: test lctl clear_conf one config ========================================================== 08:34:48 (1784291688) [ 3435.827986] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3435.894043] alg: No test for adler32 (adler32-zlib) [ 3436.779884] Lustre: Lustre: Build Version: 2.17.54_173_g0a24909 [ 3436.875929] LNet: Added LNI 192.168.204.33@tcp [8/256/0/180] [ 3438.456102] Key type lgssc registered [ 3438.827100] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3467.072376] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3468.863421] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3476.619775] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3483.103102] Lustre: Mounted lustre-client [ 3539.956131] Lustre: Unmounted lustre-client [ 3580.905666] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3582.683991] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3589.510690] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3599.856320] Lustre: Mounted lustre-client [ 3614.789461] Lustre: Unmounted lustre-client [ 3632.047035] Key type lgssc unregistered [ 3632.173601] LNet: 163177:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3632.177580] LNetError: 163177:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3632.187441] LNet: Removed LNI 192.168.204.33@tcp [ 3632.468329] Key type .llcrypt unregistered [ 3632.469939] Key type ._llcrypt unregistered [ 3637.413897] Key type ._llcrypt registered [ 3637.414712] Key type .llcrypt registered [ 3637.845202] Lustre: DEBUG MARKER: == conf-sanity test 110: Adding large_dir with 3-level htree ========================================================== 08:38:20 (1784291900) [ 3641.975120] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3641.999393] alg: No test for adler32 (adler32-zlib) [ 3642.859805] Lustre: Lustre: Build Version: 2.17.54_173_g0a24909 [ 3642.944276] LNet: Added LNI 192.168.204.33@tcp [8/256/0/180] [ 3644.528163] Key type lgssc registered [ 3644.890300] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3673.851842] Lustre: Mounted lustre-client [ 3675.422066] Lustre: Mounted lustre-client [ 3837.926898] Lustre: Unmounted lustre-client [ 3886.594386] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3888.557421] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3896.710078] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3924.750168] Lustre: DEBUG MARKER: SKIP: conf-sanity test_111 skipping SLOW test 111 [ 3925.312161] Lustre: DEBUG MARKER: == conf-sanity test 112a: mount OST with no_create option ========================================================== 08:43:07 (1784292187) [ 3935.589546] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3937.378552] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3944.029718] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3950.906922] Lustre: Mounted lustre-client [ 3955.164194] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffffa0f2119fa000.ost_server_uuid 50 [ 3955.636787] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffffa0f2119fa000.ost_server_uuid in FULL state after 0 sec [ 3957.497786] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffffa0f2119fa000.ost_server_uuid 50 [ 3958.022475] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffffa0f2119fa000.ost_server_uuid in FULL state after 0 sec [ 3971.556910] Lustre: lustre-OST0001-osc-ffffa0f2119fa000: Connection to lustre-OST0001 (at 192.168.204.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3984.963188] Lustre: Unmounted lustre-client [ 3984.964511] Lustre: Skipped 1 previous similar message [ 4002.183100] Key type lgssc unregistered [ 4002.307335] LNet: 174569:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4002.309720] LNetError: 174569:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4002.318645] LNet: Removed LNI 192.168.204.33@tcp [ 4002.596579] Key type .llcrypt unregistered [ 4002.598493] Key type ._llcrypt unregistered [ 4007.671984] Key type ._llcrypt registered [ 4007.673099] Key type .llcrypt registered [ 4008.150672] Lustre: DEBUG MARKER: == conf-sanity test 112b: mount MDT with no_create option ========================================================== 08:44:30 (1784292270) [ 4008.857096] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4008.884745] alg: No test for adler32 (adler32-zlib) [ 4009.744527] Lustre: Lustre: Build Version: 2.17.54_173_g0a24909 [ 4009.833753] LNet: Added LNI 192.168.204.33@tcp [8/256/0/180] [ 4011.432194] Key type lgssc registered [ 4011.850386] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4025.774570] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4036.618831] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4043.397905] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4048.886118] Lustre: Mounted lustre-client [ 4132.511138] Lustre: Unmounted lustre-client [ 4145.557124] Key type lgssc unregistered [ 4145.679553] LNet: 178452:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4145.682153] LNetError: 178452:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4145.689710] LNet: Removed LNI 192.168.204.33@tcp [ 4145.950479] Key type .llcrypt unregistered [ 4145.951461] Key type ._llcrypt unregistered [ 4151.019140] Key type ._llcrypt registered [ 4151.020845] Key type .llcrypt registered [ 4151.533424] Lustre: DEBUG MARKER: == conf-sanity test 113: Shadow mountpoint correctly report ro/rw for mounts ========================================================== 08:46:53 (1784292413) [ 4161.239845] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4161.283269] alg: No test for adler32 (adler32-zlib) [ 4162.175743] Lustre: Lustre: Build Version: 2.17.54_173_g0a24909 [ 4162.280194] LNet: Added LNI 192.168.204.33@tcp [8/256/0/180] [ 4163.872181] Key type lgssc registered [ 4164.334205] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4191.616801] Lustre: Mounted lustre-client [ 4193.866432] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 4200.705355] Lustre: Unmounted lustre-client [ 4254.875892] Lustre: Mounted lustre-client [ 4257.201239] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 4258.737538] Lustre: Unmounted lustre-client [ 4290.449214] Lustre: DEBUG MARKER: SKIP: conf-sanity test_114 skipping SLOW test 114 [ 4290.955679] Lustre: DEBUG MARKER: SKIP: conf-sanity test_115 skipping excluded test 115 [ 4291.497380] Lustre: DEBUG MARKER: == conf-sanity test 116: big size MDT support ============ 08:49:13 (1784292553) [ 4363.645610] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4365.927487] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4375.541439] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4399.026911] Lustre: DEBUG MARKER: == conf-sanity test 117: lctl get_param return errors properly ========================================================== 08:51:01 (1784292661) [ 4410.430027] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4412.296294] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4419.154125] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4429.810666] Lustre: Mounted lustre-client [ 4432.127968] Lustre: Unmounted lustre-client [ 4449.761978] Key type lgssc unregistered [ 4449.877498] LNet: 192248:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4449.881749] LNetError: 192248:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4449.890752] LNet: Removed LNI 192.168.204.33@tcp [ 4450.137149] Key type .llcrypt unregistered [ 4450.138960] Key type ._llcrypt unregistered [ 4455.820750] Key type ._llcrypt registered [ 4455.822286] Key type .llcrypt registered [ 4456.320936] Lustre: DEBUG MARKER: == conf-sanity test 119: writeconf on slave mdt shouldn't duplicate mdc/osp and crash ========================================================== 08:51:58 (1784292718) [ 4457.988750] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4457.999499] alg: No test for adler32 (adler32-zlib) [ 4458.887146] Lustre: Lustre: Build Version: 2.17.54_173_g0a24909 [ 4458.990497] LNet: Added LNI 192.168.204.33@tcp [8/256/0/180] [ 4460.584196] Key type lgssc registered [ 4461.083691] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4480.728922] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4482.514953] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4489.403644] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4499.955656] Lustre: Mounted lustre-client [ 4505.063453] Lustre: lustre-MDT0001-mdc-ffffa0f207c67000: Connection to lustre-MDT0001 (at 192.168.204.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4528.107030] LustreError: 195737:0:(obd_config.c:2103:class_config_llog_handler()) MGC192.168.204.133@tcp: cfg command failed: rc = -17 [ 4528.110511] Lustre: cmd=cf001 0:lustre-MDT0001-mdc 1:mdc 2:lustre-clilmv_UUID [ 4528.110511] [ 4528.113162] LustreError: 195345:0:(mgc_request.c:614:do_requeue()) failed processing log: -17 [ 4827.619057] Lustre: lustre-MDT0001-mdc-ffffa0f207c67000: Connection to lustre-MDT0001 (at 192.168.204.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4840.938811] LustreError: 197900:0:(obd_config.c:761:class_setup()) mdc: device 4 already setup: rc = -17 [ 4840.941122] LustreError: 197900:0:(obd_config.c:866:class_detach()) OBD device 4 still set up [ 4840.943018] LustreError: 197900:0:(obd_config.c:2103:class_config_llog_handler()) MGC192.168.204.133@tcp: cfg command failed: rc = -17 [ 4840.945533] Lustre: cmd=cf003 0:lustre-MDT0001-mdc 1:lustre-MDT0001_UUID 2:192.168.204.133@tcp [ 4840.945533] [ 4840.948046] LustreError: 195345:0:(mgc_request.c:614:do_requeue()) failed processing log: -17 [ 5139.938848] Lustre: lustre-MDT0001-mdc-ffffa0f207c67000: Connection to lustre-MDT0001 (at 192.168.204.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5156.843572] LustreError: 200098:0:(obd_config.c:2103:class_config_llog_handler()) MGC192.168.204.133@tcp: cfg command failed: rc = -17 [ 5156.849280] Lustre: cmd=cf014 0:lustre-clilmv 1:lustre-MDT0001_UUID 2:1 3:1 4:lustre-MDT0001-mdc_UUID [ 5156.849280] [ 5156.854452] LustreError: 195345:0:(mgc_request.c:614:do_requeue()) failed processing log: -17 [ 5451.176218] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5461.432101] Lustre: Unmounted lustre-client [ 5497.023554] Lustre: DEBUG MARKER: == conf-sanity test 120: cross-target rename should not create bad symlinks ========================================================== 09:09:19 (1784293759) [ 5509.791247] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5511.698716] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5520.174706] Lustre: DEBUG MARKER: oleg433-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5520.335361] Lustre: Mounted lustre-client [ 5522.183139] Lustre: Unmounted lustre-client [ 5566.053924] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 5442 sec ========== 09:10:28 (1784293828) [ 5566.618385] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 09:10:28 (1784293828) === [ 5567.753937] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 09:10:30 (1784293830) === [ 5579.252323] Key type lgssc unregistered [ 5579.370747] LNet: 209922:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5579.373953] LNetError: 209922:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5579.382528] LNet: Removed LNI 192.168.204.33@tcp [ 5579.646198] Key type .llcrypt unregistered [ 5579.648195] Key type ._llcrypt unregistered