[ 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 780954619 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2464 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE2300 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 0022C0 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2374 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE2404 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE243C 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe2300-0xbffe2373] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22ff] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2374-0xbffe2403] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe2404-0xbffe243b] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe243c-0xbffe2463] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524584K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001009] APIC: Switch to symmetric I/O mode setup [ 0.002304] x2apic enabled [ 0.003004] Switched APIC routing to physical x2apic. [ 0.004007] kvm-guest: setup PV IPIs [ 0.007316] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.008000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.008014] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009007] pid_max: default: 32768 minimum: 301 [ 0.010106] LSM: Security Framework initializing [ 0.011031] Yama: becoming mindful. [ 0.011859] SELinux: Initializing. [ 0.012043] *** VALIDATE selinux *** [ 0.020074] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.024267] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.025108] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.026077] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.027075] *** VALIDATE tmpfs *** [ 0.028382] *** VALIDATE proc *** [ 0.029174] *** VALIDATE cgroup *** [ 0.030005] *** VALIDATE cgroup2 *** [ 0.031204] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.032126] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.033004] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.034020] Spectre V2 : User space: Vulnerable [ 0.035004] Speculative Store Bypass: Vulnerable [ 0.037930] debug: unmapping init [mem 0xffffffff88459000-0xffffffff88460fff] [ 0.040000] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.040620] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.041012] ... version: 2 [ 0.041961] ... bit width: 48 [ 0.042007] ... generic registers: 4 [ 0.042975] ... value mask: 0000ffffffffffff [ 0.043008] ... max period: 00007fffffffffff [ 0.044007] ... fixed-purpose events: 3 [ 0.044964] ... event mask: 000000070000000f [ 0.046172] rcu: Hierarchical SRCU implementation. [ 0.048311] smp: Bringing up secondary CPUs ... [ 0.049478] x86: Booting SMP configuration: [ 0.050018] .... node #0, CPUs: #1 #2 #3 [ 0.062117] smp: Brought up 1 node, 4 CPUs [ 0.064013] smpboot: Max logical packages: 1 [ 0.065007] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.119031] node 0 deferred pages initialised in 53ms [ 0.122145] devtmpfs: initialized [ 0.123164] x86/mm: Memory block size: 128MB [ 0.125911] gcov: version magic: 0x41383552 [ 0.126537] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.128118] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.130192] pinctrl core: initialized pinctrl subsystem [ 0.131143] [ 0.131585] ************************************************************* [ 0.132007] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.133007] ** ** [ 0.134009] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.135007] ** ** [ 0.136008] ** This means that this kernel is built to expose internal ** [ 0.137009] ** IOMMU data structures, which may compromise security on ** [ 0.138007] ** your system. ** [ 0.139007] ** ** [ 0.140007] ** If you see this message and you are not debugging the ** [ 0.141008] ** kernel, report this immediately to your vendor! ** [ 0.142006] ** ** [ 0.143040] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.144007] ************************************************************* [ 0.145638] NET: Registered protocol family 16 [ 0.146384] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.147037] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.148034] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.149589] cpuidle: using governor menu [ 0.151440] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.152515] PCI: Using configuration type 1 for base access [ 0.153107] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.159295] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.160000] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.160224] cryptd: max_cpu_qlen set to 1000 [ 0.163000] ACPI: Added _OSI(Module Device) [ 0.163000] ACPI: Added _OSI(Processor Device) [ 0.163000] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.164007] ACPI: Added _OSI(Processor Aggregator Device) [ 0.168338] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.174010] ACPI: Interpreter enabled [ 0.175041] ACPI: PM: (supports S0 S3 S4 S5) [ 0.177006] ACPI: Using IOAPIC for interrupt routing [ 0.178137] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.181327] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.191716] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.193021] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.196010] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.198114] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.203374] acpiphp: Slot [2] registered [ 0.204103] acpiphp: Slot [5] registered [ 0.205078] acpiphp: Slot [6] registered [ 0.206088] acpiphp: Slot [3] registered [ 0.207050] acpiphp: Slot [4] registered [ 0.208069] acpiphp: Slot [7] registered [ 0.209099] acpiphp: Slot [8] registered [ 0.211136] acpiphp: Slot [9] registered [ 0.212052] acpiphp: Slot [10] registered [ 0.214052] acpiphp: Slot [11] registered [ 0.215049] acpiphp: Slot [12] registered [ 0.216071] acpiphp: Slot [13] registered [ 0.217103] acpiphp: Slot [14] registered [ 0.218089] acpiphp: Slot [15] registered [ 0.219115] acpiphp: Slot [16] registered [ 0.221052] acpiphp: Slot [17] registered [ 0.222053] acpiphp: Slot [18] registered [ 0.223057] acpiphp: Slot [19] registered [ 0.224066] acpiphp: Slot [20] registered [ 0.225056] acpiphp: Slot [21] registered [ 0.227053] acpiphp: Slot [22] registered [ 0.228049] acpiphp: Slot [23] registered [ 0.229097] acpiphp: Slot [24] registered [ 0.230064] acpiphp: Slot [25] registered [ 0.231062] acpiphp: Slot [26] registered [ 0.232105] acpiphp: Slot [27] registered [ 0.233108] acpiphp: Slot [28] registered [ 0.235066] acpiphp: Slot [29] registered [ 0.236060] acpiphp: Slot [30] registered [ 0.237102] acpiphp: Slot [31] registered [ 0.238041] PCI host bridge to bus 0000:00 [ 0.240012] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.241010] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.243012] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.246010] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.248032] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.250015] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.252168] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.253984] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.257076] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.264487] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.268008] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.271023] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.273021] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.275014] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.278151] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.280624] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.281022] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.283711] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.287799] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.297014] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.300010] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.305306] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.345014] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.354016] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.388033] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.412700] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.437014] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.455015] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.525023] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.542801] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.545264] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.546257] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.548299] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.550138] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.554018] iommu: Default domain type: Passthrough [ 0.556347] SCSI subsystem initialized [ 0.557079] ACPI: bus type USB registered [ 0.558073] usbcore: registered new interface driver usbfs [ 0.560045] usbcore: registered new interface driver hub [ 0.561041] usbcore: registered new device driver usb [ 0.563123] pps_core: LinuxPPS API ver. 1 registered [ 0.564005] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.567038] PTP clock support registered [ 0.570050] EDAC MC: Ver: 3.0.0 [ 0.572121] PCI: Using ACPI for IRQ routing [ 0.573613] NetLabel: Initializing [ 0.575008] NetLabel: domain hash size = 128 [ 0.576005] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.577072] NetLabel: unlabeled traffic allowed by default [ 0.579180] vgaarb: loaded [ 0.581205] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.582009] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.588000] clocksource: Switched to clocksource kvm-clock [ 0.688898] VFS: Disk quotas dquot_6.6.0 [ 0.690063] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.691992] *** VALIDATE ramfs *** [ 0.692969] *** VALIDATE hugetlbfs *** [ 0.694114] pnp: PnP ACPI init [ 0.696113] pnp: PnP ACPI: found 6 devices [ 0.739821] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.742396] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.743915] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.745455] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.747089] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.748709] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.750563] NET: Registered protocol family 2 [ 0.752404] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.756105] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.758731] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.762371] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.765398] TCP: Hash tables configured (established 65536 bind 65536) [ 0.767395] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.769528] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.771509] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.773571] NET: Registered protocol family 1 [ 0.775870] RPC: Registered named UNIX socket transport module. [ 0.777573] RPC: Registered udp transport module. [ 0.778755] RPC: Registered tcp transport module. [ 0.780068] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.781851] NET: Registered protocol family 44 [ 0.783228] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.784614] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.786065] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.787813] PCI: CLS 0 bytes, default 64 [ 0.788967] Unpacking initramfs... [ 3.653627] debug: unmapping init [mem 0xffff9cd1fcc64000-0xffff9cd1fffcffff] [ 3.673889] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 3.678525] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 3.692789] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 7.299799] Initialise system trusted keyrings [ 7.306794] Key type blacklist registered [ 7.312459] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 7.355706] zbud: loaded [ 7.372396] *** VALIDATE nfs *** [ 7.373563] *** VALIDATE nfs4 *** [ 7.379810] pstore: using deflate compression [ 7.405862] Platform Keyring initialized [ 8.221088] NET: Registered protocol family 38 [ 8.232482] Key type asymmetric registered [ 8.240630] Asymmetric key parser 'x509' registered [ 8.242277] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 8.252366] io scheduler mq-deadline registered [ 8.257480] io scheduler kyber registered [ 8.268709] io scheduler bfq registered [ 8.279174] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 8.289945] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 8.297984] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 8.323822] ACPI: Power Button [PWRF] [ 8.338758] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 8.353884] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 8.393493] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 8.460948] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 8.544415] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 8.560910] Non-volatile memory driver v1.3 [ 8.566716] Linux agpgart interface v0.103 [ 8.719349] virtio_blk virtio1: [vda] 145904 512-byte logical blocks (74.7 MB/71.2 MiB) [ 8.733161] vda: detected capacity change from 0 to 74702848 [ 8.806529] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 8.826205] vdb: detected capacity change from 0 to 1073741824 [ 8.854044] libphy: Fixed MDIO Bus: probed [ 8.877587] usbcore: registered new interface driver usbserial_generic [ 8.881943] usbserial: USB Serial support registered for generic [ 8.891826] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 8.907387] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 8.912580] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 8.924747] mousedev: PS/2 mouse device common for all mice [ 8.936606] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 8.945435] rtc_cmos 00:05: RTC can wake from S4 [ 8.958270] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 8.970623] rtc_cmos 00:05: registered as rtc0 [ 8.977109] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 8.978773] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 8.989662] intel_pstate: CPU model not supported [ 8.998494] hid: raw HID events driver (C) Jiri Kosina [ 9.019802] usbcore: registered new interface driver usbhid [ 9.025177] usbhid: USB HID core driver [ 9.036620] drop_monitor: Initializing network drop monitor service [ 9.045449] Initializing XFRM netlink socket [ 9.052394] NET: Registered protocol family 10 [ 9.076871] Segment Routing with IPv6 [ 9.088725] NET: Registered protocol family 17 [ 9.094973] mpls_gso: MPLS GSO support [ 9.131426] RAS: Correctable Errors collector initialized. [ 9.146873] AVX version of gcm_enc/dec engaged. [ 9.151750] AES CTR mode by8 optimization enabled [ 9.469381] sched_clock: Marking stable (9469354205, 0)->(10274929315, -805575110) [ 9.479453] registered taskstats version 1 [ 9.493461] Loading compiled-in X.509 certificates [ 9.496847] zswap: loaded using pool lzo/zbud [ 9.613302] Key type big_key registered [ 9.696798] Key type encrypted registered [ 9.700634] ima: No TPM chip found, activating TPM-bypass! [ 9.709138] ima: Allocated hash algorithm: sha1 [ 9.713932] ima: No architecture policies found [ 9.722912] evm: Initialising EVM extended attributes: [ 9.732126] evm: security.selinux [ 9.737109] evm: security.ima [ 9.741108] evm: security.capability [ 9.745212] evm: HMAC attrs: 0x1 [ 9.755593] rtc_cmos 00:05: setting system clock to 2026-07-30 04:59:17 UTC (1785387557) [ 9.769145] debug: unmapping init [mem 0xffffffff89403000-0xffffffff895fffff] [ 9.805093] debug: unmapping init [mem 0xffffffff88182000-0xffffffff88458fff] [ 9.817098] Write protecting the kernel read-only data: 28672k [ 9.825635] debug: unmapping init [mem 0xffffffff86803000-0xffffffff869fffff] [ 9.835536] debug: unmapping init [mem 0xffffffff87114000-0xffffffff871fffff] [ 9.987631] 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) [ 10.009600] systemd[1]: Detected virtualization kvm. [ 10.024664] systemd[1]: Detected architecture x86-64. [ 10.026231] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 10.122419] systemd[1]: No hostname configured. [ 10.129043] systemd[1]: Set hostname to . [ 10.146683] random: systemd: uninitialized urandom read (16 bytes read) [ 10.153379] systemd[1]: Initializing machine ID from random generator. [ 10.353800] random: ln: uninitialized urandom read (6 bytes read) [ 10.659833] random: systemd: uninitialized urandom read (16 bytes read) [ 10.669704] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. [ 10.693827] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ 10.709176] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Local File Systems. Starting Journal Service... [ OK ] Listening on udev Kernel Socket. Starting Create Volatile Files and Directories... [ OK ] Started Dispatch Password Requests to Console Directory Watch. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Timers. [ OK ] Reached target Swap. Starting Setup Virtual Console... [ OK ] Started Memstrack Anylazing Service. [ OK ] Reached target Paths. Starting Apply Kernel Variables... [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Initrd Root Device. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Setup Virtual Console. [ OK ] Started Apply Kernel Variables. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 12.645164] device-mapper: uevent: version 1.0.3 [ 12.654516] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting dracut initqueue hook... [ 14.632907] virtio_net virtio0 ens2: renamed from eth0 [ 14.852846] scsi host0: ata_piix [ 14.907305] scsi host1: ata_piix [ 14.927331] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 14.942449] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 20.271935] random: crng init done [ 20.283595] random: 7 urandom warning(s) missed due to ratelimiting [ 22.891887] dracut-initqueue[580]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 25.509195] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped target Timers. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Sockets. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped target Swap. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Paths. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped target Slices. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 29.570557] printk: systemd: 26 output lines suppressed due to ratelimiting [ 30.748161] SELinux: Disabled at runtime. [ 31.036652] 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) [ 31.058277] systemd[1]: Detected virtualization kvm. [ 31.063015] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 33.864921] systemd[1]: initrd-switch-root.service: Succeeded. [ 33.870739] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 33.883390] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 33.893511] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 33.901658] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 33.953503] systemd[1]: Starting Journal Service... Starting Journal Service... [ 33.973368] systemd[1]: Stopped target Switch Root. [ OK ] Stopped target Switch Root. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Created slice system-serial\x2dgetty.slice. Mounting Kernel Debug File System... [ OK ] Started Forward Password Requests to Wall Directory Watch. Activating swap /dev/disk/by-label/SWAP... Starting Remount Root and Kernel File Systems... [ 34.335010] hrtimer: interrupt took 13953597 ns [ 34.386564] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Created slice system-sshd\x2dkeygen.slice. Mounting Huge Pages File System... [ OK ] Stopped target Initrd File Systems. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Listening on udev Control Socket. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on RPCbind Server Activation Socket. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Paths. [ OK ] Listening on Process Core Dump Socket. [ OK ] Reached target rpc_pipefs.target. [ OK ] Reached target RPC Port Mapper. [ OK ] Created slice system-getty.slice. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Stopped target Initrd Root File System. Starting Apply Kernel Variables... Mounting POSIX Message Queue File System... [ OK ] Listening on udev Kernel Socket. Starting udev Coldplug all Devices... [ OK ] Started Journal Service. [ OK ] Mounted Kernel Debug File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted Huge Pages File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted POSIX Message Queue 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 Create Static Device Nodes in /dev. [ OK ] Started Flush Journal to Persistent Storage. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... [ OK ] Mounted /mnt. [ 35.773499] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Started udev Coldplug all Devices. [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 37.665786] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 37.930658] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 38.454238] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 38.583118] EDAC sbridge: Ver: 1.1.2 [* ] A start job is running for Configur…-only root support (8s / no limit)[ 42.640540] Key type dns_resolver registered [** ] A start job is running for Configur…-only root support (9s / no limit) [*** ] A start job is running for Configur…-only root support (9s / no limit) [ *** ] A start job is running for Configur…only root support (10s / no limit)[ 43.901855] NFS: Registering the id_resolver key type [ 43.908137] Key type id_resolver registered [ 43.918733] Key type id_legacy registered [ *** ] A start job is running for Configur…only root support (10s / no limit) [ ***] A start job is running for Configur…only root support (11s / no limit) [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Mark the need to relabel after reboot... Starting Rebuild Dynamic Linker Cache... Starting Load/Save Random Seed... Starting Create Volatile Files and Directories... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Load/Save Random Seed. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started dnf makecache --timer. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Restore /run/initramfs on shutdown... Starting Login Service... [ OK ] Started irqbalance daemon. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Timers. [ OK ] Reached target sshd-keygen.target. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting Dynamic System Tuning Daemon... Starting OpenSSH server daemon... Starting GSSAPI Proxy Daemon... Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ OK ] Started Login Service. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Getty on tty1. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting System Logging Service... Starting Crash recovery kernel arming... Starting Notify NFS peers of a restart... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg120-client login: [ 130.500390] libcfs: loading out-of-tree module taints kernel. [ 130.896367] Key type ._llcrypt registered [ 130.902205] Key type .llcrypt registered [ 131.882944] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 131.907610] alg: No test for adler32 (adler32-zlib) [ 133.770318] Lustre: Lustre: Build Version: 2.17.56_2_g669939b [ 134.745489] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [ 136.616469] Key type lgssc registered [ 140.082993] Lustre: Echo OBD driver; http://www.lustre.org/ [ 348.222430] Lustre: Mounted lustre-client [ 354.225802] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 369.559299] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing check_logdir /tmp/testlogs/ [ 373.729359] Lustre: lustre-OST0000-osc-ffff9cd242cff000: disconnect after 23s idle [ 374.630440] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing yml_node [ 382.753577] Lustre: DEBUG MARKER: Client: 2.17.56.2 [ 386.026071] Lustre: DEBUG MARKER: MDS: 2.17.56.2 [ 388.917251] Lustre: DEBUG MARKER: OSS: 2.17.56.2 [ 390.636488] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Thu Jul 30 01:05:36 EDT 2026 [ 411.405462] Lustre: DEBUG MARKER: excepting tests: 32newtarball [ 413.518974] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 417.788178] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 428.216389] Lustre: Unmounted lustre-client [ 590.954765] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 599.031553] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 626.758774] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 676.248837] Lustre: DEBUG MARKER: == conf-sanity test 121: failover MGS ==================== 01:10:22 (1785388222) [ 729.714235] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 739.293501] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 824.799357] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mgc.*.mgs_server_uuid 1475 0 [ 900.097941] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 908.633795] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 960.261298] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mgc.*.mgs_server_uuid 1475 0 [ 988.744263] Lustre: DEBUG MARKER: == conf-sanity test 122a: Check OST sequence update ====== 01:15:34 (1785388534) [ 1141.524642] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1150.800321] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1183.543832] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1184.754362] Lustre: Mounted lustre-client [ 1221.751275] Lustre: Unmounted lustre-client [ 1258.613084] Key type lgssc unregistered [ 1259.024268] LNet: 21930:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1259.036518] LNetError: 21930:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1259.068540] LNet: Removed LNI 192.168.201.20@tcp [ 1260.349261] Key type .llcrypt unregistered [ 1260.351402] Key type ._llcrypt unregistered [ 1283.375474] Key type ._llcrypt registered [ 1283.378952] Key type .llcrypt registered [ 1285.500988] Lustre: DEBUG MARKER: == conf-sanity test 123aa: llog_print works with FIDs and simple names ========================================================== 01:20:30 (1785388830) [ 1289.506964] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1289.540540] alg: No test for adler32 (adler32-zlib) [ 1290.910480] Lustre: Lustre: Build Version: 2.17.56_2_g669939b [ 1291.408478] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [ 1293.183387] Key type lgssc registered [ 1294.881551] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1361.631805] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1373.158935] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1405.603402] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1409.006253] Lustre: Mounted lustre-client [ 1427.457902] Lustre: DEBUG MARKER: == conf-sanity test 123ab: llog_print params output values from set_param -P ========================================================== 01:22:52 (1785388972) [ 1445.514859] Lustre: DEBUG MARKER: == conf-sanity test 123ac: llog_print with --start and --end ========================================================== 01:23:10 (1785388990) [ 1458.582461] Lustre: DEBUG MARKER: == conf-sanity test 123ad: llog_print shows all records == 01:23:24 (1785389004) [ 1473.897496] Lustre: DEBUG MARKER: == conf-sanity test 123ae: llog_cancel can cancel requested record ========================================================== 01:23:39 (1785389019) [ 1519.862928] Lustre: DEBUG MARKER: == conf-sanity test 123af: llog_catlist can show all config files correctly ========================================================== 01:24:25 (1785389065) [ 1545.137532] Lustre: DEBUG MARKER: == conf-sanity test 123ag: llog_print skips values deleted by set_param -P -d ========================================================== 01:24:51 (1785389091) [ 1565.608160] Lustre: DEBUG MARKER: == conf-sanity test 123ah: del_ost cancels config log entries correctly ========================================================== 01:25:11 (1785389111) [ 1577.426852] Lustre: Unmounted lustre-client [ 1578.112310] Lustre: Mounted lustre-client [ 1582.029322] Lustre: Unmounted lustre-client [ 1625.328456] Key type lgssc unregistered [ 1625.715855] LNet: 30320:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1625.743279] LNetError: 30320:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1625.775680] LNet: Removed LNI 192.168.201.20@tcp [ 1626.850336] Key type .llcrypt unregistered [ 1626.852575] Key type ._llcrypt unregistered [ 1667.952942] Key type ._llcrypt registered [ 1667.954750] Key type .llcrypt registered [ 1668.775791] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1668.810955] alg: No test for adler32 (adler32-zlib) [ 1670.519580] Lustre: Lustre: Build Version: 2.17.56_2_g669939b [ 1671.257962] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [ 1673.135176] Key type lgssc registered [ 1675.162833] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1796.201328] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1806.387171] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1835.609551] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1885.089266] Lustre: DEBUG MARKER: == conf-sanity test 123ai: llog_print display all non skipped records ========================================================== 01:30:30 (1785389430) [ 1937.513261] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1945.117405] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1975.511438] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1977.514734] Lustre: Mounted lustre-client [ 2152.796940] Lustre: DEBUG MARKER: == conf-sanity test 123aj: check permanent TBF rules ===== 01:34:58 (1785389698) [ 2206.971322] Lustre: DEBUG MARKER: == conf-sanity test 123F: clear and reset all parameters using set_param -F ========================================================== 01:35:52 (1785389752) [ 2222.270811] Lustre: Unmounted lustre-client [ 2316.119178] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2322.721204] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2344.657777] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2345.190181] Lustre: Mounted lustre-client [ 2351.096381] Lustre: Unmounted lustre-client [ 2386.337824] Key type lgssc unregistered [ 2386.937283] LNet: 41677:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2386.945236] LNetError: 41677:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2386.995134] LNet: Removed LNI 192.168.201.20@tcp [ 2388.488572] Key type .llcrypt unregistered [ 2388.508286] Key type ._llcrypt unregistered [ 2411.767548] Key type ._llcrypt registered [ 2411.768934] Key type .llcrypt registered [ 2412.829395] Lustre: DEBUG MARKER: == conf-sanity test 123G: clear and reset all parameters using apply_yaml ========================================================== 01:39:19 (1785389959) [ 2416.200272] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2416.408176] alg: No test for adler32 (adler32-zlib) [ 2417.818796] Lustre: Lustre: Build Version: 2.17.56_2_g669939b [ 2418.294149] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [ 2420.150128] Key type lgssc registered [ 2422.600894] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2490.674406] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2497.924154] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2523.463989] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2526.410220] Lustre: Mounted lustre-client [ 2539.803340] Lustre: Unmounted lustre-client [ 2648.563909] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2656.581930] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2681.399974] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2682.095454] Lustre: Mounted lustre-client [ 2688.974755] Lustre: Unmounted lustre-client [ 2721.638308] Key type lgssc unregistered [ 2721.992985] LNet: 47224:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2721.999542] LNetError: 47224:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2723.045963] LNet: Removed LNI 192.168.201.20@tcp [ 2724.252800] Key type .llcrypt unregistered [ 2724.258729] Key type ._llcrypt unregistered [ 2743.570954] Key type ._llcrypt registered [ 2743.572805] Key type .llcrypt registered [ 2745.015861] Lustre: DEBUG MARKER: == conf-sanity test 123H: check concurent accesses with 'lctl llog_print ========================================================== 01:44:51 (1785390291) [ 2748.085732] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2748.248793] alg: No test for adler32 (adler32-zlib) [ 2749.472427] Lustre: Lustre: Build Version: 2.17.56_2_g669939b [ 2749.790875] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [ 2751.551225] Key type lgssc registered [ 2753.250551] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2809.043495] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2815.354496] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2839.515848] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2842.646267] Lustre: Mounted lustre-client [ 2966.933542] Lustre: DEBUG MARKER: == conf-sanity test 124: check failover after replace_nids ========================================================== 01:48:32 (1785390512) [ 2969.641601] Lustre: DEBUG MARKER: SKIP: conf-sanity test_124 needs MDT failover setup [ 2972.188581] Lustre: DEBUG MARKER: == conf-sanity test 126: mount in parallel shouldn't cause a crash ========================================================== 01:48:37 (1785390517) [ 2975.208935] Lustre: Unmounted lustre-client [ 3010.697858] Key type lgssc unregistered [ 3011.255472] LNet: 52569:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3011.266197] LNetError: 52569:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3011.289213] LNet: Removed LNI 192.168.201.20@tcp [ 3012.350916] Key type .llcrypt unregistered [ 3012.354138] Key type ._llcrypt unregistered [ 3047.959208] Key type ._llcrypt registered [ 3047.969508] Key type .llcrypt registered [ 3049.314322] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3049.368796] alg: No test for adler32 (adler32-zlib) [ 3050.785819] Lustre: Lustre: Build Version: 2.17.56_2_g669939b [ 3051.332658] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [ 3053.151335] Key type lgssc registered [ 3056.279825] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3095.033486] Lustre: DEBUG MARKER: == conf-sanity test 127: direct io overwrite on full ost ========================================================== 01:50:41 (1785390641) [ 3108.950852] Key type lgssc unregistered [ 3109.284190] LNet: 54999:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3109.296468] LNetError: 54999:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3109.311504] LNet: Removed LNI 192.168.201.20@tcp [ 3110.115196] Key type .llcrypt unregistered [ 3110.117636] Key type ._llcrypt unregistered [ 3127.049545] Key type ._llcrypt registered [ 3127.072095] Key type .llcrypt registered [ 3127.489608] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3127.505091] alg: No test for adler32 (adler32-zlib) [ 3128.570411] Lustre: Lustre: Build Version: 2.17.56_2_g669939b [ 3128.893025] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [ 3130.665703] Key type lgssc registered [ 3133.648731] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3201.384915] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3211.133884] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3239.823659] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3242.201306] Lustre: Mounted lustre-client [ 3293.751334] Lustre: DEBUG MARKER: == conf-sanity test 128: Force using remote logs with --nolocallogs ========================================================== 01:53:59 (1785390839) [ 3295.364751] Lustre: DEBUG MARKER: SKIP: conf-sanity test_128 need separate mgs device [ 3297.573110] Lustre: DEBUG MARKER: == conf-sanity test 129: attempt to connect an OST with the same index should fail ========================================================== 01:54:03 (1785390843) [ 3299.924431] Lustre: Unmounted lustre-client [ 3375.086669] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3383.614626] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3470.533635] Lustre: DEBUG MARKER: == conf-sanity test 130: re-register an MDT after writeconf ========================================================== 01:56:56 (1785391016) [ 3570.000710] Lustre: Mounted lustre-client [ 3575.358925] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 3595.744050] Lustre: lustre-OST0000-osc-ffff9cd24a2c8000: disconnect after 24s idle [ 3595.763430] Lustre: lustre-MDT0001-mdc-ffff9cd24a2c8000: Connection to lustre-MDT0001 (at 192.168.201.120@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3614.473656] LustreError: 63713:0:(obd_config.c:2103:class_config_llog_handler()) MGC192.168.201.120@tcp: cfg command failed: rc = -17 [ 3614.481208] Lustre: cmd=cf001 0:lustre-MDT0001-mdc 1:mdc 2:lustre-clilmv_UUID [ 3614.481208] [ 3614.485818] LustreError: 62955:0:(mgc_request.c:614:do_requeue()) failed processing log: -17 [ 3634.260496] Lustre: DEBUG MARKER: == conf-sanity test 131: MDT backup restore with project ID and inheritance flag ========================================================== 01:59:40 (1785391180) [ 3712.611759] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 3807.928353] Lustre: Unmounted lustre-client [ 3999.676441] Lustre: Mounted lustre-client [ 4006.271119] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 4081.669474] Lustre: DEBUG MARKER: == conf-sanity test 132: hsm_actions processed after failover ========================================================== 02:07:07 (1785391627) [ 4084.186993] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4085.008515] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 4085.015754] Lustre: Skipped 1 previous similar message [ 4102.465330] Lustre: Unmounted lustre-client [ 4298.974452] Lustre: DEBUG MARKER: == conf-sanity test 133: stripe QOS: free space balance in a pool ========================================================== 02:10:44 (1785391844) [ 4301.499333] Lustre: DEBUG MARKER: SKIP: conf-sanity test_133 needs >= 4 OSTs [ 4304.297533] Lustre: DEBUG MARKER: == conf-sanity test 134: check_iam works without faults == 02:10:49 (1785391849) [ 4425.397169] Lustre: Mounted lustre-client [ 4431.421173] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 5313.894794] Lustre: DEBUG MARKER: == conf-sanity test 135: check the behavior when changelog is wrapped around ========================================================== 02:27:39 (1785392859) [ 5318.400614] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5318.411617] Lustre: Skipped 1 previous similar message [ 5328.800035] Lustre: Unmounted lustre-client [ 5521.684093] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5529.698647] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5566.325551] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5567.070930] Lustre: Mounted lustre-client [ 7878.642440] Lustre: 95611:0:(llog_cat.c:310:llog_cat_process_or_fork()) lustre-MDT0000-mdc-ffff9cd248047800: catlog [0xa:0x5:0x0] crosses index zero [ 7909.360815] Lustre: Unmounted lustre-client [ 7939.248853] Key type lgssc unregistered [ 7939.695774] LNet: 103866:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7939.709486] LNetError: 103866:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7940.777846] LNet: Removed LNI 192.168.201.20@tcp [ 7942.576288] Key type .llcrypt unregistered [ 7942.581623] Key type ._llcrypt unregistered [ 7966.784679] Key type ._llcrypt registered [ 7966.787831] Key type .llcrypt registered [ 7968.253933] Lustre: DEBUG MARKER: == conf-sanity test 136: don't panic with bad obdecho setup ========================================================== 03:11:54 (1785395514) [ 7994.748610] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7994.801267] alg: No test for adler32 (adler32-zlib) [ 7995.982777] Lustre: Lustre: Build Version: 2.17.56_2_g669939b [ 7996.320756] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [ 7998.087183] Key type lgssc registered [ 7999.741105] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8109.267326] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8116.610468] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8143.929733] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8145.752096] Lustre: Mounted lustre-client [ 8167.331537] Lustre: Unmounted lustre-client [ 8329.098760] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8336.867642] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8369.055822] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8430.040189] Lustre: DEBUG MARKER: == conf-sanity test 137: a new MDT should inherit pools, parameters and failnode ========================================================== 03:19:35 (1785395975) [ 8432.417510] Lustre: DEBUG MARKER: SKIP: conf-sanity test_137 needs >= 3 MDTs [ 8434.486271] Lustre: DEBUG MARKER: == conf-sanity test 140: remove_updatelog script actions ========================================================== 03:19:40 (1785395980) [ 8476.356929] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8484.314815] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8511.947253] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8513.251498] Lustre: Mounted lustre-client [ 8523.758380] Lustre: lustre-MDT0000-mdc-ffff9cd242eee000: Connection to lustre-MDT0000 (at 192.168.201.120@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8533.991778] Lustre: lustre-MDT0001-mdc-ffff9cd242eee000: Connection to lustre-MDT0001 (at 192.168.201.120@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8545.247862] Lustre: 104629:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1785396076/real 1785396076] req@0000000025edc157 x1872122918022784/t0(0) o400->MGC192.168.201.120@tcp@192.168.201.120@tcp:26/25 lens 224/224 e 0 to 1 dl 1785396092 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 8545.296156] LustreError: MGC192.168.201.120@tcp: Connection to MGS (at 192.168.201.120@tcp) was lost; in progress operations using this service will fail [ 8676.332557] LustreError: lustre-MDT0000-mdc-ffff9cd242eee000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 8676.398247] Lustre: lustre-MDT0000-mdc-ffff9cd242eee000: Connection restored to 192.168.201.120@tcp (at 192.168.201.120@tcp) [ 8686.575608] LustreError: lustre-MDT0001-mdc-ffff9cd242eee000: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 8686.602980] Lustre: lustre-MDT0001-mdc-ffff9cd242eee000: Connection restored to 192.168.201.120@tcp (at 192.168.201.120@tcp) [ 8690.751724] Lustre: Evicted from MGS (at 192.168.201.120@tcp) after server handle changed from 0x2ec0854342a1096f to 0x2ec0854342a121be [ 8690.791655] Lustre: MGC192.168.201.120@tcp: Connection restored to 192.168.201.120@tcp (at 192.168.201.120@tcp) [ 8694.253619] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8697.402602] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 8709.005549] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8711.473308] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 8739.015621] Lustre: Unmounted lustre-client [ 8891.863983] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8898.774538] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8927.277718] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8984.499339] Lustre: DEBUG MARKER: == conf-sanity test 150: test setting max_cached_mb to a % ========================================================== 03:28:50 (1785396530) [ 9031.774880] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9039.659835] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9071.734851] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9072.874051] Lustre: Mounted lustre-client [ 9075.527320] LustreError: 121375: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 [ 9084.171860] Lustre: DEBUG MARKER: == conf-sanity test 151a: damaged local config doesn't prevent mounting ========================================================== 03:30:29 (1785396629) [ 9089.145937] Lustre: Unmounted lustre-client [ 9124.948841] Key type lgssc unregistered [ 9125.425754] LNet: 122385:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9125.437729] LNetError: 122385:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9125.464488] LNet: Removed LNI 192.168.201.20@tcp [ 9127.095819] Key type .llcrypt unregistered [ 9127.114566] Key type ._llcrypt unregistered [ 9147.032405] Key type ._llcrypt registered [ 9147.044799] Key type .llcrypt registered [ 9147.760338] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9147.794531] alg: No test for adler32 (adler32-zlib) [ 9149.091432] Lustre: Lustre: Build Version: 2.17.56_2_g669939b [ 9149.507830] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [ 9151.320100] Key type lgssc registered [ 9153.763372] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9265.269719] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9273.223700] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9310.620281] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9464.166987] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9473.002367] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9506.592387] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9562.743887] Lustre: DEBUG MARKER: == conf-sanity test 151b: -ENOSPC doesn't affect mount === 03:38:28 (1785397108) [ 9578.136960] Key type lgssc unregistered [ 9578.651787] LNet: 129625:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9578.670576] LNetError: 129625:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9578.699505] LNet: Removed LNI 192.168.201.20@tcp [ 9579.913498] Key type .llcrypt unregistered [ 9579.914943] Key type ._llcrypt unregistered [ 9597.560651] Key type ._llcrypt registered [ 9597.561940] Key type .llcrypt registered [ 9598.055798] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9598.069241] alg: No test for adler32 (adler32-zlib) [ 9599.775603] Lustre: Lustre: Build Version: 2.17.56_2_g669939b [ 9600.645692] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [ 9602.775186] Key type lgssc registered [ 9605.600851] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9676.053263] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9683.932642] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9711.744957] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9876.869813] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9885.509840] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9914.557636] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9965.865456] Lustre: DEBUG MARKER: == conf-sanity test 152: seq allocation error in OSP ===== 03:45:11 (1785397511) [10080.826570] Lustre: Mounted lustre-client [10089.213942] Lustre: DEBUG MARKER: Using TIMEOUT=20 [10103.609439] Lustre: DEBUG MARKER: ADD OST3 [10106.339415] Lustre: lustre-OST0000-osc-ffff9cd248c18000: disconnect after 23s idle [10118.847187] Lustre: DEBUG MARKER: STOP OST3 [10133.513182] Lustre: lustre-OST0003-osc-ffff9cd248c18000: Connection to lustre-OST0003 (at 192.168.201.120@tcp) was lost; in progress operations using this service will wait for recovery to complete [10146.766806] Lustre: DEBUG MARKER: START OST3 again [10161.311613] Lustre: lustre-OST0003-osc-ffff9cd248c18000: Connection restored to 192.168.201.120@tcp (at 192.168.201.120@tcp) [10200.398760] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [10210.825344] Lustre: Unmounted lustre-client [10371.686479] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10381.010443] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10412.133311] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10463.988251] Lustre: DEBUG MARKER: == conf-sanity test 153a: bypass invalid NIDs quickly ==== 03:53:29 (1785398009) [10590.582470] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10598.321828] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10628.921302] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10719.881476] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10728.117554] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10758.186990] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10760.868658] LNetError: 130038:0:(lib-move.c:2158:lnet_handle_find_routed_path()) peer 192.168.252.112@tcp2 has no available nets [10761.262341] Lustre: Mounted lustre-client [10766.773992] Lustre: Unmounted lustre-client [10798.917138] Key type lgssc unregistered [10811.295324] LNet: 1 local NIs in recovery (showing 1): 192.168.201.20@tcp [10816.611456] LNet: 149453:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10816.623075] LNetError: 149453:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10892.456210] LNet: Removed LNI 192.168.201.20@tcp [10893.141368] Key type .llcrypt unregistered [10893.143675] Key type ._llcrypt unregistered [10919.858645] Key type ._llcrypt registered [10919.859988] Key type .llcrypt registered [10922.158524] Lustre: DEBUG MARKER: == conf-sanity test 153b: added IPv6 NID support ========= 04:01:07 (1785398467) [10951.167248] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10951.369448] alg: No test for adler32 (adler32-zlib) [10952.644989] Lustre: Lustre: Build Version: 2.17.56_2_g669939b [10953.092202] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [10954.887189] Key type lgssc registered [10956.894919] Lustre: Echo OBD driver; http://www.lustre.org/ [11056.606375] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11067.619912] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11102.772431] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11273.001646] Lustre: Mounted lustre-client [11279.677962] Lustre: DEBUG MARKER: Using TIMEOUT=20 [11289.030188] Lustre: Unmounted lustre-client [11289.177287] LNetError: 150648:0:(lib-socket.c:308:lnet_sock_create()) Error trying to bind to fe80::54eb:d0ff:fe02:a56a/1023: rc = -22 [11289.186922] LNetError: Unexpected error -22 connecting to 6699:7654::1234:1234:d84@tcp at host [6699:7654::1234:1234:d84]:988 [11289.557401] Lustre: Mounted lustre-client [11289.777059] Lustre: Unmounted lustre-client [11290.811946] LNetError: 150649:0:(lib-socket.c:308:lnet_sock_create()) Error trying to bind to fe80::54eb:d0ff:fe02:a56a/1023: rc = -22 [11290.820226] LNetError: Unexpected error -22 connecting to 6699:7654::1234:1234:d84@tcp at host [6699:7654::1234:1234:d84]:988 [11291.124093] Lustre: Mounted lustre-client [11291.126629] Lustre: Skipped 1 previous similar message [11291.322143] Lustre: Unmounted lustre-client [11291.332484] Lustre: Skipped 1 previous similar message [11293.353562] Lustre: Unmounted lustre-client [11293.361138] Lustre: Skipped 2 previous similar messages [11293.489231] LNetError: 150649:0:(lib-socket.c:308:lnet_sock_create()) Error trying to bind to fe80::54eb:d0ff:fe02:a56a/1023: rc = -22 [11293.497869] LNetError: Unexpected error -22 connecting to 6699:7654::1234:1234:d84@tcp at host [6699:7654::1234:1234:d84]:988 [11293.849444] Lustre: Mounted lustre-client [11293.850879] Lustre: Skipped 3 previous similar messages [11294.877269] Lustre: 156594: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 [11295.601352] Lustre: 156625: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 [11295.625245] LNetError: 150650:0:(lib-socket.c:308:lnet_sock_create()) Error trying to bind to fe80::54eb:d0ff:fe02:a56a/1023: rc = -22 [11295.641521] LNetError: 150650:0:(lib-socket.c:308:lnet_sock_create()) Skipped 1 previous similar message [11295.661161] LNetError: Unexpected error -22 connecting to 2001:db8:85a3::8a2e:370:7334@tcp at host [2001:db8:85a3::8a2e:370:7334]:988 [11295.684534] LNetError: Skipped 1 previous similar message [11305.330524] Lustre: DEBUG MARKER: == conf-sanity test 153c: don't stuck on unreached NID === 04:07:31 (1785398851) [11343.391345] LNet: 1 local NIs in recovery (showing 1): 192.168.201.20@tcp [11453.426588] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11460.604077] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11490.550727] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11590.017562] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11597.034365] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11626.817812] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11633.056739] LustreError: 162247:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.252.112@tcp: failed processing log, type 1: rc = -110 [11681.375364] LNet: 1 local NIs in recovery (showing 1): 192.168.201.20@tcp [11757.151277] LNet: 1 local NIs in recovery (showing 1): 192.168.201.20@tcp [11771.872334] 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 [11771.909570] Lustre: Unmounted lustre-client [11771.912425] Lustre: Skipped 4 previous similar messages [11771.934534] LustreError: 162247:0:(super25.c:178:lustre_fill_super()) llite: Unable to mount : rc = -110 [11825.384942] Key type lgssc unregistered [11825.815264] LNet: 162709:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11825.827802] LNetError: 162709:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11888.487353] LNet: Removed LNI 192.168.201.20@tcp [11889.295173] Key type .llcrypt unregistered [11889.297040] Key type ._llcrypt unregistered [11909.958221] Key type ._llcrypt registered [11909.960022] Key type .llcrypt registered [11911.876128] Lustre: DEBUG MARKER: == conf-sanity test 154: expand .. on rename after MDT backup restore ========================================================== 04:17:37 (1785399457) [11934.822423] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11934.841791] alg: No test for adler32 (adler32-zlib) [11936.446876] Lustre: Lustre: Build Version: 2.17.56_2_g669939b [11936.995552] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [11938.751197] Key type lgssc registered [11940.646280] Lustre: Echo OBD driver; http://www.lustre.org/ [12128.650070] Lustre: Mounted lustre-client [12134.874779] Lustre: DEBUG MARKER: Using TIMEOUT=20 [12154.335364] Lustre: lustre-OST0000-osc-ffff9cd24c00c000: disconnect after 23s idle [12156.528052] Lustre: Unmounted lustre-client [12343.337735] Lustre: Mounted lustre-client [12349.226832] Lustre: DEBUG MARKER: Using TIMEOUT=20 [12358.660571] Lustre: Unmounted lustre-client [12416.629190] Lustre: DEBUG MARKER: == conf-sanity test 155: gap in seq allocation from ofd after restarting ========================================================== 04:26:02 (1785399962) [12546.623023] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12556.239411] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12590.318834] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12747.621243] Lustre: Mounted lustre-client [12756.701764] Lustre: DEBUG MARKER: Using TIMEOUT=20 [12773.343775] Lustre: lustre-OST0000-osc-ffff9cd248040000: disconnect after 23s idle [12773.350572] Lustre: Skipped 1 previous similar message [12777.619128] Lustre: Unmounted lustre-client [12932.827783] Lustre: Mounted lustre-client [12939.943130] Lustre: DEBUG MARKER: Using TIMEOUT=20 [12958.693753] Lustre: lustre-OST0001-osc-ffff9cd24c1bc800: disconnect after 23s idle [12958.711760] Lustre: Skipped 1 previous similar message [12965.322143] Lustre: DEBUG MARKER: == conf-sanity test 156: root_fid on export consistent with client mount ========================================================== 04:35:11 (1785400511) [12968.779554] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [12979.234931] Lustre: Unmounted lustre-client [13210.018312] Lustre: Mounted lustre-client [13217.755680] Lustre: DEBUG MARKER: Using TIMEOUT=20 [13232.165228] Lustre: Unmounted lustre-client [13233.211391] Lustre: Mounted lustre-client [13249.789670] Lustre: Unmounted lustre-client [13251.590801] Lustre: DEBUG MARKER: == conf-sanity test 157a: test allow_register for MDT registration and MGS restart ========================================================== 04:39:57 (1785400797) [13344.517879] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13353.305526] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13382.198293] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13388.501943] Lustre: Mounted lustre-client [13485.571554] LustreError: lustre-OST0001-osc-ffff9cd242cf9000: operation ost_connect to node 192.168.201.120@tcp failed: rc = -19 [13516.280282] Lustre: lustre-MDT0000-mdc-ffff9cd242cf9000: Connection to lustre-MDT0000 (at 192.168.201.120@tcp) was lost; in progress operations using this service will wait for recovery to complete [13541.881832] LustreError: lustre-MDT0000-mdc-ffff9cd242cf9000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [13541.883556] LustreError: MGC192.168.201.120@tcp: Connection to MGS (at 192.168.201.120@tcp) was lost; in progress operations using this service will fail [13541.936379] Lustre: lustre-MDT0000-mdc-ffff9cd242cf9000: Connection restored to 192.168.201.120@tcp (at 192.168.201.120@tcp) [13541.975566] Lustre: Evicted from MGS (at 192.168.201.120@tcp) after server handle changed from 0x31a47d5b86f5e5d1 to 0x31a47d5b86f5e89b [13602.151403] Lustre: Unmounted lustre-client [13645.210230] Key type lgssc unregistered [13645.610704] LNet: 190282:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13645.621766] LNetError: 190282:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13645.641590] LNet: Removed LNI 192.168.201.20@tcp [13646.884286] Key type .llcrypt unregistered [13646.885819] Key type ._llcrypt unregistered [13662.383954] Key type ._llcrypt registered [13662.386142] Key type .llcrypt registered [13664.553980] Lustre: DEBUG MARKER: == conf-sanity test 157b: verify allow_register (block new OSTs, allow existing) ========================================================== 04:46:49 (1785401209) [13668.443452] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13668.463592] alg: No test for adler32 (adler32-zlib) [13669.929521] Lustre: Lustre: Build Version: 2.17.56_2_g669939b [13670.345035] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [13672.239614] Key type lgssc registered [13674.256794] Lustre: Echo OBD driver; http://www.lustre.org/ [13737.275317] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13743.859888] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13766.906673] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13768.783220] Lustre: Mounted lustre-client [13865.968786] LustreError: lustre-OST0001-osc-ffff9cd24c00c000: operation ost_connect to node 192.168.201.120@tcp failed: rc = -19 [14019.726379] LustreError: lustre-OST0001-osc-ffff9cd24c00c000: operation ost_connect to node 192.168.201.120@tcp failed: rc = -19 [14063.480053] Lustre: Unmounted lustre-client [14098.062249] Key type lgssc unregistered [14098.395195] LNet: 196321:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14098.401895] LNetError: 196321:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14098.453493] LNet: Removed LNI 192.168.201.20@tcp [14099.697164] Key type .llcrypt unregistered [14099.699093] Key type ._llcrypt unregistered [14115.615898] Key type ._llcrypt registered [14115.619193] Key type .llcrypt registered [14117.524783] Lustre: DEBUG MARKER: == conf-sanity test 160: MGC updates failnodes from all participants ========================================================== 04:54:23 (1785401663) [14159.080173] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14159.116651] alg: No test for adler32 (adler32-zlib) [14160.431482] Lustre: Lustre: Build Version: 2.17.56_2_g669939b [14160.809476] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [14162.535237] Key type lgssc registered [14164.558944] Lustre: Echo OBD driver; http://www.lustre.org/ [14291.899619] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14299.987165] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14330.818212] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14388.807854] Key type lgssc unregistered [14389.220703] LNet: 201315:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14389.228075] LNetError: 201315:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14389.246930] LNet: Removed LNI 192.168.201.20@tcp [14390.116067] Key type .llcrypt unregistered [14390.117265] Key type ._llcrypt unregistered [14493.896882] Key type ._llcrypt registered [14493.898112] Key type .llcrypt registered [14494.172108] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14494.188216] alg: No test for adler32 (adler32-zlib) [14495.443604] Lustre: Lustre: Build Version: 2.17.56_2_g669939b [14495.763274] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [14497.656129] Key type lgssc registered [14500.470693] Lustre: Echo OBD driver; http://www.lustre.org/ [14565.205740] Lustre: DEBUG MARKER: == conf-sanity test 161: test '-o mgsname' option ======== 05:01:50 (1785402110) [14611.249286] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14620.753247] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14649.731941] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14652.073860] Lustre: Mounted lustre-client [14655.201438] Lustre: Unmounted lustre-client [14655.699336] Lustre: Mounted lustre-client [14656.032622] Lustre: Unmounted lustre-client [14656.941878] Lustre: Mounted lustre-client [14669.514687] Lustre: Unmounted lustre-client [14705.695708] Key type lgssc unregistered [14705.938458] LNet: 206308:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14705.944133] LNetError: 206308:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14705.956691] LNet: Removed LNI 192.168.201.20@tcp [14706.941751] Key type .llcrypt unregistered [14706.945249] Key type ._llcrypt unregistered [14724.408116] Key type ._llcrypt registered [14724.409647] Key type .llcrypt registered [14725.820613] Lustre: DEBUG MARKER: == conf-sanity test 162: replace nids with -o noclient === 05:04:31 (1785402271) [14731.566756] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14731.848748] alg: No test for adler32 (adler32-zlib) [14733.109495] Lustre: Lustre: Build Version: 2.17.56_2_g669939b [14733.654483] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [14735.487223] Key type lgssc registered [14737.946295] Lustre: Echo OBD driver; http://www.lustre.org/ [14815.320848] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14822.961777] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14854.429283] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14857.685662] Lustre: Mounted lustre-client [14871.969951] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14874.734477] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [14883.318570] Lustre: lustre-MDT0000-mdc-ffff9cd24aca4800: Connection to lustre-MDT0000 (at 192.168.201.120@tcp) was lost; in progress operations using this service will wait for recovery to complete [14893.554065] Lustre: lustre-MDT0001-mdc-ffff9cd24aca4800: Connection to lustre-MDT0001 (at 192.168.201.120@tcp) was lost; in progress operations using this service will wait for recovery to complete [14904.799305] Lustre: 206984:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1785402436/real 1785402436] req@0000000082c055e7 x1872129982208384/t0(0) o400->MGC192.168.201.120@tcp@192.168.201.120@tcp:26/25 lens 224/224 e 0 to 1 dl 1785402452 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [14904.875939] LustreError: MGC192.168.201.120@tcp: Connection to MGS (at 192.168.201.120@tcp) was lost; in progress operations using this service will fail [14954.102252] Lustre: Evicted from MGS (at 192.168.201.120@tcp) after server handle changed from 0x745379d11baa6cc6 to 0x745379d11baa6e6a [14954.123879] Lustre: MGC192.168.201.120@tcp: Connection restored to 192.168.201.120@tcp (at 192.168.201.120@tcp) [14990.316787] LustreError: lustre-MDT0000-mdc-ffff9cd24aca4800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [14990.352917] Lustre: lustre-MDT0000-mdc-ffff9cd24aca4800: Connection restored to 192.168.201.120@tcp (at 192.168.201.120@tcp) [15006.168914] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15008.838839] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in IDLE state after 0 sec [15018.322938] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15021.178535] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [15027.746945] Lustre: Unmounted lustre-client [15062.126749] Key type lgssc unregistered [15062.620444] LNet: 211857:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15062.634376] LNetError: 211857:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15062.716940] LNet: Removed LNI 192.168.201.20@tcp [15063.949259] Key type .llcrypt unregistered [15063.954313] Key type ._llcrypt unregistered [15102.495199] Key type ._llcrypt registered [15102.497149] Key type .llcrypt registered [15102.647400] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [15102.664578] alg: No test for adler32 (adler32-zlib) [15103.896549] Lustre: Lustre: Build Version: 2.17.56_2_g669939b [15104.191380] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [15105.831612] Key type lgssc registered [15108.483740] Lustre: Echo OBD driver; http://www.lustre.org/ [15216.124990] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15224.358414] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15257.160437] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15306.250754] Lustre: DEBUG MARKER: == conf-sanity test 164: test expected_clients parameter and max client tracking ========================================================== 05:14:11 (1785402851) [15434.415711] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15443.987467] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15475.425180] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15478.981836] Lustre: Mounted lustre-client [15485.401487] Lustre: Mounted lustre-client [15486.409902] Lustre: Mounted lustre-client [15488.475104] Lustre: Mounted lustre-client [15488.476136] Lustre: Skipped 2 previous similar messages [15491.805847] Lustre: Unmounted lustre-client [15494.559988] Lustre: Unmounted lustre-client [15497.094295] Lustre: Unmounted lustre-client [15499.470063] Lustre: Unmounted lustre-client [15517.795020] Lustre: Unmounted lustre-client [15517.799400] Lustre: Skipped 1 previous similar message [15552.931505] Key type lgssc unregistered [15553.193592] LNet: 221040:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15553.198630] LNetError: 221040:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15553.238067] LNet: Removed LNI 192.168.201.20@tcp [15554.145684] Key type .llcrypt unregistered [15554.147402] Key type ._llcrypt unregistered [15575.331086] Key type ._llcrypt registered [15575.337416] Key type .llcrypt registered [15577.228992] Lustre: DEBUG MARKER: == conf-sanity test 200a: check CPU partitions =========== 05:18:42 (1785403122) [15592.912549] Key type .llcrypt unregistered [15592.916896] Key type ._llcrypt unregistered [15594.839869] Key type ._llcrypt registered [15594.859641] Key type .llcrypt registered [15595.810766] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 4 [15595.835610] alg: No test for adler32 (adler32-zlib) [15597.220511] Lustre: Lustre: Build Version: 2.17.56_2_g669939b [15597.666048] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [15599.471646] Key type lgssc registered [15602.705839] Lustre: Echo OBD driver; http://www.lustre.org/ [15636.065120] Key type lgssc unregistered [15636.352923] LNet: 223200:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15636.364459] LNetError: 223200:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15636.383366] LNet: Removed LNI 192.168.201.20@tcp [15637.212800] Key type .llcrypt unregistered [15637.215373] Key type ._llcrypt unregistered [15637.632623] Key type ._llcrypt registered [15637.634633] Key type .llcrypt registered [15639.674259] Lustre: DEBUG MARKER: == conf-sanity test 200b: set CPU pattern using core selection ========================================================== 05:19:45 (1785403185) [15658.243133] Key type .llcrypt unregistered [15658.245121] Key type ._llcrypt unregistered [15659.335694] Key type ._llcrypt registered [15659.337085] Key type .llcrypt registered [15659.889060] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [15659.901903] alg: No test for adler32 (adler32-zlib) [15661.165930] Lustre: Lustre: Build Version: 2.17.56_2_g669939b [15661.646539] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [15663.439253] Key type lgssc registered [15665.072972] Lustre: Echo OBD driver; http://www.lustre.org/ [15688.858751] Key type lgssc unregistered [15689.076734] LNet: 224758:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15689.106454] LNetError: 224758:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15689.137593] LNet: Removed LNI 192.168.201.20@tcp [15690.883047] Key type .llcrypt unregistered [15690.884919] Key type ._llcrypt unregistered [15704.367672] Key type ._llcrypt registered [15704.371821] Key type .llcrypt registered [15704.915672] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [15704.951635] alg: No test for adler32 (adler32-zlib) [15706.237475] Lustre: Lustre: Build Version: 2.17.56_2_g669939b [15706.527179] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [15708.304762] Key type lgssc registered [15710.058014] Lustre: Echo OBD driver; http://www.lustre.org/ [15749.665673] Key type lgssc unregistered [15750.335663] LNet: 226524:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15750.352372] LNetError: 226524:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15750.373951] LNet: Removed LNI 192.168.201.20@tcp [15752.444831] Key type .llcrypt unregistered [15752.446601] Key type ._llcrypt unregistered [15753.376413] Key type ._llcrypt registered [15753.380823] Key type .llcrypt registered [15756.032402] Lustre: DEBUG MARKER: == conf-sanity test 200c: set CPU pattern using NUMA node layout ========================================================== 05:21:40 (1785403300) [15772.546553] Key type .llcrypt unregistered [15772.548315] Key type ._llcrypt unregistered [15773.973529] Key type ._llcrypt registered [15773.976800] Key type .llcrypt registered [15774.585325] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [15774.616560] alg: No test for adler32 (adler32-zlib) [15776.082824] Lustre: Lustre: Build Version: 2.17.56_2_g669939b [15776.483275] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [15778.351178] Key type lgssc registered [15780.334310] Lustre: Echo OBD driver; http://www.lustre.org/ [15802.833682] Key type lgssc unregistered [15803.228334] LNet: 228074:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15803.242523] LNetError: 228074:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15803.253498] LNet: Removed LNI 192.168.201.20@tcp [15804.436592] Key type .llcrypt unregistered [15804.443038] Key type ._llcrypt unregistered [15817.519748] Key type ._llcrypt registered [15817.532269] Key type .llcrypt registered [15818.745188] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [15818.805310] alg: No test for adler32 (adler32-zlib) [15820.354827] Lustre: Lustre: Build Version: 2.17.56_2_g669939b [15820.661761] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [15822.480535] Key type lgssc registered [15824.343844] Lustre: Echo OBD driver; http://www.lustre.org/ [15850.288523] Key type lgssc unregistered [15850.609925] LNet: 229385:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15850.619214] LNetError: 229385:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15850.637224] LNet: Removed LNI 192.168.201.20@tcp [15851.829761] Key type .llcrypt unregistered [15851.832971] Key type ._llcrypt unregistered [15861.184281] Key type ._llcrypt registered [15861.185620] Key type .llcrypt registered [15861.774340] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [15861.835947] alg: No test for adler32 (adler32-zlib) [15863.024379] Lustre: Lustre: Build Version: 2.17.56_2_g669939b [15863.459632] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [15865.095119] Key type lgssc registered [15868.056332] Lustre: Echo OBD driver; http://www.lustre.org/ [15894.248300] Key type lgssc unregistered [15894.754709] LNet: 230691:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15894.759330] LNetError: 230691:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15894.778180] LNet: Removed LNI 192.168.201.20@tcp [15896.201896] Key type .llcrypt unregistered [15896.203542] Key type ._llcrypt unregistered [15908.261350] Key type ._llcrypt registered [15908.264564] Key type .llcrypt registered [15908.719756] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [15908.736802] alg: No test for adler32 (adler32-zlib) [15909.748274] Lustre: Lustre: Build Version: 2.17.56_2_g669939b [15909.957756] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [15911.647192] Key type lgssc registered [15913.803242] Lustre: Echo OBD driver; http://www.lustre.org/ [15946.520913] Key type lgssc unregistered [15946.880376] LNet: 232463:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15946.894560] LNetError: 232463:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15946.910262] LNet: Removed LNI 192.168.201.20@tcp [15948.203712] Key type .llcrypt unregistered [15948.206070] Key type ._llcrypt unregistered [15948.624833] Key type ._llcrypt registered [15948.628236] Key type .llcrypt registered [15949.838528] Lustre: DEBUG MARKER: == conf-sanity test 200d: set CPU pattern to exclude only CPU 1 ========================================================== 05:24:56 (1785403496) [15962.200456] Key type .llcrypt unregistered [15962.202371] Key type ._llcrypt unregistered [15963.245073] Key type ._llcrypt registered [15963.290066] Key type .llcrypt registered [15963.537370] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [15963.570320] alg: No test for adler32 (adler32-zlib) [15964.754495] Lustre: Lustre: Build Version: 2.17.56_2_g669939b [15965.015259] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [15966.824102] Key type lgssc registered [15968.366405] Lustre: Echo OBD driver; http://www.lustre.org/ [15988.852371] Key type lgssc unregistered [15989.183678] LNet: 234016:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15989.195690] LNetError: 234016:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15989.216360] LNet: Removed LNI 192.168.201.20@tcp [15989.934579] Key type .llcrypt unregistered [15989.948380] Key type ._llcrypt unregistered [15999.070912] Key type ._llcrypt registered [15999.079698] Key type .llcrypt registered [15999.844263] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [15999.889170] alg: No test for adler32 (adler32-zlib) [16001.108660] Lustre: Lustre: Build Version: 2.17.56_2_g669939b [16001.508392] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [16003.319214] Key type lgssc registered [16004.605844] Lustre: Echo OBD driver; http://www.lustre.org/ [16024.903147] Key type lgssc unregistered [16025.296699] LNet: 235331:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16025.309329] LNetError: 235331:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16025.356954] LNet: Removed LNI 192.168.201.20@tcp [16026.530536] Key type .llcrypt unregistered [16026.531888] Key type ._llcrypt unregistered [16035.400048] Key type ._llcrypt registered [16035.408985] Key type .llcrypt registered [16036.211900] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [16036.237095] alg: No test for adler32 (adler32-zlib) [16037.195366] Lustre: Lustre: Build Version: 2.17.56_2_g669939b [16037.362856] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [16039.001871] Key type lgssc registered [16041.562929] Lustre: Echo OBD driver; http://www.lustre.org/ [16063.997847] Key type lgssc unregistered [16064.504152] LNet: 236639:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16064.521043] LNetError: 236639:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16064.553806] LNet: Removed LNI 192.168.201.20@tcp [16065.746731] Key type .llcrypt unregistered [16065.750760] Key type ._llcrypt unregistered [16074.367056] Key type ._llcrypt registered [16074.371492] Key type .llcrypt registered [16074.713617] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [16074.738223] alg: No test for adler32 (adler32-zlib) [16076.138191] Lustre: Lustre: Build Version: 2.17.56_2_g669939b [16076.611685] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [16078.416153] Key type lgssc registered [16079.918366] Lustre: Echo OBD driver; http://www.lustre.org/ [16098.995472] Key type lgssc unregistered [16099.392111] LNet: 237964:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16099.401604] LNetError: 237964:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16099.419798] LNet: Removed LNI 192.168.201.20@tcp [16100.518904] Key type .llcrypt unregistered [16100.526219] Key type ._llcrypt unregistered [16110.269950] Key type ._llcrypt registered [16110.274242] Key type .llcrypt registered [16110.707507] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [16110.719398] alg: No test for adler32 (adler32-zlib) [16111.988525] Lustre: Lustre: Build Version: 2.17.56_2_g669939b [16112.184199] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [16113.863263] Key type lgssc registered [16115.161422] Lustre: Echo OBD driver; http://www.lustre.org/ [16137.011342] Key type lgssc unregistered [16137.384601] LNet: 239276:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16137.387611] LNetError: 239276:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16137.398578] LNet: Removed LNI 192.168.201.20@tcp [16138.273630] Key type .llcrypt unregistered [16138.275805] Key type ._llcrypt unregistered [16146.015701] Key type ._llcrypt registered [16146.044994] Key type .llcrypt registered [16146.382168] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [16146.400167] alg: No test for adler32 (adler32-zlib) [16147.707589] Lustre: Lustre: Build Version: 2.17.56_2_g669939b [16148.115116] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [16149.815246] Key type lgssc registered [16150.926369] Lustre: Echo OBD driver; http://www.lustre.org/ [16168.123328] Key type lgssc unregistered [16168.669670] LNet: 240583:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16168.683598] LNetError: 240583:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16168.700956] LNet: Removed LNI 192.168.201.20@tcp [16170.184460] Key type .llcrypt unregistered [16170.190889] Key type ._llcrypt unregistered [16213.368339] Key type ._llcrypt registered [16213.369975] Key type .llcrypt registered [16215.756754] Lustre: DEBUG MARKER: == conf-sanity test 200e: set CPU pattern using relative core exclusion ========================================================== 05:29:20 (1785403760) [16231.177358] Key type .llcrypt unregistered [16231.178966] Key type ._llcrypt unregistered [16233.195166] Key type ._llcrypt registered [16233.207357] Key type .llcrypt registered [16233.608616] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [16233.642812] alg: No test for adler32 (adler32-zlib) [16234.988126] Lustre: Lustre: Build Version: 2.17.56_2_g669939b [16235.337500] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [16237.055186] Key type lgssc registered [16238.792787] Lustre: Echo OBD driver; http://www.lustre.org/ [16265.959727] Key type lgssc unregistered [16266.483260] LNet: 242908:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16266.493640] LNetError: 242908:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16266.536564] LNet: Removed LNI 192.168.201.20@tcp [16268.256265] Key type .llcrypt unregistered [16268.272943] Key type ._llcrypt unregistered [16278.591340] Key type ._llcrypt registered [16278.593385] Key type .llcrypt registered [16279.213708] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [16279.254462] alg: No test for adler32 (adler32-zlib) [16280.580527] Lustre: Lustre: Build Version: 2.17.56_2_g669939b [16281.029493] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [16282.831202] Key type lgssc registered [16285.217164] Lustre: Echo OBD driver; http://www.lustre.org/ [16307.217449] Key type lgssc unregistered [16307.540426] LNet: 244230:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16307.550931] LNetError: 244230:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16307.573157] LNet: Removed LNI 192.168.201.20@tcp [16308.563538] Key type .llcrypt unregistered [16308.565239] Key type ._llcrypt unregistered [16318.780305] Key type ._llcrypt registered [16318.790507] Key type .llcrypt registered [16319.588395] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [16319.607828] alg: No test for adler32 (adler32-zlib) [16320.896343] Lustre: Lustre: Build Version: 2.17.56_2_g669939b [16321.300372] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [16323.112121] Key type lgssc registered [16325.121709] Lustre: Echo OBD driver; http://www.lustre.org/ [16351.088353] Key type lgssc unregistered [16351.753851] LNet: 245542:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16351.766842] LNetError: 245542:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16351.782917] LNet: Removed LNI 192.168.201.20@tcp [16352.454181] Key type .llcrypt unregistered [16352.456113] Key type ._llcrypt unregistered [16363.898809] Key type ._llcrypt registered [16363.907489] Key type .llcrypt registered [16364.335825] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [16364.354215] alg: No test for adler32 (adler32-zlib) [16365.703052] Lustre: Lustre: Build Version: 2.17.56_2_g669939b [16366.088052] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [16367.904249] Key type lgssc registered [16369.195390] Lustre: Echo OBD driver; http://www.lustre.org/ [16402.424706] Key type lgssc unregistered [16402.909938] LNet: 247338:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16402.930965] LNetError: 247338:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16402.962324] LNet: Removed LNI 192.168.201.20@tcp [16404.138991] Key type .llcrypt unregistered [16404.141659] Key type ._llcrypt unregistered [16404.659518] Key type ._llcrypt registered [16404.664397] Key type .llcrypt registered [16407.636829] Lustre: DEBUG MARKER: == conf-sanity test 250: verify lcfg_erase removes filesystem parameters ========================================================== 05:32:32 (1785403952) [16434.474284] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [16434.507306] alg: No test for adler32 (adler32-zlib) [16435.745096] Lustre: Lustre: Build Version: 2.17.56_2_g669939b [16435.985027] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [16437.655187] Key type lgssc registered [16439.698399] Lustre: Echo OBD driver; http://www.lustre.org/ [16735.101171] Lustre: DEBUG MARKER: == conf-sanity test 802a: simulate readonly device ======= 05:38:00 (1785404280) [16736.943641] Lustre: DEBUG MARKER: SKIP: conf-sanity test_802a ZFS specific test [16830.207664] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 16438 sec ========= 05:39:36 (1785404376) [16833.011868] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 05:39:38 (1785404378) === [16838.177817] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 05:39:43 (1785404383) === [16886.805453] Key type lgssc unregistered [16887.273019] LNet: 256417:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16887.286457] LNetError: 256417:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16887.309777] LNet: Removed LNI 192.168.201.20@tcp [16888.469620] Key type .llcrypt unregistered [16888.475364] Key type ._llcrypt unregistered