[ 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 430445902 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.001010] APIC: Switch to symmetric I/O mode setup [ 0.002363] x2apic enabled [ 0.003009] Switched APIC routing to physical x2apic. [ 0.005014] kvm-guest: setup PV IPIs [ 0.007944] ..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.008021] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009014] pid_max: default: 32768 minimum: 301 [ 0.010132] LSM: Security Framework initializing [ 0.012050] Yama: becoming mindful. [ 0.013038] SELinux: Initializing. [ 0.014067] *** VALIDATE selinux *** [ 0.023418] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.028171] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.029139] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.031109] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.032119] *** VALIDATE tmpfs *** [ 0.034222] *** VALIDATE proc *** [ 0.035232] *** VALIDATE cgroup *** [ 0.036011] *** VALIDATE cgroup2 *** [ 0.037271] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.038155] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.039010] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.040031] Spectre V2 : User space: Vulnerable [ 0.041010] Speculative Store Bypass: Vulnerable [ 0.044277] debug: unmapping init [mem 0xffffffffb7a59000-0xffffffffb7a60fff] [ 0.046191] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.047648] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.048022] ... version: 2 [ 0.049013] ... bit width: 48 [ 0.050014] ... generic registers: 4 [ 0.051012] ... value mask: 0000ffffffffffff [ 0.052015] ... max period: 00007fffffffffff [ 0.053016] ... fixed-purpose events: 3 [ 0.054013] ... event mask: 000000070000000f [ 0.055286] rcu: Hierarchical SRCU implementation. [ 0.057365] smp: Bringing up secondary CPUs ... [ 0.058543] x86: Booting SMP configuration: [ 0.059031] .... node #0, CPUs: #1 #2 #3 [ 0.062602] smp: Brought up 1 node, 4 CPUs [ 0.064014] smpboot: Max logical packages: 1 [ 0.065027] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.147015] node 0 deferred pages initialised in 80ms [ 0.150091] devtmpfs: initialized [ 0.151167] x86/mm: Memory block size: 128MB [ 0.153203] gcov: version magic: 0x41383552 [ 0.156289] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.157077] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.158431] pinctrl core: initialized pinctrl subsystem [ 0.160280] [ 0.160809] ************************************************************* [ 0.163024] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.165011] ** ** [ 0.167013] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.169011] ** ** [ 0.171012] ** This means that this kernel is built to expose internal ** [ 0.173013] ** IOMMU data structures, which may compromise security on ** [ 0.175012] ** your system. ** [ 0.177015] ** ** [ 0.179013] ** If you see this message and you are not debugging the ** [ 0.182011] ** kernel, report this immediately to your vendor! ** [ 0.184023] ** ** [ 0.186012] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.189011] ************************************************************* [ 0.191625] NET: Registered protocol family 16 [ 0.193285] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.196070] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.199069] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.203011] cpuidle: using governor menu [ 0.205689] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.209488] PCI: Using configuration type 1 for base access [ 0.212120] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.220042] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.222059] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.226079] cryptd: max_cpu_qlen set to 1000 [ 0.229338] ACPI: Added _OSI(Module Device) [ 0.231016] ACPI: Added _OSI(Processor Device) [ 0.233017] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.236016] ACPI: Added _OSI(Processor Aggregator Device) [ 0.241077] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.247059] ACPI: Interpreter enabled [ 0.248067] ACPI: PM: (supports S0 S3 S4 S5) [ 0.250019] ACPI: Using IOAPIC for interrupt routing [ 0.253122] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.259410] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.270074] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.272040] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.276021] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.278069] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.283896] acpiphp: Slot [2] registered [ 0.286212] acpiphp: Slot [5] registered [ 0.287150] acpiphp: Slot [6] registered [ 0.289139] acpiphp: Slot [3] registered [ 0.290094] acpiphp: Slot [4] registered [ 0.292098] acpiphp: Slot [7] registered [ 0.293094] acpiphp: Slot [8] registered [ 0.295088] acpiphp: Slot [9] registered [ 0.296128] acpiphp: Slot [10] registered [ 0.298136] acpiphp: Slot [11] registered [ 0.300111] acpiphp: Slot [12] registered [ 0.302115] acpiphp: Slot [13] registered [ 0.303171] acpiphp: Slot [14] registered [ 0.304096] acpiphp: Slot [15] registered [ 0.306106] acpiphp: Slot [16] registered [ 0.308122] acpiphp: Slot [17] registered [ 0.309127] acpiphp: Slot [18] registered [ 0.311103] acpiphp: Slot [19] registered [ 0.312107] acpiphp: Slot [20] registered [ 0.314114] acpiphp: Slot [21] registered [ 0.316087] acpiphp: Slot [22] registered [ 0.317104] acpiphp: Slot [23] registered [ 0.319114] acpiphp: Slot [24] registered [ 0.321095] acpiphp: Slot [25] registered [ 0.322103] acpiphp: Slot [26] registered [ 0.324205] acpiphp: Slot [27] registered [ 0.326127] acpiphp: Slot [28] registered [ 0.327098] acpiphp: Slot [29] registered [ 0.329179] acpiphp: Slot [30] registered [ 0.330113] acpiphp: Slot [31] registered [ 0.332060] PCI host bridge to bus 0000:00 [ 0.333016] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.335016] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.337016] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.339024] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.342021] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.345029] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.347177] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.350027] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.353249] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.361015] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.364059] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.368025] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.370016] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.372025] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.374528] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.376709] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.380053] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.383043] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.389012] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.400015] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.404013] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.410073] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.414011] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.419014] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.439015] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.450421] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.459015] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.463887] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.486015] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.496314] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.499396] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.501275] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.503340] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.505228] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.511039] iommu: Default domain type: Passthrough [ 0.513417] SCSI subsystem initialized [ 0.514114] ACPI: bus type USB registered [ 0.516126] usbcore: registered new interface driver usbfs [ 0.518086] usbcore: registered new interface driver hub [ 0.522132] usbcore: registered new device driver usb [ 0.524198] pps_core: LinuxPPS API ver. 1 registered [ 0.526016] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.529057] PTP clock support registered [ 0.531137] EDAC MC: Ver: 3.0.0 [ 0.533443] PCI: Using ACPI for IRQ routing [ 0.535837] NetLabel: Initializing [ 0.537011] NetLabel: domain hash size = 128 [ 0.538008] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.539126] NetLabel: unlabeled traffic allowed by default [ 0.542105] vgaarb: loaded [ 0.543343] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.544012] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.550000] clocksource: Switched to clocksource kvm-clock [ 0.658209] VFS: Disk quotas dquot_6.6.0 [ 0.660174] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.663163] *** VALIDATE ramfs *** [ 0.664685] *** VALIDATE hugetlbfs *** [ 0.666889] pnp: PnP ACPI init [ 0.669340] pnp: PnP ACPI: found 6 devices [ 0.691447] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.695159] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.697484] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.699451] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.702049] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.704266] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.706785] NET: Registered protocol family 2 [ 0.709266] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.714090] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.718115] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.723687] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.726982] TCP: Hash tables configured (established 65536 bind 65536) [ 0.730123] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.733581] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.736904] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.739687] NET: Registered protocol family 1 [ 0.741973] RPC: Registered named UNIX socket transport module. [ 0.744498] RPC: Registered udp transport module. [ 0.746075] RPC: Registered tcp transport module. [ 0.748692] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.751019] NET: Registered protocol family 44 [ 0.752924] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.755149] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.757569] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.760028] PCI: CLS 0 bytes, default 64 [ 0.761780] Unpacking initramfs... [ 2.146513] debug: unmapping init [mem 0xffff88e2bcc64000-0xffff88e2bffcffff] [ 2.150062] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.152251] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.155020] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.668307] Initialise system trusted keyrings [ 2.672080] Key type blacklist registered [ 2.674169] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.688284] zbud: loaded [ 2.691971] *** VALIDATE nfs *** [ 2.693527] *** VALIDATE nfs4 *** [ 2.695449] pstore: using deflate compression [ 2.699580] Platform Keyring initialized [ 2.825830] NET: Registered protocol family 38 [ 2.828928] Key type asymmetric registered [ 2.830413] Asymmetric key parser 'x509' registered [ 2.832399] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.835513] io scheduler mq-deadline registered [ 2.837273] io scheduler kyber registered [ 2.838779] io scheduler bfq registered [ 2.841080] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.844283] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.847316] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.850207] ACPI: Power Button [PWRF] [ 2.855563] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.863616] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.875858] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.903338] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.930505] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.936433] Non-volatile memory driver v1.3 [ 2.938019] Linux agpgart interface v0.103 [ 2.973491] virtio_blk virtio1: [vda] 146744 512-byte logical blocks (75.1 MB/71.7 MiB) [ 2.976433] vda: detected capacity change from 0 to 75132928 [ 2.992215] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 2.995191] vdb: detected capacity change from 0 to 1073741824 [ 3.004621] libphy: Fixed MDIO Bus: probed [ 3.009705] usbcore: registered new interface driver usbserial_generic [ 3.012761] usbserial: USB Serial support registered for generic [ 3.015718] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.019869] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.021984] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.024570] mousedev: PS/2 mouse device common for all mice [ 3.027436] rtc_cmos 00:05: RTC can wake from S4 [ 3.032767] rtc_cmos 00:05: registered as rtc0 [ 3.033422] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.034945] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.041166] intel_pstate: CPU model not supported [ 3.044135] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.048602] hid: raw HID events driver (C) Jiri Kosina [ 3.050727] usbcore: registered new interface driver usbhid [ 3.052836] usbhid: USB HID core driver [ 3.054573] drop_monitor: Initializing network drop monitor service [ 3.054702] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.056885] Initializing XFRM netlink socket [ 3.061981] NET: Registered protocol family 10 [ 3.065104] Segment Routing with IPv6 [ 3.066495] NET: Registered protocol family 17 [ 3.068425] mpls_gso: MPLS GSO support [ 3.073578] RAS: Correctable Errors collector initialized. [ 3.075847] AVX version of gcm_enc/dec engaged. [ 3.077660] AES CTR mode by8 optimization enabled [ 3.157732] sched_clock: Marking stable (3157698663, 0)->(4047266821, -889568158) [ 3.162150] registered taskstats version 1 [ 3.164324] Loading compiled-in X.509 certificates [ 3.166297] zswap: loaded using pool lzo/zbud [ 3.189627] Key type big_key registered [ 3.202231] Key type encrypted registered [ 3.204206] ima: No TPM chip found, activating TPM-bypass! [ 3.207507] ima: Allocated hash algorithm: sha1 [ 3.209109] ima: No architecture policies found [ 3.211056] evm: Initialising EVM extended attributes: [ 3.213011] evm: security.selinux [ 3.214249] evm: security.ima [ 3.215427] evm: security.capability [ 3.216774] evm: HMAC attrs: 0x1 [ 3.219287] rtc_cmos 00:05: setting system clock to 2026-09-08 04:02:49 UTC (1788840169) [ 3.225179] debug: unmapping init [mem 0xffffffffb8a03000-0xffffffffb8bfffff] [ 3.227876] debug: unmapping init [mem 0xffffffffb7782000-0xffffffffb7a58fff] [ 3.237076] Write protecting the kernel read-only data: 28672k [ 3.239965] debug: unmapping init [mem 0xffffffffb5e03000-0xffffffffb5ffffff] [ 3.241983] debug: unmapping init [mem 0xffffffffb6714000-0xffffffffb67fffff] [ 3.272642] 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.281140] systemd[1]: Detected virtualization kvm. [ 3.283631] systemd[1]: Detected architecture x86-64. [ 3.285457] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.312819] systemd[1]: No hostname configured. [ 3.315501] systemd[1]: Set hostname to . [ 3.317602] random: systemd: uninitialized urandom read (16 bytes read) [ 3.320115] systemd[1]: Initializing machine ID from random generator. [ 3.473879] random: systemd: uninitialized urandom read (16 bytes read) [ 3.476528] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. [ 3.481247] random: systemd: uninitialized urandom read (16 bytes read) [ 3.484085] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. [ 3.494057] systemd[1]: Starting Create Volatile Files and Directories... Starting Create Volatile Files and Directories... Starting Setup Virtual Console... [ OK ] Reached target Swap. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Initrd Root Device. Starting Apply Kernel Variables... [ OK ] Listening on Journal Socket (/dev/log). Starting Journal Service... [ OK ] Reached target Sockets. [ OK ] Started Memstrack Anylazing Service. [ OK ] Reached target Slices. [ OK ] Reached target Timers. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Setup Virtual Console. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create list of required sta…vice nodes for the current kernel. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.174559] device-mapper: uevent: version 1.0.3 [ 4.176639] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting dracut initqueue hook... [ 4.902258] virtio_net virtio0 ens2: renamed from eth0 [ 4.937599] scsi host0: ata_piix [ 4.971163] scsi host1: ata_piix [ 4.974092] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 4.976956] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 9.727270] random: crng init done [ 9.729963] random: 7 urandom warning(s) missed due to ratelimiting [ 10.470497] dracut-initqueue[586]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ 12.212871] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Reached target Remote File Systems. [ 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 ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped target Basic System. [ OK ] Stopped target Paths. [ OK ] Stopped target Slices. [ OK ] Stopped target Sockets. [ OK ] Stopped target System Initialization. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Swap. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped target Local File Systems. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 15.247072] printk: systemd: 26 output lines suppressed due to ratelimiting [ 15.946849] SELinux: Disabled at runtime. [ 16.062437] 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) [ 16.081953] systemd[1]: Detected virtualization kvm. [ 16.088964] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 17.921571] systemd[1]: initrd-switch-root.service: Succeeded. [ 17.934360] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 17.955954] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 17.986287] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 17.999019] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 18.024110] systemd[1]: Starting Journal Service... Starting Journal Service... [ 18.030443] systemd[1]: Created slice system-getty.slice. [ OK ] Created slice system-getty.slice. Activating swap /dev/disk/by-label/SWAP... [ OK ] Stopped target Switch Root. [ OK ] Reached target rpc_pipefs.target. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ 18.131850] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS Mounting Kernel Debug File System... [ OK ] Started Dispatch Password Requests to Console Directory Watch. Starting Remount Root and Kernel File Systems... [ OK ] Listening on udev Kernel Socket. Mounting Huge Pages File System... [ OK ] Stopped target Initrd File Systems. [ OK ] Created slice User and Session Slice. Starting Apply Kernel Variables... [ OK ] Created slice system-sshd\x2dkeygen.slice. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... [ OK ] Listening on Process Core Dump Socket. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Stopped target Initrd Root File System. Mounting POSIX Message Queue File System... [ OK ] Reached target Slices. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Started Journal Service. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted Kernel Debug File System. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted Huge Pages File System. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted POSIX Message Queue File System. Starting Create Static Device Nodes in /dev... Starting Configure read-only root support... [ OK ] Reached target Swap. Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started udev Coldplug all Devices. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... [ OK ] Mounted /mnt. [ 19.715269] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 20.468958] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 20.591350] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 21.058415] hrtimer: interrupt took 1412623 ns [ 21.160663] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 21.236622] EDAC sbridge: Ver: 1.1.2 [* ] A start job is running for Configur…-only root support (7s / no limit) [** ] 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)[ 26.491678] Key type dns_resolver registered [ *** ] A start job is running for Configur…-only root support (9s / no limit)[ 27.157219] NFS: Registering the id_resolver key type [ 27.161679] Key type id_resolver registered [ 27.163425] Key type id_legacy registered [ *** ] A start job is running for Configur…-only root support (9s / no limit) [ ***] A start job is running for Configur…only root support (10s / no limit) [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... [ OK ] Started Load/Save Random Seed. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Mark the need to relabel after reboot. Starting Update UTMP about System Boot/Shutdown... Starting RPC Bind... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. Starting Restore /run/initramfs on shutdown... [ OK ] Started irqbalance daemon. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Login Service... [ OK ] Reached target sshd-keygen.target. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting OpenSSH server daemon... Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ OK ] Started Permit User Sessions. [ OK ] Started 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 oleg646-client login: [ 87.819245] libcfs: loading out-of-tree module taints kernel. [ 88.063572] Key type ._llcrypt registered [ 88.069442] Key type .llcrypt registered [ 88.589650] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 88.599630] alg: No test for adler32 (adler32-zlib) [ 90.085816] Lustre: Lustre: Build Version: 2.17.57_115_g0321d60 [ 91.255461] LNet: Added LNI 192.168.206.46@tcp [8/256/0/180] [ 92.967166] Key type lgssc registered [ 94.575629] Lustre: Echo OBD driver; http://www.lustre.org/ [ 261.540760] Lustre: Mounted lustre-client - version 2.17.57_115_g0321d60 [ 266.555312] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 278.783976] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing check_logdir /tmp/testlogs/ [ 284.147632] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing yml_node [ 287.199219] Lustre: lustre-OST0000-osc-ffff88e304509800: disconnect after 23s idle [ 288.633273] Lustre: DEBUG MARKER: Client: 2.17.57.115 [ 290.976639] Lustre: DEBUG MARKER: MDS: 2.17.57.115 [ 293.537908] Lustre: DEBUG MARKER: OSS: 2.17.57.115 [ 295.098451] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Tue Sep 8 00:07:40 EDT 2026 [ 310.827913] Lustre: DEBUG MARKER: excepting tests: 32newtarball [ 312.136447] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 314.560538] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 324.876215] Lustre: Unmounted lustre-client [ 438.517628] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 443.978370] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 468.771412] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 508.491821] Lustre: DEBUG MARKER: == conf-sanity test 121: failover MGS ==================== 00:11:12 (1788840672) [ 547.862520] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 552.212460] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 582.781630] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mgc.*.mgs_server_uuid 1475 0 [ 625.762612] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 630.566154] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 673.909955] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mgc.*.mgs_server_uuid 1475 0 [ 694.457167] Lustre: DEBUG MARKER: == conf-sanity test 122a: Check OST sequence update ====== 00:14:19 (1788840859) [ 779.503967] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 783.620224] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 799.937286] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 800.508130] Lustre: Mounted lustre-client - version 2.17.57_115_g0321d60 [ 819.031405] Lustre: Unmounted lustre-client [ 844.470588] Key type lgssc unregistered [ 844.676198] LNet: 21837:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 844.685396] LNetError: 21837:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 844.705360] LNet: Removed LNI 192.168.206.46@tcp [ 845.295870] Key type .llcrypt unregistered [ 845.299046] Key type ._llcrypt unregistered [ 857.157594] Key type ._llcrypt registered [ 857.160816] Key type .llcrypt registered [ 858.144592] Lustre: DEBUG MARKER: == conf-sanity test 123aa: llog_print works with FIDs and simple names ========================================================== 00:17:03 (1788841023) [ 860.002499] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 860.023624] alg: No test for adler32 (adler32-zlib) [ 861.031508] Lustre: Lustre: Build Version: 2.17.57_115_g0321d60 [ 861.272826] LNet: Added LNI 192.168.206.46@tcp [8/256/0/180] [ 862.919197] Key type lgssc registered [ 863.982458] Lustre: Echo OBD driver; http://www.lustre.org/ [ 901.376867] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 905.234758] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 921.200723] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 928.291757] Lustre: Mounted lustre-client - version 2.17.57_115_g0321d60 [ 937.793786] Lustre: DEBUG MARKER: == conf-sanity test 123ab: llog_print params output values from set_param -P ========================================================== 00:18:22 (1788841102) [ 946.862891] Lustre: DEBUG MARKER: == conf-sanity test 123ac: llog_print with --start and --end ========================================================== 00:18:31 (1788841111) [ 953.487902] Lustre: DEBUG MARKER: == conf-sanity test 123ad: llog_print shows all records == 00:18:38 (1788841118) [ 961.815784] Lustre: DEBUG MARKER: == conf-sanity test 123ae: llog_cancel can cancel requested record ========================================================== 00:18:46 (1788841126) [ 982.994506] Lustre: DEBUG MARKER: == conf-sanity test 123af: llog_catlist can show all config files correctly ========================================================== 00:19:08 (1788841148) [ 995.026865] Lustre: DEBUG MARKER: == conf-sanity test 123ag: llog_print skips values deleted by set_param -P -d ========================================================== 00:19:20 (1788841160) [ 1008.085910] Lustre: DEBUG MARKER: == conf-sanity test 123ah: del_ost cancels config log entries correctly ========================================================== 00:19:32 (1788841172) [ 1017.363177] Lustre: Unmounted lustre-client [ 1017.833814] Lustre: Mounted lustre-client - version 2.17.57_115_g0321d60 [ 1020.149762] Lustre: Unmounted lustre-client [ 1044.211519] Key type lgssc unregistered [ 1044.432722] LNet: 30200:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1044.442092] LNetError: 30200:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1044.454199] LNet: Removed LNI 192.168.206.46@tcp [ 1044.956201] Key type .llcrypt unregistered [ 1044.958163] Key type ._llcrypt unregistered [ 1065.447266] Key type ._llcrypt registered [ 1065.451209] Key type .llcrypt registered [ 1065.857640] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1065.882400] alg: No test for adler32 (adler32-zlib) [ 1066.885579] Lustre: Lustre: Build Version: 2.17.57_115_g0321d60 [ 1067.102204] LNet: Added LNI 192.168.206.46@tcp [8/256/0/180] [ 1068.767261] Key type lgssc registered [ 1069.776570] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1139.375185] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1143.899642] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1165.033079] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1212.819373] Lustre: DEBUG MARKER: == conf-sanity test 123ai: llog_print display all non skipped records ========================================================== 00:22:57 (1788841377) [ 1242.503963] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1247.194925] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1266.398598] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1269.116895] Lustre: Mounted lustre-client - version 2.17.57_115_g0321d60 [ 1378.750856] Lustre: DEBUG MARKER: == conf-sanity test 123aj: check permanent TBF rules ===== 00:25:43 (1788841543) [ 1415.044796] Lustre: DEBUG MARKER: == conf-sanity test 123F: clear and reset all parameters using set_param -F ========================================================== 00:26:19 (1788841579) [ 1424.683940] Lustre: Unmounted lustre-client [ 1491.242800] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1495.188552] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1510.979623] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1511.494642] Lustre: Mounted lustre-client - version 2.17.57_115_g0321d60 [ 1515.998888] Lustre: Unmounted lustre-client [ 1542.997953] Key type lgssc unregistered [ 1543.194913] LNet: 41491:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1543.210165] LNetError: 41491:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1543.230878] LNet: Removed LNI 192.168.206.46@tcp [ 1543.811737] Key type .llcrypt unregistered [ 1543.817719] Key type ._llcrypt unregistered [ 1558.037646] Key type ._llcrypt registered [ 1558.040159] Key type .llcrypt registered [ 1559.441087] Lustre: DEBUG MARKER: == conf-sanity test 123G: clear and reset all parameters using apply_yaml ========================================================== 00:28:44 (1788841724) [ 1561.776983] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1561.792706] alg: No test for adler32 (adler32-zlib) [ 1562.790526] Lustre: Lustre: Build Version: 2.17.57_115_g0321d60 [ 1562.948510] LNet: Added LNI 192.168.206.46@tcp [8/256/0/180] [ 1564.583183] Key type lgssc registered [ 1566.092221] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1610.447208] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1615.020593] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1630.660839] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1637.399788] Lustre: Mounted lustre-client - version 2.17.57_115_g0321d60 [ 1647.454146] Lustre: Unmounted lustre-client [ 1721.469563] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1726.075620] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1742.858556] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1743.509696] Lustre: Mounted lustre-client - version 2.17.57_115_g0321d60 [ 1748.661037] Lustre: Unmounted lustre-client [ 1774.679074] Key type lgssc unregistered [ 1774.898841] LNet: 47016:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1774.903764] LNetError: 47016:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1774.917916] LNet: Removed LNI 192.168.206.46@tcp [ 1775.488342] Key type .llcrypt unregistered [ 1775.491056] Key type ._llcrypt unregistered [ 1787.671203] Key type ._llcrypt registered [ 1787.672513] Key type .llcrypt registered [ 1788.708374] Lustre: DEBUG MARKER: == conf-sanity test 123H: check concurent accesses with 'lctl llog_print ========================================================== 00:32:33 (1788841953) [ 1790.317368] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1790.379069] alg: No test for adler32 (adler32-zlib) [ 1791.454034] Lustre: Lustre: Build Version: 2.17.57_115_g0321d60 [ 1791.646847] LNet: Added LNI 192.168.206.46@tcp [8/256/0/180] [ 1793.304560] Key type lgssc registered [ 1794.379746] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1834.117307] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1839.004572] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1860.552469] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1863.454736] Lustre: Mounted lustre-client - version 2.17.57_115_g0321d60 [ 1951.095695] Lustre: DEBUG MARKER: == conf-sanity test 124: check failover after replace_nids ========================================================== 00:35:16 (1788842116) [ 1952.425405] Lustre: DEBUG MARKER: SKIP: conf-sanity test_124 needs MDT failover setup [ 1953.974399] Lustre: DEBUG MARKER: == conf-sanity test 126: mount in parallel shouldn't cause a crash ========================================================== 00:35:19 (1788842119) [ 1955.511159] Lustre: Unmounted lustre-client [ 1988.226332] Key type lgssc unregistered [ 1988.573896] LNet: 52341:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1988.577931] LNetError: 52341:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1988.605726] LNet: Removed LNI 192.168.206.46@tcp [ 1989.183089] Key type .llcrypt unregistered [ 1989.185876] Key type ._llcrypt unregistered [ 2016.400600] Key type ._llcrypt registered [ 2016.402425] Key type .llcrypt registered [ 2016.904187] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2016.916969] alg: No test for adler32 (adler32-zlib) [ 2018.028636] Lustre: Lustre: Build Version: 2.17.57_115_g0321d60 [ 2018.276483] LNet: Added LNI 192.168.206.46@tcp [8/256/0/180] [ 2020.007178] Key type lgssc registered [ 2021.235435] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2052.724552] Lustre: DEBUG MARKER: == conf-sanity test 127: direct io overwrite on full ost ========================================================== 00:36:57 (1788842217) [ 2063.008219] Key type lgssc unregistered [ 2063.250307] LNet: 54742:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2063.256850] LNetError: 54742:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2063.278463] LNet: Removed LNI 192.168.206.46@tcp [ 2063.892895] Key type .llcrypt unregistered [ 2063.896682] Key type ._llcrypt unregistered [ 2072.523638] Key type ._llcrypt registered [ 2072.525848] Key type .llcrypt registered [ 2072.956150] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2072.972251] alg: No test for adler32 (adler32-zlib) [ 2074.057622] Lustre: Lustre: Build Version: 2.17.57_115_g0321d60 [ 2074.275075] LNet: Added LNI 192.168.206.46@tcp [8/256/0/180] [ 2075.919205] Key type lgssc registered [ 2077.030733] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2118.301473] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2122.456929] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2137.060473] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2143.267640] Lustre: Mounted lustre-client - version 2.17.57_115_g0321d60 [ 2182.043794] Lustre: DEBUG MARKER: == conf-sanity test 128: Force using remote logs with --nolocallogs ========================================================== 00:39:07 (1788842347) [ 2183.467680] Lustre: DEBUG MARKER: SKIP: conf-sanity test_128 need separate mgs device [ 2185.173754] Lustre: DEBUG MARKER: == conf-sanity test 129: attempt to connect an OST with the same index should fail ========================================================== 00:39:10 (1788842350) [ 2186.760725] Lustre: Unmounted lustre-client [ 2240.685115] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2245.244795] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2315.947933] Lustre: DEBUG MARKER: == conf-sanity test 130: re-register an MDT after writeconf ========================================================== 00:41:20 (1788842480) [ 2396.489154] Lustre: Mounted lustre-client - version 2.17.57_115_g0321d60 [ 2401.378554] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 2417.151029] Lustre: lustre-MDT0001-mdc-ffff88e3030ba000: Connection to lustre-MDT0001 (at 192.168.206.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2422.245815] Lustre: lustre-OST0000-osc-ffff88e3030ba000: disconnect after 23s idle [ 2433.803515] LustreError: 63430:0:(obd_config.c:2102:class_config_llog_handler()) MGC192.168.206.146@tcp: cfg command failed: rc = -17 [ 2433.819229] Lustre: cmd=cf001 0:lustre-MDT0001-mdc 1:mdc 2:lustre-clilmv_UUID [ 2433.819229] [ 2433.827915] LustreError: 62674:0:(mgc_request.c:614:do_requeue()) failed processing log: -17 [ 2445.971940] Lustre: DEBUG MARKER: == conf-sanity test 131: MDT backup restore with project ID and inheritance flag ========================================================== 00:43:30 (1788842610) [ 2505.285528] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 2587.158766] Lustre: Unmounted lustre-client [ 2741.680877] Lustre: Mounted lustre-client - version 2.17.57_115_g0321d60 [ 2747.613915] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 2801.834644] Lustre: DEBUG MARKER: == conf-sanity test 132: hsm_actions processed after failover ========================================================== 00:49:26 (1788842966) [ 2803.630935] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2826.460952] Lustre: Unmounted lustre-client [ 2961.989371] Lustre: DEBUG MARKER: == conf-sanity test 133: stripe QOS: free space balance in a pool ========================================================== 00:52:07 (1788843127) [ 2963.321545] Lustre: DEBUG MARKER: SKIP: conf-sanity test_133 needs >= 4 OSTs [ 2964.941602] Lustre: DEBUG MARKER: == conf-sanity test 134: check_iam works without faults == 00:52:09 (1788843129) [ 3049.435958] Lustre: Mounted lustre-client - version 2.17.57_115_g0321d60 [ 3054.151737] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 3661.286322] Lustre: DEBUG MARKER: == conf-sanity test 135: check the behavior when changelog is wrapped around ========================================================== 01:03:46 (1788843826) [ 3663.613112] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3663.631960] Lustre: Skipped 3 previous similar messages [ 3673.955694] Lustre: Unmounted lustre-client [ 3787.383936] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3792.873290] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3812.453148] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3813.034334] Lustre: Mounted lustre-client - version 2.17.57_115_g0321d60 [ 5397.993364] Lustre: 95042:0:(llog_cat.c:310:llog_cat_process_or_fork()) lustre-MDT0000-mdc-ffff88e30956d000: catlog [0xa:0x5:0x0] crosses index zero [ 5425.125511] Lustre: Unmounted lustre-client [ 5451.192783] Key type lgssc unregistered [ 5451.443677] LNet: 103175:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5451.454624] LNetError: 103175:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5451.476139] LNet: Removed LNI 192.168.206.46@tcp [ 5452.134190] Key type .llcrypt unregistered [ 5452.135891] Key type ._llcrypt unregistered [ 5460.543735] Key type ._llcrypt registered [ 5460.547420] Key type .llcrypt registered [ 5461.595245] Lustre: DEBUG MARKER: == conf-sanity test 136: don't panic with bad obdecho setup ========================================================== 01:33:46 (1788845626) [ 5476.402104] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5476.468691] alg: No test for adler32 (adler32-zlib) [ 5477.563743] Lustre: Lustre: Build Version: 2.17.57_115_g0321d60 [ 5477.818141] LNet: Added LNI 192.168.206.46@tcp [8/256/0/180] [ 5479.519957] Key type lgssc registered [ 5480.901844] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5557.705167] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5563.176823] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5582.674987] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5584.279951] Lustre: Mounted lustre-client - version 2.17.57_115_g0321d60 [ 5600.202879] Lustre: Unmounted lustre-client [ 5728.153243] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5733.224291] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5754.452287] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5788.488990] Lustre: DEBUG MARKER: == conf-sanity test 137: a new MDT should inherit pools, parameters and failnode ========================================================== 01:39:13 (1788845953) [ 5790.135687] Lustre: DEBUG MARKER: SKIP: conf-sanity test_137 needs >= 3 MDTs [ 5791.719883] Lustre: DEBUG MARKER: == conf-sanity test 140: remove_updatelog script actions ========================================================== 01:39:16 (1788845956) [ 5823.260496] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5828.309729] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5845.056821] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5845.653824] Lustre: Mounted lustre-client - version 2.17.57_115_g0321d60 [ 5856.238113] Lustre: lustre-MDT0000-mdc-ffff88e30956b000: Connection to lustre-MDT0000 (at 192.168.206.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5866.476911] Lustre: lustre-MDT0001-mdc-ffff88e30956b000: Connection to lustre-MDT0001 (at 192.168.206.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5877.729838] Lustre: 103930:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788846027/real 1788846027] req@00000000062f0e83 x1875740610080000/t0(0) o400->MGC192.168.206.146@tcp@192.168.206.146@tcp:26/25 lens 224/224 e 0 to 1 dl 1788846043 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 5877.773404] LustreError: MGC192.168.206.146@tcp: Connection to MGS (at 192.168.206.146@tcp) was lost; in progress operations using this service will fail [ 6008.820728] LustreError: lustre-MDT0000-mdc-ffff88e30956b000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 6008.851194] Lustre: lustre-MDT0000-mdc-ffff88e30956b000: Connection restored to 192.168.206.146@tcp (at 192.168.206.146@tcp) [ 6019.050092] LustreError: lustre-MDT0001-mdc-ffff88e30956b000: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 6019.072651] Lustre: lustre-MDT0001-mdc-ffff88e30956b000: Connection restored to 192.168.206.146@tcp (at 192.168.206.146@tcp) [ 6021.036695] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6022.658558] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 6023.166085] Lustre: Evicted from MGS (at 192.168.206.146@tcp) after server handle changed from 0x4e22427f1d9eaa96 to 0x4e22427f1d9ec2de [ 6023.188310] Lustre: MGC192.168.206.146@tcp: Connection restored to 192.168.206.146@tcp (at 192.168.206.146@tcp) [ 6027.831430] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6029.660897] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 6046.419920] Lustre: Unmounted lustre-client [ 6175.544668] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6180.063866] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6200.363864] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6237.426483] Lustre: DEBUG MARKER: == conf-sanity test 150: test setting max_cached_mb to a % ========================================================== 01:46:41 (1788846401) [ 6268.347367] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6274.059102] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6293.605727] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6294.281776] Lustre: Mounted lustre-client - version 2.17.57_115_g0321d60 [ 6296.426324] LustreError: 120649: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 [ 6302.380866] Lustre: DEBUG MARKER: == conf-sanity test 151a: damaged local config doesn't prevent mounting ========================================================== 01:47:47 (1788846467) [ 6304.824463] Lustre: Unmounted lustre-client [ 6330.879501] Key type lgssc unregistered [ 6331.113627] LNet: 121664:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6331.122377] LNetError: 121664:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6331.137742] LNet: Removed LNI 192.168.206.46@tcp [ 6331.736808] Key type .llcrypt unregistered [ 6331.739552] Key type ._llcrypt unregistered [ 6344.552835] Key type ._llcrypt registered [ 6344.579596] Key type .llcrypt registered [ 6344.896565] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6344.911649] alg: No test for adler32 (adler32-zlib) [ 6346.051562] Lustre: Lustre: Build Version: 2.17.57_115_g0321d60 [ 6346.266538] LNet: Added LNI 192.168.206.46@tcp [8/256/0/180] [ 6347.927183] Key type lgssc registered [ 6349.517563] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6430.955959] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6436.475701] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6456.194406] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6576.139498] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6581.599497] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6601.820359] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6646.666965] Lustre: DEBUG MARKER: == conf-sanity test 151b: -ENOSPC doesn't affect mount === 01:53:31 (1788846811) [ 6656.766955] Key type lgssc unregistered [ 6656.985756] LNet: 128875:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6656.992917] LNetError: 128875:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6657.008100] LNet: Removed LNI 192.168.206.46@tcp [ 6657.554893] Key type .llcrypt unregistered [ 6657.559074] Key type ._llcrypt unregistered [ 6670.297395] Key type ._llcrypt registered [ 6670.304373] Key type .llcrypt registered [ 6670.713485] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6670.722516] alg: No test for adler32 (adler32-zlib) [ 6671.761634] Lustre: Lustre: Build Version: 2.17.57_115_g0321d60 [ 6671.933734] LNet: Added LNI 192.168.206.46@tcp [8/256/0/180] [ 6673.567773] Key type lgssc registered [ 6674.557430] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6721.493288] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6726.668581] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6746.573621] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6848.085914] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6853.603224] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6875.446730] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6913.890299] Lustre: DEBUG MARKER: == conf-sanity test 152: seq allocation error in OSP ===== 01:57:58 (1788847078) [ 6994.194896] Lustre: Mounted lustre-client - version 2.17.57_115_g0321d60 [ 6999.579248] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 7011.594108] Lustre: DEBUG MARKER: ADD OST3 [ 7020.008798] Lustre: lustre-OST0000-osc-ffff88e3032a7800: disconnect after 24s idle [ 7025.049041] Lustre: DEBUG MARKER: STOP OST3 [ 7036.904876] Lustre: lustre-OST0003-osc-ffff88e3032a7800: Connection to lustre-OST0003 (at 192.168.206.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7045.760732] Lustre: DEBUG MARKER: START OST3 again [ 7054.847172] Lustre: lustre-OST0003-osc-ffff88e3032a7800: Connection restored to 192.168.206.146@tcp (at 192.168.206.146@tcp) [ 7082.790661] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7082.805594] LustreError: 139682:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff88e3032a7800: inode [0x240000bd0:0x1:0x0] mdc close failed: rc = -108 [ 7093.242160] Lustre: Unmounted lustre-client [ 7199.043343] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7204.162776] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7224.648575] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7259.479818] Lustre: DEBUG MARKER: == conf-sanity test 153a: bypass invalid NIDs quickly ==== 02:03:44 (1788847424) [ 7352.427449] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7357.684581] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7380.050913] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7448.492912] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7453.627553] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7477.498356] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7479.851557] LNetError: 129284:0:(lib-move.c:2158:lnet_handle_find_routed_path()) peer 192.168.252.112@tcp2 has no available nets [ 7480.207211] Lustre: Mounted lustre-client - version 2.17.57_115_g0321d60 [ 7485.517973] Lustre: Unmounted lustre-client [ 7512.824991] Key type lgssc unregistered [ 7535.200488] LNet: 1 local NIs in recovery (showing 1): 192.168.206.46@tcp [ 7541.733326] LNet: 148660:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7541.740254] LNetError: 148660:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7612.390819] LNet: Removed LNI 192.168.206.46@tcp [ 7613.301153] Key type .llcrypt unregistered [ 7613.304058] Key type ._llcrypt unregistered [ 7632.934389] Key type ._llcrypt registered [ 7632.936828] Key type .llcrypt registered [ 7635.009397] Lustre: DEBUG MARKER: == conf-sanity test 153b: added IPv6 NID support ========= 02:09:59 (1788847799) [ 7659.673726] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7659.798093] alg: No test for adler32 (adler32-zlib) [ 7660.959745] Lustre: Lustre: Build Version: 2.17.57_115_g0321d60 [ 7661.323942] LNet: Added LNI 192.168.206.46@tcp [8/256/0/180] [ 7663.120165] Key type lgssc registered [ 7664.666553] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7758.811383] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7766.664405] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7796.155325] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7920.836609] Lustre: Mounted lustre-client - version 2.17.57_115_g0321d60 [ 7926.408123] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 7936.123143] Lustre: Unmounted lustre-client [ 7936.225503] LNetError: 149844:0:(lib-socket.c:308:lnet_sock_create()) Error trying to bind to fe80::54fb:49ff:fe54:3bdc/1023: rc = -22 [ 7936.235336] LNetError: Unexpected error -22 connecting to 6699:7654::1234:1234:d84@tcp at host [6699:7654::1234:1234:d84]:988 [ 7936.507344] Lustre: Mounted lustre-client - version 2.17.57_115_g0321d60 [ 7936.660226] Lustre: Unmounted lustre-client [ 7937.436866] LNetError: 149845:0:(lib-socket.c:308:lnet_sock_create()) Error trying to bind to fe80::54fb:49ff:fe54:3bdc/1023: rc = -22 [ 7937.461732] LNetError: Unexpected error -22 connecting to 6699:7654::1234:1234:d84@tcp at host [6699:7654::1234:1234:d84]:988 [ 7937.794359] Lustre: Mounted lustre-client - version 2.17.57_115_g0321d60 [ 7937.801532] Lustre: Skipped 1 previous similar message [ 7938.074287] Lustre: Unmounted lustre-client [ 7938.079117] Lustre: Skipped 1 previous similar message [ 7940.093694] LNetError: 149845:0:(lib-socket.c:308:lnet_sock_create()) Error trying to bind to fe80::54fb:49ff:fe54:3bdc/1023: rc = -22 [ 7940.102193] LNetError: Unexpected error -22 connecting to 6699:7654::1234:1234:d84@tcp at host [6699:7654::1234:1234:d84]:988 [ 7940.365179] Lustre: Mounted lustre-client - version 2.17.57_115_g0321d60 [ 7940.368042] Lustre: Skipped 3 previous similar messages [ 7940.487597] Lustre: Unmounted lustre-client [ 7940.491154] Lustre: Skipped 3 previous similar messages [ 7941.435817] Lustre: 155784: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 [ 7942.094569] Lustre: 155814: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 [ 7942.120843] LNetError: 149845:0:(lib-socket.c:308:lnet_sock_create()) Error trying to bind to fe80::54fb:49ff:fe54:3bdc/1023: rc = -22 [ 7942.131022] LNetError: 149845:0:(lib-socket.c:308:lnet_sock_create()) Skipped 1 previous similar message [ 7942.148556] LNetError: Unexpected error -22 connecting to 2001:db8:85a3::8a2e:370:7334@tcp at host [2001:db8:85a3::8a2e:370:7334]:988 [ 7942.166956] LNetError: Skipped 1 previous similar message [ 7950.603052] Lustre: DEBUG MARKER: == conf-sanity test 153c: don't stuck on unreached NID === 02:15:15 (1788848115) [ 7999.267117] LNet: 1 local NIs in recovery (showing 1): 192.168.206.46@tcp [ 8095.850972] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8103.318332] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8128.482515] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8196.005104] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8201.550822] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8222.319989] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8227.938808] LustreError: 161411:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.252.112@tcp: failed processing log, type 1: rc = -110 [ 8273.760767] LNet: 1 local NIs in recovery (showing 1): 192.168.206.46@tcp [ 8353.631501] LNet: 1 local NIs in recovery (showing 1): 192.168.206.46@tcp [ 8363.999740] 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 [ 8364.038108] Lustre: Unmounted lustre-client [ 8364.042526] Lustre: Skipped 3 previous similar messages [ 8364.050677] LustreError: 161411:0:(super25.c:179:lustre_fill_super()) llite: Unable to mount : rc = -110 [ 8398.438375] Key type lgssc unregistered [ 8413.023217] LNet: 1 local NIs in recovery (showing 1): 192.168.206.46@tcp [ 8414.311980] LNet: 161869:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8414.322948] LNetError: 161869:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8484.007267] LNet: Removed LNI 192.168.206.46@tcp [ 8484.933702] Key type .llcrypt unregistered [ 8484.935458] Key type ._llcrypt unregistered [ 8504.105235] Key type ._llcrypt registered [ 8504.108532] Key type .llcrypt registered [ 8505.942098] Lustre: DEBUG MARKER: == conf-sanity test 154: expand .. on rename after MDT backup restore ========================================================== 02:24:30 (1788848670) [ 8524.786347] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8524.830112] alg: No test for adler32 (adler32-zlib) [ 8525.876591] Lustre: Lustre: Build Version: 2.17.57_115_g0321d60 [ 8526.125287] LNet: Added LNI 192.168.206.46@tcp [8/256/0/180] [ 8527.816882] Key type lgssc registered [ 8529.180949] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8667.347545] Lustre: Mounted lustre-client - version 2.17.57_115_g0321d60 [ 8672.298173] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 8689.722718] Lustre: Unmounted lustre-client [ 8850.269033] Lustre: Mounted lustre-client - version 2.17.57_115_g0321d60 [ 8855.380943] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 8862.270429] Lustre: Unmounted lustre-client [ 8910.186358] Lustre: DEBUG MARKER: == conf-sanity test 155: gap in seq allocation from ofd after restarting ========================================================== 02:31:14 (1788849074) [ 9000.791696] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9006.186872] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9030.210296] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9138.799217] Lustre: Mounted lustre-client - version 2.17.57_115_g0321d60 [ 9143.296938] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 9154.244716] Lustre: Unmounted lustre-client [ 9247.999631] Lustre: Mounted lustre-client - version 2.17.57_115_g0321d60 [ 9252.266249] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 9266.824990] Lustre: DEBUG MARKER: == conf-sanity test 156: root_fid on export consistent with client mount ========================================================== 02:37:11 (1788849431) [ 9268.265849] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 9278.612063] Lustre: Unmounted lustre-client [ 9427.862671] Lustre: Mounted lustre-client - version 2.17.57_115_g0321d60 [ 9432.295079] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 9438.455495] Lustre: Unmounted lustre-client [ 9438.857537] Lustre: Mounted lustre-client - version 2.17.57_115_g0321d60 [ 9447.099615] Lustre: DEBUG MARKER: == conf-sanity test 157a: test allow_register for MDT registration and MGS restart ========================================================== 02:40:12 (1788849612) [ 9512.521356] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9516.507634] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9531.304572] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9542.162376] Lustre: Mounted lustre-client - version 2.17.57_115_g0321d60 [ 9603.558735] Lustre: lustre-MDT0000-mdc-ffff88e309d95800: Connection to lustre-MDT0000 (at 192.168.206.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9618.933848] LustreError: MGC192.168.206.146@tcp: Connection to MGS (at 192.168.206.146@tcp) was lost; in progress operations using this service will fail [ 9618.934994] LustreError: lustre-MDT0000-mdc-ffff88e309d95800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 9618.968228] Lustre: lustre-MDT0000-mdc-ffff88e309d95800: Connection restored to 192.168.206.146@tcp (at 192.168.206.146@tcp) [ 9618.968683] Lustre: Evicted from MGS (at 192.168.206.146@tcp) after server handle changed from 0xd437897f4232c059 to 0xd437897f4232c3bd [ 9639.405091] LustreError: lustre-OST0001-osc-ffff88e309d95800: operation ost_connect to node 192.168.206.146@tcp failed: rc = -19 [ 9647.705851] Lustre: Unmounted lustre-client [ 9647.708293] Lustre: Skipped 1 previous similar message [ 9686.665517] Key type lgssc unregistered [ 9686.909438] LNet: 189362:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9686.918399] LNetError: 189362:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9686.934947] LNet: Removed LNI 192.168.206.46@tcp [ 9687.469254] Key type .llcrypt unregistered [ 9687.472366] Key type ._llcrypt unregistered [ 9694.854847] Key type ._llcrypt registered [ 9694.856782] Key type .llcrypt registered [ 9695.865962] Lustre: DEBUG MARKER: == conf-sanity test 157b: verify allow_register (block new OSTs, allow existing) ========================================================== 02:44:21 (1788849861) [ 9697.352315] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9697.386275] alg: No test for adler32 (adler32-zlib) [ 9698.409549] Lustre: Lustre: Build Version: 2.17.57_115_g0321d60 [ 9698.590536] LNet: Added LNI 192.168.206.46@tcp [8/256/0/180] [ 9700.215208] Key type lgssc registered [ 9701.078308] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9736.971972] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9741.086496] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9754.352479] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9766.428906] Lustre: Mounted lustre-client - version 2.17.57_115_g0321d60 [ 9863.656219] LustreError: lustre-OST0001-osc-ffff88e310019000: operation ost_connect to node 192.168.206.146@tcp failed: rc = -19 [ 9888.867166] Lustre: Unmounted lustre-client [ 9911.231688] Key type lgssc unregistered [ 9911.375110] LNet: 195361:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9911.379036] LNetError: 195361:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9911.393302] LNet: Removed LNI 192.168.206.46@tcp [ 9911.854145] Key type .llcrypt unregistered [ 9911.859098] Key type ._llcrypt unregistered [ 9918.959869] Key type ._llcrypt registered [ 9918.962465] Key type .llcrypt registered [ 9920.158488] Lustre: DEBUG MARKER: == conf-sanity test 160: MGC updates failnodes from all participants ========================================================== 02:48:05 (1788850085) [ 9940.168109] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9940.184068] alg: No test for adler32 (adler32-zlib) [ 9941.175432] Lustre: Lustre: Build Version: 2.17.57_115_g0321d60 [ 9941.360837] LNet: Added LNI 192.168.206.46@tcp [8/256/0/180] [ 9942.991234] Key type lgssc registered [ 9943.821836] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9998.807265] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10002.344809] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10014.788034] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10049.358538] Key type lgssc unregistered [10049.545660] LNet: 200325:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10049.550316] LNetError: 200325:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10049.565372] LNet: Removed LNI 192.168.206.46@tcp [10049.990727] Key type .llcrypt unregistered [10049.992364] Key type ._llcrypt unregistered [10158.865332] Key type ._llcrypt registered [10158.868915] Key type .llcrypt registered [10159.070240] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10159.083580] alg: No test for adler32 (adler32-zlib) [10160.098991] Lustre: Lustre: Build Version: 2.17.57_115_g0321d60 [10160.264251] LNet: Added LNI 192.168.206.46@tcp [8/256/0/180] [10161.935292] Key type lgssc registered [10162.795723] Lustre: Echo OBD driver; http://www.lustre.org/ [10191.074320] Lustre: DEBUG MARKER: == conf-sanity test 161: test '-o mgsname' option ======== 02:52:36 (1788850356) [10215.464768] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10219.602874] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10236.390497] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10237.857151] Lustre: Mounted lustre-client - version 2.17.57_115_g0321d60 [10239.809668] Lustre: Unmounted lustre-client [10240.044443] Lustre: Mounted lustre-client - version 2.17.57_115_g0321d60 [10245.485175] Lustre: Unmounted lustre-client [10245.490957] Lustre: Skipped 1 previous similar message [10266.206732] Key type lgssc unregistered [10266.366859] LNet: 205286:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10266.372573] LNetError: 205286:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10266.384556] LNet: Removed LNI 192.168.206.46@tcp [10266.765577] Key type .llcrypt unregistered [10266.767678] Key type ._llcrypt unregistered [10272.340693] Key type ._llcrypt registered [10272.341884] Key type .llcrypt registered [10273.026451] Lustre: DEBUG MARKER: == conf-sanity test 162: replace nids with -o noclient === 02:53:58 (1788850438) [10274.772582] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10274.793912] alg: No test for adler32 (adler32-zlib) [10275.780254] Lustre: Lustre: Build Version: 2.17.57_115_g0321d60 [10275.939772] LNet: Added LNI 192.168.206.46@tcp [8/256/0/180] [10277.591169] Key type lgssc registered [10278.407167] Lustre: Echo OBD driver; http://www.lustre.org/ [10305.808903] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10308.989029] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10321.072714] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10322.415686] Lustre: Mounted lustre-client - version 2.17.57_115_g0321d60 [10330.716297] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10331.503790] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [10337.764738] Lustre: lustre-MDT0000-mdc-ffff88e306477800: Connection to lustre-MDT0000 (at 192.168.206.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [10342.890584] Lustre: lustre-MDT0001-mdc-ffff88e306477800: Connection to lustre-MDT0001 (at 192.168.206.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [10356.066836] LustreError: MGC192.168.206.146@tcp: Connection to MGS (at 192.168.206.146@tcp) was lost; in progress operations using this service will fail [10371.563903] Lustre: Evicted from MGS (at 192.168.206.146@tcp) after server handle changed from 0x9e685495eb857eb7 to 0x9e685495eb85805b [10371.571316] Lustre: MGC192.168.206.146@tcp: Connection restored to 192.168.206.146@tcp (at 192.168.206.146@tcp) [10387.075293] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10387.899745] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in IDLE state after 0 sec [10390.919574] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10392.040210] LustreError: lustre-MDT0000-mdc-ffff88e306477800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [10392.051795] Lustre: lustre-MDT0000-mdc-ffff88e306477800: Connection restored to 192.168.206.146@tcp (at 192.168.206.146@tcp) [10393.773716] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 2 sec [10394.803406] Lustre: Unmounted lustre-client [10414.397795] Key type lgssc unregistered [10414.586768] LNet: 210816:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10414.592063] LNetError: 210816:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10414.605233] LNet: Removed LNI 192.168.206.46@tcp [10415.053350] Key type .llcrypt unregistered [10415.055107] Key type ._llcrypt unregistered [10427.782988] Key type ._llcrypt registered [10427.784940] Key type .llcrypt registered [10427.995990] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10428.006930] alg: No test for adler32 (adler32-zlib) [10428.922482] Lustre: Lustre: Build Version: 2.17.57_115_g0321d60 [10429.053344] LNet: Added LNI 192.168.206.46@tcp [8/256/0/180] [10430.663576] Key type lgssc registered [10431.309042] Lustre: Echo OBD driver; http://www.lustre.org/ [10469.753082] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10472.118263] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10482.012864] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10506.380968] Lustre: DEBUG MARKER: == conf-sanity test 164: test expected_clients parameter and max client tracking ========================================================== 02:57:52 (1788850672) [10549.038314] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10551.697596] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10562.835250] Lustre: DEBUG MARKER: oleg646-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10564.106729] Lustre: Mounted lustre-client - version 2.17.57_115_g0321d60 [10566.578482] Lustre: Mounted lustre-client - version 2.17.57_115_g0321d60 [10568.529218] Lustre: Unmounted lustre-client [10569.056128] Lustre: Unmounted lustre-client [10570.352181] Lustre: Unmounted lustre-client [10570.354118] Lustre: Skipped 1 previous similar message [10574.223376] Lustre: Unmounted lustre-client [10574.224688] Lustre: Skipped 1 previous similar message [10592.834233] Key type lgssc unregistered [10592.974478] LNet: 219938:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10592.978258] LNetError: 219938:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10592.987422] LNet: Removed LNI 192.168.206.46@tcp [10593.282682] Key type .llcrypt unregistered [10593.284285] Key type ._llcrypt unregistered [10597.836560] Key type ._llcrypt registered [10597.838126] Key type .llcrypt registered [10598.512524] Lustre: DEBUG MARKER: == conf-sanity test 200a: check CPU partitions =========== 02:59:24 (1788850764) [10603.458287] Key type .llcrypt unregistered [10603.460091] Key type ._llcrypt unregistered [10603.812271] Key type ._llcrypt registered [10603.814185] Key type .llcrypt registered [10604.079341] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 4 [10604.087892] alg: No test for adler32 (adler32-zlib) [10604.978520] Lustre: Lustre: Build Version: 2.17.57_115_g0321d60 [10605.108391] LNet: Added LNI 192.168.206.46@tcp [8/256/0/180] [10606.711158] Key type lgssc registered [10607.265819] Lustre: Echo OBD driver; http://www.lustre.org/ [10618.025238] Key type lgssc unregistered [10618.157536] LNet: 222086:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10618.161471] LNetError: 222086:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10618.172685] LNet: Removed LNI 192.168.206.46@tcp [10618.480866] Key type .llcrypt unregistered [10618.483590] Key type ._llcrypt unregistered [10618.657172] Key type ._llcrypt registered [10618.658525] Key type .llcrypt registered [10619.427710] Lustre: DEBUG MARKER: == conf-sanity test 200b: set CPU pattern using core selection ========================================================== 02:59:44 (1788850784) [10624.904336] Key type .llcrypt unregistered [10624.905893] Key type ._llcrypt unregistered [10625.277255] Key type ._llcrypt registered [10625.278940] Key type .llcrypt registered [10625.464384] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10625.471440] alg: No test for adler32 (adler32-zlib) [10626.392927] Lustre: Lustre: Build Version: 2.17.57_115_g0321d60 [10626.513667] LNet: Added LNI 192.168.206.46@tcp [8/256/0/180] [10628.135175] Key type lgssc registered [10628.723429] Lustre: Echo OBD driver; http://www.lustre.org/ [10635.771955] Key type lgssc unregistered [10635.900645] LNet: 223625:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10635.905392] LNetError: 223625:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10635.915509] LNet: Removed LNI 192.168.206.46@tcp [10636.245873] Key type .llcrypt unregistered [10636.247870] Key type ._llcrypt unregistered [10639.019142] Key type ._llcrypt registered [10639.021162] Key type .llcrypt registered [10639.233684] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10639.242294] alg: No test for adler32 (adler32-zlib) [10640.131893] Lustre: Lustre: Build Version: 2.17.57_115_g0321d60 [10640.248045] LNet: Added LNI 192.168.206.46@tcp [8/256/0/180] [10641.863262] Key type lgssc registered [10642.408995] Lustre: Echo OBD driver; http://www.lustre.org/ [10652.307488] Key type lgssc unregistered [10652.438701] LNet: 225378:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10652.443039] LNetError: 225378:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10652.455809] LNet: Removed LNI 192.168.206.46@tcp [10652.737810] Key type .llcrypt unregistered [10652.739773] Key type ._llcrypt unregistered [10652.899810] Key type ._llcrypt registered [10652.901518] Key type .llcrypt registered [10653.542526] Lustre: DEBUG MARKER: == conf-sanity test 200c: set CPU pattern using NUMA node layout ========================================================== 03:00:19 (1788850819) [10658.761511] Key type .llcrypt unregistered [10658.763369] Key type ._llcrypt unregistered [10659.133365] Key type ._llcrypt registered [10659.136748] Key type .llcrypt registered [10659.422666] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10659.433555] alg: No test for adler32 (adler32-zlib) [10660.338043] Lustre: Lustre: Build Version: 2.17.57_115_g0321d60 [10660.481975] LNet: Added LNI 192.168.206.46@tcp [8/256/0/180] [10662.096643] Key type lgssc registered [10662.716494] Lustre: Echo OBD driver; http://www.lustre.org/ [10669.979517] Key type lgssc unregistered [10670.105971] LNet: 226913:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10670.110846] LNetError: 226913:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10670.119414] LNet: Removed LNI 192.168.206.46@tcp [10670.444778] Key type .llcrypt unregistered [10670.446927] Key type ._llcrypt unregistered [10673.823196] Key type ._llcrypt registered [10673.824639] Key type .llcrypt registered [10673.923590] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10673.931693] alg: No test for adler32 (adler32-zlib) [10674.833819] Lustre: Lustre: Build Version: 2.17.57_115_g0321d60 [10674.962330] LNet: Added LNI 192.168.206.46@tcp [8/256/0/180] [10676.575156] Key type lgssc registered [10677.170740] Lustre: Echo OBD driver; http://www.lustre.org/ [10684.571065] Key type lgssc unregistered [10684.727706] LNet: 228213:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10684.732470] LNetError: 228213:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10684.744732] LNet: Removed LNI 192.168.206.46@tcp [10685.098366] Key type .llcrypt unregistered [10685.100478] Key type ._llcrypt unregistered [10688.729820] Key type ._llcrypt registered [10688.731819] Key type .llcrypt registered [10689.102843] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10689.113674] alg: No test for adler32 (adler32-zlib) [10690.078608] Lustre: Lustre: Build Version: 2.17.57_115_g0321d60 [10690.234260] LNet: Added LNI 192.168.206.46@tcp [8/256/0/180] [10691.863220] Key type lgssc registered [10692.576549] Lustre: Echo OBD driver; http://www.lustre.org/ [10701.460312] Key type lgssc unregistered [10701.611540] LNet: 229521:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10701.618757] LNetError: 229521:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10701.634515] LNet: Removed LNI 192.168.206.46@tcp [10702.005297] Key type .llcrypt unregistered [10702.008527] Key type ._llcrypt unregistered [10705.683394] Key type ._llcrypt registered [10705.686784] Key type .llcrypt registered [10705.897889] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10705.906291] alg: No test for adler32 (adler32-zlib) [10706.856612] Lustre: Lustre: Build Version: 2.17.57_115_g0321d60 [10707.029245] LNet: Added LNI 192.168.206.46@tcp [8/256/0/180] [10708.671294] Key type lgssc registered [10709.397233] Lustre: Echo OBD driver; http://www.lustre.org/ [10721.247175] Key type lgssc unregistered [10721.373589] LNet: 231280:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10721.377205] LNetError: 231280:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10721.391881] LNet: Removed LNI 192.168.206.46@tcp [10721.706959] Key type .llcrypt unregistered [10721.708884] Key type ._llcrypt unregistered [10721.885294] Key type ._llcrypt registered [10721.887288] Key type .llcrypt registered [10722.533494] Lustre: DEBUG MARKER: == conf-sanity test 200d: set CPU pattern to exclude only CPU 1 ========================================================== 03:01:28 (1788850888) [10727.490328] Key type .llcrypt unregistered [10727.492355] Key type ._llcrypt unregistered [10727.817680] Key type ._llcrypt registered [10727.819149] Key type .llcrypt registered [10728.040626] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10728.047917] alg: No test for adler32 (adler32-zlib) [10728.959719] Lustre: Lustre: Build Version: 2.17.57_115_g0321d60 [10729.071247] LNet: Added LNI 192.168.206.46@tcp [8/256/0/180] [10730.679207] Key type lgssc registered [10731.258299] Lustre: Echo OBD driver; http://www.lustre.org/ [10740.529269] Key type lgssc unregistered [10740.695356] LNet: 232818:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10740.700638] LNetError: 232818:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10740.713797] LNet: Removed LNI 192.168.206.46@tcp [10741.157330] Key type .llcrypt unregistered [10741.158967] Key type ._llcrypt unregistered [10745.323226] Key type ._llcrypt registered [10745.327559] Key type .llcrypt registered [10745.537864] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10745.554643] alg: No test for adler32 (adler32-zlib) [10746.495658] Lustre: Lustre: Build Version: 2.17.57_115_g0321d60 [10746.631759] LNet: Added LNI 192.168.206.46@tcp [8/256/0/180] [10748.255937] Key type lgssc registered [10749.085765] Lustre: Echo OBD driver; http://www.lustre.org/ [10757.527952] Key type lgssc unregistered [10757.677364] LNet: 234125:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10757.683783] LNetError: 234125:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10757.696545] LNet: Removed LNI 192.168.206.46@tcp [10758.029053] Key type .llcrypt unregistered [10758.030557] Key type ._llcrypt unregistered [10761.382634] Key type ._llcrypt registered [10761.393544] Key type .llcrypt registered [10761.515505] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10761.521952] alg: No test for adler32 (adler32-zlib) [10762.408572] Lustre: Lustre: Build Version: 2.17.57_115_g0321d60 [10762.538835] LNet: Added LNI 192.168.206.46@tcp [8/256/0/180] [10764.143231] Key type lgssc registered [10764.791066] Lustre: Echo OBD driver; http://www.lustre.org/ [10772.416192] Key type lgssc unregistered [10772.567898] LNet: 235424:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10772.571872] LNetError: 235424:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10772.580579] LNet: Removed LNI 192.168.206.46@tcp [10772.930953] Key type .llcrypt unregistered [10772.932646] Key type ._llcrypt unregistered [10776.113899] Key type ._llcrypt registered [10776.118524] Key type .llcrypt registered [10776.386306] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10776.393385] alg: No test for adler32 (adler32-zlib) [10777.284590] Lustre: Lustre: Build Version: 2.17.57_115_g0321d60 [10777.404043] LNet: Added LNI 192.168.206.46@tcp [8/256/0/180] [10778.999235] Key type lgssc registered [10779.516610] Lustre: Echo OBD driver; http://www.lustre.org/ [10786.390614] Key type lgssc unregistered [10786.529570] LNet: 236725:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10786.536747] LNetError: 236725:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10786.545665] LNet: Removed LNI 192.168.206.46@tcp [10786.848061] Key type .llcrypt unregistered [10786.850102] Key type ._llcrypt unregistered [10789.957189] Key type ._llcrypt registered [10789.958916] Key type .llcrypt registered [10790.151980] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10790.159356] alg: No test for adler32 (adler32-zlib) [10791.056719] Lustre: Lustre: Build Version: 2.17.57_115_g0321d60 [10791.173207] LNet: Added LNI 192.168.206.46@tcp [8/256/0/180] [10792.775171] Key type lgssc registered [10793.281059] Lustre: Echo OBD driver; http://www.lustre.org/ [10800.519444] Key type lgssc unregistered [10800.678367] LNet: 238029:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10800.684327] LNetError: 238029:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10800.691665] LNet: Removed LNI 192.168.206.46@tcp [10800.983236] Key type .llcrypt unregistered [10800.985962] Key type ._llcrypt unregistered [10804.041611] Key type ._llcrypt registered [10804.044302] Key type .llcrypt registered [10804.279973] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10804.287927] alg: No test for adler32 (adler32-zlib) [10805.222453] Lustre: Lustre: Build Version: 2.17.57_115_g0321d60 [10805.355227] LNet: Added LNI 192.168.206.46@tcp [8/256/0/180] [10806.967173] Key type lgssc registered [10807.566687] Lustre: Echo OBD driver; http://www.lustre.org/ [10814.837591] Key type lgssc unregistered [10814.972452] LNet: 239328:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10814.975986] LNetError: 239328:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10814.984313] LNet: Removed LNI 192.168.206.46@tcp [10815.323462] Key type .llcrypt unregistered [10815.325320] Key type ._llcrypt unregistered [10826.217331] Key type ._llcrypt registered [10826.218915] Key type .llcrypt registered [10826.903243] Lustre: DEBUG MARKER: == conf-sanity test 200e: set CPU pattern using relative core exclusion ========================================================== 03:03:12 (1788850992) [10833.181264] Key type .llcrypt unregistered [10833.184245] Key type ._llcrypt unregistered [10833.573580] Key type ._llcrypt registered [10833.574825] Key type .llcrypt registered [10833.809496] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10833.820912] alg: No test for adler32 (adler32-zlib) [10834.764872] Lustre: Lustre: Build Version: 2.17.57_115_g0321d60 [10834.906474] LNet: Added LNI 192.168.206.46@tcp [8/256/0/180] [10836.559151] Key type lgssc registered [10837.196537] Lustre: Echo OBD driver; http://www.lustre.org/ [10845.183600] Key type lgssc unregistered [10845.341327] LNet: 241628:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10845.345309] LNetError: 241628:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10845.353794] LNet: Removed LNI 192.168.206.46@tcp [10845.679795] Key type .llcrypt unregistered [10845.682072] Key type ._llcrypt unregistered [10848.654242] Key type ._llcrypt registered [10848.655988] Key type .llcrypt registered [10848.912085] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10848.919405] alg: No test for adler32 (adler32-zlib) [10849.863466] Lustre: Lustre: Build Version: 2.17.57_115_g0321d60 [10849.974890] LNet: Added LNI 192.168.206.46@tcp [8/256/0/180] [10851.583129] Key type lgssc registered [10852.197043] Lustre: Echo OBD driver; http://www.lustre.org/ [10859.646737] Key type lgssc unregistered [10859.794753] LNet: 242940:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10859.799400] LNetError: 242940:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10859.809526] LNet: Removed LNI 192.168.206.46@tcp [10860.188157] Key type .llcrypt unregistered [10860.190093] Key type ._llcrypt unregistered [10863.600225] Key type ._llcrypt registered [10863.602363] Key type .llcrypt registered [10863.846919] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10863.856169] alg: No test for adler32 (adler32-zlib) [10864.771679] Lustre: Lustre: Build Version: 2.17.57_115_g0321d60 [10864.897786] LNet: Added LNI 192.168.206.46@tcp [8/256/0/180] [10866.511354] Key type lgssc registered [10867.146691] Lustre: Echo OBD driver; http://www.lustre.org/ [10874.297458] Key type lgssc unregistered [10874.430791] LNet: 244242:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10874.436159] LNetError: 244242:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10874.454072] LNet: Removed LNI 192.168.206.46@tcp [10874.769793] Key type .llcrypt unregistered [10874.773209] Key type ._llcrypt unregistered [10877.566548] Key type ._llcrypt registered [10877.568334] Key type .llcrypt registered [10877.774437] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10877.782111] alg: No test for adler32 (adler32-zlib) [10878.688066] Lustre: Lustre: Build Version: 2.17.57_115_g0321d60 [10878.810173] LNet: Added LNI 192.168.206.46@tcp [8/256/0/180] [10880.423313] Key type lgssc registered [10881.002741] Lustre: Echo OBD driver; http://www.lustre.org/ [10890.581564] Key type lgssc unregistered [10890.722611] LNet: 246028:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10890.726719] LNetError: 246028:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10890.736743] LNet: Removed LNI 192.168.206.46@tcp [10891.009234] Key type .llcrypt unregistered [10891.011290] Key type ._llcrypt unregistered [10891.161486] Key type ._llcrypt registered [10891.162985] Key type .llcrypt registered [10891.704814] Lustre: DEBUG MARKER: == conf-sanity test 250: verify lcfg_erase removes filesystem parameters ========================================================== 03:04:17 (1788851057) [10898.526422] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10898.538349] alg: No test for adler32 (adler32-zlib) [10899.424776] Lustre: Lustre: Build Version: 2.17.57_115_g0321d60 [10899.531856] LNet: Added LNI 192.168.206.46@tcp [8/256/0/180] [10901.127171] Key type lgssc registered [10901.589681] Lustre: Echo OBD driver; http://www.lustre.org/ [11009.276823] Lustre: DEBUG MARKER: == conf-sanity test 802a: simulate readonly device ======= 03:06:14 (1788851174) [11009.923440] Lustre: DEBUG MARKER: SKIP: conf-sanity test_802a ZFS specific test [11030.409478] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 10735 sec ========= 03:06:36 (1788851196) [11031.047473] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 03:06:36 (1788851196) === [11032.264751] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 03:06:37 (1788851197) === [11044.177305] Key type lgssc unregistered [11044.300619] LNet: 255042:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11044.303034] LNetError: 255042:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11044.309552] LNet: Removed LNI 192.168.206.46@tcp [11044.537777] Key type .llcrypt unregistered [11044.538986] Key type ._llcrypt unregistered