[ 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 432710289 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2464 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE2300 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 0022C0 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2374 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE2404 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE243C 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe2300-0xbffe2373] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22ff] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2374-0xbffe2403] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe2404-0xbffe243b] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe243c-0xbffe2463] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001012] APIC: Switch to symmetric I/O mode setup [ 0.002397] x2apic enabled [ 0.003009] Switched APIC routing to physical x2apic. [ 0.004015] kvm-guest: setup PV IPIs [ 0.007544] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.008000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.008023] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009015] pid_max: default: 32768 minimum: 301 [ 0.010140] LSM: Security Framework initializing [ 0.011058] Yama: becoming mindful. [ 0.012035] SELinux: Initializing. [ 0.013072] *** VALIDATE selinux *** [ 0.021444] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.025797] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.027027] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.028112] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029118] *** VALIDATE tmpfs *** [ 0.031206] *** VALIDATE proc *** [ 0.032243] *** VALIDATE cgroup *** [ 0.033011] *** VALIDATE cgroup2 *** [ 0.034279] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.035158] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.036010] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.037030] Spectre V2 : User space: Vulnerable [ 0.038008] Speculative Store Bypass: Vulnerable [ 0.041408] debug: unmapping init [mem 0xffffffffb7059000-0xffffffffb7060fff] [ 0.043163] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.044673] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.045025] ... version: 2 [ 0.046012] ... bit width: 48 [ 0.047011] ... generic registers: 4 [ 0.048009] ... value mask: 0000ffffffffffff [ 0.049014] ... max period: 00007fffffffffff [ 0.050018] ... fixed-purpose events: 3 [ 0.051014] ... event mask: 000000070000000f [ 0.052311] rcu: Hierarchical SRCU implementation. [ 0.054498] smp: Bringing up secondary CPUs ... [ 0.055579] x86: Booting SMP configuration: [ 0.056026] .... node #0, CPUs: #1 #2 #3 [ 0.059436] smp: Brought up 1 node, 4 CPUs [ 0.061012] smpboot: Max logical packages: 1 [ 0.062017] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.135274] node 0 deferred pages initialised in 70ms [ 0.138522] devtmpfs: initialized [ 0.139285] x86/mm: Memory block size: 128MB [ 0.141788] gcov: version magic: 0x41383552 [ 0.143109] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.144074] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.145244] pinctrl core: initialized pinctrl subsystem [ 0.146218] [ 0.146749] ************************************************************* [ 0.147014] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.148018] ** ** [ 0.149015] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.150013] ** ** [ 0.151014] ** This means that this kernel is built to expose internal ** [ 0.152016] ** IOMMU data structures, which may compromise security on ** [ 0.153013] ** your system. ** [ 0.154016] ** ** [ 0.155015] ** If you see this message and you are not debugging the ** [ 0.156014] ** kernel, report this immediately to your vendor! ** [ 0.157017] ** ** [ 0.158013] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.159014] ************************************************************* [ 0.160743] NET: Registered protocol family 16 [ 0.161538] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.162063] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.163074] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.165031] cpuidle: using governor menu [ 0.169147] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.172511] PCI: Using configuration type 1 for base access [ 0.175141] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.186056] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.190041] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.195098] cryptd: max_cpu_qlen set to 1000 [ 0.197281] ACPI: Added _OSI(Module Device) [ 0.199019] ACPI: Added _OSI(Processor Device) [ 0.202020] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.204015] ACPI: Added _OSI(Processor Aggregator Device) [ 0.209319] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.216578] ACPI: Interpreter enabled [ 0.217059] ACPI: PM: (supports S0 S3 S4 S5) [ 0.218012] ACPI: Using IOAPIC for interrupt routing [ 0.219177] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.223503] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.236428] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.238042] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.240018] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.243100] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.247418] acpiphp: Slot [2] registered [ 0.249129] acpiphp: Slot [5] registered [ 0.251114] acpiphp: Slot [6] registered [ 0.252096] acpiphp: Slot [3] registered [ 0.253145] acpiphp: Slot [4] registered [ 0.254087] acpiphp: Slot [7] registered [ 0.256085] acpiphp: Slot [8] registered [ 0.257114] acpiphp: Slot [9] registered [ 0.258081] acpiphp: Slot [10] registered [ 0.259090] acpiphp: Slot [11] registered [ 0.261134] acpiphp: Slot [12] registered [ 0.265143] acpiphp: Slot [13] registered [ 0.267110] acpiphp: Slot [14] registered [ 0.268154] acpiphp: Slot [15] registered [ 0.270101] acpiphp: Slot [16] registered [ 0.272097] acpiphp: Slot [17] registered [ 0.274157] acpiphp: Slot [18] registered [ 0.275116] acpiphp: Slot [19] registered [ 0.277098] acpiphp: Slot [20] registered [ 0.279134] acpiphp: Slot [21] registered [ 0.280103] acpiphp: Slot [22] registered [ 0.282103] acpiphp: Slot [23] registered [ 0.284095] acpiphp: Slot [24] registered [ 0.285122] acpiphp: Slot [25] registered [ 0.287101] acpiphp: Slot [26] registered [ 0.289110] acpiphp: Slot [27] registered [ 0.290095] acpiphp: Slot [28] registered [ 0.292123] acpiphp: Slot [29] registered [ 0.293082] acpiphp: Slot [30] registered [ 0.295118] acpiphp: Slot [31] registered [ 0.296053] PCI host bridge to bus 0000:00 [ 0.297016] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.299019] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.301022] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.303023] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.306023] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.308019] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.309162] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.312905] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.315152] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.322506] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.326060] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.329017] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.331019] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.333019] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.335370] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.339821] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.343083] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.347814] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.352013] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.364025] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.369016] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.376878] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.383012] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.388020] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.407016] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.418113] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.422014] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.428000] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.447014] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.462237] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.463395] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.465383] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.467354] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.469299] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.473598] iommu: Default domain type: Passthrough [ 0.474610] SCSI subsystem initialized [ 0.476016] ACPI: bus type USB registered [ 0.478177] usbcore: registered new interface driver usbfs [ 0.480093] usbcore: registered new interface driver hub [ 0.482128] usbcore: registered new device driver usb [ 0.484193] pps_core: LinuxPPS API ver. 1 registered [ 0.487012] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.490056] PTP clock support registered [ 0.492137] EDAC MC: Ver: 3.0.0 [ 0.494186] PCI: Using ACPI for IRQ routing [ 0.495318] NetLabel: Initializing [ 0.496000] NetLabel: domain hash size = 128 [ 0.499025] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.501108] NetLabel: unlabeled traffic allowed by default [ 0.503212] vgaarb: loaded [ 0.505245] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.507011] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.513122] clocksource: Switched to clocksource kvm-clock [ 0.639506] VFS: Disk quotas dquot_6.6.0 [ 0.641069] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.643516] *** VALIDATE ramfs *** [ 0.644925] *** VALIDATE hugetlbfs *** [ 0.646490] pnp: PnP ACPI init [ 0.648809] pnp: PnP ACPI: found 6 devices [ 0.670145] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.673386] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.675237] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.677183] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.679243] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.681088] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.683747] NET: Registered protocol family 2 [ 0.686603] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.691572] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.695327] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.701051] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.704688] TCP: Hash tables configured (established 65536 bind 65536) [ 0.707331] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.709897] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.712475] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.714907] NET: Registered protocol family 1 [ 0.717377] RPC: Registered named UNIX socket transport module. [ 0.719519] RPC: Registered udp transport module. [ 0.720719] RPC: Registered tcp transport module. [ 0.722617] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.724954] NET: Registered protocol family 44 [ 0.726408] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.727861] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.729726] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.731855] PCI: CLS 0 bytes, default 64 [ 0.733565] Unpacking initramfs... [ 2.208413] debug: unmapping init [mem 0xffff9c75fcc64000-0xffff9c75fffcffff] [ 2.213851] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.217356] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.220618] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.724542] Initialise system trusted keyrings [ 2.726307] Key type blacklist registered [ 2.727986] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.737341] zbud: loaded [ 2.740398] *** VALIDATE nfs *** [ 2.741604] *** VALIDATE nfs4 *** [ 2.743306] pstore: using deflate compression [ 2.746756] Platform Keyring initialized [ 2.856583] NET: Registered protocol family 38 [ 2.859470] Key type asymmetric registered [ 2.861216] Asymmetric key parser 'x509' registered [ 2.864358] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.867827] io scheduler mq-deadline registered [ 2.869972] io scheduler kyber registered [ 2.871876] io scheduler bfq registered [ 2.874098] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.878123] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.881453] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.884900] ACPI: Power Button [PWRF] [ 2.891620] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.899797] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.914061] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.943899] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.970184] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.975635] Non-volatile memory driver v1.3 [ 2.977524] Linux agpgart interface v0.103 [ 3.013876] virtio_blk virtio1: [vda] 146720 512-byte logical blocks (75.1 MB/71.6 MiB) [ 3.016971] vda: detected capacity change from 0 to 75120640 [ 3.041460] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.044756] vdb: detected capacity change from 0 to 1073741824 [ 3.051938] libphy: Fixed MDIO Bus: probed [ 3.059353] usbcore: registered new interface driver usbserial_generic [ 3.061325] usbserial: USB Serial support registered for generic [ 3.064052] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.068800] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.070452] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.072568] mousedev: PS/2 mouse device common for all mice [ 3.076296] rtc_cmos 00:05: RTC can wake from S4 [ 3.078692] rtc_cmos 00:05: registered as rtc0 [ 3.078920] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.080260] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.087608] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.088130] intel_pstate: CPU model not supported [ 3.092931] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.098520] hid: raw HID events driver (C) Jiri Kosina [ 3.100486] usbcore: registered new interface driver usbhid [ 3.102572] usbhid: USB HID core driver [ 3.104043] drop_monitor: Initializing network drop monitor service [ 3.106510] Initializing XFRM netlink socket [ 3.109218] NET: Registered protocol family 10 [ 3.113310] Segment Routing with IPv6 [ 3.114899] NET: Registered protocol family 17 [ 3.117236] mpls_gso: MPLS GSO support [ 3.123853] RAS: Correctable Errors collector initialized. [ 3.126688] AVX version of gcm_enc/dec engaged. [ 3.128867] AES CTR mode by8 optimization enabled [ 3.229075] sched_clock: Marking stable (3229020408, 0)->(4189638141, -960617733) [ 3.233453] registered taskstats version 1 [ 3.235914] Loading compiled-in X.509 certificates [ 3.239763] zswap: loaded using pool lzo/zbud [ 3.270653] Key type big_key registered [ 3.286992] Key type encrypted registered [ 3.288835] ima: No TPM chip found, activating TPM-bypass! [ 3.291170] ima: Allocated hash algorithm: sha1 [ 3.293121] ima: No architecture policies found [ 3.294949] evm: Initialising EVM extended attributes: [ 3.297352] evm: security.selinux [ 3.298641] evm: security.ima [ 3.299805] evm: security.capability [ 3.301297] evm: HMAC attrs: 0x1 [ 3.304186] rtc_cmos 00:05: setting system clock to 2026-09-08 04:50:47 UTC (1788843047) [ 3.311041] debug: unmapping init [mem 0xffffffffb8003000-0xffffffffb81fffff] [ 3.314164] debug: unmapping init [mem 0xffffffffb6d82000-0xffffffffb7058fff] [ 3.323107] Write protecting the kernel read-only data: 28672k [ 3.327262] debug: unmapping init [mem 0xffffffffb5403000-0xffffffffb55fffff] [ 3.330237] debug: unmapping init [mem 0xffffffffb5d14000-0xffffffffb5dfffff] [ 3.368207] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 3.377395] systemd[1]: Detected virtualization kvm. [ 3.379385] systemd[1]: Detected architecture x86-64. [ 3.381410] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.412905] systemd[1]: No hostname configured. [ 3.415408] systemd[1]: Set hostname to . [ 3.419226] random: systemd: uninitialized urandom read (16 bytes read) [ 3.422090] systemd[1]: Initializing machine ID from random generator. [ 3.483803] random: ln: uninitialized urandom read (6 bytes read) [ 3.565595] random: systemd: uninitialized urandom read (16 bytes read) [ 3.568746] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ 3.580712] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ 3.591588] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. Starting Apply Kernel Variables... [ OK ] Started Memstrack Anylazing Service. Starting Setup Virtual Console... [ OK ] Listening on udev Control Socket. [ OK ] Reached target Slices. [ OK ] Reached target Swap. [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on Journal Socket (/dev/log). Starting Journal Service... [ OK ] Reached target Sockets. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Timers. [ OK ] Started Apply Kernel Variables. [ OK ] Started Setup Virtual Console. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Create list of required sta…vice nodes for the current kernel. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.336840] device-mapper: uevent: version 1.0.3 [ 4.339607] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 5.080713] virtio_net virtio0 ens2: renamed from eth0 [ 5.137228] scsi host0: ata_piix [ 5.152653] scsi host1: ata_piix [ 5.154693] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.157518] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 9.083270] dracut-initqueue[581]: RTNETLINK answers: File exists [ 9.811096] random: crng init done [ 9.813722] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 10.966208] 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 Initrd Default Target. [ OK ] Stopped target Initrd Root Device. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Paths. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Swap. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Sockets. [ 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... [ 13.813154] printk: systemd: 26 output lines suppressed due to ratelimiting [ 14.923561] SELinux: Disabled at runtime. [ 15.141098] 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) [ 15.170964] systemd[1]: Detected virtualization kvm. [ 15.175789] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 17.274880] systemd[1]: initrd-switch-root.service: Succeeded. [ 17.281210] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 17.291787] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 17.305488] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 17.317096] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 17.349225] systemd[1]: Starting Journal Service... Starting Journal Service... [ 17.374286] systemd[1]: Reached target rpc_pipefs.target. [ OK ] Reached target rpc_pipefs.target. Starting Remount Root and Kernel File Systems... Starting Create list of required st…ce nodes for the current kernel... Mounting Huge Pages File System... [ OK ] Listening on udev Control Socket. [ OK ] Listening on RPCbind Server Activation Socket. Mounting POSIX Message Queue File System... Activating swap /dev/disk/by-label/SWAP... Mounting Kernel Debug File System... [ OK ] Created slice User and Session Slice. [ 17.670781] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd Root File System. [ OK ] Stopped target Initrd File Systems. [ OK ] Listening on Process Core Dump Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Listening on udev Kernel Socket. Starting udev Coldplug all Devices... [ OK ] Created slice system-serial\x2dgetty.slice. Starting Apply Kernel Variables... [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Created slice system-getty.slice. [ OK ] Reached target Slices. [ OK ] Started Journal Service. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted Huge Pages File System. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted Kernel Debug File System. [ OK ] Started Apply Kernel Variables. [ OK ] Reached target Swap. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started udev Coldplug all Devices. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... Starting udev Kernel Device Manager... [ OK ] Mounted /mnt. [ 18.826676] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 19.912154] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 19.915768] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 20.807776] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 20.848250] EDAC sbridge: Ver: 1.1.2 [ 24.244084] Key type dns_resolver registered [* ] A start job is running for Configur…-only root support (7s / no limit)[ 25.122107] NFS: Registering the id_resolver key type [ 25.124816] Key type id_resolver registered [ 25.128134] Key type id_legacy registered [** ] A start job is running for Configur…-only root support (8s / no limit) [*** ] A start job is running for Configur…-only root support (8s / no limit) [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Login Service... [ OK ] Started D-Bus System Message Bus. Starting Network Manager... Starting Restore /run/initramfs on shutdown... [ OK ] Started irqbalance daemon. [ OK ] Reached target sshd-keygen.target. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting OpenSSH server daemon... Starting Dynamic System Tuning Daemon... Starting GSSAPI Proxy Daemon... Starting Hostname Service... [ OK ] Started Login Service. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started OpenSSH server daemon. [ OK ] Started Permit User Sessions. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Command Scheduler. [ OK ] Started Getty on tty1. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting System Logging Service... Starting Crash recovery kernel arming... Starting Notify NFS peers of a restart... [ 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 oleg420-client login: [ 95.867431] libcfs: loading out-of-tree module taints kernel. [ 96.067601] Key type ._llcrypt registered [ 96.071746] Key type .llcrypt registered [ 97.044029] hrtimer: interrupt took 4866785 ns [ 97.258755] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 97.277065] alg: No test for adler32 (adler32-zlib) [ 98.896100] Lustre: Lustre: Build Version: 2.17.57_112_g92cfe64 [ 100.310353] LNet: Added LNI 192.168.204.20@tcp [8/256/0/180] [ 102.217335] Key type lgssc registered [ 104.683677] Lustre: Echo OBD driver; http://www.lustre.org/ [ 298.385633] Lustre: Mounted lustre-client - version 2.17.57_112_g92cfe64 [ 304.694333] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 321.350855] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing check_logdir /tmp/testlogs/ [ 324.066084] Lustre: lustre-OST0000-osc-ffff9c76518c4800: disconnect after 23s idle [ 325.807729] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing yml_node [ 330.679766] Lustre: DEBUG MARKER: Client: 2.17.57.112 [ 333.986501] Lustre: DEBUG MARKER: MDS: 2.17.57.112 [ 337.284087] Lustre: DEBUG MARKER: OSS: 2.17.57.112 [ 339.225858] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Tue Sep 8 00:56:21 EDT 2026 [ 358.505290] Lustre: DEBUG MARKER: excepting tests: 32newtarball [ 359.932670] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 362.535797] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 372.900299] Lustre: Unmounted lustre-client [ 507.334908] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 515.302921] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 542.209948] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 596.438199] Lustre: DEBUG MARKER: == conf-sanity test 121: failover MGS ==================== 01:00:38 (1788843638) [ 650.487804] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 657.051630] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 703.351928] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mgc.*.mgs_server_uuid 1475 0 [ 758.020514] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 764.975622] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 812.342273] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mgc.*.mgs_server_uuid 1475 0 [ 838.181763] Lustre: DEBUG MARKER: == conf-sanity test 122a: Check OST sequence update ====== 01:04:40 (1788843880) [ 967.990305] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 974.909835] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1001.232162] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1002.317701] Lustre: Mounted lustre-client - version 2.17.57_112_g92cfe64 [ 1028.475268] Lustre: Unmounted lustre-client [ 1060.625497] Key type lgssc unregistered [ 1061.096334] LNet: 21927:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1061.115395] LNetError: 21927:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1061.146569] LNet: Removed LNI 192.168.204.20@tcp [ 1062.314319] Key type .llcrypt unregistered [ 1062.317645] Key type ._llcrypt unregistered [ 1081.247636] Key type ._llcrypt registered [ 1081.250548] Key type .llcrypt registered [ 1082.672484] Lustre: DEBUG MARKER: == conf-sanity test 123aa: llog_print works with FIDs and simple names ========================================================== 01:08:45 (1788844125) [ 1085.193435] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1085.205342] alg: No test for adler32 (adler32-zlib) [ 1086.277973] Lustre: Lustre: Build Version: 2.17.57_112_g92cfe64 [ 1086.495957] LNet: Added LNI 192.168.204.20@tcp [8/256/0/180] [ 1088.247259] Key type lgssc registered [ 1089.837360] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1149.768353] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1157.433939] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1180.617698] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1187.901853] Lustre: Mounted lustre-client - version 2.17.57_112_g92cfe64 [ 1201.363315] Lustre: DEBUG MARKER: == conf-sanity test 123ab: llog_print params output values from set_param -P ========================================================== 01:10:43 (1788844243) [ 1216.098478] Lustre: DEBUG MARKER: == conf-sanity test 123ac: llog_print with --start and --end ========================================================== 01:10:58 (1788844258) [ 1228.208843] Lustre: DEBUG MARKER: == conf-sanity test 123ad: llog_print shows all records == 01:11:10 (1788844270) [ 1241.462112] Lustre: DEBUG MARKER: == conf-sanity test 123ae: llog_cancel can cancel requested record ========================================================== 01:11:23 (1788844283) [ 1270.794879] Lustre: DEBUG MARKER: == conf-sanity test 123af: llog_catlist can show all config files correctly ========================================================== 01:11:53 (1788844313) [ 1288.498362] Lustre: DEBUG MARKER: == conf-sanity test 123ag: llog_print skips values deleted by set_param -P -d ========================================================== 01:12:10 (1788844330) [ 1307.645519] Lustre: DEBUG MARKER: == conf-sanity test 123ah: del_ost cancels config log entries correctly ========================================================== 01:12:29 (1788844349) [ 1318.472073] Lustre: Unmounted lustre-client [ 1318.964224] Lustre: Mounted lustre-client - version 2.17.57_112_g92cfe64 [ 1322.008154] Lustre: Unmounted lustre-client [ 1361.025212] Key type lgssc unregistered [ 1361.471772] LNet: 30306:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1361.489435] LNetError: 30306:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1361.520574] LNet: Removed LNI 192.168.204.20@tcp [ 1362.668915] Key type .llcrypt unregistered [ 1362.671237] Key type ._llcrypt unregistered [ 1397.568413] Key type ._llcrypt registered [ 1397.585857] Key type .llcrypt registered [ 1398.235162] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1398.258160] alg: No test for adler32 (adler32-zlib) [ 1399.452765] Lustre: Lustre: Build Version: 2.17.57_112_g92cfe64 [ 1399.778562] LNet: Added LNI 192.168.204.20@tcp [8/256/0/180] [ 1401.609220] Key type lgssc registered [ 1403.178893] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1508.260390] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1516.170151] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1544.509776] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1608.961440] Lustre: DEBUG MARKER: == conf-sanity test 123ai: llog_print display all non skipped records ========================================================== 01:17:31 (1788844651) [ 1650.573758] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1657.721324] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1687.330528] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1693.828554] Lustre: Mounted lustre-client - version 2.17.57_112_g92cfe64 [ 1853.964557] Lustre: DEBUG MARKER: == conf-sanity test 123aj: check permanent TBF rules ===== 01:21:36 (1788844896) [ 1905.371925] Lustre: DEBUG MARKER: == conf-sanity test 123F: clear and reset all parameters using set_param -F ========================================================== 01:22:27 (1788844947) [ 1917.276422] Lustre: Unmounted lustre-client [ 1999.861841] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2006.280514] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2032.127874] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2033.535699] Lustre: Mounted lustre-client - version 2.17.57_112_g92cfe64 [ 2040.820971] Lustre: Unmounted lustre-client [ 2071.974996] Key type lgssc unregistered [ 2072.345593] LNet: 41645:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2072.372290] LNetError: 41645:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2072.393901] LNet: Removed LNI 192.168.204.20@tcp [ 2073.522170] Key type .llcrypt unregistered [ 2073.524651] Key type ._llcrypt unregistered [ 2094.209137] Key type ._llcrypt registered [ 2094.213852] Key type .llcrypt registered [ 2095.717952] Lustre: DEBUG MARKER: == conf-sanity test 123G: clear and reset all parameters using apply_yaml ========================================================== 01:25:38 (1788845138) [ 2099.062105] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2099.076857] alg: No test for adler32 (adler32-zlib) [ 2100.261633] Lustre: Lustre: Build Version: 2.17.57_112_g92cfe64 [ 2100.615333] LNet: Added LNI 192.168.204.20@tcp [8/256/0/180] [ 2102.407229] Key type lgssc registered [ 2104.370400] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2162.039911] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2169.055985] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2193.608784] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2205.268300] Lustre: Mounted lustre-client - version 2.17.57_112_g92cfe64 [ 2219.246454] Lustre: Unmounted lustre-client [ 2308.873335] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2314.987480] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2340.965270] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2341.898518] Lustre: Mounted lustre-client - version 2.17.57_112_g92cfe64 [ 2349.230794] Lustre: Unmounted lustre-client [ 2386.372796] Key type lgssc unregistered [ 2386.630942] LNet: 47181:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2386.645492] LNetError: 47181:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2386.669262] LNet: Removed LNI 192.168.204.20@tcp [ 2387.492373] Key type .llcrypt unregistered [ 2387.497453] Key type ._llcrypt unregistered [ 2405.948778] Key type ._llcrypt registered [ 2405.951483] Key type .llcrypt registered [ 2407.665693] Lustre: DEBUG MARKER: == conf-sanity test 123H: check concurent accesses with 'lctl llog_print ========================================================== 01:30:49 (1788845449) [ 2410.463660] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2410.584748] alg: No test for adler32 (adler32-zlib) [ 2411.768768] Lustre: Lustre: Build Version: 2.17.57_112_g92cfe64 [ 2412.139272] LNet: Added LNI 192.168.204.20@tcp [8/256/0/180] [ 2413.839218] Key type lgssc registered [ 2415.347644] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2470.093698] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2475.165876] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2495.643397] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2497.332137] Lustre: Mounted lustre-client - version 2.17.57_112_g92cfe64 [ 2612.495589] Lustre: DEBUG MARKER: == conf-sanity test 124: check failover after replace_nids ========================================================== 01:34:14 (1788845654) [ 2614.505598] Lustre: DEBUG MARKER: SKIP: conf-sanity test_124 needs MDT failover setup [ 2616.979471] Lustre: DEBUG MARKER: == conf-sanity test 126: mount in parallel shouldn't cause a crash ========================================================== 01:34:19 (1788845659) [ 2619.280434] Lustre: Unmounted lustre-client [ 2647.620498] Key type lgssc unregistered [ 2647.910923] LNet: 52517:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2647.921988] LNetError: 52517:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2647.938048] LNet: Removed LNI 192.168.204.20@tcp [ 2648.575508] Key type .llcrypt unregistered [ 2648.578054] Key type ._llcrypt unregistered [ 2679.698659] Key type ._llcrypt registered [ 2679.701722] Key type .llcrypt registered [ 2680.298498] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2680.330530] alg: No test for adler32 (adler32-zlib) [ 2681.403923] Lustre: Lustre: Build Version: 2.17.57_112_g92cfe64 [ 2681.671511] LNet: Added LNI 192.168.204.20@tcp [8/256/0/180] [ 2683.336123] Key type lgssc registered [ 2685.254320] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2726.573423] Lustre: DEBUG MARKER: == conf-sanity test 127: direct io overwrite on full ost ========================================================== 01:36:08 (1788845768) [ 2739.983396] Key type lgssc unregistered [ 2740.240588] LNet: 54923:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2740.245679] LNetError: 54923:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2740.257236] LNet: Removed LNI 192.168.204.20@tcp [ 2740.871569] Key type .llcrypt unregistered [ 2740.874204] Key type ._llcrypt unregistered [ 2752.961583] Key type ._llcrypt registered [ 2752.964813] Key type .llcrypt registered [ 2753.594502] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2753.605505] alg: No test for adler32 (adler32-zlib) [ 2754.961699] Lustre: Lustre: Build Version: 2.17.57_112_g92cfe64 [ 2755.286980] LNet: Added LNI 192.168.204.20@tcp [8/256/0/180] [ 2756.983781] Key type lgssc registered [ 2758.488158] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2822.480830] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2828.367420] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2851.658427] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2863.241383] Lustre: Mounted lustre-client - version 2.17.57_112_g92cfe64 [ 2920.758334] Lustre: DEBUG MARKER: == conf-sanity test 128: Force using remote logs with --nolocallogs ========================================================== 01:39:23 (1788845963) [ 2922.689683] Lustre: DEBUG MARKER: SKIP: conf-sanity test_128 need separate mgs device [ 2925.723370] Lustre: DEBUG MARKER: == conf-sanity test 129: attempt to connect an OST with the same index should fail ========================================================== 01:39:27 (1788845967) [ 2928.197257] Lustre: Unmounted lustre-client [ 2996.014488] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3004.123326] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3078.839856] Lustre: DEBUG MARKER: == conf-sanity test 130: re-register an MDT after writeconf ========================================================== 01:42:01 (1788846121) [ 3167.219813] Lustre: Mounted lustre-client - version 2.17.57_112_g92cfe64 [ 3173.583534] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 3187.704737] Lustre: lustre-MDT0001-mdc-ffff9c76518c1800: Connection to lustre-MDT0001 (at 192.168.204.120@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3192.806268] Lustre: lustre-OST0000-osc-ffff9c76518c1800: disconnect after 23s idle [ 3206.340952] LustreError: 63607:0:(obd_config.c:2102:class_config_llog_handler()) MGC192.168.204.120@tcp: cfg command failed: rc = -17 [ 3206.362550] Lustre: cmd=cf001 0:lustre-MDT0001-mdc 1:mdc 2:lustre-clilmv_UUID [ 3206.362550] [ 3206.371695] LustreError: 62881:0:(mgc_request.c:614:do_requeue()) failed processing log: -17 [ 3224.417680] Lustre: DEBUG MARKER: == conf-sanity test 131: MDT backup restore with project ID and inheritance flag ========================================================== 01:44:26 (1788846266) [ 3295.572436] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 3388.835366] Lustre: Unmounted lustre-client [ 3567.641386] Lustre: Mounted lustre-client - version 2.17.57_112_g92cfe64 [ 3574.522099] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 3641.342172] Lustre: DEBUG MARKER: == conf-sanity test 132: hsm_actions processed after failover ========================================================== 01:51:23 (1788846683) [ 3643.898284] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3644.485209] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 3644.494341] Lustre: Skipped 1 previous similar message [ 3662.646570] Lustre: Unmounted lustre-client [ 3827.336814] Lustre: DEBUG MARKER: == conf-sanity test 133: stripe QOS: free space balance in a pool ========================================================== 01:54:29 (1788846869) [ 3829.512219] Lustre: DEBUG MARKER: SKIP: conf-sanity test_133 needs >= 4 OSTs [ 3832.663439] Lustre: DEBUG MARKER: == conf-sanity test 134: check_iam works without faults == 01:54:34 (1788846874) [ 3934.597893] Lustre: Mounted lustre-client - version 2.17.57_112_g92cfe64 [ 3942.764856] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 4732.586036] Lustre: DEBUG MARKER: == conf-sanity test 135: check the behavior when changelog is wrapped around ========================================================== 02:09:35 (1788847775) [ 4735.133269] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4735.139329] Lustre: Skipped 1 previous similar message [ 4745.560168] Lustre: Unmounted lustre-client [ 4883.255290] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4888.912632] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4915.702554] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4916.609182] Lustre: Mounted lustre-client - version 2.17.57_112_g92cfe64 [ 6669.293757] Lustre: 95433:0:(llog_cat.c:310:llog_cat_process_or_fork()) lustre-MDT0000-mdc-ffff9c76587f8800: catlog [0xa:0x5:0x0] crosses index zero [ 6691.394426] Lustre: Unmounted lustre-client [ 6714.381568] Key type lgssc unregistered [ 6714.569794] LNet: 103597:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6714.579797] LNetError: 103597:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6714.600590] LNet: Removed LNI 192.168.204.20@tcp [ 6715.135412] Key type .llcrypt unregistered [ 6715.139845] Key type ._llcrypt unregistered [ 6722.969856] Key type ._llcrypt registered [ 6722.972581] Key type .llcrypt registered [ 6724.083444] Lustre: DEBUG MARKER: == conf-sanity test 136: don't panic with bad obdecho setup ========================================================== 02:42:47 (1788849767) [ 6737.843237] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6737.859080] alg: No test for adler32 (adler32-zlib) [ 6738.885603] Lustre: Lustre: Build Version: 2.17.57_112_g92cfe64 [ 6739.137177] LNet: Added LNI 192.168.204.20@tcp [8/256/0/180] [ 6740.807706] Key type lgssc registered [ 6741.743672] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6800.975357] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6805.461302] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6823.968358] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6826.606271] Lustre: Mounted lustre-client - version 2.17.57_112_g92cfe64 [ 6839.751728] Lustre: Unmounted lustre-client [ 6935.468455] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6939.907394] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6957.106996] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6993.335626] Lustre: DEBUG MARKER: == conf-sanity test 137: a new MDT should inherit pools, parameters and failnode ========================================================== 02:47:16 (1788850036) [ 6994.592327] Lustre: DEBUG MARKER: SKIP: conf-sanity test_137 needs >= 3 MDTs [ 6996.075509] Lustre: DEBUG MARKER: == conf-sanity test 140: remove_updatelog script actions ========================================================== 02:47:19 (1788850039) [ 7021.422551] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7026.322590] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7041.638477] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7052.311743] Lustre: Mounted lustre-client - version 2.17.57_112_g92cfe64 [ 7057.383779] Lustre: lustre-MDT0000-mdc-ffff9c7658f63800: Connection to lustre-MDT0000 (at 192.168.204.120@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7062.508570] Lustre: lustre-MDT0001-mdc-ffff9c7658f63800: Connection to lustre-MDT0001 (at 192.168.204.120@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7077.855622] Lustre: 104353:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788850106/real 1788850106] req@00000000bee69071 x1875744950135936/t0(0) o400->MGC192.168.204.120@tcp@192.168.204.120@tcp:26/25 lens 224/224 e 0 to 1 dl 1788850122 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 7077.877364] LustreError: MGC192.168.204.120@tcp: Connection to MGS (at 192.168.204.120@tcp) was lost; in progress operations using this service will fail [ 7198.704209] Lustre: Evicted from MGS (at 192.168.204.120@tcp) after server handle changed from 0xf6f33986c1b35739 to 0xf6f33986c1b36dd6 [ 7198.711369] Lustre: MGC192.168.204.120@tcp: Connection restored to 192.168.204.120@tcp (at 192.168.204.120@tcp) [ 7205.865534] Lustre: 113731:0:(mgc_request.c:1900:mgc_process_log()) MGC192.168.204.120@tcp: IR log lustre-cliir failed, not fatal: rc = -2 [ 7208.936326] LustreError: lustre-MDT0000-mdc-ffff9c7658f63800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 7208.957279] Lustre: lustre-MDT0000-mdc-ffff9c7658f63800: Connection restored to 192.168.204.120@tcp (at 192.168.204.120@tcp) [ 7212.710622] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7213.585550] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 7214.055796] LustreError: lustre-MDT0001-mdc-ffff9c7658f63800: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 7214.083650] Lustre: lustre-MDT0001-mdc-ffff9c7658f63800: Connection restored to 192.168.204.120@tcp (at 192.168.204.120@tcp) [ 7217.185077] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7218.232948] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 7229.022435] Lustre: Unmounted lustre-client [ 7308.502909] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7311.908531] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7326.973825] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7356.176332] Lustre: DEBUG MARKER: == conf-sanity test 150: test setting max_cached_mb to a % ========================================================== 02:53:19 (1788850399) [ 7376.081370] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7379.551040] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7391.937509] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7392.246428] Lustre: Mounted lustre-client - version 2.17.57_112_g92cfe64 [ 7397.595821] LustreError: 121049: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 [ 7401.767812] Lustre: DEBUG MARKER: == conf-sanity test 151a: damaged local config doesn't prevent mounting ========================================================== 02:54:05 (1788850445) [ 7402.752519] Lustre: Unmounted lustre-client [ 7423.519632] Key type lgssc unregistered [ 7423.675330] LNet: 122052:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7423.682457] LNetError: 122052:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7423.696947] LNet: Removed LNI 192.168.204.20@tcp [ 7424.121583] Key type .llcrypt unregistered [ 7424.123634] Key type ._llcrypt unregistered [ 7431.724196] Key type ._llcrypt registered [ 7431.728579] Key type .llcrypt registered [ 7432.034127] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7432.043457] alg: No test for adler32 (adler32-zlib) [ 7433.042961] Lustre: Lustre: Build Version: 2.17.57_112_g92cfe64 [ 7433.217694] LNet: Added LNI 192.168.204.20@tcp [8/256/0/180] [ 7434.871512] Key type lgssc registered [ 7435.684112] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7494.671687] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7497.697368] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7508.678457] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7582.940319] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7585.832931] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7597.709533] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7653.107580] Lustre: DEBUG MARKER: == conf-sanity test 151b: -ENOSPC doesn't affect mount === 02:58:13 (1788850693) [ 7675.717232] Key type lgssc unregistered [ 7675.989596] LNet: 129247:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7676.013240] LNetError: 129247:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7676.046051] LNet: Removed LNI 192.168.204.20@tcp [ 7676.872775] Key type .llcrypt unregistered [ 7676.874413] Key type ._llcrypt unregistered [ 7695.125824] Key type ._llcrypt registered [ 7695.128850] Key type .llcrypt registered [ 7695.852170] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7695.864348] alg: No test for adler32 (adler32-zlib) [ 7697.080296] Lustre: Lustre: Build Version: 2.17.57_112_g92cfe64 [ 7697.518521] LNet: Added LNI 192.168.204.20@tcp [8/256/0/180] [ 7699.231239] Key type lgssc registered [ 7700.470630] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7750.193538] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7756.202330] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7777.346696] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7896.434449] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7902.481150] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7928.522432] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7972.372749] Lustre: DEBUG MARKER: == conf-sanity test 152: seq allocation error in OSP ===== 03:03:35 (1788851015) [ 8064.564711] Lustre: Mounted lustre-client - version 2.17.57_112_g92cfe64 [ 8070.127476] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 8084.087742] Lustre: DEBUG MARKER: ADD OST3 [ 8090.092750] Lustre: lustre-OST0000-osc-ffff9c7651910000: disconnect after 23s idle [ 8098.345511] Lustre: DEBUG MARKER: STOP OST3 [ 8113.132337] Lustre: lustre-OST0003-osc-ffff9c7651910000: Connection to lustre-OST0003 (at 192.168.204.120@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8125.527697] Lustre: DEBUG MARKER: START OST3 again [ 8135.023252] Lustre: lustre-OST0003-osc-ffff9c7651910000: Connection restored to 192.168.204.120@tcp (at 192.168.204.120@tcp) [ 8166.907479] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 8166.924856] LustreError: 140125:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff9c7651910000: inode [0x240000bd0:0x1:0x0] mdc close failed: rc = -108 [ 8177.251584] Lustre: Unmounted lustre-client [ 8298.700958] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8305.344774] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8331.497639] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8370.065986] Lustre: DEBUG MARKER: == conf-sanity test 153a: bypass invalid NIDs quickly ==== 03:10:12 (1788851412) [ 8478.099452] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8484.015543] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8508.768719] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8581.720663] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8589.496617] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8612.925157] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8615.584757] LNetError: 129656:0:(lib-move.c:2158:lnet_handle_find_routed_path()) peer 192.168.252.112@tcp2 has no available nets [ 8615.825905] Lustre: Mounted lustre-client - version 2.17.57_112_g92cfe64 [ 8621.353291] Lustre: Unmounted lustre-client [ 8652.632260] Key type lgssc unregistered [ 8669.343763] LNet: 1 local NIs in recovery (showing 1): 192.168.204.20@tcp [ 8676.575839] LNet: 149104:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8676.586700] LNetError: 149104:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8746.294723] LNet: Removed LNI 192.168.204.20@tcp [ 8747.111282] Key type .llcrypt unregistered [ 8747.112803] Key type ._llcrypt unregistered [ 8763.211398] Key type ._llcrypt registered [ 8763.214788] Key type .llcrypt registered [ 8764.673404] Lustre: DEBUG MARKER: == conf-sanity test 153b: added IPv6 NID support ========= 03:16:47 (1788851807) [ 8783.439953] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8783.473869] alg: No test for adler32 (adler32-zlib) [ 8784.627122] Lustre: Lustre: Build Version: 2.17.57_112_g92cfe64 [ 8784.873461] LNet: Added LNI 192.168.204.20@tcp [8/256/0/180] [ 8786.592089] Key type lgssc registered [ 8788.017545] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8883.200216] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8891.696761] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8916.112345] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9058.733392] Lustre: Mounted lustre-client - version 2.17.57_112_g92cfe64 [ 9064.346482] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 9069.692474] Lustre: Unmounted lustre-client [ 9069.811808] LNetError: 150280:0:(lib-socket.c:308:lnet_sock_create()) Error trying to bind to fe80::54bc:89ff:fe7c:b410/1023: rc = -22 [ 9069.835407] LNetError: Unexpected error -22 connecting to 6699:7654::1234:1234:d84@tcp at host [6699:7654::1234:1234:d84]:988 [ 9070.115800] Lustre: Mounted lustre-client - version 2.17.57_112_g92cfe64 [ 9070.285513] Lustre: Unmounted lustre-client [ 9071.090406] LNetError: 150282:0:(lib-socket.c:308:lnet_sock_create()) Error trying to bind to fe80::54bc:89ff:fe7c:b410/1023: rc = -22 [ 9071.104884] LNetError: Unexpected error -22 connecting to 6699:7654::1234:1234:d84@tcp at host [6699:7654::1234:1234:d84]:988 [ 9071.394511] Lustre: Mounted lustre-client - version 2.17.57_112_g92cfe64 [ 9071.403425] Lustre: Skipped 1 previous similar message [ 9071.606261] Lustre: Unmounted lustre-client [ 9071.607871] Lustre: Skipped 1 previous similar message [ 9073.439476] LNetError: 150282:0:(lib-socket.c:308:lnet_sock_create()) Error trying to bind to fe80::54bc:89ff:fe7c:b410/1023: rc = -22 [ 9073.449985] LNetError: Unexpected error -22 connecting to 6699:7654::1234:1234:d84@tcp at host [6699:7654::1234:1234:d84]:988 [ 9073.645256] Lustre: Mounted lustre-client - version 2.17.57_112_g92cfe64 [ 9073.648382] Lustre: Skipped 3 previous similar messages [ 9073.787264] Lustre: Unmounted lustre-client [ 9073.788834] Lustre: Skipped 3 previous similar messages [ 9074.664425] Lustre: 156199: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 [ 9075.200158] Lustre: 156228: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 [ 9082.491712] Lustre: DEBUG MARKER: == conf-sanity test 153c: don't stuck on unreached NID === 03:22:05 (1788852125) [ 9123.807495] LNet: 1 local NIs in recovery (showing 1): 192.168.204.20@tcp [ 9211.949657] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9217.983035] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9244.410112] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9328.989118] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9334.718695] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9357.366822] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9362.978334] LustreError: 161825:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.252.112@tcp: failed processing log, type 1: rc = -110 [ 9409.506454] LNet: 1 local NIs in recovery (showing 1): 192.168.204.20@tcp [ 9487.392563] LNet: 1 local NIs in recovery (showing 1): 192.168.204.20@tcp [ 9498.597987] 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 [ 9498.644735] Lustre: Unmounted lustre-client [ 9498.651623] Lustre: Skipped 3 previous similar messages [ 9498.676248] LustreError: 161825:0:(super25.c:179:lustre_fill_super()) llite: Unable to mount : rc = -110 [ 9527.477518] Key type lgssc unregistered [ 9547.809751] LNet: 1 local NIs in recovery (showing 1): 192.168.204.20@tcp [ 9550.239944] LNet: 162284:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9550.256807] LNetError: 162284:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9618.922108] LNet: Removed LNI 192.168.204.20@tcp [ 9619.869169] Key type .llcrypt unregistered [ 9619.875218] Key type ._llcrypt unregistered [ 9642.975631] Key type ._llcrypt registered [ 9642.978718] Key type .llcrypt registered [ 9645.052061] Lustre: DEBUG MARKER: == conf-sanity test 154: expand .. on rename after MDT backup restore ========================================================== 03:31:26 (1788852686) [ 9674.341871] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9674.386423] alg: No test for adler32 (adler32-zlib) [ 9675.774666] Lustre: Lustre: Build Version: 2.17.57_112_g92cfe64 [ 9676.232239] LNet: Added LNI 192.168.204.20@tcp [8/256/0/180] [ 9678.153576] Key type lgssc registered [ 9680.097904] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9837.440348] Lustre: Mounted lustre-client - version 2.17.57_112_g92cfe64 [ 9842.623876] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 9861.004975] Lustre: Unmounted lustre-client [10046.743167] Lustre: Mounted lustre-client - version 2.17.57_112_g92cfe64 [10052.132640] Lustre: DEBUG MARKER: Using TIMEOUT=20 [10058.709253] Lustre: Unmounted lustre-client [10122.218647] Lustre: DEBUG MARKER: == conf-sanity test 155: gap in seq allocation from ofd after restarting ========================================================== 03:39:24 (1788853164) [10244.731479] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10251.403950] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10275.369253] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10422.694362] Lustre: Mounted lustre-client - version 2.17.57_112_g92cfe64 [10430.014223] Lustre: DEBUG MARKER: Using TIMEOUT=20 [10447.470396] Lustre: Unmounted lustre-client [10589.610960] Lustre: Mounted lustre-client - version 2.17.57_112_g92cfe64 [10595.385356] Lustre: DEBUG MARKER: Using TIMEOUT=20 [10615.263946] Lustre: lustre-OST0001-osc-ffff9c7651868800: disconnect after 23s idle [10616.958522] Lustre: DEBUG MARKER: == conf-sanity test 156: root_fid on export consistent with client mount ========================================================== 03:47:39 (1788853659) [10620.556407] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [10630.970816] Lustre: Unmounted lustre-client [10850.100087] Lustre: Mounted lustre-client - version 2.17.57_112_g92cfe64 [10856.268935] Lustre: DEBUG MARKER: Using TIMEOUT=20 [10865.649784] Lustre: Unmounted lustre-client [10866.210261] Lustre: Mounted lustre-client - version 2.17.57_112_g92cfe64 [10875.270520] Lustre: Unmounted lustre-client [10876.999414] Lustre: DEBUG MARKER: == conf-sanity test 157a: test allow_register for MDT registration and MGS restart ========================================================== 03:51:59 (1788853919) [10953.114438] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10959.993973] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10992.091118] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10993.189330] Lustre: Mounted lustre-client - version 2.17.57_112_g92cfe64 [11090.433250] LustreError: lustre-OST0001-osc-ffff9c764e86a800: operation ost_connect to node 192.168.204.120@tcp failed: rc = -19 [11116.024430] Lustre: lustre-MDT0000-mdc-ffff9c764e86a800: Connection to lustre-MDT0000 (at 192.168.204.120@tcp) was lost; in progress operations using this service will wait for recovery to complete [11131.376784] LustreError: MGC192.168.204.120@tcp: Connection to MGS (at 192.168.204.120@tcp) was lost; in progress operations using this service will fail [11131.411385] LustreError: lustre-MDT0000-mdc-ffff9c764e86a800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [11131.452507] Lustre: Evicted from MGS (at 192.168.204.120@tcp) after server handle changed from 0x819078a9f3cfd18b to 0x819078a9f3cfd44e [11131.461633] Lustre: lustre-MDT0000-mdc-ffff9c764e86a800: Connection restored to 192.168.204.120@tcp (at 192.168.204.120@tcp) [11131.492843] Lustre: Skipped 1 previous similar message [11187.171712] Lustre: Unmounted lustre-client [11227.699062] Key type lgssc unregistered [11228.002716] LNet: 189834:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11228.012843] LNetError: 189834:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11228.039111] LNet: Removed LNI 192.168.204.20@tcp [11228.975165] Key type .llcrypt unregistered [11228.978626] Key type ._llcrypt unregistered [11243.388775] Key type ._llcrypt registered [11243.390804] Key type .llcrypt registered [11245.392716] Lustre: DEBUG MARKER: == conf-sanity test 157b: verify allow_register (block new OSTs, allow existing) ========================================================== 03:58:07 (1788854287) [11248.658225] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11248.719375] alg: No test for adler32 (adler32-zlib) [11250.057192] Lustre: Lustre: Build Version: 2.17.57_112_g92cfe64 [11250.514677] LNet: Added LNI 192.168.204.20@tcp [8/256/0/180] [11252.271199] Key type lgssc registered [11253.733556] Lustre: Echo OBD driver; http://www.lustre.org/ [11322.376208] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11328.598249] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11352.706751] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11359.808218] Lustre: Mounted lustre-client - version 2.17.57_112_g92cfe64 [11457.004366] LustreError: lustre-OST0001-osc-ffff9c7658f61000: operation ost_connect to node 192.168.204.120@tcp failed: rc = -19 [11587.286559] Lustre: Unmounted lustre-client [11620.605798] Key type lgssc unregistered [11620.895559] LNet: 195877:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11620.909349] LNetError: 195877:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11620.937427] LNet: Removed LNI 192.168.204.20@tcp [11621.820203] Key type .llcrypt unregistered [11621.823147] Key type ._llcrypt unregistered [11633.726244] Key type ._llcrypt registered [11633.728370] Key type .llcrypt registered [11635.306175] Lustre: DEBUG MARKER: == conf-sanity test 160: MGC updates failnodes from all participants ========================================================== 04:04:37 (1788854677) [11670.224839] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11670.248138] alg: No test for adler32 (adler32-zlib) [11671.670282] Lustre: Lustre: Build Version: 2.17.57_112_g92cfe64 [11672.079593] LNet: Added LNI 192.168.204.20@tcp [8/256/0/180] [11673.890063] Key type lgssc registered [11675.414189] Lustre: Echo OBD driver; http://www.lustre.org/ [11785.676262] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11792.709288] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11819.422871] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11860.571499] Key type lgssc unregistered [11860.950577] LNet: 200861:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11860.962970] LNetError: 200861:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11860.997083] LNet: Removed LNI 192.168.204.20@tcp [11862.099695] Key type .llcrypt unregistered [11862.106448] Key type ._llcrypt unregistered [11974.746666] Key type ._llcrypt registered [11974.750115] Key type .llcrypt registered [11975.342299] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11975.359558] alg: No test for adler32 (adler32-zlib) [11976.469549] Lustre: Lustre: Build Version: 2.17.57_112_g92cfe64 [11976.789258] LNet: Added LNI 192.168.204.20@tcp [8/256/0/180] [11978.495249] Key type lgssc registered [11979.905282] Lustre: Echo OBD driver; http://www.lustre.org/ [12027.024921] Lustre: DEBUG MARKER: == conf-sanity test 161: test '-o mgsname' option ======== 04:11:09 (1788855069) [12073.498865] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12082.178924] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12110.766815] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12112.589057] Lustre: Mounted lustre-client - version 2.17.57_112_g92cfe64 [12115.201446] Lustre: Unmounted lustre-client [12115.812578] Lustre: Mounted lustre-client - version 2.17.57_112_g92cfe64 [12116.112256] Lustre: Unmounted lustre-client [12124.581390] Lustre: Unmounted lustre-client [12156.209715] Key type lgssc unregistered [12156.568826] LNet: 205842:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12156.580493] LNetError: 205842:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12156.601796] LNet: Removed LNI 192.168.204.20@tcp [12157.534204] Key type .llcrypt unregistered [12157.538748] Key type ._llcrypt unregistered [12171.334367] Key type ._llcrypt registered [12171.347324] Key type .llcrypt registered [12172.968372] Lustre: DEBUG MARKER: == conf-sanity test 162: replace nids with -o noclient === 04:13:35 (1788855215) [12176.916244] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12176.927522] alg: No test for adler32 (adler32-zlib) [12178.021810] Lustre: Lustre: Build Version: 2.17.57_112_g92cfe64 [12178.393843] LNet: Added LNI 192.168.204.20@tcp [8/256/0/180] [12180.128043] Key type lgssc registered [12181.863570] Lustre: Echo OBD driver; http://www.lustre.org/ [12241.805908] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12247.611952] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12268.105281] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12271.258411] Lustre: Mounted lustre-client - version 2.17.57_112_g92cfe64 [12284.273600] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12286.104672] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [12292.090281] Lustre: lustre-MDT0000-mdc-ffff9c7648a20800: Connection to lustre-MDT0000 (at 192.168.204.120@tcp) was lost; in progress operations using this service will wait for recovery to complete [12312.555280] Lustre: lustre-MDT0001-mdc-ffff9c7648a20800: Connection to lustre-MDT0001 (at 192.168.204.120@tcp) was lost; in progress operations using this service will wait for recovery to complete [12323.744209] Lustre: 206516:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788855351/real 1788855351] req@0000000035296bc2 x1875750653270784/t0(0) o400->MGC192.168.204.120@tcp@192.168.204.120@tcp:26/25 lens 224/224 e 0 to 1 dl 1788855367 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [12323.777730] LustreError: MGC192.168.204.120@tcp: Connection to MGS (at 192.168.204.120@tcp) was lost; in progress operations using this service will fail [12362.725625] Lustre: Evicted from MGS (at 192.168.204.120@tcp) after server handle changed from 0x59052f08b68172cf to 0x59052f08b6817473 [12362.739553] Lustre: MGC192.168.204.120@tcp: Connection restored to 192.168.204.120@tcp (at 192.168.204.120@tcp) [12403.183612] LustreError: lustre-MDT0000-mdc-ffff9c7648a20800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [12403.207049] Lustre: lustre-MDT0000-mdc-ffff9c7648a20800: Connection restored to 192.168.204.120@tcp (at 192.168.204.120@tcp) [12405.696668] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12408.357223] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in IDLE state after 0 sec [12415.542952] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12417.727384] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [12420.911471] Lustre: Unmounted lustre-client [12451.006619] Key type lgssc unregistered [12451.344457] LNet: 211385:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12451.350573] LNetError: 211385:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12451.367454] LNet: Removed LNI 192.168.204.20@tcp [12452.222644] Key type .llcrypt unregistered [12452.226347] Key type ._llcrypt unregistered [12486.945587] Key type ._llcrypt registered [12486.949367] Key type .llcrypt registered [12487.632724] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12487.710203] alg: No test for adler32 (adler32-zlib) [12489.033943] Lustre: Lustre: Build Version: 2.17.57_112_g92cfe64 [12489.438030] LNet: Added LNI 192.168.204.20@tcp [8/256/0/180] [12491.223801] Key type lgssc registered [12492.611392] Lustre: Echo OBD driver; http://www.lustre.org/ [12589.994537] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12595.910687] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12622.035558] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12672.398974] Lustre: DEBUG MARKER: == conf-sanity test 164: test expected_clients parameter and max client tracking ========================================================== 04:21:54 (1788855714) [12788.909553] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12795.250502] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12826.385105] Lustre: DEBUG MARKER: oleg420-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12829.394461] Lustre: Mounted lustre-client - version 2.17.57_112_g92cfe64 [12835.517841] Lustre: Mounted lustre-client - version 2.17.57_112_g92cfe64 [12836.955392] Lustre: Mounted lustre-client - version 2.17.57_112_g92cfe64 [12836.962271] Lustre: Skipped 1 previous similar message [12843.375197] Lustre: Unmounted lustre-client [12847.209518] Lustre: Unmounted lustre-client [12851.735446] Lustre: Unmounted lustre-client [12854.402049] Lustre: Unmounted lustre-client [12870.149789] Lustre: Unmounted lustre-client [12870.155321] Lustre: Skipped 1 previous similar message [12899.831795] Key type lgssc unregistered [12900.104921] LNet: 220559:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12900.111210] LNetError: 220559:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12900.128426] LNet: Removed LNI 192.168.204.20@tcp [12900.948115] Key type .llcrypt unregistered [12900.950775] Key type ._llcrypt unregistered [12912.602391] Key type ._llcrypt registered [12912.607956] Key type .llcrypt registered [12914.204140] Lustre: DEBUG MARKER: == conf-sanity test 200a: check CPU partitions =========== 04:25:56 (1788855956) [12927.831324] Key type .llcrypt unregistered [12927.833371] Key type ._llcrypt unregistered [12928.952075] Key type ._llcrypt registered [12928.954861] Key type .llcrypt registered [12929.337449] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 4 [12929.353162] alg: No test for adler32 (adler32-zlib) [12930.581083] Lustre: Lustre: Build Version: 2.17.57_112_g92cfe64 [12930.835167] LNet: Added LNI 192.168.204.20@tcp [8/256/0/180] [12932.503323] Key type lgssc registered [12934.279228] Lustre: Echo OBD driver; http://www.lustre.org/ [12965.385703] Key type lgssc unregistered [12966.014562] LNet: 222709:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12966.034335] LNetError: 222709:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12966.066382] LNet: Removed LNI 192.168.204.20@tcp [12967.618728] Key type .llcrypt unregistered [12967.622484] Key type ._llcrypt unregistered [12968.370207] Key type ._llcrypt registered [12968.384966] Key type .llcrypt registered [12970.877599] Lustre: DEBUG MARKER: == conf-sanity test 200b: set CPU pattern using core selection ========================================================== 04:26:52 (1788856012) [12988.071294] Key type .llcrypt unregistered [12988.079802] Key type ._llcrypt unregistered [12989.258883] Key type ._llcrypt registered [12989.287376] Key type .llcrypt registered [12989.970696] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12989.992851] alg: No test for adler32 (adler32-zlib) [12991.264709] Lustre: Lustre: Build Version: 2.17.57_112_g92cfe64 [12991.871768] LNet: Added LNI 192.168.204.20@tcp [8/256/0/180] [12993.599314] Key type lgssc registered [12995.447846] Lustre: Echo OBD driver; http://www.lustre.org/ [13017.695621] Key type lgssc unregistered [13018.091691] LNet: 224254:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13018.104455] LNetError: 224254:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13018.138430] LNet: Removed LNI 192.168.204.20@tcp [13019.307806] Key type .llcrypt unregistered [13019.310362] Key type ._llcrypt unregistered [13028.878877] Key type ._llcrypt registered [13028.891686] Key type .llcrypt registered [13029.393314] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13029.429524] alg: No test for adler32 (adler32-zlib) [13030.553572] Lustre: Lustre: Build Version: 2.17.57_112_g92cfe64 [13030.925939] LNet: Added LNI 192.168.204.20@tcp [8/256/0/180] [13032.640596] Key type lgssc registered [13033.944662] Lustre: Echo OBD driver; http://www.lustre.org/ [13062.354655] Key type lgssc unregistered [13062.655736] LNet: 226014:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13062.660696] LNetError: 226014:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13062.677849] LNet: Removed LNI 192.168.204.20@tcp [13063.375539] Key type .llcrypt unregistered [13063.380943] Key type ._llcrypt unregistered [13063.853755] Key type ._llcrypt registered [13063.856664] Key type .llcrypt registered [13065.512449] Lustre: DEBUG MARKER: == conf-sanity test 200c: set CPU pattern using NUMA node layout ========================================================== 04:28:27 (1788856107) [13078.735996] Key type .llcrypt unregistered [13078.738769] Key type ._llcrypt unregistered [13079.664210] Key type ._llcrypt registered [13079.672149] Key type .llcrypt registered [13080.476798] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13080.514929] alg: No test for adler32 (adler32-zlib) [13081.709636] Lustre: Lustre: Build Version: 2.17.57_112_g92cfe64 [13082.085662] LNet: Added LNI 192.168.204.20@tcp [8/256/0/180] [13083.815278] Key type lgssc registered [13085.588377] Lustre: Echo OBD driver; http://www.lustre.org/ [13105.699900] Key type lgssc unregistered [13106.053351] LNet: 227553:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13106.066398] LNetError: 227553:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13106.092979] LNet: Removed LNI 192.168.204.20@tcp [13107.083713] Key type .llcrypt unregistered [13107.090363] Key type ._llcrypt unregistered [13118.744443] Key type ._llcrypt registered [13118.754957] Key type .llcrypt registered [13119.459916] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13119.514406] alg: No test for adler32 (adler32-zlib) [13121.054327] Lustre: Lustre: Build Version: 2.17.57_112_g92cfe64 [13121.517937] LNet: Added LNI 192.168.204.20@tcp [8/256/0/180] [13123.311976] Key type lgssc registered [13125.149204] Lustre: Echo OBD driver; http://www.lustre.org/ [13146.472527] Key type lgssc unregistered [13146.803657] LNet: 228858:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13146.809066] LNetError: 228858:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13146.821045] LNet: Removed LNI 192.168.204.20@tcp [13147.797754] Key type .llcrypt unregistered [13147.802775] Key type ._llcrypt unregistered [13157.077031] Key type ._llcrypt registered [13157.081665] Key type .llcrypt registered [13157.568648] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13157.586596] alg: No test for adler32 (adler32-zlib) [13158.771457] Lustre: Lustre: Build Version: 2.17.57_112_g92cfe64 [13159.150661] LNet: Added LNI 192.168.204.20@tcp [8/256/0/180] [13160.921076] Key type lgssc registered [13162.193599] Lustre: Echo OBD driver; http://www.lustre.org/ [13178.688719] Key type lgssc unregistered [13178.956949] LNet: 230159:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13178.970871] LNetError: 230159:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13178.981930] LNet: Removed LNI 192.168.204.20@tcp [13179.740830] Key type .llcrypt unregistered [13179.744643] Key type ._llcrypt unregistered [13187.954839] Key type ._llcrypt registered [13187.966931] Key type .llcrypt registered [13188.801679] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13188.837479] alg: No test for adler32 (adler32-zlib) [13189.932871] Lustre: Lustre: Build Version: 2.17.57_112_g92cfe64 [13190.260918] LNet: Added LNI 192.168.204.20@tcp [8/256/0/180] [13191.975196] Key type lgssc registered [13193.412820] Lustre: Echo OBD driver; http://www.lustre.org/ [13223.651952] Key type lgssc unregistered [13224.004905] LNet: 231923:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13224.019029] LNetError: 231923:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13224.040166] LNet: Removed LNI 192.168.204.20@tcp [13224.929630] Key type .llcrypt unregistered [13224.932424] Key type ._llcrypt unregistered [13225.501990] Key type ._llcrypt registered [13225.505516] Key type .llcrypt registered [13227.254832] Lustre: DEBUG MARKER: == conf-sanity test 200d: set CPU pattern to exclude only CPU 1 ========================================================== 04:31:09 (1788856269) [13243.133323] Key type .llcrypt unregistered [13243.135976] Key type ._llcrypt unregistered [13244.284610] Key type ._llcrypt registered [13244.294937] Key type .llcrypt registered [13244.887995] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13244.902828] alg: No test for adler32 (adler32-zlib) [13246.159168] Lustre: Lustre: Build Version: 2.17.57_112_g92cfe64 [13246.427480] LNet: Added LNI 192.168.204.20@tcp [8/256/0/180] [13248.162203] Key type lgssc registered [13250.202044] Lustre: Echo OBD driver; http://www.lustre.org/ [13270.295692] Key type lgssc unregistered [13270.557495] LNet: 233465:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13270.563316] LNetError: 233465:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13270.586503] LNet: Removed LNI 192.168.204.20@tcp [13271.399631] Key type .llcrypt unregistered [13271.404138] Key type ._llcrypt unregistered [13280.421585] Key type ._llcrypt registered [13280.423211] Key type .llcrypt registered [13281.009810] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13281.030431] alg: No test for adler32 (adler32-zlib) [13282.207934] Lustre: Lustre: Build Version: 2.17.57_112_g92cfe64 [13282.511768] LNet: Added LNI 192.168.204.20@tcp [8/256/0/180] [13284.271229] Key type lgssc registered [13285.988599] Lustre: Echo OBD driver; http://www.lustre.org/ [13308.369606] Key type lgssc unregistered [13308.921245] LNet: 234777:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13308.931222] LNetError: 234777:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13308.964532] LNet: Removed LNI 192.168.204.20@tcp [13310.264134] Key type .llcrypt unregistered [13310.272066] Key type ._llcrypt unregistered [13320.359401] Key type ._llcrypt registered [13320.368090] Key type .llcrypt registered [13321.027621] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13321.047231] alg: No test for adler32 (adler32-zlib) [13322.342323] Lustre: Lustre: Build Version: 2.17.57_112_g92cfe64 [13322.705798] LNet: Added LNI 192.168.204.20@tcp [8/256/0/180] [13324.464193] Key type lgssc registered [13325.608541] Lustre: Echo OBD driver; http://www.lustre.org/ [13343.760637] Key type lgssc unregistered [13344.053720] LNet: 236080:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13344.061066] LNetError: 236080:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13344.085531] LNet: Removed LNI 192.168.204.20@tcp [13344.864619] Key type .llcrypt unregistered [13344.869429] Key type ._llcrypt unregistered [13354.560498] Key type ._llcrypt registered [13354.569796] Key type .llcrypt registered [13355.008914] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13355.035771] alg: No test for adler32 (adler32-zlib) [13356.161697] Lustre: Lustre: Build Version: 2.17.57_112_g92cfe64 [13356.634552] LNet: Added LNI 192.168.204.20@tcp [8/256/0/180] [13358.456273] Key type lgssc registered [13360.645673] Lustre: Echo OBD driver; http://www.lustre.org/ [13381.439822] Key type lgssc unregistered [13381.794614] LNet: 237388:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13381.804933] LNetError: 237388:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13381.828748] LNet: Removed LNI 192.168.204.20@tcp [13382.518736] Key type .llcrypt unregistered [13382.523485] Key type ._llcrypt unregistered [13390.956922] Key type ._llcrypt registered [13390.965964] Key type .llcrypt registered [13391.818912] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13391.870859] alg: No test for adler32 (adler32-zlib) [13393.196339] Lustre: Lustre: Build Version: 2.17.57_112_g92cfe64 [13393.536343] LNet: Added LNI 192.168.204.20@tcp [8/256/0/180] [13395.216366] Key type lgssc registered [13397.373931] Lustre: Echo OBD driver; http://www.lustre.org/ [13423.414989] Key type lgssc unregistered [13423.857971] LNet: 238694:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13423.870518] LNetError: 238694:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13423.895168] LNet: Removed LNI 192.168.204.20@tcp [13424.972611] Key type .llcrypt unregistered [13424.974054] Key type ._llcrypt unregistered [13436.137619] Key type ._llcrypt registered [13436.162462] Key type .llcrypt registered [13436.805139] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13436.838952] alg: No test for adler32 (adler32-zlib) [13438.068674] Lustre: Lustre: Build Version: 2.17.57_112_g92cfe64 [13438.449638] LNet: Added LNI 192.168.204.20@tcp [8/256/0/180] [13440.336448] Key type lgssc registered [13441.977464] Lustre: Echo OBD driver; http://www.lustre.org/ [13461.010363] Key type lgssc unregistered [13461.334463] LNet: 239995:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13461.343378] LNetError: 239995:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13461.353801] LNet: Removed LNI 192.168.204.20@tcp [13462.296750] Key type .llcrypt unregistered [13462.299468] Key type ._llcrypt unregistered [13490.418299] Key type ._llcrypt registered [13490.429749] Key type .llcrypt registered [13492.210593] Lustre: DEBUG MARKER: == conf-sanity test 200e: set CPU pattern using relative core exclusion ========================================================== 04:35:34 (1788856534) [13504.779538] Key type .llcrypt unregistered [13504.782560] Key type ._llcrypt unregistered [13505.847686] Key type ._llcrypt registered [13505.852646] Key type .llcrypt registered [13506.203106] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13506.220188] alg: No test for adler32 (adler32-zlib) [13507.363531] Lustre: Lustre: Build Version: 2.17.57_112_g92cfe64 [13507.692827] LNet: Added LNI 192.168.204.20@tcp [8/256/0/180] [13509.391372] Key type lgssc registered [13510.959089] Lustre: Echo OBD driver; http://www.lustre.org/ [13529.277947] Key type lgssc unregistered [13529.564723] LNet: 242305:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13529.577200] LNetError: 242305:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13529.602821] LNet: Removed LNI 192.168.204.20@tcp [13530.513736] Key type .llcrypt unregistered [13530.525870] Key type ._llcrypt unregistered [13538.919491] Key type ._llcrypt registered [13538.923734] Key type .llcrypt registered [13539.495606] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13539.512291] alg: No test for adler32 (adler32-zlib) [13540.738130] Lustre: Lustre: Build Version: 2.17.57_112_g92cfe64 [13541.058030] LNet: Added LNI 192.168.204.20@tcp [8/256/0/180] [13542.847386] Key type lgssc registered [13544.067779] Lustre: Echo OBD driver; http://www.lustre.org/ [13564.153394] Key type lgssc unregistered [13564.500779] LNet: 243618:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13564.517404] LNetError: 243618:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13564.558205] LNet: Removed LNI 192.168.204.20@tcp [13565.650844] Key type .llcrypt unregistered [13565.652669] Key type ._llcrypt unregistered [13577.878091] Key type ._llcrypt registered [13577.883462] Key type .llcrypt registered [13578.853265] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13578.887819] alg: No test for adler32 (adler32-zlib) [13580.215596] Lustre: Lustre: Build Version: 2.17.57_112_g92cfe64 [13580.765352] LNet: Added LNI 192.168.204.20@tcp [8/256/0/180] [13582.711190] Key type lgssc registered [13585.097623] Lustre: Echo OBD driver; http://www.lustre.org/ [13607.312401] Key type lgssc unregistered [13607.669925] LNet: 244924:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13607.685054] LNetError: 244924:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13607.708818] LNet: Removed LNI 192.168.204.20@tcp [13608.464313] Key type .llcrypt unregistered [13608.465901] Key type ._llcrypt unregistered [13617.677897] Key type ._llcrypt registered [13617.683916] Key type .llcrypt registered [13618.241514] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13618.258447] alg: No test for adler32 (adler32-zlib) [13619.457696] Lustre: Lustre: Build Version: 2.17.57_112_g92cfe64 [13619.804419] LNet: Added LNI 192.168.204.20@tcp [8/256/0/180] [13621.575211] Key type lgssc registered [13623.081456] Lustre: Echo OBD driver; http://www.lustre.org/ [13649.793365] Key type lgssc unregistered [13650.050428] LNet: 246714:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13650.064450] LNetError: 246714:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13650.093911] LNet: Removed LNI 192.168.204.20@tcp [13650.845222] Key type .llcrypt unregistered [13650.851676] Key type ._llcrypt unregistered [13651.273386] Key type ._llcrypt registered [13651.276959] Key type .llcrypt registered [13652.656921] Lustre: DEBUG MARKER: == conf-sanity test 250: verify lcfg_erase removes filesystem parameters ========================================================== 04:38:15 (1788856695) [13673.675757] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13673.707980] alg: No test for adler32 (adler32-zlib) [13674.796604] Lustre: Lustre: Build Version: 2.17.57_112_g92cfe64 [13675.000595] LNet: Added LNI 192.168.204.20@tcp [8/256/0/180] [13676.727222] Key type lgssc registered [13678.110480] Lustre: Echo OBD driver; http://www.lustre.org/ [13947.939053] Lustre: DEBUG MARKER: == conf-sanity test 802a: simulate readonly device ======= 04:43:10 (1788856990) [13949.636540] Lustre: DEBUG MARKER: SKIP: conf-sanity test_802a ZFS specific test [14021.480409] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 13680 sec ========= 04:44:23 (1788857063) [14023.821826] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 04:44:25 (1788857065) === [14027.674147] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 04:44:30 (1788857070) === [14067.419925] Key type lgssc unregistered [14067.811233] LNet: 255771:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14067.819477] LNetError: 255771:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14067.842179] LNet: Removed LNI 192.168.204.20@tcp [14068.741188] Key type .llcrypt unregistered [14068.743592] Key type ._llcrypt unregistered