[ 0.000000] Linux version 4.18.0rh8.10-debug (green@maintenance) (gcc version 8.5.0 20210514 (Red Hat 8.5.0-26) (GCC)) #2 SMP Mon Jul 14 01:24:22 EDT 2025 [ 0.000000] Command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' [ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 [ 0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. [ 0.000000] signal: max sigframe size: 1776 [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bffd9fff] usable [ 0.000000] BIOS-e820: [mem 0x00000000bffda000-0x00000000bfffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x0000000146dfffff] usable [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] SMBIOS 2.8 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.17.0-8.fc42 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 437828624 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2421 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE22BD 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 00227D (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2331 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE23C1 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE23F9 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe22bd-0xbffe2330] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22bc] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2331-0xbffe23c0] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe23c1-0xbffe23f8] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe23f9-0xbffe2420] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524584K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001010] APIC: Switch to symmetric I/O mode setup [ 0.002272] x2apic enabled [ 0.003008] Switched APIC routing to physical x2apic. [ 0.004024] kvm-guest: setup PV IPIs [ 0.006552] ..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.008006] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009015] pid_max: default: 32768 minimum: 301 [ 0.010138] LSM: Security Framework initializing [ 0.011049] Yama: becoming mindful. [ 0.013025] SELinux: Initializing. [ 0.014074] *** VALIDATE selinux *** [ 0.023198] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.028247] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.029188] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.030097] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.031099] *** VALIDATE tmpfs *** [ 0.032340] *** VALIDATE proc *** [ 0.033204] *** VALIDATE cgroup *** [ 0.034008] *** VALIDATE cgroup2 *** [ 0.035312] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.036155] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.037012] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.038037] Spectre V2 : User space: Vulnerable [ 0.039009] Speculative Store Bypass: Vulnerable [ 0.042336] debug: unmapping init [mem 0xffffffff90c59000-0xffffffff90c60fff] [ 0.045000] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.045731] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.046031] ... version: 2 [ 0.047015] ... bit width: 48 [ 0.048011] ... generic registers: 4 [ 0.049021] ... value mask: 0000ffffffffffff [ 0.050014] ... max period: 00007fffffffffff [ 0.051015] ... fixed-purpose events: 3 [ 0.052016] ... event mask: 000000070000000f [ 0.053328] rcu: Hierarchical SRCU implementation. [ 0.055422] smp: Bringing up secondary CPUs ... [ 0.056634] x86: Booting SMP configuration: [ 0.057035] .... node #0, CPUs: #1 #2 #3 [ 0.061216] smp: Brought up 1 node, 4 CPUs [ 0.063021] smpboot: Max logical packages: 1 [ 0.064017] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.153033] node 0 deferred pages initialised in 86ms [ 0.156187] devtmpfs: initialized [ 0.157373] x86/mm: Memory block size: 128MB [ 0.160105] gcov: version magic: 0x41383552 [ 0.162449] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.163078] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.164327] pinctrl core: initialized pinctrl subsystem [ 0.165263] [ 0.165875] ************************************************************* [ 0.166028] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.167018] ** ** [ 0.168017] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.169018] ** ** [ 0.170014] ** This means that this kernel is built to expose internal ** [ 0.171017] ** IOMMU data structures, which may compromise security on ** [ 0.172019] ** your system. ** [ 0.173019] ** ** [ 0.174020] ** If you see this message and you are not debugging the ** [ 0.175019] ** kernel, report this immediately to your vendor! ** [ 0.176019] ** ** [ 0.177021] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.178019] ************************************************************* [ 0.179779] NET: Registered protocol family 16 [ 0.180527] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.181082] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.182070] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.184044] cpuidle: using governor menu [ 0.186046] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.187474] PCI: Using configuration type 1 for base access [ 0.188130] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.194191] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.195043] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.197098] cryptd: max_cpu_qlen set to 1000 [ 0.199197] ACPI: Added _OSI(Module Device) [ 0.200017] ACPI: Added _OSI(Processor Device) [ 0.201012] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.202014] ACPI: Added _OSI(Processor Aggregator Device) [ 0.206016] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.209609] ACPI: Interpreter enabled [ 0.210062] ACPI: PM: (supports S0 S3 S4 S5) [ 0.210998] ACPI: Using IOAPIC for interrupt routing [ 0.211143] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.212253] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.220950] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.221050] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.222023] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.223088] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.225379] acpiphp: Slot [2] registered [ 0.226177] acpiphp: Slot [5] registered [ 0.227131] acpiphp: Slot [6] registered [ 0.228130] acpiphp: Slot [3] registered [ 0.229117] acpiphp: Slot [4] registered [ 0.230108] acpiphp: Slot [7] registered [ 0.231115] acpiphp: Slot [8] registered [ 0.232141] acpiphp: Slot [9] registered [ 0.233159] acpiphp: Slot [10] registered [ 0.234113] acpiphp: Slot [11] registered [ 0.235114] acpiphp: Slot [12] registered [ 0.236141] acpiphp: Slot [13] registered [ 0.237115] acpiphp: Slot [14] registered [ 0.238113] acpiphp: Slot [15] registered [ 0.239182] acpiphp: Slot [16] registered [ 0.240115] acpiphp: Slot [17] registered [ 0.241122] acpiphp: Slot [18] registered [ 0.242114] acpiphp: Slot [19] registered [ 0.243083] acpiphp: Slot [20] registered [ 0.244087] acpiphp: Slot [21] registered [ 0.245069] acpiphp: Slot [22] registered [ 0.246083] acpiphp: Slot [23] registered [ 0.247094] acpiphp: Slot [24] registered [ 0.248091] acpiphp: Slot [25] registered [ 0.249013] acpiphp: Slot [26] registered [ 0.249865] acpiphp: Slot [27] registered [ 0.250073] acpiphp: Slot [28] registered [ 0.251081] acpiphp: Slot [29] registered [ 0.251952] acpiphp: Slot [30] registered [ 0.252094] acpiphp: Slot [31] registered [ 0.253057] PCI host bridge to bus 0000:00 [ 0.253962] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.254017] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.255026] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.256031] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.257031] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.258030] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.259182] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.261017] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.263093] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.267020] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.269437] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.270022] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.271021] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.272028] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.273648] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.274749] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.275049] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.276852] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.278018] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.283017] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.285014] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.288581] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.291018] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.293987] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.299805] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.303794] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.305941] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.307014] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.313025] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.320372] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.321364] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.322352] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.323370] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.324263] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.328139] iommu: Default domain type: Passthrough [ 0.329550] SCSI subsystem initialized [ 0.330161] ACPI: bus type USB registered [ 0.331119] usbcore: registered new interface driver usbfs [ 0.332121] usbcore: registered new interface driver hub [ 0.333099] usbcore: registered new device driver usb [ 0.334180] pps_core: LinuxPPS API ver. 1 registered [ 0.335030] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.336082] PTP clock support registered [ 0.337154] EDAC MC: Ver: 3.0.0 [ 0.338398] PCI: Using ACPI for IRQ routing [ 0.339762] NetLabel: Initializing [ 0.340013] NetLabel: domain hash size = 128 [ 0.341013] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.342085] NetLabel: unlabeled traffic allowed by default [ 0.343173] vgaarb: loaded [ 0.344293] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.345017] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.351126] clocksource: Switched to clocksource kvm-clock [ 0.461650] VFS: Disk quotas dquot_6.6.0 [ 0.463215] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.465676] *** VALIDATE ramfs *** [ 0.466851] *** VALIDATE hugetlbfs *** [ 0.468283] pnp: PnP ACPI init [ 0.470166] pnp: PnP ACPI: found 6 devices [ 0.485405] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.488524] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.491541] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.494442] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.497439] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.500892] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.505080] NET: Registered protocol family 2 [ 0.508750] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.515434] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.520931] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.527627] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.532085] TCP: Hash tables configured (established 65536 bind 65536) [ 0.535366] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.539059] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.543104] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.547692] NET: Registered protocol family 1 [ 0.551214] RPC: Registered named UNIX socket transport module. [ 0.553886] RPC: Registered udp transport module. [ 0.556322] RPC: Registered tcp transport module. [ 0.558638] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.561321] NET: Registered protocol family 44 [ 0.562933] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.565141] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.567327] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.569619] PCI: CLS 0 bytes, default 64 [ 0.571244] Unpacking initramfs... [ 1.977127] debug: unmapping init [mem 0xffff89ec3cc64000-0xffff89ec3ffcffff] [ 1.980140] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 1.982059] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 1.984174] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.461614] Initialise system trusted keyrings [ 2.463122] Key type blacklist registered [ 2.464949] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.473870] zbud: loaded [ 2.478275] *** VALIDATE nfs *** [ 2.479347] *** VALIDATE nfs4 *** [ 2.480911] pstore: using deflate compression [ 2.487151] Platform Keyring initialized [ 2.569603] NET: Registered protocol family 38 [ 2.570850] Key type asymmetric registered [ 2.571904] Asymmetric key parser 'x509' registered [ 2.573421] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.575481] io scheduler mq-deadline registered [ 2.577198] io scheduler kyber registered [ 2.578980] io scheduler bfq registered [ 2.580335] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.582685] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.584904] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.588136] ACPI: Power Button [PWRF] [ 2.591899] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.596890] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.604267] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.632053] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.659259] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.663885] Non-volatile memory driver v1.3 [ 2.665512] Linux agpgart interface v0.103 [ 2.694645] virtio_blk virtio1: [vda] 134360 512-byte logical blocks (68.8 MB/65.6 MiB) [ 2.697491] vda: detected capacity change from 0 to 68792320 [ 2.714344] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 2.717452] vdb: detected capacity change from 0 to 1073741824 [ 2.722468] libphy: Fixed MDIO Bus: probed [ 2.730917] usbcore: registered new interface driver usbserial_generic [ 2.732390] usbserial: USB Serial support registered for generic [ 2.733757] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 2.737631] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 2.739240] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 2.741573] mousedev: PS/2 mouse device common for all mice [ 2.744277] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 2.746414] rtc_cmos 00:05: RTC can wake from S4 [ 2.749603] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 2.749821] rtc_cmos 00:05: registered as rtc0 [ 2.753673] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 2.753790] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 2.755086] intel_pstate: CPU model not supported [ 2.759896] hid: raw HID events driver (C) Jiri Kosina [ 2.761955] usbcore: registered new interface driver usbhid [ 2.763404] usbhid: USB HID core driver [ 2.765072] drop_monitor: Initializing network drop monitor service [ 2.767287] Initializing XFRM netlink socket [ 2.769308] NET: Registered protocol family 10 [ 2.771344] Segment Routing with IPv6 [ 2.772149] NET: Registered protocol family 17 [ 2.773090] mpls_gso: MPLS GSO support [ 2.777856] RAS: Correctable Errors collector initialized. [ 2.779032] AVX version of gcm_enc/dec engaged. [ 2.779824] AES CTR mode by8 optimization enabled [ 2.838274] sched_clock: Marking stable (2838254903, 0)->(3835929849, -997674946) [ 2.840662] registered taskstats version 1 [ 2.842334] Loading compiled-in X.509 certificates [ 2.843536] zswap: loaded using pool lzo/zbud [ 2.863405] Key type big_key registered [ 2.874036] Key type encrypted registered [ 2.875352] ima: No TPM chip found, activating TPM-bypass! [ 2.877296] ima: Allocated hash algorithm: sha1 [ 2.878466] ima: No architecture policies found [ 2.879839] evm: Initialising EVM extended attributes: [ 2.881359] evm: security.selinux [ 2.882191] evm: security.ima [ 2.882872] evm: security.capability [ 2.883783] evm: HMAC attrs: 0x1 [ 2.885407] rtc_cmos 00:05: setting system clock to 2026-01-30 06:41:53 UTC (1769755313) [ 2.889828] debug: unmapping init [mem 0xffffffff91c03000-0xffffffff91dfffff] [ 2.892390] debug: unmapping init [mem 0xffffffff90982000-0xffffffff90c58fff] [ 2.900068] Write protecting the kernel read-only data: 28672k [ 2.902194] debug: unmapping init [mem 0xffffffff8f003000-0xffffffff8f1fffff] [ 2.904072] debug: unmapping init [mem 0xffffffff8f914000-0xffffffff8f9fffff] [ 2.928990] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 2.935773] systemd[1]: Detected virtualization kvm. [ 2.937116] systemd[1]: Detected architecture x86-64. [ 2.938419] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 2.962517] systemd[1]: No hostname configured. [ 2.964249] systemd[1]: Set hostname to . [ 2.966326] random: systemd: uninitialized urandom read (16 bytes read) [ 2.968458] systemd[1]: Initializing machine ID from random generator. [ 3.083727] random: systemd: uninitialized urandom read (16 bytes read) [ 3.085773] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ 3.089588] random: systemd: uninitialized urandom read (16 bytes read) [ 3.091219] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ 3.094289] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ OK ] Reached target Timers. [ OK ] Listening on udev Control Socket. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Reached target Local File Systems. [ OK ] Reached target Swap. [ OK ] Listening on Journal Socket. Starting Setup Virtual Console... Starting Create Volatile Files and Directories... [ OK ] Started Memstrack Anylazing Service. Starting Apply Kernel Variables... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Sockets. Starting Journal Service... [ OK ] Started Setup Virtual Console. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create list of required sta…vice nodes for the current kernel. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 3.650886] device-mapper: uevent: version 1.0.3 [ 3.652854] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 4.311308] virtio_net virtio0 ens2: renamed from eth0 [ 4.336406] scsi host0: ata_piix [ 4.357969] scsi host1: ata_piix [ 4.359889] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 4.362156] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 8.760453] dracut-initqueue[590]: RTNETLINK answers: File exists [ 9.404564] random: crng init done [ 9.405711] 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. [ 9.809666] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target 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 target Timers. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Sockets. [ OK ] Stopped target Paths. [ OK ] Stopped target Slices. [ OK ] Stopped target System Initialization. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. [ OK ] Stopped target Swap. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. Stopping udev Kernel Device Manager... [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped 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.040706] printk: systemd: 26 output lines suppressed due to ratelimiting [ 11.328485] SELinux: Disabled at runtime. [ 11.390704] 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.396757] systemd[1]: Detected virtualization kvm. [ 11.397863] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 11.910405] systemd[1]: initrd-switch-root.service: Succeeded. [ 11.913788] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 11.918835] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 11.924407] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 11.927465] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 11.941820] systemd[1]: Starting Journal Service... Starting Journal Service... [ 11.946470] systemd[1]: Reached target rpc_pipefs.target. [ OK ] Reached target rpc_pipefs.target. Mounting POSIX Message Queue File System... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Created slice User and Session Slice. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on Process Core Dump Socket. Activating swap /dev/disk/by-label/SWAP... [ OK ] Reached target Slices. Mounting Kernel Debug File System... [ OK ] Created slice system-getty.slice. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Stopped target Switch Root. Starting Remount Root and Kernel File Systems... [ 12.042591] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Stopped target Initrd Root File System. Starting Apply Kernel Variables... [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Stopped target Initrd File Systems. [ OK ] Listening on udev Control Socket. Mounting Huge Pages File System... [ OK ] Listening on udev Kernel Socket. Starting udev Coldplug all Devices... [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Started Journal Service. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted Kernel Debug File System. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted Huge Pages File System. Starting Configure read-only root support... [ OK ] Reached target Swap. 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. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... [ OK ] Started udev Coldplug all Devices. [ OK ] Mounted /mnt. [ 12.486980] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 12.791795] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 12.863389] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 13.015695] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 13.025844] EDAC sbridge: Ver: 1.1.2 [ 14.365193] Key type dns_resolver registered [ 14.659200] NFS: Registering the id_resolver key type [ 14.660683] Key type id_resolver registered [ 14.661680] Key type id_legacy registered [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Mark the need to relabel after reboot... Starting Rebuild Dynamic Linker Cache... Starting Create Volatile Files and Directories... Starting Load/Save Random Seed... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Load/Save Random Seed. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. [ OK ] Reached target Basic System. [ OK ] Started D-Bus System Message Bus. Starting Login Service... Starting Network Manager... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started irqbalance daemon. Starting Restore /run/initramfs on shutdown... [ OK ] Reached target sshd-keygen.target. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting Dynamic System Tuning Daemon... Starting OpenSSH server daemon... Starting GSSAPI Proxy Daemon... Starting Network Manager Wait Online... [ OK ] Started 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 Permit User Sessions. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Getty on tty1. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS0. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Notify NFS peers of a restart... Starting Crash recovery kernel arming... Starting System Logging Service... [ 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 oleg607-client login: [ 54.347274] libcfs: loading out-of-tree module taints kernel. [ 54.486447] Key type ._llcrypt registered [ 54.488619] Key type .llcrypt registered [ 55.370540] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 55.406954] alg: No test for adler32 (adler32-zlib) [ 56.920611] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 58.037080] LNet: Added LNI 192.168.206.7@tcp [8/256/0/180] [ 59.920263] Key type lgssc registered [ 61.170138] hrtimer: interrupt took 8122337 ns [ 62.198993] Lustre: Echo OBD driver; http://www.lustre.org/ [ 265.037198] Lustre: Mounted lustre-client [ 271.140852] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 290.791286] Lustre: lustre-OST0000-osc-ffff89eca035f800: disconnect after 23s idle [ 291.862706] Lustre: DEBUG MARKER: oleg607-client.virtnet: executing check_logdir /tmp/testlogs/ [ 297.722628] Lustre: DEBUG MARKER: oleg607-client.virtnet: executing yml_node [ 303.054647] Lustre: DEBUG MARKER: Client: 2.17.50.86 [ 306.665839] Lustre: DEBUG MARKER: MDS: 2.17.50.86 [ 310.154434] Lustre: DEBUG MARKER: OSS: 2.17.50.86 [ 312.143801] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Fri Jan 30 01:47:00 EST 2026 [ 333.144372] Lustre: DEBUG MARKER: excepting tests: 27 40a [ 334.952116] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 336.538480] Lustre: DEBUG MARKER: === sanityn: start setup 01:47:25 (1769755645) === [ 337.738971] Lustre: Mounted lustre-client [ 342.468676] Lustre: DEBUG MARKER: oleg607-client.virtnet: executing check_config_client /mnt/lustre [ 364.646133] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 379.328492] Lustre: DEBUG MARKER: === sanityn: finish setup 01:48:08 (1769755688) === [ 381.896086] Lustre: DEBUG MARKER: == sanityn test 0: do_node_vp() and do_facet_vp() do the right thing ========================================================== 01:48:10 (1769755690) [ 390.829101] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 01:48:20 (1769755700) [ 398.259850] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 01:48:27 (1769755707) [ 405.120502] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 01:48:34 (1769755714) [ 411.407154] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 01:48:40 (1769755720) [ 417.728240] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 01:48:46 (1769755726) [ 424.465853] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 01:48:53 (1769755733) [ 431.342484] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 01:49:00 (1769755740) [ 439.095029] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 01:49:08 (1769755748) [ 445.421261] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 01:49:14 (1769755754) [ 452.076294] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 01:49:21 (1769755761) [ 459.453114] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 01:49:28 (1769755768) [ 460.767158] Lustre: lustre-OST0000-osc-ffff89eca035f800: disconnect after 21s idle [ 460.769649] Lustre: Skipped 1 previous similar message [ 466.290241] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 01:49:35 (1769755775) [ 473.092800] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 01:49:42 (1769755782) [ 476.128880] Lustre: lustre-OST0001-osc-ffff89ec911e2000: disconnect after 23s idle [ 479.984409] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 01:49:49 (1769755789) [ 486.107138] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 01:49:55 (1769755795) [ 491.487404] Lustre: lustre-OST0000-osc-ffff89eca035f800: disconnect after 20s idle [ 492.303888] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 01:50:01 (1769755801) [ 498.230053] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 01:50:07 (1769755807) [ 506.046815] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 01:50:15 (1769755815) [ 512.337793] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 01:50:21 (1769755821) [ 518.700733] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 01:50:27 (1769755827) [ 519.440676] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=144115205289279518 file: /mnt/lustre/lockdir/lockfile=144115205289279517 [ 653.623894] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 01:52:43 (1769755963) [ 660.522636] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 01:52:49 (1769755969) [ 666.914633] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 01:52:56 (1769755976) [ 673.810495] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 01:53:03 (1769755983) [ 680.442752] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 01:53:09 (1769755989) [ 687.442713] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 01:53:16 (1769755996) [ 689.363135] Lustre: DEBUG MARKER: chmod [ 697.103537] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 01:53:26 (1769756006) [ 1697.008704] Lustre: DEBUG MARKER: == sanityn test 16a: 2500 iterations of dual-mount fsx === 02:10:06 (1769757006) [ 1838.048515] Lustre: lustre-OST0001-osc-ffff89ec911e2000: disconnect after 24s idle [ 1900.412908] Lustre: DEBUG MARKER: == sanityn test 16b: 2500 iterations of dual-mount fsx at small size ========================================================== 02:13:29 (1769757209) [ 1998.826444] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 02:15:07 (1769757307) [ 2132.441752] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 02:17:21 (1769757441) [ 2169.403915] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 02:17:58 (1769757478) [ 2170.848152] Lustre: lustre-OST0000-osc-ffff89ec911e2000: disconnect after 21s idle [ 2176.812628] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 02:18:05 (1769757485) [ 2177.645929] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2177.760092] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2177.820400] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2177.863060] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2177.943790] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2178.008595] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2178.063577] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2178.116586] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2178.163734] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2178.254035] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2178.330680] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2178.408543] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2178.475524] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2178.547443] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2178.651720] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2178.726579] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2178.794901] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2178.876920] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2178.954811] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2178.997645] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2179.055420] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2179.116438] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2179.172443] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2179.246155] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2179.295469] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2179.340433] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2179.394516] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2179.483141] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2179.575592] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2179.635644] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2179.685815] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2179.750534] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2179.826962] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2179.895424] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2179.969476] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2180.026209] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2180.067786] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2180.134808] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2180.213551] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2180.303852] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2180.370620] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2180.432492] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2180.511510] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2180.572475] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2180.655767] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2180.731057] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2180.801655] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2180.852479] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2180.920986] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2180.987252] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2181.059076] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2181.129330] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2181.199784] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2181.275573] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2181.361220] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2181.452507] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2181.539947] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2181.603958] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2181.668233] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2181.748472] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2181.813567] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2181.927051] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2182.023730] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2182.062616] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2182.116164] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2182.188774] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2182.271953] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2182.372414] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2182.440178] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2182.500973] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2182.584312] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2182.636077] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2182.715505] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2182.781625] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2182.886067] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2183.019872] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2183.173599] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2183.286279] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2183.360518] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2183.446397] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2183.502428] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2183.582552] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2183.652891] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2183.733441] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2183.794968] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2183.862397] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2183.931622] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2184.021530] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2184.113093] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2184.211425] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2184.316768] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2184.412095] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2184.474678] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2184.550557] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2184.635824] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2184.730156] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2184.822320] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2184.954649] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2185.035735] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2185.088738] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2185.144790] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2185.264133] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2185.361392] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2185.435589] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2185.502474] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2185.566547] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2185.624553] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2185.713511] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2185.808462] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2185.922417] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2185.988727] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2186.092195] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2186.175194] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2186.303780] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2186.389561] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2186.453213] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2186.512215] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2186.565699] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2186.629259] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2186.671769] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2186.714320] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2186.758170] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2186.793267] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2186.830772] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2186.890985] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2186.973348] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2187.067474] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2187.155178] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2187.248408] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2187.347309] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2187.420577] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2187.485141] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2187.548250] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2187.611270] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2187.678973] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2187.776804] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2187.856135] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2187.925888] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2187.993042] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2188.069879] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2188.125559] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2188.204066] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2188.278690] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2188.355593] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2188.433560] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2188.491278] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2188.539899] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2188.623330] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2188.692138] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2188.745650] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2188.817972] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2188.873596] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2188.922932] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2188.997485] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2189.059315] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2189.124220] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2189.198396] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2189.285362] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2189.345126] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2189.409257] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2189.486273] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2189.558404] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2189.620699] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2189.679765] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2189.748687] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2189.856412] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2189.950425] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2190.014316] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2190.075026] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2190.128708] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2190.187066] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2190.241869] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2190.285483] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2190.368545] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2190.450569] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2190.543700] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2190.622629] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2190.700267] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2190.755759] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2190.827346] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2190.861470] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2190.903478] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2190.939720] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2191.018374] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2191.092811] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2191.208341] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2191.296942] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2191.338854] Lustre: lustre-OST0001-osc-ffff89ec911e2000: disconnect after 20s idle [ 2191.401603] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2191.463044] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2191.529371] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2191.600903] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2191.667179] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2191.722862] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2191.784355] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2191.854950] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2191.929933] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2192.007716] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2192.063419] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2192.105067] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2192.174401] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2192.232955] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2192.300984] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2192.385316] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2192.465917] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2192.536070] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2192.584671] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2192.652713] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2192.706984] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2192.779619] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2192.851310] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2192.906926] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2192.979508] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2193.040438] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2193.117634] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2193.204697] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2193.295824] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2193.369107] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2193.439717] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2193.540079] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2193.623849] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2193.731712] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2193.817320] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2193.899214] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2193.968433] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2194.045700] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2194.113869] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2194.176329] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2194.261162] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2194.331741] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2194.394426] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2194.450537] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2194.512524] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2194.573794] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2194.664361] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2194.737805] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2194.825059] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2194.919537] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2194.985871] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2195.049607] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2195.107088] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2195.167735] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2195.243678] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2195.340667] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2195.388144] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2195.427130] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2195.469936] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2195.549650] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2195.630434] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2195.672206] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2195.769702] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2195.819666] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2195.875118] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2195.940593] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2196.014285] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2196.056347] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2196.099154] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2196.180580] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2196.233555] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2196.290746] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2196.345742] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2196.421589] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2196.448629] Lustre: lustre-OST0001-osc-ffff89eca035f800: disconnect after 25s idle [ 2196.497731] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2196.555793] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2196.633646] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2196.697298] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2196.768388] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2196.887923] rw_seq_cst_vs_d (32406): drop_caches: 3 [ 2204.397984] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 02:18:33 (1769757513) [ 2204.829113] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2204.894847] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2204.994406] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2205.081171] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2205.116095] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2205.319590] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2205.490853] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2205.606826] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2205.783728] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2205.968508] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2206.134350] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2206.314532] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2206.417204] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2206.491496] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2206.595600] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2206.674233] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2206.733557] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2206.917945] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2206.945973] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2206.984687] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2207.117840] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2207.225855] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2207.269591] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2207.438071] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2207.563113] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2207.628371] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2207.769051] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2208.043209] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2208.208378] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2208.291081] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2208.350874] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2208.480467] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2208.875872] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2208.911103] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2209.028847] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2209.180465] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2209.257224] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2209.315462] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2209.363613] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2209.466652] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2209.542779] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2209.660177] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2209.754899] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2209.891663] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2210.031075] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2210.279519] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2210.338716] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2210.375131] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2210.487870] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2210.603103] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2210.773387] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2210.798972] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2210.899197] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2210.956376] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2211.055828] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2211.157713] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2211.294276] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2211.492913] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2211.554780] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2211.686163] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2211.852269] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2211.961247] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2212.075151] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2212.171473] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2212.212809] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2212.430320] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2212.553293] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2212.693585] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2212.829586] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2212.982412] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2213.147714] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2213.347145] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2213.623500] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2213.768536] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2213.867937] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2214.020789] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2214.232829] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2214.387404] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2214.507633] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2214.641257] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2214.707656] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2214.805604] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2214.858152] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2214.943390] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2215.018585] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2215.075935] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2215.269210] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2215.379823] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2215.439605] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2215.478185] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2215.628345] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2215.759696] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2215.978698] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2216.090789] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2216.164386] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2216.226969] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2216.286562] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2216.415618] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2216.573172] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2216.671834] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2216.767179] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2216.930404] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2217.068551] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2217.178906] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2217.248769] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2217.300133] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2217.546064] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2217.604238] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2217.863401] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2218.010196] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2218.186870] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2218.285716] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2218.375256] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2218.491710] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2218.557766] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2218.660592] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2218.705513] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2218.792399] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2218.902765] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2219.170521] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2219.221994] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2219.414367] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2219.682884] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2219.799311] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2219.856374] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2219.971859] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2220.082178] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2220.209478] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2220.337721] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2220.379036] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2220.467321] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2220.529810] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2220.751068] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2220.801432] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2220.847597] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2220.970401] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2221.064289] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2221.136926] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2221.300981] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2221.380142] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2221.481468] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2221.548212] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2221.696334] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2221.814371] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2221.899447] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2221.952414] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2222.068741] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2222.160420] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2222.275847] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2222.314543] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2222.351858] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2222.399986] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2222.512486] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2222.590504] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2222.705492] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2222.815575] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2223.032282] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2223.142454] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2223.289788] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2223.344227] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2223.465532] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2223.616238] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2223.719372] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2223.811710] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2223.884560] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2223.955515] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2224.173302] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2224.212087] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2224.308581] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2224.401049] rw_seq_cst_vs_d (32989): drop_caches: 3 [ 2227.170613] Lustre: lustre-OST0000-osc-ffff89eca035f800: disconnect after 24s idle [ 2231.438877] Lustre: DEBUG MARKER: == sanityn test 16h: mmap read after truncate file ======= 02:19:00 (1769757540) [ 2238.095384] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 02:19:07 (1769757547) [ 2245.198343] Lustre: DEBUG MARKER: == sanityn test 16j: race dio with buffered i/o ========== 02:19:14 (1769757554) [ 2276.088859] Lustre: DEBUG MARKER: == sanityn test 16k: Parallel FSX and drop caches should not panic ========================================================== 02:19:45 (1769757585) [ 2276.611470] bash (35477): drop_caches: 3 [ 2279.967740] bash (35477): drop_caches: 3 [ 2283.143947] bash (35477): drop_caches: 3 [ 2286.296403] bash (35477): drop_caches: 3 [ 2289.455335] bash (35477): drop_caches: 3 [ 2292.602866] bash (35477): drop_caches: 3 [ 2295.794027] bash (35477): drop_caches: 3 [ 2299.189278] bash (35477): drop_caches: 3 [ 2302.354963] bash (35477): drop_caches: 3 [ 2305.667472] bash (35477): drop_caches: 3 [ 2308.825810] bash (35477): drop_caches: 3 [ 2312.013261] bash (35477): drop_caches: 3 [ 2315.179267] bash (35477): drop_caches: 3 [ 2320.026873] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 02:20:29 (1769757629) [ 2330.509412] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 02:20:39 (1769757639) [ 2351.159616] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 02:21:00 (1769757660) [ 2358.491497] Lustre: DEBUG MARKER: loop 5 [ 2364.195444] Lustre: DEBUG MARKER: loop 10 [ 2365.415173] Lustre: lustre-OST0001-osc-ffff89eca035f800: disconnect after 22s idle [ 2365.428222] Lustre: Skipped 1 previous similar message [ 2370.551955] Lustre: DEBUG MARKER: loop 15 [ 2376.469552] Lustre: DEBUG MARKER: loop 20 [ 2384.564339] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 02:21:33 (1769757693) [ 2390.983637] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 02:21:40 (1769757700) [ 2397.589874] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 02:21:47 (1769757707) [ 2406.367365] Lustre: lustre-OST0000-osc-ffff89eca035f800: disconnect after 22s idle [ 2406.379393] Lustre: Skipped 1 previous similar message [ 2465.318569] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 02:22:54 (1769757774) [ 2470.675346] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 02:23:00 (1769757780) [ 2475.850880] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 02:23:05 (1769757785) [ 2481.978351] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 02:23:11 (1769757791) [ 2488.511184] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 02:23:17 (1769757797) [ 2494.987825] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 02:23:24 (1769757804) [ 2503.432822] Lustre: DEBUG MARKER: == sanityn test 26c: set-in-past on open file is not lost on close ========================================================== 02:23:32 (1769757812) [ 2510.796946] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 2512.432064] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 02:23:41 (1769757821) [ 2520.603408] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 02:23:50 (1769757830) [ 2520.986718] Lustre: *** cfs_fail_loc=314, val=0*** [ 2522.015947] Lustre: *** cfs_fail_loc=314, val=0*** [ 2522.017982] Lustre: Skipped 2 previous similar messages [ 2527.207561] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 02:23:56 (1769757836) [ 2529.247232] Lustre: lustre-OST0001-osc-ffff89eca035f800: disconnect after 24s idle [ 2529.256837] Lustre: Skipped 3 previous similar messages [ 2536.120663] Lustre: *** cfs_fail_loc=314, val=0*** [ 2536.223827] LustreError: lustre-OST0000-osc-ffff89ec911e2000: operation ldlm_enqueue to node 192.168.206.107@tcp failed: rc = -107 [ 2536.232043] Lustre: lustre-OST0000-osc-ffff89ec911e2000: Connection to lustre-OST0000 (at 192.168.206.107@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2536.240669] LustreError: lustre-OST0000-osc-ffff89ec911e2000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 2536.249170] LustreError: 46394:0:(ldlm_resource.c:1170:ldlm_resource_complain()) lustre-OST0000-osc-ffff89ec911e2000: namespace resource [0x280000401:0x37:0x0].0x0 (ffff89ec90ed3600) refcount nonzero (1) after lock cleanup; forcing cleanup. [ 2536.262318] Lustre: lustre-OST0000-osc-ffff89ec911e2000: Connection restored to 192.168.206.107@tcp (at 192.168.206.107@tcp) [ 2542.669662] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 02:24:12 (1769757852) [ 2542.998152] LustreError: 46984:0:(file.c:785:ll_intent_file_open()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 2546.039782] LustreError: 46984:0:(file.c:785:ll_intent_file_open()) cfs_fail_timeout id 1419 awake [ 2552.117220] Lustre: DEBUG MARKER: == sanityn test 31s: open should not revalidate invalid dentry ========================================================== 02:24:21 (1769757861) [ 2558.491572] Lustre: DEBUG MARKER: == sanityn test 31t: getattr should not revalidate invalid dentry ========================================================== 02:24:27 (1769757867) [ 2566.736485] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 2568.510962] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 02:24:37 (1769757877) [ 2570.442336] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 2571.838397] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-on-Lock-Cancel ========================================================== 02:24:41 (1769757881) [ 2575.333242] Lustre: lustre-MDT0000-mdc-ffff89ec911e2000: Connection to lustre-MDT0000 (at 192.168.206.107@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2575.344514] Lustre: Skipped 1 previous similar message [ 2585.583476] LustreError: MGC192.168.206.107@tcp: Connection to MGS (at 192.168.206.107@tcp) was lost; in progress operations using this service will fail [ 2585.601031] Lustre: Evicted from MGS (at 192.168.206.107@tcp) after server handle changed from 0x675ae00b1c81f19a to 0x675ae00b1c8cbd35 [ 2585.607373] Lustre: MGC192.168.206.107@tcp: Connection restored to 192.168.206.107@tcp (at 192.168.206.107@tcp) [ 2587.635069] Lustre: lustre-MDT0000-mdc-ffff89eca035f800: Connection restored to 192.168.206.107@tcp (at 192.168.206.107@tcp) [ 2614.518735] Lustre: DEBUG MARKER: == sanityn test 33d: dependent transactions should trigger COS ========================================================== 02:25:23 (1769757923) [ 2658.131538] Lustre: DEBUG MARKER: == sanityn test 33e: independent transactions shouldn't trigger COS ========================================================== 02:26:07 (1769757967) [ 2675.575876] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 02:26:24 (1769757984) [ 2727.932160] Lustre: lustre-OST0000-osc-ffff89eca035f800: Connection to lustre-OST0000 (at 192.168.206.107@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2727.975231] LustreError: lustre-OST0000-osc-ffff89eca035f800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 2727.997312] Lustre: lustre-OST0000-osc-ffff89eca035f800: Connection restored to 192.168.206.107@tcp (at 192.168.206.107@tcp) [ 2728.016980] Lustre: Skipped 1 previous similar message [ 2728.949975] LustreError: lustre-OST0000-osc-ffff89ec911e2000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 2743.242468] Lustre: lustre-OST0001-osc-ffff89eca035f800: Connection to lustre-OST0001 (at 192.168.206.107@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2743.250425] Lustre: Skipped 1 previous similar message [ 2743.285381] LustreError: lustre-OST0001-osc-ffff89eca035f800: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 2743.316835] Lustre: lustre-OST0001-osc-ffff89eca035f800: Connection restored to 192.168.206.107@tcp (at 192.168.206.107@tcp) [ 2743.339397] Lustre: Skipped 1 previous similar message [ 2749.407243] Lustre: lustre-OST0000-osc-ffff89eca035f800: disconnect after 22s idle [ 2760.599579] Lustre: DEBUG MARKER: oleg607-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff89ec911e2000.ost_server_uuid 50 [ 2762.104516] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff89ec911e2000.ost_server_uuid in IDLE state after 0 sec [ 2765.570349] Lustre: DEBUG MARKER: oleg607-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff89ec911e2000.ost_server_uuid 50 [ 2766.952756] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff89ec911e2000.ost_server_uuid in FULL state after 0 sec [ 2771.027138] Lustre: DEBUG MARKER: oleg607-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff89ec911e2000.ost_server_uuid 50 [ 2772.581814] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff89ec911e2000.ost_server_uuid in IDLE state after 0 sec [ 2776.015745] Lustre: DEBUG MARKER: oleg607-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff89ec911e2000.ost_server_uuid 50 [ 2777.259455] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff89ec911e2000.ost_server_uuid in FULL state after 0 sec [ 2787.586403] Lustre: DEBUG MARKER: oleg607-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff89ec911e2000.ost_server_uuid 50 [ 2789.043732] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff89ec911e2000.ost_server_uuid in IDLE state after 0 sec [ 2792.567996] Lustre: DEBUG MARKER: oleg607-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff89ec911e2000.ost_server_uuid 50 [ 2793.840724] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff89ec911e2000.ost_server_uuid in FULL state after 0 sec [ 2795.522616] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 02:28:24 (1769758104) [ 2798.329461] Lustre: DEBUG MARKER: Race attempt 0 [ 2800.939402] Lustre: DEBUG MARKER: Wait for 57468 57513 for 60 sec... [ 2866.791885] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 02:29:36 (1769758176) [ 2874.255752] Lustre: DEBUG MARKER: start test - cycle (0) [ 2895.689772] Lustre: DEBUG MARKER: start test - cycle (1) [ 2916.537482] Lustre: DEBUG MARKER: start test - cycle (2) [ 2936.821751] Lustre: DEBUG MARKER: start test - cycle (3) [ 2956.867952] Lustre: DEBUG MARKER: start test - cycle (4) [ 2977.849339] Lustre: DEBUG MARKER: start test - cycle (5) [ 2997.282134] Lustre: DEBUG MARKER: start test - cycle (6) [ 3018.798868] Lustre: DEBUG MARKER: start test - cycle (7) [ 3038.807335] Lustre: DEBUG MARKER: start test - cycle (8) [ 3059.370653] Lustre: DEBUG MARKER: start test - cycle (9) [ 3080.087168] Lustre: DEBUG MARKER: start test - cycle (10) [ 3105.961601] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 02:33:35 (1769758415) [ 3112.932615] Lustre: lustre-OST0000-osc-ffff89ec911e2000: disconnect after 23s idle [ 3112.940951] Lustre: Skipped 2 previous similar messages [ 3159.829622] Lustre: DEBUG MARKER: == sanityn test 39a: file mtime does not change after rename ========================================================== 02:34:29 (1769758469) [ 3164.727503] Lustre: DEBUG MARKER: == sanityn test 39b: file mtime the same on clients with/out lock ========================================================== 02:34:34 (1769758474) [ 3170.533755] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 02:34:40 (1769758480) [ 3176.340599] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 02:34:46 (1769758486) [ 3176.532804] Lustre: *** cfs_fail_loc=411, val=0*** [ 3181.084570] Lustre: DEBUG MARKER: SKIP: sanityn test_40a skipping ALWAYS excluded test 40a [ 3182.174826] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 02:34:51 (1769758491) [ 3195.083492] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 02:35:04 (1769758504) [ 3207.380312] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 02:35:17 (1769758517) [ 3220.869214] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 02:35:30 (1769758530) [ 3233.401347] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 02:35:42 (1769758542) [ 3242.705596] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 02:35:52 (1769758552) [ 3252.053128] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 02:36:01 (1769758561) [ 3262.063954] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 02:36:11 (1769758571) [ 3271.059428] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 02:36:20 (1769758580) [ 3280.638918] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 02:36:30 (1769758590) [ 3289.589803] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 02:36:39 (1769758599) [ 3298.790471] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 02:36:48 (1769758608) [ 3308.302569] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 02:36:57 (1769758617) [ 3937.250767] Lustre: lustre-OST0000-osc-ffff89ec911e2000: disconnect after 23s idle [ 3937.254038] Lustre: Skipped 9 previous similar messages [ 4134.946980] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 02:50:45 (1769759445) [ 4142.381401] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 02:50:52 (1769759452) [ 4149.439961] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 02:50:59 (1769759459) [ 4155.496359] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 02:51:05 (1769759465) [ 4162.166768] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 02:51:12 (1769759472) [ 4168.892202] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 02:51:18 (1769759478) [ 4175.477426] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 02:51:25 (1769759485) [ 4181.417151] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 02:51:31 (1769759491) [ 4187.218386] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 02:51:37 (1769759497) [ 4231.786708] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 02:52:21 (1769759541) [ 4238.272539] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 02:52:28 (1769759548) [ 4245.752561] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 02:52:35 (1769759555) [ 4253.221740] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 02:52:43 (1769759563) [ 4260.750236] Lustre: DEBUG MARKER: == sanityn test 43f: pdirops: unlink and rename (src) ======================================================================== 02:52:50 (1769759570) [ 4268.772948] Lustre: DEBUG MARKER: == sanityn test 43g: pdirops: unlink vs getattr ======================================================================== 02:52:58 (1769759578) [ 4276.768484] Lustre: DEBUG MARKER: == sanityn test 43h: pdirops: unlink vs readdir ======================================================================== 02:53:06 (1769759586) [ 4284.396229] Lustre: DEBUG MARKER: == sanityn test 43i: pdirops: unlink vs remote mkdir ===== 02:53:14 (1769759594) [ 4291.969759] Lustre: DEBUG MARKER: == sanityn test 43j: racy mkdir return EEXIST ======================================================================== 02:53:21 (1769759601) [ 4350.852749] Lustre: DEBUG MARKER: == sanityn test 43k: unlink vs create ==================== 02:54:20 (1769759660) [ 4485.087400] Lustre: lustre-OST0001-osc-ffff89eca035f800: disconnect after 22s idle [ 4485.090496] Lustre: Skipped 5 previous similar messages [ 4912.698430] Lustre: DEBUG MARKER: == sanityn test 44a: pdirops: rename tgt vs mkdir ======================================================================== 03:03:42 (1769760222) [ 4918.302978] Lustre: DEBUG MARKER: == sanityn test 44b: pdirops: rename tgt vs create ======================================================================== 03:03:48 (1769760228) [ 4924.041814] Lustre: DEBUG MARKER: == sanityn test 44c: pdirops: rename tgt vs link ======================================================================== 03:03:54 (1769760234) [ 4929.649090] Lustre: DEBUG MARKER: == sanityn test 44d: pdirops: rename tgt vs unlink ======================================================================== 03:03:59 (1769760239) [ 4935.840188] Lustre: DEBUG MARKER: == sanityn test 44e: pdirops: rename tgt and rename (tgt) ======================================================================== 03:04:05 (1769760245) [ 4942.085438] Lustre: DEBUG MARKER: == sanityn test 44f: pdirops: rename tgt and rename (src) ======================================================================== 03:04:12 (1769760252) [ 4948.223719] Lustre: DEBUG MARKER: == sanityn test 44g: pdirops: rename tgt vs getattr ======================================================================== 03:04:18 (1769760258) [ 4954.375377] Lustre: DEBUG MARKER: == sanityn test 44h: pdirops: rename tgt vs readdir ======================================================================== 03:04:24 (1769760264) [ 4960.311907] Lustre: DEBUG MARKER: == sanityn test 44i: pdirops: rename tgt vs remote mkdir ========================================================== 03:04:30 (1769760270) [ 4967.068269] Lustre: DEBUG MARKER: == sanityn test 45a: rename,mkdir doesn't return -EEXIST ======================================================================== 03:04:36 (1769760276) [ 5030.011968] Lustre: DEBUG MARKER: == sanityn test 45b: pdirops: rename src vs create ======================================================================== 03:05:40 (1769760340) [ 5035.924106] Lustre: DEBUG MARKER: == sanityn test 45c: pdirops: rename src vs link ======================================================================== 03:05:46 (1769760346) [ 5041.549894] Lustre: DEBUG MARKER: == sanityn test 45d: pdirops: rename src vs unlink ======================================================================== 03:05:51 (1769760351) [ 5047.073020] Lustre: DEBUG MARKER: == sanityn test 45e: pdirops: rename src and rename (tgt) ======================================================================== 03:05:57 (1769760357) [ 5052.667845] Lustre: DEBUG MARKER: == sanityn test 45f: pdirops: rename src and rename (src) ======================================================================== 03:06:02 (1769760362) [ 5058.438814] Lustre: DEBUG MARKER: == sanityn test 45g: pdirops: rename src vs getattr ======================================================================== 03:06:08 (1769760368) [ 5064.267502] Lustre: DEBUG MARKER: == sanityn test 45h: pdirops: unlink vs readdir ======================================================================== 03:06:14 (1769760374) [ 5069.447677] Lustre: DEBUG MARKER: == sanityn test 45i: pdirops: rename src vs remote mkdir ========================================================== 03:06:19 (1769760379) [ 5075.570078] Lustre: DEBUG MARKER: == sanityn test 45j: read vs rename ====================== 03:06:25 (1769760385) [ 5545.630144] Lustre: DEBUG MARKER: == sanityn test 46a: pdirops: link vs mkdir ======================================================================== 03:14:15 (1769760855) [ 5550.874289] Lustre: DEBUG MARKER: == sanityn test 46b: pdirops: link vs create ======================================================================== 03:14:21 (1769760861) [ 5555.985113] Lustre: DEBUG MARKER: == sanityn test 46c: pdirops: link vs link ======================================================================== 03:14:26 (1769760866) [ 5561.086485] Lustre: DEBUG MARKER: == sanityn test 46d: pdirops: link vs unlink ======================================================================== 03:14:31 (1769760871) [ 5566.370086] Lustre: DEBUG MARKER: == sanityn test 46e: pdirops: link and rename (tgt) ======================================================================== 03:14:36 (1769760876) [ 5571.599957] Lustre: DEBUG MARKER: == sanityn test 46f: pdirops: link and rename (src) ======================================================================== 03:14:41 (1769760881) [ 5576.828895] Lustre: DEBUG MARKER: == sanityn test 46g: pdirops: link vs getattr ======================================================================== 03:14:46 (1769760886) [ 5582.004469] Lustre: DEBUG MARKER: == sanityn test 46h: pdirops: link vs readdir ======================================================================== 03:14:52 (1769760892) [ 5587.242740] Lustre: DEBUG MARKER: == sanityn test 46i: pdirops: link vs remote mkdir ======= 03:14:57 (1769760897) [ 5592.345183] Lustre: DEBUG MARKER: == sanityn test 47a: pdirops: remote mkdir vs mkdir ====== 03:15:02 (1769760902) [ 5597.459217] Lustre: DEBUG MARKER: == sanityn test 47b: pdirops: remote mkdir vs create ===== 03:15:07 (1769760907) [ 5603.778652] Lustre: DEBUG MARKER: == sanityn test 47c: pdirops: remote mkdir vs link ======= 03:15:13 (1769760913) [ 5609.250836] Lustre: DEBUG MARKER: == sanityn test 47d: pdirops: remote mkdir vs unlink ===== 03:15:19 (1769760919) [ 5614.973286] Lustre: DEBUG MARKER: == sanityn test 47e: pdirops: remote mkdir and rename (tgt) ========================================================== 03:15:24 (1769760924) [ 5620.487477] Lustre: DEBUG MARKER: == sanityn test 47f: pdirops: remote mkdir and rename (src) ========================================================== 03:15:30 (1769760930) [ 5625.939656] Lustre: DEBUG MARKER: == sanityn test 47g: pdirops: remote mkdir vs getattr ==== 03:15:36 (1769760936) [ 5631.967206] Lustre: lustre-OST0001-osc-ffff89eca035f800: disconnect after 20s idle [ 5631.969337] Lustre: Skipped 6 previous similar messages [ 5632.039105] Lustre: DEBUG MARKER: == sanityn test 50: osc lvb attrs: enqueue vs. CP AST ======================================================================== 03:15:42 (1769760942) [ 5632.126463] LustreError: 6170:0:(ldlm_lockd.c:2078:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 sleeping for 2000ms [ 5634.207105] LustreError: 6170:0:(ldlm_lockd.c:2078:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 awake [ 5639.194062] Lustre: DEBUG MARKER: == sanityn test 51a: layout lock: refresh layout should work ========================================================== 03:15:49 (1769760949) [ 5643.506621] Lustre: DEBUG MARKER: == sanityn test 51b: layout lock: glimpse should be able to restart if layout changed ========================================================== 03:15:53 (1769760953) [ 5643.579402] LustreError: 238114:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 5647.639085] LustreError: 238114:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 5647.649877] LustreError: 238114:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 5651.711104] LustreError: 238114:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 5651.723669] LustreError: 238121:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 5655.783095] LustreError: 238121:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 5657.879622] Lustre: DEBUG MARKER: == sanityn test 51c: layout lock: IT_LAYOUT blocked and correct layout can be returned ========================================================== 03:16:08 (1769760968) [ 5664.681080] Lustre: DEBUG MARKER: == sanityn test 51d: layout lock: losing layout lock should clean up memory map region ========================================================== 03:16:14 (1769760974) [ 5667.793343] Lustre: DEBUG MARKER: == sanityn test 51e: lfs getstripe does not break leases, part 2 ========================================================== 03:16:17 (1769760977) [ 5671.969146] Lustre: DEBUG MARKER: == sanityn test 54: rename locking ======================= 03:16:22 (1769760982) [ 5696.456721] Lustre: DEBUG MARKER: == sanityn test 55a: rename vs unlink target dir ========= 03:16:46 (1769761006) [ 5704.148783] Lustre: DEBUG MARKER: == sanityn test 55b: rename vs unlink source dir ========= 03:16:54 (1769761014) [ 5711.851192] Lustre: DEBUG MARKER: == sanityn test 55c: rename vs unlink orphan target dir == 03:17:01 (1769761021) [ 5724.922980] Lustre: DEBUG MARKER: == sanityn test 55d: rename file vs link ================= 03:17:15 (1769761035) [ 5734.674993] Lustre: DEBUG MARKER: == sanityn test 55e: rename race AB/BA under the same parent dir ========================================================== 03:17:24 (1769761044) [ 5748.177546] Lustre: DEBUG MARKER: == sanityn test 55f: rename: (P1/A -> P2/B) race with (P2/B -> P1/A) ========================================================== 03:17:38 (1769761058) [ 5761.426563] Lustre: DEBUG MARKER: == sanityn test 55g: rename: race with trylock =========== 03:17:51 (1769761071) [ 5775.470902] Lustre: DEBUG MARKER: == sanityn test 56a: test llverdev with single large stripe ========================================================== 03:18:05 (1769761085) [ 5783.159735] Lustre: DEBUG MARKER: == sanityn test 56b: test llverdev and partial verify of wide stripe file ========================================================== 03:18:13 (1769761093) [ 5813.642365] Lustre: DEBUG MARKER: == sanityn test 60: Verify data_version behaviour ======== 03:18:43 (1769761123) [ 5815.960763] Lustre: DEBUG MARKER: == sanityn test 70a: cd directory [ 5818.788327] Lustre: DEBUG MARKER: == sanityn test 70b: remove files after calling rm_entry ========================================================== 03:18:48 (1769761128) [ 5821.280669] Lustre: DEBUG MARKER: == sanityn test 71a: correct file map just after write operation is finished ========================================================== 03:18:51 (1769761131) [ 5823.682913] Lustre: DEBUG MARKER: == sanityn test 71b: check fiemap support for stripecount > 1 ========================================================== 03:18:53 (1769761133) [ 5825.885982] Lustre: DEBUG MARKER: == sanityn test 71c: check FIEMAP_EXTENT_LAST flag with different extents number ========================================================== 03:18:56 (1769761136) [ 5836.449846] Lustre: DEBUG MARKER: == sanityn test 71d: fiemap corruption test with fm_extent_count=0 ========================================================== 03:19:06 (1769761146) [ 5852.162270] Lustre: DEBUG MARKER: == sanityn test 72: getxattr/setxattr cache should be consistent between nodes ========================================================== 03:19:22 (1769761162) [ 5854.612148] Lustre: DEBUG MARKER: == sanityn test 73: getxattr should not cause xattr lock cancellation ========================================================== 03:19:24 (1769761164) [ 5857.077853] Lustre: DEBUG MARKER: == sanityn test 74: flock deadlock: different mounts ======================================================================== 03:19:27 (1769761167) [ 5860.144733] LustreError: lustre-MDT0000-mdc-ffff89eca035f800: operation ldlm_enqueue to node 192.168.206.107@tcp failed: rc = -35 [ 5860.148256] LustreError: Skipped 1 previous similar message [ 5863.238529] Lustre: DEBUG MARKER: == sanityn test 75: osc: upcall after unuse lock============================================================================= 03:19:33 (1769761173) [ 5863.391728] LustreError: 2408:0:(osc_request.c:3093:osc_enqueue_interpret()) cfs_fail_timeout id 31d sleeping for 2000ms [ 5865.471111] LustreError: 2408:0:(osc_request.c:3093:osc_enqueue_interpret()) cfs_fail_timeout id 31d awake [ 5870.685159] Lustre: DEBUG MARKER: == sanityn test 76: Verify MDT open_files listing ======== 03:19:40 (1769761180) [ 5926.546692] Lustre: DEBUG MARKER: == sanityn test 77a: check FIFO NRS policy =============== 03:20:36 (1769761236) [ 5929.696538] Lustre: DEBUG MARKER: == sanityn test 77b: check CRR-N NRS policy ============== 03:20:39 (1769761239) [ 5934.267099] Lustre: DEBUG MARKER: == sanityn test 77c: check ORR NRS policy ================ 03:20:44 (1769761244) [ 5940.266240] Lustre: DEBUG MARKER: == sanityn test 77d: check TRR nrs policy ================ 03:20:50 (1769761250) [ 5946.091846] Lustre: DEBUG MARKER: == sanityn test 77e: check TBF NID nrs policy ============ 03:20:56 (1769761256) [ 5953.968873] Lustre: DEBUG MARKER: == sanityn test 77f: check TBF JobID nrs policy ========== 03:21:04 (1769761264) [ 5962.386985] Lustre: DEBUG MARKER: == sanityn test 77g: Change TBF type directly ============ 03:21:12 (1769761272) [ 5966.232969] Lustre: DEBUG MARKER: == sanityn test 77h: Wrong policy name should report error, not LBUG ========================================================== 03:21:16 (1769761276) [ 5970.488212] Lustre: DEBUG MARKER: == sanityn test 77i: Change rank of TBF rule ============= 03:21:20 (1769761280) [ 5978.489876] Lustre: DEBUG MARKER: == sanityn test 77j: check TBF-OPCode NRS policy ========= 03:21:28 (1769761288) [ 6020.085060] Lustre: DEBUG MARKER: == sanityn test 77ja: check TBF-UID/GID NRS policy ======= 03:22:10 (1769761330) [ 6130.521870] Lustre: DEBUG MARKER: == sanityn test 77jb: check TBF-UID/GID NRS policy on files that don't belong to us ========================================================== 03:24:00 (1769761440) [ 6240.759322] Lustre: DEBUG MARKER: == sanityn test 77k: check TBF policy with UID/GID/JobID/OPCode expression ========================================================== 03:25:50 (1769761550) [ 6261.727190] Lustre: lustre-OST0001-osc-ffff89eca035f800: disconnect after 20s idle [ 6261.729562] Lustre: Skipped 10 previous similar messages [ 6515.889384] Lustre: DEBUG MARKER: == sanityn test 77kb: Check different granular TBF type combination: jobid+opcode ========================================================== 03:30:26 (1769761826) [ 6543.868206] Lustre: DEBUG MARKER: == sanityn test 77kc: Check different granular TBF type combination: nid+opcode ========================================================== 03:30:54 (1769761854) [ 6571.789203] Lustre: DEBUG MARKER: == sanityn test 77kd: Check different granular TBF type combination: nid+jobid ========================================================== 03:31:21 (1769761881) [ 6595.478831] Lustre: DEBUG MARKER: == sanityn test 77ke: Check different granular TBF type combination: jobid+opcode ========================================================== 03:31:45 (1769761905) [ 6654.214568] Lustre: DEBUG MARKER: == sanityn test 77kf: Check different granular TBF type combination: uid+gid ========================================================== 03:32:44 (1769761964) [ 6705.696123] Lustre: DEBUG MARKER: == sanityn test 77kg: check different granular TBF type combination: uid+opcode ========================================================== 03:33:35 (1769762015) [ 6790.428118] Lustre: DEBUG MARKER: == sanityn test 77kh: Verify that Project ID support for NRS TBF rule ========================================================== 03:35:00 (1769762100) [ 6791.492568] LustreError: 285109:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89eca035f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6791.497161] LustreError: 285109:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 6791.513083] Lustre: Unmounted lustre-client [ 6792.507363] LustreError: 285122:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89ec911e2000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6792.510817] LustreError: 285122:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 6792.513106] LustreError: 285122:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 6792.514954] LustreError: 285122:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 6792.533102] Lustre: Unmounted lustre-client [ 6848.512903] Lustre: Mounted lustre-client [ 6850.025538] Lustre: Mounted lustre-client [ 6851.037145] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 6870.495186] Lustre: lustre-OST0000-osc-ffff89ecbc630800: disconnect after 21s idle [ 6870.497576] Lustre: Skipped 9 previous similar messages [ 6907.191830] Lustre: DEBUG MARKER: == sanityn test 77ki: Add rule with unsupported TBF type should fail for generic TBF ========================================================== 03:36:57 (1769762217) [ 6914.830407] Lustre: DEBUG MARKER: == sanityn test 77l: check the output of NRS policies for generic TBF ========================================================== 03:37:04 (1769762224) [ 6918.090157] Lustre: DEBUG MARKER: == sanityn test 77m: check NRS Delay slows write RPC processing ========================================================== 03:37:08 (1769762228) [ 6968.637932] Lustre: DEBUG MARKER: == sanityn test 77n: check wildcard support for TBF JobID NRS policy ========================================================== 03:37:58 (1769762278) [ 7013.282492] Lustre: DEBUG MARKER: == sanityn test 77o: Changing rank should not panic ====== 03:38:43 (1769762323) [ 7017.082758] Lustre: DEBUG MARKER: == sanityn test 77q: Parallel TBF rule definitions should not panic ========================================================== 03:38:47 (1769762327) [ 7056.748765] Lustre: DEBUG MARKER: == sanityn test 77p: Check validity of rule names for TBF policies ========================================================== 03:39:26 (1769762366) [ 7068.505452] Lustre: DEBUG MARKER: == sanityn test 77r: Change type of tbf policy at run time ========================================================== 03:39:38 (1769762378) [ 7111.051473] Lustre: DEBUG MARKER: == sanityn test 78: Enable policy and specify tunings right away ========================================================== 03:40:21 (1769762421) [ 7114.074888] Lustre: DEBUG MARKER: == sanityn test 79: xattr: intent error ================== 03:40:24 (1769762424) [ 7126.850610] Lustre: DEBUG MARKER: == sanityn test 80a: migrate directory when some children is being opened ========================================================== 03:40:36 (1769762436) [ 7130.512314] Lustre: DEBUG MARKER: == sanityn test 80b: Accessing directory during migration ========================================================== 03:40:40 (1769762440) [ 7130.832175] LustreError: 305946:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x3c6:0x0]: rc = -5 [ 7130.836456] LustreError: 305946:0:(llite_lib.c:3739:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 7131.356152] LustreError: 305986:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x3ce:0x0]: rc = -5 [ 7131.360811] LustreError: 305986:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 9 previous similar messages [ 7131.364995] LustreError: 305986:0:(llite_lib.c:3739:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 7131.369933] LustreError: 305986:0:(llite_lib.c:3739:ll_prep_inode()) Skipped 9 previous similar messages [ 7132.438970] LustreError: 306067:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x3f2:0x0]: rc = -5 [ 7132.444147] LustreError: 306067:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 23 previous similar messages [ 7132.448052] LustreError: 306067:0:(llite_lib.c:3739:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 7132.451091] LustreError: 306067:0:(llite_lib.c:3739:ll_prep_inode()) Skipped 23 previous similar messages [ 7134.529195] LustreError: 306241:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x420:0x0]: rc = -5 [ 7134.533430] LustreError: 306241:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 50 previous similar messages [ 7134.535463] LustreError: 306241:0:(llite_lib.c:3739:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 7134.537426] LustreError: 306241:0:(llite_lib.c:3739:ll_prep_inode()) Skipped 50 previous similar messages [ 7138.556451] LustreError: 306598:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000bd0:0xf8:0x0]: rc = -5 [ 7138.559913] LustreError: 306598:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 104 previous similar messages [ 7138.562225] LustreError: 306598:0:(llite_lib.c:3739:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 7138.564487] LustreError: 306598:0:(llite_lib.c:3739:ll_prep_inode()) Skipped 104 previous similar messages [ 7146.580913] LustreError: 307335:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000bd0:0x1ee:0x0]: rc = -5 [ 7146.585198] LustreError: 307335:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 255 previous similar messages [ 7146.588126] LustreError: 307335:0:(llite_lib.c:3739:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 7146.590501] LustreError: 307335:0:(llite_lib.c:3739:ll_prep_inode()) Skipped 255 previous similar messages [ 7162.648467] LustreError: 308812:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x742:0x0]: rc = -5 [ 7162.652159] LustreError: 308812:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 575 previous similar messages [ 7162.654760] LustreError: 308812:0:(llite_lib.c:3739:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 7162.657260] LustreError: 308812:0:(llite_lib.c:3739:ll_prep_inode()) Skipped 575 previous similar messages [ 7165.681694] LustreError: 309107:0:(file.c:249:ll_close_inode_openhandle()) lustre-clilmv-ffff89ecbc630800: inode [0x2000013a1:0x799:0x0] mdc close failed: rc = -2 [ 7166.388086] LustreError: 309172:0:(file.c:249:ll_close_inode_openhandle()) lustre-clilmv-ffff89ecbc630800: inode [0x2000013a1:0x79f:0x0] mdc close failed: rc = -2 [ 7272.406321] LustreError: 305785:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x81f:0x0]: rc = -5 [ 7272.411342] LustreError: 305785:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 289 previous similar messages [ 7272.414883] LustreError: 305785:0:(llite_lib.c:3739:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 7272.417093] LustreError: 305785:0:(llite_lib.c:3739:ll_prep_inode()) Skipped 289 previous similar messages [ 7272.419506] LustreError: lustre-MDT0000-mdc-ffff89ec9c53e800: operation mds_getattr_lock to node 192.168.206.107@tcp failed: rc = -107 [ 7272.425477] Lustre: lustre-MDT0000-mdc-ffff89ec9c53e800: Connection to lustre-MDT0000 (at 192.168.206.107@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7272.434235] LustreError: lustre-MDT0000-mdc-ffff89ec9c53e800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 7272.440839] LustreError: 309552:0:(file.c:249:ll_close_inode_openhandle()) lustre-clilmv-ffff89ec9c53e800: inode [0x2000013a1:0x81a:0x0] mdc close failed: rc = -108 [ 7272.448313] LustreError: 309552:0:(file.c:249:ll_close_inode_openhandle()) Skipped 1 previous similar message [ 7272.452368] LustreError: 309552:0:(file.c:6147:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [ 7272.459029] Lustre: lustre-MDT0000-mdc-ffff89ec9c53e800: Connection restored to 192.168.206.107@tcp (at 192.168.206.107@tcp) [ 7274.777169] Lustre: DEBUG MARKER: == sanityn test 81a: rename and stat under striped directory ========================================================== 03:43:04 (1769762584) [ 7277.074501] Lustre: DEBUG MARKER: == sanityn test 81b: rename under striped directory doesn't deadlock ========================================================== 03:43:07 (1769762587) [ 7322.071895] Lustre: DEBUG MARKER: == sanityn test 81c: rename revoke LOOKUP lock for remote object ========================================================== 03:43:52 (1769762632) [ 7322.569671] Lustre: DEBUG MARKER: SKIP: sanityn test_81c needs >= 4 MDTs [ 7323.110633] Lustre: DEBUG MARKER: == sanityn test 81d: parallel rename file cross-dir on same MDT ========================================================== 03:43:53 (1769762633) [ 7360.975657] Lustre: DEBUG MARKER: == sanityn test 82: fsetxattr and fgetxattr on orphan files ========================================================== 03:44:31 (1769762671) [ 7362.835554] Lustre: DEBUG MARKER: == sanityn test 83: access striped directory while it is being created/unlinked ========================================================== 03:44:33 (1769762673) [ 7485.105768] Lustre: DEBUG MARKER: == sanityn test 84: 0-nlink race in lu_object_find() ===== 03:46:35 (1769762795) [ 7492.455760] Lustre: DEBUG MARKER: == sanityn test 85: Lustre API root cache race =========== 03:46:42 (1769762802) [ 7495.439838] Lustre: DEBUG MARKER: == sanityn test 90: open/create and unlink striped directory ========================================================== 03:46:45 (1769762805) [ 7677.710879] Lustre: DEBUG MARKER: == sanityn test 91: chmod and unlink striped directory === 03:49:47 (1769762987) [ 7859.802468] Lustre: DEBUG MARKER: == sanityn test 92: create remote directory under orphan directory ========================================================== 03:52:49 (1769763169) [ 7861.855617] Lustre: DEBUG MARKER: == sanityn test 93: alloc_rr should not allocate on same ost ========================================================== 03:52:52 (1769763172) [ 7870.511692] Lustre: DEBUG MARKER: == sanityn test 94: signal vs CP callback race =========== 03:53:00 (1769763180) [ 7870.574478] Lustre: DEBUG MARKER: write [ 7870.589959] LustreError: 287366:0:(ldlm_request.c:1402:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 sleeping for 5000ms [ 7872.592768] Lustre: DEBUG MARKER: kill 372005 [ 7872.594585] LustreError: 372005:0:(ldlm_request.c:1287:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 sleeping for 6000ms [ 7875.687080] LustreError: 287366:0:(ldlm_request.c:1402:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 awake [ 7878.631059] LustreError: 372005:0:(ldlm_request.c:1287:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 awake [ 7880.605646] Lustre: DEBUG MARKER: == sanityn test 95a: Check readpage() on a page that was removed from page cache ========================================================== 03:53:10 (1769763190) [ 7882.756527] LustreError: 372619:0:(rw.c:1959:ll_readpage()) cfs_fail_timeout id 1422 sleeping for 10000ms [ 7892.847074] LustreError: 372619:0:(rw.c:1959:ll_readpage()) cfs_fail_timeout id 1422 awake [ 7895.062325] Lustre: DEBUG MARKER: == sanityn test 95b: Check readpage() on a page that is no longer uptodate ========================================================== 03:53:25 (1769763205) [ 7895.173982] LustreError: 373206:0:(rw.c:2204:ll_readpage()) cfs_fail_timeout id 1424 sleeping for 5000ms [ 7897.255114] LustreError: 373206:0:(rw.c:2204:ll_readpage()) cfs_fail_timeout interrupted [ 7903.340362] Lustre: DEBUG MARKER: == sanityn test 100a: DoM: glimpse RPCs for stat without IO lock (DoM only file) ========================================================== 03:53:33 (1769763213) [ 7903.863220] Lustre: DEBUG MARKER: SKIP: sanityn test_100a Reserved for glimpse-ahead [ 7904.388044] Lustre: DEBUG MARKER: == sanityn test 100b: DoM: no glimpse RPC for stat with IO lock (DoM only file) ========================================================== 03:53:34 (1769763214) [ 7906.794708] Lustre: DEBUG MARKER: == sanityn test 100c: DoM: write vs stat without IO lock (combined file) ========================================================== 03:53:36 (1769763216) [ 7908.905271] Lustre: DEBUG MARKER: == sanityn test 100d: DoM: write+truncate vs stat without IO lock (combined file) ========================================================== 03:53:39 (1769763219) [ 7911.032558] Lustre: DEBUG MARKER: == sanityn test 100e: DoM: read on open and file size ==== 03:53:41 (1769763221) [ 7913.105274] Lustre: DEBUG MARKER: == sanityn test 101a: Discard DoM data on unlink ========= 03:53:43 (1769763223) [ 7915.185908] Lustre: DEBUG MARKER: == sanityn test 101b: Discard DoM data on rename ========= 03:53:45 (1769763225) [ 7917.228507] Lustre: DEBUG MARKER: == sanityn test 101c: Discard DoM data on close-unlink === 03:53:47 (1769763227) [ 7920.330164] Lustre: DEBUG MARKER: == sanityn test 102: Test open by handle of unlinked file ========================================================== 03:53:50 (1769763230) [ 7922.910165] Lustre: DEBUG MARKER: == sanityn test 103: Test size correctness with lockahead ========================================================== 03:53:53 (1769763233) [ 7923.534708] Lustre: *** cfs_fail_loc=415, val=0*** [ 7930.152279] Lustre: DEBUG MARKER: == sanityn test 104: Verify that MDS stores atime/mtime/ctime during close ========================================================== 03:54:00 (1769763240) [ 7949.005232] Lustre: DEBUG MARKER: == sanityn test 105: Glimpse and lock cancel race ======== 03:54:19 (1769763259) [ 7949.091521] LustreError: 288271:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 sleeping for 5000ms [ 7949.093889] LustreError: 288271:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) Skipped 3 previous similar messages [ 7954.191050] LustreError: 286833:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [ 7954.193594] LustreError: 286833:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) Skipped 3 previous similar messages [ 7964.383106] LustreError: 288271:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [ 7964.385210] LustreError: 288271:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) Skipped 3 previous similar messages [ 7971.735082] Lustre: DEBUG MARKER: == sanityn test 106a: Verify the btime via statx() ======= 03:54:41 (1769763281) [ 7974.124994] Lustre: DEBUG MARKER: == sanityn test 106b: Glimpse RPCs test for statx ======== 03:54:44 (1769763284) [ 7976.383277] Lustre: DEBUG MARKER: == sanityn test 106c: Verify statx attributes mask ======= 03:54:46 (1769763286) [ 7978.574357] Lustre: DEBUG MARKER: == sanityn test 107a: Basic grouplock conflict =========== 03:54:48 (1769763288) [ 7982.731617] Lustre: DEBUG MARKER: == sanityn test 107b: Grouplock is added to the head of waiting list ========================================================== 03:54:52 (1769763292) [ 7990.900494] Lustre: DEBUG MARKER: == sanityn test 108a: lseek: parallel updates ============ 03:55:01 (1769763301) [ 7991.044644] LustreError: 383937:0:(osc_request.c:2944:osc_build_rpc()) cfs_fail_timeout id 414 sleeping for 4000ms [ 7991.047937] LustreError: 383937:0:(osc_request.c:2944:osc_build_rpc()) Skipped 6 previous similar messages [ 7995.103082] LustreError: 383937:0:(osc_request.c:2944:osc_build_rpc()) cfs_fail_timeout id 414 awake [ 7995.106045] LustreError: 383937:0:(osc_request.c:2944:osc_build_rpc()) Skipped 2 previous similar messages [ 7997.335916] Lustre: DEBUG MARKER: == sanityn test 109: Race with several mount instances on 1 node ========================================================== 03:55:07 (1769763307) [ 7999.044905] LustreError: 384646:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89ec9c53e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7999.052806] LustreError: 384646:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 7999.061445] LustreError: 384646:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 7999.063326] LustreError: 384646:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 7999.082785] Lustre: Unmounted lustre-client [ 7999.635190] LustreError: 384666:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89ecbc630800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7999.638325] LustreError: 384666:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 7999.643255] LustreError: 384666:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7999.645630] LustreError: 384666:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 7999.692081] Lustre: Unmounted lustre-client [ 8000.171092] Lustre: DEBUG MARKER: Iteration 1 [ 8000.258875] LustreError: 384829:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 8000.260333] LustreError: 384830:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 8000.263543] LustreError: 384829:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8000.315306] Lustre: Mounted lustre-client [ 8000.773910] LustreError: 384944:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89ebcb582000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8000.776473] LustreError: 384944:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 8000.779587] LustreError: 384945:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 8000.782262] LustreError: 384945:0:(obd_class.h:479:obd_check_dev()) Skipped 11 previous similar messages [ 8000.819051] Lustre: Unmounted lustre-client [ 8001.763957] Key type lgssc unregistered [ 8001.882363] LNet: 385188:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8001.885394] LNetError: 385188:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8001.893956] LNet: Removed LNI 192.168.206.7@tcp [ 8002.188089] Key type .llcrypt unregistered [ 8002.189044] Key type ._llcrypt unregistered [ 8002.432483] Key type ._llcrypt registered [ 8002.433716] Key type .llcrypt registered [ 8002.695705] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8002.701100] alg: No test for adler32 (adler32-zlib) [ 8003.661493] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 8003.921569] LNet: Added LNI 192.168.206.7@tcp [8/256/0/180] [ 8005.519093] Key type lgssc registered [ 8006.002115] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8009.767514] Lustre: DEBUG MARKER: Iteration 2 [ 8009.861624] LustreError: 386017:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 8009.861669] LustreError: 386018:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 8009.867118] LustreError: 386017:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 8010.930867] Lustre: Mounted lustre-client [ 8011.370381] LustreError: 386130:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89ec88559800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8011.376820] LustreError: 386130:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 8011.378913] LustreError: 386130:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 8011.425119] Lustre: Unmounted lustre-client [ 8012.261585] Key type lgssc unregistered [ 8012.369471] LNet: 386372:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8012.371590] LNetError: 386372:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8012.379745] LNet: Removed LNI 192.168.206.7@tcp [ 8012.585092] Key type .llcrypt unregistered [ 8012.587172] Key type ._llcrypt unregistered [ 8012.828163] Key type ._llcrypt registered [ 8012.829114] Key type .llcrypt registered [ 8013.013352] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8013.018982] alg: No test for adler32 (adler32-zlib) [ 8013.867164] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 8013.944511] LNet: Added LNI 192.168.206.7@tcp [8/256/0/180] [ 8015.519089] Key type lgssc registered [ 8015.867545] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8019.295770] Lustre: DEBUG MARKER: Iteration 3 [ 8019.396927] LustreError: 387200:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 8019.396967] LustreError: 387201:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 8019.402258] LustreError: 387200:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8020.457185] Lustre: Mounted lustre-client [ 8020.459232] Lustre: Skipped 1 previous similar message [ 8020.901125] LustreError: 387311:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89ec98ec8000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8020.903224] LustreError: 387310:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8020.905605] LustreError: 387311:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8020.946126] Lustre: Unmounted lustre-client [ 8021.847410] Key type lgssc unregistered [ 8021.951501] LNet: 387554:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8021.954795] LNetError: 387554:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8021.963805] LNet: Removed LNI 192.168.206.7@tcp [ 8022.182112] Key type .llcrypt unregistered [ 8022.183729] Key type ._llcrypt unregistered [ 8022.466596] Key type ._llcrypt registered [ 8022.468015] Key type .llcrypt registered [ 8022.635896] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8022.640529] alg: No test for adler32 (adler32-zlib) [ 8023.504866] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 8023.586615] LNet: Added LNI 192.168.206.7@tcp [8/256/0/180] [ 8025.167155] Key type lgssc registered [ 8025.524515] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8028.823393] Lustre: DEBUG MARKER: Iteration 4 [ 8028.921886] LustreError: 388383:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 8028.921931] LustreError: 388384:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 8028.926945] LustreError: 388383:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 8029.979862] Lustre: Mounted lustre-client [ 8029.981414] Lustre: Skipped 1 previous similar message [ 8030.441643] LustreError: 388496:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89ec84d07000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8030.447167] LustreError: 388496:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 8030.449118] LustreError: 388496:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 8030.488236] Lustre: Unmounted lustre-client [ 8031.352870] Key type lgssc unregistered [ 8031.460490] LNet: 388739:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8031.462767] LNetError: 388739:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8031.473594] LNet: Removed LNI 192.168.206.7@tcp [ 8031.699097] Key type .llcrypt unregistered [ 8031.700136] Key type ._llcrypt unregistered [ 8032.026201] Key type ._llcrypt registered [ 8032.027176] Key type .llcrypt registered [ 8032.180345] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8032.185972] alg: No test for adler32 (adler32-zlib) [ 8033.030241] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 8033.105859] LNet: Added LNI 192.168.206.7@tcp [8/256/0/180] [ 8034.687146] Key type lgssc registered [ 8035.090640] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8038.676581] Lustre: DEBUG MARKER: Iteration 5 [ 8038.775448] LustreError: 389567:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 8038.776530] LustreError: 389568:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 8038.779038] LustreError: 389567:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8039.840076] Lustre: Mounted lustre-client [ 8040.285110] LustreError: 389679:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89ec91896000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8040.287649] LustreError: 389680:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 8040.289116] LustreError: 389679:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8040.335075] Lustre: Unmounted lustre-client [ 8041.266803] Key type lgssc unregistered [ 8041.382510] LNet: 389921:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8041.385902] LNetError: 389921:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8041.397710] LNet: Removed LNI 192.168.206.7@tcp [ 8041.635076] Key type .llcrypt unregistered [ 8041.636111] Key type ._llcrypt unregistered [ 8041.861252] Key type ._llcrypt registered [ 8041.862461] Key type .llcrypt registered [ 8042.095845] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8042.100147] alg: No test for adler32 (adler32-zlib) [ 8042.958413] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 8043.033751] LNet: Added LNI 192.168.206.7@tcp [8/256/0/180] [ 8044.615126] Key type lgssc registered [ 8044.996940] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8048.291276] Lustre: DEBUG MARKER: Iteration 6 [ 8048.391198] LustreError: 390750:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 8048.391237] LustreError: 390751:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 8048.397330] LustreError: 390750:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 8049.467617] Lustre: Mounted lustre-client [ 8049.468529] Lustre: Skipped 1 previous similar message [ 8049.913674] LustreError: 390858:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89eca6a22000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8049.918407] LustreError: 390858:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 8049.922763] LustreError: 390858:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [ 8049.949100] Lustre: Unmounted lustre-client [ 8050.853531] Key type lgssc unregistered [ 8050.960543] LNet: 391100:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8050.962584] LNetError: 391100:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8050.971733] LNet: Removed LNI 192.168.206.7@tcp [ 8051.182100] Key type .llcrypt unregistered [ 8051.183046] Key type ._llcrypt unregistered [ 8051.420491] Key type ._llcrypt registered [ 8051.421438] Key type .llcrypt registered [ 8051.611934] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8051.618429] alg: No test for adler32 (adler32-zlib) [ 8052.468562] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 8052.548165] LNet: Added LNI 192.168.206.7@tcp [8/256/0/180] [ 8054.127116] Key type lgssc registered [ 8054.473297] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8057.667441] Lustre: DEBUG MARKER: Iteration 7 [ 8057.771763] LustreError: 391928:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 8057.771819] LustreError: 391929:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 8057.777310] LustreError: 391928:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 8058.826412] Lustre: Mounted lustre-client [ 8058.827427] Lustre: Skipped 1 previous similar message [ 8059.287876] LustreError: 392037:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89ec86614000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8059.290985] LustreError: 392036:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8059.291494] LustreError: 392037:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8059.325824] Lustre: Unmounted lustre-client [ 8060.229081] Key type lgssc unregistered [ 8060.336547] LNet: 392278:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8060.340597] LNetError: 392278:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8060.348409] LNet: Removed LNI 192.168.206.7@tcp [ 8060.572084] Key type .llcrypt unregistered [ 8060.573078] Key type ._llcrypt unregistered [ 8060.809452] Key type ._llcrypt registered [ 8060.810366] Key type .llcrypt registered [ 8060.994169] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8061.002733] alg: No test for adler32 (adler32-zlib) [ 8061.847246] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 8061.922022] LNet: Added LNI 192.168.206.7@tcp [8/256/0/180] [ 8063.503114] Key type lgssc registered [ 8063.844767] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8067.215545] Lustre: DEBUG MARKER: Iteration 8 [ 8067.310763] LustreError: 393106:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 8067.312848] LustreError: 393107:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 8067.315272] LustreError: 393106:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8068.369310] Lustre: Mounted lustre-client [ 8068.370246] Lustre: Skipped 1 previous similar message [ 8068.845810] LustreError: 393218:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89ec91281000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8068.848879] LustreError: 393218:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8068.849370] LustreError: 393219:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 8068.853110] LustreError: 393219:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 8068.894094] Lustre: Unmounted lustre-client [ 8069.888493] Key type lgssc unregistered [ 8070.008442] LNet: 393461:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8070.010660] LNetError: 393461:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8070.019033] LNet: Removed LNI 192.168.206.7@tcp [ 8070.285104] Key type .llcrypt unregistered [ 8070.286217] Key type ._llcrypt unregistered [ 8070.592874] Key type ._llcrypt registered [ 8070.598721] Key type .llcrypt registered [ 8070.814099] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8070.819856] alg: No test for adler32 (adler32-zlib) [ 8071.689564] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 8071.781492] LNet: Added LNI 192.168.206.7@tcp [8/256/0/180] [ 8073.375141] Key type lgssc registered [ 8073.787834] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8077.398551] Lustre: DEBUG MARKER: Iteration 9 [ 8077.491583] LustreError: 394289:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 8077.491656] LustreError: 394290:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 8077.496832] LustreError: 394289:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 8078.568404] Lustre: Mounted lustre-client [ 8078.569718] Lustre: Skipped 1 previous similar message [ 8079.139456] LustreError: 394404:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89ebcb581000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8079.143250] LustreError: 394405:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8079.143717] LustreError: 394404:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8079.192220] Lustre: Unmounted lustre-client [ 8080.255401] Key type lgssc unregistered [ 8080.377559] LNet: 394647:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8080.380677] LNetError: 394647:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8080.392158] LNet: Removed LNI 192.168.206.7@tcp [ 8080.639142] Key type .llcrypt unregistered [ 8080.640901] Key type ._llcrypt unregistered [ 8081.085531] Key type ._llcrypt registered [ 8081.088304] Key type .llcrypt registered [ 8081.251423] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8081.256763] alg: No test for adler32 (adler32-zlib) [ 8082.132863] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 8082.226887] LNet: Added LNI 192.168.206.7@tcp [8/256/0/180] [ 8083.815167] Key type lgssc registered [ 8084.183760] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8087.690797] Lustre: DEBUG MARKER: Iteration 10 [ 8087.809783] LustreError: 395476:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 8087.810947] LustreError: 395477:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 8087.814348] LustreError: 395476:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8088.870752] Lustre: Mounted lustre-client [ 8088.871958] Lustre: Skipped 1 previous similar message [ 8089.379214] LustreError: 395589:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89ec891ec800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8089.382803] LustreError: 395589:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8089.382864] LustreError: 395590:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 8089.388547] LustreError: 395590:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [ 8089.438986] Lustre: Unmounted lustre-client [ 8090.367429] Key type lgssc unregistered [ 8090.478439] LNet: 395832:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8090.480396] LNetError: 395832:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8090.491132] LNet: Removed LNI 192.168.206.7@tcp [ 8090.721153] Key type .llcrypt unregistered [ 8090.722188] Key type ._llcrypt unregistered [ 8091.006973] Key type ._llcrypt registered [ 8091.008160] Key type .llcrypt registered [ 8091.236788] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8091.241444] alg: No test for adler32 (adler32-zlib) [ 8092.091640] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 8092.173541] LNet: Added LNI 192.168.206.7@tcp [8/256/0/180] [ 8093.751161] Key type lgssc registered [ 8094.111841] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8097.516399] Lustre: DEBUG MARKER: Iteration 11 [ 8097.610563] LustreError: 396660:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 8097.610605] LustreError: 396661:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 8097.614971] LustreError: 396660:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8098.679586] Lustre: Mounted lustre-client [ 8098.681151] Lustre: Skipped 1 previous similar message [ 8099.108021] LustreError: 396771:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89ebcb582000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8099.111788] LustreError: 396772:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 8099.112122] LustreError: 396771:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8099.158063] Lustre: Unmounted lustre-client [ 8100.027356] Key type lgssc unregistered [ 8100.139420] LNet: 397013:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8100.141685] LNetError: 397013:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8100.151750] LNet: Removed LNI 192.168.206.7@tcp [ 8100.378094] Key type .llcrypt unregistered [ 8100.379074] Key type ._llcrypt unregistered [ 8100.664521] Key type ._llcrypt registered [ 8100.665642] Key type .llcrypt registered [ 8100.829364] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8100.834184] alg: No test for adler32 (adler32-zlib) [ 8101.682446] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 8101.763797] LNet: Added LNI 192.168.206.7@tcp [8/256/0/180] [ 8103.343100] Key type lgssc registered [ 8103.713156] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8107.112311] Lustre: DEBUG MARKER: Iteration 12 [ 8107.221177] LustreError: 397843:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 8107.221223] LustreError: 397844:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 8107.225639] LustreError: 397843:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8108.285920] Lustre: Mounted lustre-client [ 8108.287724] Lustre: Skipped 1 previous similar message [ 8108.803586] LustreError: 397953:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89ec9c4c7800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8108.809811] LustreError: 397953:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 8108.813523] LustreError: 397953:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 8108.853114] Lustre: Unmounted lustre-client [ 8109.770795] Key type lgssc unregistered [ 8109.883528] LNet: 398199:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8109.885687] LNetError: 398199:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8109.892726] LNet: Removed LNI 192.168.206.7@tcp [ 8110.120107] Key type .llcrypt unregistered [ 8110.120996] Key type ._llcrypt unregistered [ 8110.349694] Key type ._llcrypt registered [ 8110.351154] Key type .llcrypt registered [ 8110.574741] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8110.580097] alg: No test for adler32 (adler32-zlib) [ 8111.435982] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 8111.517904] LNet: Added LNI 192.168.206.7@tcp [8/256/0/180] [ 8113.095100] Key type lgssc registered [ 8113.446521] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8116.719385] Lustre: DEBUG MARKER: Iteration 13 [ 8116.835337] LustreError: 399035:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 8116.836526] LustreError: 399036:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 8116.839764] LustreError: 399035:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8117.897157] Lustre: Mounted lustre-client [ 8117.898135] Lustre: Skipped 1 previous similar message [ 8118.478651] LustreError: 399148:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89ec98d6c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8118.481381] LustreError: 399147:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 8118.482431] LustreError: 399148:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8118.527305] Lustre: Unmounted lustre-client [ 8119.473204] Key type lgssc unregistered [ 8119.583376] LNet: 399390:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8119.585488] LNetError: 399390:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8119.592337] LNet: Removed LNI 192.168.206.7@tcp [ 8119.824120] Key type .llcrypt unregistered [ 8119.825111] Key type ._llcrypt unregistered [ 8120.159264] Key type ._llcrypt registered [ 8120.164282] Key type .llcrypt registered [ 8120.389826] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8120.394411] alg: No test for adler32 (adler32-zlib) [ 8121.263091] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 8121.356914] LNet: Added LNI 192.168.206.7@tcp [8/256/0/180] [ 8122.951162] Key type lgssc registered [ 8123.354410] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8126.666511] Lustre: DEBUG MARKER: Iteration 14 [ 8126.763907] LustreError: 400218:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 8126.763938] LustreError: 400219:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 8126.768625] LustreError: 400218:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8127.826730] Lustre: Mounted lustre-client [ 8127.828103] Lustre: Skipped 1 previous similar message [ 8128.246243] LustreError: 400327:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89ec91203800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8128.249394] LustreError: 400327:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8128.252255] LustreError: 400329:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 8128.254108] LustreError: 400329:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 8128.283215] Lustre: Unmounted lustre-client [ 8129.144649] Key type lgssc unregistered [ 8129.256405] LNet: 400570:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8129.258623] LNetError: 400570:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8129.266736] LNet: Removed LNI 192.168.206.7@tcp [ 8129.498084] Key type .llcrypt unregistered [ 8129.499067] Key type ._llcrypt unregistered [ 8129.771779] Key type ._llcrypt registered [ 8129.773216] Key type .llcrypt registered [ 8129.939753] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8129.944804] alg: No test for adler32 (adler32-zlib) [ 8130.788985] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 8130.865707] LNet: Added LNI 192.168.206.7@tcp [8/256/0/180] [ 8132.447125] Key type lgssc registered [ 8132.818052] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8136.130952] Lustre: DEBUG MARKER: Iteration 15 [ 8136.223452] LustreError: 401400:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 8136.223854] LustreError: 401401:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 8136.227526] LustreError: 401400:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8137.281940] Lustre: Mounted lustre-client [ 8137.723844] LustreError: 401512:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89ec89399800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8137.726890] LustreError: 401513:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 8137.726965] LustreError: 401512:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8137.771690] Lustre: Unmounted lustre-client [ 8138.645206] Key type lgssc unregistered [ 8138.762516] LNet: 401754:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8138.764979] LNetError: 401754:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8138.772565] LNet: Removed LNI 192.168.206.7@tcp [ 8138.996080] Key type .llcrypt unregistered [ 8138.997205] Key type ._llcrypt unregistered [ 8139.266337] Key type ._llcrypt registered [ 8139.267569] Key type .llcrypt registered [ 8139.441797] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8139.447080] alg: No test for adler32 (adler32-zlib) [ 8140.295358] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 8140.373486] LNet: Added LNI 192.168.206.7@tcp [8/256/0/180] [ 8141.951123] Key type lgssc registered [ 8142.299876] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8145.834367] Lustre: DEBUG MARKER: Iteration 16 [ 8145.935659] LustreError: 402582:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 8145.935683] LustreError: 402583:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 8145.940458] LustreError: 402582:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8146.992670] Lustre: Mounted lustre-client [ 8147.428528] LustreError: 402697:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89ec86611800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8147.436274] LustreError: 402697:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 8147.440301] LustreError: 402697:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [ 8147.485084] Lustre: Unmounted lustre-client [ 8148.354626] Key type lgssc unregistered [ 8148.470496] LNet: 402940:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8148.472720] LNetError: 402940:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8148.483674] LNet: Removed LNI 192.168.206.7@tcp [ 8148.700094] Key type .llcrypt unregistered [ 8148.701091] Key type ._llcrypt unregistered [ 8148.953549] Key type ._llcrypt registered [ 8148.954603] Key type .llcrypt registered [ 8149.125414] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8149.129867] alg: No test for adler32 (adler32-zlib) [ 8149.974538] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 8150.051810] LNet: Added LNI 192.168.206.7@tcp [8/256/0/180] [ 8151.631157] Key type lgssc registered [ 8152.023848] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8155.373364] Lustre: DEBUG MARKER: Iteration 17 [ 8155.466922] LustreError: 403769:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 8155.467427] LustreError: 403770:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 8155.473018] LustreError: 403769:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8156.526150] Lustre: Mounted lustre-client [ 8156.527339] Lustre: Skipped 1 previous similar message [ 8156.967093] LustreError: 403882:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89ec8781b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8156.969472] LustreError: 403883:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 8156.970519] LustreError: 403882:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8156.974486] LustreError: 403883:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [ 8157.005670] Lustre: Unmounted lustre-client [ 8157.880358] Key type lgssc unregistered [ 8158.004467] LNet: 404123:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8158.007090] LNetError: 404123:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8158.013631] LNet: Removed LNI 192.168.206.7@tcp [ 8158.238120] Key type .llcrypt unregistered [ 8158.239952] Key type ._llcrypt unregistered [ 8158.525087] Key type ._llcrypt registered [ 8158.530731] Key type .llcrypt registered [ 8158.695791] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8158.700825] alg: No test for adler32 (adler32-zlib) [ 8159.560022] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 8159.641797] LNet: Added LNI 192.168.206.7@tcp [8/256/0/180] [ 8161.231119] Key type lgssc registered [ 8161.578743] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8165.036138] Lustre: DEBUG MARKER: Iteration 18 [ 8165.149286] LustreError: 404951:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 8165.149328] LustreError: 404952:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 8165.155064] LustreError: 404951:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 8166.209062] Lustre: Mounted lustre-client [ 8166.210606] Lustre: Skipped 1 previous similar message [ 8166.634195] LustreError: 405062:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89ebcb584000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8166.637082] LustreError: 405064:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 8166.637671] LustreError: 405062:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8166.674951] Lustre: Unmounted lustre-client [ 8167.593482] Key type lgssc unregistered [ 8167.722404] LNet: 405305:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8167.724450] LNetError: 405305:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8167.732760] LNet: Removed LNI 192.168.206.7@tcp [ 8167.968109] Key type .llcrypt unregistered [ 8167.969044] Key type ._llcrypt unregistered [ 8168.304770] Key type ._llcrypt registered [ 8168.305648] Key type .llcrypt registered [ 8168.424844] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8168.429196] alg: No test for adler32 (adler32-zlib) [ 8169.286875] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 8169.374571] LNet: Added LNI 192.168.206.7@tcp [8/256/0/180] [ 8170.959084] Key type lgssc registered [ 8171.339846] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8174.699906] Lustre: DEBUG MARKER: Iteration 19 [ 8174.804579] LustreError: 406135:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 8174.804628] LustreError: 406136:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 8174.809033] LustreError: 406135:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8175.870967] Lustre: Mounted lustre-client [ 8175.873232] Lustre: Skipped 1 previous similar message [ 8176.402154] LustreError: 406241:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89ec87ca4000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8176.406980] LustreError: 406241:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 8176.428371] Lustre: Unmounted lustre-client [ 8177.453214] Key type lgssc unregistered [ 8177.587816] LNet: 406489:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8177.590172] LNetError: 406489:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8177.597714] LNet: Removed LNI 192.168.206.7@tcp [ 8177.850122] Key type .llcrypt unregistered [ 8177.851712] Key type ._llcrypt unregistered [ 8178.178342] Key type ._llcrypt registered [ 8178.179379] Key type .llcrypt registered [ 8178.352638] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8178.358886] alg: No test for adler32 (adler32-zlib) [ 8179.211409] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 8179.292782] LNet: Added LNI 192.168.206.7@tcp [8/256/0/180] [ 8180.871153] Key type lgssc registered [ 8181.246207] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8184.697668] Lustre: DEBUG MARKER: Iteration 20 [ 8184.789757] LustreError: 407317:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 8184.790953] LustreError: 407318:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 8184.793749] LustreError: 407317:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8185.850249] Lustre: Mounted lustre-client [ 8186.288732] LustreError: 407432:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89ec86611800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8186.290770] LustreError: 407433:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8186.291892] LustreError: 407432:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8186.338973] Lustre: Unmounted lustre-client [ 8187.237707] Key type lgssc unregistered [ 8187.357718] LNet: 407675:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8187.360618] LNetError: 407675:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8187.366757] LNet: Removed LNI 192.168.206.7@tcp [ 8187.602087] Key type .llcrypt unregistered [ 8187.603127] Key type ._llcrypt unregistered [ 8187.874073] Key type ._llcrypt registered [ 8187.875027] Key type .llcrypt registered [ 8188.054361] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8188.059823] alg: No test for adler32 (adler32-zlib) [ 8188.911435] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 8188.989357] LNet: Added LNI 192.168.206.7@tcp [8/256/0/180] [ 8190.567134] Key type lgssc registered [ 8191.001534] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8194.556168] Lustre: DEBUG MARKER: Iteration 21 [ 8194.691441] LustreError: 408505:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 8194.691481] LustreError: 408504:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 8194.697443] LustreError: 408505:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8195.762716] Lustre: Mounted lustre-client [ 8196.273092] LustreError: 408610:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89ec91206000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8196.276933] LustreError: 408611:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8196.277042] LustreError: 408610:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8196.316847] Lustre: Unmounted lustre-client [ 8197.308615] Key type lgssc unregistered [ 8197.416545] LNet: 408852:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8197.418789] LNetError: 408852:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8197.426427] LNet: Removed LNI 192.168.206.7@tcp [ 8197.663099] Key type .llcrypt unregistered [ 8197.664268] Key type ._llcrypt unregistered [ 8197.938208] Key type ._llcrypt registered [ 8197.940436] Key type .llcrypt registered [ 8198.127103] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8198.132167] alg: No test for adler32 (adler32-zlib) [ 8198.985892] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 8199.078809] LNet: Added LNI 192.168.206.7@tcp [8/256/0/180] [ 8200.663187] Key type lgssc registered [ 8201.097566] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8204.728796] Lustre: DEBUG MARKER: Iteration 22 [ 8204.835171] LustreError: 409681:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 8204.835220] LustreError: 409682:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 8204.840626] LustreError: 409681:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8205.905385] Lustre: Mounted lustre-client [ 8206.374101] LustreError: 409790:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89ec86335800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8206.376409] LustreError: 409791:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 8206.378444] LustreError: 409790:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8206.425779] Lustre: Unmounted lustre-client [ 8207.359561] Key type lgssc unregistered [ 8207.471403] LNet: 410033:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8207.473571] LNetError: 410033:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8207.483395] LNet: Removed LNI 192.168.206.7@tcp [ 8207.711157] Key type .llcrypt unregistered [ 8207.712169] Key type ._llcrypt unregistered [ 8207.978674] Key type ._llcrypt registered [ 8207.980759] Key type .llcrypt registered [ 8208.171126] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8208.176905] alg: No test for adler32 (adler32-zlib) [ 8209.042019] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 8209.122985] LNet: Added LNI 192.168.206.7@tcp [8/256/0/180] [ 8210.703133] Key type lgssc registered [ 8211.055685] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8214.447479] Lustre: DEBUG MARKER: Iteration 23 [ 8214.545835] LustreError: 410862:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 8214.545864] LustreError: 410863:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 8214.550663] LustreError: 410862:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8215.603182] Lustre: Mounted lustre-client [ 8215.604684] Lustre: Skipped 1 previous similar message [ 8216.043731] LustreError: 410975:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89ec8857d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8216.048649] LustreError: 410975:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8216.049157] LustreError: 410976:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 8216.125169] Lustre: Unmounted lustre-client [ 8217.042161] Key type lgssc unregistered [ 8217.149429] LNet: 411216:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8217.153093] LNetError: 411216:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8217.160694] LNet: Removed LNI 192.168.206.7@tcp [ 8217.379090] Key type .llcrypt unregistered [ 8217.380041] Key type ._llcrypt unregistered [ 8217.613933] Key type ._llcrypt registered [ 8217.619205] Key type .llcrypt registered [ 8217.835120] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8217.839574] alg: No test for adler32 (adler32-zlib) [ 8218.703799] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 8218.785567] LNet: Added LNI 192.168.206.7@tcp [8/256/0/180] [ 8220.367133] Key type lgssc registered [ 8220.728785] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8224.045028] Lustre: DEBUG MARKER: Iteration 24 [ 8224.142457] LustreError: 412044:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 8224.142488] LustreError: 412045:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 8224.146140] LustreError: 412044:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8225.205518] Lustre: Mounted lustre-client [ 8225.206445] Lustre: Skipped 1 previous similar message [ 8225.636299] LustreError: 412159:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89ec88611000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8225.641169] LustreError: 412159:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 8225.643178] LustreError: 412159:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 8225.702681] Lustre: Unmounted lustre-client [ 8226.601794] Key type lgssc unregistered [ 8226.717470] LNet: 412400:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8226.719683] LNetError: 412400:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8226.726671] LNet: Removed LNI 192.168.206.7@tcp [ 8226.959079] Key type .llcrypt unregistered [ 8226.960236] Key type ._llcrypt unregistered [ 8227.237062] Key type ._llcrypt registered [ 8227.238461] Key type .llcrypt registered [ 8227.425088] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8227.431929] alg: No test for adler32 (adler32-zlib) [ 8228.291097] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 8228.374163] LNet: Added LNI 192.168.206.7@tcp [8/256/0/180] [ 8229.959179] Key type lgssc registered [ 8230.310862] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8233.586969] Lustre: DEBUG MARKER: Iteration 25 [ 8233.688147] LustreError: 413236:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 8233.691455] LustreError: 413237:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 8233.694943] LustreError: 413236:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 8234.750617] Lustre: Mounted lustre-client [ 8235.169566] LustreError: 413350:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89ec83eea800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8235.172561] LustreError: 413351:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 8235.173894] LustreError: 413350:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8235.216541] Lustre: Unmounted lustre-client [ 8236.094267] Key type lgssc unregistered [ 8236.204522] LNet: 413592:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8236.206386] LNetError: 413592:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8236.215763] LNet: Removed LNI 192.168.206.7@tcp [ 8236.418106] Key type .llcrypt unregistered [ 8236.419078] Key type ._llcrypt unregistered [ 8236.644933] Key type ._llcrypt registered [ 8236.648788] Key type .llcrypt registered [ 8236.840948] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8236.846428] alg: No test for adler32 (adler32-zlib) [ 8237.697824] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 8237.775176] LNet: Added LNI 192.168.206.7@tcp [8/256/0/180] [ 8239.359152] Key type lgssc registered [ 8239.738559] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8242.953847] Lustre: DEBUG MARKER: Iteration 26 [ 8243.059548] LustreError: 414421:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 8243.059581] LustreError: 414422:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 8243.064058] LustreError: 414421:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 8244.117317] Lustre: Mounted lustre-client [ 8244.553677] LustreError: 414536:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89ec82e8b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8244.555504] LustreError: 414537:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 8244.557384] LustreError: 414536:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8244.601296] Lustre: Unmounted lustre-client [ 8245.542705] Key type lgssc unregistered [ 8245.670363] LNet: 414779:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8245.672427] LNetError: 414779:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8245.680386] LNet: Removed LNI 192.168.206.7@tcp [ 8245.905094] Key type .llcrypt unregistered [ 8245.906077] Key type ._llcrypt unregistered [ 8246.151354] Key type ._llcrypt registered [ 8246.153250] Key type .llcrypt registered [ 8246.359749] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8246.364460] alg: No test for adler32 (adler32-zlib) [ 8247.223996] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 8247.312748] LNet: Added LNI 192.168.206.7@tcp [8/256/0/180] [ 8248.895116] Key type lgssc registered [ 8249.241081] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8252.820680] Lustre: DEBUG MARKER: Iteration 27 [ 8252.919153] LustreError: 415608:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 8252.919207] LustreError: 415609:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 8252.923042] LustreError: 415608:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8253.975914] Lustre: Mounted lustre-client [ 8254.421106] LustreError: 415720:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89ec98eca000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8254.424503] LustreError: 415720:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8254.424658] LustreError: 415719:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8254.479667] Lustre: Unmounted lustre-client [ 8255.368426] Key type lgssc unregistered [ 8255.485286] LNet: 415960:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8255.488267] LNetError: 415960:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8255.496743] LNet: Removed LNI 192.168.206.7@tcp [ 8255.719108] Key type .llcrypt unregistered [ 8255.720590] Key type ._llcrypt unregistered [ 8255.945626] Key type ._llcrypt registered [ 8255.946774] Key type .llcrypt registered [ 8256.154948] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8256.160537] alg: No test for adler32 (adler32-zlib) [ 8257.013604] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 8257.094851] LNet: Added LNI 192.168.206.7@tcp [8/256/0/180] [ 8258.679105] Key type lgssc registered [ 8259.032199] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8262.691594] Lustre: DEBUG MARKER: Iteration 28 [ 8262.810802] LustreError: 416788:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 8262.810841] LustreError: 416789:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 8262.814769] LustreError: 416788:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8263.872943] Lustre: Mounted lustre-client [ 8263.874423] Lustre: Skipped 1 previous similar message [ 8264.437578] LustreError: 416900:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89ebcb580000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8264.440417] LustreError: 416901:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8264.441456] LustreError: 416900:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8264.486609] Lustre: Unmounted lustre-client [ 8265.406544] Key type lgssc unregistered [ 8265.525545] LNet: 417144:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8265.527914] LNetError: 417144:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8265.538731] LNet: Removed LNI 192.168.206.7@tcp [ 8265.765099] Key type .llcrypt unregistered [ 8265.766115] Key type ._llcrypt unregistered [ 8266.033878] Key type ._llcrypt registered [ 8266.043765] Key type .llcrypt registered [ 8266.225902] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8266.231052] alg: No test for adler32 (adler32-zlib) [ 8267.079589] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 8267.160565] LNet: Added LNI 192.168.206.7@tcp [8/256/0/180] [ 8268.735178] Key type lgssc registered [ 8269.101626] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8272.617647] Lustre: DEBUG MARKER: Iteration 29 [ 8272.706816] LustreError: 417972:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 8272.706824] LustreError: 417973:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 8272.711761] LustreError: 417972:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 8273.763711] Lustre: Mounted lustre-client [ 8274.196879] LustreError: 418085:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89eca4c91000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8274.199902] LustreError: 418086:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 8274.200565] LustreError: 418085:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8274.250686] Lustre: Unmounted lustre-client [ 8275.067590] Key type lgssc unregistered [ 8275.173524] LNet: 418329:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8275.176635] LNetError: 418329:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8275.185571] LNet: Removed LNI 192.168.206.7@tcp [ 8275.407093] Key type .llcrypt unregistered [ 8275.408658] Key type ._llcrypt unregistered [ 8275.623944] Key type ._llcrypt registered [ 8275.625176] Key type .llcrypt registered [ 8275.827832] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8275.834927] alg: No test for adler32 (adler32-zlib) [ 8276.677576] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 8276.751049] LNet: Added LNI 192.168.206.7@tcp [8/256/0/180] [ 8278.327131] Key type lgssc registered [ 8278.658351] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8282.080883] Lustre: DEBUG MARKER: Iteration 30 [ 8282.178594] LustreError: 419157:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 8282.178633] LustreError: 419158:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 8282.183090] LustreError: 419157:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8283.236962] Lustre: Mounted lustre-client [ 8283.662388] LustreError: 419271:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89ec9023a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8283.665663] LustreError: 419272:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8283.666250] LustreError: 419271:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8283.705826] Lustre: Unmounted lustre-client [ 8284.563776] Key type lgssc unregistered [ 8284.672573] LNet: 419515:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8284.674573] LNetError: 419515:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8284.681739] LNet: Removed LNI 192.168.206.7@tcp [ 8284.907115] Key type .llcrypt unregistered [ 8284.908158] Key type ._llcrypt unregistered [ 8285.172438] Key type ._llcrypt registered [ 8285.173583] Key type .llcrypt registered [ 8285.355124] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8285.360244] alg: No test for adler32 (adler32-zlib) [ 8286.213811] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 8286.293326] LNet: Added LNI 192.168.206.7@tcp [8/256/0/180] [ 8287.879107] Key type lgssc registered [ 8288.226627] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8291.802667] Lustre: DEBUG MARKER: Iteration 31 [ 8291.915747] LustreError: 420344:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 8291.919109] LustreError: 420345:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 8291.922701] LustreError: 420344:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 8292.987728] Lustre: Mounted lustre-client [ 8292.988744] Lustre: Skipped 1 previous similar message [ 8293.414448] LustreError: 420458:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89ec84dfa800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8293.416514] LustreError: 420457:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 8293.417567] LustreError: 420458:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8293.456103] Lustre: Unmounted lustre-client [ 8294.309152] Key type lgssc unregistered [ 8294.414457] LNet: 420700:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8294.416663] LNetError: 420700:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8294.422674] LNet: Removed LNI 192.168.206.7@tcp [ 8294.637096] Key type .llcrypt unregistered [ 8294.638126] Key type ._llcrypt unregistered [ 8294.850296] Key type ._llcrypt registered [ 8294.852260] Key type .llcrypt registered [ 8295.077844] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8295.082678] alg: No test for adler32 (adler32-zlib) [ 8295.956790] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 8296.046409] LNet: Added LNI 192.168.206.7@tcp [8/256/0/180] [ 8297.631117] Key type lgssc registered [ 8298.023563] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8301.586235] Lustre: DEBUG MARKER: Iteration 32 [ 8301.689429] LustreError: 421529:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 8301.689468] LustreError: 421530:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 8301.692302] LustreError: 421529:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8302.751805] Lustre: Mounted lustre-client [ 8302.752736] Lustre: Skipped 1 previous similar message [ 8303.180040] LustreError: 421643:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89ec83eee800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8303.182851] LustreError: 421643:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8303.186684] LustreError: 421643:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 8303.188411] LustreError: 421643:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 8303.224599] Lustre: Unmounted lustre-client [ 8304.058602] Key type lgssc unregistered [ 8304.157428] LNet: 421884:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8304.159801] LNetError: 421884:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8304.169685] LNet: Removed LNI 192.168.206.7@tcp [ 8304.396087] Key type .llcrypt unregistered [ 8304.397262] Key type ._llcrypt unregistered [ 8304.643721] Key type ._llcrypt registered [ 8304.644696] Key type .llcrypt registered [ 8304.832065] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8304.836618] alg: No test for adler32 (adler32-zlib) [ 8305.685220] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 8305.765695] LNet: Added LNI 192.168.206.7@tcp [8/256/0/180] [ 8307.351131] Key type lgssc registered [ 8307.743160] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8311.443432] Lustre: DEBUG MARKER: Iteration 33 [ 8311.552519] LustreError: 422712:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 8311.552567] LustreError: 422713:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 8311.557840] LustreError: 422712:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 8312.621247] Lustre: Mounted lustre-client [ 8313.058718] LustreError: 422829:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89ec8591c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8313.063647] LustreError: 422829:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 8313.111094] Lustre: Unmounted lustre-client [ 8314.003521] Key type lgssc unregistered [ 8314.111502] LNet: 423072:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8314.113763] LNetError: 423072:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8314.125800] LNet: Removed LNI 192.168.206.7@tcp [ 8314.349117] Key type .llcrypt unregistered [ 8314.350757] Key type ._llcrypt unregistered [ 8314.567241] Key type ._llcrypt registered [ 8314.568181] Key type .llcrypt registered [ 8314.807886] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8314.813780] alg: No test for adler32 (adler32-zlib) [ 8315.666204] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 8315.740048] LNet: Added LNI 192.168.206.7@tcp [8/256/0/180] [ 8317.319109] Key type lgssc registered [ 8317.675293] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8320.812567] Lustre: DEBUG MARKER: Iteration 34 [ 8320.908617] LustreError: 423901:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 8320.908660] LustreError: 423902:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 8320.913088] LustreError: 423901:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8321.964618] Lustre: Mounted lustre-client [ 8321.966039] Lustre: Skipped 1 previous similar message [ 8322.415526] LustreError: 424014:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89ec91203000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8322.418991] LustreError: 424014:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8322.421607] LustreError: 424013:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8322.423600] LustreError: 424013:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 8322.467336] Lustre: Unmounted lustre-client [ 8323.352687] Key type lgssc unregistered [ 8323.462508] LNet: 424256:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8323.464935] LNetError: 424256:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8323.471704] LNet: Removed LNI 192.168.206.7@tcp [ 8323.700088] Key type .llcrypt unregistered [ 8323.701120] Key type ._llcrypt unregistered [ 8323.930732] Key type ._llcrypt registered [ 8323.932128] Key type .llcrypt registered [ 8324.130317] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8324.135278] alg: No test for adler32 (adler32-zlib) [ 8324.980628] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 8325.059048] LNet: Added LNI 192.168.206.7@tcp [8/256/0/180] [ 8326.639083] Key type lgssc registered [ 8326.987492] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8330.314370] Lustre: DEBUG MARKER: Iteration 35 [ 8330.411473] LustreError: 425084:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 8330.412061] LustreError: 425085:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 8330.415506] LustreError: 425084:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8331.463834] Lustre: Mounted lustre-client [ 8331.899741] LustreError: 425196:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89ec857cd000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8331.901511] LustreError: 425197:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8331.903968] LustreError: 425196:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8331.933258] Lustre: Unmounted lustre-client [ 8332.788516] Key type lgssc unregistered [ 8332.906361] LNet: 425439:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8332.908403] LNetError: 425439:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8332.917222] LNet: Removed LNI 192.168.206.7@tcp [ 8333.173105] Key type .llcrypt unregistered [ 8333.174076] Key type ._llcrypt unregistered [ 8333.520225] Key type ._llcrypt registered [ 8333.521159] Key type .llcrypt registered [ 8333.684429] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8333.689443] alg: No test for adler32 (adler32-zlib) [ 8334.534286] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 8334.611110] LNet: Added LNI 192.168.206.7@tcp [8/256/0/180] [ 8336.191121] Key type lgssc registered [ 8336.547076] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8340.245795] Lustre: DEBUG MARKER: Iteration 36 [ 8340.345039] LustreError: 426268:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 8340.345070] LustreError: 426269:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 8340.348848] LustreError: 426268:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 8341.401669] Lustre: Mounted lustre-client [ 8341.842802] LustreError: 426381:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89ecb6c5a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8341.845368] LustreError: 426382:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 8341.848033] LustreError: 426381:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8341.892052] Lustre: Unmounted lustre-client [ 8342.858691] Key type lgssc unregistered [ 8343.023511] LNet: 426625:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8343.025832] LNetError: 426625:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8343.034842] LNet: Removed LNI 192.168.206.7@tcp [ 8343.287085] Key type .llcrypt unregistered [ 8343.288418] Key type ._llcrypt unregistered [ 8343.502745] Key type ._llcrypt registered [ 8343.503996] Key type .llcrypt registered [ 8343.701251] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8343.710399] alg: No test for adler32 (adler32-zlib) [ 8344.566559] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 8344.646659] LNet: Added LNI 192.168.206.7@tcp [8/256/0/180] [ 8346.231148] Key type lgssc registered [ 8346.601086] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8350.249476] Lustre: DEBUG MARKER: Iteration 37 [ 8350.355790] LustreError: 427452:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 8350.355838] LustreError: 427453:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 8350.359777] LustreError: 427452:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8351.413891] Lustre: Mounted lustre-client [ 8351.849358] LustreError: 427575:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89ecb6c5d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8351.852850] LustreError: 427574:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 8351.855261] LustreError: 427575:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8351.910082] Lustre: Unmounted lustre-client [ 8352.905195] Key type lgssc unregistered [ 8353.057653] LNet: 427816:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8353.062805] LNetError: 427816:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8353.075658] LNet: Removed LNI 192.168.206.7@tcp [ 8353.333110] Key type .llcrypt unregistered [ 8353.334705] Key type ._llcrypt unregistered [ 8353.598786] Key type ._llcrypt registered [ 8353.600370] Key type .llcrypt registered [ 8353.786364] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8353.791557] alg: No test for adler32 (adler32-zlib) [ 8354.642124] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 8354.719646] LNet: Added LNI 192.168.206.7@tcp [8/256/0/180] [ 8356.295139] Key type lgssc registered [ 8356.654170] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8359.906307] Lustre: DEBUG MARKER: Iteration 38 [ 8359.997593] LustreError: 428645:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 8360.000820] LustreError: 428646:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 8360.002760] LustreError: 428645:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 8361.059208] Lustre: Mounted lustre-client [ 8361.060219] Lustre: Skipped 1 previous similar message [ 8361.508293] LustreError: 428757:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89ec9023a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8361.513569] LustreError: 428757:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 8361.516153] LustreError: 428757:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 8361.559062] Lustre: Unmounted lustre-client [ 8362.475051] Key type lgssc unregistered [ 8362.595436] LNet: 428999:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8362.597610] LNetError: 428999:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8362.604699] LNet: Removed LNI 192.168.206.7@tcp [ 8362.817089] Key type .llcrypt unregistered [ 8362.818088] Key type ._llcrypt unregistered [ 8363.059798] Key type ._llcrypt registered [ 8363.060869] Key type .llcrypt registered [ 8363.212164] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8363.220427] alg: No test for adler32 (adler32-zlib) [ 8364.068307] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 8364.145191] LNet: Added LNI 192.168.206.7@tcp [8/256/0/180] [ 8365.719111] Key type lgssc registered [ 8366.059495] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8369.251417] Lustre: DEBUG MARKER: Iteration 39 [ 8369.379364] LustreError: 429838:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 8369.383125] LustreError: 429839:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 8369.386812] LustreError: 429838:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [ 8370.439510] Lustre: Mounted lustre-client [ 8370.440617] Lustre: Skipped 1 previous similar message [ 8370.964847] LustreError: 429950:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89ec8857c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8370.966343] LustreError: 429951:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 8370.968686] LustreError: 429950:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8371.018457] Lustre: Unmounted lustre-client [ 8371.969262] Key type lgssc unregistered [ 8372.083436] LNet: 430194:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8372.085465] LNetError: 430194:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8372.092768] LNet: Removed LNI 192.168.206.7@tcp [ 8372.316130] Key type .llcrypt unregistered [ 8372.317135] Key type ._llcrypt unregistered [ 8372.602753] Key type ._llcrypt registered [ 8372.603789] Key type .llcrypt registered [ 8372.791620] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8372.796980] alg: No test for adler32 (adler32-zlib) [ 8373.664657] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 8373.744574] LNet: Added LNI 192.168.206.7@tcp [8/256/0/180] [ 8375.327117] Key type lgssc registered [ 8375.664988] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8378.712918] Lustre: DEBUG MARKER: Iteration 40 [ 8378.832865] LustreError: 431024:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 8378.832896] LustreError: 431025:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 8378.836427] LustreError: 431024:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8379.884142] Lustre: Mounted lustre-client [ 8380.310439] LustreError: 431137:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89ec83eeb000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8380.313375] LustreError: 431137:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8380.314990] LustreError: 431136:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 8380.369151] Lustre: Unmounted lustre-client [ 8380.370250] Lustre: Skipped 1 previous similar message [ 8381.247858] Key type lgssc unregistered [ 8381.367269] LNet: 431378:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8381.369458] LNetError: 431378:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8381.376781] LNet: Removed LNI 192.168.206.7@tcp [ 8381.608102] Key type .llcrypt unregistered [ 8381.609059] Key type ._llcrypt unregistered [ 8381.875106] Key type ._llcrypt registered [ 8381.877059] Key type .llcrypt registered [ 8382.078067] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8382.083045] alg: No test for adler32 (adler32-zlib) [ 8382.936181] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 8383.027407] LNet: Added LNI 192.168.206.7@tcp [8/256/0/180] [ 8384.615107] Key type lgssc registered [ 8384.985622] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8388.225065] Lustre: DEBUG MARKER: Iteration 41 [ 8388.324458] LustreError: 432206:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 8388.324529] LustreError: 432207:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 8388.329251] LustreError: 432206:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 8389.377443] Lustre: Mounted lustre-client [ 8389.378988] Lustre: Skipped 1 previous similar message [ 8389.802820] LustreError: 432320:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89ec98fb8800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8389.808282] LustreError: 432320:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 8389.879922] Lustre: Unmounted lustre-client [ 8389.880843] Lustre: Skipped 1 previous similar message [ 8390.749161] Key type lgssc unregistered [ 8390.857480] LNet: 432564:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8390.860146] LNetError: 432564:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8390.866661] LNet: Removed LNI 192.168.206.7@tcp [ 8391.092096] Key type .llcrypt unregistered [ 8391.092985] Key type ._llcrypt unregistered [ 8391.404191] Key type ._llcrypt registered [ 8391.405298] Key type .llcrypt registered [ 8391.540071] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8391.545603] alg: No test for adler32 (adler32-zlib) [ 8392.397719] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 8392.482286] LNet: Added LNI 192.168.206.7@tcp [8/256/0/180] [ 8394.063125] Key type lgssc registered [ 8394.416649] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8397.642492] Lustre: DEBUG MARKER: Iteration 42 [ 8397.739332] LustreError: 433394:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 8397.739393] LustreError: 433395:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 8397.745187] LustreError: 433394:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 8398.798118] Lustre: Mounted lustre-client [ 8399.246288] LustreError: 433512:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89ec87ca4000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8399.249709] LustreError: 433512:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8399.252733] LustreError: 433511:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 8399.255160] LustreError: 433511:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 8399.295074] Lustre: Unmounted lustre-client [ 8400.140161] Key type lgssc unregistered [ 8400.249459] LNet: 433753:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8400.254039] LNetError: 433753:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8400.263797] LNet: Removed LNI 192.168.206.7@tcp [ 8400.495093] Key type .llcrypt unregistered [ 8400.496132] Key type ._llcrypt unregistered [ 8400.745738] Key type ._llcrypt registered [ 8400.748460] Key type .llcrypt registered [ 8400.929589] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8400.933909] alg: No test for adler32 (adler32-zlib) [ 8401.788572] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 8401.870765] LNet: Added LNI 192.168.206.7@tcp [8/256/0/180] [ 8403.455079] Key type lgssc registered [ 8403.794044] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8407.115276] Lustre: DEBUG MARKER: Iteration 43 [ 8407.216189] LustreError: 434583:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 8407.216545] LustreError: 434584:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 8407.221103] LustreError: 434583:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 8408.290689] Lustre: Mounted lustre-client [ 8408.291680] Lustre: Skipped 1 previous similar message [ 8408.756925] LustreError: 434690:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89ebcb583800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8408.760543] LustreError: 434690:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8408.760694] LustreError: 434691:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 8408.821109] Lustre: Unmounted lustre-client [ 8409.779407] Key type lgssc unregistered [ 8409.907793] LNet: 434932:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8409.911489] LNetError: 434932:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8409.922012] LNet: Removed LNI 192.168.206.7@tcp [ 8410.158138] Key type .llcrypt unregistered [ 8410.160266] Key type ._llcrypt unregistered [ 8410.450246] Key type ._llcrypt registered [ 8410.451646] Key type .llcrypt registered [ 8410.594809] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8410.599242] alg: No test for adler32 (adler32-zlib) [ 8411.456844] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 8411.539813] LNet: Added LNI 192.168.206.7@tcp [8/256/0/180] [ 8413.127153] Key type lgssc registered [ 8413.470850] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8416.746292] Lustre: DEBUG MARKER: Iteration 44 [ 8416.840658] LustreError: 435761:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 8416.840693] LustreError: 435762:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 8416.844610] LustreError: 435761:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8417.890257] Lustre: Mounted lustre-client [ 8418.342110] LustreError: 435876:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89ec86614000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8418.346392] LustreError: 435877:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 8418.347058] LustreError: 435876:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8418.386080] Lustre: Unmounted lustre-client [ 8419.278948] Key type lgssc unregistered [ 8419.384490] LNet: 436119:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8419.386617] LNetError: 436119:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8419.396702] LNet: Removed LNI 192.168.206.7@tcp [ 8419.613105] Key type .llcrypt unregistered [ 8419.614109] Key type ._llcrypt unregistered [ 8419.939277] Key type ._llcrypt registered [ 8419.940277] Key type .llcrypt registered [ 8420.067258] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8420.071975] alg: No test for adler32 (adler32-zlib) [ 8420.916963] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 8420.995283] LNet: Added LNI 192.168.206.7@tcp [8/256/0/180] [ 8422.583097] Key type lgssc registered [ 8422.937628] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8426.367502] Lustre: DEBUG MARKER: Iteration 45 [ 8426.466972] LustreError: 436948:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 8426.467055] LustreError: 436949:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 8426.473548] LustreError: 436948:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8427.535817] Lustre: Mounted lustre-client [ 8427.993597] LustreError: 437060:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89ecb8fe3800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8427.996798] LustreError: 437059:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 8427.997215] LustreError: 437060:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8428.047143] Lustre: Unmounted lustre-client [ 8428.910202] Key type lgssc unregistered [ 8429.023409] LNet: 437301:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8429.025723] LNetError: 437301:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8429.031729] LNet: Removed LNI 192.168.206.7@tcp [ 8429.247093] Key type .llcrypt unregistered [ 8429.248040] Key type ._llcrypt unregistered [ 8429.485429] Key type ._llcrypt registered [ 8429.488204] Key type .llcrypt registered [ 8429.663437] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8429.668803] alg: No test for adler32 (adler32-zlib) [ 8430.521898] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 8430.600498] LNet: Added LNI 192.168.206.7@tcp [8/256/0/180] [ 8432.183105] Key type lgssc registered [ 8432.555974] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8435.890955] Lustre: DEBUG MARKER: Iteration 46 [ 8435.996632] LustreError: 438130:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 8435.996831] LustreError: 438131:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 8436.000209] LustreError: 438130:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8437.044807] Lustre: Mounted lustre-client [ 8437.498664] LustreError: 438244:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89ec91204000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8437.500751] LustreError: 438245:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 8437.501623] LustreError: 438244:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8437.535616] Lustre: Unmounted lustre-client [ 8438.378708] Key type lgssc unregistered [ 8438.491370] LNet: 438486:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8438.493423] LNetError: 438486:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8438.500633] LNet: Removed LNI 192.168.206.7@tcp [ 8438.736121] Key type .llcrypt unregistered [ 8438.737500] Key type ._llcrypt unregistered [ 8438.971370] Key type ._llcrypt registered [ 8438.974621] Key type .llcrypt registered [ 8439.163784] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8439.170284] alg: No test for adler32 (adler32-zlib) [ 8440.021602] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 8440.103657] LNet: Added LNI 192.168.206.7@tcp [8/256/0/180] [ 8441.687142] Key type lgssc registered [ 8442.044526] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8445.413286] Lustre: DEBUG MARKER: Iteration 47 [ 8445.516737] LustreError: 439314:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 8445.516990] LustreError: 439315:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 8445.520752] LustreError: 439314:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8446.572686] Lustre: Mounted lustre-client [ 8447.012662] LustreError: 439429:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89ec8855a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8447.014696] LustreError: 439428:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 8447.015940] LustreError: 439429:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8447.063295] Lustre: Unmounted lustre-client [ 8447.941827] Key type lgssc unregistered [ 8448.052449] LNet: 439671:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8448.056487] LNetError: 439671:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8448.068331] LNet: Removed LNI 192.168.206.7@tcp [ 8448.317124] Key type .llcrypt unregistered [ 8448.318776] Key type ._llcrypt unregistered [ 8448.683347] Key type ._llcrypt registered [ 8448.689064] Key type .llcrypt registered [ 8448.861150] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8448.866454] alg: No test for adler32 (adler32-zlib) [ 8449.718787] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 8449.802239] LNet: Added LNI 192.168.206.7@tcp [8/256/0/180] [ 8451.383146] Key type lgssc registered [ 8451.791284] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8455.194674] Lustre: DEBUG MARKER: Iteration 48 [ 8455.288818] LustreError: 440500:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 8455.288852] LustreError: 440501:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 8455.292959] LustreError: 440500:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 8456.343842] Lustre: Mounted lustre-client [ 8456.959705] LustreError: 440613:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89ecb8a24000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8456.963537] LustreError: 440613:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8456.964210] LustreError: 440614:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 8457.035175] Lustre: Unmounted lustre-client [ 8458.031771] Key type lgssc unregistered [ 8458.149856] LNet: 440856:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8458.152144] LNetError: 440856:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8458.159348] LNet: Removed LNI 192.168.206.7@tcp [ 8458.412091] Key type .llcrypt unregistered [ 8458.413970] Key type ._llcrypt unregistered [ 8458.702381] Key type ._llcrypt registered [ 8458.703492] Key type .llcrypt registered [ 8458.938653] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8458.944277] alg: No test for adler32 (adler32-zlib) [ 8459.819129] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 8459.911291] LNet: Added LNI 192.168.206.7@tcp [8/256/0/180] [ 8461.503167] Key type lgssc registered [ 8461.892188] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8465.308500] Lustre: DEBUG MARKER: Iteration 49 [ 8465.416657] LustreError: 441684:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 8465.416708] LustreError: 441685:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 8465.420973] LustreError: 441684:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8466.480205] Lustre: Mounted lustre-client [ 8466.931714] LustreError: 441795:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89ec84dfa800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8466.939380] LustreError: 441795:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8466.939486] LustreError: 441796:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 8466.993334] Lustre: Unmounted lustre-client [ 8467.938814] Key type lgssc unregistered [ 8468.057808] LNet: 442037:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8468.060204] LNetError: 442037:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8468.067251] LNet: Removed LNI 192.168.206.7@tcp [ 8468.298111] Key type .llcrypt unregistered [ 8468.299197] Key type ._llcrypt unregistered [ 8468.572411] Key type ._llcrypt registered [ 8468.573585] Key type .llcrypt registered [ 8468.807797] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8468.836917] alg: No test for adler32 (adler32-zlib) [ 8469.699019] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 8469.790151] LNet: Added LNI 192.168.206.7@tcp [8/256/0/180] [ 8471.375157] Key type lgssc registered [ 8471.782779] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8475.335634] Lustre: DEBUG MARKER: Iteration 50 [ 8475.443847] LustreError: 442866:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 8475.443937] LustreError: 442867:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 8475.449317] LustreError: 442866:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 8476.515116] Lustre: Mounted lustre-client [ 8476.948451] LustreError: 442973:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89ec86617800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8476.951525] LustreError: 442973:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8476.954215] LustreError: 442974:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8476.955940] LustreError: 442974:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 8477.018331] Lustre: Unmounted lustre-client [ 8478.017809] Key type lgssc unregistered [ 8478.137734] LNet: 443215:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8478.139983] LNetError: 443215:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8478.148115] LNet: Removed LNI 192.168.206.7@tcp [ 8478.412168] Key type .llcrypt unregistered [ 8478.413375] Key type ._llcrypt unregistered [ 8478.698461] Key type ._llcrypt registered [ 8478.700528] Key type .llcrypt registered [ 8479.015289] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8479.021186] alg: No test for adler32 (adler32-zlib) [ 8479.892983] Lustre: Lustre: Build Version: 2.17.50_86_g2f7fa4d [ 8479.997369] LNet: Added LNI 192.168.206.7@tcp [8/256/0/180] [ 8481.599159] Key type lgssc registered [ 8482.018134] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8486.147216] Lustre: Mounted lustre-client [ 8488.464658] Lustre: DEBUG MARKER: == sanityn test 110: do not grant another lock on resend ========================================================== 04:03:18 (1769763798) [ 8505.311122] Lustre: 444566:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1769763799/real 1769763799] req@ffff89ec87060a80 x1855731835873152/t0(0) o36->lustre-MDT0000-mdc-ffff89ec98782800@192.168.206.107@tcp:12/10 lens 496/440 e 0 to 1 dl 1769763815 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 8505.318139] Lustre: lustre-MDT0000-mdc-ffff89ec98782800: Connection to lustre-MDT0000 (at 192.168.206.107@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8505.327128] Lustre: lustre-MDT0000-mdc-ffff89ec98782800: Connection restored to 192.168.206.107@tcp (at 192.168.206.107@tcp) [ 8520.671118] Lustre: 444566:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1769763815/real 1769763815] req@ffff89ec87060a80 x1855731835873152/t0(0) o36->lustre-MDT0000-mdc-ffff89ec98782800@192.168.206.107@tcp:12/10 lens 496/440 e 0 to 1 dl 1769763831 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 8520.680521] Lustre: lustre-MDT0000-mdc-ffff89ec98782800: Connection to lustre-MDT0000 (at 192.168.206.107@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8520.689878] Lustre: lustre-MDT0000-mdc-ffff89ec98782800: Connection restored to 192.168.206.107@tcp (at 192.168.206.107@tcp) [ 8537.055112] Lustre: 444566:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1769763831/real 1769763831] req@ffff89ec87060a80 x1855731835873152/t0(0) o36->lustre-MDT0000-mdc-ffff89ec98782800@192.168.206.107@tcp:12/10 lens 496/440 e 0 to 1 dl 1769763847 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 8537.064031] Lustre: lustre-MDT0000-mdc-ffff89ec98782800: Connection to lustre-MDT0000 (at 192.168.206.107@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8537.074360] Lustre: lustre-MDT0000-mdc-ffff89ec98782800: Connection restored to 192.168.206.107@tcp (at 192.168.206.107@tcp) [ 8552.415181] Lustre: 444566:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1769763847/real 1769763847] req@ffff89ec87060a80 x1855731835873152/t0(0) o36->lustre-MDT0000-mdc-ffff89ec98782800@192.168.206.107@tcp:12/10 lens 496/440 e 0 to 1 dl 1769763863 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 8552.426815] Lustre: lustre-MDT0000-mdc-ffff89ec98782800: Connection to lustre-MDT0000 (at 192.168.206.107@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8552.436985] Lustre: lustre-MDT0000-mdc-ffff89ec98782800: Connection restored to 192.168.206.107@tcp (at 192.168.206.107@tcp) [ 8552.991971] Lustre: DEBUG MARKER: == sanityn test 111: A racy rename/link an open file should not cause fs corruption ========================================================== 04:04:23 (1769763863) [ 8558.712690] Lustre: DEBUG MARKER: == sanityn test 112: update max-inherit in default LMV === 04:04:28 (1769763868) [ 8562.458125] Lustre: DEBUG MARKER: == sanityn test 113: check servers of specified fs ======= 04:04:32 (1769763872) [ 8564.641658] Lustre: DEBUG MARKER: == sanityn test 114: implicit default LMV inherit ======== 04:04:34 (1769763874) [ 8571.649807] Lustre: DEBUG MARKER: == sanityn test 115: ldiskfs doesn't check direntry for uniqueness ========================================================== 04:04:41 (1769763881) [ 8584.322992] Lustre: DEBUG MARKER: == sanityn test 116: DNE: Set default LMV layout from a remote client ========================================================== 04:04:54 (1769763894) [ 8586.535739] Lustre: DEBUG MARKER: == sanityn test 121: trunc append race =================== 04:04:56 (1769763896) [ 8586.605083] LustreError: 449339:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 sleeping for 2000ms [ 8588.687081] LustreError: 449339:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 awake [ 8590.672236] Lustre: DEBUG MARKER: == sanityn test 200: service remains healthy while able to process request ========================================================== 04:05:00 (1769763900) [ 8608.735136] Lustre: 443406:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1769763903/real 1769763903] req@ffff89ec9fc1df80 x1855731836907648/t0(0) o4->lustre-OST0000-osc-ffff89ec98782800@192.168.206.107@tcp:6/4 lens 4584/448 e 0 to 1 dl 1769763919 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 8608.735198] Lustre: lustre-OST0000-osc-ffff89ec98782800: Connection to lustre-OST0000 (at 192.168.206.107@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8608.741328] Lustre: 443406:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 3 previous similar messages [ 8608.760101] Lustre: lustre-OST0000-osc-ffff89ec98782800: Connection restored to 192.168.206.107@tcp (at 192.168.206.107@tcp) [ 8625.119138] Lustre: 443405:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1769763919/real 1769763919] req@ffff89ecbc721880 x1855731836909184/t0(0) o4->lustre-OST0000-osc-ffff89ec98782800@192.168.206.107@tcp:6/4 lens 4584/448 e 0 to 1 dl 1769763935 ref 2 fl Rpc:XQr/602/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 8625.119174] Lustre: lustre-OST0000-osc-ffff89ec98782800: Connection to lustre-OST0000 (at 192.168.206.107@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8625.128911] Lustre: 443405:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 8 previous similar messages [ 8625.138293] Lustre: lustre-OST0000-osc-ffff89ec98782800: Connection restored to 192.168.206.107@tcp (at 192.168.206.107@tcp) [ 8656.863117] Lustre: 443404:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1769763951/real 1769763951] req@ffff89ecbc721f80 x1855731836908416/t0(0) o4->lustre-OST0000-osc-ffff89ec98782800@192.168.206.107@tcp:6/4 lens 4584/448 e 0 to 1 dl 1769763967 ref 2 fl Rpc:XQr/602/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 8656.868839] Lustre: 443404:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 5 previous similar messages [ 8656.870439] Lustre: lustre-OST0000-osc-ffff89ec98782800: Connection to lustre-OST0000 (at 192.168.206.107@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8656.873312] Lustre: Skipped 1 previous similar message [ 8656.879729] Lustre: lustre-OST0000-osc-ffff89ec98782800: Connection restored to 192.168.206.107@tcp (at 192.168.206.107@tcp) [ 8656.881741] Lustre: Skipped 1 previous similar message [ 8679.882734] Lustre: DEBUG MARKER: oleg607-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff89ec84df8800.ost_server_uuid 50 [ 8680.339192] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff89ec84df8800.ost_server_uuid in IDLE state after 0 sec [ 8680.842467] Lustre: DEBUG MARKER: cleanup: ====================================================== [ 8681.375884] Lustre: DEBUG MARKER: == sanityn test complete, duration 8368 sec ============== 04:06:31 (1769763991) [ 8681.859395] Lustre: DEBUG MARKER: === sanityn: start cleanup 04:06:32 (1769763992) === [ 8739.811126] LustreError: 451387:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89ec84df8800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8739.814512] LustreError: 451387:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 8739.830088] Lustre: Unmounted lustre-client [ 8740.935347] Lustre: DEBUG MARKER: === sanityn: finish cleanup 04:07:31 (1769764051) === [ 8741.225490] LustreError: 451690:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89ec98782800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8741.228414] LustreError: 451690:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 8741.233640] LustreError: 451690:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 8741.235084] LustreError: 451690:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 8741.256058] Lustre: Unmounted lustre-client [ 8774.089751] Key type lgssc unregistered [ 8774.194509] LNet: 452373:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8774.196355] LNetError: 452373:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8774.203753] LNet: Removed LNI 192.168.206.7@tcp [ 8774.419086] Key type .llcrypt unregistered [ 8774.419895] Key type ._llcrypt unregistered