[ 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 849318356 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.001018] APIC: Switch to symmetric I/O mode setup [ 0.002385] x2apic enabled [ 0.003016] Switched APIC routing to physical x2apic. [ 0.004023] kvm-guest: setup PV IPIs [ 0.008381] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.009000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.009032] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.010020] pid_max: default: 32768 minimum: 301 [ 0.011150] LSM: Security Framework initializing [ 0.012084] Yama: becoming mindful. [ 0.013082] SELinux: Initializing. [ 0.015714] *** VALIDATE selinux *** [ 0.025877] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.031231] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.032200] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.034034] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.035161] *** VALIDATE tmpfs *** [ 0.037387] *** VALIDATE proc *** [ 0.039242] *** VALIDATE cgroup *** [ 0.040015] *** VALIDATE cgroup2 *** [ 0.042007] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.043182] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.044012] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.045040] Spectre V2 : User space: Vulnerable [ 0.046015] Speculative Store Bypass: Vulnerable [ 0.049520] debug: unmapping init [mem 0xffffffffac659000-0xffffffffac660fff] [ 0.051319] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.052747] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.053032] ... version: 2 [ 0.054015] ... bit width: 48 [ 0.055020] ... generic registers: 4 [ 0.056016] ... value mask: 0000ffffffffffff [ 0.057178] ... max period: 00007fffffffffff [ 0.058023] ... fixed-purpose events: 3 [ 0.059019] ... event mask: 000000070000000f [ 0.060473] rcu: Hierarchical SRCU implementation. [ 0.063064] smp: Bringing up secondary CPUs ... [ 0.064805] x86: Booting SMP configuration: [ 0.065056] .... node #0, CPUs: #1 #2 #3 [ 0.079443] smp: Brought up 1 node, 4 CPUs [ 0.081021] smpboot: Max logical packages: 1 [ 0.082020] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.170523] node 0 deferred pages initialised in 86ms [ 0.175588] devtmpfs: initialized [ 0.176395] x86/mm: Memory block size: 128MB [ 0.182066] gcov: version magic: 0x41383552 [ 0.188000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.190098] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.198752] pinctrl core: initialized pinctrl subsystem [ 0.203254] [ 0.205011] ************************************************************* [ 0.212029] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.219015] ** ** [ 0.226019] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.229019] ** ** [ 0.235022] ** This means that this kernel is built to expose internal ** [ 0.241023] ** IOMMU data structures, which may compromise security on ** [ 0.244071] ** your system. ** [ 0.247020] ** ** [ 0.255020] ** If you see this message and you are not debugging the ** [ 0.259026] ** kernel, report this immediately to your vendor! ** [ 0.262029] ** ** [ 0.264067] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.266025] ************************************************************* [ 0.276524] NET: Registered protocol family 16 [ 0.280000] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.286087] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.291091] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.299918] cpuidle: using governor menu [ 0.304035] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.308000] PCI: Using configuration type 1 for base access [ 0.312158] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.331132] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.332028] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.334086] cryptd: max_cpu_qlen set to 1000 [ 0.339321] ACPI: Added _OSI(Module Device) [ 0.345050] ACPI: Added _OSI(Processor Device) [ 0.349032] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.353022] ACPI: Added _OSI(Processor Aggregator Device) [ 0.366209] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.382530] ACPI: Interpreter enabled [ 0.386120] ACPI: PM: (supports S0 S3 S4 S5) [ 0.392021] ACPI: Using IOAPIC for interrupt routing [ 0.396143] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.408465] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.445000] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.447059] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.451031] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.455102] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.464108] acpiphp: Slot [2] registered [ 0.466224] acpiphp: Slot [5] registered [ 0.472364] acpiphp: Slot [6] registered [ 0.475903] acpiphp: Slot [3] registered [ 0.480174] acpiphp: Slot [4] registered [ 0.484309] acpiphp: Slot [7] registered [ 0.492231] acpiphp: Slot [8] registered [ 0.493158] acpiphp: Slot [9] registered [ 0.494197] acpiphp: Slot [10] registered [ 0.498161] acpiphp: Slot [11] registered [ 0.502897] acpiphp: Slot [12] registered [ 0.506183] acpiphp: Slot [13] registered [ 0.511191] acpiphp: Slot [14] registered [ 0.512141] acpiphp: Slot [15] registered [ 0.517164] acpiphp: Slot [16] registered [ 0.522206] acpiphp: Slot [17] registered [ 0.525196] acpiphp: Slot [18] registered [ 0.531180] acpiphp: Slot [19] registered [ 0.534472] acpiphp: Slot [20] registered [ 0.539842] acpiphp: Slot [21] registered [ 0.544172] acpiphp: Slot [22] registered [ 0.548239] acpiphp: Slot [23] registered [ 0.553209] acpiphp: Slot [24] registered [ 0.556132] acpiphp: Slot [25] registered [ 0.558166] acpiphp: Slot [26] registered [ 0.560156] acpiphp: Slot [27] registered [ 0.564182] acpiphp: Slot [28] registered [ 0.568680] acpiphp: Slot [29] registered [ 0.573163] acpiphp: Slot [30] registered [ 0.576194] acpiphp: Slot [31] registered [ 0.578129] PCI host bridge to bus 0000:00 [ 0.580033] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.583032] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.590038] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.596034] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.602033] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.606035] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.611900] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.617791] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.624166] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.635021] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.647289] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.657032] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.661026] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.665026] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.671703] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.678017] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.680060] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.684753] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.692964] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.712022] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.717023] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.726015] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.734017] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.742021] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.758021] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.772392] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.782020] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.793840] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.807022] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.824622] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.829572] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.832419] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.836522] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.839288] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.845078] iommu: Default domain type: Passthrough [ 0.846000] SCSI subsystem initialized [ 0.847413] ACPI: bus type USB registered [ 0.850172] usbcore: registered new interface driver usbfs [ 0.853106] usbcore: registered new interface driver hub [ 0.855123] usbcore: registered new device driver usb [ 0.857220] pps_core: LinuxPPS API ver. 1 registered [ 0.860014] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.864078] PTP clock support registered [ 0.868110] EDAC MC: Ver: 3.0.0 [ 0.869000] PCI: Using ACPI for IRQ routing [ 0.871548] NetLabel: Initializing [ 0.875017] NetLabel: domain hash size = 128 [ 0.878016] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.881299] NetLabel: unlabeled traffic allowed by default [ 0.885835] vgaarb: loaded [ 0.888004] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.891019] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.896793] clocksource: Switched to clocksource kvm-clock [ 1.119598] VFS: Disk quotas dquot_6.6.0 [ 1.122465] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 1.130077] *** VALIDATE ramfs *** [ 1.131375] *** VALIDATE hugetlbfs *** [ 1.134482] pnp: PnP ACPI init [ 1.140738] pnp: PnP ACPI: found 6 devices [ 1.170809] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 1.174134] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 1.179480] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 1.184780] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 1.187494] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 1.193064] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 1.196065] NET: Registered protocol family 2 [ 1.201909] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 1.209973] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 1.216747] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 1.225474] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 1.232774] TCP: Hash tables configured (established 65536 bind 65536) [ 1.238934] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 1.245296] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 1.252399] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 1.262159] NET: Registered protocol family 1 [ 1.266205] RPC: Registered named UNIX socket transport module. [ 1.270578] RPC: Registered udp transport module. [ 1.275829] RPC: Registered tcp transport module. [ 1.277631] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 1.283905] NET: Registered protocol family 44 [ 1.285888] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 1.290076] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 1.294427] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 1.298987] PCI: CLS 0 bytes, default 64 [ 1.304278] Unpacking initramfs... [ 4.732208] debug: unmapping init [mem 0xffff96f03cc64000-0xffff96f03ffcffff] [ 4.740420] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 4.745523] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 4.750426] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 6.375875] Initialise system trusted keyrings [ 6.377857] Key type blacklist registered [ 6.381364] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 6.399357] zbud: loaded [ 6.405684] *** VALIDATE nfs *** [ 6.406940] *** VALIDATE nfs4 *** [ 6.412417] pstore: using deflate compression [ 6.418154] Platform Keyring initialized [ 6.668769] NET: Registered protocol family 38 [ 6.670939] Key type asymmetric registered [ 6.673200] Asymmetric key parser 'x509' registered [ 6.675403] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 6.679260] io scheduler mq-deadline registered [ 6.681365] io scheduler kyber registered [ 6.683649] io scheduler bfq registered [ 6.685784] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 6.689239] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 6.692875] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 6.696397] ACPI: Power Button [PWRF] [ 6.704653] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 6.713102] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 6.733788] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 6.778747] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 6.829762] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 6.838665] Non-volatile memory driver v1.3 [ 6.841141] Linux agpgart interface v0.103 [ 6.903081] virtio_blk virtio1: [vda] 146656 512-byte logical blocks (75.1 MB/71.6 MiB) [ 6.913618] vda: detected capacity change from 0 to 75087872 [ 6.941747] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 6.948270] vdb: detected capacity change from 0 to 1073741824 [ 6.971394] libphy: Fixed MDIO Bus: probed [ 6.984916] usbcore: registered new interface driver usbserial_generic [ 6.990507] usbserial: USB Serial support registered for generic [ 6.994476] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 7.003835] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 7.007346] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 7.011855] mousedev: PS/2 mouse device common for all mice [ 7.017532] rtc_cmos 00:05: RTC can wake from S4 [ 7.020195] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 7.024407] rtc_cmos 00:05: registered as rtc0 [ 7.024469] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 7.024505] intel_pstate: CPU model not supported [ 7.029452] hid: raw HID events driver (C) Jiri Kosina [ 7.057060] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 7.060147] usbcore: registered new interface driver usbhid [ 7.070398] usbhid: USB HID core driver [ 7.072954] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 7.079257] drop_monitor: Initializing network drop monitor service [ 7.092814] Initializing XFRM netlink socket [ 7.096760] NET: Registered protocol family 10 [ 7.103179] Segment Routing with IPv6 [ 7.105108] NET: Registered protocol family 17 [ 7.109138] mpls_gso: MPLS GSO support [ 7.121803] RAS: Correctable Errors collector initialized. [ 7.126508] AVX version of gcm_enc/dec engaged. [ 7.129084] AES CTR mode by8 optimization enabled [ 7.290748] sched_clock: Marking stable (7290198331, 0)->(8917801862, -1627603531) [ 7.301883] registered taskstats version 1 [ 7.312124] Loading compiled-in X.509 certificates [ 7.320163] zswap: loaded using pool lzo/zbud [ 7.417260] Key type big_key registered [ 7.457633] Key type encrypted registered [ 7.461909] ima: No TPM chip found, activating TPM-bypass! [ 7.467936] ima: Allocated hash algorithm: sha1 [ 7.472536] ima: No architecture policies found [ 7.477849] evm: Initialising EVM extended attributes: [ 7.486652] evm: security.selinux [ 7.490174] evm: security.ima [ 7.493284] evm: security.capability [ 7.497989] evm: HMAC attrs: 0x1 [ 7.513883] rtc_cmos 00:05: setting system clock to 2026-09-08 01:17:44 UTC (1788830264) [ 7.537445] debug: unmapping init [mem 0xffffffffad603000-0xffffffffad7fffff] [ 7.547311] debug: unmapping init [mem 0xffffffffac382000-0xffffffffac658fff] [ 7.558133] Write protecting the kernel read-only data: 28672k [ 7.567817] debug: unmapping init [mem 0xffffffffaaa03000-0xffffffffaabfffff] [ 7.575669] debug: unmapping init [mem 0xffffffffab314000-0xffffffffab3fffff] [ 7.713357] 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) [ 7.736832] systemd[1]: Detected virtualization kvm. [ 7.740376] systemd[1]: Detected architecture x86-64. [ 7.743176] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 7.787735] systemd[1]: No hostname configured. [ 7.789403] systemd[1]: Set hostname to . [ 7.793523] random: systemd: uninitialized urandom read (16 bytes read) [ 7.797278] systemd[1]: Initializing machine ID from random generator. [ 7.957425] random: ln: uninitialized urandom read (6 bytes read) [ 8.211294] random: systemd: uninitialized urandom read (16 bytes read) [ 8.219161] systemd[1]: Reached target Timers. [ OK ] Reached target Timers. [ 8.231902] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ 8.238800] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. Starting Apply Kernel Variables... [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on udev Control Socket. [ OK ] Started Memstrack Anylazing Service. [ OK ] Reached target Sockets. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. Starting Setup Virtual Console... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Slices. [ OK ] Reached target Swap. Starting Journal Service... [ OK ] Reached target Initrd Root Device. [ 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 Create Volatile Files and Directories. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 9.963222] device-mapper: uevent: version 1.0.3 [ 9.965471] 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. [ 11.895010] hrtimer: interrupt took 7553466 ns [ 11.996269] virtio_net virtio0 ens2: renamed from eth0 [ 12.509879] scsi host0: ata_piix [ 12.670779] scsi host1: ata_piix [ 12.672298] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 12.674797] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 18.030872] random: crng init done [ 18.037285] random: 7 urandom warning(s) missed due to ratelimiting [ 20.995711] dracut-initqueue[582]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 24.365298] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped target Timers. [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Sockets. [ OK ] Stopped target Paths. [ OK ] Stopped target System Initialization. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Swap. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped target Slices. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 27.843228] printk: systemd: 26 output lines suppressed due to ratelimiting [ 28.560763] SELinux: Disabled at runtime. [ 28.644534] 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) [ 28.664469] systemd[1]: Detected virtualization kvm. [ 28.668257] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 30.527615] systemd[1]: initrd-switch-root.service: Succeeded. [ 30.539573] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 30.561124] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 30.571617] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 30.579396] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 30.598880] systemd[1]: Starting Journal Service... Starting Journal Service... [ 30.629362] systemd[1]: Starting Remount Root and Kernel File Systems... Starting Remount Root and Kernel File Systems... Activating swap /dev/disk/by-label/SWAP... [ OK ] Listening on Process Core Dump Socket. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [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 ] Reached target rpc_pipefs.target. [ 30.803669] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS Mounting Huge Pages File System... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Created slice User and Session Slice. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. [ OK ] Reached target Slices. [ OK ] Listening on udev Kernel Socket. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. Mounting POSIX Message Queue File System... [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Created slice system-getty.slice. [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... [ OK ] Stopped target Initrd Root File System. Mounting Kernel Debug File System... Starting Apply Kernel Variables... [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Started Journal Service. [ 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 ] Mounted Kernel Debug File System. [ OK ] Started Apply Kernel Variables. [ OK ] Reached target Swap. Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... Starting Configure read-only root support... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... [ OK ] Started udev Coldplug all Devices. [ OK ] Mounted /mnt. [ 32.867564] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 34.419955] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 34.442910] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 35.079410] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 35.266703] EDAC sbridge: Ver: 1.1.2 [* ] A start job is running for Configur…-only root support (8s / no limit) [** ] A start job is running for Configur…-only root support (9s / no limit) [*** ] A start job is running for Configur…-only root support (9s / no limit)[ 40.264438] Key type dns_resolver registered [ *** ] A start job is running for Configur…only root support (10s / no limit)[ 41.096412] NFS: Registering the id_resolver key type [ 41.098537] Key type id_resolver registered [ 41.100367] Key type id_legacy registered [ *** ] A start job is running for Configur…only root support (10s / no limit) [ ***] A start job is running for Configur…only root support (11s / no limit) [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Mark the need to relabel after reboot... Starting Rebuild Dynamic Linker Cache... Starting Load/Save Random Seed... Starting Create Volatile Files and Directories... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Load/Save Random Seed. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Basic System. Starting Login Service... Starting Restore /run/initramfs on shutdown... [ OK ] Started irqbalance daemon. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Reached target sshd-keygen.target. [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting Dynamic System Tuning Daemon... Starting OpenSSH server daemon... Starting GSSAPI Proxy Daemon... Starting Network Manager Wait Online... Starting Hostname Service... [ 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... [ OK ] Started Permit User Sessions. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS1. [ OK ] Reached target Login Prompts. [ OK ] Started Command Scheduler. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Crash recovery kernel arming... Starting Notify NFS peers of a restart... Starting System Logging Service... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg402-client login: [ 99.535417] libcfs: loading out-of-tree module taints kernel. [ 99.677617] Key type ._llcrypt registered [ 99.679771] Key type .llcrypt registered [ 100.099524] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 100.121241] alg: No test for adler32 (adler32-zlib) [ 101.313384] Lustre: Lustre: Build Version: 2.17.57_104_ga33798f [ 101.919912] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [ 103.649115] Key type lgssc registered [ 105.287435] Lustre: Echo OBD driver; http://www.lustre.org/ [ 230.314914] Lustre: Mounted lustre-client - version 2.17.57_104_ga33798f [ 234.416517] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 246.693286] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing check_logdir /tmp/testlogs/ [ 250.504940] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing yml_node [ 253.694265] Lustre: DEBUG MARKER: Client: 2.17.57.104 [ 255.455933] Lustre: DEBUG MARKER: MDS: 2.17.57.104 [ 255.968606] Lustre: lustre-OST0000-osc-ffff96f082a21000: disconnect after 23s idle [ 257.458449] Lustre: DEBUG MARKER: OSS: 2.17.57.104 [ 258.835517] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Mon Sep 7 21:21:54 EDT 2026 [ 271.552517] Lustre: DEBUG MARKER: excepting tests: 32newtarball [ 272.815838] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 274.751334] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 285.063213] Lustre: Unmounted lustre-client [ 380.689769] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 385.679761] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 406.099673] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 447.262897] Lustre: DEBUG MARKER: == conf-sanity test 121: failover MGS ==================== 21:25:03 (1788830703) [ 479.353593] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 483.446736] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 512.760584] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mgc.*.mgs_server_uuid 1475 0 [ 549.433888] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 553.384711] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 594.741543] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mgc.*.mgs_server_uuid 1475 0 [ 636.822398] Lustre: DEBUG MARKER: == conf-sanity test 122a: Check OST sequence update ====== 21:28:11 (1788830891) [ 758.179236] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 764.927342] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 788.032419] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 788.645868] Lustre: Mounted lustre-client - version 2.17.57_104_ga33798f [ 811.668692] Lustre: Unmounted lustre-client [ 848.204245] Key type lgssc unregistered [ 848.538227] LNet: 21859:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 848.546105] LNetError: 21859:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 848.553683] LNet: Removed LNI 192.168.204.2@tcp [ 849.480697] Key type .llcrypt unregistered [ 849.486477] Key type ._llcrypt unregistered [ 865.690843] Key type ._llcrypt registered [ 865.693845] Key type .llcrypt registered [ 867.178417] Lustre: DEBUG MARKER: == conf-sanity test 123aa: llog_print works with FIDs and simple names ========================================================== 21:32:02 (1788831122) [ 870.434297] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 870.530993] alg: No test for adler32 (adler32-zlib) [ 871.731493] Lustre: Lustre: Build Version: 2.17.57_104_ga33798f [ 871.988347] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [ 873.687230] Key type lgssc registered [ 875.309526] Lustre: Echo OBD driver; http://www.lustre.org/ [ 933.483690] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 939.922221] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 963.289824] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 966.216397] Lustre: Mounted lustre-client - version 2.17.57_104_ga33798f [ 979.097360] Lustre: DEBUG MARKER: == conf-sanity test 123ab: llog_print params output values from set_param -P ========================================================== 21:33:54 (1788831234) [ 991.162893] Lustre: DEBUG MARKER: == conf-sanity test 123ac: llog_print with --start and --end ========================================================== 21:34:06 (1788831246) [ 1001.313511] Lustre: DEBUG MARKER: == conf-sanity test 123ad: llog_print shows all records == 21:34:16 (1788831256) [ 1015.093591] Lustre: DEBUG MARKER: == conf-sanity test 123ae: llog_cancel can cancel requested record ========================================================== 21:34:30 (1788831270) [ 1042.822666] Lustre: DEBUG MARKER: == conf-sanity test 123af: llog_catlist can show all config files correctly ========================================================== 21:34:57 (1788831297) [ 1061.144392] Lustre: DEBUG MARKER: == conf-sanity test 123ag: llog_print skips values deleted by set_param -P -d ========================================================== 21:35:16 (1788831316) [ 1078.548764] Lustre: DEBUG MARKER: == conf-sanity test 123ah: del_ost cancels config log entries correctly ========================================================== 21:35:33 (1788831333) [ 1088.993172] Lustre: Unmounted lustre-client [ 1089.532927] Lustre: Mounted lustre-client - version 2.17.57_104_ga33798f [ 1092.574522] Lustre: Unmounted lustre-client [ 1120.534431] Key type lgssc unregistered [ 1120.769173] LNet: 30234:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1120.779695] LNetError: 30234:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1120.802759] LNet: Removed LNI 192.168.204.2@tcp [ 1121.651194] Key type .llcrypt unregistered [ 1121.653387] Key type ._llcrypt unregistered [ 1150.410557] Key type ._llcrypt registered [ 1150.413560] Key type .llcrypt registered [ 1151.085271] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1151.101358] alg: No test for adler32 (adler32-zlib) [ 1152.153363] Lustre: Lustre: Build Version: 2.17.57_104_ga33798f [ 1152.460270] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [ 1154.151879] Key type lgssc registered [ 1155.513472] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1246.096966] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1252.086480] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1278.492718] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1324.119545] Lustre: DEBUG MARKER: == conf-sanity test 123ai: llog_print display all non skipped records ========================================================== 21:39:39 (1788831579) [ 1361.022840] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1368.134616] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1394.147448] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1395.925636] Lustre: Mounted lustre-client - version 2.17.57_104_ga33798f [ 1553.995786] Lustre: DEBUG MARKER: == conf-sanity test 123aj: check permanent TBF rules ===== 21:43:29 (1788831809) [ 1604.147646] Lustre: DEBUG MARKER: == conf-sanity test 123F: clear and reset all parameters using set_param -F ========================================================== 21:44:19 (1788831859) [ 1616.055532] Lustre: Unmounted lustre-client [ 1705.797657] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1712.685266] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1738.131839] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1739.014168] Lustre: Mounted lustre-client - version 2.17.57_104_ga33798f [ 1745.242156] Lustre: Unmounted lustre-client [ 1776.024860] Key type lgssc unregistered [ 1776.395972] LNet: 41563:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1776.409171] LNetError: 41563:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1776.435596] LNet: Removed LNI 192.168.204.2@tcp [ 1777.206338] Key type .llcrypt unregistered [ 1777.208668] Key type ._llcrypt unregistered [ 1794.280693] Key type ._llcrypt registered [ 1794.282814] Key type .llcrypt registered [ 1795.640971] Lustre: DEBUG MARKER: == conf-sanity test 123G: clear and reset all parameters using apply_yaml ========================================================== 21:47:31 (1788832051) [ 1798.417459] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1798.429778] alg: No test for adler32 (adler32-zlib) [ 1799.547569] Lustre: Lustre: Build Version: 2.17.57_104_ga33798f [ 1799.847438] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [ 1801.551870] Key type lgssc registered [ 1802.985212] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1859.289637] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1865.112667] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1892.514561] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1894.513723] Lustre: Mounted lustre-client - version 2.17.57_104_ga33798f [ 1908.819401] Lustre: Unmounted lustre-client [ 1994.400371] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2000.211661] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2027.680360] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2028.928757] Lustre: Mounted lustre-client - version 2.17.57_104_ga33798f [ 2036.388559] Lustre: Unmounted lustre-client [ 2065.759672] Key type lgssc unregistered [ 2066.030711] LNet: 47106:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2066.043284] LNetError: 47106:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2066.058844] LNet: Removed LNI 192.168.204.2@tcp [ 2066.818442] Key type .llcrypt unregistered [ 2066.824772] Key type ._llcrypt unregistered [ 2085.080211] Key type ._llcrypt registered [ 2085.083226] Key type .llcrypt registered [ 2087.318483] Lustre: DEBUG MARKER: == conf-sanity test 123H: check concurent accesses with 'lctl llog_print ========================================================== 21:52:21 (1788832341) [ 2090.384994] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2090.404778] alg: No test for adler32 (adler32-zlib) [ 2091.564156] Lustre: Lustre: Build Version: 2.17.57_104_ga33798f [ 2091.945944] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [ 2093.680699] Key type lgssc registered [ 2095.094352] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2151.383782] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2158.830936] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2181.449130] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2184.571212] Lustre: Mounted lustre-client - version 2.17.57_104_ga33798f [ 2298.393480] Lustre: DEBUG MARKER: == conf-sanity test 124: check failover after replace_nids ========================================================== 21:55:53 (1788832553) [ 2300.478909] Lustre: DEBUG MARKER: SKIP: conf-sanity test_124 needs MDT failover setup [ 2302.708029] Lustre: DEBUG MARKER: == conf-sanity test 126: mount in parallel shouldn't cause a crash ========================================================== 21:55:57 (1788832557) [ 2305.338323] Lustre: Unmounted lustre-client [ 2346.158864] Key type lgssc unregistered [ 2346.587992] LNet: 52449:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2346.593558] LNetError: 52449:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2346.635514] LNet: Removed LNI 192.168.204.2@tcp [ 2347.680336] Key type .llcrypt unregistered [ 2347.682781] Key type ._llcrypt unregistered [ 2383.572511] Key type ._llcrypt registered [ 2383.579310] Key type .llcrypt registered [ 2384.225251] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2384.258064] alg: No test for adler32 (adler32-zlib) [ 2385.650679] Lustre: Lustre: Build Version: 2.17.57_104_ga33798f [ 2385.896262] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [ 2387.551576] Key type lgssc registered [ 2389.283298] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2427.400371] Lustre: DEBUG MARKER: == conf-sanity test 127: direct io overwrite on full ost ========================================================== 21:58:02 (1788832682) [ 2442.036772] Key type lgssc unregistered [ 2442.364363] LNet: 54869:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2442.380985] LNetError: 54869:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2442.396821] LNet: Removed LNI 192.168.204.2@tcp [ 2443.384471] Key type .llcrypt unregistered [ 2443.385728] Key type ._llcrypt unregistered [ 2457.062150] Key type ._llcrypt registered [ 2457.065968] Key type .llcrypt registered [ 2457.255766] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2457.269215] alg: No test for adler32 (adler32-zlib) [ 2458.424917] Lustre: Lustre: Build Version: 2.17.57_104_ga33798f [ 2458.751639] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [ 2460.447229] Key type lgssc registered [ 2461.869296] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2527.073500] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2533.845708] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2558.794230] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2569.290123] Lustre: Mounted lustre-client - version 2.17.57_104_ga33798f [ 2619.579961] Lustre: DEBUG MARKER: == conf-sanity test 128: Force using remote logs with --nolocallogs ========================================================== 22:01:15 (1788832875) [ 2621.175817] Lustre: DEBUG MARKER: SKIP: conf-sanity test_128 need separate mgs device [ 2622.956829] Lustre: DEBUG MARKER: == conf-sanity test 129: attempt to connect an OST with the same index should fail ========================================================== 22:01:18 (1788832878) [ 2625.234907] Lustre: Unmounted lustre-client [ 2697.074911] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2703.002730] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2774.980924] Lustre: DEBUG MARKER: == conf-sanity test 130: re-register an MDT after writeconf ========================================================== 22:03:50 (1788833030) [ 2864.874725] Lustre: Mounted lustre-client - version 2.17.57_104_ga33798f [ 2870.443693] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 2890.719371] Lustre: lustre-OST0000-osc-ffff96f08500f000: disconnect after 23s idle [ 2890.739346] Lustre: lustre-MDT0001-mdc-ffff96f08500f000: Connection to lustre-MDT0001 (at 192.168.204.102@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2915.641095] LustreError: 63598:0:(obd_config.c:2102:class_config_llog_handler()) MGC192.168.204.102@tcp: cfg command failed: rc = -17 [ 2915.653543] Lustre: cmd=cf001 0:lustre-MDT0001-mdc 1:mdc 2:lustre-clilmv_UUID [ 2915.653543] [ 2915.661903] LustreError: 62833:0:(mgc_request.c:614:do_requeue()) failed processing log: -17 [ 2931.481593] Lustre: DEBUG MARKER: == conf-sanity test 131: MDT backup restore with project ID and inheritance flag ========================================================== 22:06:27 (1788833187) [ 2997.096268] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 3086.444859] Lustre: Unmounted lustre-client [ 3254.428993] Lustre: Mounted lustre-client - version 2.17.57_104_ga33798f [ 3259.740725] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 3317.203821] Lustre: DEBUG MARKER: == conf-sanity test 132: hsm_actions processed after failover ========================================================== 22:12:52 (1788833572) [ 3319.574377] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3320.146751] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 3320.157613] Lustre: Skipped 2 previous similar messages [ 3340.996614] Lustre: Unmounted lustre-client [ 3488.857600] Lustre: DEBUG MARKER: == conf-sanity test 133: stripe QOS: free space balance in a pool ========================================================== 22:15:44 (1788833744) [ 3490.790798] Lustre: DEBUG MARKER: SKIP: conf-sanity test_133 needs >= 4 OSTs [ 3492.941660] Lustre: DEBUG MARKER: == conf-sanity test 134: check_iam works without faults == 22:15:48 (1788833748) [ 3583.627368] Lustre: Mounted lustre-client - version 2.17.57_104_ga33798f [ 3588.462989] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 4332.924361] Lustre: DEBUG MARKER: == conf-sanity test 135: check the behavior when changelog is wrapped around ========================================================== 22:29:48 (1788834588) [ 4335.399142] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4345.787819] Lustre: Unmounted lustre-client [ 4492.811520] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4498.966920] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4521.237323] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4521.933271] Lustre: Mounted lustre-client - version 2.17.57_104_ga33798f [ 6629.240241] Lustre: 95314:0:(llog_cat.c:310:llog_cat_process_or_fork()) lustre-MDT0000-mdc-ffff96f0833af800: catlog [0xa:0x5:0x0] crosses index zero [ 6658.499132] Lustre: Unmounted lustre-client [ 6701.072265] Key type lgssc unregistered [ 6701.347370] LNet: 103553:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6701.355566] LNetError: 103553:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6701.373828] LNet: Removed LNI 192.168.204.2@tcp [ 6702.311202] Key type .llcrypt unregistered [ 6702.313401] Key type ._llcrypt unregistered [ 6716.476293] Key type ._llcrypt registered [ 6716.482278] Key type .llcrypt registered [ 6718.005311] Lustre: DEBUG MARKER: == conf-sanity test 136: don't panic with bad obdecho setup ========================================================== 23:09:33 (1788836973) [ 6740.369176] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6740.426582] alg: No test for adler32 (adler32-zlib) [ 6741.629548] Lustre: Lustre: Build Version: 2.17.57_104_ga33798f [ 6741.989232] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [ 6743.687234] Key type lgssc registered [ 6745.186409] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6839.052133] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6846.408830] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6879.173699] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6882.237080] Lustre: Mounted lustre-client - version 2.17.57_104_ga33798f [ 6906.089678] Lustre: Unmounted lustre-client [ 7045.401853] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7051.412521] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7075.033223] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7118.860750] Lustre: DEBUG MARKER: == conf-sanity test 137: a new MDT should inherit pools, parameters and failnode ========================================================== 23:16:14 (1788837374) [ 7121.020114] Lustre: DEBUG MARKER: SKIP: conf-sanity test_137 needs >= 3 MDTs [ 7123.201930] Lustre: DEBUG MARKER: == conf-sanity test 140: remove_updatelog script actions ========================================================== 23:16:18 (1788837378) [ 7170.655303] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7177.441541] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7201.214534] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7207.468518] Lustre: Mounted lustre-client - version 2.17.57_104_ga33798f [ 7217.641151] Lustre: lustre-MDT0000-mdc-ffff96f08520d000: Connection to lustre-MDT0000 (at 192.168.204.102@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7227.898079] Lustre: lustre-MDT0001-mdc-ffff96f08520d000: Connection to lustre-MDT0001 (at 192.168.204.102@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7238.111157] Lustre: 104312:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788837479/real 1788837479] req@000000001de4397e x1875731544092800/t0(0) o400->MGC192.168.204.102@tcp@192.168.204.102@tcp:26/25 lens 224/224 e 0 to 1 dl 1788837495 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 7238.149209] LustreError: MGC192.168.204.102@tcp: Connection to MGS (at 192.168.204.102@tcp) was lost; in progress operations using this service will fail [ 7383.534349] Lustre: Evicted from MGS (at 192.168.204.102@tcp) after server handle changed from 0x396061081ddb32ce to 0x396061081ddb496b [ 7383.557323] Lustre: MGC192.168.204.102@tcp: Connection restored to 192.168.204.102@tcp (at 192.168.204.102@tcp) [ 7389.185764] Lustre: 113708:0:(mgc_request.c:1900:mgc_process_log()) MGC192.168.204.102@tcp: IR log lustre-cliir failed, not fatal: rc = -2 [ 7398.939714] LustreError: lustre-MDT0000-mdc-ffff96f08520d000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 7399.007119] Lustre: lustre-MDT0000-mdc-ffff96f08520d000: Connection restored to 192.168.204.102@tcp (at 192.168.204.102@tcp) [ 7404.042498] LustreError: lustre-MDT0001-mdc-ffff96f08520d000: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 7404.075954] Lustre: lustre-MDT0001-mdc-ffff96f08520d000: Connection restored to 192.168.204.102@tcp (at 192.168.204.102@tcp) [ 7411.950668] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7413.624522] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 7420.034472] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7421.846408] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 7439.466714] Lustre: Unmounted lustre-client [ 7572.108635] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7579.189907] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7604.906508] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7659.476188] Lustre: DEBUG MARKER: == conf-sanity test 150: test setting max_cached_mb to a % ========================================================== 23:25:15 (1788837915) [ 7696.133333] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7702.615535] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7729.006612] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7730.131833] Lustre: Mounted lustre-client - version 2.17.57_104_ga33798f [ 7733.176870] LustreError: 121040: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 [ 7741.386543] Lustre: DEBUG MARKER: == conf-sanity test 151a: damaged local config doesn't prevent mounting ========================================================== 23:26:36 (1788837996) [ 7743.752191] Lustre: Unmounted lustre-client [ 7773.986443] Key type lgssc unregistered [ 7774.303043] LNet: 122045:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7774.327487] LNetError: 122045:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7775.403623] LNet: Removed LNI 192.168.204.2@tcp [ 7776.267296] Key type .llcrypt unregistered [ 7776.269261] Key type ._llcrypt unregistered [ 7791.616651] Key type ._llcrypt registered [ 7791.619571] Key type .llcrypt registered [ 7792.324796] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7792.339653] alg: No test for adler32 (adler32-zlib) [ 7793.379205] Lustre: Lustre: Build Version: 2.17.57_104_ga33798f [ 7793.762050] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [ 7795.656070] Key type lgssc registered [ 7797.922766] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7895.927306] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7903.020577] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7925.441583] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8043.953871] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8050.417886] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8075.099837] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8121.017361] Lustre: DEBUG MARKER: == conf-sanity test 151b: -ENOSPC doesn't affect mount === 23:32:56 (1788838376) [ 8134.643422] Key type lgssc unregistered [ 8134.969472] LNet: 129253:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8134.976829] LNetError: 129253:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8134.994716] LNet: Removed LNI 192.168.204.2@tcp [ 8135.981944] Key type .llcrypt unregistered [ 8135.983806] Key type ._llcrypt unregistered [ 8150.133597] Key type ._llcrypt registered [ 8150.137135] Key type .llcrypt registered [ 8150.712703] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8150.745888] alg: No test for adler32 (adler32-zlib) [ 8152.216521] Lustre: Lustre: Build Version: 2.17.57_104_ga33798f [ 8152.528276] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [ 8154.248658] Key type lgssc registered [ 8156.415695] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8216.479559] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8223.269194] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8246.309530] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8370.803773] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8376.606081] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8403.757895] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8457.167578] Lustre: DEBUG MARKER: == conf-sanity test 152: seq allocation error in OSP ===== 23:38:32 (1788838712) [ 8552.292425] Lustre: Mounted lustre-client - version 2.17.57_104_ga33798f [ 8557.273535] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 8566.852392] Lustre: DEBUG MARKER: ADD OST3 [ 8578.015756] Lustre: lustre-OST0000-osc-ffff96f082a25800: disconnect after 24s idle [ 8579.639049] Lustre: DEBUG MARKER: STOP OST3 [ 8591.345684] Lustre: lustre-OST0003-osc-ffff96f082a25800: Connection to lustre-OST0003 (at 192.168.204.102@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8602.094379] Lustre: DEBUG MARKER: START OST3 again [ 8611.796518] Lustre: lustre-OST0003-osc-ffff96f082a25800: Connection restored to 192.168.204.102@tcp (at 192.168.204.102@tcp) [ 8645.238309] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 8645.254294] LustreError: 140057:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff96f082a25800: inode [0x240000bd0:0x1:0x0] mdc close failed: rc = -108 [ 8655.617319] Lustre: Unmounted lustre-client [ 8781.014404] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8786.947494] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8813.158579] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8863.477642] Lustre: DEBUG MARKER: == conf-sanity test 153a: bypass invalid NIDs quickly ==== 23:45:19 (1788839119) [ 8967.802124] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8977.120517] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9006.801883] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9082.724672] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9088.417236] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9112.710678] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9115.165214] LNetError: 129662:0:(lib-move.c:2158:lnet_handle_find_routed_path()) peer 192.168.252.112@tcp2 has no available nets [ 9115.570260] Lustre: Mounted lustre-client - version 2.17.57_104_ga33798f [ 9120.738118] Lustre: Unmounted lustre-client [ 9162.097349] Key type lgssc unregistered [ 9170.463158] LNet: 1 local NIs in recovery (showing 1): 192.168.204.2@tcp [ 9176.735632] LNet: 149042:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9176.741618] LNetError: 149042:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9246.376388] LNet: Removed LNI 192.168.204.2@tcp [ 9247.390161] Key type .llcrypt unregistered [ 9247.391925] Key type ._llcrypt unregistered [ 9266.924149] Key type ._llcrypt registered [ 9266.926463] Key type .llcrypt registered [ 9268.613220] Lustre: DEBUG MARKER: == conf-sanity test 153b: added IPv6 NID support ========= 23:52:03 (1788839523) [ 9290.909161] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9290.989457] alg: No test for adler32 (adler32-zlib) [ 9292.268280] Lustre: Lustre: Build Version: 2.17.57_104_ga33798f [ 9292.713656] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [ 9294.463679] Key type lgssc registered [ 9296.229691] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9395.136753] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9402.154892] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9429.239393] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9573.103083] Lustre: Mounted lustre-client - version 2.17.57_104_ga33798f [ 9578.158824] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 9586.038923] Lustre: Unmounted lustre-client [ 9586.193205] LNetError: 150221:0:(lib-socket.c:308:lnet_sock_create()) Error trying to bind to fe80::5403:b4ff:fe41:d8f3/1023: rc = -22 [ 9586.210185] LNetError: Unexpected error -22 connecting to 6699:7654::1234:1234:d84@tcp at host [6699:7654::1234:1234:d84]:988 [ 9586.519234] Lustre: Mounted lustre-client - version 2.17.57_104_ga33798f [ 9586.679109] Lustre: Unmounted lustre-client [ 9587.575660] LNetError: 150222:0:(lib-socket.c:308:lnet_sock_create()) Error trying to bind to fe80::5403:b4ff:fe41:d8f3/1023: rc = -22 [ 9587.598757] LNetError: Unexpected error -22 connecting to 6699:7654::1234:1234:d84@tcp at host [6699:7654::1234:1234:d84]:988 [ 9587.616708] LNet: 1 local NIs in recovery (showing 1): 192.168.204.2@tcp [ 9587.982707] Lustre: Mounted lustre-client - version 2.17.57_104_ga33798f [ 9587.991528] Lustre: Skipped 1 previous similar message [ 9588.350345] Lustre: Unmounted lustre-client [ 9588.355069] Lustre: Skipped 1 previous similar message [ 9590.308365] Lustre: Mounted lustre-client - version 2.17.57_104_ga33798f [ 9590.311396] Lustre: Skipped 2 previous similar messages [ 9590.501448] Lustre: Unmounted lustre-client [ 9590.505091] Lustre: Skipped 2 previous similar messages [ 9590.601967] LNetError: 150222:0:(lib-socket.c:308:lnet_sock_create()) Error trying to bind to fe80::5403:b4ff:fe41:d8f3/1023: rc = -22 [ 9590.611498] LNetError: Unexpected error -22 connecting to 6699:7654::1234:1234:d84@tcp at host [6699:7654::1234:1234:d84]:988 [ 9591.945318] Lustre: 156145: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 [ 9592.668212] Lustre: 156176: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 [ 9592.692654] LNetError: 150219:0:(lib-socket.c:308:lnet_sock_create()) Error trying to bind to fe80::5403:b4ff:fe41:d8f3/1023: rc = -22 [ 9592.703629] LNetError: 150219:0:(lib-socket.c:308:lnet_sock_create()) Skipped 1 previous similar message [ 9592.715256] LNetError: Unexpected error -22 connecting to 2001:db8:85a3::8a2e:370:7334@tcp at host [2001:db8:85a3::8a2e:370:7334]:988 [ 9592.725246] LNetError: Skipped 1 previous similar message [ 9602.895538] Lustre: DEBUG MARKER: == conf-sanity test 153c: don't stuck on unreached NID === 23:57:38 (1788839858) [ 9737.043655] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9744.589736] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9769.167599] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9842.240871] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9848.608894] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9871.751141] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9877.343937] LustreError: 161784:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.252.112@tcp: failed processing log, type 1: rc = -110 [ 9922.527197] LNet: 1 local NIs in recovery (showing 1): 192.168.204.2@tcp [ 9982.949249] LNet: 1 local NIs in recovery (showing 1): 192.168.204.2@tcp [10014.689477] 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 [10014.716896] Lustre: Unmounted lustre-client [10014.721093] Lustre: Skipped 4 previous similar messages [10014.760132] LustreError: 161784:0:(super25.c:179:lustre_fill_super()) llite: Unable to mount : rc = -110 [10053.425991] Key type lgssc unregistered [10063.968144] LNet: 162241:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10063.981126] LNetError: 162241:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10134.701762] LNet: Removed LNI 192.168.204.2@tcp [10135.855348] Key type .llcrypt unregistered [10135.857049] Key type ._llcrypt unregistered [10157.488978] Key type ._llcrypt registered [10157.496405] Key type .llcrypt registered [10159.264535] Lustre: DEBUG MARKER: == conf-sanity test 154: expand .. on rename after MDT backup restore ========================================================== 00:06:54 (1788840414) [10184.697594] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10184.730229] alg: No test for adler32 (adler32-zlib) [10186.154345] Lustre: Lustre: Build Version: 2.17.57_104_ga33798f [10186.539167] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [10188.327274] Key type lgssc registered [10190.271475] Lustre: Echo OBD driver; http://www.lustre.org/ [10354.311245] Lustre: Mounted lustre-client - version 2.17.57_104_ga33798f [10360.113599] Lustre: DEBUG MARKER: Using TIMEOUT=20 [10377.603774] Lustre: Unmounted lustre-client [10561.553312] Lustre: Mounted lustre-client - version 2.17.57_104_ga33798f [10568.629869] Lustre: DEBUG MARKER: Using TIMEOUT=20 [10576.963879] Lustre: Unmounted lustre-client [10634.844813] Lustre: DEBUG MARKER: == conf-sanity test 155: gap in seq allocation from ofd after restarting ========================================================== 00:14:49 (1788840889) [10748.704640] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10755.611563] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10780.195963] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10917.249353] Lustre: Mounted lustre-client - version 2.17.57_104_ga33798f [10923.185088] Lustre: DEBUG MARKER: Using TIMEOUT=20 [10938.020728] Lustre: Unmounted lustre-client [11080.116558] Lustre: Mounted lustre-client - version 2.17.57_104_ga33798f [11086.398713] Lustre: DEBUG MARKER: Using TIMEOUT=20 [11105.759566] Lustre: lustre-OST0001-osc-ffff96f09fd2c000: disconnect after 23s idle [11105.988415] Lustre: DEBUG MARKER: == conf-sanity test 156: root_fid on export consistent with client mount ========================================================== 00:22:41 (1788841361) [11108.400856] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [11118.782523] Lustre: Unmounted lustre-client [11311.601878] Lustre: Mounted lustre-client - version 2.17.57_104_ga33798f [11317.175125] Lustre: DEBUG MARKER: Using TIMEOUT=20 [11324.994184] Lustre: Unmounted lustre-client [11325.445899] Lustre: Mounted lustre-client - version 2.17.57_104_ga33798f [11333.037805] Lustre: Unmounted lustre-client [11334.919242] Lustre: DEBUG MARKER: == conf-sanity test 157a: test allow_register for MDT registration and MGS restart ========================================================== 00:26:30 (1788841590) [11403.800841] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11410.026620] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11435.597715] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11436.289639] Lustre: Mounted lustre-client - version 2.17.57_104_ga33798f [11526.640653] LustreError: lustre-OST0001-osc-ffff96f0833af800: operation ost_connect to node 192.168.204.102@tcp failed: rc = -19 [11531.753557] Lustre: lustre-MDT0000-mdc-ffff96f0833af800: Connection to lustre-MDT0000 (at 192.168.204.102@tcp) was lost; in progress operations using this service will wait for recovery to complete [11547.149977] LustreError: MGC192.168.204.102@tcp: Connection to MGS (at 192.168.204.102@tcp) was lost; in progress operations using this service will fail [11547.217154] Lustre: Evicted from MGS (at 192.168.204.102@tcp) after server handle changed from 0x1f1d7a6429090d89 to 0x1f1d7a6429090fa4 [11547.254146] Lustre: MGC192.168.204.102@tcp: Connection restored to 192.168.204.102@tcp (at 192.168.204.102@tcp) [11548.444076] LustreError: lustre-MDT0000-mdc-ffff96f0833af800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [11548.470957] Lustre: lustre-MDT0000-mdc-ffff96f0833af800: Connection restored to 192.168.204.102@tcp (at 192.168.204.102@tcp) [11599.607200] Lustre: Unmounted lustre-client [11629.451665] Key type lgssc unregistered [11629.747880] LNet: 189766:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11629.757361] LNetError: 189766:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11629.779244] LNet: Removed LNI 192.168.204.2@tcp [11630.551196] Key type .llcrypt unregistered [11630.555346] Key type ._llcrypt unregistered [11642.922911] Key type ._llcrypt registered [11642.925308] Key type .llcrypt registered [11644.346531] Lustre: DEBUG MARKER: == conf-sanity test 157b: verify allow_register (block new OSTs, allow existing) ========================================================== 00:31:39 (1788841899) [11647.056875] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11647.094046] alg: No test for adler32 (adler32-zlib) [11648.235439] Lustre: Lustre: Build Version: 2.17.57_104_ga33798f [11648.611538] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [11650.367236] Key type lgssc registered [11652.078680] Lustre: Echo OBD driver; http://www.lustre.org/ [11710.472561] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11716.911962] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11741.769887] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11744.709046] Lustre: Mounted lustre-client - version 2.17.57_104_ga33798f [11842.046814] LustreError: lustre-OST0001-osc-ffff96f082895800: operation ost_connect to node 192.168.204.102@tcp failed: rc = -19 [11961.124689] Lustre: Unmounted lustre-client [11999.715734] Key type lgssc unregistered [11999.955176] LNet: 195800:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11999.964586] LNetError: 195800:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11999.978203] LNet: Removed LNI 192.168.204.2@tcp [12000.552149] Key type .llcrypt unregistered [12000.557140] Key type ._llcrypt unregistered [12012.145492] Key type ._llcrypt registered [12012.147035] Key type .llcrypt registered [12013.742966] Lustre: DEBUG MARKER: == conf-sanity test 160: MGC updates failnodes from all participants ========================================================== 00:37:48 (1788842268) [12045.513552] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12045.556108] alg: No test for adler32 (adler32-zlib) [12046.755539] Lustre: Lustre: Build Version: 2.17.57_104_ga33798f [12047.101290] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [12048.801440] Key type lgssc registered [12050.492065] Lustre: Echo OBD driver; http://www.lustre.org/ [12146.372516] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12151.627287] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12174.853812] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12221.949590] Key type lgssc unregistered [12222.254640] LNet: 200779:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12222.269185] LNetError: 200779:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12222.293852] LNet: Removed LNI 192.168.204.2@tcp [12223.013294] Key type .llcrypt unregistered [12223.017107] Key type ._llcrypt unregistered [12331.272265] Key type ._llcrypt registered [12331.276968] Key type .llcrypt registered [12331.839928] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12331.877939] alg: No test for adler32 (adler32-zlib) [12333.149675] Lustre: Lustre: Build Version: 2.17.57_104_ga33798f [12333.411369] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [12335.111245] Key type lgssc registered [12336.626891] Lustre: Echo OBD driver; http://www.lustre.org/ [12392.455811] Lustre: DEBUG MARKER: == conf-sanity test 161: test '-o mgsname' option ======== 00:44:07 (1788842647) [12427.167591] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12433.277554] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12459.537564] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12461.546831] Lustre: Mounted lustre-client - version 2.17.57_104_ga33798f [12464.150775] Lustre: Unmounted lustre-client [12464.776578] Lustre: Mounted lustre-client - version 2.17.57_104_ga33798f [12465.069037] Lustre: Unmounted lustre-client [12474.299822] Lustre: Unmounted lustre-client [12504.640340] Key type lgssc unregistered [12504.939880] LNet: 205758:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12504.953684] LNetError: 205758:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12504.980239] LNet: Removed LNI 192.168.204.2@tcp [12505.912077] Key type .llcrypt unregistered [12505.914678] Key type ._llcrypt unregistered [12516.842832] Key type ._llcrypt registered [12516.851254] Key type .llcrypt registered [12518.533373] Lustre: DEBUG MARKER: == conf-sanity test 162: replace nids with -o noclient === 00:46:13 (1788842773) [12522.116627] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12522.181324] alg: No test for adler32 (adler32-zlib) [12523.256890] Lustre: Lustre: Build Version: 2.17.57_104_ga33798f [12523.468248] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [12525.191386] Key type lgssc registered [12526.578734] Lustre: Echo OBD driver; http://www.lustre.org/ [12580.876503] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12587.370811] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12609.813390] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12612.570263] Lustre: Mounted lustre-client - version 2.17.57_104_ga33798f [12624.855703] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12627.317877] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [12633.087857] Lustre: lustre-MDT0000-mdc-ffff96f09f503000: Connection to lustre-MDT0000 (at 192.168.204.102@tcp) was lost; in progress operations using this service will wait for recovery to complete [12648.430774] Lustre: lustre-MDT0001-mdc-ffff96f09f503000: Connection to lustre-MDT0001 (at 192.168.204.102@tcp) was lost; in progress operations using this service will wait for recovery to complete [12654.560573] Lustre: 206431:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788842895/real 1788842895] req@00000000a98bff76 x1875737606887296/t0(0) o400->MGC192.168.204.102@tcp@192.168.204.102@tcp:26/25 lens 224/224 e 0 to 1 dl 1788842911 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [12654.605126] LustreError: MGC192.168.204.102@tcp: Connection to MGS (at 192.168.204.102@tcp) was lost; in progress operations using this service will fail [12703.675397] Lustre: Evicted from MGS (at 192.168.204.102@tcp) after server handle changed from 0x629398377c7afc3d to 0x629398377c7afec1 [12703.696707] Lustre: MGC192.168.204.102@tcp: Connection restored to 192.168.204.102@tcp (at 192.168.204.102@tcp) [12732.348985] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12733.983348] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in IDLE state after 0 sec [12739.075564] LustreError: lustre-MDT0000-mdc-ffff96f09f503000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [12739.111473] Lustre: lustre-MDT0000-mdc-ffff96f09f503000: Connection restored to 192.168.204.102@tcp (at 192.168.204.102@tcp) [12740.883264] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12743.001932] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [12746.353266] Lustre: Unmounted lustre-client [12776.609713] Key type lgssc unregistered [12776.914119] LNet: 211290:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12776.922505] LNetError: 211290:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12776.938964] LNet: Removed LNI 192.168.204.2@tcp [12777.539498] Key type .llcrypt unregistered [12777.541648] Key type ._llcrypt unregistered [12812.036986] Key type ._llcrypt registered [12812.041338] Key type .llcrypt registered [12812.591900] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12812.607956] alg: No test for adler32 (adler32-zlib) [12813.670418] Lustre: Lustre: Build Version: 2.17.57_104_ga33798f [12813.878837] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [12815.511408] Key type lgssc registered [12817.175482] Lustre: Echo OBD driver; http://www.lustre.org/ [12910.207774] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12917.166946] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12944.255346] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12993.567968] Lustre: DEBUG MARKER: == conf-sanity test 164: test expected_clients parameter and max client tracking ========================================================== 00:54:08 (1788843248) [13111.080409] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13117.164704] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13144.919598] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13146.578622] Lustre: Mounted lustre-client - version 2.17.57_104_ga33798f [13151.433561] Lustre: Mounted lustre-client - version 2.17.57_104_ga33798f [13152.592093] Lustre: Mounted lustre-client - version 2.17.57_104_ga33798f [13152.601152] Lustre: Skipped 1 previous similar message [13156.700265] Lustre: Unmounted lustre-client [13158.450468] Lustre: Unmounted lustre-client [13160.730888] Lustre: Unmounted lustre-client [13162.740618] Lustre: Unmounted lustre-client [13175.136721] Lustre: Unmounted lustre-client [13175.141807] Lustre: Skipped 1 previous similar message [13203.370767] Key type lgssc unregistered [13203.740640] LNet: 220454:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13203.752347] LNetError: 220454:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13203.794863] LNet: Removed LNI 192.168.204.2@tcp [13204.563551] Key type .llcrypt unregistered [13204.569286] Key type ._llcrypt unregistered [13216.145797] Key type ._llcrypt registered [13216.147822] Key type .llcrypt registered [13217.693777] Lustre: DEBUG MARKER: == conf-sanity test 200a: check CPU partitions =========== 00:57:52 (1788843472) [13230.306583] Key type .llcrypt unregistered [13230.315212] Key type ._llcrypt unregistered [13231.412555] Key type ._llcrypt registered [13231.421701] Key type .llcrypt registered [13231.926755] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 4 [13231.938841] alg: No test for adler32 (adler32-zlib) [13233.100910] Lustre: Lustre: Build Version: 2.17.57_104_ga33798f [13233.489790] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [13235.167575] Key type lgssc registered [13236.618408] Lustre: Echo OBD driver; http://www.lustre.org/ [13263.539967] Key type lgssc unregistered [13263.756426] LNet: 222601:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13263.767364] LNetError: 222601:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13263.786580] LNet: Removed LNI 192.168.204.2@tcp [13264.548950] Key type .llcrypt unregistered [13264.552960] Key type ._llcrypt unregistered [13265.004709] Key type ._llcrypt registered [13265.007451] Key type .llcrypt registered [13266.588169] Lustre: DEBUG MARKER: == conf-sanity test 200b: set CPU pattern using core selection ========================================================== 00:58:41 (1788843521) [13280.435848] Key type .llcrypt unregistered [13280.438279] Key type ._llcrypt unregistered [13280.982366] Key type ._llcrypt registered [13280.990250] Key type .llcrypt registered [13281.312491] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13281.331566] alg: No test for adler32 (adler32-zlib) [13282.374629] Lustre: Lustre: Build Version: 2.17.57_104_ga33798f [13282.710132] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [13284.407182] Key type lgssc registered [13286.131556] Lustre: Echo OBD driver; http://www.lustre.org/ [13308.011893] Key type lgssc unregistered [13308.315370] LNet: 224145:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13308.321591] LNetError: 224145:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13308.332929] LNet: Removed LNI 192.168.204.2@tcp [13309.243871] Key type .llcrypt unregistered [13309.249029] Key type ._llcrypt unregistered [13318.302037] Key type ._llcrypt registered [13318.303798] Key type .llcrypt registered [13318.881864] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13318.902622] alg: No test for adler32 (adler32-zlib) [13319.843458] Lustre: Lustre: Build Version: 2.17.57_104_ga33798f [13320.111466] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [13321.799236] Key type lgssc registered [13322.961335] Lustre: Echo OBD driver; http://www.lustre.org/ [13350.000347] Key type lgssc unregistered [13350.311165] LNet: 225905:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13350.322577] LNetError: 225905:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13350.349143] LNet: Removed LNI 192.168.204.2@tcp [13351.251977] Key type .llcrypt unregistered [13351.253732] Key type ._llcrypt unregistered [13351.714748] Key type ._llcrypt registered [13351.716582] Key type .llcrypt registered [13353.461137] Lustre: DEBUG MARKER: == conf-sanity test 200c: set CPU pattern using NUMA node layout ========================================================== 01:00:08 (1788843608) [13366.469506] Key type .llcrypt unregistered [13366.473326] Key type ._llcrypt unregistered [13367.413331] Key type ._llcrypt registered [13367.417620] Key type .llcrypt registered [13367.595669] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13367.618605] alg: No test for adler32 (adler32-zlib) [13368.605489] Lustre: Lustre: Build Version: 2.17.57_104_ga33798f [13368.841767] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [13370.568112] Key type lgssc registered [13372.229167] Lustre: Echo OBD driver; http://www.lustre.org/ [13395.360751] Key type lgssc unregistered [13395.694478] LNet: 227442:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13395.704759] LNetError: 227442:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13395.718288] LNet: Removed LNI 192.168.204.2@tcp [13396.461349] Key type .llcrypt unregistered [13396.463893] Key type ._llcrypt unregistered [13404.233850] Key type ._llcrypt registered [13404.236704] Key type .llcrypt registered [13404.899132] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13404.929670] alg: No test for adler32 (adler32-zlib) [13406.231099] Lustre: Lustre: Build Version: 2.17.57_104_ga33798f [13406.452412] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [13408.175416] Key type lgssc registered [13409.704909] Lustre: Echo OBD driver; http://www.lustre.org/ [13429.222453] Key type lgssc unregistered [13429.544525] LNet: 228757:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13429.554603] LNetError: 228757:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13429.570205] LNet: Removed LNI 192.168.204.2@tcp [13430.484601] Key type .llcrypt unregistered [13430.487229] Key type ._llcrypt unregistered [13440.037176] Key type ._llcrypt registered [13440.068594] Key type .llcrypt registered [13440.571986] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13440.603840] alg: No test for adler32 (adler32-zlib) [13442.027740] Lustre: Lustre: Build Version: 2.17.57_104_ga33798f [13442.286829] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [13444.087186] Key type lgssc registered [13445.827674] Lustre: Echo OBD driver; http://www.lustre.org/ [13466.240269] Key type lgssc unregistered [13466.497371] LNet: 230057:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13466.508143] LNetError: 230057:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13466.526924] LNet: Removed LNI 192.168.204.2@tcp [13467.147848] Key type .llcrypt unregistered [13467.154274] Key type ._llcrypt unregistered [13476.314113] Key type ._llcrypt registered [13476.319799] Key type .llcrypt registered [13476.916654] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13476.991545] alg: No test for adler32 (adler32-zlib) [13478.234360] Lustre: Lustre: Build Version: 2.17.57_104_ga33798f [13478.544806] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [13480.271189] Key type lgssc registered [13481.700798] Lustre: Echo OBD driver; http://www.lustre.org/ [13511.309838] Key type lgssc unregistered [13511.593331] LNet: 231822:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13511.605119] LNetError: 231822:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13511.621447] LNet: Removed LNI 192.168.204.2@tcp [13512.434645] Key type .llcrypt unregistered [13512.441769] Key type ._llcrypt unregistered [13512.933570] Key type ._llcrypt registered [13512.939040] Key type .llcrypt registered [13514.496528] Lustre: DEBUG MARKER: == conf-sanity test 200d: set CPU pattern to exclude only CPU 1 ========================================================== 01:02:49 (1788843769) [13527.725513] Key type .llcrypt unregistered [13527.730818] Key type ._llcrypt unregistered [13528.587597] Key type ._llcrypt registered [13528.596114] Key type .llcrypt registered [13529.193443] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13529.215618] alg: No test for adler32 (adler32-zlib) [13530.352413] Lustre: Lustre: Build Version: 2.17.57_104_ga33798f [13530.609311] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [13532.319232] Key type lgssc registered [13533.637956] Lustre: Echo OBD driver; http://www.lustre.org/ [13552.560373] Key type lgssc unregistered [13552.801184] LNet: 233364:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13552.807077] LNetError: 233364:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13552.831303] LNet: Removed LNI 192.168.204.2@tcp [13553.570545] Key type .llcrypt unregistered [13553.576959] Key type ._llcrypt unregistered [13562.347912] Key type ._llcrypt registered [13562.354931] Key type .llcrypt registered [13562.866758] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13562.890069] alg: No test for adler32 (adler32-zlib) [13564.072709] Lustre: Lustre: Build Version: 2.17.57_104_ga33798f [13564.389934] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [13566.127162] Key type lgssc registered [13567.539610] Lustre: Echo OBD driver; http://www.lustre.org/ [13587.041349] Key type lgssc unregistered [13587.356845] LNet: 234677:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13587.369278] LNetError: 234677:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13587.391819] LNet: Removed LNI 192.168.204.2@tcp [13588.179959] Key type .llcrypt unregistered [13588.185080] Key type ._llcrypt unregistered [13598.697932] Key type ._llcrypt registered [13598.709416] Key type .llcrypt registered [13599.004405] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13599.015500] alg: No test for adler32 (adler32-zlib) [13600.210477] Lustre: Lustre: Build Version: 2.17.57_104_ga33798f [13600.654254] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [13602.407194] Key type lgssc registered [13603.942329] Lustre: Echo OBD driver; http://www.lustre.org/ [13623.343256] Key type lgssc unregistered [13623.687284] LNet: 235980:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13623.696873] LNetError: 235980:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13623.714062] LNet: Removed LNI 192.168.204.2@tcp [13624.606430] Key type .llcrypt unregistered [13624.608742] Key type ._llcrypt unregistered [13634.351775] Key type ._llcrypt registered [13634.367871] Key type .llcrypt registered [13634.833826] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13634.883203] alg: No test for adler32 (adler32-zlib) [13636.216890] Lustre: Lustre: Build Version: 2.17.57_104_ga33798f [13636.650945] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [13638.527807] Key type lgssc registered [13640.082388] Lustre: Echo OBD driver; http://www.lustre.org/ [13661.300867] Key type lgssc unregistered [13661.631905] LNet: 237287:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13661.647433] LNetError: 237287:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13661.670332] LNet: Removed LNI 192.168.204.2@tcp [13662.530214] Key type .llcrypt unregistered [13662.533507] Key type ._llcrypt unregistered [13672.358602] Key type ._llcrypt registered [13672.376176] Key type .llcrypt registered [13673.226334] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13673.255163] alg: No test for adler32 (adler32-zlib) [13674.447976] Lustre: Lustre: Build Version: 2.17.57_104_ga33798f [13674.773593] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [13676.535366] Key type lgssc registered [13678.323136] Lustre: Echo OBD driver; http://www.lustre.org/ [13698.068359] Key type lgssc unregistered [13698.397147] LNet: 238594:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13698.404950] LNetError: 238594:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13698.420198] LNet: Removed LNI 192.168.204.2@tcp [13699.273497] Key type .llcrypt unregistered [13699.284142] Key type ._llcrypt unregistered [13706.964315] Key type ._llcrypt registered [13706.966113] Key type .llcrypt registered [13707.188293] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13707.217103] alg: No test for adler32 (adler32-zlib) [13708.358364] Lustre: Lustre: Build Version: 2.17.57_104_ga33798f [13708.700216] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [13710.535478] Key type lgssc registered [13712.630479] Lustre: Echo OBD driver; http://www.lustre.org/ [13733.122805] Key type lgssc unregistered [13733.493858] LNet: 239898:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13733.506959] LNetError: 239898:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13733.523723] LNet: Removed LNI 192.168.204.2@tcp [13734.459931] Key type .llcrypt unregistered [13734.462753] Key type ._llcrypt unregistered [13763.461300] Key type ._llcrypt registered [13763.462789] Key type .llcrypt registered [13764.913721] Lustre: DEBUG MARKER: == conf-sanity test 200e: set CPU pattern using relative core exclusion ========================================================== 01:07:00 (1788844020) [13779.110284] Key type .llcrypt unregistered [13779.112875] Key type ._llcrypt unregistered [13780.052506] Key type ._llcrypt registered [13780.055952] Key type .llcrypt registered [13780.678727] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13780.693476] alg: No test for adler32 (adler32-zlib) [13782.113713] Lustre: Lustre: Build Version: 2.17.57_104_ga33798f [13782.523855] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [13784.279294] Key type lgssc registered [13785.695830] Lustre: Echo OBD driver; http://www.lustre.org/ [13804.933904] Key type lgssc unregistered [13805.215778] LNet: 242208:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13805.222889] LNetError: 242208:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13805.240492] LNet: Removed LNI 192.168.204.2@tcp [13806.086416] Key type .llcrypt unregistered [13806.088501] Key type ._llcrypt unregistered [13815.167717] Key type ._llcrypt registered [13815.173561] Key type .llcrypt registered [13815.918231] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13815.931223] alg: No test for adler32 (adler32-zlib) [13817.033622] Lustre: Lustre: Build Version: 2.17.57_104_ga33798f [13817.401098] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [13819.143181] Key type lgssc registered [13820.686454] Lustre: Echo OBD driver; http://www.lustre.org/ [13843.486507] Key type lgssc unregistered [13843.968059] LNet: 243524:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13843.973721] LNetError: 243524:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13843.995922] LNet: Removed LNI 192.168.204.2@tcp [13844.848584] Key type .llcrypt unregistered [13844.850594] Key type ._llcrypt unregistered [13855.143483] Key type ._llcrypt registered [13855.146140] Key type .llcrypt registered [13855.747950] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13855.757264] alg: No test for adler32 (adler32-zlib) [13857.146756] Lustre: Lustre: Build Version: 2.17.57_104_ga33798f [13857.559587] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [13859.327600] Key type lgssc registered [13861.199932] Lustre: Echo OBD driver; http://www.lustre.org/ [13880.890971] Key type lgssc unregistered [13881.262723] LNet: 244832:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13881.271198] LNetError: 244832:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13881.289674] LNet: Removed LNI 192.168.204.2@tcp [13882.317410] Key type .llcrypt unregistered [13882.319740] Key type ._llcrypt unregistered [13891.284696] Key type ._llcrypt registered [13891.286852] Key type .llcrypt registered [13891.546796] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13891.576650] alg: No test for adler32 (adler32-zlib) [13892.783843] Lustre: Lustre: Build Version: 2.17.57_104_ga33798f [13893.192313] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [13894.911554] Key type lgssc registered [13896.562719] Lustre: Echo OBD driver; http://www.lustre.org/ [13926.999184] Key type lgssc unregistered [13927.385740] LNet: 246622:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13927.392067] LNetError: 246622:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13927.404333] LNet: Removed LNI 192.168.204.2@tcp [13928.388310] Key type .llcrypt unregistered [13928.392303] Key type ._llcrypt unregistered [13929.056636] Key type ._llcrypt registered [13929.061340] Key type .llcrypt registered [13930.867293] Lustre: DEBUG MARKER: == conf-sanity test 250: verify lcfg_erase removes filesystem parameters ========================================================== 01:09:45 (1788844185) [13953.742319] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13953.769239] alg: No test for adler32 (adler32-zlib) [13954.935951] Lustre: Lustre: Build Version: 2.17.57_104_ga33798f [13955.349205] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [13957.167238] Key type lgssc registered [13958.755344] Lustre: Echo OBD driver; http://www.lustre.org/ [14224.115968] Lustre: DEBUG MARKER: == conf-sanity test 802a: simulate readonly device ======= 01:14:38 (1788844478) [14226.204455] Lustre: DEBUG MARKER: SKIP: conf-sanity test_802a ZFS specific test [14304.787831] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 14044 sec ========= 01:15:59 (1788844559) [14307.042685] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 01:16:02 (1788844562) === [14311.085511] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 01:16:06 (1788844566) === [14350.619657] Key type lgssc unregistered [14351.007940] LNet: 255685:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14351.015944] LNetError: 255685:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14351.040966] LNet: Removed LNI 192.168.204.2@tcp [14351.918095] Key type .llcrypt unregistered [14351.920575] Key type ._llcrypt unregistered