[ 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-0x00000000bffcdfff] usable [ 0.000000] BIOS-e820: [mem 0x00000000bffce000-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 474493302 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 = 0xbffce max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54b0-0x000f54bf] [ 0.000000] RAMDISK: [mem 0xbcc54000-0xbffbffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52D0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE247C 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE2318 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 0022D8 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE238C 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE241C 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE2454 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe2318-0xbffe238b] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe2317] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe238c-0xbffe241b] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe241c-0xbffe2453] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe2454-0xbffe247b] [ 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-0x00000000bffcdfff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4756 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 0xbffce000-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: 1059606 [ 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: 2829652K/4306352K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001012] APIC: Switch to symmetric I/O mode setup [ 0.003128] x2apic enabled [ 0.004000] Switched APIC routing to physical x2apic. [ 0.004000] kvm-guest: setup PV IPIs [ 0.006354] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.007000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.007027] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.008016] pid_max: default: 32768 minimum: 301 [ 0.009148] LSM: Security Framework initializing [ 0.010060] Yama: becoming mindful. [ 0.011041] SELinux: Initializing. [ 0.012072] *** VALIDATE selinux *** [ 0.020244] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.024772] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.025153] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.026118] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.028040] *** VALIDATE tmpfs *** [ 0.029474] *** VALIDATE proc *** [ 0.030245] *** VALIDATE cgroup *** [ 0.031009] *** VALIDATE cgroup2 *** [ 0.032279] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.033170] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.034008] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.035028] Spectre V2 : User space: Vulnerable [ 0.036008] Speculative Store Bypass: Vulnerable [ 0.039523] debug: unmapping init [mem 0xffffffffb4659000-0xffffffffb4660fff] [ 0.042170] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.043700] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.044025] ... version: 2 [ 0.045014] ... bit width: 48 [ 0.045995] ... generic registers: 4 [ 0.046011] ... value mask: 0000ffffffffffff [ 0.047010] ... max period: 00007fffffffffff [ 0.048014] ... fixed-purpose events: 3 [ 0.049008] ... event mask: 000000070000000f [ 0.050337] rcu: Hierarchical SRCU implementation. [ 0.052477] smp: Bringing up secondary CPUs ... [ 0.053598] x86: Booting SMP configuration: [ 0.054026] .... node #0, CPUs: #1 #2 #3 [ 0.057149] smp: Brought up 1 node, 4 CPUs [ 0.059010] smpboot: Max logical packages: 1 [ 0.059990] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.137256] node 0 deferred pages initialised in 76ms [ 0.140171] devtmpfs: initialized [ 0.142265] x86/mm: Memory block size: 128MB [ 0.144758] gcov: version magic: 0x41383552 [ 0.147366] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.151130] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.154456] pinctrl core: initialized pinctrl subsystem [ 0.157264] [ 0.157922] ************************************************************* [ 0.160022] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.163026] ** ** [ 0.165019] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.168022] ** ** [ 0.170022] ** This means that this kernel is built to expose internal ** [ 0.173020] ** IOMMU data structures, which may compromise security on ** [ 0.176020] ** your system. ** [ 0.178029] ** ** [ 0.181021] ** If you see this message and you are not debugging the ** [ 0.183022] ** kernel, report this immediately to your vendor! ** [ 0.186021] ** ** [ 0.188019] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.190015] ************************************************************* [ 0.193354] NET: Registered protocol family 16 [ 0.195448] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.198063] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.200054] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.203162] cpuidle: using governor menu [ 0.204587] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.206435] PCI: Using configuration type 1 for base access [ 0.209156] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.218172] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.219040] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.221038] cryptd: max_cpu_qlen set to 1000 [ 0.223245] ACPI: Added _OSI(Module Device) [ 0.225031] ACPI: Added _OSI(Processor Device) [ 0.227017] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.228018] ACPI: Added _OSI(Processor Aggregator Device) [ 0.233156] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.239647] ACPI: Interpreter enabled [ 0.241091] ACPI: PM: (supports S0 S3 S4 S5) [ 0.243031] ACPI: Using IOAPIC for interrupt routing [ 0.245181] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.249483] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.261539] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.263072] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.266031] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.270117] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.275460] acpiphp: Slot [2] registered [ 0.277095] acpiphp: Slot [5] registered [ 0.278119] acpiphp: Slot [6] registered [ 0.280115] acpiphp: Slot [7] registered [ 0.282175] acpiphp: Slot [8] registered [ 0.284131] acpiphp: Slot [9] registered [ 0.286144] acpiphp: Slot [10] registered [ 0.288107] acpiphp: Slot [3] registered [ 0.289101] acpiphp: Slot [4] registered [ 0.291121] acpiphp: Slot [11] registered [ 0.292131] acpiphp: Slot [12] registered [ 0.293111] acpiphp: Slot [13] registered [ 0.295114] acpiphp: Slot [14] registered [ 0.297111] acpiphp: Slot [15] registered [ 0.298164] acpiphp: Slot [16] registered [ 0.299101] acpiphp: Slot [17] registered [ 0.301110] acpiphp: Slot [18] registered [ 0.303129] acpiphp: Slot [19] registered [ 0.305107] acpiphp: Slot [20] registered [ 0.306105] acpiphp: Slot [21] registered [ 0.307090] acpiphp: Slot [22] registered [ 0.309221] acpiphp: Slot [23] registered [ 0.310096] acpiphp: Slot [24] registered [ 0.312144] acpiphp: Slot [25] registered [ 0.314101] acpiphp: Slot [26] registered [ 0.315088] acpiphp: Slot [27] registered [ 0.316083] acpiphp: Slot [28] registered [ 0.317080] acpiphp: Slot [29] registered [ 0.319091] acpiphp: Slot [30] registered [ 0.320115] acpiphp: Slot [31] registered [ 0.321060] PCI host bridge to bus 0000:00 [ 0.323019] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.324017] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.326020] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.328021] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.330024] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.332047] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.334174] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.336983] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.339237] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.349017] pci 0000:00:01.1: reg 0x20: [io 0xc320-0xc32f] [ 0.354136] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.356016] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.357013] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.359014] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.361603] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.365945] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.368044] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.371711] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.377014] pci 0000:00:02.0: reg 0x10: [io 0xc300-0xc31f] [ 0.390000] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.395014] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.401664] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.409016] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.415018] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.431017] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.445173] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.457016] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.472014] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.501016] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.512000] pci 0000:00:07.0: [1af4:1001] type 00 class 0x010000 [ 0.520018] pci 0000:00:07.0: reg 0x10: [io 0xc100-0xc17f] [ 0.530024] pci 0000:00:07.0: reg 0x14: [mem 0xfebc2000-0xfebc2fff] [ 0.552021] pci 0000:00:07.0: reg 0x20: [mem 0xe000000c000-0xe000000ffff 64bit pref] [ 0.565304] pci 0000:00:08.0: [1af4:1001] type 00 class 0x010000 [ 0.575016] pci 0000:00:08.0: reg 0x10: [io 0xc180-0xc1ff] [ 0.582018] pci 0000:00:08.0: reg 0x14: [mem 0xfebc3000-0xfebc3fff] [ 0.601019] pci 0000:00:08.0: reg 0x20: [mem 0xe0000010000-0xe0000013fff 64bit pref] [ 0.613000] pci 0000:00:09.0: [1af4:1001] type 00 class 0x010000 [ 0.620014] pci 0000:00:09.0: reg 0x10: [io 0xc200-0xc27f] [ 0.627015] pci 0000:00:09.0: reg 0x14: [mem 0xfebc4000-0xfebc4fff] [ 0.657015] pci 0000:00:09.0: reg 0x20: [mem 0xe0000014000-0xe0000017fff 64bit pref] [ 0.672901] pci 0000:00:0a.0: [1af4:1001] type 00 class 0x010000 [ 0.685017] pci 0000:00:0a.0: reg 0x10: [io 0xc280-0xc2ff] [ 0.695018] pci 0000:00:0a.0: reg 0x14: [mem 0xfebc5000-0xfebc5fff] [ 0.720019] pci 0000:00:0a.0: reg 0x20: [mem 0xe0000018000-0xe000001bfff 64bit pref] [ 0.735971] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.739373] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.742335] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.744298] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.745180] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.749111] iommu: Default domain type: Passthrough [ 0.752613] SCSI subsystem initialized [ 0.754197] ACPI: bus type USB registered [ 0.756132] usbcore: registered new interface driver usbfs [ 0.757097] usbcore: registered new interface driver hub [ 0.759135] usbcore: registered new device driver usb [ 0.760138] pps_core: LinuxPPS API ver. 1 registered [ 0.762009] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.764068] PTP clock support registered [ 0.766082] EDAC MC: Ver: 3.0.0 [ 0.767445] PCI: Using ACPI for IRQ routing [ 0.769921] NetLabel: Initializing [ 0.771013] NetLabel: domain hash size = 128 [ 0.773018] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.775103] NetLabel: unlabeled traffic allowed by default [ 0.777123] vgaarb: loaded [ 0.779314] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.781016] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.788352] clocksource: Switched to clocksource kvm-clock [ 0.897082] VFS: Disk quotas dquot_6.6.0 [ 0.898782] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.901507] *** VALIDATE ramfs *** [ 0.902838] *** VALIDATE hugetlbfs *** [ 0.904354] pnp: PnP ACPI init [ 0.906675] pnp: PnP ACPI: found 6 devices [ 0.931078] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.934750] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.938094] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.940260] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.942681] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.945234] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.947944] NET: Registered protocol family 2 [ 0.950489] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.955463] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.959302] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.964810] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.969515] TCP: Hash tables configured (established 65536 bind 65536) [ 0.972800] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.976151] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.979172] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.982867] NET: Registered protocol family 1 [ 0.986239] RPC: Registered named UNIX socket transport module. [ 0.988522] RPC: Registered udp transport module. [ 0.990588] RPC: Registered tcp transport module. [ 0.992447] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.995148] NET: Registered protocol family 44 [ 0.997044] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.999421] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 1.001708] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 1.004249] PCI: CLS 0 bytes, default 64 [ 1.006844] Unpacking initramfs... [ 2.442455] debug: unmapping init [mem 0xffff9cf73cc54000-0xffff9cf73ffbffff] [ 2.446913] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.449266] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.452367] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.930763] Initialise system trusted keyrings [ 2.935113] Key type blacklist registered [ 2.937076] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.945585] zbud: loaded [ 2.948456] *** VALIDATE nfs *** [ 2.949595] *** VALIDATE nfs4 *** [ 2.951020] pstore: using deflate compression [ 2.954328] Platform Keyring initialized [ 3.055049] NET: Registered protocol family 38 [ 3.056796] Key type asymmetric registered [ 3.058325] Asymmetric key parser 'x509' registered [ 3.060272] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 3.062698] io scheduler mq-deadline registered [ 3.064300] io scheduler kyber registered [ 3.065674] io scheduler bfq registered [ 3.067380] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 3.069976] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 3.072477] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 3.074804] ACPI: Power Button [PWRF] [ 3.080056] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.086684] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.109090] ACPI: \_SB_.LNKC: Enabled at IRQ 11 [ 3.117084] ACPI: \_SB_.LNKD: Enabled at IRQ 10 [ 3.130222] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.158380] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.186346] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.191501] Non-volatile memory driver v1.3 [ 3.193426] Linux agpgart interface v0.103 [ 3.229295] virtio_blk virtio1: [vda] 145960 512-byte logical blocks (74.7 MB/71.3 MiB) [ 3.232258] vda: detected capacity change from 0 to 74731520 [ 3.255129] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.257798] vdb: detected capacity change from 0 to 1073741824 [ 3.275031] virtio_blk virtio3: [vdc] 5120000 512-byte logical blocks (2.62 GB/2.44 GiB) [ 3.278703] vdc: detected capacity change from 0 to 2621440000 [ 3.294793] virtio_blk virtio4: [vdd] 5120000 512-byte logical blocks (2.62 GB/2.44 GiB) [ 3.297905] vdd: detected capacity change from 0 to 2621440000 [ 3.316612] virtio_blk virtio5: [vde] 8388608 512-byte logical blocks (4.29 GB/4.00 GiB) [ 3.320044] vde: detected capacity change from 0 to 4294967296 [ 3.340165] virtio_blk virtio6: [vdf] 8388608 512-byte logical blocks (4.29 GB/4.00 GiB) [ 3.342614] vdf: detected capacity change from 0 to 4294967296 [ 3.349282] libphy: Fixed MDIO Bus: probed [ 3.360951] usbcore: registered new interface driver usbserial_generic [ 3.363344] usbserial: USB Serial support registered for generic [ 3.365965] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.369483] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.371095] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.373498] mousedev: PS/2 mouse device common for all mice [ 3.376626] rtc_cmos 00:05: RTC can wake from S4 [ 3.379429] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.380879] rtc_cmos 00:05: registered as rtc0 [ 3.387431] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.391601] intel_pstate: CPU model not supported [ 3.394275] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.398475] hid: raw HID events driver (C) Jiri Kosina [ 3.402325] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.404363] usbcore: registered new interface driver usbhid [ 3.409639] usbhid: USB HID core driver [ 3.411421] drop_monitor: Initializing network drop monitor service [ 3.413931] Initializing XFRM netlink socket [ 3.416189] NET: Registered protocol family 10 [ 3.419425] Segment Routing with IPv6 [ 3.421068] NET: Registered protocol family 17 [ 3.422976] mpls_gso: MPLS GSO support [ 3.429626] RAS: Correctable Errors collector initialized. [ 3.431452] AVX version of gcm_enc/dec engaged. [ 3.432904] AES CTR mode by8 optimization enabled [ 3.522262] sched_clock: Marking stable (3522228665, 0)->(4396052576, -873823911) [ 3.525131] registered taskstats version 1 [ 3.527595] Loading compiled-in X.509 certificates [ 3.529862] zswap: loaded using pool lzo/zbud [ 3.555652] Key type big_key registered [ 3.568881] Key type encrypted registered [ 3.570457] ima: No TPM chip found, activating TPM-bypass! [ 3.572717] ima: Allocated hash algorithm: sha1 [ 3.574590] ima: No architecture policies found [ 3.576219] evm: Initialising EVM extended attributes: [ 3.577875] evm: security.selinux [ 3.579219] evm: security.ima [ 3.580432] evm: security.capability [ 3.581988] evm: HMAC attrs: 0x1 [ 3.584758] rtc_cmos 00:05: setting system clock to 2026-09-08 21:33:09 UTC (1788903189) [ 3.592724] debug: unmapping init [mem 0xffffffffb5603000-0xffffffffb57fffff] [ 3.596388] debug: unmapping init [mem 0xffffffffb4382000-0xffffffffb4658fff] [ 3.605103] Write protecting the kernel read-only data: 28672k [ 3.607969] debug: unmapping init [mem 0xffffffffb2a03000-0xffffffffb2bfffff] [ 3.610808] debug: unmapping init [mem 0xffffffffb3314000-0xffffffffb33fffff] [ 3.646984] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 3.656509] systemd[1]: Detected virtualization kvm. [ 3.658477] systemd[1]: Detected architecture x86-64. [ 3.660470] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.685440] systemd[1]: No hostname configured. [ 3.687317] systemd[1]: Set hostname to . [ 3.689612] random: systemd: uninitialized urandom read (16 bytes read) [ 3.692194] systemd[1]: Initializing machine ID from random generator. [ 3.742601] random: ln: uninitialized urandom read (6 bytes read) [ 3.820887] random: systemd: uninitialized urandom read (16 bytes read) [ 3.823735] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ 3.829442] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. [ 3.834244] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ OK ] Started Memstrack Anylazing Service. Starting Apply Kernel Variables... Starting Create list of required st…ce nodes for the current kernel... Starting Setup Virtual Console... Starting Journal Service... [ OK ] Reached target Timers. [ OK ] Reached target Swap. [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Listening on udev Control Socket. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Initrd Root Device. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Setup Virtual Console. [ OK ] Started Create Volatile Files and Directories. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ 4.018366] random: fast init done [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.424965] device-mapper: uevent: version 1.0.3 [ 4.427731] 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... [ 5.184609] virtio_net virtio0 ens2: renamed from eth0 [ 5.219776] scsi host0: ata_piix [ 5.314104] scsi host1: ata_piix [ 5.316105] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc320 irq 14 [ 5.319778] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc328 irq 15 [ 8.864178] dracut-initqueue[583]: RTNETLINK answers: File exists [ 10.087305] random: crng init done [ 10.089543] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 10.424250] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Timers. [ 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 Slices. [ OK ] Stopped target Sockets. [ OK ] Stopped target Paths. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Swap. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ 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... [ 11.569246] printk: systemd: 26 output lines suppressed due to ratelimiting [ 11.856758] SELinux: Disabled at runtime. [ 11.916089] 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) [ 11.926380] systemd[1]: Detected virtualization kvm. [ 11.928520] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 12.449404] systemd[1]: initrd-switch-root.service: Succeeded. [ 12.454449] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 12.459269] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 12.464360] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 12.468134] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 12.475946] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.480410] systemd[1]: Created slice User and Session Slice. [ OK ] Created slice User and Session Slice. [ OK ] Listening on RPCbind Server Activation Socket. Starting Create list of required st…ce nodes for the current kernel... Starting Remount Root and Kernel File Systems... [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Listening on Process Core Dump Socket. Starting Apply Kernel Variables... [ OK ] Created slice system-getty.slice. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Reached target rpc_pipefs.target. Mounting POSIX Message Queue File System... [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Listening on udev Kernel Socket. Mounting Huge Pages File System... [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd Root File System. [ OK ] Stopped target Initrd File Systems. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Slices. Mounting Kernel Debug File System... Activating swap /dev/disk/by-label/SWAP... [ OK ] Reached target RPC Port Mapper. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ 12.669713] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Started Journal Service. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Mounted Huge Pages File System. [ OK ] Mounted Kernel Debug File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Reached target Swap. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... Starting udev Kernel Device Manager... [ OK ] Started udev Coldplug all Devices. [ OK ] Mounted /mnt. [ 12.941353] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 13.235801] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 13.245501] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 13.347541] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 13.376441] EDAC sbridge: Ver: 1.1.2 [ 14.998619] Key type dns_resolver registered [ 15.300512] NFS: Registering the id_resolver key type [ 15.302439] Key type id_resolver registered [ 15.303922] Key type id_legacy registered [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... 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 dnf makecache --timer. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started D-Bus System Message Bus. Starting Login Service... Starting Network Manager... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Restore /run/initramfs on shutdown... [ OK ] Started irqbalance daemon. [ OK ] Reached target sshd-keygen.target. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Timers. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... Starting OpenSSH server daemon... Starting Hostname Service... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started OpenSSH server daemon. [ OK ] Started Permit User Sessions. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Getty on tty1. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS1. [ 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 Notify NFS peers of a restart... Starting Crash recovery kernel arming... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started Authorization Manager. [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg447-server login: [ 42.250499] libcfs: loading out-of-tree module taints kernel. [ 42.275991] Key type ._llcrypt registered [ 42.277738] Key type .llcrypt registered [ 42.335843] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing set_hostid [ 59.516425] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing load_modules_local [ 61.614742] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 61.639200] alg: No test for adler32 (adler32-zlib) [ 63.162347] Lustre: Lustre: Build Version: 2.17.55_26_gfb964ff [ 64.322932] LNet: Added LNI 192.168.204.147@tcp [8/256/0/180] [ 66.183968] Key type lgssc registered [ 68.055077] Lustre: Echo OBD driver; http://www.lustre.org/ [ 74.227890] hrtimer: interrupt took 6901575 ns [ 85.277521] ZFS: Loaded module v2.3.2-1, ZFS pool version 5000, ZFS filesystem version 5 [ 125.069409] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing load_modules_local [ 142.272423] Lustre: lustre-MDT0000: mounting server target with '-t lustre' deprecated, use '-t lustre_tgt' [ 142.342423] LDISKFS-fs (dm-0): mounted filesystem with ordered data mode. Opts: user_xattr,errors=remount-ro,no_mbcache,nodelalloc [ 143.713133] Lustre: Setting parameter lustre-MDT0000.mdt.identity_upcall=/home/green/git/lustre-release/lustre/utils/l_getidentity in log lustre-MDT0000 [ 143.752857] Lustre: ctl-lustre-MDT0000: No data found on store. Initialize space. [ 143.843993] Lustre: lustre-MDT0000: new disk, initializing [ 143.931789] Lustre: lustre-MDT0000: Imperative Recovery not enabled, recovery window 60-180 [ 143.952708] Lustre: ctl-lustre-MDT0000: super-sequence allocation rc = 0 [0x0000000200000400-0x0000000240000400]:0:mdt [ 149.038771] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing set_default_debug vfstrace rpctrace dlmtrace neterror ha config ioctl super lfsck all [ 162.992130] LDISKFS-fs (dm-1): mounted filesystem with ordered data mode. Opts: user_xattr,errors=remount-ro,no_mbcache,nodelalloc [ 163.111599] Lustre: 6509:0:(mgs_llog.c:1450:mgs_modify_param()) MGS: modify lustre-MDT0001/mdt.identity_upcall=/home/green/git/lustre-release/lustre/utils/l_getidentity (mode = 0) failed: rc = -17 [ 163.142325] Lustre: srv-lustre-MDT0001: No data found on store. Initialize space. [ 163.151562] Lustre: Skipped 1 previous similar message [ 163.258667] Lustre: lustre-MDT0001: new disk, initializing [ 163.355555] Lustre: lustre-MDT0001: Imperative Recovery not enabled, recovery window 60-180 [ 163.408236] Lustre: ctl-lustre-MDT0000: super-sequence allocation rc = 0 [0x0000000240000400-0x0000000280000400]:1:mdt [ 163.424841] Lustre: cli-ctl-lustre-MDT0001: Allocated super-sequence [0x0000000240000400-0x0000000280000400]:1:mdt] [ 169.357748] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing set_default_debug vfstrace rpctrace dlmtrace neterror ha config ioctl super lfsck all [ 174.615701] Lustre: Modifying parameter general.debug_raw_pointers=Y in log params [ 184.414735] LDISKFS-fs (dm-2): mounted filesystem with ordered data mode. Opts: errors=remount-ro,no_mbcache,nodelalloc [ 185.024214] Lustre: lustre-OST0000: new disk, initializing [ 185.038648] Lustre: srv-lustre-OST0000: No data found on store. Initialize space. [ 185.049897] Lustre: 8448:0:(osd_compat.c:1352:osd_object_spec_find()) UNKNOWN COMPAT FID [0x200000001:0x101e:0x0] [ 185.259644] Lustre: lustre-OST0000: Imperative Recovery not enabled, recovery window 60-180 [ 186.913208] Lustre: ctl-lustre-MDT0000: super-sequence allocation rc = 0 [0x0000000280000400-0x00000002c0000400]:0:ost [ 186.928502] Lustre: cli-lustre-OST0000-super: Allocated super-sequence [0x0000000280000400-0x00000002c0000400]:0:ost] [ 187.035483] Lustre: lustre-OST0000-osc-MDT0000: update sequence from 0x100000000 to 0x280000401 [ 192.107075] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing set_default_debug vfstrace rpctrace dlmtrace neterror ha config ioctl super lfsck all [ 210.601491] LDISKFS-fs (dm-3): mounted filesystem with ordered data mode. Opts: errors=remount-ro,no_mbcache,nodelalloc [ 210.798052] Lustre: lustre-OST0001: new disk, initializing [ 210.815905] Lustre: srv-lustre-OST0001: No data found on store. Initialize space. [ 210.839421] Lustre: 9520:0:(osd_compat.c:1352:osd_object_spec_find()) UNKNOWN COMPAT FID [0x200000001:0x101e:0x0] [ 210.937505] Lustre: lustre-OST0001: Imperative Recovery not enabled, recovery window 60-180 [ 218.621106] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing set_default_debug vfstrace rpctrace dlmtrace neterror ha config ioctl super lfsck all [ 220.171000] Lustre: ctl-lustre-MDT0000: super-sequence allocation rc = 0 [0x00000002c0000400-0x0000000300000400]:1:ost [ 220.183244] Lustre: cli-lustre-OST0001-super: Allocated super-sequence [0x00000002c0000400-0x0000000300000400]:1:ost] [ 220.256177] Lustre: lustre-OST0001-osc-MDT0000: update sequence from 0x100010000 to 0x2c0000401 [ 229.963217] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 235.022599] Lustre: Setting parameter general.lod.*.mdt_hash=crush in log params [ 240.850165] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing check_logdir /tmp/testlogs/ [ 245.747735] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing yml_node [ 249.708974] Lustre: DEBUG MARKER: Client: 2.17.55.26 [ 251.733119] Lustre: DEBUG MARKER: MDS: 2.17.55.26 [ 253.597888] Lustre: DEBUG MARKER: OSS: 2.17.55.26 [ 254.954765] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Tue Sep 8 17:37:20 EDT 2026 [ 269.169270] Lustre: DEBUG MARKER: excepting tests: 32newtarball [ 270.747509] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 286.177383] LustreError: lustre-MDT0000-osp-MDT0001: operation mds_statfs to node 0@lo failed: rc = -107 [ 286.181210] Lustre: lustre-MDT0000-osp-MDT0001: Connection to lustre-MDT0000 (at 0@lo) was lost; in progress operations using this service will wait for recovery to complete [ 286.189571] Lustre: lustre-MDT0000: Not available for connect from 0@lo (stopping) [ 286.690989] Lustre: lustre-MDT0000-lwp-OST0000: Connection to lustre-MDT0000 (at 0@lo) was lost; in progress operations using this service will wait for recovery to complete [ 286.696477] Lustre: lustre-MDT0000: Not available for connect from 0@lo (stopping) [ 286.706066] Lustre: Skipped 2 previous similar messages [ 286.712557] Lustre: Skipped 1 previous similar message [ 291.383950] Lustre: server umount lustre-MDT0000 complete [ 293.229975] LustreError: 6501:0:(ldlm_lockd.c:2564:ldlm_cancel_handler()) ldlm_cancel from 0@lo arrived at 1788903479 with bad export cookie 1659967660531486661 [ 293.231361] LustreError: MGC192.168.204.147@tcp: Connection to MGS (at 0@lo) was lost; in progress operations using this service will fail [ 293.237072] LustreError: 6501:0:(ldlm_lockd.c:2564:ldlm_cancel_handler()) Skipped 3 previous similar messages [ 296.927723] LustreError: 6515:0:(ldlm_lib.c:1192:target_handle_connect()) lustre-MDT0000: not available for connect from 0@lo (no target). If you are running an HA pair check that the target is mounted on the other server. [ 296.927923] Lustre: lustre-MDT0001-lwp-OST0001: Connection to lustre-MDT0001 (at 0@lo) was lost; in progress operations using this service will wait for recovery to complete [ 296.928329] Lustre: lustre-MDT0001: Not available for connect from 0@lo (stopping) [ 296.928334] Lustre: Skipped 1 previous similar message [ 296.936082] LustreError: 6515:0:(ldlm_lib.c:1192:target_handle_connect()) Skipped 6 previous similar messages [ 296.944423] Lustre: Skipped 1 previous similar message [ 298.977605] Lustre: lustre-MDT0001: Not available for connect from 0@lo (stopping) [ 298.977603] LustreError: 6516:0:(ldlm_lib.c:1192:target_handle_connect()) lustre-MDT0000: not available for connect from 0@lo (no target). If you are running an HA pair check that the target is mounted on the other server. [ 298.988198] Lustre: Skipped 2 previous similar messages [ 299.470769] Lustre: server umount lustre-MDT0001 complete [ 301.643144] Lustre: server umount lustre-OST0000 complete [ 303.845935] Lustre: server umount lustre-OST0001 complete [ 307.179898] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing set_hostid [ 312.179779] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing load_modules_local [ 332.165252] LDISKFS-fs (dm-0): mounted filesystem with ordered data mode. Opts: user_xattr,errors=remount-ro,no_mbcache,nodelalloc [ 332.335568] Lustre: Setting parameter lustre-MDT0000.mdt.identity_upcall=/home/green/git/lustre-release/lustre/utils/l_getidentity in log lustre-MDT0000 [ 332.363349] Lustre: ctl-lustre-MDT0000: No data found on store. Initialize space. [ 332.422220] Lustre: lustre-MDT0000: new disk, initializing [ 332.472434] Lustre: lustre-MDT0000: Imperative Recovery not enabled, recovery window 60-180 [ 332.484510] Lustre: ctl-lustre-MDT0000: super-sequence allocation rc = 0 [0x0000000200000400-0x0000000240000400]:0:mdt [ 334.895307] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing set_default_debug -1 all [ 341.066123] LDISKFS-fs (dm-1): mounted filesystem with ordered data mode. Opts: user_xattr,errors=remount-ro,no_mbcache,nodelalloc [ 341.143641] Lustre: 15064:0:(mgs_llog.c:1450:mgs_modify_param()) MGS: modify lustre-MDT0001/mdt.identity_upcall=/home/green/git/lustre-release/lustre/utils/l_getidentity (mode = 0) failed: rc = -17 [ 341.187612] Lustre: srv-lustre-MDT0001: No data found on store. Initialize space. [ 341.190615] Lustre: Skipped 1 previous similar message [ 341.241212] Lustre: lustre-MDT0001: new disk, initializing [ 341.304291] Lustre: lustre-MDT0001: Imperative Recovery not enabled, recovery window 60-180 [ 341.331940] Lustre: ctl-lustre-MDT0000: super-sequence allocation rc = 0 [0x0000000240000400-0x0000000280000400]:1:mdt [ 341.337501] Lustre: cli-ctl-lustre-MDT0001: Allocated super-sequence [0x0000000240000400-0x0000000280000400]:1:mdt] [ 343.358200] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing set_default_debug -1 all [ 348.263441] Lustre: DEBUG MARKER: oleg447-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 350.983291] Lustre: DEBUG MARKER: oleg447-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 354.220779] LDISKFS-fs (dm-2): mounted filesystem with ordered data mode. Opts: errors=remount-ro,no_mbcache,nodelalloc [ 354.370156] Lustre: 17034:0:(osd_compat.c:1352:osd_object_spec_find()) UNKNOWN COMPAT FID [0x200000001:0x101e:0x0] [ 355.462620] Lustre: cli-lustre-OST0000-super: Allocated super-sequence [0x0000000280000400-0x00000002c0000400]:0:ost] [ 355.532337] Lustre: lustre-OST0000-osc-MDT0000: update sequence from 0x100000000 to 0x280000401 [ 357.170262] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing set_default_debug -1 all [ 361.900454] Lustre: DEBUG MARKER: oleg447-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 363.712396] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing wait_import_state FULL os[cp].lustre-OST0000-osc-MDT0000.ost_server_uuid 50 [ 363.806042] Lustre: DEBUG MARKER: os[cp].lustre-OST0000-osc-MDT0000.ost_server_uuid in FULL state after 0 sec [ 365.563177] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing wait_import_state FULL os[cp].lustre-OST0000-osc-MDT0001.ost_server_uuid 50 [ 365.668212] Lustre: DEBUG MARKER: os[cp].lustre-OST0000-osc-MDT0001.ost_server_uuid in FULL state after 0 sec [ 371.169415] LustreError: lustre-OST0000-osc-MDT0001: operation ost_statfs to node 0@lo failed: rc = -107 [ 371.169863] Lustre: lustre-OST0000-osc-MDT0000: Connection to lustre-OST0000 (at 0@lo) was lost; in progress operations using this service will wait for recovery to complete [ 371.173037] LustreError: Skipped 1 previous similar message [ 371.173781] Lustre: lustre-OST0000: Not available for connect from 0@lo (stopping) [ 371.179192] Lustre: Skipped 1 previous similar message [ 372.661128] Lustre: server umount lustre-OST0000 complete [ 376.290080] Lustre: lustre-MDT0000-osp-MDT0001: Connection to lustre-MDT0000 (at 0@lo) was lost; in progress operations using this service will wait for recovery to complete [ 380.674745] Lustre: server umount lustre-MDT0000 complete [ 381.408891] LustreError: 15075:0:(ldlm_lib.c:1192:target_handle_connect()) lustre-MDT0000: not available for connect from 0@lo (no target). If you are running an HA pair check that the target is mounted on the other server. [ 381.419262] LustreError: 15075:0:(ldlm_lib.c:1192:target_handle_connect()) Skipped 2 previous similar messages [ 382.631592] LustreError: 15055:0:(ldlm_lockd.c:2564:ldlm_cancel_handler()) ldlm_cancel from 0@lo arrived at 1788903568 with bad export cookie 1659967660531488194 [ 382.633122] LustreError: MGC192.168.204.147@tcp: Connection to MGS (at 0@lo) was lost; in progress operations using this service will fail [ 382.638987] LustreError: 15055:0:(ldlm_lockd.c:2564:ldlm_cancel_handler()) Skipped 2 previous similar messages [ 382.795457] Lustre: server umount lustre-MDT0001 complete [ 385.220215] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 17:39:31 (1788903571) [ 386.924975] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing load_modules_local [ 399.742393] LDISKFS-fs (dm-0): mounted filesystem with ordered data mode. Opts: user_xattr,errors=remount-ro,no_mbcache,nodelalloc [ 399.868820] Lustre: MGS: Logs for fs lustre were removed by user request. All servers must be restarted in order to regenerate the logs: rc = 0 [ 399.879125] Lustre: Setting parameter lustre-MDT0000.mdt.identity_upcall=/home/green/git/lustre-release/lustre/utils/l_getidentity in log lustre-MDT0000 [ 399.957283] Lustre: lustre-MDT0000: Imperative Recovery not enabled, recovery window 60-180 [ 399.960717] Lustre: Skipped 1 previous similar message [ 401.664452] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing set_default_debug -1 all [ 405.385927] LDISKFS-fs (dm-1): mounted filesystem with ordered data mode. Opts: user_xattr,errors=remount-ro,no_mbcache,nodelalloc [ 405.448435] Lustre: MGS: Regenerating lustre-MDT0001 log by user request: rc = 0 [ 405.461627] Lustre: 20640:0:(mgs_llog.c:1450:mgs_modify_param()) MGS: modify lustre-MDT0001/mdt.identity_upcall=/home/green/git/lustre-release/lustre/utils/l_getidentity (mode = 0) failed: rc = -17 [ 407.254577] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing set_default_debug -1 all [ 410.723915] Lustre: DEBUG MARKER: oleg447-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 413.324664] Lustre: DEBUG MARKER: oleg447-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 429.605350] LDISKFS-fs (dm-2): mounted filesystem with ordered data mode. Opts: errors=remount-ro,no_mbcache,nodelalloc [ 429.730503] Lustre: MGS: Regenerating lustre-OST0000 log by user request: rc = 0 [ 432.398744] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing set_default_debug -1 all [ 436.001631] Lustre: DEBUG MARKER: oleg447-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 437.217992] LustreError: lustre-OST0000-osc-MDT0000: operation ost_statfs to node 0@lo failed: rc = -107 [ 437.220136] Lustre: lustre-OST0000-osc-MDT0001: Connection to lustre-OST0000 (at 0@lo) was lost; in progress operations using this service will wait for recovery to complete [ 437.225147] LustreError: Skipped 1 previous similar message [ 437.226171] Lustre: lustre-OST0000: Not available for connect from 0@lo (stopping) [ 437.232927] Lustre: Skipped 2 previous similar messages [ 437.237296] Lustre: Skipped 3 previous similar messages [ 439.148158] Lustre: server umount lustre-OST0000 complete [ 441.312430] LustreError: lustre-MDT0000-osp-MDT0001: operation mds_statfs to node 0@lo failed: rc = -107 [ 455.135400] Lustre: lustre-MDT0000 is waiting for obd_unlinked_exports more than 8 seconds. The obd refcount = 3. Is it stuck? [ 455.249422] Lustre: server umount lustre-MDT0000 complete [ 457.112422] LustreError: 21381:0:(ldlm_lockd.c:2564:ldlm_cancel_handler()) ldlm_cancel from 0@lo arrived at 1788903643 with bad export cookie 1659967660531489076 [ 457.115765] LustreError: MGC192.168.204.147@tcp: Connection to MGS (at 0@lo) was lost; in progress operations using this service will fail [ 457.121087] LustreError: 21381:0:(ldlm_lockd.c:2564:ldlm_cancel_handler()) Skipped 4 previous similar messages [ 457.301745] Lustre: server umount lustre-MDT0001 complete [ 462.701735] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing unload_modules_local [ 464.245592] Key type lgssc unregistered [ 464.446942] LNet: 24208:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 464.455747] LNetError: 24208:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 464.469530] LNet: Removed LNI 192.168.204.147@tcp [ 464.993340] Key type .llcrypt unregistered [ 464.996764] Key type ._llcrypt unregistered [ 475.722593] Key type ._llcrypt registered [ 475.724188] Key type .llcrypt registered [ 475.770349] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing unload_modules_local [ 475.911538] Key type .llcrypt unregistered [ 475.915963] Key type ._llcrypt unregistered [ 483.857235] Key type ._llcrypt registered [ 483.858611] Key type .llcrypt registered [ 483.911994] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing load_modules_local [ 484.340143] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 484.364211] alg: No test for adler32 (adler32-zlib) [ 485.249132] Lustre: Lustre: Build Version: 2.17.55_26_gfb964ff [ 485.356764] LNet: Added LNI 192.168.204.147@tcp [8/256/0/180] [ 486.959110] Key type lgssc registered [ 487.405973] Lustre: Echo OBD driver; http://www.lustre.org/ [ 507.397830] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing lnet_if_list [ 507.947500] LNet: Added LNI 192.168.204.147@tcp1 [8/256/0/180] [ 512.842478] loop: module loaded [ 512.865502] loop0: detected capacity change from 0 to 204800000 [ 512.987865] blk_update_request: operation not supported error, dev loop0, sector 399872 op 0x9:(WRITE_ZEROES) flags 0x400800 phys_seg 0 prio class 0 [ 512.993524] blk_update_request: operation not supported error, dev loop0, sector 8224 op 0x9:(WRITE_ZEROES) flags 0x400800 phys_seg 0 prio class 0 [ 513.000385] blk_update_request: operation not supported error, dev loop0, sector 58264 op 0x9:(WRITE_ZEROES) flags 0x400800 phys_seg 0 prio class 0 [ 526.466153] loop0: detected capacity change from 0 to 204800000 [ 526.536228] blk_update_request: operation not supported error, dev loop0, sector 399872 op 0x9:(WRITE_ZEROES) flags 0x400800 phys_seg 0 prio class 0 [ 526.541347] blk_update_request: operation not supported error, dev loop0, sector 8224 op 0x9:(WRITE_ZEROES) flags 0x400800 phys_seg 0 prio class 0 [ 526.546416] blk_update_request: operation not supported error, dev loop0, sector 58264 op 0x9:(WRITE_ZEROES) flags 0x400800 phys_seg 0 prio class 0 [ 540.358747] Lustre: lustre-MDT0000: mounting server target with '-t lustre' deprecated, use '-t lustre_tgt' [ 540.371377] LDISKFS-fs (dm-0): mounted filesystem with ordered data mode. Opts: user_xattr,errors=remount-ro,no_mbcache,nodelalloc [ 541.489205] Lustre: MGS: Logs for fs lustre were removed by user request. All servers must be restarted in order to regenerate the logs: rc = 0 [ 541.502150] Lustre: Setting parameter lustre-MDT0000.mdt.identity_upcall=/home/green/git/lustre-release/lustre/utils/l_getidentity in log lustre-MDT0000 [ 541.578804] Lustre: lustre-MDT0000: Imperative Recovery not enabled, recovery window 60-180 [ 543.222678] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing set_default_debug -1 all [ 546.797262] LDISKFS-fs (dm-1): mounted filesystem with ordered data mode. Opts: user_xattr,errors=remount-ro,no_mbcache,nodelalloc [ 546.848856] Lustre: MGS: Regenerating lustre-MDT0001 log by user request: rc = 0 [ 546.866739] Lustre: 28106:0:(mgs_llog.c:1450:mgs_modify_param()) MGS: modify lustre-MDT0001/mdt.identity_upcall=/home/green/git/lustre-release/lustre/utils/l_getidentity (mode = 0) failed: rc = -17 [ 546.992655] Lustre: lustre-MDT0001: Imperative Recovery not enabled, recovery window 60-180 [ 548.661209] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing set_default_debug -1 all [ 551.800582] Lustre: DEBUG MARKER: oleg447-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 554.052337] Lustre: DEBUG MARKER: oleg447-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 556.118271] loop0: detected capacity change from 0 to 204800000 [ 571.617167] LDISKFS-fs (dm-4): mounted filesystem with ordered data mode. Opts: errors=remount-ro,no_mbcache,nodelalloc [ 571.718803] Lustre: sv6238-OST0000: new disk, initializing [ 571.721382] Lustre: srv-sv6238-OST0000: No data found on store. Initialize space. [ 571.725411] Lustre: 30181:0:(osd_compat.c:1352:osd_object_spec_find()) UNKNOWN COMPAT FID [0x200000001:0x101e:0x0] [ 571.749287] Lustre: sv6238-OST0000: Imperative Recovery not enabled, recovery window 60-180 [ 573.975342] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing set_default_debug -1 all [ 578.340106] loop1: detected capacity change from 0 to 204800000 [ 593.702179] LDISKFS-fs (dm-5): mounted filesystem with ordered data mode. Opts: errors=remount-ro,no_mbcache,nodelalloc [ 593.764471] Lustre: sv6238-OST0001: new disk, initializing [ 593.766880] Lustre: srv-sv6238-OST0001: No data found on store. Initialize space. [ 593.771374] Lustre: 31298:0:(osd_compat.c:1352:osd_object_spec_find()) UNKNOWN COMPAT FID [0x200000001:0x101e:0x0] [ 593.800642] Lustre: sv6238-OST0001: Imperative Recovery not enabled, recovery window 60-180 [ 595.956144] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing set_default_debug -1 all [ 601.635147] Lustre: server umount sv6238-OST0001 complete [ 605.573624] Lustre: server umount sv6238-OST0000 complete [ 612.320379] Lustre: lustre-MDT0000-osp-MDT0001: Connection to lustre-MDT0000 (at 0@lo) was lost; in progress operations using this service will wait for recovery to complete [ 612.321320] Lustre: lustre-MDT0000: Not available for connect from 0@lo (stopping) [ 612.325286] Lustre: Skipped 1 previous similar message [ 616.746848] Lustre: server umount lustre-MDT0000 complete [ 618.170468] LustreError: 28096:0:(ldlm_lockd.c:2564:ldlm_cancel_handler()) ldlm_cancel from 0@lo arrived at 1788903804 with bad export cookie 13685893583718082602 [ 618.172894] LustreError: MGC192.168.204.147@tcp: Connection to MGS (at 0@lo) was lost; in progress operations using this service will fail [ 618.175361] LustreError: 28096:0:(ldlm_lockd.c:2564:ldlm_cancel_handler()) Skipped 1 previous similar message [ 618.313043] Lustre: server umount lustre-MDT0001 complete [ 623.593274] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing unload_modules_local [ 624.777907] Key type lgssc unregistered [ 624.905398] LNet: 34048:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 624.908458] LNetError: 34048:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 624.910580] LNet: Removed LNI 192.168.204.147@tcp [ 625.957377] LNet: Removed LNI 192.168.204.147@tcp1 [ 626.313253] Key type .llcrypt unregistered [ 626.314581] Key type ._llcrypt unregistered [ 632.999926] Key type ._llcrypt registered [ 633.001359] Key type .llcrypt registered [ 633.048333] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing unload_modules_local [ 633.162243] Key type .llcrypt unregistered [ 633.163671] Key type ._llcrypt unregistered [ 639.609233] Key type ._llcrypt registered [ 639.610128] Key type .llcrypt registered [ 639.653233] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing load_modules_local [ 640.119508] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 640.131720] alg: No test for adler32 (adler32-zlib) [ 640.974304] Lustre: Lustre: Build Version: 2.17.55_26_gfb964ff [ 641.063845] LNet: Added LNI 192.168.204.147@tcp [8/256/0/180] [ 642.639145] Key type lgssc registered [ 642.984449] Lustre: Echo OBD driver; http://www.lustre.org/ [ 649.732044] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing lnet_if_list [ 650.139790] LNet: Added LNI 192.168.204.147@tcp1 [8/256/0/180] [ 653.336952] loop0: detected capacity change from 0 to 204800000 [ 653.453349] blk_update_request: operation not supported error, dev loop0, sector 399872 op 0x9:(WRITE_ZEROES) flags 0x400800 phys_seg 0 prio class 0 [ 653.457580] blk_update_request: operation not supported error, dev loop0, sector 8224 op 0x9:(WRITE_ZEROES) flags 0x400800 phys_seg 0 prio class 0 [ 653.462044] blk_update_request: operation not supported error, dev loop0, sector 58264 op 0x9:(WRITE_ZEROES) flags 0x400800 phys_seg 0 prio class 0 [ 666.313411] loop0: detected capacity change from 0 to 204800000 [ 666.386908] blk_update_request: operation not supported error, dev loop0, sector 399872 op 0x9:(WRITE_ZEROES) flags 0x400800 phys_seg 0 prio class 0 [ 666.390536] blk_update_request: operation not supported error, dev loop0, sector 8224 op 0x9:(WRITE_ZEROES) flags 0x400800 phys_seg 0 prio class 0 [ 666.394875] blk_update_request: operation not supported error, dev loop0, sector 58264 op 0x9:(WRITE_ZEROES) flags 0x400800 phys_seg 0 prio class 0 [ 679.665820] Lustre: lustre-MDT0000: mounting server target with '-t lustre' deprecated, use '-t lustre_tgt' [ 679.671415] LDISKFS-fs (dm-0): mounted filesystem with ordered data mode. Opts: user_xattr,errors=remount-ro,no_mbcache,nodelalloc [ 680.751490] Lustre: MGS: Logs for fs lustre were removed by user request. All servers must be restarted in order to regenerate the logs: rc = 0 [ 680.757430] Lustre: Setting parameter lustre-MDT0000.mdt.identity_upcall=/home/green/git/lustre-release/lustre/utils/l_getidentity in log lustre-MDT0000 [ 680.805891] Lustre: lustre-MDT0000: Imperative Recovery not enabled, recovery window 60-180 [ 682.053714] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing set_default_debug -1 all [ 684.718845] LDISKFS-fs (dm-1): mounted filesystem with ordered data mode. Opts: user_xattr,errors=remount-ro,no_mbcache,nodelalloc [ 684.747027] Lustre: MGS: Regenerating lustre-MDT0001 log by user request: rc = 0 [ 684.757515] Lustre: 37843:0:(mgs_llog.c:1450:mgs_modify_param()) MGS: modify lustre-MDT0001/mdt.identity_upcall=/home/green/git/lustre-release/lustre/utils/l_getidentity (mode = 0) failed: rc = -17 [ 684.843723] Lustre: lustre-MDT0001: Imperative Recovery not enabled, recovery window 60-180 [ 686.090972] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing set_default_debug -1 all [ 688.387734] Lustre: DEBUG MARKER: oleg447-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 690.134963] Lustre: DEBUG MARKER: oleg447-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 691.753403] loop0: detected capacity change from 0 to 204800000 [ 706.593140] LDISKFS-fs (dm-4): mounted filesystem with ordered data mode. Opts: errors=remount-ro,no_mbcache,nodelalloc [ 706.687563] Lustre: sv14351-OST0000: new disk, initializing [ 706.689223] Lustre: srv-sv14351-OST0000: No data found on store. Initialize space. [ 706.691632] Lustre: 39920:0:(osd_compat.c:1352:osd_object_spec_find()) UNKNOWN COMPAT FID [0x200000001:0x101e:0x0] [ 706.711290] Lustre: sv14351-OST0000: Imperative Recovery not enabled, recovery window 60-180 [ 708.698533] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing set_default_debug -1 all [ 712.479196] loop1: detected capacity change from 0 to 204800000 [ 727.265046] LDISKFS-fs (dm-5): mounted filesystem with ordered data mode. Opts: errors=remount-ro,no_mbcache,nodelalloc [ 727.307919] Lustre: sv14351-OST0001: new disk, initializing [ 727.309473] Lustre: srv-sv14351-OST0001: No data found on store. Initialize space. [ 727.311955] Lustre: 41036:0:(osd_compat.c:1352:osd_object_spec_find()) UNKNOWN COMPAT FID [0x200000001:0x101e:0x0] [ 727.330412] Lustre: sv14351-OST0001: Imperative Recovery not enabled, recovery window 60-180 [ 729.212382] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing set_default_debug -1 all [ 734.001038] Lustre: server umount sv14351-OST0001 complete [ 736.963173] Lustre: server umount sv14351-OST0000 complete [ 741.344501] LustreError: lustre-MDT0000-osp-MDT0001: operation mds_statfs to node 0@lo failed: rc = -107 [ 741.347670] Lustre: lustre-MDT0000-osp-MDT0001: Connection to lustre-MDT0000 (at 0@lo) was lost; in progress operations using this service will wait for recovery to complete [ 741.353139] Lustre: lustre-MDT0000: Not available for connect from 0@lo (stopping) [ 741.856659] Lustre: lustre-MDT0000-lwp-MDT0001: Connection to lustre-MDT0000 (at 0@lo) was lost; in progress operations using this service will wait for recovery to complete [ 741.861079] Lustre: lustre-MDT0000: Not available for connect from 0@lo (stopping) [ 746.976332] Lustre: lustre-MDT0000: Not available for connect from 0@lo (stopping) [ 746.978682] Lustre: Skipped 1 previous similar message [ 747.091764] Lustre: server umount lustre-MDT0000 complete [ 748.275247] LustreError: 38585:0:(ldlm_lockd.c:2564:ldlm_cancel_handler()) ldlm_cancel from 0@lo arrived at 1788903934 with bad export cookie 7911076984531529503 [ 748.277641] LustreError: MGC192.168.204.147@tcp: Connection to MGS (at 0@lo) was lost; in progress operations using this service will fail [ 748.278705] LustreError: 38585:0:(ldlm_lockd.c:2564:ldlm_cancel_handler()) Skipped 2 previous similar messages [ 748.382427] Lustre: server umount lustre-MDT0001 complete [ 752.788657] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing unload_modules_local [ 753.863175] Key type lgssc unregistered [ 753.960977] LNet: 1 peer NIs in recovery (showing 1): 192.168.204.47@tcp [ 753.984319] LNet: 43783:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 753.986514] LNetError: 43783:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 753.989373] LNet: Removed LNI 192.168.204.147@tcp [ 755.045280] LNet: Removed LNI 192.168.204.147@tcp1 [ 755.348657] Key type .llcrypt unregistered [ 755.349740] Key type ._llcrypt unregistered [ 761.127985] Key type ._llcrypt registered [ 761.129052] Key type .llcrypt registered [ 761.165746] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing unload_modules_local [ 761.284452] Key type .llcrypt unregistered [ 761.285810] Key type ._llcrypt unregistered [ 767.456530] Key type ._llcrypt registered [ 767.457618] Key type .llcrypt registered [ 767.494307] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing load_modules_local [ 767.938663] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 767.946665] alg: No test for adler32 (adler32-zlib) [ 768.797382] Lustre: Lustre: Build Version: 2.17.55_26_gfb964ff [ 768.892907] LNet: Added LNI 192.168.204.147@tcp [8/256/0/180] [ 770.479107] Key type lgssc registered [ 770.808227] Lustre: Echo OBD driver; http://www.lustre.org/ [ 777.466210] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing lnet_if_list [ 777.854355] LNet: Added LNI 192.168.204.147@tcp1 [8/256/0/180] [ 780.906536] loop0: detected capacity change from 0 to 204800000 [ 781.021954] blk_update_request: operation not supported error, dev loop0, sector 399872 op 0x9:(WRITE_ZEROES) flags 0x400800 phys_seg 0 prio class 0 [ 781.025608] blk_update_request: operation not supported error, dev loop0, sector 8224 op 0x9:(WRITE_ZEROES) flags 0x400800 phys_seg 0 prio class 0 [ 781.030693] blk_update_request: operation not supported error, dev loop0, sector 58264 op 0x9:(WRITE_ZEROES) flags 0x400800 phys_seg 0 prio class 0 [ 793.931907] loop0: detected capacity change from 0 to 204800000 [ 793.984158] blk_update_request: operation not supported error, dev loop0, sector 399872 op 0x9:(WRITE_ZEROES) flags 0x400800 phys_seg 0 prio class 0 [ 793.987303] blk_update_request: operation not supported error, dev loop0, sector 8224 op 0x9:(WRITE_ZEROES) flags 0x400800 phys_seg 0 prio class 0 [ 793.990417] blk_update_request: operation not supported error, dev loop0, sector 58264 op 0x9:(WRITE_ZEROES) flags 0x400800 phys_seg 0 prio class 0 [ 807.149229] Lustre: lustre-MDT0000: mounting server target with '-t lustre' deprecated, use '-t lustre_tgt' [ 807.154618] LDISKFS-fs (dm-0): mounted filesystem with ordered data mode. Opts: user_xattr,errors=remount-ro,no_mbcache,nodelalloc [ 808.230614] Lustre: MGS: Logs for fs lustre were removed by user request. All servers must be restarted in order to regenerate the logs: rc = 0 [ 808.238340] Lustre: Setting parameter lustre-MDT0000.mdt.identity_upcall=/home/green/git/lustre-release/lustre/utils/l_getidentity in log lustre-MDT0000 [ 808.290256] Lustre: lustre-MDT0000: Imperative Recovery not enabled, recovery window 60-180 [ 809.506955] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing set_default_debug -1 all [ 812.171990] LDISKFS-fs (dm-1): mounted filesystem with ordered data mode. Opts: user_xattr,errors=remount-ro,no_mbcache,nodelalloc [ 812.202201] Lustre: MGS: Regenerating lustre-MDT0001 log by user request: rc = 0 [ 812.213101] Lustre: 47577:0:(mgs_llog.c:1450:mgs_modify_param()) MGS: modify lustre-MDT0001/mdt.identity_upcall=/home/green/git/lustre-release/lustre/utils/l_getidentity (mode = 0) failed: rc = -17 [ 812.303978] Lustre: lustre-MDT0001: Imperative Recovery not enabled, recovery window 60-180 [ 813.534377] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing set_default_debug -1 all [ 815.816347] Lustre: DEBUG MARKER: oleg447-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 817.488214] Lustre: DEBUG MARKER: oleg447-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 819.059162] loop0: detected capacity change from 0 to 204800000 [ 833.825030] LDISKFS-fs (dm-4): mounted filesystem with ordered data mode. Opts: errors=remount-ro,no_mbcache,nodelalloc [ 833.905153] Lustre: sv26510-OST0000: new disk, initializing [ 833.906972] Lustre: srv-sv26510-OST0000: No data found on store. Initialize space. [ 833.909192] Lustre: 49652:0:(osd_compat.c:1352:osd_object_spec_find()) UNKNOWN COMPAT FID [0x200000001:0x101e:0x0] [ 833.924776] Lustre: sv26510-OST0000: Imperative Recovery not enabled, recovery window 60-180 [ 835.759123] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing set_default_debug -1 all [ 841.524822] Lustre: server umount sv26510-OST0000 complete [ 848.351785] LustreError: lustre-MDT0000-osp-MDT0001: operation mds_statfs to node 0@lo failed: rc = -107 [ 848.355263] Lustre: lustre-MDT0000-osp-MDT0001: Connection to lustre-MDT0000 (at 0@lo) was lost; in progress operations using this service will wait for recovery to complete [ 848.359135] Lustre: lustre-MDT0000: Not available for connect from 0@lo (stopping) [ 848.864418] Lustre: lustre-MDT0000-lwp-MDT0001: Connection to lustre-MDT0000 (at 0@lo) was lost; in progress operations using this service will wait for recovery to complete [ 848.869485] Lustre: lustre-MDT0000: Not available for connect from 0@lo (stopping) [ 851.668274] Lustre: server umount lustre-MDT0000 complete [ 852.774346] LustreError: 47568:0:(ldlm_lockd.c:2564:ldlm_cancel_handler()) ldlm_cancel from 0@lo arrived at 1788904038 with bad export cookie 10999310868061579117 [ 852.777269] LustreError: 47568:0:(ldlm_lockd.c:2564:ldlm_cancel_handler()) Skipped 3 previous similar messages [ 852.777659] LustreError: MGC192.168.204.147@tcp: Connection to MGS (at 0@lo) was lost; in progress operations using this service will fail [ 852.869051] Lustre: server umount lustre-MDT0001 complete [ 857.306195] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing unload_modules_local [ 858.297162] Key type lgssc unregistered [ 858.412285] LNet: 52100:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 858.416194] LNetError: 52100:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 858.419750] LNet: Removed LNI 192.168.204.147@tcp [ 859.429266] LNet: Removed LNI 192.168.204.147@tcp1 [ 859.722599] Key type .llcrypt unregistered [ 859.723478] Key type ._llcrypt unregistered [ 865.429547] Key type ._llcrypt registered [ 865.430658] Key type .llcrypt registered [ 865.468049] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing unload_modules_local [ 865.557392] Key type .llcrypt unregistered [ 865.558269] Key type ._llcrypt unregistered [ 871.942052] Key type ._llcrypt registered [ 871.943436] Key type .llcrypt registered [ 871.975550] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing load_modules_local [ 872.291454] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 872.313331] alg: No test for adler32 (adler32-zlib) [ 873.156290] Lustre: Lustre: Build Version: 2.17.55_26_gfb964ff [ 873.231418] LNet: Added LNI 192.168.204.147@tcp [8/256/0/180] [ 874.807118] Key type lgssc registered [ 875.157884] Lustre: Echo OBD driver; http://www.lustre.org/ [ 881.933177] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing lnet_if_list [ 882.317029] LNet: Added LNI 192.168.204.147@tcp1 [8/256/0/180] [ 885.452069] loop0: detected capacity change from 0 to 204800000 [ 885.504667] blk_update_request: operation not supported error, dev loop0, sector 399872 op 0x9:(WRITE_ZEROES) flags 0x400800 phys_seg 0 prio class 0 [ 885.507879] blk_update_request: operation not supported error, dev loop0, sector 8224 op 0x9:(WRITE_ZEROES) flags 0x400800 phys_seg 0 prio class 0 [ 885.511500] blk_update_request: operation not supported error, dev loop0, sector 58264 op 0x9:(WRITE_ZEROES) flags 0x400800 phys_seg 0 prio class 0 [ 898.412818] loop0: detected capacity change from 0 to 204800000 [ 898.469980] blk_update_request: operation not supported error, dev loop0, sector 399872 op 0x9:(WRITE_ZEROES) flags 0x400800 phys_seg 0 prio class 0 [ 898.472771] blk_update_request: operation not supported error, dev loop0, sector 8224 op 0x9:(WRITE_ZEROES) flags 0x400800 phys_seg 0 prio class 0 [ 898.476643] blk_update_request: operation not supported error, dev loop0, sector 58264 op 0x9:(WRITE_ZEROES) flags 0x400800 phys_seg 0 prio class 0 [ 911.726899] Lustre: lustre-MDT0000: mounting server target with '-t lustre' deprecated, use '-t lustre_tgt' [ 911.732021] LDISKFS-fs (dm-0): mounted filesystem with ordered data mode. Opts: user_xattr,errors=remount-ro,no_mbcache,nodelalloc [ 912.807845] Lustre: MGS: Logs for fs lustre were removed by user request. All servers must be restarted in order to regenerate the logs: rc = 0 [ 912.815377] Lustre: Setting parameter lustre-MDT0000.mdt.identity_upcall=/home/green/git/lustre-release/lustre/utils/l_getidentity in log lustre-MDT0000 [ 912.866252] Lustre: lustre-MDT0000: Imperative Recovery not enabled, recovery window 60-180 [ 914.197398] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing set_default_debug -1 all [ 916.979700] LDISKFS-fs (dm-1): mounted filesystem with ordered data mode. Opts: user_xattr,errors=remount-ro,no_mbcache,nodelalloc [ 917.018048] Lustre: MGS: Regenerating lustre-MDT0001 log by user request: rc = 0 [ 917.030509] Lustre: 55944:0:(mgs_llog.c:1450:mgs_modify_param()) MGS: modify lustre-MDT0001/mdt.identity_upcall=/home/green/git/lustre-release/lustre/utils/l_getidentity (mode = 0) failed: rc = -17 [ 917.120939] Lustre: lustre-MDT0001: Imperative Recovery not enabled, recovery window 60-180 [ 918.486940] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing set_default_debug -1 all [ 920.836064] Lustre: DEBUG MARKER: oleg447-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 922.570070] Lustre: DEBUG MARKER: oleg447-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 924.090434] loop0: detected capacity change from 0 to 204800000 [ 938.976933] LDISKFS-fs (dm-4): mounted filesystem with ordered data mode. Opts: errors=remount-ro,no_mbcache,nodelalloc [ 939.063536] Lustre: sv1476-OST0000: new disk, initializing [ 939.065141] Lustre: srv-sv1476-OST0000: No data found on store. Initialize space. [ 939.067205] Lustre: 58018:0:(osd_compat.c:1352:osd_object_spec_find()) UNKNOWN COMPAT FID [0x200000001:0x101e:0x0] [ 939.083802] Lustre: sv1476-OST0000: Imperative Recovery not enabled, recovery window 60-180 [ 940.932911] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing set_default_debug -1 all [ 946.888349] Lustre: server umount sv1476-OST0000 complete [ 952.288416] Lustre: lustre-MDT0000-osp-MDT0001: Connection to lustre-MDT0000 (at 0@lo) was lost; in progress operations using this service will wait for recovery to complete [ 952.289198] Lustre: lustre-MDT0000: Not available for connect from 0@lo (stopping) [ 952.292764] Lustre: Skipped 1 previous similar message [ 952.295450] Lustre: Skipped 1 previous similar message [ 957.143526] Lustre: server umount lustre-MDT0000 complete [ 958.280026] LustreError: 55934:0:(ldlm_lockd.c:2564:ldlm_cancel_handler()) ldlm_cancel from 0@lo arrived at 1788904144 with bad export cookie 5381485502585045966 [ 958.282712] LustreError: MGC192.168.204.147@tcp: Connection to MGS (at 0@lo) was lost; in progress operations using this service will fail [ 958.284350] LustreError: 55934:0:(ldlm_lockd.c:2564:ldlm_cancel_handler()) Skipped 4 previous similar messages [ 958.380784] Lustre: server umount lustre-MDT0001 complete [ 962.989212] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing unload_modules_local [ 963.896061] Key type lgssc unregistered [ 964.019336] LNet: 60468:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 964.022365] LNetError: 60468:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 964.024397] LNet: Removed LNI 192.168.204.147@tcp [ 965.093287] LNet: Removed LNI 192.168.204.147@tcp1 [ 965.374916] Key type .llcrypt unregistered [ 965.376322] Key type ._llcrypt unregistered [ 971.965351] Key type ._llcrypt registered [ 971.966518] Key type .llcrypt registered [ 972.013967] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing unload_modules_local [ 972.144175] Key type .llcrypt unregistered [ 972.146386] Key type ._llcrypt unregistered [ 982.922905] Key type ._llcrypt registered [ 982.924727] Key type .llcrypt registered [ 983.000991] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing load_modules_local [ 983.539141] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 983.599076] alg: No test for adler32 (adler32-zlib) [ 984.549784] Lustre: Lustre: Build Version: 2.17.55_26_gfb964ff [ 984.741209] LNet: Added LNI 192.168.204.147@tcp [8/256/0/180] [ 986.424247] Key type lgssc registered [ 987.337724] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1002.361587] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing lnet_if_list [ 1003.161083] LNet: Added LNI 192.168.204.147@tcp1 [8/256/0/180] [ 1009.000767] loop0: detected capacity change from 0 to 204800000 [ 1009.116802] blk_update_request: operation not supported error, dev loop0, sector 399872 op 0x9:(WRITE_ZEROES) flags 0x400800 phys_seg 0 prio class 0 [ 1009.124688] blk_update_request: operation not supported error, dev loop0, sector 8224 op 0x9:(WRITE_ZEROES) flags 0x400800 phys_seg 0 prio class 0 [ 1009.131287] blk_update_request: operation not supported error, dev loop0, sector 58264 op 0x9:(WRITE_ZEROES) flags 0x400800 phys_seg 0 prio class 0 [ 1022.784927] loop0: detected capacity change from 0 to 204800000 [ 1022.864446] blk_update_request: operation not supported error, dev loop0, sector 399872 op 0x9:(WRITE_ZEROES) flags 0x400800 phys_seg 0 prio class 0 [ 1022.870604] blk_update_request: operation not supported error, dev loop0, sector 8224 op 0x9:(WRITE_ZEROES) flags 0x400800 phys_seg 0 prio class 0 [ 1022.876943] blk_update_request: operation not supported error, dev loop0, sector 58264 op 0x9:(WRITE_ZEROES) flags 0x400800 phys_seg 0 prio class 0 [ 1040.589657] Lustre: lustre-MDT0000: mounting server target with '-t lustre' deprecated, use '-t lustre_tgt' [ 1040.613657] LDISKFS-fs (dm-0): mounted filesystem with ordered data mode. Opts: user_xattr,errors=remount-ro,no_mbcache,nodelalloc [ 1042.223656] Lustre: MGS: Logs for fs lustre were removed by user request. All servers must be restarted in order to regenerate the logs: rc = 0 [ 1042.251904] Lustre: Setting parameter lustre-MDT0000.mdt.identity_upcall=/home/green/git/lustre-release/lustre/utils/l_getidentity in log lustre-MDT0000 [ 1042.355887] Lustre: lustre-MDT0000: Imperative Recovery not enabled, recovery window 60-180 [ 1048.843617] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing set_default_debug -1 all [ 1060.535326] LDISKFS-fs (dm-1): mounted filesystem with ordered data mode. Opts: user_xattr,errors=remount-ro,no_mbcache,nodelalloc [ 1060.689202] Lustre: MGS: Regenerating lustre-MDT0001 log by user request: rc = 0 [ 1060.774438] Lustre: 64266:0:(mgs_llog.c:1450:mgs_modify_param()) MGS: modify lustre-MDT0001/mdt.identity_upcall=/home/green/git/lustre-release/lustre/utils/l_getidentity (mode = 0) failed: rc = -17 [ 1061.184611] Lustre: lustre-MDT0001: Imperative Recovery not enabled, recovery window 60-180 [ 1068.408299] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing set_default_debug -1 all [ 1080.063143] Lustre: DEBUG MARKER: oleg447-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1089.880696] Lustre: DEBUG MARKER: oleg447-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1101.928083] loop0: detected capacity change from 0 to 204800000 [ 1128.262261] LDISKFS-fs (dm-4): mounted filesystem with ordered data mode. Opts: errors=remount-ro,no_mbcache,nodelalloc [ 1128.716543] Lustre: sv26996-OST0000: new disk, initializing [ 1128.720891] Lustre: srv-sv26996-OST0000: No data found on store. Initialize space. [ 1128.728151] Lustre: 66349:0:(osd_compat.c:1352:osd_object_spec_find()) UNKNOWN COMPAT FID [0x200000001:0x101e:0x0] [ 1128.827457] Lustre: sv26996-OST0000: Imperative Recovery not enabled, recovery window 60-180 [ 1135.551346] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing set_default_debug -1 all [ 1146.796430] loop1: detected capacity change from 0 to 204800000 [ 1167.846743] LDISKFS-fs (dm-5): mounted filesystem with ordered data mode. Opts: errors=remount-ro,no_mbcache,nodelalloc [ 1168.173338] Lustre: sv26996-OST0001: new disk, initializing [ 1168.182942] Lustre: srv-sv26996-OST0001: No data found on store. Initialize space. [ 1168.191776] Lustre: 67517:0:(osd_compat.c:1352:osd_object_spec_find()) UNKNOWN COMPAT FID [0x200000001:0x101e:0x0] [ 1168.287983] Lustre: sv26996-OST0001: Imperative Recovery not enabled, recovery window 60-180 [ 1175.648721] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing set_default_debug -1 all [ 1188.729168] Lustre: server umount sv26996-OST0001 complete [ 1198.004137] Lustre: server umount sv26996-OST0000 complete [ 1213.925975] Lustre: lustre-MDT0000-lwp-MDT0001: Connection to lustre-MDT0000 (at 0@lo) was lost; in progress operations using this service will wait for recovery to complete [ 1213.941575] Lustre: Skipped 1 previous similar message [ 1213.951537] Lustre: lustre-MDT0000: Not available for connect from 0@lo (stopping) [ 1217.550529] Lustre: server umount lustre-MDT0000 complete [ 1221.484529] LustreError: 64258:0:(ldlm_lockd.c:2564:ldlm_cancel_handler()) ldlm_cancel from 0@lo arrived at 1788904407 with bad export cookie 3918827221719694576 [ 1221.485220] LustreError: MGC192.168.204.147@tcp: Connection to MGS (at 0@lo) was lost; in progress operations using this service will fail [ 1221.498257] LustreError: 64258:0:(ldlm_lockd.c:2564:ldlm_cancel_handler()) Skipped 3 previous similar messages [ 1221.856690] Lustre: server umount lustre-MDT0001 complete [ 1234.317407] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing unload_modules_local [ 1237.185445] Key type lgssc unregistered [ 1237.514310] LNet: 1 peer NIs in recovery (showing 1): 192.168.204.47@tcp [ 1237.550686] LNet: 70271:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1237.566793] LNetError: 70271:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1238.623288] LNet: Removed LNI 192.168.204.147@tcp [ 1238.627293] LNetError: 70271:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1238.653338] LNet: Removed LNI 192.168.204.147@tcp1 [ 1239.699264] Key type .llcrypt unregistered [ 1239.703657] Key type ._llcrypt unregistered [ 1259.544352] Key type ._llcrypt registered [ 1259.547305] Key type .llcrypt registered [ 1259.671931] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing unload_modules_local [ 1260.003337] Key type .llcrypt unregistered [ 1260.008947] Key type ._llcrypt unregistered [ 1275.425244] Key type ._llcrypt registered [ 1275.427498] Key type .llcrypt registered [ 1275.553723] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing load_modules_local [ 1276.668108] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1276.753512] alg: No test for adler32 (adler32-zlib) [ 1277.746823] Lustre: Lustre: Build Version: 2.17.55_26_gfb964ff [ 1277.987265] LNet: Added LNI 192.168.204.147@tcp [8/256/0/180] [ 1279.679174] Key type lgssc registered [ 1280.894556] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1304.669373] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing lnet_if_list [ 1306.196032] LNet: Added LNI 192.168.204.147@tcp1 [8/256/0/180] [ 1339.185098] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing unload_modules_local [ 1342.254571] Key type lgssc unregistered [ 1342.501908] LNet: 1 peer NIs in recovery (showing 1): 192.168.204.47@tcp [ 1342.522526] LNet: 74235:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1342.529217] LNetError: 74235:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1342.539629] LNet: Removed LNI 192.168.204.147@tcp [ 1342.556056] LNet: Removed LNI 192.168.204.147@tcp1 [ 1343.599621] Key type .llcrypt unregistered [ 1343.604348] Key type ._llcrypt unregistered [ 1366.097923] Key type ._llcrypt registered [ 1366.099981] Key type .llcrypt registered [ 1366.206831] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing unload_modules_local [ 1366.469206] Key type .llcrypt unregistered [ 1366.470631] Key type ._llcrypt unregistered [ 1384.987490] Key type ._llcrypt registered [ 1384.990844] Key type .llcrypt registered [ 1385.100375] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing load_modules_local [ 1385.928807] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1385.969146] alg: No test for adler32 (adler32-zlib) [ 1387.191103] Lustre: Lustre: Build Version: 2.17.55_26_gfb964ff [ 1387.474929] LNet: Added LNI 192.168.204.147@tcp [8/256/0/180] [ 1389.239263] Key type lgssc registered [ 1390.672826] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1416.560616] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing lnet_if_list [ 1418.238851] LNet: Added LNI 192.168.204.147@tcp1 [8/256/0/180] [ 1421.293838] LNet: Added LNI 192.168.204.147@tcp2 [8/256/0/180] [ 1434.702952] loop0: detected capacity change from 0 to 204800000 [ 1434.887486] blk_update_request: operation not supported error, dev loop0, sector 399872 op 0x9:(WRITE_ZEROES) flags 0x400800 phys_seg 0 prio class 0 [ 1434.894175] blk_update_request: operation not supported error, dev loop0, sector 8224 op 0x9:(WRITE_ZEROES) flags 0x400800 phys_seg 0 prio class 0 [ 1434.908263] blk_update_request: operation not supported error, dev loop0, sector 58264 op 0x9:(WRITE_ZEROES) flags 0x400800 phys_seg 0 prio class 0 [ 1452.685720] loop0: detected capacity change from 0 to 204800000 [ 1452.950472] blk_update_request: operation not supported error, dev loop0, sector 399872 op 0x9:(WRITE_ZEROES) flags 0x400800 phys_seg 0 prio class 0 [ 1452.971532] blk_update_request: operation not supported error, dev loop0, sector 8224 op 0x9:(WRITE_ZEROES) flags 0x400800 phys_seg 0 prio class 0 [ 1453.003606] blk_update_request: operation not supported error, dev loop0, sector 58264 op 0x9:(WRITE_ZEROES) flags 0x400800 phys_seg 0 prio class 0 [ 1471.754732] Lustre: lustre-MDT0000: mounting server target with '-t lustre' deprecated, use '-t lustre_tgt' [ 1471.778904] LDISKFS-fs (dm-0): mounted filesystem with ordered data mode. Opts: user_xattr,errors=remount-ro,no_mbcache,nodelalloc [ 1472.935344] LNetError: 75563:0:(peer.c:2149:lnet_peer_add_nid()) Peer 192.168.204.147@tcp NID 192.168.204.147@tcp is already locked with peer 192.168.204.147@tcp [ 1473.046984] Lustre: MGS: Logs for fs lustre were removed by user request. All servers must be restarted in order to regenerate the logs: rc = 0 [ 1473.092903] Lustre: Setting parameter lustre-MDT0000.mdt.identity_upcall=/home/green/git/lustre-release/lustre/utils/l_getidentity in log lustre-MDT0000 [ 1473.299173] Lustre: lustre-MDT0000: Imperative Recovery not enabled, recovery window 60-180 [ 1478.593430] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing set_default_debug -1 all [ 1486.945450] LDISKFS-fs (dm-1): mounted filesystem with ordered data mode. Opts: user_xattr,errors=remount-ro,no_mbcache,nodelalloc [ 1487.043155] Lustre: MGS: Regenerating lustre-MDT0001 log by user request: rc = 0 [ 1487.061646] Lustre: 78151:0:(mgs_llog.c:1450:mgs_modify_param()) MGS: modify lustre-MDT0001/mdt.identity_upcall=/home/green/git/lustre-release/lustre/utils/l_getidentity (mode = 0) failed: rc = -17 [ 1487.158444] LNetError: 75563:0:(peer.c:2149:lnet_peer_add_nid()) Peer 192.168.204.147@tcp NID 192.168.204.147@tcp is already locked with peer 192.168.204.147@tcp [ 1487.168648] LNetError: 75563:0:(peer.c:2149:lnet_peer_add_nid()) Skipped 2 previous similar messages [ 1487.226625] Lustre: lustre-MDT0001: Imperative Recovery not enabled, recovery window 60-180 [ 1490.801229] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing set_default_debug -1 all [ 1490.838308] LNetError: 75563:0:(peer.c:2149:lnet_peer_add_nid()) Peer 192.168.204.147@tcp NID 192.168.204.147@tcp is already locked with peer 192.168.204.147@tcp [ 1490.849811] LNetError: 75563:0:(peer.c:2149:lnet_peer_add_nid()) Skipped 1 previous similar message [ 1500.874716] Lustre: DEBUG MARKER: oleg447-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1507.473755] Lustre: DEBUG MARKER: oleg447-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1513.484402] loop0: detected capacity change from 0 to 204800000 [ 1533.392857] LDISKFS-fs (dm-4): mounted filesystem with ordered data mode. Opts: errors=remount-ro,no_mbcache,nodelalloc [ 1533.846503] Lustre: sv15593-OST0000: new disk, initializing [ 1533.854597] Lustre: srv-sv15593-OST0000: No data found on store. Initialize space. [ 1533.862944] Lustre: 80228:0:(osd_compat.c:1352:osd_object_spec_find()) UNKNOWN COMPAT FID [0x200000001:0x101e:0x0] [ 1533.925348] Lustre: sv15593-OST0000: Imperative Recovery not enabled, recovery window 60-180 [ 1542.005898] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing set_default_debug -1 all [ 1552.506341] loop1: detected capacity change from 0 to 204800000 [ 1569.525981] LDISKFS-fs (dm-5): mounted filesystem with ordered data mode. Opts: errors=remount-ro,no_mbcache,nodelalloc [ 1569.630947] Lustre: sv15593-OST0001: new disk, initializing [ 1569.634713] Lustre: srv-sv15593-OST0001: No data found on store. Initialize space. [ 1569.639869] Lustre: 81397:0:(osd_compat.c:1352:osd_object_spec_find()) UNKNOWN COMPAT FID [0x200000001:0x101e:0x0] [ 1569.709534] Lustre: sv15593-OST0001: Imperative Recovery not enabled, recovery window 60-180 [ 1573.838309] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing set_default_debug -1 all [ 1582.493383] Lustre: server umount sv15593-OST0001 complete [ 1588.919778] Lustre: server umount sv15593-OST0000 complete [ 1598.433968] Lustre: lustre-MDT0000-lwp-MDT0001: Connection to lustre-MDT0000 (at 0@lo) was lost; in progress operations using this service will wait for recovery to complete [ 1598.435441] Lustre: lustre-MDT0000: Not available for connect from 0@lo (stopping) [ 1598.442899] Lustre: Skipped 1 previous similar message [ 1603.553398] Lustre: lustre-MDT0000: Not available for connect from 0@lo (stopping) [ 1603.556527] Lustre: Skipped 2 previous similar messages [ 1608.673042] Lustre: lustre-MDT0000: Not available for connect from 0@lo (stopping) [ 1608.679405] Lustre: Skipped 1 previous similar message [ 1611.231124] Lustre: lustre-MDT0000 is waiting for obd_unlinked_exports more than 8 seconds. The obd refcount = 3. Is it stuck? [ 1611.317874] Lustre: server umount lustre-MDT0000 complete [ 1613.375569] LustreError: 78141:0:(ldlm_lockd.c:2564:ldlm_cancel_handler()) ldlm_cancel from 0@lo arrived at 1788904799 with bad export cookie 5090536043936925011 [ 1613.378461] LustreError: MGC192.168.204.147@tcp: Connection to MGS (at 0@lo) was lost; in progress operations using this service will fail [ 1613.382632] LustreError: 78141:0:(ldlm_lockd.c:2564:ldlm_cancel_handler()) Skipped 3 previous similar messages [ 1613.557342] Lustre: server umount lustre-MDT0001 complete [ 1620.171659] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing unload_modules_local [ 1621.853943] Key type lgssc unregistered [ 1622.055695] LNet: 84149:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1622.065926] LNetError: 84149:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1622.070885] LNet: Removed LNI 192.168.204.147@tcp [ 1623.135170] LNet: Removed LNI 192.168.204.147@tcp1 [ 1623.137527] LNetError: 84149:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1623.142993] LNetError: 84149:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Skipped 1 previous similar message [ 1623.157656] LNet: Removed LNI 192.168.204.147@tcp2 [ 1623.719671] Key type .llcrypt unregistered [ 1623.722478] Key type ._llcrypt unregistered [ 1633.627172] Key type ._llcrypt registered [ 1633.629043] Key type .llcrypt registered [ 1633.689940] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing unload_modules_local [ 1633.847696] Key type .llcrypt unregistered [ 1633.849214] Key type ._llcrypt unregistered [ 1642.575697] Key type ._llcrypt registered [ 1642.578238] Key type .llcrypt registered [ 1642.628530] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing load_modules_local [ 1643.138777] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1643.153991] alg: No test for adler32 (adler32-zlib) [ 1644.067216] Lustre: Lustre: Build Version: 2.17.55_26_gfb964ff [ 1644.189713] LNet: Added LNI 192.168.204.147@tcp [8/256/0/180] [ 1645.799553] Key type lgssc registered [ 1646.411098] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1657.141441] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing lnet_if_list [ 1657.777574] LNet: Added LNI 192.168.204.147@tcp1 [8/256/0/180] [ 1662.714290] loop0: detected capacity change from 0 to 204800000 [ 1662.809574] blk_update_request: operation not supported error, dev loop0, sector 399872 op 0x9:(WRITE_ZEROES) flags 0x400800 phys_seg 0 prio class 0 [ 1662.817970] blk_update_request: operation not supported error, dev loop0, sector 8224 op 0x9:(WRITE_ZEROES) flags 0x400800 phys_seg 0 prio class 0 [ 1662.825338] blk_update_request: operation not supported error, dev loop0, sector 58264 op 0x9:(WRITE_ZEROES) flags 0x400800 phys_seg 0 prio class 0 [ 1677.233539] loop0: detected capacity change from 0 to 204800000 [ 1677.311275] blk_update_request: operation not supported error, dev loop0, sector 399872 op 0x9:(WRITE_ZEROES) flags 0x400800 phys_seg 0 prio class 0 [ 1677.318692] blk_update_request: operation not supported error, dev loop0, sector 8224 op 0x9:(WRITE_ZEROES) flags 0x400800 phys_seg 0 prio class 0 [ 1677.327616] blk_update_request: operation not supported error, dev loop0, sector 58264 op 0x9:(WRITE_ZEROES) flags 0x400800 phys_seg 0 prio class 0 [ 1691.545768] Lustre: lustre-MDT0000: mounting server target with '-t lustre' deprecated, use '-t lustre_tgt' [ 1691.556354] LDISKFS-fs (dm-0): mounted filesystem with ordered data mode. Opts: user_xattr,errors=remount-ro,no_mbcache,nodelalloc [ 1692.698744] Lustre: MGS: Logs for fs lustre were removed by user request. All servers must be restarted in order to regenerate the logs: rc = 0 [ 1692.708450] Lustre: Setting parameter lustre-MDT0000.mdt.identity_upcall=/home/green/git/lustre-release/lustre/utils/l_getidentity in log lustre-MDT0000 [ 1692.771520] Lustre: lustre-MDT0000: Imperative Recovery not enabled, recovery window 60-180 [ 1694.580526] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing set_default_debug -1 all [ 1698.682951] LDISKFS-fs (dm-1): mounted filesystem with ordered data mode. Opts: user_xattr,errors=remount-ro,no_mbcache,nodelalloc [ 1698.740098] Lustre: MGS: Regenerating lustre-MDT0001 log by user request: rc = 0 [ 1698.755573] Lustre: 88009:0:(mgs_llog.c:1450:mgs_modify_param()) MGS: modify lustre-MDT0001/mdt.identity_upcall=/home/green/git/lustre-release/lustre/utils/l_getidentity (mode = 0) failed: rc = -17 [ 1698.881220] Lustre: lustre-MDT0001: Imperative Recovery not enabled, recovery window 60-180 [ 1700.969546] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing set_default_debug -1 all [ 1704.760939] Lustre: DEBUG MARKER: oleg447-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1707.634387] Lustre: DEBUG MARKER: oleg447-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1710.096982] loop0: detected capacity change from 0 to 204800000 [ 1725.859022] LDISKFS-fs (dm-4): mounted filesystem with ordered data mode. Opts: errors=remount-ro,no_mbcache,nodelalloc [ 1725.990549] Lustre: sv19713-OST0000: new disk, initializing [ 1725.993363] Lustre: srv-sv19713-OST0000: No data found on store. Initialize space. [ 1725.997446] Lustre: 90088:0:(osd_compat.c:1352:osd_object_spec_find()) UNKNOWN COMPAT FID [0x200000001:0x101e:0x0] [ 1726.028080] Lustre: sv19713-OST0000: Imperative Recovery not enabled, recovery window 60-180 [ 1728.780694] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing set_default_debug -1 all [ 1734.482489] loop1: detected capacity change from 0 to 204800000 [ 1750.242977] LDISKFS-fs (dm-5): mounted filesystem with ordered data mode. Opts: errors=remount-ro,no_mbcache,nodelalloc [ 1750.313473] Lustre: sv19713-OST0001: new disk, initializing [ 1750.315981] Lustre: srv-sv19713-OST0001: No data found on store. Initialize space. [ 1750.319538] Lustre: 91205:0:(osd_compat.c:1352:osd_object_spec_find()) UNKNOWN COMPAT FID [0x200000001:0x101e:0x0] [ 1750.346478] Lustre: sv19713-OST0001: Imperative Recovery not enabled, recovery window 60-180 [ 1752.939591] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing set_default_debug -1 all [ 1758.727146] Lustre: server umount sv19713-OST0001 complete [ 1762.627237] Lustre: server umount sv19713-OST0000 complete [ 1770.464087] LustreError: lustre-MDT0000-osp-MDT0001: operation mds_statfs to node 0@lo failed: rc = -107 [ 1770.468287] Lustre: lustre-MDT0000-osp-MDT0001: Connection to lustre-MDT0000 (at 0@lo) was lost; in progress operations using this service will wait for recovery to complete [ 1770.475389] Lustre: lustre-MDT0000: Not available for connect from 0@lo (stopping) [ 1770.976927] Lustre: lustre-MDT0000-lwp-MDT0001: Connection to lustre-MDT0000 (at 0@lo) was lost; in progress operations using this service will wait for recovery to complete [ 1770.985484] Lustre: lustre-MDT0000: Not available for connect from 0@lo (stopping) [ 1773.723571] Lustre: server umount lustre-MDT0000 complete [ 1775.276352] LustreError: 88003:0:(ldlm_lockd.c:2564:ldlm_cancel_handler()) ldlm_cancel from 0@lo arrived at 1788904961 with bad export cookie 7542473671577235873 [ 1775.280384] LustreError: MGC192.168.204.147@tcp: Connection to MGS (at 0@lo) was lost; in progress operations using this service will fail [ 1775.283462] LustreError: 88003:0:(ldlm_lockd.c:2564:ldlm_cancel_handler()) Skipped 4 previous similar messages [ 1775.426915] Lustre: server umount lustre-MDT0001 complete [ 1780.330401] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing unload_modules_local [ 1781.364677] Key type lgssc unregistered [ 1781.479241] LNet: 1 peer NIs in recovery (showing 1): 192.168.204.47@tcp [ 1781.499512] LNet: 93954:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1781.503518] LNetError: 93954:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1781.506088] LNet: Removed LNI 192.168.204.147@tcp [ 1782.565352] LNet: Removed LNI 192.168.204.147@tcp1 [ 1782.905125] Key type .llcrypt unregistered [ 1782.906931] Key type ._llcrypt unregistered [ 1789.141920] Key type ._llcrypt registered [ 1789.143366] Key type .llcrypt registered [ 1789.184523] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing unload_modules_local [ 1789.292463] Key type .llcrypt unregistered [ 1789.293921] Key type ._llcrypt unregistered [ 1791.171313] Key type ._llcrypt registered [ 1791.172771] Key type .llcrypt registered [ 1791.212985] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing unload_modules_local [ 1791.309327] Key type .llcrypt unregistered [ 1791.311440] Key type ._llcrypt unregistered [ 1795.699888] Key type ._llcrypt registered [ 1795.700851] Key type .llcrypt registered [ 1795.743462] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing unload_modules_local [ 1795.850223] Key type .llcrypt unregistered [ 1795.851291] Key type ._llcrypt unregistered [ 1801.923458] Key type ._llcrypt registered [ 1801.924692] Key type .llcrypt registered [ 1801.977688] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing unload_modules_local [ 1802.097263] Key type .llcrypt unregistered [ 1802.098802] Key type ._llcrypt unregistered [ 1809.307973] Key type ._llcrypt registered [ 1809.309239] Key type .llcrypt registered [ 1809.355462] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing load_modules_local [ 1809.717416] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1809.752326] alg: No test for adler32 (adler32-zlib) [ 1810.625530] Lustre: Lustre: Build Version: 2.17.55_26_gfb964ff [ 1810.719618] LNet: Added LNI 192.168.204.147@tcp [8/256/0/180] [ 1812.303156] Key type lgssc registered [ 1812.740183] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1820.453715] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing lnet_if_list [ 1820.888236] LNet: Added LNI 192.168.204.147@tcp1 [8/256/0/180] [ 1824.384659] loop0: detected capacity change from 0 to 204800000 [ 1824.461588] blk_update_request: operation not supported error, dev loop0, sector 399872 op 0x9:(WRITE_ZEROES) flags 0x400800 phys_seg 0 prio class 0 [ 1824.467124] blk_update_request: operation not supported error, dev loop0, sector 8224 op 0x9:(WRITE_ZEROES) flags 0x400800 phys_seg 0 prio class 0 [ 1824.471233] blk_update_request: operation not supported error, dev loop0, sector 58264 op 0x9:(WRITE_ZEROES) flags 0x400800 phys_seg 0 prio class 0 [ 1837.642595] loop0: detected capacity change from 0 to 204800000 [ 1837.706654] blk_update_request: operation not supported error, dev loop0, sector 399872 op 0x9:(WRITE_ZEROES) flags 0x400800 phys_seg 0 prio class 0 [ 1837.711968] blk_update_request: operation not supported error, dev loop0, sector 8224 op 0x9:(WRITE_ZEROES) flags 0x400800 phys_seg 0 prio class 0 [ 1837.717107] blk_update_request: operation not supported error, dev loop0, sector 58264 op 0x9:(WRITE_ZEROES) flags 0x400800 phys_seg 0 prio class 0 [ 1851.377609] Lustre: lustre-MDT0000: mounting server target with '-t lustre' deprecated, use '-t lustre_tgt' [ 1851.385443] LDISKFS-fs (dm-0): mounted filesystem with ordered data mode. Opts: user_xattr,errors=remount-ro,no_mbcache,nodelalloc [ 1852.498651] Lustre: MGS: Logs for fs lustre were removed by user request. All servers must be restarted in order to regenerate the logs: rc = 0 [ 1852.508558] Lustre: Setting parameter lustre-MDT0000.mdt.identity_upcall=/home/green/git/lustre-release/lustre/utils/l_getidentity in log lustre-MDT0000 [ 1852.570258] Lustre: lustre-MDT0000: Imperative Recovery not enabled, recovery window 60-180 [ 1854.083247] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing set_default_debug -1 all [ 1857.222268] LDISKFS-fs (dm-1): mounted filesystem with ordered data mode. Opts: user_xattr,errors=remount-ro,no_mbcache,nodelalloc [ 1857.279726] Lustre: MGS: Regenerating lustre-MDT0001 log by user request: rc = 0 [ 1857.297697] Lustre: 99377:0:(mgs_llog.c:1450:mgs_modify_param()) MGS: modify lustre-MDT0001/mdt.identity_upcall=/home/green/git/lustre-release/lustre/utils/l_getidentity (mode = 0) failed: rc = -17 [ 1857.405770] Lustre: lustre-MDT0001: Imperative Recovery not enabled, recovery window 60-180 [ 1858.919154] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing set_default_debug -1 all [ 1861.911321] Lustre: DEBUG MARKER: oleg447-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1864.091540] Lustre: DEBUG MARKER: oleg447-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1866.078369] loop0: detected capacity change from 0 to 204800000 [ 1881.377025] LDISKFS-fs (dm-4): mounted filesystem with ordered data mode. Opts: errors=remount-ro,no_mbcache,nodelalloc [ 1881.470145] Lustre: sv8580-OST0000: new disk, initializing [ 1881.472188] Lustre: srv-sv8580-OST0000: No data found on store. Initialize space. [ 1881.474283] Lustre: 101452:0:(osd_compat.c:1352:osd_object_spec_find()) UNKNOWN COMPAT FID [0x200000001:0x101e:0x0] [ 1881.492417] Lustre: sv8580-OST0000: Imperative Recovery not enabled, recovery window 60-180 [ 1883.497038] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing set_default_debug -1 all [ 1886.860255] Lustre: server umount sv8580-OST0000 complete [ 1891.259230] loop0: detected capacity change from 0 to 204800000 [ 1895.329338] LDISKFS-fs (dm-4): mounted filesystem with ordered data mode. Opts: errors=remount-ro,no_mbcache,nodelalloc [ 1895.428239] Lustre: *** cfs_fail_loc=727, val=1*** [ 1895.433170] Lustre: sv8580-OST0000: Imperative Recovery not enabled, recovery window 60-180 [ 1897.497732] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing set_default_debug -1 all [ 1902.649202] Lustre: server umount sv8580-OST0000 complete [ 1908.703904] LustreError: lustre-MDT0000-osp-MDT0001: operation mds_statfs to node 0@lo failed: rc = -107 [ 1908.707199] Lustre: lustre-MDT0000-osp-MDT0001: Connection to lustre-MDT0000 (at 0@lo) was lost; in progress operations using this service will wait for recovery to complete [ 1908.713274] Lustre: lustre-MDT0000: Not available for connect from 0@lo (stopping) [ 1910.751974] Lustre: lustre-MDT0000: Not available for connect from 0@lo (stopping) [ 1910.752679] Lustre: lustre-MDT0000-lwp-MDT0001: Connection to lustre-MDT0000 (at 0@lo) was lost; in progress operations using this service will wait for recovery to complete [ 1912.916479] Lustre: server umount lustre-MDT0000 complete [ 1914.185937] LustreError: 99369:0:(ldlm_lockd.c:2564:ldlm_cancel_handler()) ldlm_cancel from 0@lo arrived at 1788905100 with bad export cookie 17619173927628284954 [ 1914.188328] LustreError: MGC192.168.204.147@tcp: Connection to MGS (at 0@lo) was lost; in progress operations using this service will fail [ 1914.190118] LustreError: 99369:0:(ldlm_lockd.c:2564:ldlm_cancel_handler()) Skipped 3 previous similar messages [ 1914.307930] Lustre: server umount lustre-MDT0001 complete [ 1917.880700] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing unload_modules_local [ 1918.888786] Key type lgssc unregistered [ 1919.021354] LNet: 105613:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1919.024083] LNetError: 105613:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1919.026572] LNet: Removed LNI 192.168.204.147@tcp [ 1920.037333] LNet: Removed LNI 192.168.204.147@tcp1 [ 1920.388587] Key type .llcrypt unregistered [ 1920.389570] Key type ._llcrypt unregistered [ 1927.415376] Key type ._llcrypt registered [ 1927.416533] Key type .llcrypt registered [ 1927.456131] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing set_hostid [ 1933.989114] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing load_modules_local [ 1934.323430] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1934.374668] alg: No test for adler32 (adler32-zlib) [ 1935.232393] Lustre: Lustre: Build Version: 2.17.55_26_gfb964ff [ 1935.316432] LNet: Added LNI 192.168.204.147@tcp [8/256/0/180] [ 1936.895144] Key type lgssc registered [ 1937.234314] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1947.936595] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 1692 sec ========== 18:05:34 (1788905134) [ 1948.544929] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 18:05:35 (1788905135) === [ 1949.707258] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 18:05:36 (1788905136) === [ 1963.802033] Lustre: DEBUG MARKER: oleg447-server.virtnet: executing unload_modules_local [ 1964.825736] Key type lgssc unregistered [ 1964.946311] LNet: 110724:0:(lib-ptl.c:977:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1964.949222] LNetError: 110724:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1964.959355] LNet: Removed LNI 192.168.204.147@tcp [ 1965.259452] Key type .llcrypt unregistered [ 1965.260707] Key type ._llcrypt unregistered