[ 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 402967725 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2464 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE2300 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 0022C0 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2374 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE2404 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE243C 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe2300-0xbffe2373] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22ff] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2374-0xbffe2403] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe2404-0xbffe243b] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe243c-0xbffe2463] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2895288K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001012] APIC: Switch to symmetric I/O mode setup [ 0.002236] x2apic enabled [ 0.003003] Switched APIC routing to physical x2apic. [ 0.004005] kvm-guest: setup PV IPIs [ 0.006390] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.007000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.007009] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.008005] pid_max: default: 32768 minimum: 301 [ 0.009111] LSM: Security Framework initializing [ 0.011001] Yama: becoming mindful. [ 0.011643] SELinux: Initializing. [ 0.012052] *** VALIDATE selinux *** [ 0.019323] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.023376] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.024097] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.025058] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.026055] *** VALIDATE tmpfs *** [ 0.027320] *** VALIDATE proc *** [ 0.028133] *** VALIDATE cgroup *** [ 0.029005] *** VALIDATE cgroup2 *** [ 0.030210] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.031105] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.032003] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.033021] Spectre V2 : User space: Vulnerable [ 0.034004] Speculative Store Bypass: Vulnerable [ 0.036836] debug: unmapping init [mem 0xffffffffa8a59000-0xffffffffa8a60fff] [ 0.038173] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.039635] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.040011] ... version: 2 [ 0.041005] ... bit width: 48 [ 0.041991] ... generic registers: 4 [ 0.042007] ... value mask: 0000ffffffffffff [ 0.043007] ... max period: 00007fffffffffff [ 0.044005] ... fixed-purpose events: 3 [ 0.045005] ... event mask: 000000070000000f [ 0.046217] rcu: Hierarchical SRCU implementation. [ 0.048115] smp: Bringing up secondary CPUs ... [ 0.049451] x86: Booting SMP configuration: [ 0.050014] .... node #0, CPUs: #1 #2 #3 [ 0.052382] smp: Brought up 1 node, 4 CPUs [ 0.053976] smpboot: Max logical packages: 1 [ 0.054009] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.134315] node 0 deferred pages initialised in 79ms [ 0.136615] devtmpfs: initialized [ 0.137203] x86/mm: Memory block size: 128MB [ 0.139395] gcov: version magic: 0x41383552 [ 0.141269] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.142059] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.143197] pinctrl core: initialized pinctrl subsystem [ 0.145148] [ 0.145709] ************************************************************* [ 0.147006] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.149008] ** ** [ 0.151006] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.153009] ** ** [ 0.155006] ** This means that this kernel is built to expose internal ** [ 0.157006] ** IOMMU data structures, which may compromise security on ** [ 0.158005] ** your system. ** [ 0.159006] ** ** [ 0.162006] ** If you see this message and you are not debugging the ** [ 0.163006] ** kernel, report this immediately to your vendor! ** [ 0.165005] ** ** [ 0.166006] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.168006] ************************************************************* [ 0.169429] NET: Registered protocol family 16 [ 0.171266] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.173038] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.175037] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.179012] cpuidle: using governor menu [ 0.179936] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.182580] PCI: Using configuration type 1 for base access [ 0.184119] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.191082] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.192017] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.194092] cryptd: max_cpu_qlen set to 1000 [ 0.196191] ACPI: Added _OSI(Module Device) [ 0.197008] ACPI: Added _OSI(Processor Device) [ 0.197940] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.199006] ACPI: Added _OSI(Processor Aggregator Device) [ 0.202072] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.206539] ACPI: Interpreter enabled [ 0.208042] ACPI: PM: (supports S0 S3 S4 S5) [ 0.209005] ACPI: Using IOAPIC for interrupt routing [ 0.210049] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.213328] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.220670] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.222015] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.223007] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.225042] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.229016] acpiphp: Slot [2] registered [ 0.229868] acpiphp: Slot [5] registered [ 0.231063] acpiphp: Slot [6] registered [ 0.231842] acpiphp: Slot [3] registered [ 0.232063] acpiphp: Slot [4] registered [ 0.233000] acpiphp: Slot [7] registered [ 0.233975] acpiphp: Slot [8] registered [ 0.235039] acpiphp: Slot [9] registered [ 0.235844] acpiphp: Slot [10] registered [ 0.237037] acpiphp: Slot [11] registered [ 0.237825] acpiphp: Slot [12] registered [ 0.238037] acpiphp: Slot [13] registered [ 0.238866] acpiphp: Slot [14] registered [ 0.240061] acpiphp: Slot [15] registered [ 0.240875] acpiphp: Slot [16] registered [ 0.241035] acpiphp: Slot [17] registered [ 0.242040] acpiphp: Slot [18] registered [ 0.242914] acpiphp: Slot [19] registered [ 0.244038] acpiphp: Slot [20] registered [ 0.244870] acpiphp: Slot [21] registered [ 0.246041] acpiphp: Slot [22] registered [ 0.246860] acpiphp: Slot [23] registered [ 0.247037] acpiphp: Slot [24] registered [ 0.247929] acpiphp: Slot [25] registered [ 0.249039] acpiphp: Slot [26] registered [ 0.249833] acpiphp: Slot [27] registered [ 0.251061] acpiphp: Slot [28] registered [ 0.251936] acpiphp: Slot [29] registered [ 0.252047] acpiphp: Slot [30] registered [ 0.252931] acpiphp: Slot [31] registered [ 0.254036] PCI host bridge to bus 0000:00 [ 0.255014] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.256009] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.258011] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.260009] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.261009] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.263010] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.264134] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.265645] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.267859] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.273339] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.276045] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.277006] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.279007] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.280006] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.282383] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.283593] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.286023] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.287496] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.290006] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.297778] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.301406] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.305686] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.311012] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.315850] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.324013] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.329642] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.335009] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.339007] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.348012] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.354556] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.357266] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.358192] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.360238] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.361095] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.366027] iommu: Default domain type: Passthrough [ 0.368396] SCSI subsystem initialized [ 0.369088] ACPI: bus type USB registered [ 0.371091] usbcore: registered new interface driver usbfs [ 0.372042] usbcore: registered new interface driver hub [ 0.373035] usbcore: registered new device driver usb [ 0.374096] pps_core: LinuxPPS API ver. 1 registered [ 0.375005] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.377034] PTP clock support registered [ 0.379107] EDAC MC: Ver: 3.0.0 [ 0.380401] PCI: Using ACPI for IRQ routing [ 0.382590] NetLabel: Initializing [ 0.383007] NetLabel: domain hash size = 128 [ 0.385007] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.386063] NetLabel: unlabeled traffic allowed by default [ 0.388097] vgaarb: loaded [ 0.389251] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.390005] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.398288] clocksource: Switched to clocksource kvm-clock [ 0.485645] VFS: Disk quotas dquot_6.6.0 [ 0.487031] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.488876] *** VALIDATE ramfs *** [ 0.489713] *** VALIDATE hugetlbfs *** [ 0.490640] pnp: PnP ACPI init [ 0.492688] pnp: PnP ACPI: found 6 devices [ 0.504251] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.506887] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.509127] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.510961] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.512811] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.515072] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.517428] NET: Registered protocol family 2 [ 0.519406] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.522752] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.525161] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.529456] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.532400] TCP: Hash tables configured (established 65536 bind 65536) [ 0.534724] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.536707] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.538600] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.540538] NET: Registered protocol family 1 [ 0.542753] RPC: Registered named UNIX socket transport module. [ 0.544083] RPC: Registered udp transport module. [ 0.545093] RPC: Registered tcp transport module. [ 0.546338] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.548020] NET: Registered protocol family 44 [ 0.549158] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.550774] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.552363] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.554108] PCI: CLS 0 bytes, default 64 [ 0.555354] Unpacking initramfs... [ 1.886321] debug: unmapping init [mem 0xffff9b4cbcc64000-0xffff9b4cbffcffff] [ 1.890655] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 1.892369] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 1.894672] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.333064] Initialise system trusted keyrings [ 2.334526] Key type blacklist registered [ 2.335926] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.344090] zbud: loaded [ 2.346787] *** VALIDATE nfs *** [ 2.348065] *** VALIDATE nfs4 *** [ 2.349139] pstore: using deflate compression [ 2.351163] Platform Keyring initialized [ 2.424660] NET: Registered protocol family 38 [ 2.425772] Key type asymmetric registered [ 2.427335] Asymmetric key parser 'x509' registered [ 2.429179] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.431189] io scheduler mq-deadline registered [ 2.432419] io scheduler kyber registered [ 2.433583] io scheduler bfq registered [ 2.434870] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.436857] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.439013] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.441075] ACPI: Power Button [PWRF] [ 2.444817] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.449686] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.455958] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.482113] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.509336] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.513241] Non-volatile memory driver v1.3 [ 2.514271] Linux agpgart interface v0.103 [ 2.536204] virtio_blk virtio1: [vda] 150024 512-byte logical blocks (76.8 MB/73.3 MiB) [ 2.538967] vda: detected capacity change from 0 to 76812288 [ 2.550477] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 2.552835] vdb: detected capacity change from 0 to 1073741824 [ 2.557962] libphy: Fixed MDIO Bus: probed [ 2.563941] usbcore: registered new interface driver usbserial_generic [ 2.565727] usbserial: USB Serial support registered for generic [ 2.567405] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 2.570486] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 2.571959] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 2.573745] mousedev: PS/2 mouse device common for all mice [ 2.575774] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 2.578400] rtc_cmos 00:05: RTC can wake from S4 [ 2.581295] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 2.581490] rtc_cmos 00:05: registered as rtc0 [ 2.585563] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 2.587447] intel_pstate: CPU model not supported [ 2.589064] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 2.590820] hid: raw HID events driver (C) Jiri Kosina [ 2.592977] usbcore: registered new interface driver usbhid [ 2.594611] usbhid: USB HID core driver [ 2.595888] drop_monitor: Initializing network drop monitor service [ 2.597565] Initializing XFRM netlink socket [ 2.599351] NET: Registered protocol family 10 [ 2.601222] Segment Routing with IPv6 [ 2.602198] NET: Registered protocol family 17 [ 2.603513] mpls_gso: MPLS GSO support [ 2.607695] RAS: Correctable Errors collector initialized. [ 2.609733] AVX version of gcm_enc/dec engaged. [ 2.611469] AES CTR mode by8 optimization enabled [ 2.659619] sched_clock: Marking stable (2659603504, 0)->(3330224219, -670620715) [ 2.661793] registered taskstats version 1 [ 2.663273] Loading compiled-in X.509 certificates [ 2.664958] zswap: loaded using pool lzo/zbud [ 2.681394] Key type big_key registered [ 2.689858] Key type encrypted registered [ 2.690896] ima: No TPM chip found, activating TPM-bypass! [ 2.692252] ima: Allocated hash algorithm: sha1 [ 2.693694] ima: No architecture policies found [ 2.694782] evm: Initialising EVM extended attributes: [ 2.696124] evm: security.selinux [ 2.696943] evm: security.ima [ 2.697572] evm: security.capability [ 2.698348] evm: HMAC attrs: 0x1 [ 2.699871] rtc_cmos 00:05: setting system clock to 2026-09-14 23:13:13 UTC (1789427593) [ 2.704592] debug: unmapping init [mem 0xffffffffa9a03000-0xffffffffa9bfffff] [ 2.706519] debug: unmapping init [mem 0xffffffffa8782000-0xffffffffa8a58fff] [ 2.715098] Write protecting the kernel read-only data: 28672k [ 2.718255] debug: unmapping init [mem 0xffffffffa6e03000-0xffffffffa6ffffff] [ 2.720530] debug: unmapping init [mem 0xffffffffa7714000-0xffffffffa77fffff] [ 2.744137] 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) [ 2.750818] systemd[1]: Detected virtualization kvm. [ 2.751870] systemd[1]: Detected architecture x86-64. [ 2.753576] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 2.776337] systemd[1]: No hostname configured. [ 2.777451] systemd[1]: Set hostname to . [ 2.778647] random: systemd: uninitialized urandom read (16 bytes read) [ 2.780065] systemd[1]: Initializing machine ID from random generator. [ 2.812090] random: ln: uninitialized urandom read (6 bytes read) [ 2.879127] random: systemd: uninitialized urandom read (16 bytes read) [ 2.881340] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ 2.884417] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ 2.887777] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. [ OK ] Listening on Journal Socket. [ OK ] Started Memstrack Anylazing Service. Starting Create Volatile Files and Directories... [ OK ] Reached target Initrd Root Device. [ OK ] Listening on udev Control Socket. [ 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 ] Reached target Sockets. [ OK ] Reached target Swap. Starting Setup Virtual Console... Starting Create list of required st…ce nodes for the current kernel... Starting Journal Service... [ OK ] Reached target Timers. Starting Apply Kernel Variables... [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Setup Virtual Console. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. Starting Create Static Device Nodes in /dev... Starting 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... [ 3.358045] device-mapper: uevent: version 1.0.3 [ 3.359696] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 3.902019] virtio_net virtio0 ens2: renamed from eth0 [ 3.912833] scsi host0: ata_piix [ 3.949290] scsi host1: ata_piix [ 3.966046] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 3.968048] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 7.560303] dracut-initqueue[581]: RTNETLINK answers: File exists [ 7.712129] random: fast init done [ 8.202210] random: crng init done [ 8.203332] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 8.591571] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Timers. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ OK ] Stopped target Sockets. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Swap. Stopping udev Kernel Device Manager... [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ 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... [ 9.526905] printk: systemd: 26 output lines suppressed due to ratelimiting [ 9.689891] SELinux: Disabled at runtime. [ 9.737047] 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) [ 9.743744] systemd[1]: Detected virtualization kvm. [ 9.745080] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 10.138289] systemd[1]: initrd-switch-root.service: Succeeded. [ 10.140892] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 10.144711] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 10.147161] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 10.151123] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 10.155804] systemd[1]: Starting Journal Service... Starting Journal Service... [ 10.159558] systemd[1]: Created slice User and Session Slice. [ OK ] Created slice User and Session Slice. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Created slice system-serial\x2dgetty.slice. Activating swap /dev/disk/by-label/SWAP... [ OK ] Reached target Slices. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. Mounting Huge Pages File System... [ 10.188951] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS Starting Remount Root and Kernel File Systems... [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Listening on Process Core Dump Socket. [ OK ] Created slice system-getty.slice. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Reached target rpc_pipefs.target. Starting Apply Kernel Variables... Mounting POSIX Message Queue File System... Mounting Kernel Debug File System... [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd Root File System. [ OK ] Stopped target Initrd File Systems. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Listening on udev Control Socket. [ OK ] Listening on udev Kernel Socket. Starting udev Coldplug all Devices... [ OK ] Started Journal Service. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted Huge Pages File System. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Mounted Kernel Debug File System. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... [ OK ] Reached target Swap. Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... Starting udev Kernel Device Manager... [ OK ] Mounted /mnt. [ 10.522087] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ OK ] Started udev Coldplug all Devices. [ 10.833551] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 10.880028] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 10.955573] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 10.965991] EDAC sbridge: Ver: 1.1.2 [ 11.793829] Key type dns_resolver registered [ 12.059676] NFS: Registering the id_resolver key type [ 12.061041] Key type id_resolver registered [ 12.062295] Key type id_legacy registered [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started dnf makecache --timer. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Reached target sshd-keygen.target. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started D-Bus System Message Bus. [ OK ] Started irqbalance daemon. Starting Network Manager... Starting Login Service... Starting Restore /run/initramfs on shutdown... [ 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 Dynamic System Tuning Daemon... Starting GSSAPI Proxy Daemon... Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Serial Getty on ttyS0. [ 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 Crash recovery kernel arming... Starting System Logging Service... Starting Notify NFS peers of a restart... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started Authorization Manager. [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg212-client login: [ 36.672389] libcfs: loading out-of-tree module taints kernel. [ 36.698431] Key type ._llcrypt registered [ 36.699632] Key type .llcrypt registered [ 36.978310] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 36.984048] alg: No test for adler32 (adler32-zlib) [ 37.942618] Lustre: Lustre: Build Version: 2.17.58_41_g9380d02 [ 38.223850] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [ 39.832368] Key type lgssc registered [ 40.426420] Lustre: Echo OBD driver; http://www.lustre.org/ [ 98.833997] Lustre: Mounted lustre-client - version 2.17.58_41_g9380d02 [ 101.261182] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 108.999953] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing check_logdir /tmp/testlogs/ [ 110.527212] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing yml_node [ 111.910219] Lustre: DEBUG MARKER: Client: 2.17.58.41 [ 112.771417] Lustre: DEBUG MARKER: MDS: 2.17.58.41 [ 113.601465] Lustre: DEBUG MARKER: OSS: 2.17.58.41 [ 114.137841] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Mon Sep 14 19:15:04 EDT 2026 [ 119.402929] Lustre: DEBUG MARKER: excepting tests: 32newtarball [ 119.911813] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 120.438775] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 130.745234] Lustre: Unmounted lustre-client [ 225.443922] hrtimer: interrupt took 5984880 ns [ 227.153697] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 238.387192] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 268.327611] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 321.790313] Lustre: DEBUG MARKER: == conf-sanity test 121: failover MGS ==================== 19:18:30 (1789427910) [ 381.206489] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 387.599127] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 429.704719] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mgc.*.mgs_server_uuid 1475 0 [ 482.135938] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 486.968208] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 526.720773] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mgc.*.mgs_server_uuid 1475 0 [ 547.826689] Lustre: DEBUG MARKER: == conf-sanity test 122a: Check OST sequence update ====== 19:22:17 (1789428137) [ 637.369654] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 643.556241] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 662.226763] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 662.997280] Lustre: Mounted lustre-client - version 2.17.58_41_g9380d02 [ 681.283873] Lustre: Unmounted lustre-client [ 704.765840] Key type lgssc unregistered [ 704.979743] LNet: 21843:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 704.983287] LNetError: 21843:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 705.004760] LNet: Removed LNI 192.168.202.12@tcp [ 705.586328] Key type .llcrypt unregistered [ 705.587930] Key type ._llcrypt unregistered [ 718.512132] Key type ._llcrypt registered [ 718.516786] Key type .llcrypt registered [ 719.624230] Lustre: DEBUG MARKER: == conf-sanity test 123aa: llog_print works with FIDs and simple names ========================================================== 19:25:09 (1789428309) [ 721.613149] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 721.657919] alg: No test for adler32 (adler32-zlib) [ 722.824523] Lustre: Lustre: Build Version: 2.17.58_41_g9380d02 [ 723.050657] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [ 724.697478] Key type lgssc registered [ 725.706532] Lustre: Echo OBD driver; http://www.lustre.org/ [ 773.118600] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 779.030070] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 797.234787] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 798.699680] Lustre: Mounted lustre-client - version 2.17.58_41_g9380d02 [ 809.651883] Lustre: DEBUG MARKER: == conf-sanity test 123ab: llog_print params output values from set_param -P ========================================================== 19:26:38 (1789428398) [ 820.526877] Lustre: DEBUG MARKER: == conf-sanity test 123ac: llog_print with --start and --end ========================================================== 19:26:49 (1789428409) [ 828.841556] Lustre: DEBUG MARKER: == conf-sanity test 123ad: llog_print shows all records == 19:26:58 (1789428418) [ 840.781627] Lustre: DEBUG MARKER: == conf-sanity test 123ae: llog_cancel can cancel requested record ========================================================== 19:27:09 (1789428429) [ 862.853692] Lustre: DEBUG MARKER: == conf-sanity test 123af: llog_catlist can show all config files correctly ========================================================== 19:27:32 (1789428452) [ 875.850594] Lustre: DEBUG MARKER: == conf-sanity test 123ag: llog_print skips values deleted by set_param -P -d ========================================================== 19:27:45 (1789428465) [ 888.756972] Lustre: DEBUG MARKER: == conf-sanity test 123ah: del_ost cancels config log entries correctly ========================================================== 19:27:58 (1789428478) [ 896.608589] Lustre: Unmounted lustre-client [ 897.090424] Lustre: Mounted lustre-client - version 2.17.58_41_g9380d02 [ 898.822689] Lustre: Unmounted lustre-client [ 932.212588] Key type lgssc unregistered [ 932.483782] LNet: 30206:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 932.488193] LNetError: 30206:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 932.500736] LNet: Removed LNI 192.168.202.12@tcp [ 933.010715] Key type .llcrypt unregistered [ 933.012894] Key type ._llcrypt unregistered [ 956.032946] Key type ._llcrypt registered [ 956.039273] Key type .llcrypt registered [ 956.341918] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 956.353944] alg: No test for adler32 (adler32-zlib) [ 957.466791] Lustre: Lustre: Build Version: 2.17.58_41_g9380d02 [ 957.725595] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [ 959.481496] Key type lgssc registered [ 960.810401] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1038.886557] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1045.006084] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1068.216277] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1117.398655] Lustre: DEBUG MARKER: == conf-sanity test 123ai: llog_print display all non skipped records ========================================================== 19:31:46 (1789428706) [ 1149.174927] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1155.093863] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1175.131405] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1176.784241] Lustre: Mounted lustre-client - version 2.17.58_41_g9380d02 [ 1294.417788] Lustre: DEBUG MARKER: == conf-sanity test 123aj: check permanent TBF rules ===== 19:34:43 (1789428883) [ 1331.718446] Lustre: DEBUG MARKER: == conf-sanity test 123F: clear and reset all parameters using set_param -F ========================================================== 19:35:21 (1789428921) [ 1341.873192] Lustre: Unmounted lustre-client [ 1421.264938] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1426.790855] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1445.981867] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1446.790338] Lustre: Mounted lustre-client - version 2.17.58_41_g9380d02 [ 1452.393170] Lustre: Unmounted lustre-client [ 1479.326825] Key type lgssc unregistered [ 1479.616216] LNet: 41520:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1479.629797] LNetError: 41520:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1479.656530] LNet: Removed LNI 192.168.202.12@tcp [ 1480.580422] Key type .llcrypt unregistered [ 1480.582725] Key type ._llcrypt unregistered [ 1495.193220] Key type ._llcrypt registered [ 1495.197934] Key type .llcrypt registered [ 1496.677384] Lustre: DEBUG MARKER: == conf-sanity test 123G: clear and reset all parameters using apply_yaml ========================================================== 19:38:05 (1789429085) [ 1498.971381] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1499.060519] alg: No test for adler32 (adler32-zlib) [ 1500.234779] Lustre: Lustre: Build Version: 2.17.58_41_g9380d02 [ 1500.511426] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [ 1502.160829] Key type lgssc registered [ 1503.220323] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1554.204644] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1559.144886] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1576.531591] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1579.282896] Lustre: Mounted lustre-client - version 2.17.58_41_g9380d02 [ 1591.113047] Lustre: Unmounted lustre-client [ 1671.751438] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1677.331626] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1696.690627] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1697.380664] Lustre: Mounted lustre-client - version 2.17.58_41_g9380d02 [ 1702.703172] Lustre: Unmounted lustre-client [ 1728.070166] Key type lgssc unregistered [ 1728.338774] LNet: 47049:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1728.347804] LNetError: 47049:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1728.369349] LNet: Removed LNI 192.168.202.12@tcp [ 1729.034758] Key type .llcrypt unregistered [ 1729.037126] Key type ._llcrypt unregistered [ 1740.844209] Key type ._llcrypt registered [ 1740.845844] Key type .llcrypt registered [ 1741.758428] Lustre: DEBUG MARKER: == conf-sanity test 123H: check concurent accesses with 'lctl llog_print ========================================================== 19:42:11 (1789429331) [ 1743.331259] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1743.347144] alg: No test for adler32 (adler32-zlib) [ 1744.328816] Lustre: Lustre: Build Version: 2.17.58_41_g9380d02 [ 1744.547848] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [ 1746.216208] Key type lgssc registered [ 1747.181492] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1785.583472] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1791.574683] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1811.421369] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1814.165640] Lustre: Mounted lustre-client - version 2.17.58_41_g9380d02 [ 1909.282333] Lustre: DEBUG MARKER: == conf-sanity test 124: check failover after replace_nids ========================================================== 19:44:58 (1789429498) [ 1910.810690] Lustre: DEBUG MARKER: SKIP: conf-sanity test_124 needs MDT failover setup [ 1912.907952] Lustre: DEBUG MARKER: == conf-sanity test 126: mount in parallel shouldn't cause a crash ========================================================== 19:45:01 (1789429501) [ 1915.299182] Lustre: Unmounted lustre-client [ 1951.584610] Key type lgssc unregistered [ 1951.844077] LNet: 52378:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1951.849668] LNetError: 52378:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1951.868247] LNet: Removed LNI 192.168.202.12@tcp [ 1952.854196] Key type .llcrypt unregistered [ 1952.856845] Key type ._llcrypt unregistered [ 1979.691653] Key type ._llcrypt registered [ 1979.716960] Key type .llcrypt registered [ 1980.163900] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1980.176103] alg: No test for adler32 (adler32-zlib) [ 1981.297730] Lustre: Lustre: Build Version: 2.17.58_41_g9380d02 [ 1981.559456] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [ 1983.200285] Key type lgssc registered [ 1984.335329] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2013.935473] Lustre: DEBUG MARKER: == conf-sanity test 127: direct io overwrite on full ost ========================================================== 19:46:43 (1789429603) [ 2025.374216] Key type lgssc unregistered [ 2025.604699] LNet: 54781:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2025.612579] LNetError: 54781:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2025.627901] LNet: Removed LNI 192.168.202.12@tcp [ 2026.285509] Key type .llcrypt unregistered [ 2026.287799] Key type ._llcrypt unregistered [ 2038.517392] Key type ._llcrypt registered [ 2038.522139] Key type .llcrypt registered [ 2039.016819] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2039.039496] alg: No test for adler32 (adler32-zlib) [ 2040.150459] Lustre: Lustre: Build Version: 2.17.58_41_g9380d02 [ 2040.398121] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [ 2042.088270] Key type lgssc registered [ 2043.544510] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2092.107313] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2097.387941] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2119.153949] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2121.027402] Lustre: Mounted lustre-client - version 2.17.58_41_g9380d02 [ 2168.612786] Lustre: DEBUG MARKER: == conf-sanity test 128: Force using remote logs with --nolocallogs ========================================================== 19:49:17 (1789429757) [ 2170.366199] Lustre: DEBUG MARKER: SKIP: conf-sanity test_128 need separate mgs device [ 2172.349418] Lustre: DEBUG MARKER: == conf-sanity test 129: attempt to connect an OST with the same index should fail ========================================================== 19:49:21 (1789429761) [ 2174.143367] Lustre: Unmounted lustre-client [ 2233.626632] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2238.945912] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2302.896107] Lustre: DEBUG MARKER: == conf-sanity test 130: re-register an MDT after writeconf ========================================================== 19:51:32 (1789429892) [ 2377.167304] Lustre: Mounted lustre-client - version 2.17.58_41_g9380d02 [ 2381.783594] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 2392.556783] Lustre: lustre-MDT0001-mdc-ffff9b4d06708000: Connection to lustre-MDT0001 (at 192.168.202.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2402.792203] Lustre: lustre-OST0000-osc-ffff9b4d06708000: disconnect after 23s idle [ 2407.748302] LustreError: 63458:0:(obd_config.c:2102:class_config_llog_handler()) MGC192.168.202.112@tcp: cfg command failed: rc = -17 [ 2407.753480] Lustre: cmd=cf001 0:lustre-MDT0001-mdc 1:mdc 2:lustre-clilmv_UUID [ 2407.753480] [ 2407.756458] LustreError: 62728:0:(mgc_request.c:614:do_requeue()) failed processing log: -17 [ 2421.588872] Lustre: DEBUG MARKER: == conf-sanity test 131: MDT backup restore with project ID and inheritance flag ========================================================== 19:53:30 (1789430010) [ 2484.758093] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 2567.420774] Lustre: Unmounted lustre-client [ 2730.444236] Lustre: Mounted lustre-client - version 2.17.58_41_g9380d02 [ 2735.683991] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 2792.285579] Lustre: DEBUG MARKER: == conf-sanity test 132: hsm_actions processed after failover ========================================================== 19:59:41 (1789430381) [ 2793.943517] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2815.550886] Lustre: Unmounted lustre-client [ 2952.368020] Lustre: DEBUG MARKER: == conf-sanity test 133: stripe QOS: free space balance in a pool ========================================================== 20:02:21 (1789430541) [ 2953.738838] Lustre: DEBUG MARKER: SKIP: conf-sanity test_133 needs >= 4 OSTs [ 2955.136733] Lustre: DEBUG MARKER: == conf-sanity test 134: check_iam works without faults == 20:02:24 (1789430544) [ 3045.833179] Lustre: Mounted lustre-client - version 2.17.58_41_g9380d02 [ 3051.394606] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 3784.525380] Lustre: DEBUG MARKER: == conf-sanity test 135: check the behavior when changelog is wrapped around ========================================================== 20:16:13 (1789431373) [ 3786.725513] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3786.730296] Lustre: Skipped 3 previous similar messages [ 3797.167185] Lustre: Unmounted lustre-client [ 3918.868460] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3924.678462] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3948.816443] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3949.455456] Lustre: Mounted lustre-client - version 2.17.58_41_g9380d02 [ 5517.669604] Lustre: 95224:0:(llog_cat.c:310:llog_cat_process_or_fork()) lustre-MDT0000-mdc-ffff9b4d02bba000: catlog [0xa:0x5:0x0] crosses index zero [ 5541.825252] Lustre: Unmounted lustre-client [ 5576.487147] Key type lgssc unregistered [ 5576.721799] LNet: 103371:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5576.725134] LNetError: 103371:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5576.739960] LNet: Removed LNI 192.168.202.12@tcp [ 5577.446177] Key type .llcrypt unregistered [ 5577.448294] Key type ._llcrypt unregistered [ 5587.856966] Key type ._llcrypt registered [ 5587.862956] Key type .llcrypt registered [ 5589.381295] Lustre: DEBUG MARKER: == conf-sanity test 136: don't panic with bad obdecho setup ========================================================== 20:46:18 (1789433178) [ 5607.579083] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5607.605358] alg: No test for adler32 (adler32-zlib) [ 5608.601447] Lustre: Lustre: Build Version: 2.17.58_41_g9380d02 [ 5608.800710] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [ 5610.432203] Key type lgssc registered [ 5611.631187] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5702.405818] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5709.388131] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5732.247458] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5734.952769] Lustre: Mounted lustre-client - version 2.17.58_41_g9380d02 [ 5749.080372] Lustre: Unmounted lustre-client [ 5871.366568] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5877.025180] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5900.482976] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5934.984358] Lustre: DEBUG MARKER: == conf-sanity test 137: a new MDT should inherit pools, parameters and failnode ========================================================== 20:52:04 (1789433524) [ 5936.595246] Lustre: DEBUG MARKER: SKIP: conf-sanity test_137 needs >= 3 MDTs [ 5938.023390] Lustre: DEBUG MARKER: == conf-sanity test 140: remove_updatelog script actions ========================================================== 20:52:07 (1789433527) [ 5968.364709] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5973.322351] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5993.701857] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5994.484707] Lustre: Mounted lustre-client - version 2.17.58_41_g9380d02 [ 5999.595239] Lustre: lustre-MDT0000-mdc-ffff9b4d0670a800: Connection to lustre-MDT0000 (at 192.168.202.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6009.832584] Lustre: lustre-MDT0001-mdc-ffff9b4d0670a800: Connection to lustre-MDT0001 (at 192.168.202.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6021.088111] Lustre: 104128:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789433595/real 1789433595] req@00000000cee4cbb1 x1876356706150784/t0(0) o400->MGC192.168.202.112@tcp@192.168.202.112@tcp:26/25 lens 224/224 e 0 to 1 dl 1789433611 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 6021.118922] LustreError: MGC192.168.202.112@tcp: Connection to MGS (at 192.168.202.112@tcp) was lost; in progress operations using this service will fail [ 6156.298230] LustreError: lustre-MDT0000-mdc-ffff9b4d0670a800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 6156.356787] Lustre: lustre-MDT0000-mdc-ffff9b4d0670a800: Connection restored to 192.168.202.112@tcp (at 192.168.202.112@tcp) [ 6161.381891] LustreError: lustre-MDT0001-mdc-ffff9b4d0670a800: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 6161.409130] Lustre: lustre-MDT0001-mdc-ffff9b4d0670a800: Connection restored to 192.168.202.112@tcp (at 192.168.202.112@tcp) [ 6164.390857] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6165.507996] Lustre: Evicted from MGS (at 192.168.202.112@tcp) after server handle changed from 0xbf6668739b16f331 to 0xbf6668739b170b79 [ 6165.544445] Lustre: MGC192.168.202.112@tcp: Connection restored to 192.168.202.112@tcp (at 192.168.202.112@tcp) [ 6166.074204] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 6171.072985] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6172.549986] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 6184.950624] Lustre: Unmounted lustre-client [ 6311.823525] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6317.390373] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6339.563627] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6386.586922] Lustre: DEBUG MARKER: == conf-sanity test 150: test setting max_cached_mb to a % ========================================================== 20:59:36 (1789433976) [ 6421.385826] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6428.094839] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6454.708471] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6455.365904] Lustre: Mounted lustre-client - version 2.17.58_41_g9380d02 [ 6458.405681] LustreError: 120849: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 [ 6468.223603] Lustre: DEBUG MARKER: == conf-sanity test 151a: damaged local config doesn't prevent mounting ========================================================== 21:00:57 (1789434057) [ 6469.015762] Lustre: Unmounted lustre-client [ 6501.187836] Key type lgssc unregistered [ 6501.611180] LNet: 121868:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6501.623633] LNetError: 121868:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6501.649336] LNet: Removed LNI 192.168.202.12@tcp [ 6502.637697] Key type .llcrypt unregistered [ 6502.641759] Key type ._llcrypt unregistered [ 6519.340523] Key type ._llcrypt registered [ 6519.345893] Key type .llcrypt registered [ 6520.039932] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6520.077753] alg: No test for adler32 (adler32-zlib) [ 6521.184972] Lustre: Lustre: Build Version: 2.17.58_41_g9380d02 [ 6521.554848] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [ 6523.280367] Key type lgssc registered [ 6524.888811] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6612.133268] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6618.228521] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6644.856678] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6766.889230] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6773.412086] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6799.005104] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6849.449742] Lustre: DEBUG MARKER: == conf-sanity test 151b: -ENOSPC doesn't affect mount === 21:07:17 (1789434437) [ 6863.240960] Key type lgssc unregistered [ 6863.448186] LNet: 129088:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6863.453857] LNetError: 129088:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6863.466609] LNet: Removed LNI 192.168.202.12@tcp [ 6864.070946] Key type .llcrypt unregistered [ 6864.073492] Key type ._llcrypt unregistered [ 6875.998524] Key type ._llcrypt registered [ 6876.006894] Key type .llcrypt registered [ 6876.458264] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6876.472349] alg: No test for adler32 (adler32-zlib) [ 6877.600251] Lustre: Lustre: Build Version: 2.17.58_41_g9380d02 [ 6877.893719] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [ 6879.632316] Key type lgssc registered [ 6880.880065] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6932.785214] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6938.639843] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6961.530200] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7078.559439] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7084.542613] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7110.879997] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7156.113372] Lustre: DEBUG MARKER: == conf-sanity test 152: seq allocation error in OSP ===== 21:12:25 (1789434745) [ 7251.960348] Lustre: Mounted lustre-client - version 2.17.58_41_g9380d02 [ 7257.154935] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 7269.146837] Lustre: DEBUG MARKER: ADD OST3 [ 7277.536551] Lustre: lustre-OST0000-osc-ffff9b4d060b3000: disconnect after 23s idle [ 7282.169614] Lustre: DEBUG MARKER: STOP OST3 [ 7295.484231] Lustre: lustre-OST0003-osc-ffff9b4d060b3000: Connection to lustre-OST0003 (at 192.168.202.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7305.005853] Lustre: DEBUG MARKER: START OST3 again [ 7315.123625] Lustre: lustre-OST0003-osc-ffff9b4d060b3000: Connection restored to 192.168.202.112@tcp (at 192.168.202.112@tcp) [ 7346.630889] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7346.645688] LustreError: 139911:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff9b4d060b3000: inode [0x240000bd0:0x1:0x0] mdc close failed: rc = -108 [ 7357.051756] Lustre: Unmounted lustre-client [ 7481.312079] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7488.679625] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7526.919939] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7580.580391] Lustre: DEBUG MARKER: == conf-sanity test 153a: bypass invalid NIDs quickly ==== 21:19:30 (1789435170) [ 7692.542880] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7698.294209] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7724.929455] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7805.715303] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7811.683879] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7835.569604] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7837.482094] LNetError: 129495:0:(lib-move.c:2151:lnet_handle_find_routed_path()) peer 192.168.252.112@tcp2 has no available nets [ 7837.842820] Lustre: Mounted lustre-client - version 2.17.58_41_g9380d02 [ 7843.026461] Lustre: Unmounted lustre-client [ 7869.515921] Key type lgssc unregistered [ 7889.761420] LNet: 1 local NIs in recovery (showing 1): 192.168.202.12@tcp [ 7896.481464] LNet: 148902:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7896.499647] LNetError: 148902:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7968.232236] LNet: Removed LNI 192.168.202.12@tcp [ 7968.828154] Key type .llcrypt unregistered [ 7968.832210] Key type ._llcrypt unregistered [ 7985.718578] Key type ._llcrypt registered [ 7985.722728] Key type .llcrypt registered [ 7987.115198] Lustre: DEBUG MARKER: == conf-sanity test 153b: added IPv6 NID support ========= 21:26:16 (1789435576) [ 8005.117143] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8005.214914] alg: No test for adler32 (adler32-zlib) [ 8006.464947] Lustre: Lustre: Build Version: 2.17.58_41_g9380d02 [ 8006.771071] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [ 8008.480238] Key type lgssc registered [ 8009.796643] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8099.301240] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8104.844651] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8127.903923] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8273.063213] Lustre: Mounted lustre-client - version 2.17.58_41_g9380d02 [ 8278.665475] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 8286.288497] Lustre: Unmounted lustre-client [ 8286.412499] LNetError: 150081:0:(lib-socket.c:308:lnet_sock_create()) Error trying to bind to fe80::542b:3ff:fe06:27f6/1023: rc = -22 [ 8286.424782] LNetError: Unexpected error -22 connecting to 6699:7654::1234:1234:d84@tcp at host [6699:7654::1234:1234:d84]:988 [ 8286.609740] Lustre: Mounted lustre-client - version 2.17.58_41_g9380d02 [ 8287.245471] Lustre: Unmounted lustre-client [ 8287.253059] Lustre: Skipped 1 previous similar message [ 8287.367396] LNetError: 150079:0:(lib-socket.c:308:lnet_sock_create()) Error trying to bind to fe80::542b:3ff:fe06:27f6/1023: rc = -22 [ 8287.391942] LNetError: Unexpected error -22 connecting to 6699:7654::1234:1234:d84@tcp at host [6699:7654::1234:1234:d84]:988 [ 8287.641931] Lustre: Mounted lustre-client - version 2.17.58_41_g9380d02 [ 8287.645403] Lustre: Skipped 1 previous similar message [ 8288.531910] Lustre: Unmounted lustre-client [ 8288.539164] Lustre: Skipped 1 previous similar message [ 8288.996687] LustreError: 150090:0:(lmv_obd.c:211:lmv_notify()) activation of lustre-MDT0001_UUID failed: -22 [ 8289.858639] LNetError: 150079:0:(lib-socket.c:308:lnet_sock_create()) Error trying to bind to fe80::542b:3ff:fe06:27f6/1023: rc = -22 [ 8289.864848] LNetError: Unexpected error -22 connecting to 6699:7654::1234:1234:d84@tcp at host [6699:7654::1234:1234:d84]:988 [ 8290.221896] Lustre: Mounted lustre-client - version 2.17.58_41_g9380d02 [ 8290.225261] Lustre: Skipped 3 previous similar messages [ 8290.900792] Lustre: Unmounted lustre-client [ 8290.904708] Lustre: Skipped 3 previous similar messages [ 8291.023935] Lustre: 156008: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 [ 8291.605985] Lustre: 156037: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 [ 8291.847904] LustreError: 150090:0:(lmv_obd.c:211:lmv_notify()) activation of lustre-MDT0001_UUID failed: -22 [ 8299.063901] Lustre: DEBUG MARKER: == conf-sanity test 153c: don't stuck on unreached NID === 21:31:28 (1789435888) [ 8344.740727] LNet: 1 local NIs in recovery (showing 1): 192.168.202.12@tcp [ 8424.900468] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8432.419952] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8459.372392] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8541.912263] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8548.036896] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8569.551875] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8575.265064] LustreError: 161633:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.252.112@tcp: failed processing log, type 1: rc = -110 [ 8620.192242] LNet: 1 local NIs in recovery (showing 1): 192.168.202.12@tcp [ 8682.656199] LNet: 1 local NIs in recovery (showing 1): 192.168.202.12@tcp [ 8712.165725] 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 [ 8712.190814] Lustre: Unmounted lustre-client [ 8712.198260] Lustre: Skipped 2 previous similar messages [ 8712.239291] LustreError: 161633:0:(super25.c:179:lustre_fill_super()) llite: Unable to mount : rc = -110 [ 8740.246657] Key type lgssc unregistered [ 8751.264245] LNet: 1 local NIs in recovery (showing 1): 192.168.202.12@tcp [ 8762.016619] LNet: 162091:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8762.023956] LNetError: 162091:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8832.783563] LNet: Removed LNI 192.168.202.12@tcp [ 8833.752262] Key type .llcrypt unregistered [ 8833.755885] Key type ._llcrypt unregistered [ 8851.593320] Key type ._llcrypt registered [ 8851.596155] Key type .llcrypt registered [ 8853.120832] Lustre: DEBUG MARKER: == conf-sanity test 154: expand .. on rename after MDT backup restore ========================================================== 21:40:42 (1789436442) [ 8871.321258] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8871.345735] alg: No test for adler32 (adler32-zlib) [ 8872.485301] Lustre: Lustre: Build Version: 2.17.58_41_g9380d02 [ 8872.727798] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [ 8874.345075] Key type lgssc registered [ 8875.606481] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9019.191582] Lustre: Mounted lustre-client - version 2.17.58_41_g9380d02 [ 9023.983424] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 9043.692323] Lustre: Unmounted lustre-client [ 9226.550980] Lustre: Mounted lustre-client - version 2.17.58_41_g9380d02 [ 9231.709606] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 9238.734904] Lustre: Unmounted lustre-client [ 9287.627206] Lustre: DEBUG MARKER: == conf-sanity test 155: gap in seq allocation from ofd after restarting ========================================================== 21:47:56 (1789436876) [ 9390.679499] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9397.309940] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9425.509821] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9569.412616] Lustre: Mounted lustre-client - version 2.17.58_41_g9380d02 [ 9575.789916] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 9594.848927] Lustre: lustre-OST0001-osc-ffff9b4d02d76000: disconnect after 23s idle [ 9595.210279] Lustre: Unmounted lustre-client [ 9724.012290] Lustre: Mounted lustre-client - version 2.17.58_41_g9380d02 [ 9729.496710] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 9749.486582] Lustre: lustre-OST0001-osc-ffff9b4d02d77800: disconnect after 23s idle [ 9754.537105] Lustre: DEBUG MARKER: == conf-sanity test 156: root_fid on export consistent with client mount ========================================================== 21:55:43 (1789437343) [ 9756.945877] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 9756.959648] LustreError: 180526:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff9b4d02d77800: inode [0x200000bd1:0x1:0x0] mdc close failed: rc = -108 [ 9767.334746] Lustre: Unmounted lustre-client [ 9964.472611] Lustre: Mounted lustre-client - version 2.17.58_41_g9380d02 [ 9970.659863] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 9980.757607] Lustre: Unmounted lustre-client [ 9981.520654] Lustre: Mounted lustre-client - version 2.17.58_41_g9380d02 [ 9992.094578] Lustre: Unmounted lustre-client [ 9994.069618] Lustre: DEBUG MARKER: == conf-sanity test 157a: test allow_register for MDT registration and MGS restart ========================================================== 21:59:43 (1789437583) [10073.612734] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10081.403467] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10105.128703] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10116.164328] Lustre: Mounted lustre-client - version 2.17.58_41_g9380d02 [10213.366144] LustreError: lustre-OST0001-osc-ffff9b4d076bd000: operation ost_connect to node 192.168.202.112@tcp failed: rc = -19 [10213.367688] Lustre: lustre-MDT0000-mdc-ffff9b4d076bd000: Connection to lustre-MDT0000 (at 192.168.202.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [10228.718163] LustreError: MGC192.168.202.112@tcp: Connection to MGS (at 192.168.202.112@tcp) was lost; in progress operations using this service will fail [10228.731428] LustreError: lustre-MDT0000-mdc-ffff9b4d076bd000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [10228.771678] Lustre: lustre-MDT0000-mdc-ffff9b4d076bd000: Connection restored to 192.168.202.112@tcp (at 192.168.202.112@tcp) [10228.779839] Lustre: Evicted from MGS (at 192.168.202.112@tcp) after server handle changed from 0xb209fd18938bdf18 to 0xb209fd18938be1e2 [10278.630785] Lustre: Unmounted lustre-client [10307.583649] Key type lgssc unregistered [10307.802783] LNet: 189779:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10307.811585] LNetError: 189779:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10307.827530] LNet: Removed LNI 192.168.202.12@tcp [10308.554175] Key type .llcrypt unregistered [10308.557361] Key type ._llcrypt unregistered [10320.862905] Key type ._llcrypt registered [10320.864790] Key type .llcrypt registered [10322.285956] Lustre: DEBUG MARKER: == conf-sanity test 157b: verify allow_register (block new OSTs, allow existing) ========================================================== 22:05:11 (1789437911) [10324.748646] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10324.802607] alg: No test for adler32 (adler32-zlib) [10325.890221] Lustre: Lustre: Build Version: 2.17.58_41_g9380d02 [10326.122079] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [10327.785710] Key type lgssc registered [10329.738887] Lustre: Echo OBD driver; http://www.lustre.org/ [10380.722529] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10387.167996] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10412.471803] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10415.303685] Lustre: Mounted lustre-client - version 2.17.58_41_g9380d02 [10512.879033] LustreError: lustre-OST0001-osc-ffff9b4d04142800: operation ost_connect to node 192.168.202.112@tcp failed: rc = -19 [10609.072693] Lustre: Unmounted lustre-client [10636.623266] Key type lgssc unregistered [10636.921831] LNet: 195801:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10636.942579] LNetError: 195801:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10637.993812] LNet: Removed LNI 192.168.202.12@tcp [10638.855190] Key type .llcrypt unregistered [10638.858406] Key type ._llcrypt unregistered [10650.498413] Key type ._llcrypt registered [10650.501520] Key type .llcrypt registered [10652.100681] Lustre: DEBUG MARKER: == conf-sanity test 160: MGC updates failnodes from all participants ========================================================== 22:10:41 (1789438241) [10684.680582] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10684.836904] alg: No test for adler32 (adler32-zlib) [10685.983685] Lustre: Lustre: Build Version: 2.17.58_41_g9380d02 [10686.396854] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [10688.136609] Key type lgssc registered [10690.077215] Lustre: Echo OBD driver; http://www.lustre.org/ [10776.156474] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10781.406338] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10807.547884] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10845.049567] Key type lgssc unregistered [10845.357059] LNet: 200777:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10845.377799] LNetError: 200777:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10845.411395] LNet: Removed LNI 192.168.202.12@tcp [10846.102704] Key type .llcrypt unregistered [10846.104872] Key type ._llcrypt unregistered [10964.918778] Key type ._llcrypt registered [10964.920503] Key type .llcrypt registered [10965.429922] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10965.442586] alg: No test for adler32 (adler32-zlib) [10966.515107] Lustre: Lustre: Build Version: 2.17.58_41_g9380d02 [10966.793514] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [10968.512450] Key type lgssc registered [10969.711120] Lustre: Echo OBD driver; http://www.lustre.org/ [11025.429977] Lustre: DEBUG MARKER: == conf-sanity test 161: test '-o mgsname' option ======== 22:16:54 (1789438614) [11069.280850] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11075.363797] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11099.751872] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11102.667618] Lustre: Mounted lustre-client - version 2.17.58_41_g9380d02 [11105.020154] Lustre: Unmounted lustre-client [11105.372261] Lustre: Mounted lustre-client - version 2.17.58_41_g9380d02 [11105.594625] Lustre: Unmounted lustre-client [11113.583188] Lustre: Unmounted lustre-client [11143.095600] Key type lgssc unregistered [11143.529067] LNet: 205753:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11143.544327] LNetError: 205753:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11143.574429] LNet: Removed LNI 192.168.202.12@tcp [11144.521413] Key type .llcrypt unregistered [11144.524054] Key type ._llcrypt unregistered [11157.167355] Key type ._llcrypt registered [11157.169465] Key type .llcrypt registered [11158.670278] Lustre: DEBUG MARKER: == conf-sanity test 162: replace nids with -o noclient === 22:19:07 (1789438747) [11162.537688] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11162.662093] alg: No test for adler32 (adler32-zlib) [11163.905425] Lustre: Lustre: Build Version: 2.17.58_41_g9380d02 [11164.154888] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [11165.848301] Key type lgssc registered [11167.421575] Lustre: Echo OBD driver; http://www.lustre.org/ [11224.705714] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11230.208091] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11252.629645] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11255.605436] Lustre: Mounted lustre-client - version 2.17.58_41_g9380d02 [11268.278530] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11270.241282] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [11276.269741] Lustre: lustre-MDT0000-mdc-ffff9b4d18991000: Connection to lustre-MDT0000 (at 192.168.202.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [11286.500400] Lustre: lustre-MDT0001-mdc-ffff9b4d18991000: Connection to lustre-MDT0001 (at 192.168.202.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [11297.506852] Lustre: 206439:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789438872/real 1789438872] req@0000000039286b35 x1876362530920320/t0(0) o400->MGC192.168.202.112@tcp@192.168.202.112@tcp:26/25 lens 224/224 e 0 to 1 dl 1789438888 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [11297.544345] LustreError: MGC192.168.202.112@tcp: Connection to MGS (at 192.168.202.112@tcp) was lost; in progress operations using this service will fail [11331.321658] Lustre: Evicted from MGS (at 192.168.202.112@tcp) after server handle changed from 0x35d17048712049f5 to 0x35d1704871204b99 [11331.341429] Lustre: MGC192.168.202.112@tcp: Connection restored to 192.168.202.112@tcp (at 192.168.202.112@tcp) [11364.275782] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11366.035567] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in IDLE state after 0 sec [11368.431356] LustreError: lustre-MDT0000-mdc-ffff9b4d18991000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [11368.464706] Lustre: lustre-MDT0000-mdc-ffff9b4d18991000: Connection restored to 192.168.202.112@tcp (at 192.168.202.112@tcp) [11371.617865] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11373.056366] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [11375.481774] Lustre: Unmounted lustre-client [11402.511617] Key type lgssc unregistered [11402.737850] LNet: 211302:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11402.744646] LNetError: 211302:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11403.815637] LNet: Removed LNI 192.168.202.12@tcp [11404.608486] Key type .llcrypt unregistered [11404.609766] Key type ._llcrypt unregistered [11432.048976] Key type ._llcrypt registered [11432.055385] Key type .llcrypt registered [11432.676425] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11432.688214] alg: No test for adler32 (adler32-zlib) [11433.806807] Lustre: Lustre: Build Version: 2.17.58_41_g9380d02 [11434.012751] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [11435.704230] Key type lgssc registered [11437.167628] Lustre: Echo OBD driver; http://www.lustre.org/ [11518.959735] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11524.719789] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11553.947532] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11604.574258] Lustre: DEBUG MARKER: == conf-sanity test 164: test expected_clients parameter and max client tracking ========================================================== 22:26:33 (1789439193) [11711.973764] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11718.450712] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11741.698323] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11743.185823] Lustre: Mounted lustre-client - version 2.17.58_41_g9380d02 [11747.586347] Lustre: Mounted lustre-client - version 2.17.58_41_g9380d02 [11748.915651] Lustre: Mounted lustre-client - version 2.17.58_41_g9380d02 [11748.919635] Lustre: Skipped 1 previous similar message [11754.228769] Lustre: Unmounted lustre-client [11756.759757] Lustre: Unmounted lustre-client [11759.261336] Lustre: Unmounted lustre-client [11761.276102] Lustre: Unmounted lustre-client [11774.244271] Lustre: Unmounted lustre-client [11774.252253] Lustre: Skipped 1 previous similar message [11800.596503] Key type lgssc unregistered [11800.863252] LNet: 220467:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11800.872593] LNetError: 220467:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11800.884570] LNet: Removed LNI 192.168.202.12@tcp [11801.514269] Key type .llcrypt unregistered [11801.516320] Key type ._llcrypt unregistered [11812.121476] Key type ._llcrypt registered [11812.123030] Key type .llcrypt registered [11813.574855] Lustre: DEBUG MARKER: == conf-sanity test 200a: check CPU partitions =========== 22:30:02 (1789439402) [11827.170291] Key type .llcrypt unregistered [11827.172092] Key type ._llcrypt unregistered [11827.963070] Key type ._llcrypt registered [11827.968766] Key type .llcrypt registered [11828.531414] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 4 [11828.559796] alg: No test for adler32 (adler32-zlib) [11829.678331] Lustre: Lustre: Build Version: 2.17.58_41_g9380d02 [11829.906418] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [11831.594238] Key type lgssc registered [11832.907523] Lustre: Echo OBD driver; http://www.lustre.org/ [11858.505120] Key type lgssc unregistered [11858.731871] LNet: 222615:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11858.738720] LNetError: 222615:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11858.760683] LNet: Removed LNI 192.168.202.12@tcp [11859.501300] Key type .llcrypt unregistered [11859.505192] Key type ._llcrypt unregistered [11859.744925] Key type ._llcrypt registered [11859.746857] Key type .llcrypt registered [11861.547216] Lustre: DEBUG MARKER: == conf-sanity test 200b: set CPU pattern using core selection ========================================================== 22:30:50 (1789439450) [11875.256418] Key type .llcrypt unregistered [11875.258583] Key type ._llcrypt unregistered [11876.014771] Key type ._llcrypt registered [11876.016777] Key type .llcrypt registered [11876.940755] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11876.977299] alg: No test for adler32 (adler32-zlib) [11878.057517] Lustre: Lustre: Build Version: 2.17.58_41_g9380d02 [11878.317953] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [11879.984278] Key type lgssc registered [11881.196370] Lustre: Echo OBD driver; http://www.lustre.org/ [11898.791690] Key type lgssc unregistered [11899.151695] LNet: 224158:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11899.161697] LNetError: 224158:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11899.180679] LNet: Removed LNI 192.168.202.12@tcp [11899.841645] Key type .llcrypt unregistered [11899.843664] Key type ._llcrypt unregistered [11908.915348] Key type ._llcrypt registered [11908.922626] Key type .llcrypt registered [11909.527587] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11909.539453] alg: No test for adler32 (adler32-zlib) [11910.753980] Lustre: Lustre: Build Version: 2.17.58_41_g9380d02 [11911.116298] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [11912.816326] Key type lgssc registered [11914.425729] Lustre: Echo OBD driver; http://www.lustre.org/ [11940.424519] Key type lgssc unregistered [11940.666208] LNet: 225918:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11940.674860] LNetError: 225918:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11940.693645] LNet: Removed LNI 192.168.202.12@tcp [11941.248339] Key type .llcrypt unregistered [11941.250605] Key type ._llcrypt unregistered [11941.562924] Key type ._llcrypt registered [11941.565145] Key type .llcrypt registered [11942.951036] Lustre: DEBUG MARKER: == conf-sanity test 200c: set CPU pattern using NUMA node layout ========================================================== 22:32:12 (1789439532) [11955.051317] Key type .llcrypt unregistered [11955.053115] Key type ._llcrypt unregistered [11956.188454] Key type ._llcrypt registered [11956.200024] Key type .llcrypt registered [11956.479333] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11956.495188] alg: No test for adler32 (adler32-zlib) [11957.601149] Lustre: Lustre: Build Version: 2.17.58_41_g9380d02 [11957.993480] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [11959.752249] Key type lgssc registered [11961.283159] Lustre: Echo OBD driver; http://www.lustre.org/ [11980.373366] Key type lgssc unregistered [11980.812667] LNet: 227456:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11980.822471] LNetError: 227456:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11980.842775] LNet: Removed LNI 192.168.202.12@tcp [11981.704550] Key type .llcrypt unregistered [11981.711789] Key type ._llcrypt unregistered [11991.719249] Key type ._llcrypt registered [11991.730039] Key type .llcrypt registered [11992.551324] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11992.578684] alg: No test for adler32 (adler32-zlib) [11993.892702] Lustre: Lustre: Build Version: 2.17.58_41_g9380d02 [11994.504113] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [11996.304400] Key type lgssc registered [11997.613916] Lustre: Echo OBD driver; http://www.lustre.org/ [12016.284298] Key type lgssc unregistered [12016.636634] LNet: 228760:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12016.646376] LNetError: 228760:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12016.673072] LNet: Removed LNI 192.168.202.12@tcp [12018.158903] Key type .llcrypt unregistered [12018.165799] Key type ._llcrypt unregistered [12029.339109] Key type ._llcrypt registered [12029.357017] Key type .llcrypt registered [12029.822894] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12029.856386] alg: No test for adler32 (adler32-zlib) [12031.031887] Lustre: Lustre: Build Version: 2.17.58_41_g9380d02 [12031.246624] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [12032.914874] Key type lgssc registered [12035.338523] Lustre: Echo OBD driver; http://www.lustre.org/ [12056.844520] Key type lgssc unregistered [12057.250671] LNet: 230062:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12057.265520] LNetError: 230062:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12057.284277] LNet: Removed LNI 192.168.202.12@tcp [12057.868655] Key type .llcrypt unregistered [12057.873599] Key type ._llcrypt unregistered [12066.210067] Key type ._llcrypt registered [12066.217212] Key type .llcrypt registered [12066.917311] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12066.939570] alg: No test for adler32 (adler32-zlib) [12068.250962] Lustre: Lustre: Build Version: 2.17.58_41_g9380d02 [12068.684292] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [12070.472267] Key type lgssc registered [12072.499270] Lustre: Echo OBD driver; http://www.lustre.org/ [12097.789926] Key type lgssc unregistered [12098.082757] LNet: 231825:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12098.094937] LNetError: 231825:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12098.117982] LNet: Removed LNI 192.168.202.12@tcp [12098.967513] Key type .llcrypt unregistered [12098.970374] Key type ._llcrypt unregistered [12099.268426] Key type ._llcrypt registered [12099.270588] Key type .llcrypt registered [12100.950939] Lustre: DEBUG MARKER: == conf-sanity test 200d: set CPU pattern to exclude only CPU 1 ========================================================== 22:34:49 (1789439689) [12114.522876] Key type .llcrypt unregistered [12114.526062] Key type ._llcrypt unregistered [12115.426866] Key type ._llcrypt registered [12115.433916] Key type .llcrypt registered [12115.995938] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12116.008598] alg: No test for adler32 (adler32-zlib) [12117.212427] Lustre: Lustre: Build Version: 2.17.58_41_g9380d02 [12117.542923] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [12119.224232] Key type lgssc registered [12120.785116] Lustre: Echo OBD driver; http://www.lustre.org/ [12138.329557] Key type lgssc unregistered [12138.576968] LNet: 233366:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12138.586644] LNetError: 233366:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12138.601480] LNet: Removed LNI 192.168.202.12@tcp [12139.460649] Key type .llcrypt unregistered [12139.463674] Key type ._llcrypt unregistered [12148.003426] Key type ._llcrypt registered [12148.012059] Key type .llcrypt registered [12148.539670] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12148.547343] alg: No test for adler32 (adler32-zlib) [12149.747358] Lustre: Lustre: Build Version: 2.17.58_41_g9380d02 [12149.986465] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [12151.681908] Key type lgssc registered [12153.216558] Lustre: Echo OBD driver; http://www.lustre.org/ [12171.323351] Key type lgssc unregistered [12171.674669] LNet: 234675:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12171.683125] LNetError: 234675:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12171.702304] LNet: Removed LNI 192.168.202.12@tcp [12172.347717] Key type .llcrypt unregistered [12172.349891] Key type ._llcrypt unregistered [12180.196346] Key type ._llcrypt registered [12180.203062] Key type .llcrypt registered [12180.766818] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12180.783894] alg: No test for adler32 (adler32-zlib) [12181.981832] Lustre: Lustre: Build Version: 2.17.58_41_g9380d02 [12182.293059] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [12183.976266] Key type lgssc registered [12185.473741] Lustre: Echo OBD driver; http://www.lustre.org/ [12201.992183] Key type lgssc unregistered [12202.510762] LNet: 235986:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12202.519492] LNetError: 235986:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12202.536376] LNet: Removed LNI 192.168.202.12@tcp [12203.477600] Key type .llcrypt unregistered [12203.479482] Key type ._llcrypt unregistered [12211.291550] Key type ._llcrypt registered [12211.293132] Key type .llcrypt registered [12212.068672] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12212.078421] alg: No test for adler32 (adler32-zlib) [12213.297959] Lustre: Lustre: Build Version: 2.17.58_41_g9380d02 [12213.643571] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [12215.376823] Key type lgssc registered [12216.569404] Lustre: Echo OBD driver; http://www.lustre.org/ [12231.983874] Key type lgssc unregistered [12232.195746] LNet: 237290:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12232.201721] LNetError: 237290:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12232.218318] LNet: Removed LNI 192.168.202.12@tcp [12232.923350] Key type .llcrypt unregistered [12232.928528] Key type ._llcrypt unregistered [12240.148407] Key type ._llcrypt registered [12240.150691] Key type .llcrypt registered [12240.515961] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12240.539696] alg: No test for adler32 (adler32-zlib) [12241.671989] Lustre: Lustre: Build Version: 2.17.58_41_g9380d02 [12241.925069] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [12243.608461] Key type lgssc registered [12244.874580] Lustre: Echo OBD driver; http://www.lustre.org/ [12261.258472] Key type lgssc unregistered [12261.573883] LNet: 238595:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12261.587131] LNetError: 238595:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12261.603204] LNet: Removed LNI 192.168.202.12@tcp [12262.332191] Key type .llcrypt unregistered [12262.334493] Key type ._llcrypt unregistered [12268.974483] Key type ._llcrypt registered [12268.979438] Key type .llcrypt registered [12269.558743] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12269.571422] alg: No test for adler32 (adler32-zlib) [12270.913994] Lustre: Lustre: Build Version: 2.17.58_41_g9380d02 [12271.284721] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [12272.992305] Key type lgssc registered [12274.185354] Lustre: Echo OBD driver; http://www.lustre.org/ [12289.974802] Key type lgssc unregistered [12290.193903] LNet: 239896:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12290.208405] LNetError: 239896:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12290.243088] LNet: Removed LNI 192.168.202.12@tcp [12290.856975] Key type .llcrypt unregistered [12290.859177] Key type ._llcrypt unregistered [12314.196601] Key type ._llcrypt registered [12314.198203] Key type .llcrypt registered [12315.338867] Lustre: DEBUG MARKER: == conf-sanity test 200e: set CPU pattern using relative core exclusion ========================================================== 22:38:24 (1789439904) [12327.726374] Key type .llcrypt unregistered [12327.729660] Key type ._llcrypt unregistered [12328.517074] Key type ._llcrypt registered [12328.518861] Key type .llcrypt registered [12329.159573] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12329.173167] alg: No test for adler32 (adler32-zlib) [12330.269944] Lustre: Lustre: Build Version: 2.17.58_41_g9380d02 [12330.580918] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [12332.336392] Key type lgssc registered [12333.441616] Lustre: Echo OBD driver; http://www.lustre.org/ [12351.093283] Key type lgssc unregistered [12351.385231] LNet: 242204:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12351.391827] LNetError: 242204:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12351.410450] LNet: Removed LNI 192.168.202.12@tcp [12352.157631] Key type .llcrypt unregistered [12352.161275] Key type ._llcrypt unregistered [12360.388040] Key type ._llcrypt registered [12360.391814] Key type .llcrypt registered [12360.919238] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12360.938299] alg: No test for adler32 (adler32-zlib) [12362.085314] Lustre: Lustre: Build Version: 2.17.58_41_g9380d02 [12362.307229] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [12364.001113] Key type lgssc registered [12365.391912] Lustre: Echo OBD driver; http://www.lustre.org/ [12381.146561] Key type lgssc unregistered [12381.486523] LNet: 243520:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12381.501760] LNetError: 243520:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12381.531670] LNet: Removed LNI 192.168.202.12@tcp [12382.136396] Key type .llcrypt unregistered [12382.143570] Key type ._llcrypt unregistered [12390.807914] Key type ._llcrypt registered [12390.819173] Key type .llcrypt registered [12391.368142] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12391.378362] alg: No test for adler32 (adler32-zlib) [12392.444920] Lustre: Lustre: Build Version: 2.17.58_41_g9380d02 [12392.681685] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [12394.384252] Key type lgssc registered [12395.357917] Lustre: Echo OBD driver; http://www.lustre.org/ [12411.203138] Key type lgssc unregistered [12411.424566] LNet: 244824:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12411.434424] LNetError: 244824:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12411.446166] LNet: Removed LNI 192.168.202.12@tcp [12412.249771] Key type .llcrypt unregistered [12412.252530] Key type ._llcrypt unregistered [12421.353084] Key type ._llcrypt registered [12421.355272] Key type .llcrypt registered [12422.143952] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12422.165019] alg: No test for adler32 (adler32-zlib) [12423.521429] Lustre: Lustre: Build Version: 2.17.58_41_g9380d02 [12423.977178] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [12425.824263] Key type lgssc registered [12427.266738] Lustre: Echo OBD driver; http://www.lustre.org/ [12457.314843] Key type lgssc unregistered [12457.518276] LNet: 246614:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12457.530863] LNetError: 246614:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12457.545986] LNet: Removed LNI 192.168.202.12@tcp [12458.281928] Key type .llcrypt unregistered [12458.284977] Key type ._llcrypt unregistered [12458.891867] Key type ._llcrypt registered [12458.896446] Key type .llcrypt registered [12460.668379] Lustre: DEBUG MARKER: == conf-sanity test 250: verify lcfg_erase removes filesystem parameters ========================================================== 22:40:49 (1789440049) [12482.005361] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12482.020978] alg: No test for adler32 (adler32-zlib) [12483.193991] Lustre: Lustre: Build Version: 2.17.58_41_g9380d02 [12483.561217] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [12485.256382] Key type lgssc registered [12486.364203] Lustre: Echo OBD driver; http://www.lustre.org/ [12707.297620] Lustre: DEBUG MARKER: == conf-sanity test 802a: simulate readonly device ======= 22:44:56 (1789440296) [12708.791391] Lustre: DEBUG MARKER: SKIP: conf-sanity test_802a ZFS specific test [12772.765803] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 12657 sec ========= 22:46:01 (1789440361) [12774.340196] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 22:46:03 (1789440363) === [12777.674426] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 22:46:07 (1789440367) === [12813.602292] Key type lgssc unregistered [12813.836831] LNet: 255661:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12813.847300] LNetError: 255661:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12813.887209] LNet: Removed LNI 192.168.202.12@tcp [12814.451779] Key type .llcrypt unregistered [12814.454215] Key type ._llcrypt unregistered