[ 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.16.2-1.fc38 04/01/2014 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 449824352 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 0x000f5b50-0x000f5b5f] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F5970 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE1BB7 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE1A53 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 001A13 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE1AC7 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE1B57 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE1B8F 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe1a53-0xbffe1ac6] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe1a52] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe1ac7-0xbffe1b56] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe1b57-0xbffe1b8e] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe1b8f-0xbffe1bb6] [ 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: 2876856K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001011] APIC: Switch to symmetric I/O mode setup [ 0.003079] x2apic enabled [ 0.004006] Switched APIC routing to physical x2apic. [ 0.005014] kvm-guest: setup PV IPIs [ 0.008317] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.009000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.009022] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.010012] pid_max: default: 32768 minimum: 301 [ 0.011109] LSM: Security Framework initializing [ 0.012055] Yama: becoming mindful. [ 0.013046] SELinux: Initializing. [ 0.014075] *** VALIDATE selinux *** [ 0.022356] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.027397] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.028155] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029300] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.030114] *** VALIDATE tmpfs *** [ 0.031465] *** VALIDATE proc *** [ 0.032278] *** VALIDATE cgroup *** [ 0.033011] *** VALIDATE cgroup2 *** [ 0.034278] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.035146] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.036010] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.037025] Spectre V2 : User space: Vulnerable [ 0.038010] Speculative Store Bypass: Vulnerable [ 0.041221] debug: unmapping init [mem 0xffffffff97259000-0xffffffff97260fff] [ 0.043162] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.044680] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.045025] ... version: 2 [ 0.046012] ... bit width: 48 [ 0.047011] ... generic registers: 4 [ 0.048012] ... value mask: 0000ffffffffffff [ 0.049016] ... max period: 00007fffffffffff [ 0.050013] ... fixed-purpose events: 3 [ 0.051008] ... event mask: 000000070000000f [ 0.052325] rcu: Hierarchical SRCU implementation. [ 0.054475] smp: Bringing up secondary CPUs ... [ 0.055572] x86: Booting SMP configuration: [ 0.056026] .... node #0, CPUs: #1 #2 #3 [ 0.059539] smp: Brought up 1 node, 4 CPUs [ 0.061011] smpboot: Max logical packages: 1 [ 0.062014] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.142025] node 0 deferred pages initialised in 76ms [ 0.146097] devtmpfs: initialized [ 0.147266] x86/mm: Memory block size: 128MB [ 0.150645] gcov: version magic: 0x41383552 [ 0.152172] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.156108] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.158328] pinctrl core: initialized pinctrl subsystem [ 0.160178] [ 0.160703] ************************************************************* [ 0.163012] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.165011] ** ** [ 0.166011] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.168013] ** ** [ 0.170011] ** This means that this kernel is built to expose internal ** [ 0.172012] ** IOMMU data structures, which may compromise security on ** [ 0.174011] ** your system. ** [ 0.177021] ** ** [ 0.179010] ** If you see this message and you are not debugging the ** [ 0.181012] ** kernel, report this immediately to your vendor! ** [ 0.183012] ** ** [ 0.186011] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.188011] ************************************************************* [ 0.190670] NET: Registered protocol family 16 [ 0.192397] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.195060] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.197096] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.201099] cpuidle: using governor menu [ 0.202651] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.204437] PCI: Using configuration type 1 for base access [ 0.207882] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.217110] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.219038] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.222138] cryptd: max_cpu_qlen set to 1000 [ 0.224229] ACPI: Added _OSI(Module Device) [ 0.226018] ACPI: Added _OSI(Processor Device) [ 0.228013] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.230014] ACPI: Added _OSI(Processor Aggregator Device) [ 0.235056] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.241896] ACPI: Interpreter enabled [ 0.242000] ACPI: PM: (supports S0 S3 S4 S5) [ 0.242000] ACPI: Using IOAPIC for interrupt routing [ 0.243099] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.246404] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.257099] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.259598] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.263019] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.267074] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.272220] acpiphp: Slot [2] registered [ 0.274119] acpiphp: Slot [3] registered [ 0.275115] acpiphp: Slot [4] registered [ 0.277105] acpiphp: Slot [5] registered [ 0.278124] acpiphp: Slot [6] registered [ 0.280107] acpiphp: Slot [7] registered [ 0.282129] acpiphp: Slot [8] registered [ 0.283092] acpiphp: Slot [9] registered [ 0.285116] acpiphp: Slot [10] registered [ 0.288119] acpiphp: Slot [11] registered [ 0.289090] acpiphp: Slot [12] registered [ 0.292107] acpiphp: Slot [13] registered [ 0.293135] acpiphp: Slot [14] registered [ 0.294088] acpiphp: Slot [15] registered [ 0.295084] acpiphp: Slot [16] registered [ 0.297078] acpiphp: Slot [17] registered [ 0.298088] acpiphp: Slot [18] registered [ 0.300131] acpiphp: Slot [19] registered [ 0.302134] acpiphp: Slot [20] registered [ 0.303097] acpiphp: Slot [21] registered [ 0.305092] acpiphp: Slot [22] registered [ 0.306121] acpiphp: Slot [23] registered [ 0.308112] acpiphp: Slot [24] registered [ 0.309092] acpiphp: Slot [25] registered [ 0.311079] acpiphp: Slot [26] registered [ 0.312126] acpiphp: Slot [27] registered [ 0.314167] acpiphp: Slot [28] registered [ 0.316091] acpiphp: Slot [29] registered [ 0.317097] acpiphp: Slot [30] registered [ 0.319111] acpiphp: Slot [31] registered [ 0.321061] PCI host bridge to bus 0000:00 [ 0.322016] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.324024] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.327024] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.329020] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.332036] pci_bus 0000:00: root bus resource [mem 0x180000000-0x1ffffffff window] [ 0.334023] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.336177] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.341342] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.345131] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.352537] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.356056] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.360018] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.362014] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.364016] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.366456] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.369625] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.371041] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.373609] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.377015] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.385017] pci 0000:00:02.0: reg 0x20: [mem 0xfebf4000-0xfebf7fff 64bit pref] [ 0.388013] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.393055] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.400017] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.407015] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.422036] pci 0000:00:05.0: reg 0x20: [mem 0xfebf8000-0xfebfbfff 64bit pref] [ 0.430734] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.436015] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.443017] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.458015] pci 0000:00:06.0: reg 0x20: [mem 0xfebfc000-0xfebfffff 64bit pref] [ 0.467901] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.470429] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.473381] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.475415] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.478251] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.483261] iommu: Default domain type: Passthrough [ 0.486429] SCSI subsystem initialized [ 0.488132] ACPI: bus type USB registered [ 0.490106] usbcore: registered new interface driver usbfs [ 0.492107] usbcore: registered new interface driver hub [ 0.494097] usbcore: registered new device driver usb [ 0.496114] pps_core: LinuxPPS API ver. 1 registered [ 0.498019] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.501059] PTP clock support registered [ 0.502138] EDAC MC: Ver: 3.0.0 [ 0.505127] PCI: Using ACPI for IRQ routing [ 0.506863] NetLabel: Initializing [ 0.508015] NetLabel: domain hash size = 128 [ 0.511015] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.513076] NetLabel: unlabeled traffic allowed by default [ 0.517155] vgaarb: loaded [ 0.519153] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.521014] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.524829] clocksource: Switched to clocksource kvm-clock [ 0.648839] VFS: Disk quotas dquot_6.6.0 [ 0.650674] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.653355] *** VALIDATE ramfs *** [ 0.654698] *** VALIDATE hugetlbfs *** [ 0.656711] pnp: PnP ACPI init [ 0.659278] pnp: PnP ACPI: found 6 devices [ 0.676964] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.680099] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.682246] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.684541] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.686903] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.689278] pci_bus 0000:00: resource 8 [mem 0x180000000-0x1ffffffff window] [ 0.691834] NET: Registered protocol family 2 [ 0.696065] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.702528] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.706601] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.711801] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.715479] TCP: Hash tables configured (established 65536 bind 65536) [ 0.718208] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.720865] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.723462] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.726246] NET: Registered protocol family 1 [ 0.729564] RPC: Registered named UNIX socket transport module. [ 0.732202] RPC: Registered udp transport module. [ 0.734247] RPC: Registered tcp transport module. [ 0.736013] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.738093] NET: Registered protocol family 44 [ 0.739252] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.741407] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.743601] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.746031] PCI: CLS 0 bytes, default 64 [ 0.747668] Unpacking initramfs... [ 2.151500] debug: unmapping init [mem 0xffffa060fcc64000-0xffffa060fffcffff] [ 2.155335] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.157846] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.160640] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.651495] Initialise system trusted keyrings [ 2.653298] Key type blacklist registered [ 2.658123] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.666872] zbud: loaded [ 2.670275] *** VALIDATE nfs *** [ 2.671684] *** VALIDATE nfs4 *** [ 2.673248] pstore: using deflate compression [ 2.676611] Platform Keyring initialized [ 2.788752] NET: Registered protocol family 38 [ 2.790606] Key type asymmetric registered [ 2.792159] Asymmetric key parser 'x509' registered [ 2.794012] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.797186] io scheduler mq-deadline registered [ 2.798820] io scheduler kyber registered [ 2.800389] io scheduler bfq registered [ 2.801996] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.805326] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.807966] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.810615] ACPI: Power Button [PWRF] [ 2.903837] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.995117] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.090848] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.124796] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.153177] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.160597] Non-volatile memory driver v1.3 [ 3.162303] Linux agpgart interface v0.103 [ 3.194335] virtio_blk virtio1: [vda] 67976 512-byte logical blocks (34.8 MB/33.2 MiB) [ 3.197256] vda: detected capacity change from 0 to 34803712 [ 3.213348] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.216066] vdb: detected capacity change from 0 to 1073741824 [ 3.224044] libphy: Fixed MDIO Bus: probed [ 3.229945] usbcore: registered new interface driver usbserial_generic [ 3.232493] usbserial: USB Serial support registered for generic [ 3.234462] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.238721] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.240661] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.243055] mousedev: PS/2 mouse device common for all mice [ 3.246127] rtc_cmos 00:05: RTC can wake from S4 [ 3.249532] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.251107] rtc_cmos 00:05: registered as rtc0 [ 3.255108] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.258521] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.260129] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.261830] intel_pstate: CPU model not supported [ 3.268240] hid: raw HID events driver (C) Jiri Kosina [ 3.270759] usbcore: registered new interface driver usbhid [ 3.272731] usbhid: USB HID core driver [ 3.276372] drop_monitor: Initializing network drop monitor service [ 3.279122] Initializing XFRM netlink socket [ 3.281775] NET: Registered protocol family 10 [ 3.285489] Segment Routing with IPv6 [ 3.286687] NET: Registered protocol family 17 [ 3.288695] mpls_gso: MPLS GSO support [ 3.294649] RAS: Correctable Errors collector initialized. [ 3.297073] AVX version of gcm_enc/dec engaged. [ 3.298871] AES CTR mode by8 optimization enabled [ 3.385889] sched_clock: Marking stable (3385801065, 0)->(4276292471, -890491406) [ 3.389199] registered taskstats version 1 [ 3.391022] Loading compiled-in X.509 certificates [ 3.392662] zswap: loaded using pool lzo/zbud [ 3.416657] Key type big_key registered [ 3.428799] Key type encrypted registered [ 3.430040] ima: No TPM chip found, activating TPM-bypass! [ 3.431467] ima: Allocated hash algorithm: sha1 [ 3.432690] ima: No architecture policies found [ 3.433863] evm: Initialising EVM extended attributes: [ 3.435100] evm: security.selinux [ 3.435883] evm: security.ima [ 3.436626] evm: security.capability [ 3.437537] evm: HMAC attrs: 0x1 [ 3.439395] rtc_cmos 00:05: setting system clock to 2025-10-24 14:07:55 UTC (1761314875) [ 3.444547] debug: unmapping init [mem 0xffffffff98203000-0xffffffff983fffff] [ 3.446771] debug: unmapping init [mem 0xffffffff96f82000-0xffffffff97258fff] [ 3.458163] Write protecting the kernel read-only data: 28672k [ 3.461563] debug: unmapping init [mem 0xffffffff95603000-0xffffffff957fffff] [ 3.464165] debug: unmapping init [mem 0xffffffff95f14000-0xffffffff95ffffff] [ 3.496239] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 3.504402] systemd[1]: Detected virtualization kvm. [ 3.506455] systemd[1]: Detected architecture x86-64. [ 3.508199] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.536502] systemd[1]: No hostname configured. [ 3.538373] systemd[1]: Set hostname to . [ 3.540514] random: systemd: uninitialized urandom read (16 bytes read) [ 3.543185] systemd[1]: Initializing machine ID from random generator. [ 3.569082] random: ln: uninitialized urandom read (6 bytes read) [ 3.672469] random: systemd: uninitialized urandom read (16 bytes read) [ 3.675170] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ 3.679419] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ 3.683792] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. [ OK ] Started Memstrack Anylazing Service. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Swap. [ OK ] Listening on udev Control Socket. Starting Setup Virtual Console... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. Starting Apply Kernel Variables... [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... [ OK ] Reached target Timers. [ OK ] Reached target Initrd Root Device. [ OK ] Reached target Sockets. Starting Create list of required st…ce nodes for the current kernel... Starting Journal Service... [ OK ] Reached target Local Encrypted Volumes. [ OK ] Started Setup Virtual Console. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Create list of required sta…vice nodes for the current kernel. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.291663] device-mapper: uevent: version 1.0.3 [ 4.294144] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting dracut initqueue hook... [ 5.045679] virtio_net virtio0 ens2: renamed from eth0 [ 5.080750] scsi host0: ata_piix [ 5.104513] scsi host1: ata_piix [ 5.111185] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.114539] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 8.646278] dracut-initqueue[582]: RTNETLINK answers: File exists [ 9.949075] random: crng init done [ 9.950904] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 10.275288] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped target Timers. [ 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. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Sockets. [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Swap. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev 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.343049] printk: systemd: 26 output lines suppressed due to ratelimiting [ 11.640315] SELinux: Disabled at runtime. [ 11.699925] 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.708475] systemd[1]: Detected virtualization kvm. [ 11.710432] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 12.294245] systemd[1]: initrd-switch-root.service: Succeeded. [ 12.296982] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 12.300753] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 12.304890] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 12.308079] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 12.320292] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.328388] systemd[1]: Activating swap /dev/disk/by-label/SWAP... Activating swap /dev/disk/by-label/SWAP... [ 12.347753] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Listening on Process Core Dump Socket. Mounting Huge Pages File System... Starting Apply Kernel Variables... [ OK ] Listening on udev Kernel Socket. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Stopped target Switch Root. [ OK ] Created slice system-getty.slice. [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... Starting Remount Root and Kernel File Systems... Mounting Kernel Debug File System... [ OK ] Stopped target Initrd Root File System. Mounting POSIX Message Queue File System... [ OK ] Stopped target Initrd File Systems. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Reached target rpc_pipefs.target. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Started Journal Service. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted Huge Pages File System. [ OK ] Started Apply Kernel Variables. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted Kernel Debug File System. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. Starting Create Static Device Nodes in /dev... Starting Configure read-only root support... [ OK ] Reached target Swap. Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... Starting udev Kernel Device Manager... [ OK ] Mounted /mnt. [ 12.810906] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 13.164031] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 13.281608] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 13.355178] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 13.380211] EDAC sbridge: Ver: 1.1.2 [ 14.673348] Key type dns_resolver registered [ 14.988253] NFS: Registering the id_resolver key type [ 14.990234] Key type id_resolver registered [ 14.991685] 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 Cleanup of Temporary Directories. [ OK ] Started dnf makecache --timer. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Timers. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started irqbalance daemon. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Login Service... Starting Restore /run/initramfs on shutdown... [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Reached target sshd-keygen.target. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting OpenSSH server daemon... Starting Dynamic System Tuning Daemon... Starting GSSAPI Proxy Daemon... Starting Hostname Service... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started OpenSSH server daemon. [ OK ] Started Permit User Sessions. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Getty on tty1. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Notify NFS peers of a restart... Starting System Logging Service... Starting Crash recovery kernel arming... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started Authorization Manager. [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg342-client login: [ 47.215268] libcfs: loading out-of-tree module taints kernel. [ 47.311161] alg: No test for adler32 (adler32-zlib) [ 48.064428] Key type ._llcrypt registered [ 48.066219] Key type .llcrypt registered [ 48.354888] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 48.854724] Lustre: Lustre: Build Version: 2.15.7_10_g040485f [ 49.490131] LNet: Added LNI 192.168.203.42@tcp [8/256/0/180] [ 49.494845] LNet: Accept secure, port 988 [ 51.275678] Key type lgssc registered [ 52.412614] Lustre: Echo OBD driver; http://www.lustre.org/ [ 144.612080] hrtimer: interrupt took 3070296 ns [ 151.470806] Lustre: Mounted lustre-client [ 155.486558] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 175.577624] Lustre: DEBUG MARKER: oleg342-client.virtnet: executing check_logdir /tmp/testlogs/ [ 177.120178] Lustre: lustre-OST0000-osc-ffffa061517e7800: disconnect after 24s idle [ 179.010384] Lustre: DEBUG MARKER: oleg342-client.virtnet: executing yml_node [ 182.276723] Lustre: DEBUG MARKER: Client: 2.15.7.10 [ 184.265864] Lustre: DEBUG MARKER: MDS: 2.15.7.10 [ 186.447988] Lustre: DEBUG MARKER: OSS: 2.15.7.10 [ 187.595516] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Fri Oct 24 10:10:58 EDT 2025 [ 194.842542] Lustre: DEBUG MARKER: excepting tests: 27 28 [ 196.278851] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 196.826297] Lustre: Mounted lustre-client [ 201.708947] Lustre: DEBUG MARKER: oleg342-client.virtnet: executing check_config_client /mnt/lustre [ 217.485350] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 226.238251] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 10:11:37 (1761315097) [ 231.449612] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 10:11:42 (1761315102) [ 236.399566] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 10:11:47 (1761315107) [ 240.747849] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 10:11:51 (1761315111) [ 244.847471] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 10:11:56 (1761315116) [ 248.723613] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 10:11:59 (1761315119) [ 253.246911] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 10:12:04 (1761315124) [ 257.750867] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 10:12:08 (1761315128) [ 262.657727] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 10:12:13 (1761315133) [ 267.764883] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 10:12:18 (1761315138) [ 272.691296] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 10:12:23 (1761315143) [ 276.475081] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 10:12:27 (1761315147) [ 280.300428] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 10:12:31 (1761315151) [ 284.394708] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 10:12:35 (1761315155) [ 288.223237] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 10:12:39 (1761315159) [ 291.954472] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 10:12:43 (1761315163) [ 295.633407] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 10:12:46 (1761315166) [ 300.316711] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 10:12:51 (1761315171) [ 304.104458] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 10:12:55 (1761315175) [ 308.806198] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 10:12:59 (1761315179) [ 309.299942] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=144115205289279508 file: /mnt/lustre/lockdir/lockfile=144115205289279507 [ 459.474483] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 10:15:30 (1761315330) [ 465.405537] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 10:15:36 (1761315336) [ 470.304884] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 10:15:41 (1761315341) [ 475.442214] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 10:15:46 (1761315346) [ 480.841711] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 10:15:51 (1761315351) [ 485.789380] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 10:15:56 (1761315356) [ 486.981150] Lustre: DEBUG MARKER: chmod [ 491.224985] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 10:16:02 (1761315362) [ 495.924783] Lustre: DEBUG MARKER: SKIP: oos2 test_15 oos2.sh: 7207500kB free gt MAXFREE 800000kB, increase 800000 (or reduce test fs size) to proceed [ 503.034816] Lustre: DEBUG MARKER: == sanityn test 16a: 2500 iterations of dual-mount fsx === 10:16:14 (1761315374) [ 727.807597] Lustre: DEBUG MARKER: == sanityn test 16b: 2500 iterations of dual-mount fsx at small size ========================================================== 10:19:58 (1761315598) [ 833.194682] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 10:21:44 (1761315704) [ 978.272454] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 10:24:09 (1761315849) [ 1010.521410] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 10:24:41 (1761315881) [ 1011.177209] Lustre: lustre-OST0001-osc-ffffa06152bd2800: disconnect after 20s idle [ 1011.179557] Lustre: Skipped 1 previous similar message [ 1015.915848] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 10:24:47 (1761315887) [ 1016.791181] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1016.845800] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1016.894371] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1016.946644] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1016.990907] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1017.038526] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1017.100448] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1017.185889] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1017.238299] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1017.291138] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1017.362792] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1017.398402] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1017.441802] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1017.495206] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1017.539337] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1017.578844] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1017.638142] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1017.693224] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1017.747365] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1017.834553] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1017.910637] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1017.976617] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1018.046420] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1018.110922] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1018.168245] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1018.233901] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1018.280173] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1018.334211] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1018.384625] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1018.426971] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1018.486559] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1018.544244] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1018.607213] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1018.682629] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1018.744407] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1018.817067] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1018.890553] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1018.943298] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1018.992283] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1019.090414] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1019.149622] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1019.210764] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1019.282574] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1019.338250] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1019.387075] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1019.448678] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1019.527413] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1019.594453] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1019.673571] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1019.725344] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1019.770991] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1019.823129] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1019.861763] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1019.896558] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1019.934184] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1019.986950] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1020.020990] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1020.065654] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1020.125497] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1020.183857] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1020.237773] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1020.286489] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1020.319834] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1020.383615] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1020.423478] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1020.472193] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1020.515401] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1020.559563] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1020.625833] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1020.704091] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1020.775095] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1020.837084] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1020.902513] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1020.961423] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1021.040559] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1021.115510] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1021.198266] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1021.283089] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1021.332490] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1021.400379] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1021.506425] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1021.579544] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1021.649086] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1021.723581] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1021.805871] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1021.867447] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1021.944238] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1022.006984] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1022.077693] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1022.144396] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1022.237561] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1022.277160] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1022.356584] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1022.425756] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1022.491174] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1022.569715] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1022.652172] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1022.718396] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1022.782801] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1022.868075] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1022.944803] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1023.034505] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1023.079307] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1023.137531] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1023.176737] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1023.219737] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1023.307223] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1023.378732] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1023.427198] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1023.468901] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1023.529493] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1023.587877] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1023.692228] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1023.768798] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1023.827650] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1023.871844] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1023.916256] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1023.993262] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1024.085866] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1024.166229] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1024.223167] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1024.284276] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1024.328986] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1024.393439] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1024.476396] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1024.579706] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1024.650337] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1024.739198] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1024.812543] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1024.873735] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1024.931354] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1024.975283] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1025.032531] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1025.083824] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1025.141371] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1025.180268] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1025.247231] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1025.323224] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1025.373588] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1025.425599] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1025.477521] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1025.541424] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1025.601347] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1025.673338] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1025.761860] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1025.849786] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1025.912732] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1025.995322] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1026.072416] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1026.193837] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1026.278251] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1026.409782] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1026.493106] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1026.553701] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1026.644722] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1026.718293] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1026.772189] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1026.835293] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1026.895957] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1026.975747] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1027.040397] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1027.109485] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1027.171338] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1027.250782] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1027.301434] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1027.337629] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1027.392633] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1027.444915] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1027.497891] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1027.578192] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1027.702933] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1027.774989] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1027.820808] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1027.889732] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1027.958288] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1028.013156] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1028.086781] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1028.150816] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1028.210957] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1028.297269] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1028.352053] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1028.405949] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1028.489209] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1028.568741] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1028.653623] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1028.781638] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1028.839680] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1028.914440] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1029.004217] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1029.059531] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1029.131584] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1029.206414] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1029.263979] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1029.321053] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1029.397651] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1029.485507] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1029.580099] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1029.652828] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1029.731770] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1029.795070] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1029.905848] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1029.981244] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1030.042678] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1030.091570] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1030.159685] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1030.221593] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1030.276400] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1030.333858] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1030.417694] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1030.482115] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1030.563324] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1030.659424] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1030.709875] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1030.790416] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1030.843596] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1030.886422] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1030.940420] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1030.993791] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1031.055553] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1031.117686] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1031.183736] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1031.233837] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1031.313748] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1031.412623] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1031.503185] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1031.579725] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1031.650338] Lustre: lustre-OST0000-osc-ffffa06152bd2800: disconnect after 20s idle [ 1031.658361] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1031.741971] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1031.834178] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1031.890738] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1031.948715] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1032.006821] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1032.074672] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1032.135790] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1032.190486] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1032.245356] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1032.318389] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1032.369551] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1032.421691] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1032.475829] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1032.532091] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1032.568669] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1032.623211] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1032.699378] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1032.761639] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1032.840470] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1032.916497] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1033.002495] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1033.072471] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1033.129641] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1033.194100] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1033.245438] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1033.314590] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1033.362908] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1033.421911] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1033.483208] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1033.557301] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1033.626533] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1033.677593] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1033.736375] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1033.778497] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1033.842776] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1033.901818] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1033.954491] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1034.045092] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1034.116289] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1034.175790] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1034.256594] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1034.359402] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1034.428351] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1034.507472] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1034.572291] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1034.659894] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1034.733198] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1034.812303] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1034.877658] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1034.942766] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1034.978917] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1035.043810] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1035.088252] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1035.150214] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1035.233221] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1035.310600] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1035.381781] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1035.460286] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1035.514100] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1035.576558] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1035.644759] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1035.725540] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1035.787551] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1035.839298] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1035.902230] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1035.947096] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1035.992410] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1036.050856] rw_seq_cst_vs_d (29818): drop_caches: 3 [ 1036.770372] Lustre: lustre-OST0000-osc-ffffa061517e7800: disconnect after 22s idle [ 1042.096827] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 10:25:13 (1761315913) [ 1042.531170] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1042.663547] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1042.769618] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1042.816250] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1042.893452] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1042.960817] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1043.050235] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1043.174411] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1043.253613] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1043.308445] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1043.365171] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1043.398624] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1043.428751] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1043.625869] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1043.670682] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1043.780087] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1043.859911] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1043.896731] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1043.992650] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1044.032781] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1044.195508] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1044.396295] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1044.510335] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1044.545474] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1044.655316] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1044.742908] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1044.866140] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1044.905828] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1044.973316] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1045.059616] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1045.320945] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1045.378864] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1045.416953] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1045.472237] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1045.614779] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1045.738964] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1045.882706] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1045.953203] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1046.001575] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1046.104692] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1046.139993] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1046.170502] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1046.259529] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1046.301495] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1046.349853] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1046.388590] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1046.417593] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1046.460907] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1046.602483] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1046.755199] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1046.789459] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1046.877960] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1046.932625] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1046.983033] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1047.018975] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1047.073752] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1047.111265] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1047.190871] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1047.300616] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1047.356968] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1047.479811] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1047.638382] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1047.719500] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1047.777622] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1047.847802] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1047.946558] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1048.045432] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1048.177281] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1048.265388] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1048.288610] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1048.405696] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1048.516561] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1048.585800] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1048.676224] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1048.727587] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1048.773993] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1049.030354] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1049.171929] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1049.280657] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1049.409911] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1049.483597] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1049.511359] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1049.583352] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1049.681350] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1049.720481] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1049.897290] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1049.948396] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1050.061966] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1050.133835] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1050.201590] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1050.419169] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1050.457103] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1050.527407] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1050.573996] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1050.698201] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1050.735752] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1050.890353] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1050.923577] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1050.997225] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1051.110619] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1051.195911] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1051.299801] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1051.355448] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1051.482155] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1051.560443] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1051.761298] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1051.807469] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1051.863683] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1051.901364] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1051.935602] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1052.124882] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1052.234612] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1052.345341] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1052.478125] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1052.654501] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1052.801561] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1052.861422] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1052.891528] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1052.988594] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1053.062110] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1053.107552] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1053.223605] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1053.321692] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1053.373602] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1053.546286] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1053.596430] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1053.685462] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1053.778133] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1053.920290] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1054.225933] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1054.319416] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1054.408304] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1054.455516] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1054.568766] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1054.656415] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1054.784633] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1054.912083] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1054.981505] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1055.066409] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1055.195850] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1055.329092] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1055.359262] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1055.400106] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1055.485341] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1055.576671] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1055.675633] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1055.715421] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1055.831548] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1055.868214] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1055.945137] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1055.978067] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1056.093945] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1056.138651] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1056.222851] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1056.302261] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1056.421353] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1056.637537] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1056.846699] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1056.939952] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1057.150539] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1057.249764] Lustre: lustre-OST0001-osc-ffffa06152bd2800: disconnect after 21s idle [ 1057.254606] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1057.291833] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1057.374165] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1057.578645] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1057.657664] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1057.735568] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1057.879711] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1057.954150] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1058.013485] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1058.133342] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1058.178778] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1058.265876] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1058.448739] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1058.610848] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1058.770618] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1058.853270] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1058.934419] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1059.138750] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1059.318946] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1059.394421] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1059.500112] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1059.605395] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1059.652557] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1059.678972] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1059.773908] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1059.840733] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1059.976397] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1060.007576] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1060.043311] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1060.247709] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1060.336579] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1060.380256] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1060.428582] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1060.473586] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1060.504752] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1060.608230] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1060.698234] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1060.734672] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1060.859374] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1060.911818] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1060.961909] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1061.026082] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1061.264171] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1061.315824] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1061.342206] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1061.422453] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1061.460098] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1061.511464] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1061.608616] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1061.692545] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1061.744123] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1061.854449] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1061.919663] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1062.073274] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1062.094740] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1062.145650] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1062.221907] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1062.368382] Lustre: lustre-OST0001-osc-ffffa061517e7800: disconnect after 21s idle [ 1067.888461] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 10:25:38 (1761315938) [ 1073.368021] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 10:25:44 (1761315944) [ 1081.831274] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 10:25:52 (1761315952) [ 1121.471808] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 10:26:32 (1761315992) [ 1129.584845] Lustre: DEBUG MARKER: loop 5 [ 1133.580993] Lustre: DEBUG MARKER: loop 10 [ 1134.048126] Lustre: lustre-OST0001-osc-ffffa061517e7800: disconnect after 21s idle [ 1137.678710] Lustre: DEBUG MARKER: loop 15 [ 1141.744452] Lustre: DEBUG MARKER: loop 20 [ 1147.877995] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 10:26:58 (1761316018) [ 1153.728523] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 10:27:04 (1761316024) [ 1159.415208] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 10:27:10 (1761316030) [ 1169.888422] Lustre: lustre-OST0001-osc-ffffa061517e7800: disconnect after 21s idle [ 1169.897208] Lustre: Skipped 1 previous similar message [ 1227.455324] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 10:28:18 (1761316098) [ 1232.858762] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 10:28:23 (1761316103) [ 1237.114215] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 10:28:28 (1761316108) [ 1242.132481] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 10:28:33 (1761316113) [ 1246.647687] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 10:28:37 (1761316117) [ 1253.417660] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 10:28:44 (1761316124) [ 1261.155229] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 1262.049453] Lustre: lustre-OST0000-osc-ffffa061517e7800: disconnect after 20s idle [ 1262.052217] Lustre: Skipped 3 previous similar messages [ 1262.253828] Lustre: DEBUG MARKER: SKIP: sanityn test_28 skipping ALWAYS excluded test 28 [ 1263.736939] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 10:28:54 (1761316134) [ 1270.882975] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 10:29:01 (1761316141) [ 1271.200390] Lustre: *** cfs_fail_loc=314, val=0*** [ 1272.226754] Lustre: *** cfs_fail_loc=314, val=0*** [ 1272.235391] Lustre: Skipped 2 previous similar messages [ 1276.304426] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 10:29:07 (1761316147) [ 1283.217123] Lustre: *** cfs_fail_loc=314, val=0*** [ 1287.457443] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 10:29:18 (1761316158) [ 1287.663418] Lustre: lustre-OST0000-osc-ffffa06152bd2800: Connection to lustre-OST0000 (at 192.168.203.142@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1287.677955] LustreError: lustre-OST0000-osc-ffffa06152bd2800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1287.686839] LustreError: 41799:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 1287.689096] Lustre: lustre-OST0000-osc-ffffa06152bd2800: Connection restored to (at 192.168.203.142@tcp) [ 1290.714102] LustreError: 41799:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 1419 awake [ 1294.982263] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 1296.007182] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 10:29:27 (1761316167) [ 1297.073695] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 1298.243537] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-Lock-Cancel ========================================================== 10:29:29 (1761316169) [ 1303.015827] Lustre: lustre-MDT0000-mdc-ffffa061517e7800: Connection to lustre-MDT0000 (at 192.168.203.142@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1308.135221] LustreError: 166-1: MGC192.168.203.142@tcp: Connection to MGS (at 192.168.203.142@tcp) was lost; in progress operations using this service will fail [ 1308.157877] Lustre: Evicted from MGS (at 192.168.203.142@tcp) after server handle changed from 0x4b7e6a3f2e4f90ef to 0x4b7e6a3f2e5c61a3 [ 1308.179107] Lustre: MGC192.168.203.142@tcp: Connection restored to (at 192.168.203.142@tcp) [ 1311.907288] Lustre: lustre-MDT0000-mdc-ffffa061517e7800: Connection restored to (at 192.168.203.142@tcp) [ 1328.710817] Lustre: DEBUG MARKER: == sanityn test 33d: DNE distributed operation should trigger COS ========================================================== 10:29:59 (1761316199) [ 1408.671882] Lustre: DEBUG MARKER: == sanityn test 33e: DNE local operation shouldn't trigger COS ========================================================== 10:31:19 (1761316279) [ 1409.730447] Lustre: DEBUG MARKER: SKIP: sanityn test_33e Need two or more clients, have 1 [ 1410.847116] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 10:31:22 (1761316282) [ 1460.685246] Lustre: lustre-OST0000-osc-ffffa06152bd2800: Connection to lustre-OST0000 (at 192.168.203.142@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1460.693258] Lustre: Skipped 1 previous similar message [ 1460.705123] LustreError: lustre-OST0000-osc-ffffa06152bd2800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1460.723043] LustreError: lustre-OST0000-osc-ffffa061517e7800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1460.723831] Lustre: lustre-OST0000-osc-ffffa06152bd2800: Connection restored to (at 192.168.203.142@tcp) [ 1460.749373] Lustre: Skipped 2 previous similar messages [ 1465.822486] Lustre: lustre-OST0001-osc-ffffa061517e7800: Connection to lustre-OST0001 (at 192.168.203.142@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1465.831362] Lustre: Skipped 1 previous similar message [ 1465.843301] LustreError: lustre-OST0001-osc-ffffa061517e7800: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 1465.859781] Lustre: lustre-OST0001-osc-ffffa061517e7800: Connection restored to (at 192.168.203.142@tcp) [ 1481.488695] Lustre: DEBUG MARKER: oleg342-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffffa061517e7800.ost_server_uuid,osc.lustre-OST0000-osc-ffffa06152bd2800.ost_server_uuid 40 [ 1482.208589] Lustre: lustre-OST0000-osc-ffffa061517e7800: disconnect after 22s idle [ 1482.220372] Lustre: Skipped 1 previous similar message [ 1482.532275] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffffa061517e7800.ost_server_uuid in FULL state after 0 sec [ 1483.831538] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffffa06152bd2800.ost_server_uuid in IDLE state after 0 sec [ 1488.340388] Lustre: DEBUG MARKER: oleg342-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffffa061517e7800.ost_server_uuid,osc.lustre-OST0001-osc-ffffa06152bd2800.ost_server_uuid 40 [ 1489.552124] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffffa061517e7800.ost_server_uuid in IDLE state after 0 sec [ 1490.984199] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffffa06152bd2800.ost_server_uuid in FULL state after 0 sec [ 1496.883597] Lustre: DEBUG MARKER: oleg342-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffffa061517e7800.ost_server_uuid,osc.lustre-OST0000-osc-ffffa06152bd2800.ost_server_uuid 40 [ 1498.395557] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffffa061517e7800.ost_server_uuid in IDLE state after 0 sec [ 1499.526319] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffffa06152bd2800.ost_server_uuid in IDLE state after 0 sec [ 1504.661900] Lustre: DEBUG MARKER: oleg342-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffffa061517e7800.ost_server_uuid,osc.lustre-OST0001-osc-ffffa06152bd2800.ost_server_uuid 40 [ 1505.825195] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffffa061517e7800.ost_server_uuid in IDLE state after 0 sec [ 1507.150993] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffffa06152bd2800.ost_server_uuid in FULL state after 0 sec [ 1516.503895] Lustre: DEBUG MARKER: oleg342-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffffa061517e7800.ost_server_uuid,osc.lustre-OST0000-osc-ffffa06152bd2800.ost_server_uuid 40 [ 1517.535292] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffffa061517e7800.ost_server_uuid in IDLE state after 0 sec [ 1518.566988] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffffa06152bd2800.ost_server_uuid in IDLE state after 0 sec [ 1523.158573] Lustre: DEBUG MARKER: oleg342-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffffa061517e7800.ost_server_uuid,osc.lustre-OST0001-osc-ffffa06152bd2800.ost_server_uuid 40 [ 1524.655822] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffffa061517e7800.ost_server_uuid in IDLE state after 0 sec [ 1526.020976] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffffa06152bd2800.ost_server_uuid in FULL state after 0 sec [ 1527.360549] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 10:33:18 (1761316398) [ 1529.214159] Lustre: DEBUG MARKER: Race attempt 0 [ 1531.438883] Lustre: DEBUG MARKER: Wait for 54305 54396 for 60 sec... [ 1596.025206] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 10:34:27 (1761316467) [ 1603.571983] Lustre: DEBUG MARKER: start test - cycle (0) [ 1622.341679] Lustre: DEBUG MARKER: start test - cycle (1) [ 1642.453537] Lustre: DEBUG MARKER: start test - cycle (2) [ 1662.971892] Lustre: DEBUG MARKER: start test - cycle (3) [ 1684.235782] Lustre: DEBUG MARKER: start test - cycle (4) [ 1704.259522] Lustre: DEBUG MARKER: start test - cycle (5) [ 1724.952179] Lustre: DEBUG MARKER: start test - cycle (6) [ 1744.273920] Lustre: DEBUG MARKER: start test - cycle (7) [ 1762.229203] Lustre: DEBUG MARKER: start test - cycle (8) [ 1780.846724] Lustre: DEBUG MARKER: start test - cycle (9) [ 1802.027133] Lustre: DEBUG MARKER: start test - cycle (10) [ 1826.916807] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 10:38:18 (1761316698) [ 1830.370286] Lustre: lustre-OST0000-osc-ffffa06152bd2800: disconnect after 20s idle [ 1830.377556] Lustre: Skipped 2 previous similar messages [ 1896.318892] Lustre: DEBUG MARKER: == sanityn test 39a: test from 11063 ============================================================================================ 10:39:27 (1761316767) [ 1902.067413] Lustre: DEBUG MARKER: == sanityn test 39b: 11063 problem 1 ============================================================================================ 10:39:33 (1761316773) [ 1908.278961] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 10:39:39 (1761316779) [ 1914.830738] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 10:39:45 (1761316785) [ 1915.094641] Lustre: *** cfs_fail_loc=411, val=0*** [ 1920.876311] Lustre: DEBUG MARKER: == sanityn test 40a: pdirops: create vs others ======================================================================== 10:39:51 (1761316791) [ 1936.699931] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 10:40:07 (1761316807) [ 1950.916749] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 10:40:21 (1761316821) [ 1964.742520] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 10:40:35 (1761316835) [ 1979.476329] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 10:40:50 (1761316850) [ 1994.170116] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 10:41:05 (1761316865) [ 2004.098271] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 10:41:15 (1761316875) [ 2013.292573] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 10:41:24 (1761316884) [ 2024.047132] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 10:41:35 (1761316895) [ 2034.915964] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 10:41:45 (1761316905) [ 2046.116460] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 10:41:57 (1761316917) [ 2057.703808] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 10:42:08 (1761316928) [ 2068.688340] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 10:42:19 (1761316939) [ 2079.383797] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 10:42:30 (1761316950) [ 2091.488757] Lustre: lustre-OST0000-osc-ffffa061517e7800: disconnect after 23s idle [ 2091.496328] Lustre: Skipped 10 previous similar messages [ 3069.248889] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 10:59:00 (1761317940) [ 3078.844643] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 10:59:09 (1761317949) [ 3088.040423] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 10:59:19 (1761317959) [ 3097.532808] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 10:59:28 (1761317968) [ 3107.965133] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 10:59:39 (1761317979) [ 3115.489739] Lustre: lustre-OST0000-osc-ffffa061517e7800: disconnect after 20s idle [ 3115.492461] Lustre: Skipped 1 previous similar message [ 3119.280922] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 10:59:50 (1761317990) [ 3129.990514] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 11:00:00 (1761318000) [ 3140.920470] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 11:00:11 (1761318011) [ 3154.197359] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 11:00:25 (1761318025) [ 3216.431436] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 11:01:27 (1761318087) [ 3224.736549] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 11:01:35 (1761318095) [ 3233.851959] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 11:01:44 (1761318104) [ 3245.644409] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 11:01:56 (1761318116) [ 3256.860594] Lustre: DEBUG MARKER: == sanityn test 43f: pdirops: unlink and rename (src) ======================================================================== 11:02:07 (1761318127) [ 3268.373776] Lustre: DEBUG MARKER: == sanityn test 43g: pdirops: unlink vs getattr ======================================================================== 11:02:18 (1761318138) [ 3279.676488] Lustre: DEBUG MARKER: == sanityn test 43h: pdirops: unlink vs readdir ======================================================================== 11:02:30 (1761318150) [ 3290.215984] Lustre: DEBUG MARKER: == sanityn test 43i: pdirops: unlink vs remote mkdir ===== 11:02:41 (1761318161) [ 3299.429372] Lustre: DEBUG MARKER: == sanityn test 43j: racy mkdir return EEXIST ======================================================================== 11:02:50 (1761318170) [ 3406.480845] Lustre: DEBUG MARKER: == sanityn test 43k: unlink vs create ==================== 11:04:36 (1761318276) [ 3729.888332] Lustre: lustre-OST0001-osc-ffffa061517e7800: disconnect after 24s idle [ 3729.895505] Lustre: Skipped 6 previous similar messages [ 4373.554434] Lustre: DEBUG MARKER: == sanityn test 44a: pdirops: rename tgt vs mkdir ======================================================================== 11:20:44 (1761319244) [ 4382.674565] Lustre: DEBUG MARKER: == sanityn test 44b: pdirops: rename tgt vs create ======================================================================== 11:20:53 (1761319253) [ 4391.202386] Lustre: DEBUG MARKER: == sanityn test 44c: pdirops: rename tgt vs link ======================================================================== 11:21:02 (1761319262) [ 4398.945356] Lustre: DEBUG MARKER: == sanityn test 44d: pdirops: rename tgt vs unlink ======================================================================== 11:21:10 (1761319270) [ 4406.066923] Lustre: DEBUG MARKER: == sanityn test 44e: pdirops: rename tgt and rename (tgt) ======================================================================== 11:21:17 (1761319277) [ 4414.837340] Lustre: DEBUG MARKER: == sanityn test 44f: pdirops: rename tgt and rename (src) ======================================================================== 11:21:26 (1761319286) [ 4423.487540] Lustre: DEBUG MARKER: == sanityn test 44g: pdirops: rename tgt vs getattr ======================================================================== 11:21:34 (1761319294) [ 4432.948968] Lustre: DEBUG MARKER: == sanityn test 44h: pdirops: rename tgt vs readdir ======================================================================== 11:21:44 (1761319304) [ 4441.013456] Lustre: DEBUG MARKER: == sanityn test 44i: pdirops: rename tgt vs remote mkdir ========================================================== 11:21:52 (1761319312) [ 4447.683888] Lustre: DEBUG MARKER: == sanityn test 45a: rename,mkdir doesn't return -EEXIST ======================================================================== 11:21:59 (1761319319) [ 4462.048462] Lustre: lustre-OST0000-osc-ffffa061517e7800: disconnect after 22s idle [ 4462.053050] Lustre: Skipped 3 previous similar messages [ 4514.630620] Lustre: DEBUG MARKER: == sanityn test 45b: pdirops: rename src vs create ======================================================================== 11:23:06 (1761319386) [ 4521.264806] Lustre: DEBUG MARKER: == sanityn test 45c: pdirops: rename src vs link ======================================================================== 11:23:12 (1761319392) [ 4527.879297] Lustre: DEBUG MARKER: == sanityn test 45d: pdirops: rename src vs unlink ======================================================================== 11:23:19 (1761319399) [ 4534.464949] Lustre: DEBUG MARKER: == sanityn test 45e: pdirops: rename src and rename (tgt) ======================================================================== 11:23:25 (1761319405) [ 4541.481742] Lustre: DEBUG MARKER: == sanityn test 45f: pdirops: rename src and rename (src) ======================================================================== 11:23:32 (1761319412) [ 4548.265737] Lustre: DEBUG MARKER: == sanityn test 45g: pdirops: rename src vs getattr ======================================================================== 11:23:39 (1761319419) [ 4554.459579] Lustre: DEBUG MARKER: == sanityn test 45h: pdirops: unlink vs readdir ======================================================================== 11:23:46 (1761319426) [ 4560.244856] Lustre: DEBUG MARKER: == sanityn test 45i: pdirops: rename src vs remote mkdir ========================================================== 11:23:51 (1761319431) [ 4566.848397] Lustre: DEBUG MARKER: == sanityn test 45j: read vs rename ====================== 11:23:58 (1761319438) [ 5509.473732] Lustre: DEBUG MARKER: == sanityn test 46a: pdirops: link vs mkdir ======================================================================== 11:39:40 (1761320380) [ 5511.649600] Lustre: lustre-OST0001-osc-ffffa061517e7800: disconnect after 23s idle [ 5511.652590] Lustre: Skipped 4 previous similar messages [ 5520.435762] Lustre: DEBUG MARKER: == sanityn test 46b: pdirops: link vs create ======================================================================== 11:39:51 (1761320391) [ 5530.753225] Lustre: DEBUG MARKER: == sanityn test 46c: pdirops: link vs link ======================================================================== 11:40:01 (1761320401) [ 5540.653193] Lustre: DEBUG MARKER: == sanityn test 46d: pdirops: link vs unlink ======================================================================== 11:40:11 (1761320411) [ 5550.619698] Lustre: DEBUG MARKER: == sanityn test 46e: pdirops: link and rename (tgt) ======================================================================== 11:40:21 (1761320421) [ 5561.044250] Lustre: DEBUG MARKER: == sanityn test 46f: pdirops: link and rename (src) ======================================================================== 11:40:32 (1761320432) [ 5571.205783] Lustre: DEBUG MARKER: == sanityn test 46g: pdirops: link vs getattr ======================================================================== 11:40:42 (1761320442) [ 5581.095491] Lustre: DEBUG MARKER: == sanityn test 46h: pdirops: link vs readdir ======================================================================== 11:40:52 (1761320452) [ 5591.485865] Lustre: DEBUG MARKER: == sanityn test 46i: pdirops: link vs remote mkdir ======= 11:41:02 (1761320462) [ 5600.928455] Lustre: DEBUG MARKER: == sanityn test 47a: pdirops: remote mkdir vs mkdir ====== 11:41:11 (1761320471) [ 5610.399703] Lustre: DEBUG MARKER: == sanityn test 47b: pdirops: remote mkdir vs create ===== 11:41:21 (1761320481) [ 5620.388776] Lustre: DEBUG MARKER: == sanityn test 47c: pdirops: remote mkdir vs link ======= 11:41:31 (1761320491) [ 5630.351236] Lustre: DEBUG MARKER: == sanityn test 47d: pdirops: remote mkdir vs unlink ===== 11:41:41 (1761320501) [ 5640.303489] Lustre: DEBUG MARKER: == sanityn test 47e: pdirops: remote mkdir and rename (tgt) ========================================================== 11:41:51 (1761320511) [ 5650.809917] Lustre: DEBUG MARKER: == sanityn test 47f: pdirops: remote mkdir and rename (src) ========================================================== 11:42:01 (1761320521) [ 5659.942460] Lustre: DEBUG MARKER: == sanityn test 47g: pdirops: remote mkdir vs getattr ==== 11:42:11 (1761320531) [ 5670.809593] Lustre: DEBUG MARKER: == sanityn test 50: osc lvb attrs: enqueue vs. CP AST ======================================================================== 11:42:22 (1761320542) [ 5671.103930] LustreError: 5565:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 410 sleeping for 2000ms [ 5673.192116] LustreError: 5565:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 410 awake [ 5680.260848] Lustre: DEBUG MARKER: == sanityn test 51a: layout lock: refresh layout should work ========================================================== 11:42:31 (1761320551) [ 5685.698266] Lustre: DEBUG MARKER: == sanityn test 51b: layout lock: glimpse should be able to restart if layout changed ========================================================== 11:42:37 (1761320557) [ 5685.917952] LustreError: 267419:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 5689.976243] LustreError: 267419:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 1404 awake [ 5689.992706] LustreError: 267419:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 5694.056141] LustreError: 267419:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 1404 awake [ 5694.076414] LustreError: 267426:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 5698.136926] LustreError: 267426:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 1404 awake [ 5701.844487] Lustre: DEBUG MARKER: == sanityn test 51c: layout lock: IT_LAYOUT blocked and correct layout can be returned ========================================================== 11:42:53 (1761320573) [ 5710.589604] Lustre: DEBUG MARKER: == sanityn test 51d: layout lock: losing layout lock should clean up memory map region ========================================================== 11:43:01 (1761320581) [ 5715.145376] Lustre: DEBUG MARKER: == sanityn test 51e: lfs getstripe does not break leases, part 2 ========================================================== 11:43:06 (1761320586) [ 5720.412625] Lustre: DEBUG MARKER: == sanityn test 54: rename locking ======================= 11:43:11 (1761320591) [ 5747.387321] Lustre: DEBUG MARKER: == sanityn test 55a: rename vs unlink target dir ========= 11:43:38 (1761320618) [ 5755.776712] Lustre: DEBUG MARKER: == sanityn test 55b: rename vs unlink source dir ========= 11:43:47 (1761320627) [ 5764.560784] Lustre: DEBUG MARKER: == sanityn test 55c: rename vs unlink orphan target dir == 11:43:55 (1761320635) [ 5779.181293] Lustre: DEBUG MARKER: == sanityn test 55d: rename file vs link ================= 11:44:10 (1761320650) [ 5790.859180] Lustre: DEBUG MARKER: == sanityn test 60: Verify data_version behaviour ======== 11:44:21 (1761320661) [ 5794.928579] Lustre: DEBUG MARKER: == sanityn test 70a: cd directory [ 5799.674733] Lustre: DEBUG MARKER: == sanityn test 70b: remove files after calling rm_entry ========================================================== 11:44:30 (1761320670) [ 5803.762641] Lustre: DEBUG MARKER: == sanityn test 71a: correct file map just after write operation is finished ========================================================== 11:44:35 (1761320675) [ 5807.044041] Lustre: DEBUG MARKER: == sanityn test 71b: check fiemap support for stripecount > 1 ========================================================== 11:44:38 (1761320678) [ 5810.918210] Lustre: DEBUG MARKER: == sanityn test 71c: check FIEMAP_EXTENT_LAST flag with different extents number ========================================================== 11:44:42 (1761320682) [ 5831.168542] Lustre: DEBUG MARKER: == sanityn test 71d: fiemap corruption test with fm_extent_count=0 ========================================================== 11:45:02 (1761320702) [ 5858.773982] Lustre: DEBUG MARKER: == sanityn test 72: getxattr/setxattr cache should be consistent between nodes ========================================================== 11:45:30 (1761320730) [ 5862.842729] Lustre: DEBUG MARKER: == sanityn test 73: getxattr should not cause xattr lock cancellation ========================================================== 11:45:34 (1761320734) [ 5867.036110] Lustre: DEBUG MARKER: == sanityn test 74: flock deadlock: different mounts ======================================================================== 11:45:38 (1761320738) [ 5870.264641] LustreError: 11-0: lustre-MDT0000-mdc-ffffa061517e7800: operation ldlm_enqueue to node 192.168.203.142@tcp failed: rc = -35 [ 5874.848949] Lustre: DEBUG MARKER: == sanityn test 75: osc: upcall after unuse lock============================================================================= 11:45:46 (1761320746) [ 5875.199273] LustreError: 2266:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 31d sleeping for 2000ms [ 5877.296181] LustreError: 2266:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 31d awake [ 5883.334155] Lustre: DEBUG MARKER: == sanityn test 76: Verify MDT open_files listing ======== 11:45:54 (1761320754) [ 5967.723498] Lustre: DEBUG MARKER: == sanityn test 77a: check FIFO NRS policy =============== 11:47:18 (1761320838) [ 5973.091114] Lustre: DEBUG MARKER: == sanityn test 77b: check CRR-N NRS policy ============== 11:47:24 (1761320844) [ 5980.867404] Lustre: DEBUG MARKER: == sanityn test 77c: check ORR NRS policy ================ 11:47:32 (1761320852) [ 5991.415546] Lustre: DEBUG MARKER: == sanityn test 77d: check TRR nrs policy ================ 11:47:42 (1761320862) [ 6000.479231] Lustre: DEBUG MARKER: == sanityn test 77e: check TBF NID nrs policy ============ 11:47:51 (1761320871) [ 6013.689383] Lustre: DEBUG MARKER: == sanityn test 77f: check TBF JobID nrs policy ========== 11:48:04 (1761320884) [ 6025.572736] Lustre: DEBUG MARKER: == sanityn test 77g: Change TBF type directly ============ 11:48:16 (1761320896) [ 6030.729849] Lustre: DEBUG MARKER: == sanityn test 77h: Wrong policy name should report error, not LBUG ========================================================== 11:48:22 (1761320902) [ 6036.763513] Lustre: DEBUG MARKER: == sanityn test 77i: Change rank of TBF rule ============= 11:48:28 (1761320908) [ 6048.458444] Lustre: DEBUG MARKER: == sanityn test 77j: check TBF-OPCode NRS policy ========= 11:48:39 (1761320919) [ 6092.602726] Lustre: DEBUG MARKER: == sanityn test 77ja: check TBF-UID/GID NRS policy ======= 11:49:23 (1761320963) [ 6115.809441] Lustre: lustre-OST0001-osc-ffffa061517e7800: disconnect after 21s idle [ 6115.813171] Lustre: Skipped 12 previous similar messages [ 6210.553562] Lustre: DEBUG MARKER: == sanityn test 77k: check TBF policy with NID/JobID/OPCode expression ========================================================== 11:51:21 (1761321081) [ 6498.427422] Lustre: DEBUG MARKER: == sanityn test 77l: check the output of NRS policies for generic TBF ========================================================== 11:56:09 (1761321369) [ 6503.754533] Lustre: DEBUG MARKER: == sanityn test 77m: check NRS Delay slows write RPC processing ========================================================== 11:56:15 (1761321375) [ 6556.483027] Lustre: DEBUG MARKER: == sanityn test 77n: check wildcard support for TBF JobID NRS policy ========================================================== 11:57:07 (1761321427) [ 6607.741191] Lustre: DEBUG MARKER: == sanityn test 77o: Changing rank should not panic ====== 11:57:59 (1761321479) [ 6612.572710] Lustre: DEBUG MARKER: == sanityn test 77q: Parallel TBF rule definitions should not panic ========================================================== 11:58:04 (1761321484) [ 6662.938426] Lustre: DEBUG MARKER: == sanityn test 77p: Check validity of rule names for TBF policies ========================================================== 11:58:54 (1761321534) [ 6679.794167] Lustre: DEBUG MARKER: == sanityn test 78: Enable policy and specify tunings right away ========================================================== 11:59:11 (1761321551) [ 6684.959491] Lustre: DEBUG MARKER: == sanityn test 79: xattr: intent error ================== 11:59:16 (1761321556) [ 6699.244942] Lustre: DEBUG MARKER: == sanityn test 80a: migrate directory when some children is being opened ========================================================== 11:59:30 (1761321570) [ 6701.178262] LustreError: 307991:0:(file.c:246:ll_close_inode_openhandle()) lustre-clilmv-ffffa061517e7800: inode [0x200000402:0x7c5:0x0] mdc close failed: rc = -116 [ 6701.695842] LustreError: 307992:0:(file.c:246:ll_close_inode_openhandle()) lustre-clilmv-ffffa061517e7800: inode [0x240000402:0x5ed:0x0] mdc close failed: rc = -116 [ 6701.701865] LustreError: 307992:0:(file.c:246:ll_close_inode_openhandle()) Skipped 10 previous similar messages [ 6704.842204] Lustre: DEBUG MARKER: == sanityn test 80b: Accessing directory during migration ========================================================== 11:59:36 (1761321576) [ 6705.921681] LustreError: 308606:0:(file.c:246:ll_close_inode_openhandle()) lustre-clilmv-ffffa06152bd2800: inode [0x200000402:0x7cc:0x0] mdc close failed: rc = -2 [ 6730.208307] Lustre: lustre-OST0000-osc-ffffa06152bd2800: disconnect after 24s idle [ 6730.213463] Lustre: Skipped 10 previous similar messages [ 6768.380598] Lustre: DEBUG MARKER: == sanityn test 81a: rename and stat under striped directory ========================================================== 12:00:39 (1761321639) [ 6771.568889] Lustre: DEBUG MARKER: == sanityn test 81b: rename under striped directory doesn't deadlock ========================================================== 12:00:42 (1761321642) [ 6809.106969] Lustre: DEBUG MARKER: == sanityn test 81c: rename revoke LOOKUP lock for remote object ========================================================== 12:01:20 (1761321680) [ 6809.785128] Lustre: DEBUG MARKER: SKIP: sanityn test_81c needs >= 4 MDTs [ 6810.550391] Lustre: DEBUG MARKER: == sanityn test 82: fsetxattr and fgetxattr on orphan files ========================================================== 12:01:21 (1761321681) [ 6813.397946] Lustre: DEBUG MARKER: == sanityn test 83: access striped directory while it is being created/unlinked ========================================================== 12:01:24 (1761321684) [ 6936.994535] Lustre: DEBUG MARKER: == sanityn test 84: 0-nlink race in lu_object_find() ===== 12:03:28 (1761321808) [ 6945.456221] Lustre: DEBUG MARKER: == sanityn test 90: open/create and unlink striped directory ========================================================== 12:03:36 (1761321816) [ 7129.581363] Lustre: DEBUG MARKER: == sanityn test 91: chmod and unlink striped directory === 12:06:40 (1761322000) [ 7313.249232] Lustre: DEBUG MARKER: == sanityn test 92: create remote directory under orphan directory ========================================================== 12:09:44 (1761322184) [ 7317.029474] Lustre: DEBUG MARKER: == sanityn test 93: alloc_rr should not allocate on same ost ========================================================== 12:09:48 (1761322188) [ 7328.203578] Lustre: DEBUG MARKER: == sanityn test 94: signal vs CP callback race =========== 12:09:59 (1761322199) [ 7328.361065] Lustre: DEBUG MARKER: write [ 7328.389377] LustreError: 20979:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 312 sleeping for 5000ms [ 7330.389634] Lustre: DEBUG MARKER: kill 328367 [ 7330.396660] LustreError: 328367:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 329 sleeping for 6000ms [ 7333.488498] LustreError: 20979:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 312 awake [ 7336.440588] LustreError: 328367:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 329 awake [ 7339.840570] Lustre: DEBUG MARKER: == sanityn test 95b: Check readpage() on a page that is no longer uptodate ========================================================== 12:10:11 (1761322211) [ 7340.028576] LustreError: 328966:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 1424 sleeping for 5000ms [ 7342.112124] LustreError: 328966:0:(fail.c:144:__cfs_fail_timeout_set()) cfs_fail_timeout interrupted [ 7348.808866] Lustre: DEBUG MARKER: == sanityn test 100a: DoM: glimpse RPCs for stat without IO lock (DoM only file) ========================================================== 12:10:20 (1761322220) [ 7349.779459] Lustre: DEBUG MARKER: SKIP: sanityn test_100a Reserved for glimpse-ahead [ 7350.620235] Lustre: DEBUG MARKER: == sanityn test 100b: DoM: no glimpse RPC for stat with IO lock (DoM only file) ========================================================== 12:10:21 (1761322221) [ 7354.215352] Lustre: DEBUG MARKER: == sanityn test 100c: DoM: write vs stat without IO lock (combined file) ========================================================== 12:10:25 (1761322225) [ 7358.138323] Lustre: DEBUG MARKER: == sanityn test 100d: DoM: write+truncate vs stat without IO lock (combined file) ========================================================== 12:10:29 (1761322229) [ 7362.297493] Lustre: DEBUG MARKER: == sanityn test 100e: DoM: read on open and file size ==== 12:10:33 (1761322233) [ 7366.905665] Lustre: DEBUG MARKER: == sanityn test 101a: Discard DoM data on unlink ========= 12:10:38 (1761322238) [ 7370.933331] Lustre: DEBUG MARKER: == sanityn test 101b: Discard DoM data on rename ========= 12:10:42 (1761322242) [ 7374.047763] Lustre: DEBUG MARKER: == sanityn test 101c: Discard DoM data on close-unlink === 12:10:45 (1761322245) [ 7378.481759] Lustre: DEBUG MARKER: == sanityn test 102: Test open by handle of unlinked file ========================================================== 12:10:49 (1761322249) [ 7382.810729] Lustre: DEBUG MARKER: == sanityn test 103: Test size correctness with lockahead ========================================================== 12:10:54 (1761322254) [ 7383.665407] Lustre: *** cfs_fail_loc=415, val=0*** [ 7392.109064] Lustre: DEBUG MARKER: == sanityn test 104: Verify that MDS stores atime/mtime/ctime during close ========================================================== 12:11:03 (1761322263) [ 7414.214363] Lustre: DEBUG MARKER: == sanityn test 105: Glimpse and lock cancel race ======== 12:11:25 (1761322285) [ 7414.478384] LustreError: 282374:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 sleeping for 5000ms [ 7419.576124] LustreError: 282374:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 awake [ 7424.592182] LustreError: 282374:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 awake [ 7424.596815] LustreError: 282374:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 sleeping for 5000ms [ 7424.604713] LustreError: 282374:0:(fail.c:138:__cfs_fail_timeout_set()) Skipped 1 previous similar message [ 7434.704118] LustreError: 282374:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 awake [ 7434.709535] LustreError: 282374:0:(fail.c:149:__cfs_fail_timeout_set()) Skipped 1 previous similar message [ 7444.913073] LustreError: 5572:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 sleeping for 5000ms [ 7444.917105] LustreError: 5572:0:(fail.c:138:__cfs_fail_timeout_set()) Skipped 3 previous similar messages [ 7455.112154] LustreError: 5572:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 awake [ 7455.115614] LustreError: 5572:0:(fail.c:149:__cfs_fail_timeout_set()) Skipped 3 previous similar messages [ 7480.608498] LustreError: 20979:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 sleeping for 5000ms [ 7480.612037] LustreError: 20979:0:(fail.c:138:__cfs_fail_timeout_set()) Skipped 6 previous similar messages [ 7490.808301] LustreError: 282374:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 awake [ 7490.811463] LustreError: 282374:0:(fail.c:149:__cfs_fail_timeout_set()) Skipped 6 previous similar messages [ 7498.953644] Lustre: DEBUG MARKER: == sanityn test 106a: Verify the btime via statx() ======= 12:12:50 (1761322370) [ 7502.265439] Lustre: DEBUG MARKER: == sanityn test 106b: Glimpse RPCs test for statx ======== 12:12:53 (1761322373) [ 7505.588611] Lustre: DEBUG MARKER: == sanityn test 106c: Verify statx attributes mask ======= 12:12:57 (1761322377) [ 7508.525301] Lustre: DEBUG MARKER: == sanityn test 107a: Basic grouplock conflict =========== 12:12:59 (1761322379) [ 7513.582661] Lustre: DEBUG MARKER: == sanityn test 107b: Grouplock is added to the head of waiting list ========================================================== 12:13:05 (1761322385) [ 7522.919519] Lustre: DEBUG MARKER: == sanityn test 108a: lseek: parallel updates ============ 12:13:14 (1761322394) [ 7530.566883] Lustre: DEBUG MARKER: == sanityn test 109: Race with several mount instances on 1 node ========================================================== 12:13:21 (1761322401) [ 7531.771267] Lustre: Unmounted lustre-client [ 7532.838706] Lustre: Unmounted lustre-client [ 7533.515467] Lustre: DEBUG MARKER: Iteration 1 [ 7533.735295] LustreError: 340473:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7533.735603] LustreError: 340474:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7533.741442] LustreError: 340473:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 7533.839916] Lustre: Mounted lustre-client [ 7534.593930] Lustre: Unmounted lustre-client [ 7535.895748] Key type lgssc unregistered [ 7536.033137] LNet: 340821:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7537.056925] LNet: Removed LNI 192.168.203.42@tcp [ 7537.403268] Key type .llcrypt unregistered [ 7537.405566] Key type ._llcrypt unregistered [ 7537.738279] alg: No test for adler32 (adler32-zlib) [ 7538.505351] Key type ._llcrypt registered [ 7538.507060] Key type .llcrypt registered [ 7538.677469] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7538.948635] Lustre: Lustre: Build Version: 2.15.7_10_g040485f [ 7539.256585] LNet: Added LNI 192.168.203.42@tcp [8/256/0/180] [ 7539.258573] LNet: Accept secure, port 988 [ 7540.880407] Key type lgssc registered [ 7541.611955] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7547.040944] Lustre: DEBUG MARKER: Iteration 2 [ 7547.168994] LustreError: 341614:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7547.169457] LustreError: 341615:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7547.181193] LustreError: 341614:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4994 [ 7548.280502] Lustre: Mounted lustre-client [ 7548.283128] Lustre: Skipped 1 previous similar message [ 7548.969561] Lustre: Unmounted lustre-client [ 7550.203214] Key type lgssc unregistered [ 7550.318340] LNet: 341965:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7551.340189] LNet: Removed LNI 192.168.203.42@tcp [ 7551.672732] Key type .llcrypt unregistered [ 7551.674106] Key type ._llcrypt unregistered [ 7552.057539] alg: No test for adler32 (adler32-zlib) [ 7552.809352] Key type ._llcrypt registered [ 7552.811176] Key type .llcrypt registered [ 7552.911084] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7553.060844] Lustre: Lustre: Build Version: 2.15.7_10_g040485f [ 7553.170601] LNet: Added LNI 192.168.203.42@tcp [8/256/0/180] [ 7553.173456] LNet: Accept secure, port 988 [ 7554.792401] Key type lgssc registered [ 7555.317454] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7560.678548] Lustre: DEBUG MARKER: Iteration 3 [ 7560.804980] LustreError: 342758:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7560.805199] LustreError: 342759:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7560.812860] LustreError: 342758:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 7561.914935] Lustre: Mounted lustre-client [ 7562.698081] Lustre: Unmounted lustre-client [ 7564.054439] Key type lgssc unregistered [ 7564.204036] LNet: 343113:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7565.219509] LNet: Removed LNI 192.168.203.42@tcp [ 7565.551335] Key type .llcrypt unregistered [ 7565.552912] Key type ._llcrypt unregistered [ 7565.913669] alg: No test for adler32 (adler32-zlib) [ 7566.672404] Key type ._llcrypt registered [ 7566.673578] Key type .llcrypt registered [ 7566.792513] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7566.970932] Lustre: Lustre: Build Version: 2.15.7_10_g040485f [ 7567.086907] LNet: Added LNI 192.168.203.42@tcp [8/256/0/180] [ 7567.089764] LNet: Accept secure, port 988 [ 7568.704293] Key type lgssc registered [ 7569.339116] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7575.029944] Lustre: DEBUG MARKER: Iteration 4 [ 7575.163886] LustreError: 343905:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7575.166223] LustreError: 343906:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7575.175897] LustreError: 343905:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [ 7576.283950] Lustre: Mounted lustre-client [ 7577.053669] Lustre: Unmounted lustre-client [ 7578.569655] Key type lgssc unregistered [ 7578.721274] LNet: 344256:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7579.746717] LNet: Removed LNI 192.168.203.42@tcp [ 7580.092513] Key type .llcrypt unregistered [ 7580.095065] Key type ._llcrypt unregistered [ 7580.548718] alg: No test for adler32 (adler32-zlib) [ 7581.300502] Key type ._llcrypt registered [ 7581.302033] Key type .llcrypt registered [ 7581.421113] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7581.597175] Lustre: Lustre: Build Version: 2.15.7_10_g040485f [ 7581.721767] LNet: Added LNI 192.168.203.42@tcp [8/256/0/180] [ 7581.725328] LNet: Accept secure, port 988 [ 7583.344700] Key type lgssc registered [ 7584.030383] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7589.706859] Lustre: DEBUG MARKER: Iteration 5 [ 7589.861925] LustreError: 345049:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7589.863528] LustreError: 345050:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7589.869806] LustreError: 345049:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 7590.970292] Lustre: Mounted lustre-client [ 7591.691804] Lustre: Unmounted lustre-client [ 7593.004035] Key type lgssc unregistered [ 7593.127088] LNet: 345397:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7594.145833] LNet: Removed LNI 192.168.203.42@tcp [ 7594.563828] Key type .llcrypt unregistered [ 7594.565510] Key type ._llcrypt unregistered [ 7594.931188] alg: No test for adler32 (adler32-zlib) [ 7595.680464] Key type ._llcrypt registered [ 7595.682089] Key type .llcrypt registered [ 7595.814720] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7596.043454] Lustre: Lustre: Build Version: 2.15.7_10_g040485f [ 7596.162367] LNet: Added LNI 192.168.203.42@tcp [8/256/0/180] [ 7596.167362] LNet: Accept secure, port 988 [ 7597.792116] Key type lgssc registered [ 7598.395355] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7604.113098] Lustre: DEBUG MARKER: Iteration 6 [ 7604.278191] LustreError: 346191:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7604.280516] LustreError: 346190:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7604.288475] LustreError: 346191:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4994 [ 7605.452057] Lustre: Mounted lustre-client [ 7605.453751] Lustre: Skipped 1 previous similar message [ 7606.297827] Lustre: Unmounted lustre-client [ 7607.758775] Key type lgssc unregistered [ 7607.902472] LNet: 346537:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7608.929530] LNet: Removed LNI 192.168.203.42@tcp [ 7609.321835] Key type .llcrypt unregistered [ 7609.323826] Key type ._llcrypt unregistered [ 7609.824057] alg: No test for adler32 (adler32-zlib) [ 7610.576489] Key type ._llcrypt registered [ 7610.578446] Key type .llcrypt registered [ 7610.715911] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7610.934735] Lustre: Lustre: Build Version: 2.15.7_10_g040485f [ 7611.062781] LNet: Added LNI 192.168.203.42@tcp [8/256/0/180] [ 7611.065573] LNet: Accept secure, port 988 [ 7612.713097] Key type lgssc registered [ 7613.344651] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7618.955814] Lustre: DEBUG MARKER: Iteration 7 [ 7619.121369] LustreError: 347329:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7619.121435] LustreError: 347330:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7619.134208] LustreError: 347329:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4994 [ 7620.241619] Lustre: Mounted lustre-client [ 7620.244471] Lustre: Skipped 1 previous similar message [ 7621.045795] Lustre: Unmounted lustre-client [ 7622.362158] Key type lgssc unregistered [ 7622.485589] LNet: 347677:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7623.521073] LNet: Removed LNI 192.168.203.42@tcp [ 7623.837621] Key type .llcrypt unregistered [ 7623.839527] Key type ._llcrypt unregistered [ 7624.282526] alg: No test for adler32 (adler32-zlib) [ 7625.034371] Key type ._llcrypt registered [ 7625.035885] Key type .llcrypt registered [ 7625.130682] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7625.268527] Lustre: Lustre: Build Version: 2.15.7_10_g040485f [ 7625.376254] LNet: Added LNI 192.168.203.42@tcp [8/256/0/180] [ 7625.379219] LNet: Accept secure, port 988 [ 7626.976135] Key type lgssc registered [ 7627.496277] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7632.246719] Lustre: DEBUG MARKER: Iteration 8 [ 7632.393282] LustreError: 348473:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7632.393686] LustreError: 348472:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7632.400949] LustreError: 348473:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 7633.477991] Lustre: Mounted lustre-client [ 7633.479710] Lustre: Skipped 1 previous similar message [ 7634.189109] Lustre: Unmounted lustre-client [ 7635.493795] Key type lgssc unregistered [ 7635.612406] LNet: 348823:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7636.642043] LNet: Removed LNI 192.168.203.42@tcp [ 7636.951528] Key type .llcrypt unregistered [ 7636.953495] Key type ._llcrypt unregistered [ 7637.315668] alg: No test for adler32 (adler32-zlib) [ 7638.067468] Key type ._llcrypt registered [ 7638.069236] Key type .llcrypt registered [ 7638.176912] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7638.331368] Lustre: Lustre: Build Version: 2.15.7_10_g040485f [ 7638.452281] LNet: Added LNI 192.168.203.42@tcp [8/256/0/180] [ 7638.454660] LNet: Accept secure, port 988 [ 7640.064171] Key type lgssc registered [ 7640.625685] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7645.698602] Lustre: DEBUG MARKER: Iteration 9 [ 7645.814776] LustreError: 349616:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7645.816081] LustreError: 349617:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7645.824451] LustreError: 349616:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4994 [ 7646.926098] Lustre: Mounted lustre-client [ 7647.604817] Lustre: Unmounted lustre-client [ 7648.782986] Key type lgssc unregistered [ 7648.901590] LNet: 349965:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7649.953205] LNet: Removed LNI 192.168.203.42@tcp [ 7650.241328] Key type .llcrypt unregistered [ 7650.243218] Key type ._llcrypt unregistered [ 7650.571080] alg: No test for adler32 (adler32-zlib) [ 7651.323456] Key type ._llcrypt registered [ 7651.325039] Key type .llcrypt registered [ 7651.437273] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7651.578164] Lustre: Lustre: Build Version: 2.15.7_10_g040485f [ 7651.682234] LNet: Added LNI 192.168.203.42@tcp [8/256/0/180] [ 7651.685520] LNet: Accept secure, port 988 [ 7653.288132] Key type lgssc registered [ 7653.827456] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7658.857738] Lustre: DEBUG MARKER: Iteration 10 [ 7659.016240] LustreError: 350757:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7659.016319] LustreError: 350758:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7659.027650] LustreError: 350757:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 7660.136913] Lustre: Mounted lustre-client [ 7660.901498] Lustre: Unmounted lustre-client [ 7662.306225] Key type lgssc unregistered [ 7662.434899] LNet: 351104:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7663.457366] LNet: Removed LNI 192.168.203.42@tcp [ 7663.778830] Key type .llcrypt unregistered [ 7663.780145] Key type ._llcrypt unregistered [ 7664.185109] alg: No test for adler32 (adler32-zlib) [ 7664.939409] Key type ._llcrypt registered [ 7664.941322] Key type .llcrypt registered [ 7665.049141] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7665.187903] Lustre: Lustre: Build Version: 2.15.7_10_g040485f [ 7665.292337] LNet: Added LNI 192.168.203.42@tcp [8/256/0/180] [ 7665.293950] LNet: Accept secure, port 988 [ 7666.896362] Key type lgssc registered [ 7667.429395] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7672.397504] Lustre: DEBUG MARKER: Iteration 11 [ 7672.527942] LustreError: 351898:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7672.528583] LustreError: 351899:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7672.537625] LustreError: 351898:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4994 [ 7673.632652] Lustre: Mounted lustre-client [ 7674.381300] Lustre: Unmounted lustre-client [ 7675.703390] Key type lgssc unregistered [ 7675.825294] LNet: 352248:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7676.833991] LNet: Removed LNI 192.168.203.42@tcp [ 7677.141774] Key type .llcrypt unregistered [ 7677.143633] Key type ._llcrypt unregistered [ 7677.481548] alg: No test for adler32 (adler32-zlib) [ 7678.233376] Key type ._llcrypt registered [ 7678.234926] Key type .llcrypt registered [ 7678.336533] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7678.494862] Lustre: Lustre: Build Version: 2.15.7_10_g040485f [ 7678.594104] LNet: Added LNI 192.168.203.42@tcp [8/256/0/180] [ 7678.597464] LNet: Accept secure, port 988 [ 7680.200145] Key type lgssc registered [ 7680.773526] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7685.672621] Lustre: DEBUG MARKER: Iteration 12 [ 7685.803713] LustreError: 353039:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7685.803737] LustreError: 353042:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7685.811565] LustreError: 353039:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 7686.912647] Lustre: Mounted lustre-client [ 7687.597624] Lustre: Unmounted lustre-client [ 7688.858522] Key type lgssc unregistered [ 7688.982769] LNet: 353390:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7690.018822] LNet: Removed LNI 192.168.203.42@tcp [ 7690.441458] Key type .llcrypt unregistered [ 7690.443054] Key type ._llcrypt unregistered [ 7690.824350] alg: No test for adler32 (adler32-zlib) [ 7691.577391] Key type ._llcrypt registered [ 7691.580366] Key type .llcrypt registered [ 7691.694970] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7691.873967] Lustre: Lustre: Build Version: 2.15.7_10_g040485f [ 7691.986233] LNet: Added LNI 192.168.203.42@tcp [8/256/0/180] [ 7691.989042] LNet: Accept secure, port 988 [ 7693.600127] Key type lgssc registered [ 7694.139941] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7699.636188] Lustre: DEBUG MARKER: Iteration 13 [ 7699.802848] LustreError: 354182:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7699.809795] LustreError: 354186:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7699.814782] LustreError: 354182:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4992 [ 7700.924764] Lustre: Mounted lustre-client [ 7701.719495] Lustre: Unmounted lustre-client [ 7702.998455] Key type lgssc unregistered [ 7703.153525] LNet: 354532:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7704.225832] LNet: Removed LNI 192.168.203.42@tcp [ 7704.562522] Key type .llcrypt unregistered [ 7704.564100] Key type ._llcrypt unregistered [ 7704.922841] alg: No test for adler32 (adler32-zlib) [ 7705.676315] Key type ._llcrypt registered [ 7705.680405] Key type .llcrypt registered [ 7705.838646] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7706.008200] Lustre: Lustre: Build Version: 2.15.7_10_g040485f [ 7706.129813] LNet: Added LNI 192.168.203.42@tcp [8/256/0/180] [ 7706.132463] LNet: Accept secure, port 988 [ 7707.744319] Key type lgssc registered [ 7708.266251] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7713.145326] Lustre: DEBUG MARKER: Iteration 14 [ 7713.276525] LustreError: 355324:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7713.276565] LustreError: 355326:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7713.283215] LustreError: 355324:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 7714.373392] Lustre: Mounted lustre-client [ 7715.195070] Lustre: Unmounted lustre-client [ 7716.497522] Key type lgssc unregistered [ 7716.615817] LNet: 355678:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7717.665306] LNet: Removed LNI 192.168.203.42@tcp [ 7717.963637] Key type .llcrypt unregistered [ 7717.965291] Key type ._llcrypt unregistered [ 7718.434251] alg: No test for adler32 (adler32-zlib) [ 7719.201359] Key type ._llcrypt registered [ 7719.202986] Key type .llcrypt registered [ 7719.298677] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7719.443975] Lustre: Lustre: Build Version: 2.15.7_10_g040485f [ 7719.542614] LNet: Added LNI 192.168.203.42@tcp [8/256/0/180] [ 7719.545514] LNet: Accept secure, port 988 [ 7721.152132] Key type lgssc registered [ 7721.696410] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7726.858865] Lustre: DEBUG MARKER: Iteration 15 [ 7727.012995] LustreError: 356472:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7727.013057] LustreError: 356471:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7727.020597] LustreError: 356472:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 7728.105849] Lustre: Mounted lustre-client [ 7728.872596] Lustre: Unmounted lustre-client [ 7730.129391] Key type lgssc unregistered [ 7730.251771] LNet: 356820:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7731.299558] LNet: Removed LNI 192.168.203.42@tcp [ 7731.606668] Key type .llcrypt unregistered [ 7731.608275] Key type ._llcrypt unregistered [ 7731.957509] alg: No test for adler32 (adler32-zlib) [ 7732.722347] Key type ._llcrypt registered [ 7732.723696] Key type .llcrypt registered [ 7732.815067] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7732.956257] Lustre: Lustre: Build Version: 2.15.7_10_g040485f [ 7733.057301] LNet: Added LNI 192.168.203.42@tcp [8/256/0/180] [ 7733.059591] LNet: Accept secure, port 988 [ 7734.656261] Key type lgssc registered [ 7735.138383] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7739.548742] Lustre: DEBUG MARKER: Iteration 16 [ 7739.685409] LustreError: 357612:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7739.686601] LustreError: 357613:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7739.694646] LustreError: 357612:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [ 7740.771986] Lustre: Mounted lustre-client [ 7741.539205] Lustre: Unmounted lustre-client [ 7742.788977] Key type lgssc unregistered [ 7742.926595] LNet: 357961:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7743.968927] LNet: Removed LNI 192.168.203.42@tcp [ 7744.274842] Key type .llcrypt unregistered [ 7744.276105] Key type ._llcrypt unregistered [ 7744.718457] alg: No test for adler32 (adler32-zlib) [ 7745.470441] Key type ._llcrypt registered [ 7745.472456] Key type .llcrypt registered [ 7745.570157] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7745.715027] Lustre: Lustre: Build Version: 2.15.7_10_g040485f [ 7745.825827] LNet: Added LNI 192.168.203.42@tcp [8/256/0/180] [ 7745.827888] LNet: Accept secure, port 988 [ 7747.440123] Key type lgssc registered [ 7747.936352] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7752.487228] Lustre: DEBUG MARKER: Iteration 17 [ 7752.618490] LustreError: 358755:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7752.620214] LustreError: 358756:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7752.625536] LustreError: 358755:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 7753.699526] Lustre: Mounted lustre-client [ 7753.701952] Lustre: Skipped 1 previous similar message [ 7754.472800] Lustre: Unmounted lustre-client [ 7755.593365] Key type lgssc unregistered [ 7755.707588] LNet: 359103:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7756.769237] LNet: Removed LNI 192.168.203.42@tcp [ 7757.046603] Key type .llcrypt unregistered [ 7757.048047] Key type ._llcrypt unregistered [ 7757.469458] alg: No test for adler32 (adler32-zlib) [ 7758.221399] Key type ._llcrypt registered [ 7758.222798] Key type .llcrypt registered [ 7758.326291] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7758.469907] Lustre: Lustre: Build Version: 2.15.7_10_g040485f [ 7758.582220] LNet: Added LNI 192.168.203.42@tcp [8/256/0/180] [ 7758.585728] LNet: Accept secure, port 988 [ 7760.184196] Key type lgssc registered [ 7760.694022] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7765.213641] Lustre: DEBUG MARKER: Iteration 18 [ 7765.343793] LustreError: 359896:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7765.344523] LustreError: 359897:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7765.354198] LustreError: 359896:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4994 [ 7766.466558] Lustre: Mounted lustre-client [ 7767.304481] Lustre: Unmounted lustre-client [ 7768.666361] Key type lgssc unregistered [ 7768.809734] LNet: 360247:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7769.825791] LNet: Removed LNI 192.168.203.42@tcp [ 7770.137869] Key type .llcrypt unregistered [ 7770.139609] Key type ._llcrypt unregistered [ 7770.481048] alg: No test for adler32 (adler32-zlib) [ 7771.233438] Key type ._llcrypt registered [ 7771.234603] Key type .llcrypt registered [ 7771.340187] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7771.487967] Lustre: Lustre: Build Version: 2.15.7_10_g040485f [ 7771.614792] LNet: Added LNI 192.168.203.42@tcp [8/256/0/180] [ 7771.617742] LNet: Accept secure, port 988 [ 7773.216123] Key type lgssc registered [ 7773.762097] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7778.489434] Lustre: DEBUG MARKER: Iteration 19 [ 7778.619614] LustreError: 361041:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7778.619656] LustreError: 361042:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7778.627190] LustreError: 361041:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 7779.726528] Lustre: Mounted lustre-client [ 7780.501233] Lustre: Unmounted lustre-client [ 7781.663670] Key type lgssc unregistered [ 7781.787105] LNet: 361392:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7782.816846] LNet: Removed LNI 192.168.203.42@tcp [ 7783.143398] Key type .llcrypt unregistered [ 7783.145231] Key type ._llcrypt unregistered [ 7783.566635] alg: No test for adler32 (adler32-zlib) [ 7784.318373] Key type ._llcrypt registered [ 7784.320256] Key type .llcrypt registered [ 7784.419418] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7784.559574] Lustre: Lustre: Build Version: 2.15.7_10_g040485f [ 7784.671734] LNet: Added LNI 192.168.203.42@tcp [8/256/0/180] [ 7784.674478] LNet: Accept secure, port 988 [ 7786.296158] Key type lgssc registered [ 7786.821325] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7791.430953] Lustre: DEBUG MARKER: Iteration 20 [ 7791.646837] LustreError: 362185:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7791.646863] LustreError: 362186:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7791.653976] LustreError: 362185:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 7792.746905] Lustre: Mounted lustre-client [ 7792.748462] Lustre: Skipped 1 previous similar message [ 7793.436257] Lustre: Unmounted lustre-client [ 7794.706941] Key type lgssc unregistered [ 7794.850364] LNet: 362537:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7795.873642] LNet: Removed LNI 192.168.203.42@tcp [ 7796.173782] Key type .llcrypt unregistered [ 7796.175370] Key type ._llcrypt unregistered [ 7796.576276] alg: No test for adler32 (adler32-zlib) [ 7797.332353] Key type ._llcrypt registered [ 7797.334053] Key type .llcrypt registered [ 7797.425839] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7797.568855] Lustre: Lustre: Build Version: 2.15.7_10_g040485f [ 7797.669459] LNet: Added LNI 192.168.203.42@tcp [8/256/0/180] [ 7797.672180] LNet: Accept secure, port 988 [ 7799.280124] Key type lgssc registered [ 7799.787250] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7804.447680] Lustre: DEBUG MARKER: Iteration 21 [ 7804.589286] LustreError: 363330:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7804.591460] LustreError: 363331:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7804.596952] LustreError: 363330:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 7805.689643] Lustre: Mounted lustre-client [ 7806.355056] Lustre: Unmounted lustre-client [ 7807.647524] Key type lgssc unregistered [ 7807.784497] LNet: 363678:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7808.801249] LNet: Removed LNI 192.168.203.42@tcp [ 7809.138294] Key type .llcrypt unregistered [ 7809.140100] Key type ._llcrypt unregistered [ 7809.581363] alg: No test for adler32 (adler32-zlib) [ 7810.333395] Key type ._llcrypt registered [ 7810.335048] Key type .llcrypt registered [ 7810.438669] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7810.581615] Lustre: Lustre: Build Version: 2.15.7_10_g040485f [ 7810.688517] LNet: Added LNI 192.168.203.42@tcp [8/256/0/180] [ 7810.692181] LNet: Accept secure, port 988 [ 7812.304491] Key type lgssc registered [ 7812.859092] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7817.705651] Lustre: DEBUG MARKER: Iteration 22 [ 7817.833530] LustreError: 364471:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7817.833675] LustreError: 364472:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7817.841980] LustreError: 364471:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 7818.927313] Lustre: Mounted lustre-client [ 7819.602579] Lustre: Unmounted lustre-client [ 7820.805704] Key type lgssc unregistered [ 7820.920414] LNet: 364818:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7821.985542] LNet: Removed LNI 192.168.203.42@tcp [ 7822.262820] Key type .llcrypt unregistered [ 7822.264191] Key type ._llcrypt unregistered [ 7822.554088] alg: No test for adler32 (adler32-zlib) [ 7823.317459] Key type ._llcrypt registered [ 7823.318722] Key type .llcrypt registered [ 7823.399767] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7823.533404] Lustre: Lustre: Build Version: 2.15.7_10_g040485f [ 7823.625564] LNet: Added LNI 192.168.203.42@tcp [8/256/0/180] [ 7823.627502] LNet: Accept secure, port 988 [ 7825.216119] Key type lgssc registered [ 7825.716434] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7830.197938] Lustre: DEBUG MARKER: Iteration 23 [ 7830.345808] LustreError: 365610:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7830.345900] LustreError: 365611:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7830.353746] LustreError: 365610:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 7831.423895] Lustre: Mounted lustre-client [ 7832.188024] Lustre: Unmounted lustre-client [ 7833.462964] Key type lgssc unregistered [ 7833.586685] LNet: 365957:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7834.593115] LNet: Removed LNI 192.168.203.42@tcp [ 7834.875683] Key type .llcrypt unregistered [ 7834.877926] Key type ._llcrypt unregistered [ 7835.221309] alg: No test for adler32 (adler32-zlib) [ 7835.978446] Key type ._llcrypt registered [ 7835.980495] Key type .llcrypt registered [ 7836.087227] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7836.233310] Lustre: Lustre: Build Version: 2.15.7_10_g040485f [ 7836.337274] LNet: Added LNI 192.168.203.42@tcp [8/256/0/180] [ 7836.339884] LNet: Accept secure, port 988 [ 7837.952161] Key type lgssc registered [ 7838.496666] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7843.126947] Lustre: DEBUG MARKER: Iteration 24 [ 7843.247150] LustreError: 366750:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7843.248891] LustreError: 366751:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7843.253891] LustreError: 366750:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 7844.344660] Lustre: Mounted lustre-client [ 7844.346522] Lustre: Skipped 1 previous similar message [ 7845.080934] Lustre: Unmounted lustre-client [ 7846.295102] Key type lgssc unregistered [ 7846.413066] LNet: 367101:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7847.456809] LNet: Removed LNI 192.168.203.42@tcp [ 7847.734634] Key type .llcrypt unregistered [ 7847.736313] Key type ._llcrypt unregistered [ 7848.064941] alg: No test for adler32 (adler32-zlib) [ 7848.817442] Key type ._llcrypt registered [ 7848.818817] Key type .llcrypt registered [ 7848.907983] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7849.041303] Lustre: Lustre: Build Version: 2.15.7_10_g040485f [ 7849.136795] LNet: Added LNI 192.168.203.42@tcp [8/256/0/180] [ 7849.140130] LNet: Accept secure, port 988 [ 7850.736161] Key type lgssc registered [ 7851.206204] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7855.468343] Lustre: DEBUG MARKER: Iteration 25 [ 7855.598225] LustreError: 367903:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7855.598588] LustreError: 367904:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7855.604979] LustreError: 367903:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 7856.680420] Lustre: Mounted lustre-client [ 7857.472195] Lustre: Unmounted lustre-client [ 7858.786442] Key type lgssc unregistered [ 7858.922488] LNet: 368251:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7859.937361] LNet: Removed LNI 192.168.203.42@tcp [ 7860.234659] Key type .llcrypt unregistered [ 7860.236093] Key type ._llcrypt unregistered [ 7860.605685] alg: No test for adler32 (adler32-zlib) [ 7861.358363] Key type ._llcrypt registered [ 7861.360047] Key type .llcrypt registered [ 7861.451540] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7861.601693] Lustre: Lustre: Build Version: 2.15.7_10_g040485f [ 7861.704050] LNet: Added LNI 192.168.203.42@tcp [8/256/0/180] [ 7861.706760] LNet: Accept secure, port 988 [ 7863.312253] Key type lgssc registered [ 7863.803984] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7868.196395] Lustre: DEBUG MARKER: Iteration 26 [ 7868.317381] LustreError: 369043:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7868.317409] LustreError: 369044:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7868.327990] LustreError: 369043:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 7869.417372] Lustre: Mounted lustre-client [ 7870.143431] Lustre: Unmounted lustre-client [ 7871.190457] Key type lgssc unregistered [ 7871.297315] LNet: 369393:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7872.352933] LNet: Removed LNI 192.168.203.42@tcp [ 7872.626698] Key type .llcrypt unregistered [ 7872.628060] Key type ._llcrypt unregistered [ 7872.982027] alg: No test for adler32 (adler32-zlib) [ 7873.733358] Key type ._llcrypt registered [ 7873.735036] Key type .llcrypt registered [ 7873.822310] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7873.961118] Lustre: Lustre: Build Version: 2.15.7_10_g040485f [ 7874.052270] LNet: Added LNI 192.168.203.42@tcp [8/256/0/180] [ 7874.054555] LNet: Accept secure, port 988 [ 7875.648165] Key type lgssc registered [ 7876.099265] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7880.383277] Lustre: DEBUG MARKER: Iteration 27 [ 7880.506373] LustreError: 370186:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7880.506489] LustreError: 370187:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7880.516856] LustreError: 370186:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 7881.587214] Lustre: Mounted lustre-client [ 7882.169685] Lustre: Unmounted lustre-client [ 7883.298995] Key type lgssc unregistered [ 7883.405537] LNet: 370534:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7884.448950] LNet: Removed LNI 192.168.203.42@tcp [ 7884.720900] Key type .llcrypt unregistered [ 7884.722336] Key type ._llcrypt unregistered [ 7885.029757] alg: No test for adler32 (adler32-zlib) [ 7885.781528] Key type ._llcrypt registered [ 7885.783015] Key type .llcrypt registered [ 7885.883454] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7886.019532] Lustre: Lustre: Build Version: 2.15.7_10_g040485f [ 7886.106693] LNet: Added LNI 192.168.203.42@tcp [8/256/0/180] [ 7886.108148] LNet: Accept secure, port 988 [ 7887.696119] Key type lgssc registered [ 7888.150665] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7892.617590] Lustre: DEBUG MARKER: Iteration 28 [ 7892.802803] LustreError: 371328:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7892.803219] LustreError: 371327:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7892.813740] LustreError: 371328:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4994 [ 7893.920716] Lustre: Mounted lustre-client [ 7893.922194] Lustre: Skipped 1 previous similar message [ 7894.671154] Lustre: Unmounted lustre-client [ 7895.858458] Key type lgssc unregistered [ 7895.976396] LNet: 371672:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7896.993608] LNet: Removed LNI 192.168.203.42@tcp [ 7897.262605] Key type .llcrypt unregistered [ 7897.264193] Key type ._llcrypt unregistered [ 7897.678119] alg: No test for adler32 (adler32-zlib) [ 7898.433379] Key type ._llcrypt registered [ 7898.435083] Key type .llcrypt registered [ 7898.518651] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7898.642988] Lustre: Lustre: Build Version: 2.15.7_10_g040485f [ 7898.736116] LNet: Added LNI 192.168.203.42@tcp [8/256/0/180] [ 7898.738647] LNet: Accept secure, port 988 [ 7900.344131] Key type lgssc registered [ 7900.826445] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7905.294607] Lustre: DEBUG MARKER: Iteration 29 [ 7905.446656] LustreError: 372467:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7905.450255] LustreError: 372466:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7905.455341] LustreError: 372467:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 7906.550857] Lustre: Mounted lustre-client [ 7906.552442] Lustre: Skipped 1 previous similar message [ 7907.209983] Lustre: Unmounted lustre-client [ 7908.362755] Key type lgssc unregistered [ 7908.479504] LNet: 372818:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7909.536711] LNet: Removed LNI 192.168.203.42@tcp [ 7909.814405] Key type .llcrypt unregistered [ 7909.816131] Key type ._llcrypt unregistered [ 7910.180075] alg: No test for adler32 (adler32-zlib) [ 7910.932348] Key type ._llcrypt registered [ 7910.934071] Key type .llcrypt registered [ 7911.013546] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7911.127906] Lustre: Lustre: Build Version: 2.15.7_10_g040485f [ 7911.213685] LNet: Added LNI 192.168.203.42@tcp [8/256/0/180] [ 7911.215529] LNet: Accept secure, port 988 [ 7912.808179] Key type lgssc registered [ 7913.236819] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7917.584087] Lustre: DEBUG MARKER: Iteration 30 [ 7917.717324] LustreError: 373611:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7917.717506] LustreError: 373612:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7917.726581] LustreError: 373611:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 7918.796790] Lustre: Mounted lustre-client [ 7919.327368] Lustre: Unmounted lustre-client [ 7920.430290] Key type lgssc unregistered [ 7920.545733] LNet: 373960:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7921.569382] LNet: Removed LNI 192.168.203.42@tcp [ 7921.831609] Key type .llcrypt unregistered [ 7921.833052] Key type ._llcrypt unregistered [ 7922.130914] alg: No test for adler32 (adler32-zlib) [ 7922.882433] Key type ._llcrypt registered [ 7922.883750] Key type .llcrypt registered [ 7922.963827] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7923.083937] Lustre: Lustre: Build Version: 2.15.7_10_g040485f [ 7923.175476] LNet: Added LNI 192.168.203.42@tcp [8/256/0/180] [ 7923.178136] LNet: Accept secure, port 988 [ 7924.768115] Key type lgssc registered [ 7925.206849] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7929.712746] Lustre: DEBUG MARKER: Iteration 31 [ 7929.850201] LustreError: 374753:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7929.850776] LustreError: 374752:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7929.857832] LustreError: 374753:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 7930.948447] Lustre: Mounted lustre-client [ 7930.950343] Lustre: Skipped 1 previous similar message [ 7931.779941] Lustre: Unmounted lustre-client [ 7933.895853] Key type lgssc unregistered [ 7934.100278] LNet: 375101:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7935.140766] LNet: Removed LNI 192.168.203.42@tcp [ 7935.832329] Key type .llcrypt unregistered [ 7935.834951] Key type ._llcrypt unregistered [ 7936.436290] alg: No test for adler32 (adler32-zlib) [ 7937.212945] Key type ._llcrypt registered [ 7937.214875] Key type .llcrypt registered [ 7937.466830] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7937.821042] Lustre: Lustre: Build Version: 2.15.7_10_g040485f [ 7938.197197] LNet: Added LNI 192.168.203.42@tcp [8/256/0/180] [ 7938.205669] LNet: Accept secure, port 988 [ 7939.880625] Key type lgssc registered [ 7941.031923] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7958.451283] Lustre: DEBUG MARKER: Iteration 32 [ 7958.954017] LustreError: 375897:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7958.982417] LustreError: 375902:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7958.995755] LustreError: 375897:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4984 [ 7960.224730] Lustre: Mounted lustre-client [ 7962.103300] Lustre: Unmounted lustre-client [ 7966.358453] Key type lgssc unregistered [ 7966.683087] LNet: 376252:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7967.714524] LNet: Removed LNI 192.168.203.42@tcp [ 7968.430224] Key type .llcrypt unregistered [ 7968.440909] Key type ._llcrypt unregistered [ 7969.078975] alg: No test for adler32 (adler32-zlib) [ 7969.866802] Key type ._llcrypt registered [ 7969.869471] Key type .llcrypt registered [ 7970.082672] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7970.407189] Lustre: Lustre: Build Version: 2.15.7_10_g040485f [ 7970.597800] LNet: Added LNI 192.168.203.42@tcp [8/256/0/180] [ 7970.604417] LNet: Accept secure, port 988 [ 7972.288582] Key type lgssc registered [ 7973.562860] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7985.177726] Lustre: DEBUG MARKER: Iteration 33 [ 7985.637666] LustreError: 377046:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7985.639609] LustreError: 377052:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7985.661106] LustreError: 377046:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4976 [ 7986.932950] Lustre: Mounted lustre-client [ 7988.471511] Lustre: Unmounted lustre-client [ 7990.662890] Key type lgssc unregistered [ 7990.848935] LNet: 377397:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7991.907230] LNet: Removed LNI 192.168.203.42@tcp [ 7992.438842] Key type .llcrypt unregistered [ 7992.447314] Key type ._llcrypt unregistered [ 7993.041054] alg: No test for adler32 (adler32-zlib) [ 7993.799383] Key type ._llcrypt registered [ 7993.801402] Key type .llcrypt registered [ 7993.946722] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7994.160311] Lustre: Lustre: Build Version: 2.15.7_10_g040485f [ 7994.294166] LNet: Added LNI 192.168.203.42@tcp [8/256/0/180] [ 7994.298652] LNet: Accept secure, port 988 [ 7995.928615] Key type lgssc registered [ 7996.558732] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8004.793290] Lustre: DEBUG MARKER: Iteration 34 [ 8005.006114] LustreError: 378190:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 8005.006961] LustreError: 378192:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 8005.021965] LustreError: 378190:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4990 [ 8006.202726] Lustre: Mounted lustre-client [ 8007.096124] Lustre: Unmounted lustre-client [ 8008.655402] Key type lgssc unregistered [ 8008.881656] LNet: 378540:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8009.954071] LNet: Removed LNI 192.168.203.42@tcp [ 8010.337548] Key type .llcrypt unregistered [ 8010.340818] Key type ._llcrypt unregistered [ 8010.779163] alg: No test for adler32 (adler32-zlib) [ 8011.533385] Key type ._llcrypt registered [ 8011.535727] Key type .llcrypt registered [ 8011.669175] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8011.838349] Lustre: Lustre: Build Version: 2.15.7_10_g040485f [ 8011.946982] LNet: Added LNI 192.168.203.42@tcp [8/256/0/180] [ 8011.949514] LNet: Accept secure, port 988 [ 8013.560119] Key type lgssc registered [ 8014.282321] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8021.137172] Lustre: DEBUG MARKER: Iteration 35 [ 8021.260757] LustreError: 379333:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 8021.261027] LustreError: 379334:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 8021.278037] LustreError: 379333:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 8022.396957] Lustre: Mounted lustre-client [ 8023.373728] Lustre: Unmounted lustre-client [ 8025.015636] Key type lgssc unregistered [ 8025.166973] LNet: 379687:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8026.219827] LNet: Removed LNI 192.168.203.42@tcp [ 8026.688135] Key type .llcrypt unregistered [ 8026.689628] Key type ._llcrypt unregistered [ 8027.192413] alg: No test for adler32 (adler32-zlib) [ 8027.944422] Key type ._llcrypt registered [ 8027.947219] Key type .llcrypt registered [ 8028.074389] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8028.267778] Lustre: Lustre: Build Version: 2.15.7_10_g040485f [ 8028.424307] LNet: Added LNI 192.168.203.42@tcp [8/256/0/180] [ 8028.430720] LNet: Accept secure, port 988 [ 8030.056122] Key type lgssc registered [ 8030.858910] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8037.625997] Lustre: DEBUG MARKER: Iteration 36 [ 8037.907448] LustreError: 380481:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 8037.907467] LustreError: 380480:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 8037.916309] LustreError: 380481:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 8039.065610] Lustre: Mounted lustre-client [ 8039.930161] Lustre: Unmounted lustre-client [ 8041.756424] Key type lgssc unregistered [ 8041.932581] LNet: 380829:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8042.980873] LNet: Removed LNI 192.168.203.42@tcp [ 8043.448787] Key type .llcrypt unregistered [ 8043.450331] Key type ._llcrypt unregistered [ 8043.840097] alg: No test for adler32 (adler32-zlib) [ 8044.602442] Key type ._llcrypt registered [ 8044.603948] Key type .llcrypt registered [ 8044.752234] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8044.957456] Lustre: Lustre: Build Version: 2.15.7_10_g040485f [ 8045.099334] LNet: Added LNI 192.168.203.42@tcp [8/256/0/180] [ 8045.102369] LNet: Accept secure, port 988 [ 8046.720144] Key type lgssc registered [ 8047.414608] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8053.969315] Lustre: DEBUG MARKER: Iteration 37 [ 8054.272416] LustreError: 381623:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 8054.273356] LustreError: 381624:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 8054.290377] LustreError: 381623:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4993 [ 8055.444227] Lustre: Mounted lustre-client [ 8056.471463] Lustre: Unmounted lustre-client [ 8057.935057] Key type lgssc unregistered [ 8058.065401] LNet: 381975:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8059.106846] LNet: Removed LNI 192.168.203.42@tcp [ 8059.529717] Key type .llcrypt unregistered [ 8059.531886] Key type ._llcrypt unregistered [ 8060.107694] alg: No test for adler32 (adler32-zlib) [ 8060.860390] Key type ._llcrypt registered [ 8060.861677] Key type .llcrypt registered [ 8060.990321] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8061.196603] Lustre: Lustre: Build Version: 2.15.7_10_g040485f [ 8061.338511] LNet: Added LNI 192.168.203.42@tcp [8/256/0/180] [ 8061.341556] LNet: Accept secure, port 988 [ 8062.968132] Key type lgssc registered [ 8063.581306] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8069.977378] Lustre: DEBUG MARKER: Iteration 38 [ 8070.182327] LustreError: 382769:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 8070.182877] LustreError: 382770:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 8070.193764] LustreError: 382769:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4993 [ 8071.353768] Lustre: Mounted lustre-client [ 8071.357062] Lustre: Skipped 1 previous similar message [ 8072.188504] Lustre: Unmounted lustre-client [ 8073.791540] Key type lgssc unregistered [ 8073.990512] LNet: 383125:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8075.044128] LNet: Removed LNI 192.168.203.42@tcp [ 8075.468273] Key type .llcrypt unregistered [ 8075.469822] Key type ._llcrypt unregistered [ 8075.915154] alg: No test for adler32 (adler32-zlib) [ 8076.683325] Key type ._llcrypt registered [ 8076.684901] Key type .llcrypt registered [ 8076.786140] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8076.942399] Lustre: Lustre: Build Version: 2.15.7_10_g040485f [ 8077.077434] LNet: Added LNI 192.168.203.42@tcp [8/256/0/180] [ 8077.083784] LNet: Accept secure, port 988 [ 8078.696623] Key type lgssc registered [ 8079.353321] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8085.631561] Lustre: DEBUG MARKER: Iteration 39 [ 8085.869408] LustreError: 383919:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 8085.870797] LustreError: 383920:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 8085.878661] LustreError: 383919:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 8087.009286] Lustre: Mounted lustre-client [ 8087.891442] Lustre: Unmounted lustre-client [ 8089.339479] Key type lgssc unregistered [ 8089.506948] LNet: 384272:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8090.531763] LNet: Removed LNI 192.168.203.42@tcp [ 8090.940043] Key type .llcrypt unregistered [ 8090.941879] Key type ._llcrypt unregistered [ 8091.519362] alg: No test for adler32 (adler32-zlib) [ 8092.271298] Key type ._llcrypt registered [ 8092.272393] Key type .llcrypt registered [ 8092.387793] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8092.576270] Lustre: Lustre: Build Version: 2.15.7_10_g040485f [ 8092.693344] LNet: Added LNI 192.168.203.42@tcp [8/256/0/180] [ 8092.695554] LNet: Accept secure, port 988 [ 8094.320120] Key type lgssc registered [ 8095.022549] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8102.318204] Lustre: DEBUG MARKER: Iteration 40 [ 8102.609665] LustreError: 385061:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 8102.612717] LustreError: 385066:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 8102.617050] LustreError: 385061:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4992 [ 8103.802649] Lustre: Mounted lustre-client [ 8104.505746] Lustre: Unmounted lustre-client [ 8105.974882] Key type lgssc unregistered [ 8106.126266] LNet: 385414:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8107.169324] LNet: Removed LNI 192.168.203.42@tcp [ 8107.567735] Key type .llcrypt unregistered [ 8107.571068] Key type ._llcrypt unregistered [ 8107.903437] alg: No test for adler32 (adler32-zlib) [ 8108.655379] Key type ._llcrypt registered [ 8108.657175] Key type .llcrypt registered [ 8108.826442] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8109.040879] Lustre: Lustre: Build Version: 2.15.7_10_g040485f [ 8109.179828] LNet: Added LNI 192.168.203.42@tcp [8/256/0/180] [ 8109.182436] LNet: Accept secure, port 988 [ 8110.785902] Key type lgssc registered [ 8111.488370] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8119.410884] Lustre: DEBUG MARKER: Iteration 41 [ 8119.648476] LustreError: 386206:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 8119.651225] LustreError: 386207:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 8119.662440] LustreError: 386206:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4991 [ 8120.833486] Lustre: Mounted lustre-client [ 8121.806188] Lustre: Unmounted lustre-client [ 8123.491707] Key type lgssc unregistered [ 8123.649453] LNet: 386555:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8124.705761] LNet: Removed LNI 192.168.203.42@tcp [ 8125.135971] Key type .llcrypt unregistered [ 8125.138131] Key type ._llcrypt unregistered [ 8125.582376] alg: No test for adler32 (adler32-zlib) [ 8126.337353] Key type ._llcrypt registered [ 8126.339047] Key type .llcrypt registered [ 8126.455822] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8126.688407] Lustre: Lustre: Build Version: 2.15.7_10_g040485f [ 8126.884971] LNet: Added LNI 192.168.203.42@tcp [8/256/0/180] [ 8126.890237] LNet: Accept secure, port 988 [ 8128.520181] Key type lgssc registered [ 8129.202416] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8135.747876] Lustre: DEBUG MARKER: Iteration 42 [ 8135.963979] LustreError: 387349:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 8135.966655] LustreError: 387350:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 8135.974918] LustreError: 387349:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [ 8137.109827] Lustre: Mounted lustre-client [ 8138.026075] Lustre: Unmounted lustre-client [ 8139.274857] Key type lgssc unregistered [ 8139.387635] LNet: 387697:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8140.449828] LNet: Removed LNI 192.168.203.42@tcp [ 8140.771926] Key type .llcrypt unregistered [ 8140.773343] Key type ._llcrypt unregistered [ 8141.123795] alg: No test for adler32 (adler32-zlib) [ 8141.894379] Key type ._llcrypt registered [ 8141.897256] Key type .llcrypt registered [ 8142.007416] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8142.178578] Lustre: Lustre: Build Version: 2.15.7_10_g040485f [ 8142.316254] LNet: Added LNI 192.168.203.42@tcp [8/256/0/180] [ 8142.319656] LNet: Accept secure, port 988 [ 8143.960143] Key type lgssc registered [ 8144.572450] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8151.009402] Lustre: DEBUG MARKER: Iteration 43 [ 8151.140420] LustreError: 388490:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 8151.140546] LustreError: 388491:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 8151.149742] LustreError: 388490:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4994 [ 8152.276481] Lustre: Mounted lustre-client [ 8152.278115] Lustre: Skipped 1 previous similar message [ 8153.100351] Lustre: Unmounted lustre-client [ 8154.564989] Key type lgssc unregistered [ 8154.693582] LNet: 388840:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8155.745476] LNet: Removed LNI 192.168.203.42@tcp [ 8156.048378] Key type .llcrypt unregistered [ 8156.050561] Key type ._llcrypt unregistered [ 8156.459041] alg: No test for adler32 (adler32-zlib) [ 8157.211393] Key type ._llcrypt registered [ 8157.212902] Key type .llcrypt registered [ 8157.312125] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8157.461437] Lustre: Lustre: Build Version: 2.15.7_10_g040485f [ 8157.563993] LNet: Added LNI 192.168.203.42@tcp [8/256/0/180] [ 8157.566606] LNet: Accept secure, port 988 [ 8159.176118] Key type lgssc registered [ 8159.662092] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8164.295635] Lustre: DEBUG MARKER: Iteration 44 [ 8164.434423] LustreError: 389633:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 8164.435141] LustreError: 389634:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 8164.441649] LustreError: 389633:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 8165.536555] Lustre: Mounted lustre-client [ 8166.191397] Lustre: Unmounted lustre-client [ 8167.321940] Key type lgssc unregistered [ 8167.438473] LNet: 389981:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8168.483872] LNet: Removed LNI 192.168.203.42@tcp [ 8168.825407] Key type .llcrypt unregistered [ 8168.827570] Key type ._llcrypt unregistered [ 8169.199091] alg: No test for adler32 (adler32-zlib) [ 8169.951426] Key type ._llcrypt registered [ 8169.953068] Key type .llcrypt registered [ 8170.051818] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8170.195769] Lustre: Lustre: Build Version: 2.15.7_10_g040485f [ 8170.290825] LNet: Added LNI 192.168.203.42@tcp [8/256/0/180] [ 8170.293447] LNet: Accept secure, port 988 [ 8171.888400] Key type lgssc registered [ 8172.358439] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8176.893912] Lustre: DEBUG MARKER: Iteration 45 [ 8177.029659] LustreError: 390774:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 8177.029717] LustreError: 390775:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 8177.040717] LustreError: 390774:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4994 [ 8178.113558] Lustre: Mounted lustre-client [ 8178.813087] Lustre: Unmounted lustre-client [ 8179.995524] Key type lgssc unregistered [ 8180.110610] LNet: 391124:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8181.163158] LNet: Removed LNI 192.168.203.42@tcp [ 8181.517908] Key type .llcrypt unregistered [ 8181.519205] Key type ._llcrypt unregistered [ 8181.920578] alg: No test for adler32 (adler32-zlib) [ 8182.679395] Key type ._llcrypt registered [ 8182.680940] Key type .llcrypt registered [ 8182.768405] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8182.901472] Lustre: Lustre: Build Version: 2.15.7_10_g040485f [ 8183.001422] LNet: Added LNI 192.168.203.42@tcp [8/256/0/180] [ 8183.003876] LNet: Accept secure, port 988 [ 8184.600282] Key type lgssc registered [ 8185.115428] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8190.341826] Lustre: DEBUG MARKER: Iteration 46 [ 8190.557106] LustreError: 391917:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 8190.557141] LustreError: 391918:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 8190.566679] LustreError: 391917:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4992 [ 8191.710990] Lustre: Mounted lustre-client [ 8192.531481] Lustre: Unmounted lustre-client [ 8194.293178] Key type lgssc unregistered [ 8194.450269] LNet: 392268:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8195.490210] LNet: Removed LNI 192.168.203.42@tcp [ 8195.850421] Key type .llcrypt unregistered [ 8195.852093] Key type ._llcrypt unregistered [ 8196.355425] alg: No test for adler32 (adler32-zlib) [ 8197.107454] Key type ._llcrypt registered [ 8197.109123] Key type .llcrypt registered [ 8197.248402] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8197.460243] Lustre: Lustre: Build Version: 2.15.7_10_g040485f [ 8197.622954] LNet: Added LNI 192.168.203.42@tcp [8/256/0/180] [ 8197.625639] LNet: Accept secure, port 988 [ 8199.264718] Key type lgssc registered [ 8199.832900] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8205.663326] Lustre: DEBUG MARKER: Iteration 47 [ 8205.824718] LustreError: 393061:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 8205.824739] LustreError: 393062:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 8205.832210] LustreError: 393061:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 8206.927365] Lustre: Mounted lustre-client [ 8206.928872] Lustre: Skipped 1 previous similar message [ 8207.595771] Lustre: Unmounted lustre-client [ 8208.979363] Key type lgssc unregistered [ 8209.095501] LNet: 393411:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8210.145134] LNet: Removed LNI 192.168.203.42@tcp [ 8210.470601] Key type .llcrypt unregistered [ 8210.472327] Key type ._llcrypt unregistered [ 8210.863646] alg: No test for adler32 (adler32-zlib) [ 8211.615388] Key type ._llcrypt registered [ 8211.617683] Key type .llcrypt registered [ 8211.722166] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8211.888283] Lustre: Lustre: Build Version: 2.15.7_10_g040485f [ 8212.004616] LNet: Added LNI 192.168.203.42@tcp [8/256/0/180] [ 8212.007225] LNet: Accept secure, port 988 [ 8213.616230] Key type lgssc registered [ 8214.198158] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8219.574108] Lustre: DEBUG MARKER: Iteration 48 [ 8219.732434] LustreError: 394215:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 8219.732925] LustreError: 394218:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 8219.742632] LustreError: 394215:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4990 [ 8220.861507] Lustre: Mounted lustre-client [ 8221.638191] Lustre: Unmounted lustre-client [ 8222.788295] Key type lgssc unregistered [ 8222.923659] LNet: 394568:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8223.969708] LNet: Removed LNI 192.168.203.42@tcp [ 8224.263527] Key type .llcrypt unregistered [ 8224.265390] Key type ._llcrypt unregistered [ 8224.622173] alg: No test for adler32 (adler32-zlib) [ 8225.374399] Key type ._llcrypt registered [ 8225.376675] Key type .llcrypt registered [ 8225.491437] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8225.641368] Lustre: Lustre: Build Version: 2.15.7_10_g040485f [ 8225.744825] LNet: Added LNI 192.168.203.42@tcp [8/256/0/180] [ 8225.747588] LNet: Accept secure, port 988 [ 8227.352417] Key type lgssc registered [ 8227.844893] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8233.026375] Lustre: DEBUG MARKER: Iteration 49 [ 8233.166844] LustreError: 395360:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 8233.166893] LustreError: 395361:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 8233.176065] LustreError: 395360:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4994 [ 8234.273453] Lustre: Mounted lustre-client [ 8234.895704] Lustre: Unmounted lustre-client [ 8236.066934] Key type lgssc unregistered [ 8236.185907] LNet: 395710:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8237.216778] LNet: Removed LNI 192.168.203.42@tcp [ 8237.504531] Key type .llcrypt unregistered [ 8237.505918] Key type ._llcrypt unregistered [ 8237.817264] alg: No test for adler32 (adler32-zlib) [ 8238.569354] Key type ._llcrypt registered [ 8238.571108] Key type .llcrypt registered [ 8238.667041] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8238.808126] Lustre: Lustre: Build Version: 2.15.7_10_g040485f [ 8238.910645] LNet: Added LNI 192.168.203.42@tcp [8/256/0/180] [ 8238.915237] LNet: Accept secure, port 988 [ 8240.528688] Key type lgssc registered [ 8241.010925] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8246.444694] Lustre: DEBUG MARKER: Iteration 50 [ 8246.608077] LustreError: 396503:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 8246.608876] LustreError: 396504:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 8246.617971] LustreError: 396503:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4994 [ 8247.699330] Lustre: Mounted lustre-client [ 8248.301408] Lustre: Unmounted lustre-client [ 8249.438488] Key type lgssc unregistered [ 8249.556735] LNet: 396852:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8250.594292] LNet: Removed LNI 192.168.203.42@tcp [ 8250.916279] Key type .llcrypt unregistered [ 8250.918721] Key type ._llcrypt unregistered [ 8251.218222] alg: No test for adler32 (adler32-zlib) [ 8251.968384] Key type ._llcrypt registered [ 8251.969894] Key type .llcrypt registered [ 8252.068887] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8252.211042] Lustre: Lustre: Build Version: 2.15.7_10_g040485f [ 8252.332704] LNet: Added LNI 192.168.203.42@tcp [8/256/0/180] [ 8252.335365] LNet: Accept secure, port 988 [ 8253.960111] Key type lgssc registered [ 8254.546189] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8260.107256] Lustre: Mounted lustre-client [ 8263.233822] Lustre: DEBUG MARKER: == sanityn test 110: do not grant another lock on resend ========================================================== 12:25:34 (1761323134) [ 8270.816181] Lustre: 398159:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761323135/real 1761323135] req@00000000dfe3ba41 x1846881156076288/t0(0) o36->lustre-MDT0000-mdc-ffffa061607b9800@192.168.203.142@tcp:12/10 lens 496/440 e 0 to 1 dl 1761323142 ref 2 fl Rpc:XQr/0/ffffffff rc 0/-1 job:'ln.0' [ 8270.828279] Lustre: lustre-MDT0000-mdc-ffffa061607b9800: Connection to lustre-MDT0000 (at 192.168.203.142@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8270.842558] Lustre: lustre-MDT0000-mdc-ffffa061607b9800: Connection restored to (at 192.168.203.142@tcp) [ 8276.961580] Lustre: 398159:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761323142/real 1761323142] req@00000000dfe3ba41 x1846881156076288/t0(0) o36->lustre-MDT0000-mdc-ffffa061607b9800@192.168.203.142@tcp:12/10 lens 496/440 e 0 to 1 dl 1761323149 ref 2 fl Rpc:XQr/2/ffffffff rc 0/-1 job:'ln.0' [ 8276.974130] Lustre: lustre-MDT0000-mdc-ffffa061607b9800: Connection to lustre-MDT0000 (at 192.168.203.142@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8276.989195] Lustre: lustre-MDT0000-mdc-ffffa061607b9800: Connection restored to (at 192.168.203.142@tcp) [ 8284.128671] Lustre: 398159:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761323149/real 1761323149] req@00000000dfe3ba41 x1846881156076288/t0(0) o36->lustre-MDT0000-mdc-ffffa061607b9800@192.168.203.142@tcp:12/10 lens 496/440 e 0 to 1 dl 1761323156 ref 2 fl Rpc:XQr/2/ffffffff rc 0/-1 job:'ln.0' [ 8284.140079] Lustre: lustre-MDT0000-mdc-ffffa061607b9800: Connection to lustre-MDT0000 (at 192.168.203.142@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8284.153567] Lustre: lustre-MDT0000-mdc-ffffa061607b9800: Connection restored to (at 192.168.203.142@tcp) [ 8291.296129] Lustre: 398159:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761323156/real 1761323156] req@00000000dfe3ba41 x1846881156076288/t0(0) o36->lustre-MDT0000-mdc-ffffa061607b9800@192.168.203.142@tcp:12/10 lens 496/440 e 0 to 1 dl 1761323163 ref 2 fl Rpc:XQr/2/ffffffff rc 0/-1 job:'ln.0' [ 8291.305313] Lustre: lustre-MDT0000-mdc-ffffa061607b9800: Connection to lustre-MDT0000 (at 192.168.203.142@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8291.318619] Lustre: lustre-MDT0000-mdc-ffffa061607b9800: Connection restored to (at 192.168.203.142@tcp) [ 8298.464101] Lustre: 398159:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761323163/real 1761323163] req@00000000dfe3ba41 x1846881156076288/t0(0) o36->lustre-MDT0000-mdc-ffffa061607b9800@192.168.203.142@tcp:12/10 lens 496/440 e 0 to 1 dl 1761323170 ref 2 fl Rpc:XQr/2/ffffffff rc 0/-1 job:'ln.0' [ 8298.477970] Lustre: lustre-MDT0000-mdc-ffffa061607b9800: Connection to lustre-MDT0000 (at 192.168.203.142@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8298.492474] Lustre: lustre-MDT0000-mdc-ffffa061607b9800: Connection restored to (at 192.168.203.142@tcp) [ 8300.144715] Lustre: DEBUG MARKER: == sanityn test 111: A racy rename/link an open file should not cause fs corruption ========================================================== 12:26:11 (1761323171) [ 8306.774719] Lustre: DEBUG MARKER: == sanityn test 112: update max-inherit in default LMV === 12:26:18 (1761323178) [ 8311.558909] Lustre: DEBUG MARKER: == sanityn test 113: check servers of specified fs ======= 12:26:23 (1761323183) [ 8314.152631] Lustre: DEBUG MARKER: cleanup: ====================================================== [ 8314.843545] Lustre: DEBUG MARKER: == sanityn test complete, duration 8127 sec ============== 12:26:26 (1761323186) [ 8412.598648] Lustre: Unmounted lustre-client [ 8447.834165] Key type lgssc unregistered [ 8447.956820] LNet: 401424:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8448.993114] LNet: Removed LNI 192.168.203.42@tcp [ 8449.254135] Key type .llcrypt unregistered [ 8449.255749] Key type ._llcrypt unregistered