[ 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 425785824 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: 2895288K/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.001012] APIC: Switch to symmetric I/O mode setup [ 0.003207] x2apic enabled [ 0.004012] Switched APIC routing to physical x2apic. [ 0.005021] kvm-guest: setup PV IPIs [ 0.007835] ..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.008023] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009020] pid_max: default: 32768 minimum: 301 [ 0.010153] LSM: Security Framework initializing [ 0.012055] Yama: becoming mindful. [ 0.013059] SELinux: Initializing. [ 0.014109] *** VALIDATE selinux *** [ 0.022876] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.026789] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.028071] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.030007] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.032022] *** VALIDATE tmpfs *** [ 0.033509] *** VALIDATE proc *** [ 0.034342] *** VALIDATE cgroup *** [ 0.035011] *** VALIDATE cgroup2 *** [ 0.037048] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.038163] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.039013] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.040034] Spectre V2 : User space: Vulnerable [ 0.041015] Speculative Store Bypass: Vulnerable [ 0.044399] debug: unmapping init [mem 0xffffffffb3859000-0xffffffffb3860fff] [ 0.047300] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.048907] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.049033] ... version: 2 [ 0.050019] ... bit width: 48 [ 0.051016] ... generic registers: 4 [ 0.052018] ... value mask: 0000ffffffffffff [ 0.053030] ... max period: 00007fffffffffff [ 0.054018] ... fixed-purpose events: 3 [ 0.055014] ... event mask: 000000070000000f [ 0.056434] rcu: Hierarchical SRCU implementation. [ 0.058911] smp: Bringing up secondary CPUs ... [ 0.059721] x86: Booting SMP configuration: [ 0.060034] .... node #0, CPUs: #1 #2 #3 [ 0.065026] smp: Brought up 1 node, 4 CPUs [ 0.067040] smpboot: Max logical packages: 1 [ 0.068017] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.209653] node 0 deferred pages initialised in 137ms [ 0.213236] devtmpfs: initialized [ 0.214294] x86/mm: Memory block size: 128MB [ 0.216873] gcov: version magic: 0x41383552 [ 0.218411] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.222104] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.224473] pinctrl core: initialized pinctrl subsystem [ 0.227253] [ 0.227904] ************************************************************* [ 0.230074] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.235022] ** ** [ 0.238013] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.240014] ** ** [ 0.242016] ** This means that this kernel is built to expose internal ** [ 0.245026] ** IOMMU data structures, which may compromise security on ** [ 0.248020] ** your system. ** [ 0.249017] ** ** [ 0.251015] ** If you see this message and you are not debugging the ** [ 0.253018] ** kernel, report this immediately to your vendor! ** [ 0.256017] ** ** [ 0.258015] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.260017] ************************************************************* [ 0.263958] NET: Registered protocol family 16 [ 0.265497] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.268078] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.270070] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.274140] cpuidle: using governor menu [ 0.275822] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.278643] PCI: Using configuration type 1 for base access [ 0.280150] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.290150] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.291036] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.293085] cryptd: max_cpu_qlen set to 1000 [ 0.296261] ACPI: Added _OSI(Module Device) [ 0.298022] ACPI: Added _OSI(Processor Device) [ 0.299036] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.301023] ACPI: Added _OSI(Processor Aggregator Device) [ 0.305012] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.311519] ACPI: Interpreter enabled [ 0.313072] ACPI: PM: (supports S0 S3 S4 S5) [ 0.314021] ACPI: Using IOAPIC for interrupt routing [ 0.316100] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.320503] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.329753] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.333073] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.335024] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.337115] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.343387] acpiphp: Slot [2] registered [ 0.345175] acpiphp: Slot [5] registered [ 0.347199] acpiphp: Slot [6] registered [ 0.349180] acpiphp: Slot [3] registered [ 0.351128] acpiphp: Slot [4] registered [ 0.353145] acpiphp: Slot [7] registered [ 0.355147] acpiphp: Slot [8] registered [ 0.357133] acpiphp: Slot [9] registered [ 0.359152] acpiphp: Slot [10] registered [ 0.361123] acpiphp: Slot [11] registered [ 0.363145] acpiphp: Slot [12] registered [ 0.365133] acpiphp: Slot [13] registered [ 0.366154] acpiphp: Slot [14] registered [ 0.368146] acpiphp: Slot [15] registered [ 0.370116] acpiphp: Slot [16] registered [ 0.371117] acpiphp: Slot [17] registered [ 0.373124] acpiphp: Slot [18] registered [ 0.375140] acpiphp: Slot [19] registered [ 0.376134] acpiphp: Slot [20] registered [ 0.378139] acpiphp: Slot [21] registered [ 0.379121] acpiphp: Slot [22] registered [ 0.381205] acpiphp: Slot [23] registered [ 0.383136] acpiphp: Slot [24] registered [ 0.384181] acpiphp: Slot [25] registered [ 0.386146] acpiphp: Slot [26] registered [ 0.387125] acpiphp: Slot [27] registered [ 0.388122] acpiphp: Slot [28] registered [ 0.390134] acpiphp: Slot [29] registered [ 0.391118] acpiphp: Slot [30] registered [ 0.393168] acpiphp: Slot [31] registered [ 0.394077] PCI host bridge to bus 0000:00 [ 0.396027] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.398029] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.400047] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.404036] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.407043] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.410035] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.411211] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.415100] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.418399] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.426023] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.432709] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.435026] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.437023] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.440039] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.444604] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.448652] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.452064] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.458147] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.463940] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.474788] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.480017] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.485000] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.495020] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.500019] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.515031] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.527040] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.534018] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.540019] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.554959] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.566702] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.568446] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.571680] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.572419] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.575315] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.580104] iommu: Default domain type: Passthrough [ 0.582558] SCSI subsystem initialized [ 0.584134] ACPI: bus type USB registered [ 0.585106] usbcore: registered new interface driver usbfs [ 0.586062] usbcore: registered new interface driver hub [ 0.587097] usbcore: registered new device driver usb [ 0.589222] pps_core: LinuxPPS API ver. 1 registered [ 0.591015] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.593073] PTP clock support registered [ 0.596072] EDAC MC: Ver: 3.0.0 [ 0.598175] PCI: Using ACPI for IRQ routing [ 0.601131] NetLabel: Initializing [ 0.602015] NetLabel: domain hash size = 128 [ 0.603011] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.605101] NetLabel: unlabeled traffic allowed by default [ 0.607136] vgaarb: loaded [ 0.608296] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.609017] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.616451] clocksource: Switched to clocksource kvm-clock [ 0.727427] VFS: Disk quotas dquot_6.6.0 [ 0.728801] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.730716] *** VALIDATE ramfs *** [ 0.731809] *** VALIDATE hugetlbfs *** [ 0.733236] pnp: PnP ACPI init [ 0.735400] pnp: PnP ACPI: found 6 devices [ 0.750608] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.753350] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.756243] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.758902] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.761478] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.764312] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.767694] NET: Registered protocol family 2 [ 0.770572] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.775734] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.780439] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.786443] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.789918] TCP: Hash tables configured (established 65536 bind 65536) [ 0.793733] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.797199] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.800621] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.805059] NET: Registered protocol family 1 [ 0.808163] RPC: Registered named UNIX socket transport module. [ 0.810729] RPC: Registered udp transport module. [ 0.812684] RPC: Registered tcp transport module. [ 0.814667] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.817845] NET: Registered protocol family 44 [ 0.819788] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.822198] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.824638] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.827233] PCI: CLS 0 bytes, default 64 [ 0.830187] Unpacking initramfs... [ 2.313369] debug: unmapping init [mem 0xffff9d40bcc64000-0xffff9d40bffcffff] [ 2.319691] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.324194] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.330048] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.916338] Initialise system trusted keyrings [ 2.919245] Key type blacklist registered [ 2.921559] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.956225] zbud: loaded [ 2.962260] *** VALIDATE nfs *** [ 2.963828] *** VALIDATE nfs4 *** [ 2.965492] pstore: using deflate compression [ 2.968973] Platform Keyring initialized [ 3.098154] NET: Registered protocol family 38 [ 3.099949] Key type asymmetric registered [ 3.101881] Asymmetric key parser 'x509' registered [ 3.103811] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 3.106998] io scheduler mq-deadline registered [ 3.108593] io scheduler kyber registered [ 3.110408] io scheduler bfq registered [ 3.112200] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 3.115735] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 3.119480] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 3.123205] ACPI: Power Button [PWRF] [ 3.131925] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.142138] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.158411] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.191726] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.221276] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.229331] Non-volatile memory driver v1.3 [ 3.231791] Linux agpgart interface v0.103 [ 3.277952] virtio_blk virtio1: [vda] 146672 512-byte logical blocks (75.1 MB/71.6 MiB) [ 3.283273] vda: detected capacity change from 0 to 75096064 [ 3.300973] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.304260] vdb: detected capacity change from 0 to 1073741824 [ 3.312593] libphy: Fixed MDIO Bus: probed [ 3.318851] usbcore: registered new interface driver usbserial_generic [ 3.320986] usbserial: USB Serial support registered for generic [ 3.323124] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.327079] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.329118] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.332081] mousedev: PS/2 mouse device common for all mice [ 3.335369] rtc_cmos 00:05: RTC can wake from S4 [ 3.338368] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.343535] rtc_cmos 00:05: registered as rtc0 [ 3.346147] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.347740] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.350170] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.357027] intel_pstate: CPU model not supported [ 3.360745] hid: raw HID events driver (C) Jiri Kosina [ 3.363248] usbcore: registered new interface driver usbhid [ 3.365510] usbhid: USB HID core driver [ 3.367584] drop_monitor: Initializing network drop monitor service [ 3.370415] Initializing XFRM netlink socket [ 3.372778] NET: Registered protocol family 10 [ 3.376682] Segment Routing with IPv6 [ 3.378260] NET: Registered protocol family 17 [ 3.380763] mpls_gso: MPLS GSO support [ 3.386931] RAS: Correctable Errors collector initialized. [ 3.389768] AVX version of gcm_enc/dec engaged. [ 3.392355] AES CTR mode by8 optimization enabled [ 3.475915] sched_clock: Marking stable (3475886479, 0)->(4414164548, -938278069) [ 3.479592] registered taskstats version 1 [ 3.481837] Loading compiled-in X.509 certificates [ 3.484166] zswap: loaded using pool lzo/zbud [ 3.512862] Key type big_key registered [ 3.528583] Key type encrypted registered [ 3.530300] ima: No TPM chip found, activating TPM-bypass! [ 3.532698] ima: Allocated hash algorithm: sha1 [ 3.534214] ima: No architecture policies found [ 3.536088] evm: Initialising EVM extended attributes: [ 3.538712] evm: security.selinux [ 3.540214] evm: security.ima [ 3.541620] evm: security.capability [ 3.542989] evm: HMAC attrs: 0x1 [ 3.545218] rtc_cmos 00:05: setting system clock to 2026-08-27 14:22:23 UTC (1787840543) [ 3.551355] debug: unmapping init [mem 0xffffffffb4803000-0xffffffffb49fffff] [ 3.554197] debug: unmapping init [mem 0xffffffffb3582000-0xffffffffb3858fff] [ 3.563092] Write protecting the kernel read-only data: 28672k [ 3.567258] debug: unmapping init [mem 0xffffffffb1c03000-0xffffffffb1dfffff] [ 3.570154] debug: unmapping init [mem 0xffffffffb2514000-0xffffffffb25fffff] [ 3.602350] 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.611451] systemd[1]: Detected virtualization kvm. [ 3.613352] systemd[1]: Detected architecture x86-64. [ 3.615027] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.646022] systemd[1]: No hostname configured. [ 3.647640] systemd[1]: Set hostname to . [ 3.649208] random: systemd: uninitialized urandom read (16 bytes read) [ 3.651441] systemd[1]: Initializing machine ID from random generator. [ 3.702128] random: ln: uninitialized urandom read (6 bytes read) [ 3.788437] random: systemd: uninitialized urandom read (16 bytes read) [ 3.791218] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ 3.796777] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ 3.801426] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ OK ] Listening on udev Kernel Socket. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Slices. [ OK ] Reached target Paths. [ OK ] Listening on Journal Socket. Starting Journal Service... [ OK ] Started Memstrack Anylazing Service. Starting Apply Kernel Variables... [ OK ] Listening on udev Control Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Timers. Starting Setup Virtual Console... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... [ OK ] Started Apply Kernel Variables. [ OK ] Started Setup Virtual Console. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Journal Service. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.402946] device-mapper: uevent: version 1.0.3 [ 4.405332] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 5.156071] virtio_net virtio0 ens2: renamed from eth0 [ 5.238466] scsi host0: ata_piix [ 5.261217] scsi host1: ata_piix [ 5.263205] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.266066] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 9.722205] dracut-initqueue[589]: RTNETLINK answers: File exists [ 10.048963] random: crng init done [ 10.050696] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 10.560498] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Timers. [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target System Initialization. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Swap. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Slices. [ OK ] Stopped target Sockets. [ OK ] Stopped target Paths. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ 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... [ 11.793321] printk: systemd: 22 output lines suppressed due to ratelimiting [ 12.053631] SELinux: Disabled at runtime. [ 12.119839] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 12.127111] systemd[1]: Detected virtualization kvm. [ 12.129513] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 12.672705] systemd[1]: initrd-switch-root.service: Succeeded. [ 12.676560] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 12.685242] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 12.691366] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 12.695404] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 12.705770] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.717731] systemd[1]: Activating swap /dev/disk/by-label/SWAP... Activating swap /dev/disk/by-label/SWAP... Mounting Kernel Debug File System... [ OK ] Listening on RPC[ 12.744549] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS bind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Listening on udev Kernel Socket. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. Mounting Huge Pages File System... [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Reached target rpc_pipefs.target. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... Mounting POSIX Message Queue File System... [ OK ] Listening on Process Core Dump Socket. [ OK ] Created slice system-serial\x2dgetty.slice. Starting Apply Kernel Variables... [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. [ OK ] Stopped target Initrd Root File System. [ OK ] Created slice system-getty.slice. Starting Remount Root and Kernel File Systems... [ OK ] Started Journal Service. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted Kernel Debug File System. [ OK ] Mounted Huge Pages File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Started Apply Kernel Variables. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... [ OK ] Reached target Swap. Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... Starting udev Kernel Device Manager... [ OK ] Started udev Coldplug all Devices. [ OK ] Mounted /mnt. [ 13.180232] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 13.446792] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 13.500485] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 13.585987] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 13.605313] EDAC sbridge: Ver: 1.1.2 [ 14.646571] Key type dns_resolver registered [ 14.981846] NFS: Registering the id_resolver key type [ 14.984923] Key type id_resolver registered [ 14.986456] Key type id_legacy registered [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Mark the need to relabel after reboot... Starting Rebuild Dynamic Linker Cache... Starting Create Volatile Files and Directories... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting Update UTMP about System Boot/Shutdown... Starting RPC Bind... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started dnf makecache --timer. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started irqbalance daemon. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... Starting Login Service... [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Timers. [ OK ] Reached target sshd-keygen.target. Starting Restore /run/initramfs on shutdown... [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting OpenSSH server daemon... Starting Dynamic System Tuning Daemon... Starting GSSAPI Proxy Daemon... Starting Network Manager Wait Online... [ OK ] Started 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 Permit User Sessions. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Command Scheduler. [ OK ] Started Getty on tty1. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Notify NFS peers of a restart... Starting System Logging Service... Starting Crash recovery kernel arming... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started 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. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg624-client login: [ 51.878341] libcfs: loading out-of-tree module taints kernel. [ 51.945979] Key type ._llcrypt registered [ 51.948765] Key type .llcrypt registered [ 52.628490] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 52.646378] alg: No test for adler32 (adler32-zlib) [ 54.069670] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [ 54.827145] LNet: Added LNI 192.168.206.24@tcp [8/256/0/180] [ 56.623142] Key type lgssc registered [ 58.125287] Lustre: Echo OBD driver; http://www.lustre.org/ [ 78.550079] hrtimer: interrupt took 14019006 ns [ 189.119538] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 196.004315] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 211.366735] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing check_logdir /tmp/testlogs/ [ 215.017469] Lustre: lustre-OST0000-osc-ffff9d4108330000: disconnect after 23s idle [ 216.802788] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing yml_node [ 220.972426] Lustre: DEBUG MARKER: Client: 2.17.57.65 [ 223.202743] Lustre: DEBUG MARKER: MDS: 2.17.57.65 [ 225.420964] Lustre: DEBUG MARKER: OSS: 2.17.57.65 [ 226.927903] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Thu Aug 27 10:26:05 EDT 2026 [ 242.866568] Lustre: DEBUG MARKER: excepting tests: 32newtarball [ 244.652815] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 246.893253] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 257.258671] Lustre: Unmounted lustre-client [ 358.509407] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 363.614693] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 385.515657] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 421.604853] Lustre: DEBUG MARKER: == conf-sanity test 121: failover MGS ==================== 10:29:20 (1787840960) [ 470.170644] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 474.977995] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 509.014177] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mgc.*.mgs_server_uuid 1475 0 [ 557.989225] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 565.415156] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 609.710405] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mgc.*.mgs_server_uuid 1475 0 [ 632.294965] Lustre: DEBUG MARKER: == conf-sanity test 122a: Check OST sequence update ====== 10:32:50 (1787841170) [ 741.996752] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 747.010393] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 771.244653] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 772.058362] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 798.545992] Lustre: Unmounted lustre-client [ 826.294416] Key type lgssc unregistered [ 826.531680] LNet: 21869:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 826.542849] LNetError: 21869:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 826.565252] LNet: Removed LNI 192.168.206.24@tcp [ 827.288147] Key type .llcrypt unregistered [ 827.291416] Key type ._llcrypt unregistered [ 840.791736] Key type ._llcrypt registered [ 840.794262] Key type .llcrypt registered [ 841.997409] Lustre: DEBUG MARKER: == conf-sanity test 123aa: llog_print works with FIDs and simple names ========================================================== 10:36:20 (1787841380) [ 843.950425] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 844.046061] alg: No test for adler32 (adler32-zlib) [ 845.063371] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [ 845.316587] LNet: Added LNI 192.168.206.24@tcp [8/256/0/180] [ 846.983317] Key type lgssc registered [ 848.111362] Lustre: Echo OBD driver; http://www.lustre.org/ [ 896.613701] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 901.948069] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 922.074652] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 923.615917] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 934.179682] Lustre: DEBUG MARKER: == conf-sanity test 123ab: llog_print params output values from set_param -P ========================================================== 10:37:52 (1787841472) [ 944.065979] Lustre: DEBUG MARKER: == conf-sanity test 123ac: llog_print with --start and --end ========================================================== 10:38:02 (1787841482) [ 952.238374] Lustre: DEBUG MARKER: == conf-sanity test 123ad: llog_print shows all records == 10:38:10 (1787841490) [ 963.894573] Lustre: DEBUG MARKER: == conf-sanity test 123ae: llog_cancel can cancel requested record ========================================================== 10:38:22 (1787841502) [ 989.819177] Lustre: DEBUG MARKER: == conf-sanity test 123af: llog_catlist can show all config files correctly ========================================================== 10:38:48 (1787841528) [ 1009.275567] Lustre: DEBUG MARKER: == conf-sanity test 123ag: llog_print skips values deleted by set_param -P -d ========================================================== 10:39:07 (1787841547) [ 1025.148688] Lustre: DEBUG MARKER: == conf-sanity test 123ah: del_ost cancels config log entries correctly ========================================================== 10:39:23 (1787841563) [ 1035.403653] Lustre: Unmounted lustre-client [ 1035.860612] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 1038.092145] Lustre: Unmounted lustre-client [ 1074.389906] Key type lgssc unregistered [ 1074.697740] LNet: 30245:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1074.702239] LNetError: 30245:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1074.722882] LNet: Removed LNI 192.168.206.24@tcp [ 1075.528305] Key type .llcrypt unregistered [ 1075.533979] Key type ._llcrypt unregistered [ 1104.296750] Key type ._llcrypt registered [ 1104.301017] Key type .llcrypt registered [ 1104.512236] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1104.524184] alg: No test for adler32 (adler32-zlib) [ 1105.564786] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [ 1105.826222] LNet: Added LNI 192.168.206.24@tcp [8/256/0/180] [ 1107.599194] Key type lgssc registered [ 1108.835292] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1188.334840] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1193.797339] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1216.864351] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1261.146959] Lustre: DEBUG MARKER: == conf-sanity test 123ai: llog_print display all non skipped records ========================================================== 10:43:19 (1787841799) [ 1298.156750] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1304.000921] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1327.686845] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1329.370379] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 1464.536686] Lustre: DEBUG MARKER: == conf-sanity test 123aj: check permanent TBF rules ===== 10:46:42 (1787842002) [ 1508.799959] Lustre: DEBUG MARKER: == conf-sanity test 123F: clear and reset all parameters using set_param -F ========================================================== 10:47:27 (1787842047) [ 1520.501426] Lustre: Unmounted lustre-client [ 1597.748693] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1602.789578] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1621.478230] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1622.308532] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 1627.500872] Lustre: Unmounted lustre-client [ 1653.670733] Key type lgssc unregistered [ 1653.915966] LNet: 41558:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1653.928918] LNetError: 41558:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1653.953213] LNet: Removed LNI 192.168.206.24@tcp [ 1654.679293] Key type .llcrypt unregistered [ 1654.682633] Key type ._llcrypt unregistered [ 1668.291965] Key type ._llcrypt registered [ 1668.295682] Key type .llcrypt registered [ 1669.573960] Lustre: DEBUG MARKER: == conf-sanity test 123G: clear and reset all parameters using apply_yaml ========================================================== 10:50:08 (1787842208) [ 1672.342617] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1672.363674] alg: No test for adler32 (adler32-zlib) [ 1673.412739] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [ 1673.644502] LNet: Added LNI 192.168.206.24@tcp [8/256/0/180] [ 1675.335632] Key type lgssc registered [ 1676.761867] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1721.836151] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1726.705328] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1745.855596] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1748.916083] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 1759.883308] Lustre: Unmounted lustre-client [ 1836.922069] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1842.621307] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1864.155330] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1865.025458] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 1870.271618] Lustre: Unmounted lustre-client [ 1907.086153] Key type lgssc unregistered [ 1907.390681] LNet: 47094:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1907.401515] LNetError: 47094:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1907.441495] LNet: Removed LNI 192.168.206.24@tcp [ 1908.205741] Key type .llcrypt unregistered [ 1908.208531] Key type ._llcrypt unregistered [ 1922.805132] Key type ._llcrypt registered [ 1922.814675] Key type .llcrypt registered [ 1924.205939] Lustre: DEBUG MARKER: == conf-sanity test 123H: check concurent accesses with 'lctl llog_print ========================================================== 10:54:22 (1787842462) [ 1926.152334] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1926.254462] alg: No test for adler32 (adler32-zlib) [ 1927.302372] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [ 1927.611410] LNet: Added LNI 192.168.206.24@tcp [8/256/0/180] [ 1929.288841] Key type lgssc registered [ 1930.454075] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1988.464949] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1994.733338] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2016.666065] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2019.610526] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 2133.785637] Lustre: DEBUG MARKER: == conf-sanity test 124: check failover after replace_nids ========================================================== 10:57:52 (1787842672) [ 2135.251407] Lustre: DEBUG MARKER: SKIP: conf-sanity test_124 needs MDT failover setup [ 2137.182088] Lustre: DEBUG MARKER: == conf-sanity test 126: mount in parallel shouldn't cause a crash ========================================================== 10:57:55 (1787842675) [ 2139.496160] Lustre: Unmounted lustre-client [ 2175.897851] Key type lgssc unregistered [ 2176.274224] LNet: 52438:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2176.293376] LNetError: 52438:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2176.329140] LNet: Removed LNI 192.168.206.24@tcp [ 2177.186129] Key type .llcrypt unregistered [ 2177.190147] Key type ._llcrypt unregistered [ 2206.782126] Key type ._llcrypt registered [ 2206.787131] Key type .llcrypt registered [ 2207.376585] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2207.392370] alg: No test for adler32 (adler32-zlib) [ 2208.541887] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [ 2208.912589] LNet: Added LNI 192.168.206.24@tcp [8/256/0/180] [ 2210.695182] Key type lgssc registered [ 2211.756631] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2247.381716] Lustre: DEBUG MARKER: == conf-sanity test 127: direct io overwrite on full ost ========================================================== 10:59:45 (1787842785) [ 2261.072072] Key type lgssc unregistered [ 2261.363177] LNet: 54850:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2261.382898] LNetError: 54850:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2261.402696] LNet: Removed LNI 192.168.206.24@tcp [ 2262.108859] Key type .llcrypt unregistered [ 2262.114397] Key type ._llcrypt unregistered [ 2274.436123] Key type ._llcrypt registered [ 2274.439852] Key type .llcrypt registered [ 2274.932712] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2274.948962] alg: No test for adler32 (adler32-zlib) [ 2275.993983] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [ 2276.167466] LNet: Added LNI 192.168.206.24@tcp [8/256/0/180] [ 2277.824364] Key type lgssc registered [ 2279.103453] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2334.544974] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2340.850405] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2361.826270] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2365.055549] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 2413.237685] Lustre: DEBUG MARKER: == conf-sanity test 128: Force using remote logs with --nolocallogs ========================================================== 11:02:31 (1787842951) [ 2415.187732] Lustre: DEBUG MARKER: SKIP: conf-sanity test_128 need separate mgs device [ 2417.324238] Lustre: DEBUG MARKER: == conf-sanity test 129: attempt to connect an OST with the same index should fail ========================================================== 11:02:35 (1787842955) [ 2419.611371] Lustre: Unmounted lustre-client [ 2489.010586] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2494.843506] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2565.402592] Lustre: DEBUG MARKER: == conf-sanity test 130: re-register an MDT after writeconf ========================================================== 11:05:03 (1787843103) [ 2640.711384] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 2645.213878] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 2656.274074] Lustre: lustre-MDT0001-mdc-ffff9d411155c000: Connection to lustre-MDT0001 (at 192.168.206.124@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2666.465138] Lustre: lustre-OST0000-osc-ffff9d411155c000: disconnect after 24s idle [ 2671.471899] LustreError: 63542:0:(obd_config.c:2102:class_config_llog_handler()) MGC192.168.206.124@tcp: cfg command failed: rc = -17 [ 2671.479040] Lustre: cmd=cf001 0:lustre-MDT0001-mdc 1:mdc 2:lustre-clilmv_UUID [ 2671.479040] [ 2671.485359] LustreError: 62810:0:(mgc_request.c:614:do_requeue()) failed processing log: -17 [ 2685.265947] Lustre: DEBUG MARKER: == conf-sanity test 131: MDT backup restore with project ID and inheritance flag ========================================================== 11:07:03 (1787843223) [ 2738.546614] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 2823.170491] Lustre: Unmounted lustre-client [ 2972.471552] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 2978.167941] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 3028.869549] Lustre: DEBUG MARKER: == conf-sanity test 132: hsm_actions processed after failover ========================================================== 11:12:47 (1787843567) [ 3030.777597] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3031.317948] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 3031.327836] Lustre: Skipped 2 previous similar messages [ 3053.183972] Lustre: Unmounted lustre-client [ 3191.047523] Lustre: DEBUG MARKER: == conf-sanity test 133: stripe QOS: free space balance in a pool ========================================================== 11:15:29 (1787843729) [ 3192.879592] Lustre: DEBUG MARKER: SKIP: conf-sanity test_133 needs >= 4 OSTs [ 3194.825624] Lustre: DEBUG MARKER: == conf-sanity test 134: check_iam works without faults == 11:15:33 (1787843733) [ 3284.388840] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 3290.286824] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 3943.068324] Lustre: DEBUG MARKER: == conf-sanity test 135: check the behavior when changelog is wrapped around ========================================================== 11:28:01 (1787844481) [ 3945.773802] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3956.119861] Lustre: Unmounted lustre-client [ 4086.157974] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4091.803442] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4114.904622] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4115.751407] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 6060.216560] Lustre: 95231:0:(llog_cat.c:310:llog_cat_process_or_fork()) lustre-MDT0000-mdc-ffff9d410343e800: catlog [0xa:0x5:0x0] crosses index zero [ 6089.524609] Lustre: Unmounted lustre-client [ 6122.246290] Key type lgssc unregistered [ 6122.846922] LNet: 103439:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6122.860079] LNetError: 103439:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6122.897114] LNet: Removed LNI 192.168.206.24@tcp [ 6123.904373] Key type .llcrypt unregistered [ 6123.919268] Key type ._llcrypt unregistered [ 6137.276859] Key type ._llcrypt registered [ 6137.279228] Key type .llcrypt registered [ 6138.994569] Lustre: DEBUG MARKER: == conf-sanity test 136: don't panic with bad obdecho setup ========================================================== 12:04:36 (1787846676) [ 6157.936910] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6157.993891] alg: No test for adler32 (adler32-zlib) [ 6159.089774] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [ 6159.401991] LNet: Added LNI 192.168.206.24@tcp [8/256/0/180] [ 6161.063177] Key type lgssc registered [ 6162.559135] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6255.782674] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6263.625890] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6289.404429] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6291.499417] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 6313.833276] Lustre: Unmounted lustre-client [ 6445.190923] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6451.222774] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6477.173513] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6515.796894] Lustre: DEBUG MARKER: == conf-sanity test 137: a new MDT should inherit pools, parameters and failnode ========================================================== 12:10:54 (1787847054) [ 6517.583080] Lustre: DEBUG MARKER: SKIP: conf-sanity test_137 needs >= 3 MDTs [ 6519.565596] Lustre: DEBUG MARKER: == conf-sanity test 140: remove_updatelog script actions ========================================================== 12:10:57 (1787847057) [ 6556.156936] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6562.409067] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6586.397084] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6587.466540] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 6602.725726] Lustre: lustre-MDT0000-mdc-ffff9d410add4000: Connection to lustre-MDT0000 (at 192.168.206.124@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6612.985367] Lustre: lustre-MDT0001-mdc-ffff9d410add4000: Connection to lustre-MDT0001 (at 192.168.206.124@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6628.319275] Lustre: 104199:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787847152/real 1787847152] req@0000000032b8939c x1874693140329088/t0(0) o400->MGC192.168.206.124@tcp@192.168.206.124@tcp:26/25 lens 224/224 e 0 to 1 dl 1787847168 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 6628.349693] LustreError: MGC192.168.206.124@tcp: Connection to MGS (at 192.168.206.124@tcp) was lost; in progress operations using this service will fail [ 6773.737616] Lustre: Evicted from MGS (at 192.168.206.124@tcp) after server handle changed from 0x3ee2348d1f572550 to 0x3ee2348d1f573bed [ 6773.756636] Lustre: MGC192.168.206.124@tcp: Connection restored to 192.168.206.124@tcp (at 192.168.206.124@tcp) [ 6779.893295] Lustre: 113612:0:(mgc_request.c:1900:mgc_process_log()) MGC192.168.206.124@tcp: IR log lustre-cliir failed, not fatal: rc = -2 [ 6783.999358] LustreError: lustre-MDT0000-mdc-ffff9d410add4000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 6784.047597] Lustre: lustre-MDT0000-mdc-ffff9d410add4000: Connection restored to 192.168.206.124@tcp (at 192.168.206.124@tcp) [ 6787.093748] LustreError: lustre-MDT0001-mdc-ffff9d410add4000: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 6787.120553] Lustre: lustre-MDT0001-mdc-ffff9d410add4000: Connection restored to 192.168.206.124@tcp (at 192.168.206.124@tcp) [ 6793.690145] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6795.155279] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 6800.632339] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6802.075436] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 6818.266420] Lustre: Unmounted lustre-client [ 6948.241680] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6954.484652] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6978.708412] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7022.110714] Lustre: DEBUG MARKER: == conf-sanity test 150: test setting max_cached_mb to a % ========================================================== 12:19:20 (1787847560) [ 7053.719645] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7059.869862] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7082.026227] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7082.688557] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 7085.009981] LustreError: 120950:0:(lproc_llite.c:618:ll_max_cached_mb_seq_write()) lustre: cannot set max_cached_mb=0 MB more than 3693 MB: rc = -34 [ 7093.620548] Lustre: DEBUG MARKER: == conf-sanity test 151a: damaged local config doesn't prevent mounting ========================================================== 12:20:32 (1787847632) [ 7096.292949] Lustre: Unmounted lustre-client [ 7124.372608] Key type lgssc unregistered [ 7124.624051] LNet: 121960:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7124.638821] LNetError: 121960:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7125.673790] LNet: Removed LNI 192.168.206.24@tcp [ 7126.457391] Key type .llcrypt unregistered [ 7126.461170] Key type ._llcrypt unregistered [ 7138.964465] Key type ._llcrypt registered [ 7138.966155] Key type .llcrypt registered [ 7139.301979] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7139.315818] alg: No test for adler32 (adler32-zlib) [ 7140.513774] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [ 7140.811519] LNet: Added LNI 192.168.206.24@tcp [8/256/0/180] [ 7142.511287] Key type lgssc registered [ 7143.878031] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7228.496942] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7234.657565] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7257.014365] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7370.941112] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7376.241929] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7399.213913] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7453.720176] Lustre: DEBUG MARKER: == conf-sanity test 151b: -ENOSPC doesn't affect mount === 12:26:31 (1787847991) [ 7466.487627] Key type lgssc unregistered [ 7466.760617] LNet: 129180:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7466.771436] LNetError: 129180:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7466.793119] LNet: Removed LNI 192.168.206.24@tcp [ 7467.719317] Key type .llcrypt unregistered [ 7467.729165] Key type ._llcrypt unregistered [ 7482.575434] Key type ._llcrypt registered [ 7482.580571] Key type .llcrypt registered [ 7483.389352] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7483.433068] alg: No test for adler32 (adler32-zlib) [ 7484.608200] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [ 7484.960243] LNet: Added LNI 192.168.206.24@tcp [8/256/0/180] [ 7486.746747] Key type lgssc registered [ 7488.400260] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7545.044576] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7552.049636] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7578.393336] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7707.003570] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7712.790174] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7735.812184] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7788.173643] Lustre: DEBUG MARKER: == conf-sanity test 152: seq allocation error in OSP ===== 12:32:06 (1787848326) [ 7877.437091] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 7882.585900] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 7894.969722] Lustre: DEBUG MARKER: ADD OST3 [ 7903.199394] Lustre: lustre-OST0000-osc-ffff9d4103d37800: disconnect after 24s idle [ 7908.124947] Lustre: DEBUG MARKER: STOP OST3 [ 7920.106511] Lustre: lustre-OST0003-osc-ffff9d4103d37800: Connection to lustre-OST0003 (at 192.168.206.124@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7929.711975] Lustre: DEBUG MARKER: START OST3 again [ 7938.904673] Lustre: lustre-OST0003-osc-ffff9d4103d37800: Connection restored to 192.168.206.124@tcp (at 192.168.206.124@tcp) [ 7969.041834] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7969.065859] LustreError: 140009:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff9d4103d37800: inode [0x240000bd0:0x1:0x0] mdc close failed: rc = -108 [ 7979.417561] Lustre: Unmounted lustre-client [ 8101.352886] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8106.441149] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8130.779905] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8176.862965] Lustre: DEBUG MARKER: == conf-sanity test 153a: bypass invalid NIDs quickly ==== 12:38:35 (1787848715) [ 8282.021387] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8287.961620] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8312.409173] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8390.276524] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8396.613021] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8420.477672] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8423.065964] LNetError: 129591:0:(lib-move.c:2158:lnet_handle_find_routed_path()) peer 192.168.252.112@tcp2 has no available nets [ 8423.308747] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 8428.842231] Lustre: Unmounted lustre-client [ 8456.053902] Key type lgssc unregistered [ 8477.215292] LNet: 1 local NIs in recovery (showing 1): 192.168.206.24@tcp [ 8483.039952] LNet: 149000:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8483.053642] LNetError: 149000:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8553.785204] LNet: Removed LNI 192.168.206.24@tcp [ 8554.662532] Key type .llcrypt unregistered [ 8554.666606] Key type ._llcrypt unregistered [ 8572.001366] Key type ._llcrypt registered [ 8572.005565] Key type .llcrypt registered [ 8573.983364] Lustre: DEBUG MARKER: == conf-sanity test 153b: added IPv6 NID support ========= 12:45:11 (1787849111) [ 8595.848562] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8595.880230] alg: No test for adler32 (adler32-zlib) [ 8597.120699] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [ 8597.455937] LNet: Added LNI 192.168.206.24@tcp [8/256/0/180] [ 8599.199301] Key type lgssc registered [ 8600.601085] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8685.743665] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8690.931968] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8713.190777] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8838.352706] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 8843.985482] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 8850.114202] Lustre: Unmounted lustre-client [ 8850.199658] LNetError: 150184:0:(lib-socket.c:308:lnet_sock_create()) Error trying to bind to fe80::54c1:60ff:fef5:f377/1023: rc = -22 [ 8850.221163] LNetError: Unexpected error -22 connecting to 6699:7654::1234:1234:d84@tcp at host [6699:7654::1234:1234:d84]:988 [ 8850.493805] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 8850.651868] Lustre: Unmounted lustre-client [ 8851.275456] LNetError: 150183:0:(lib-socket.c:308:lnet_sock_create()) Error trying to bind to fe80::54c1:60ff:fef5:f377/1023: rc = -22 [ 8851.296799] LNetError: Unexpected error -22 connecting to 6699:7654::1234:1234:d84@tcp at host [6699:7654::1234:1234:d84]:988 [ 8851.524540] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 8851.528197] Lustre: Skipped 1 previous similar message [ 8851.703377] Lustre: Unmounted lustre-client [ 8851.712291] Lustre: Skipped 1 previous similar message [ 8853.309066] LNetError: 150183:0:(lib-socket.c:308:lnet_sock_create()) Error trying to bind to fe80::54c1:60ff:fef5:f377/1023: rc = -22 [ 8853.316974] LNetError: Unexpected error -22 connecting to 6699:7654::1234:1234:d84@tcp at host [6699:7654::1234:1234:d84]:988 [ 8854.062575] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 8854.069100] Lustre: Skipped 4 previous similar messages [ 8854.221584] Lustre: Unmounted lustre-client [ 8854.224236] Lustre: Skipped 4 previous similar messages [ 8854.331284] Lustre: 156111:0:(obd_mount.c:175:lustre_add_mgc_failnodes()) MGC6699:7654::1234:1234:d84@tcp: can't add failover NID 5031:db8:85a3:8d3:1319:8a2e:370:7348@tcp, rc = -75 [ 8854.915255] Lustre: 156142:0:(obd_mount.c:175:lustre_add_mgc_failnodes()) MGC193.168.0.240@tcp: can't add failover NID 5031:db8:85a3:8d3:1319:8a2e:370:7348@tcp, rc = -75 [ 8862.561120] Lustre: DEBUG MARKER: == conf-sanity test 153c: don't stuck on unreached NID === 12:50:01 (1787849401) [ 8910.816990] LNet: 1 local NIs in recovery (showing 1): 192.168.206.24@tcp [ 9004.384920] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9012.106024] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9039.812672] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9108.432644] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9113.529426] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9133.354402] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9138.847735] LustreError: 161743:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.252.112@tcp: failed processing log, type 1: rc = -110 [ 9183.199983] LNet: 1 local NIs in recovery (showing 1): 192.168.206.24@tcp [ 9262.047971] LNet: 1 local NIs in recovery (showing 1): 192.168.206.24@tcp [ 9277.407834] LustreError: MGC192.168.252.112@tcp: Confguration from log lustre-client failed from MGS -110. Communication error between node & MGS, a bad configuration, or other errors. See syslog for more info [ 9277.436399] Lustre: Unmounted lustre-client [ 9277.437992] Lustre: Skipped 2 previous similar messages [ 9277.478854] LustreError: 161743:0:(super25.c:179:lustre_fill_super()) llite: Unable to mount : rc = -110 [ 9306.548590] Key type lgssc unregistered [ 9322.463768] LNet: 1 local NIs in recovery (showing 1): 192.168.206.24@tcp [ 9325.215842] LNet: 162205:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9325.224546] LNetError: 162205:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9393.829538] LNet: Removed LNI 192.168.206.24@tcp [ 9394.807144] Key type .llcrypt unregistered [ 9394.816701] Key type ._llcrypt unregistered [ 9413.043562] Key type ._llcrypt registered [ 9413.045529] Key type .llcrypt registered [ 9414.356548] Lustre: DEBUG MARKER: == conf-sanity test 154: expand .. on rename after MDT backup restore ========================================================== 12:59:12 (1787849952) [ 9432.863697] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9432.888722] alg: No test for adler32 (adler32-zlib) [ 9433.964312] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [ 9434.232436] LNet: Added LNI 192.168.206.24@tcp [8/256/0/180] [ 9435.903177] Key type lgssc registered [ 9437.194751] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9572.310839] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 9577.204409] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 9591.900531] Lustre: Unmounted lustre-client [ 9760.092971] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 9764.736709] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 9770.689097] Lustre: Unmounted lustre-client [ 9825.594281] Lustre: DEBUG MARKER: == conf-sanity test 155: gap in seq allocation from ofd after restarting ========================================================== 13:06:04 (1787850364) [ 9910.769474] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9916.027299] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9937.753837] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10055.747421] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [10061.549844] Lustre: DEBUG MARKER: Using TIMEOUT=20 [10075.987782] Lustre: Unmounted lustre-client [10202.169585] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [10207.322934] Lustre: DEBUG MARKER: Using TIMEOUT=20 [10225.918262] Lustre: DEBUG MARKER: == conf-sanity test 156: root_fid on export consistent with client mount ========================================================== 13:12:44 (1787850764) [10227.515470] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [10237.863733] Lustre: Unmounted lustre-client [10425.825081] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [10430.756301] Lustre: DEBUG MARKER: Using TIMEOUT=20 [10438.921729] Lustre: Unmounted lustre-client [10439.443836] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [10447.645140] Lustre: Unmounted lustre-client [10449.022193] Lustre: DEBUG MARKER: == conf-sanity test 157a: test allow_register for MDT registration and MGS restart ========================================================== 13:16:27 (1787850987) [10519.892574] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10525.963287] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10544.211306] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10544.751995] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [10631.654119] Lustre: lustre-MDT0000-mdc-ffff9d4105e4c000: Connection to lustre-MDT0000 (at 192.168.206.124@tcp) was lost; in progress operations using this service will wait for recovery to complete [10641.899996] LustreError: lustre-OST0001-osc-ffff9d4105e4c000: operation ost_connect to node 192.168.206.124@tcp failed: rc = -19 [10647.021145] LustreError: MGC192.168.206.124@tcp: Connection to MGS (at 192.168.206.124@tcp) was lost; in progress operations using this service will fail [10647.026233] LustreError: lustre-MDT0000-mdc-ffff9d4105e4c000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [10647.044927] Lustre: Evicted from MGS (at 192.168.206.124@tcp) after server handle changed from 0x19abc480a23eefa9 to 0x19abc480a23ef30d [10647.048525] Lustre: lustre-MDT0000-mdc-ffff9d4105e4c000: Connection restored to 192.168.206.124@tcp (at 192.168.206.124@tcp) [10647.062860] Lustre: Skipped 1 previous similar message [10682.291959] Lustre: Unmounted lustre-client [10720.299446] Key type lgssc unregistered [10720.640197] LNet: 189751:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10720.649151] LNetError: 189751:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10720.680535] LNet: Removed LNI 192.168.206.24@tcp [10721.754118] Key type .llcrypt unregistered [10721.755589] Key type ._llcrypt unregistered [10735.398607] Key type ._llcrypt registered [10735.404441] Key type .llcrypt registered [10737.537597] Lustre: DEBUG MARKER: == conf-sanity test 157b: verify allow_register (block new OSTs, allow existing) ========================================================== 13:21:15 (1787851275) [10740.420531] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10740.431842] alg: No test for adler32 (adler32-zlib) [10741.474865] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [10741.736919] LNet: Added LNI 192.168.206.24@tcp [8/256/0/180] [10743.503232] Key type lgssc registered [10745.227034] Lustre: Echo OBD driver; http://www.lustre.org/ [10796.740731] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10802.607372] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10822.288755] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10824.967925] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [10922.469105] LustreError: lustre-OST0001-osc-ffff9d4102a05000: operation ost_connect to node 192.168.206.124@tcp failed: rc = -19 [11027.209305] Lustre: Unmounted lustre-client [11054.972354] Key type lgssc unregistered [11055.249247] LNet: 195776:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11055.255367] LNetError: 195776:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11055.285289] LNet: Removed LNI 192.168.206.24@tcp [11056.461555] Key type .llcrypt unregistered [11056.472304] Key type ._llcrypt unregistered [11068.682439] Key type ._llcrypt registered [11068.685354] Key type .llcrypt registered [11069.912377] Lustre: DEBUG MARKER: == conf-sanity test 160: MGC updates failnodes from all participants ========================================================== 13:26:48 (1787851608) [11100.484419] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11100.510756] alg: No test for adler32 (adler32-zlib) [11101.627323] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [11101.918804] LNet: Added LNI 192.168.206.24@tcp [8/256/0/180] [11103.599760] Key type lgssc registered [11104.818922] Lustre: Echo OBD driver; http://www.lustre.org/ [11211.562141] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11217.613231] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11242.439629] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11291.220642] Key type lgssc unregistered [11291.467848] LNet: 200761:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11291.472810] LNetError: 200761:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11291.496582] LNet: Removed LNI 192.168.206.24@tcp [11292.281933] Key type .llcrypt unregistered [11292.289086] Key type ._llcrypt unregistered [11396.340718] Key type ._llcrypt registered [11396.342052] Key type .llcrypt registered [11396.719294] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11396.736124] alg: No test for adler32 (adler32-zlib) [11397.919305] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [11398.247573] LNet: Added LNI 192.168.206.24@tcp [8/256/0/180] [11399.952073] Key type lgssc registered [11401.706959] Lustre: Echo OBD driver; http://www.lustre.org/ [11453.761456] Lustre: DEBUG MARKER: == conf-sanity test 161: test '-o mgsname' option ======== 13:33:11 (1787851991) [11497.624517] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11504.151680] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11527.633756] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11529.299753] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [11531.411373] Lustre: Unmounted lustre-client [11531.741490] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [11531.989067] Lustre: Unmounted lustre-client [11538.873203] Lustre: Unmounted lustre-client [11568.286601] Key type lgssc unregistered [11568.558209] LNet: 205740:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11568.570066] LNetError: 205740:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11568.652966] LNet: Removed LNI 192.168.206.24@tcp [11570.169574] Key type .llcrypt unregistered [11570.171506] Key type ._llcrypt unregistered [11583.754042] Key type ._llcrypt registered [11583.755771] Key type .llcrypt registered [11585.370367] Lustre: DEBUG MARKER: == conf-sanity test 162: replace nids with -o noclient === 13:35:23 (1787852123) [11589.606228] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11589.639958] alg: No test for adler32 (adler32-zlib) [11591.084653] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [11591.463336] LNet: Added LNI 192.168.206.24@tcp [8/256/0/180] [11593.287851] Key type lgssc registered [11594.977320] Lustre: Echo OBD driver; http://www.lustre.org/ [11659.539176] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11665.591814] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11688.064801] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11700.332834] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [11710.429355] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11712.342492] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [11720.683590] Lustre: lustre-MDT0000-mdc-ffff9d4105e4a000: Connection to lustre-MDT0000 (at 192.168.206.124@tcp) was lost; in progress operations using this service will wait for recovery to complete [11736.041406] Lustre: lustre-MDT0001-mdc-ffff9d4105e4a000: Connection to lustre-MDT0001 (at 192.168.206.124@tcp) was lost; in progress operations using this service will wait for recovery to complete [11747.295371] Lustre: 206413:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787852270/real 1787852270] req@000000001e0fe544 x1874698836122496/t0(0) o400->MGC192.168.206.124@tcp@192.168.206.124@tcp:26/25 lens 224/224 e 0 to 1 dl 1787852286 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [11747.333855] LustreError: MGC192.168.206.124@tcp: Connection to MGS (at 192.168.206.124@tcp) was lost; in progress operations using this service will fail [11781.091972] Lustre: Evicted from MGS (at 192.168.206.124@tcp) after server handle changed from 0x7a117d44f95ba3de to 0x7a117d44f95ba589 [11781.126606] Lustre: MGC192.168.206.124@tcp: Connection restored to 192.168.206.124@tcp (at 192.168.206.124@tcp) [11817.843401] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11819.904684] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in IDLE state after 0 sec [11822.074507] LustreError: lustre-MDT0000-mdc-ffff9d4105e4a000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [11822.103334] Lustre: lustre-MDT0000-mdc-ffff9d4105e4a000: Connection restored to 192.168.206.124@tcp (at 192.168.206.124@tcp) [11826.819461] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11829.166806] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [11831.629466] Lustre: Unmounted lustre-client [11858.924755] Key type lgssc unregistered [11859.280579] LNet: 211285:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11859.293278] LNetError: 211285:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11859.326479] LNet: Removed LNI 192.168.206.24@tcp [11860.592348] Key type .llcrypt unregistered [11860.599875] Key type ._llcrypt unregistered [11895.634425] Key type ._llcrypt registered [11895.648301] Key type .llcrypt registered [11896.180685] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11896.221306] alg: No test for adler32 (adler32-zlib) [11897.656957] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [11897.926360] LNet: Added LNI 192.168.206.24@tcp [8/256/0/180] [11899.575166] Key type lgssc registered [11901.051402] Lustre: Echo OBD driver; http://www.lustre.org/ [11997.443626] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12003.945605] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12033.286670] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12088.698274] Lustre: DEBUG MARKER: == conf-sanity test 164: test expected_clients parameter and max client tracking ========================================================== 13:43:46 (1787852626) [12197.823773] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12203.420500] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12225.524631] Lustre: DEBUG MARKER: oleg624-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12227.208913] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [12231.646286] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [12232.794838] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [12232.799791] Lustre: Skipped 1 previous similar message [12236.819222] Lustre: Unmounted lustre-client [12238.071470] Lustre: Unmounted lustre-client [12240.780952] Lustre: Unmounted lustre-client [12242.958536] Lustre: Unmounted lustre-client [12256.348587] Lustre: Unmounted lustre-client [12256.350994] Lustre: Skipped 1 previous similar message [12284.743806] Key type lgssc unregistered [12285.074775] LNet: 220454:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12285.080144] LNetError: 220454:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12285.092209] LNet: Removed LNI 192.168.206.24@tcp [12285.661367] Key type .llcrypt unregistered [12285.665288] Key type ._llcrypt unregistered [12299.644265] Key type ._llcrypt registered [12299.648385] Key type .llcrypt registered [12301.440825] Lustre: DEBUG MARKER: == conf-sanity test 200a: check CPU partitions =========== 13:47:19 (1787852839) [12314.037757] Key type .llcrypt unregistered [12314.041611] Key type ._llcrypt unregistered [12315.089053] Key type ._llcrypt registered [12315.094193] Key type .llcrypt registered [12315.413679] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 4 [12315.447771] alg: No test for adler32 (adler32-zlib) [12316.676723] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [12316.921802] LNet: Added LNI 192.168.206.24@tcp [8/256/0/180] [12318.583190] Key type lgssc registered [12320.040459] Lustre: Echo OBD driver; http://www.lustre.org/ [12345.249248] Key type lgssc unregistered [12345.491272] LNet: 222617:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12345.503334] LNetError: 222617:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12345.516956] LNet: Removed LNI 192.168.206.24@tcp [12346.192428] Key type .llcrypt unregistered [12346.195467] Key type ._llcrypt unregistered [12346.718928] Key type ._llcrypt registered [12346.726360] Key type .llcrypt registered [12348.194961] Lustre: DEBUG MARKER: == conf-sanity test 200b: set CPU pattern using core selection ========================================================== 13:48:06 (1787852886) [12360.706289] Key type .llcrypt unregistered [12360.708910] Key type ._llcrypt unregistered [12361.751974] Key type ._llcrypt registered [12361.761045] Key type .llcrypt registered [12362.205093] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12362.228456] alg: No test for adler32 (adler32-zlib) [12363.411274] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [12363.800481] LNet: Added LNI 192.168.206.24@tcp [8/256/0/180] [12365.535263] Key type lgssc registered [12366.700147] Lustre: Echo OBD driver; http://www.lustre.org/ [12380.191693] Key type lgssc unregistered [12380.420416] LNet: 224176:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12380.430521] LNetError: 224176:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12380.449043] LNet: Removed LNI 192.168.206.24@tcp [12380.984613] Key type .llcrypt unregistered [12380.990342] Key type ._llcrypt unregistered [12387.706121] Key type ._llcrypt registered [12387.712329] Key type .llcrypt registered [12388.106798] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12388.115526] alg: No test for adler32 (adler32-zlib) [12389.261374] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [12389.468782] LNet: Added LNI 192.168.206.24@tcp [8/256/0/180] [12391.159184] Key type lgssc registered [12392.299459] Lustre: Echo OBD driver; http://www.lustre.org/ [12416.400849] Key type lgssc unregistered [12416.623691] LNet: 225941:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12416.630493] LNetError: 225941:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12416.649844] LNet: Removed LNI 192.168.206.24@tcp [12417.382216] Key type .llcrypt unregistered [12417.385464] Key type ._llcrypt unregistered [12417.784752] Key type ._llcrypt registered [12417.787145] Key type .llcrypt registered [12418.800796] Lustre: DEBUG MARKER: == conf-sanity test 200c: set CPU pattern using NUMA node layout ========================================================== 13:49:17 (1787852957) [12428.585715] Key type .llcrypt unregistered [12428.587333] Key type ._llcrypt unregistered [12429.575760] Key type ._llcrypt registered [12429.590478] Key type .llcrypt registered [12429.898509] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12429.921298] alg: No test for adler32 (adler32-zlib) [12430.998643] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [12431.236757] LNet: Added LNI 192.168.206.24@tcp [8/256/0/180] [12432.927740] Key type lgssc registered [12434.017902] Lustre: Echo OBD driver; http://www.lustre.org/ [12449.197381] Key type lgssc unregistered [12449.573188] LNet: 227494:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12449.603288] LNetError: 227494:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12449.638076] LNet: Removed LNI 192.168.206.24@tcp [12450.412297] Key type .llcrypt unregistered [12450.417118] Key type ._llcrypt unregistered [12458.325178] Key type ._llcrypt registered [12458.347471] Key type .llcrypt registered [12458.682777] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12458.692646] alg: No test for adler32 (adler32-zlib) [12459.969490] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [12460.268223] LNet: Added LNI 192.168.206.24@tcp [8/256/0/180] [12462.023211] Key type lgssc registered [12463.745712] Lustre: Echo OBD driver; http://www.lustre.org/ [12479.215780] Key type lgssc unregistered [12479.469821] LNet: 228803:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12479.484176] LNetError: 228803:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12479.505876] LNet: Removed LNI 192.168.206.24@tcp [12480.057677] Key type .llcrypt unregistered [12480.060523] Key type ._llcrypt unregistered [12487.032399] Key type ._llcrypt registered [12487.054238] Key type .llcrypt registered [12487.420539] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12487.443509] alg: No test for adler32 (adler32-zlib) [12488.469553] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [12488.726049] LNet: Added LNI 192.168.206.24@tcp [8/256/0/180] [12490.439192] Key type lgssc registered [12491.518597] Lustre: Echo OBD driver; http://www.lustre.org/ [12508.737468] Key type lgssc unregistered [12509.159358] LNet: 230110:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12509.172729] LNetError: 230110:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12509.204284] LNet: Removed LNI 192.168.206.24@tcp [12510.151897] Key type .llcrypt unregistered [12510.158987] Key type ._llcrypt unregistered [12521.470618] Key type ._llcrypt registered [12521.472762] Key type .llcrypt registered [12521.695299] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12521.705427] alg: No test for adler32 (adler32-zlib) [12522.735568] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [12523.122411] LNet: Added LNI 192.168.206.24@tcp [8/256/0/180] [12524.871634] Key type lgssc registered [12526.357656] Lustre: Echo OBD driver; http://www.lustre.org/ [12550.462421] Key type lgssc unregistered [12550.750251] LNet: 231881:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12550.758609] LNetError: 231881:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12550.774109] LNet: Removed LNI 192.168.206.24@tcp [12551.544493] Key type .llcrypt unregistered [12551.550380] Key type ._llcrypt unregistered [12551.886988] Key type ._llcrypt registered [12551.890112] Key type .llcrypt registered [12553.360647] Lustre: DEBUG MARKER: == conf-sanity test 200d: set CPU pattern to exclude only CPU 1 ========================================================== 13:51:31 (1787853091) [12564.218720] Key type .llcrypt unregistered [12564.222747] Key type ._llcrypt unregistered [12565.215783] Key type ._llcrypt registered [12565.221260] Key type .llcrypt registered [12565.682596] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12565.693868] alg: No test for adler32 (adler32-zlib) [12566.919444] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [12567.174499] LNet: Added LNI 192.168.206.24@tcp [8/256/0/180] [12568.887177] Key type lgssc registered [12570.209532] Lustre: Echo OBD driver; http://www.lustre.org/ [12586.897332] Key type lgssc unregistered [12587.296437] LNet: 233439:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12587.307644] LNetError: 233439:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12587.350881] LNet: Removed LNI 192.168.206.24@tcp [12588.183820] Key type .llcrypt unregistered [12588.189797] Key type ._llcrypt unregistered [12596.535752] Key type ._llcrypt registered [12596.543331] Key type .llcrypt registered [12597.335495] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12597.379419] alg: No test for adler32 (adler32-zlib) [12598.429969] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [12598.672404] LNet: Added LNI 192.168.206.24@tcp [8/256/0/180] [12600.472095] Key type lgssc registered [12602.122746] Lustre: Echo OBD driver; http://www.lustre.org/ [12619.161765] Key type lgssc unregistered [12619.452965] LNet: 234757:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12619.460751] LNetError: 234757:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12619.484339] LNet: Removed LNI 192.168.206.24@tcp [12620.289800] Key type .llcrypt unregistered [12620.292372] Key type ._llcrypt unregistered [12627.651131] Key type ._llcrypt registered [12627.659091] Key type .llcrypt registered [12628.073017] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12628.084825] alg: No test for adler32 (adler32-zlib) [12629.227570] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [12629.544733] LNet: Added LNI 192.168.206.24@tcp [8/256/0/180] [12631.247182] Key type lgssc registered [12632.740205] Lustre: Echo OBD driver; http://www.lustre.org/ [12649.046567] Key type lgssc unregistered [12649.281464] LNet: 236066:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12649.288551] LNetError: 236066:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12649.308816] LNet: Removed LNI 192.168.206.24@tcp [12649.946114] Key type .llcrypt unregistered [12649.948362] Key type ._llcrypt unregistered [12656.474217] Key type ._llcrypt registered [12656.507075] Key type .llcrypt registered [12656.829316] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12656.847709] alg: No test for adler32 (adler32-zlib) [12657.991315] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [12658.323909] LNet: Added LNI 192.168.206.24@tcp [8/256/0/180] [12660.191177] Key type lgssc registered [12661.651938] Lustre: Echo OBD driver; http://www.lustre.org/ [12678.944937] Key type lgssc unregistered [12679.170709] LNet: 237380:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12679.181182] LNetError: 237380:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12679.207765] LNet: Removed LNI 192.168.206.24@tcp [12679.847643] Key type .llcrypt unregistered [12679.852389] Key type ._llcrypt unregistered [12687.064221] Key type ._llcrypt registered [12687.066412] Key type .llcrypt registered [12687.587339] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12687.601090] alg: No test for adler32 (adler32-zlib) [12688.779103] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [12689.042741] LNet: Added LNI 192.168.206.24@tcp [8/256/0/180] [12690.711418] Key type lgssc registered [12692.038738] Lustre: Echo OBD driver; http://www.lustre.org/ [12709.115582] Key type lgssc unregistered [12709.336984] LNet: 238690:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12709.343982] LNetError: 238690:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12709.354885] LNet: Removed LNI 192.168.206.24@tcp [12709.995915] Key type .llcrypt unregistered [12709.999885] Key type ._llcrypt unregistered [12717.391435] Key type ._llcrypt registered [12717.403301] Key type .llcrypt registered [12717.950517] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12717.998107] alg: No test for adler32 (adler32-zlib) [12719.266247] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [12719.557881] LNet: Added LNI 192.168.206.24@tcp [8/256/0/180] [12721.247334] Key type lgssc registered [12722.685816] Lustre: Echo OBD driver; http://www.lustre.org/ [12737.801797] Key type lgssc unregistered [12738.085852] LNet: 239996:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12738.092125] LNetError: 239996:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12738.116190] LNet: Removed LNI 192.168.206.24@tcp [12738.843989] Key type .llcrypt unregistered [12738.850226] Key type ._llcrypt unregistered [12764.089194] Key type ._llcrypt registered [12764.094453] Key type .llcrypt registered [12766.316607] Lustre: DEBUG MARKER: == conf-sanity test 200e: set CPU pattern using relative core exclusion ========================================================== 13:55:03 (1787853303) [12781.657524] Key type .llcrypt unregistered [12781.660960] Key type ._llcrypt unregistered [12783.105074] Key type ._llcrypt registered [12783.116291] Key type .llcrypt registered [12784.127305] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12784.162793] alg: No test for adler32 (adler32-zlib) [12785.801509] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [12786.172487] LNet: Added LNI 192.168.206.24@tcp [8/256/0/180] [12787.945124] Key type lgssc registered [12789.565983] Lustre: Echo OBD driver; http://www.lustre.org/ [12806.772879] Key type lgssc unregistered [12807.108783] LNet: 242326:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12807.115246] LNetError: 242326:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12807.125712] LNet: Removed LNI 192.168.206.24@tcp [12807.755627] Key type .llcrypt unregistered [12807.757922] Key type ._llcrypt unregistered [12815.045275] Key type ._llcrypt registered [12815.058361] Key type .llcrypt registered [12815.481583] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12815.508273] alg: No test for adler32 (adler32-zlib) [12816.603283] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [12816.932598] LNet: Added LNI 192.168.206.24@tcp [8/256/0/180] [12818.688542] Key type lgssc registered [12820.024327] Lustre: Echo OBD driver; http://www.lustre.org/ [12835.828993] Key type lgssc unregistered [12836.077670] LNet: 243647:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12836.086635] LNetError: 243647:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12836.102839] LNet: Removed LNI 192.168.206.24@tcp [12836.630743] Key type .llcrypt unregistered [12836.632629] Key type ._llcrypt unregistered [12843.881147] Key type ._llcrypt registered [12843.883265] Key type .llcrypt registered [12844.449890] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12844.478027] alg: No test for adler32 (adler32-zlib) [12845.520722] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [12845.789782] LNet: Added LNI 192.168.206.24@tcp [8/256/0/180] [12847.503186] Key type lgssc registered [12848.626986] Lustre: Echo OBD driver; http://www.lustre.org/ [12865.271166] Key type lgssc unregistered [12865.538488] LNet: 244961:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12865.549343] LNetError: 244961:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12865.573443] LNet: Removed LNI 192.168.206.24@tcp [12866.142323] Key type .llcrypt unregistered [12866.145396] Key type ._llcrypt unregistered [12873.084800] Key type ._llcrypt registered [12873.087637] Key type .llcrypt registered [12873.660286] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12873.667866] alg: No test for adler32 (adler32-zlib) [12874.729693] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [12875.033858] LNet: Added LNI 192.168.206.24@tcp [8/256/0/180] [12876.767227] Key type lgssc registered [12877.732718] Lustre: Echo OBD driver; http://www.lustre.org/ [12901.209925] Key type lgssc unregistered [12901.519463] LNet: 246754:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12901.529747] LNetError: 246754:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12901.545904] LNet: Removed LNI 192.168.206.24@tcp [12902.550796] Key type .llcrypt unregistered [12902.553830] Key type ._llcrypt unregistered [12903.221319] Key type ._llcrypt registered [12903.223949] Key type .llcrypt registered [12904.737210] Lustre: DEBUG MARKER: == conf-sanity test 250: verify lcfg_erase removes filesystem parameters ========================================================== 13:57:22 (1787853442) [12922.616724] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12922.638398] alg: No test for adler32 (adler32-zlib) [12923.815731] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [12924.137748] LNet: Added LNI 192.168.206.24@tcp [8/256/0/180] [12925.887462] Key type lgssc registered [12927.166384] Lustre: Echo OBD driver; http://www.lustre.org/ [13164.571881] Lustre: DEBUG MARKER: == conf-sanity test 802a: simulate readonly device ======= 14:01:43 (1787853703) [13166.161869] Lustre: DEBUG MARKER: SKIP: conf-sanity test_802a ZFS specific test [13220.352080] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 12991 sec ========= 14:02:38 (1787853758) [13221.710937] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 14:02:40 (1787853760) === [13224.424473] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 14:02:43 (1787853763) === [13254.103775] Key type lgssc unregistered [13254.343768] LNet: 255834:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13254.348732] LNetError: 255834:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13254.373690] LNet: Removed LNI 192.168.206.24@tcp [13254.939761] Key type .llcrypt unregistered [13254.947775] Key type ._llcrypt unregistered