[ 0.000000] Linux version 4.18.0rh8.10-debug (green@maintenance) (gcc version 8.5.0 20210514 (Red Hat 8.5.0-26) (GCC)) #2 SMP Mon Jul 14 01:24:22 EDT 2025 [ 0.000000] Command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' [ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 [ 0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. [ 0.000000] signal: max sigframe size: 1776 [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bffd9fff] usable [ 0.000000] BIOS-e820: [mem 0x00000000bffda000-0x00000000bfffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x0000000146dfffff] usable [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] SMBIOS 2.8 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.17.0-8.fc42 06/10/2025 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 590878313 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2421 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE22BD 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 00227D (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2331 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE23C1 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE23F9 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe22bd-0xbffe2330] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22bc] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2331-0xbffe23c0] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe23c1-0xbffe23f8] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe23f9-0xbffe2420] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 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.001015] APIC: Switch to symmetric I/O mode setup [ 0.002380] x2apic enabled [ 0.003011] Switched APIC routing to physical x2apic. [ 0.004015] kvm-guest: setup PV IPIs [ 0.007448] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.008000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.008039] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009015] pid_max: default: 32768 minimum: 301 [ 0.010215] LSM: Security Framework initializing [ 0.011044] Yama: becoming mindful. [ 0.013011] SELinux: Initializing. [ 0.013892] *** VALIDATE selinux *** [ 0.021835] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.025691] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.027117] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029045] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.030107] *** VALIDATE tmpfs *** [ 0.031401] *** VALIDATE proc *** [ 0.032318] *** VALIDATE cgroup *** [ 0.033010] *** VALIDATE cgroup2 *** [ 0.034000] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.034000] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.034016] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.035000] Spectre V2 : User space: Vulnerable [ 0.035000] Speculative Store Bypass: Vulnerable [ 0.035832] debug: unmapping init [mem 0xffffffff9de59000-0xffffffff9de60fff] [ 0.038000] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.038955] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.040036] ... version: 2 [ 0.041015] ... bit width: 48 [ 0.042012] ... generic registers: 4 [ 0.043014] ... value mask: 0000ffffffffffff [ 0.044018] ... max period: 00007fffffffffff [ 0.045019] ... fixed-purpose events: 3 [ 0.046012] ... event mask: 000000070000000f [ 0.048342] rcu: Hierarchical SRCU implementation. [ 0.051141] smp: Bringing up secondary CPUs ... [ 0.052724] x86: Booting SMP configuration: [ 0.053043] .... node #0, CPUs: #1 #2 #3 [ 0.062167] smp: Brought up 1 node, 4 CPUs [ 0.064077] smpboot: Max logical packages: 1 [ 0.065028] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.116319] node 0 deferred pages initialised in 44ms [ 0.126177] devtmpfs: initialized [ 0.127258] x86/mm: Memory block size: 128MB [ 0.130013] gcov: version magic: 0x41383552 [ 0.134439] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.141117] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.142000] pinctrl core: initialized pinctrl subsystem [ 0.142499] [ 0.143000] ************************************************************* [ 0.147018] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.149018] ** ** [ 0.152019] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.153024] ** ** [ 0.154000] ** This means that this kernel is built to expose internal ** [ 0.154019] ** IOMMU data structures, which may compromise security on ** [ 0.158016] ** your system. ** [ 0.160018] ** ** [ 0.171018] ** If you see this message and you are not debugging the ** [ 0.173014] ** kernel, report this immediately to your vendor! ** [ 0.176020] ** ** [ 0.179512] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.183019] ************************************************************* [ 0.186804] NET: Registered protocol family 16 [ 0.189486] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.192070] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.195069] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.199111] cpuidle: using governor menu [ 0.202301] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.204922] PCI: Using configuration type 1 for base access [ 0.208158] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.221216] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.225726] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.229817] cryptd: max_cpu_qlen set to 1000 [ 0.231357] ACPI: Added _OSI(Module Device) [ 0.232000] ACPI: Added _OSI(Processor Device) [ 0.234017] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.235014] ACPI: Added _OSI(Processor Aggregator Device) [ 0.242000] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.254401] ACPI: Interpreter enabled [ 0.255000] ACPI: PM: (supports S0 S3 S4 S5) [ 0.257209] ACPI: Using IOAPIC for interrupt routing [ 0.264185] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.265000] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.317384] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.321128] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.324022] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.327089] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.332821] acpiphp: Slot [2] registered [ 0.334170] acpiphp: Slot [5] registered [ 0.335113] acpiphp: Slot [6] registered [ 0.336188] acpiphp: Slot [3] registered [ 0.338081] acpiphp: Slot [4] registered [ 0.339136] acpiphp: Slot [7] registered [ 0.341075] acpiphp: Slot [8] registered [ 0.342084] acpiphp: Slot [9] registered [ 0.343243] acpiphp: Slot [10] registered [ 0.345084] acpiphp: Slot [11] registered [ 0.346078] acpiphp: Slot [12] registered [ 0.347176] acpiphp: Slot [13] registered [ 0.349073] acpiphp: Slot [14] registered [ 0.350136] acpiphp: Slot [15] registered [ 0.351069] acpiphp: Slot [16] registered [ 0.352115] acpiphp: Slot [17] registered [ 0.354071] acpiphp: Slot [18] registered [ 0.355067] acpiphp: Slot [19] registered [ 0.356096] acpiphp: Slot [20] registered [ 0.357095] acpiphp: Slot [21] registered [ 0.358243] acpiphp: Slot [22] registered [ 0.360158] acpiphp: Slot [23] registered [ 0.362311] acpiphp: Slot [24] registered [ 0.364143] acpiphp: Slot [25] registered [ 0.366342] acpiphp: Slot [26] registered [ 0.369116] acpiphp: Slot [27] registered [ 0.371135] acpiphp: Slot [28] registered [ 0.373144] acpiphp: Slot [29] registered [ 0.375145] acpiphp: Slot [30] registered [ 0.378153] acpiphp: Slot [31] registered [ 0.379079] PCI host bridge to bus 0000:00 [ 0.381032] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.383023] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.387034] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.389022] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.393033] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.398033] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.401201] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.409069] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.414249] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.420000] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.425000] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.431066] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.440082] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.447067] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.651587] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.653710] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.656029] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.657687] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.660013] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.670014] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.673017] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.687435] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.701023] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.712023] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.752018] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.772200] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.789019] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.804020] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.829022] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.858591] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.875307] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.884430] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.897000] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.897000] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.902091] iommu: Default domain type: Passthrough [ 0.907000] SCSI subsystem initialized [ 0.908093] ACPI: bus type USB registered [ 0.909134] usbcore: registered new interface driver usbfs [ 0.910106] usbcore: registered new interface driver hub [ 0.911000] usbcore: registered new device driver usb [ 0.911000] pps_core: LinuxPPS API ver. 1 registered [ 0.911000] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.911000] PTP clock support registered [ 0.912098] EDAC MC: Ver: 3.0.0 [ 0.913000] PCI: Using ACPI for IRQ routing [ 0.915000] NetLabel: Initializing [ 0.922032] NetLabel: domain hash size = 128 [ 0.926013] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.929083] NetLabel: unlabeled traffic allowed by default [ 0.934432] vgaarb: loaded [ 0.936063] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.940028] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.944350] clocksource: Switched to clocksource kvm-clock [ 1.225235] VFS: Disk quotas dquot_6.6.0 [ 1.227125] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 1.231260] *** VALIDATE ramfs *** [ 1.232606] *** VALIDATE hugetlbfs *** [ 1.234064] pnp: PnP ACPI init [ 1.237058] pnp: PnP ACPI: found 6 devices [ 1.314870] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 1.317495] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 1.319133] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 1.320849] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 1.322588] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 1.326177] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 1.331550] NET: Registered protocol family 2 [ 1.338310] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 1.350704] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 1.358242] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 1.367823] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 1.373460] TCP: Hash tables configured (established 65536 bind 65536) [ 1.380231] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 1.386968] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 1.393517] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 1.398462] NET: Registered protocol family 1 [ 1.403246] RPC: Registered named UNIX socket transport module. [ 1.408311] RPC: Registered udp transport module. [ 1.412278] RPC: Registered tcp transport module. [ 1.413488] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 1.418392] NET: Registered protocol family 44 [ 1.421881] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 1.426282] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 1.430771] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 1.435210] PCI: CLS 0 bytes, default 64 [ 1.441190] Unpacking initramfs... [ 5.290018] hrtimer: interrupt took 6844737 ns [ 5.827718] debug: unmapping init [mem 0xffff9e9d7cc64000-0xffff9e9d7ffcffff] [ 5.840488] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 5.844080] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 5.857215] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 8.108811] Initialise system trusted keyrings [ 8.114144] Key type blacklist registered [ 8.116754] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 8.138712] zbud: loaded [ 8.150338] *** VALIDATE nfs *** [ 8.153917] *** VALIDATE nfs4 *** [ 8.157181] pstore: using deflate compression [ 8.176869] Platform Keyring initialized [ 8.570181] NET: Registered protocol family 38 [ 8.571689] Key type asymmetric registered [ 8.572775] Asymmetric key parser 'x509' registered [ 8.580824] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 8.586784] io scheduler mq-deadline registered [ 8.590117] io scheduler kyber registered [ 8.592543] io scheduler bfq registered [ 8.602620] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 8.606974] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 8.612812] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 8.618446] ACPI: Power Button [PWRF] [ 8.624611] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 8.633762] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 8.646138] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 8.677065] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 8.748472] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 8.776120] Non-volatile memory driver v1.3 [ 8.784121] Linux agpgart interface v0.103 [ 8.896761] virtio_blk virtio1: [vda] 67992 512-byte logical blocks (34.8 MB/33.2 MiB) [ 8.913742] vda: detected capacity change from 0 to 34811904 [ 8.964222] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 8.974665] vdb: detected capacity change from 0 to 1073741824 [ 9.008533] libphy: Fixed MDIO Bus: probed [ 9.046437] usbcore: registered new interface driver usbserial_generic [ 9.048331] usbserial: USB Serial support registered for generic [ 9.062503] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 9.093611] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 9.101380] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 9.112097] mousedev: PS/2 mouse device common for all mice [ 9.122927] rtc_cmos 00:05: RTC can wake from S4 [ 9.125291] rtc_cmos 00:05: registered as rtc0 [ 9.133944] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 9.139018] intel_pstate: CPU model not supported [ 9.151693] hid: raw HID events driver (C) Jiri Kosina [ 9.153492] usbcore: registered new interface driver usbhid [ 9.165078] usbhid: USB HID core driver [ 9.166409] drop_monitor: Initializing network drop monitor service [ 9.178246] Initializing XFRM netlink socket [ 9.187250] NET: Registered protocol family 10 [ 9.189437] Segment Routing with IPv6 [ 9.190386] NET: Registered protocol family 17 [ 9.195988] mpls_gso: MPLS GSO support [ 9.198945] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 9.224540] RAS: Correctable Errors collector initialized. [ 9.226025] AVX version of gcm_enc/dec engaged. [ 9.227199] AES CTR mode by8 optimization enabled [ 9.239073] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 9.263507] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 9.510161] sched_clock: Marking stable (9510141737, 0)->(11182704612, -1672562875) [ 9.535381] registered taskstats version 1 [ 9.539521] Loading compiled-in X.509 certificates [ 9.545791] zswap: loaded using pool lzo/zbud [ 9.623829] Key type big_key registered [ 9.659697] Key type encrypted registered [ 9.660954] ima: No TPM chip found, activating TPM-bypass! [ 9.662414] ima: Allocated hash algorithm: sha1 [ 9.668204] ima: No architecture policies found [ 9.671128] evm: Initialising EVM extended attributes: [ 9.676407] evm: security.selinux [ 9.678326] evm: security.ima [ 9.681225] evm: security.capability [ 9.685469] evm: HMAC attrs: 0x1 [ 9.693876] rtc_cmos 00:05: setting system clock to 2026-01-08 02:30:45 UTC (1767839445) [ 9.712838] debug: unmapping init [mem 0xffffffff9ee03000-0xffffffff9effffff] [ 9.725470] debug: unmapping init [mem 0xffffffff9db82000-0xffffffff9de58fff] [ 9.739104] Write protecting the kernel read-only data: 28672k [ 9.760820] debug: unmapping init [mem 0xffffffff9c203000-0xffffffff9c3fffff] [ 9.767884] debug: unmapping init [mem 0xffffffff9cb14000-0xffffffff9cbfffff] [ 9.893112] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 9.925235] systemd[1]: Detected virtualization kvm. [ 9.926893] systemd[1]: Detected architecture x86-64. [ 9.941904] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 10.029077] systemd[1]: No hostname configured. [ 10.060127] systemd[1]: Set hostname to . [ 10.072176] random: systemd: uninitialized urandom read (16 bytes read) [ 10.081212] systemd[1]: Initializing machine ID from random generator. [ 10.269440] random: ln: uninitialized urandom read (6 bytes read) [ 10.493677] random: systemd: uninitialized urandom read (16 bytes read) [ 10.502525] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. [ 10.525035] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ 10.565073] systemd[1]: Starting Setup Virtual Console... Starting Setup Virtual Console... [ OK ] Reached target Local File Systems. [ OK ] Listening on udev Control Socket. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Started Memstrack Anylazing Service. Starting Create Volatile Files and Directories... [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Timers. [ OK ] Reached target Initrd Root Device. [ OK ] Reached target Sockets. [ OK ] Reached target Swap. Starting Journal Service... Starting Apply Kernel Variables... [ OK ] Started Setup Virtual Console. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Apply Kernel Variables. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 12.381070] device-mapper: uevent: version 1.0.3 [ 12.390036] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 14.521112] virtio_net virtio0 ens2: renamed from eth0 [ 14.963977] scsi host0: ata_piix [ 15.048200] scsi host1: ata_piix [ 15.050626] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 15.058822] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 20.206722] random: crng init done [ 20.210261] random: 7 urandom warning(s) missed due to ratelimiting [ 21.138050] dracut-initqueue[584]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 23.608426] 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 Initrd Default Target. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ 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 udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Swap. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. Stopping udev Kernel Device Manager... [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 27.012743] printk: systemd: 26 output lines suppressed due to ratelimiting [ 28.017969] SELinux: Disabled at runtime. [ 28.139407] 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) [ 28.155379] systemd[1]: Detected virtualization kvm. [ 28.158407] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 30.560294] systemd[1]: initrd-switch-root.service: Succeeded. [ 30.572520] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 30.588135] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 30.605297] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 30.619763] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 30.648842] systemd[1]: Starting Journal Service... Starting Journal Service... [ 30.682562] systemd[1]: Mounting Kernel Debug File System... Mounting Kernel Debug File System... [ OK ] Reached target rpc_pipefs.target. [ OK ] Created slice system-serial\x2dgetty.slice. [ 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 ] Listening on Process Core Dump Socket. Mounting Huge Pages File System... [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd Root File System. [ OK ] Created slice User and Session Slice. [ OK ] Listening on RPCbind Server Activation Socket. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Started Dispatch Password Requests to Console Directory Watch. Mounting POSIX Message Queue File System... [ OK ] Created slice system-getty.slice. [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... Activating swap /dev/disk/by-label/SWAP... [ OK ] Reached target RPC Port Mapper. Starting Remount Root and Kernel File Systems... [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK [[ 31.373179] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS 0m] Reached target Paths. Starting Apply Kernel Variables... [ OK ] Reached target Slices. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Stopped target Initrd File Systems. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Mounted Kernel Debug File System. [ OK ] Mounted Huge Pages File System. [ OK ] Started Journal Service. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Started Apply Kernel Variables. Starting Configure read-only root support... [ OK ] Reached target Swap. Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started udev Coldplug all Devices. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... Starting udev Kernel Device Manager... [ OK ] Mounted /mnt. [ 32.621193] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 34.155968] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 34.180757] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 34.569402] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 34.734066] EDAC sbridge: Ver: 1.1.2 [* ] A start job is running for Configur…-only root support (8s / no limit)[ 39.428837] Key type dns_resolver registered [** ] A start job is running for Configur…-only root support (9s / no limit) [*** ] A start job is running for Configur…-only root support (9s / no limit)[ 40.560851] NFS: Registering the id_resolver key type [ 40.575026] Key type id_resolver registered [ 40.576403] Key type id_legacy registered [ *** ] A start job is running for Configur…only root support (10s / no limit) [ *** ] A start job is running for Configur…only root support (10s / no limit) [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ 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... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting Update UTMP about System Boot/Shutdown... Starting RPC Bind... [ 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 ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Basic System. [ OK ] Started irqbalance daemon. Starting Restore /run/initramfs on shutdown... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. [ OK ] Reached target sshd-keygen.target. Starting Login Service... [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting Dynamic System Tuning Daemon... Starting GSSAPI Proxy Daemon... Starting OpenSSH server daemon... Starting Network Manager Wait Online... Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ OK ] Started Login Service. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS0. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting System Logging Service... Starting Notify NFS peers of a restart... Starting Crash recovery kernel arming... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg358-client login: [ 83.747348] libcfs: loading out-of-tree module taints kernel. [ 83.821061] alg: No test for adler32 (adler32-zlib) [ 84.572339] Key type ._llcrypt registered [ 84.573546] Key type .llcrypt registered [ 84.727974] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 85.004798] Lustre: Lustre: Build Version: 2.15.8_1_g5e2d639 [ 85.428783] LNet: Added LNI 192.168.203.58@tcp [8/256/0/180] [ 85.431181] LNet: Accept secure, port 988 [ 87.071127] Key type lgssc registered [ 87.688514] Lustre: Echo OBD driver; http://www.lustre.org/ [ 161.716055] Lustre: Mounted lustre-client [ 165.148965] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 174.984391] Lustre: DEBUG MARKER: oleg358-client.virtnet: executing check_logdir /tmp/testlogs/ [ 177.440722] Lustre: DEBUG MARKER: oleg358-client.virtnet: executing yml_node [ 180.203995] Lustre: DEBUG MARKER: Client: 2.15.8.1 [ 182.172582] Lustre: DEBUG MARKER: MDS: 2.15.8.1 [ 184.218946] Lustre: DEBUG MARKER: OSS: 2.15.8.1 [ 185.358786] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Wed Jan 7 21:33:40 EST 2026 [ 187.361203] Lustre: lustre-OST0000-osc-ffff9e9dc54a2000: disconnect after 24s idle [ 189.982351] Lustre: DEBUG MARKER: excepting tests: 27 28 102 [ 190.897274] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 191.327111] Lustre: Mounted lustre-client [ 194.419763] Lustre: DEBUG MARKER: oleg358-client.virtnet: executing check_config_client /mnt/lustre [ 205.512266] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 211.110861] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 21:34:06 (1767839646) [ 212.964310] Lustre: lustre-OST0001-osc-ffff9e9dcb7c1000: disconnect after 21s idle [ 212.967451] Lustre: Skipped 1 previous similar message [ 214.731247] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 21:34:09 (1767839649) [ 218.581873] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 21:34:13 (1767839653) [ 222.386925] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 21:34:17 (1767839657) [ 225.831767] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 21:34:20 (1767839660) [ 229.479893] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 21:34:24 (1767839664) [ 233.010323] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 21:34:28 (1767839668) [ 237.190676] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 21:34:32 (1767839672) [ 241.137747] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 21:34:35 (1767839675) [ 245.174781] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 21:34:40 (1767839680) [ 250.565448] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 21:34:45 (1767839685) [ 255.482410] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 21:34:50 (1767839690) [ 259.377396] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 21:34:54 (1767839694) [ 263.423984] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 21:34:58 (1767839698) [ 267.674768] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 21:35:02 (1767839702) [ 271.903744] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 21:35:06 (1767839706) [ 275.970441] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 21:35:10 (1767839710) [ 280.651791] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 21:35:15 (1767839715) [ 284.429936] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 21:35:19 (1767839719) [ 289.179654] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 21:35:24 (1767839724) [ 289.835765] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=144115205289279507 file: /mnt/lustre/lockdir/lockfile=144115205289279506 [ 427.144483] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 21:37:42 (1767839862) [ 431.670273] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 21:37:46 (1767839866) [ 434.933824] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 21:37:50 (1767839870) [ 438.144554] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 21:37:53 (1767839873) [ 441.602191] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 21:37:56 (1767839876) [ 445.357792] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 21:38:00 (1767839880) [ 446.598946] Lustre: DEBUG MARKER: chmod [ 450.117162] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 21:38:05 (1767839885) [ 454.161959] Lustre: DEBUG MARKER: SKIP: oos2 test_15 oos2.sh: 7528448kB free gt MAXFREE 800000kB, increase 800000 (or reduce test fs size) to proceed [ 465.433507] Lustre: DEBUG MARKER: == sanityn test 16a: 500 iterations of dual-mount fsx ==== 21:38:20 (1767839900) [ 502.066304] Lustre: DEBUG MARKER: == sanityn test 16b: 500 iterations of dual-mount fsx at small size ========================================================== 21:38:57 (1767839937) [ 518.745914] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 21:39:13 (1767839953) [ 520.079541] Lustre: DEBUG MARKER: SKIP: sanityn test_16c dio on ldiskfs only [ 521.047725] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 21:39:16 (1767839956) [ 542.700218] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 21:39:37 (1767839977) [ 545.798496] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 21:39:40 (1767839980) [ 546.269550] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 546.302649] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 546.329426] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 546.359713] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 546.395147] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 546.427246] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 546.459458] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 546.487637] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 546.517382] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 546.544071] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 546.567922] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 546.605801] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 546.643185] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 546.671048] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 546.702394] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 546.737502] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 546.758522] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 546.805509] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 546.836179] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 546.883225] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 546.909805] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 546.936729] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 546.965703] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 546.996314] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 547.027484] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 547.055740] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 547.086805] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 547.127620] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 547.177809] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 547.218106] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 547.257862] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 547.303927] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 547.334653] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 547.370245] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 547.399043] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 547.448495] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 547.485159] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 547.527713] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 547.572717] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 547.618586] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 547.662493] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 547.705785] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 547.739517] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 547.763296] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 547.791198] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 547.822087] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 547.846184] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 547.872062] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 547.903125] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 547.932827] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 547.966105] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 547.991337] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 548.020610] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 548.045120] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 548.090771] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 548.117329] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 548.161608] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 548.198910] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 548.247201] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 548.296088] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 548.353777] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 548.389707] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 548.423829] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 548.461727] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 548.497773] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 548.526220] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 548.554208] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 548.598991] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 548.628350] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 548.667841] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 548.702793] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 548.728381] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 548.757764] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 548.801522] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 548.836591] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 548.868215] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 548.902741] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 548.934648] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 548.967987] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 549.009578] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 549.056835] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 549.085866] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 549.123946] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 549.162408] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 549.203578] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 549.236816] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 549.270863] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 549.308532] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 549.352104] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 549.385141] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 549.422173] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 549.454322] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 549.478720] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 549.502410] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 549.527217] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 549.550938] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 549.588350] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 549.624931] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 549.660416] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 549.707542] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 549.762481] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 549.817996] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 549.868641] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 549.912368] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 549.958355] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 550.004989] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 550.052523] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 550.084424] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 550.116580] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 550.162925] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 550.198925] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 550.236897] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 550.279896] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 550.322963] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 550.357339] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 550.395304] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 550.422101] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 550.454231] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 550.482817] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 550.511354] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 550.537123] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 550.565320] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 550.590373] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 550.615505] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 550.649908] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 550.681326] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 550.713339] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 550.741487] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 550.776926] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 550.815948] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 550.853509] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 550.881063] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 550.911685] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 550.942934] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 550.970675] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 551.001174] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 551.034694] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 551.078483] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 551.102642] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 551.130237] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 551.161712] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 551.190549] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 551.226406] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 551.254786] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 551.277873] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 551.312774] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 551.340289] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 551.385383] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 551.418324] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 551.452284] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 551.489534] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 551.516575] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 551.548900] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 551.576306] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 551.605701] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 551.631344] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 551.666420] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 551.704865] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 551.729441] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 551.753641] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 551.791139] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 551.823216] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 551.846748] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 551.869385] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 551.896798] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 551.921556] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 551.952837] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 551.985099] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 552.018859] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 552.044185] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 552.073765] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 552.112085] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 552.155493] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 552.186695] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 552.217860] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 552.254354] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 552.280640] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 552.309919] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 552.337079] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 552.368661] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 552.393085] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 552.426775] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 552.453253] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 552.479866] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 552.506101] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 552.534704] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 552.559114] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 552.593684] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 552.624382] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 552.665166] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 552.693895] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 552.715742] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 552.759467] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 552.786884] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 552.816210] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 552.849817] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 552.876249] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 552.916639] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 552.951207] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 552.978225] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 553.007549] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 553.032571] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 553.056751] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 553.078849] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 553.105693] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 553.134774] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 553.170064] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 553.195161] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 553.222237] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 553.258485] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 553.286972] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 553.318160] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 553.352420] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 553.383294] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 553.417707] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 553.463100] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 553.491721] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 553.525205] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 553.564094] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 553.596320] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 553.620312] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 553.646720] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 553.678329] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 553.709398] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 553.742946] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 553.766520] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 553.797943] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 553.835173] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 553.866217] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 553.898240] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 553.934218] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 553.972165] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 554.023733] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 554.065447] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 554.099737] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 554.145479] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 554.195607] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 554.247764] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 554.284751] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 554.342339] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 554.381601] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 554.419837] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 554.468423] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 554.541648] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 554.582826] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 554.610840] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 554.646637] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 554.677296] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 554.707320] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 554.741913] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 554.776626] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 554.809854] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 554.836253] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 554.862447] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 554.909574] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 554.959518] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 555.003928] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 555.038293] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 555.063145] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 555.094100] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 555.141215] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 555.173942] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 555.210841] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 555.238923] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 555.265931] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 555.295048] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 555.323281] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 555.348363] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 555.377846] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 555.410039] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 555.439374] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 555.469377] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 555.501186] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 555.550500] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 555.577820] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 555.609422] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 555.652299] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 555.696504] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 555.728412] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 555.762898] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 555.802675] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 555.838307] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 555.869342] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 555.902403] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 555.959414] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 556.006301] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 556.062844] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 556.097297] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 556.127678] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 556.153462] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 556.177072] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 556.203791] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 556.244078] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 556.278297] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 556.312300] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 556.347721] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 556.374452] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 556.399770] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 556.432664] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 556.457672] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 556.484223] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 556.512023] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 556.541419] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 556.571754] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 556.602222] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 556.629142] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 556.654120] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 556.687889] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 556.716030] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 556.750063] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 556.778098] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 556.803886] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 556.835955] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 556.863839] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 556.899672] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 556.925603] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 556.976326] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 557.005459] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 557.029761] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 557.060215] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 557.126605] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 557.186927] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 557.238331] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 557.278036] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 557.335514] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 557.388368] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 557.436886] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 557.475359] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 557.513126] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 557.548788] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 557.590725] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 557.631684] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 557.666642] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 557.710305] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 557.739048] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 557.767451] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 557.824636] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 557.865266] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 557.897145] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 557.925709] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 557.956253] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 557.996350] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 558.027880] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 558.064069] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 558.098255] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 558.133261] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 558.171880] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 558.208377] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 558.255413] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 558.292149] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 558.336801] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 558.373198] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 558.424025] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 558.470529] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 558.527229] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 558.577543] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 558.623790] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 558.659937] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 558.705704] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 558.755914] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 558.811326] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 558.865137] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 558.922826] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 558.958607] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 558.990053] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 559.019480] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 559.058291] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 559.092934] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 559.126683] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 559.156994] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 559.218940] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 559.277209] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 559.323886] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 559.370763] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 559.398617] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 559.435750] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 559.470805] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 559.504593] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 559.541209] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 559.574624] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 559.622087] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 559.668300] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 559.717290] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 559.766257] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 559.814073] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 559.853662] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 559.886643] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 559.922369] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 559.958355] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 559.997107] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 560.035766] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 560.080372] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 560.123450] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 560.153618] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 560.178579] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 560.207036] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 560.237912] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 560.274184] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 560.327120] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 560.360370] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 560.390278] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 560.421758] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 560.460138] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 560.494846] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 560.531423] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 560.562040] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 560.587491] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 560.614233] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 560.646313] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 560.680598] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 560.724412] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 560.760634] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 560.790823] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 560.835911] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 560.875724] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 560.902844] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 560.947945] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 560.986379] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 561.034839] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 561.074565] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 561.113932] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 561.149358] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 561.192243] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 561.232671] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 561.269840] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 561.311664] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 561.352752] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 561.393168] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 561.442583] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 561.487687] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 561.517169] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 561.549710] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 561.588325] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 561.630199] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 561.668938] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 561.715974] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 561.750492] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 561.788912] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 561.819048] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 561.850985] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 561.898799] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 561.940857] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 561.975730] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 562.017956] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 562.054749] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 562.089715] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 562.127646] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 562.157712] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 562.186127] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 562.223986] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 562.269911] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 562.313750] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 562.360036] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 562.396730] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 562.435499] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 562.487101] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 562.521031] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 562.578037] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 562.616990] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 562.656616] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 562.690444] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 562.721654] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 562.753831] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 562.786336] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 562.818131] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 562.855638] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 562.886088] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 562.933524] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 562.968379] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 563.011874] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 563.050958] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 563.107019] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 563.170270] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 563.210481] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 563.245341] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 563.275687] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 563.305598] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 563.331829] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 563.354627] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 563.378907] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 563.407148] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 563.446902] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 563.491536] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 563.527928] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 563.556412] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 563.586916] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 563.614383] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 563.646710] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 563.679988] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 563.716345] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 563.761226] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 563.803565] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 563.838695] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 563.870040] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 563.898865] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 563.927547] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 563.959593] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 563.997669] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 564.051796] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 564.079817] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 564.127941] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 564.165668] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 564.212020] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 564.285371] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 564.314555] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 564.338137] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 564.361989] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 564.389794] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 564.419932] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 564.455760] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 564.488737] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 564.518978] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 564.548745] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 564.583400] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 564.610177] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 564.642228] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 564.680328] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 564.717991] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 564.744185] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 564.774864] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 564.797602] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 564.829483] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 564.861412] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 564.896250] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 564.925695] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 564.981076] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 565.025382] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 565.071619] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 565.099179] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 565.128908] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 565.164771] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 565.197630] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 565.234816] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 565.282318] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 565.320866] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 565.368838] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 565.409715] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 565.455803] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 565.499298] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 565.544794] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 565.579561] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 565.611495] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 565.636517] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 565.669469] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 565.701563] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 565.739108] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 565.785295] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 565.819848] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 565.850246] rw_seq_cst_vs_d (28875): drop_caches: 3 [ 566.241840] Lustre: lustre-OST0000-osc-ffff9e9dcb7c1000: disconnect after 24s idle [ 569.080206] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 21:40:04 (1767840004) [ 569.237279] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 569.256204] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 569.442834] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 569.476927] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 569.501328] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 569.606240] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 569.706209] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 569.905672] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 569.932378] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 570.144136] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 570.250304] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 570.275171] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 570.297168] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 570.392673] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 570.589360] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 570.688300] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 570.705772] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 570.726834] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 570.756171] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 570.783813] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 570.823022] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 570.859778] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 570.890643] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 570.935193] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 570.975395] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 571.120271] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 571.174247] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 571.221503] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 571.297990] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 571.345309] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 571.372837] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 571.396585] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 571.419084] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 571.526909] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 571.544967] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 571.605591] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 571.634834] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 571.700135] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 571.741278] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 571.926350] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 571.998255] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 572.061406] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 572.080452] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 572.159880] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 572.176991] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 572.195101] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 572.279769] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 572.373459] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 572.556800] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 572.637282] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 572.655644] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 572.743398] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 572.848555] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 572.868558] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 572.892929] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 572.912734] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 572.929030] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 572.966222] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 572.986777] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 573.003729] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 573.042572] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 573.084978] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 573.127341] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 573.142594] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 573.183424] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 573.273634] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 573.353903] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 573.448750] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 573.470138] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 573.536633] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 573.666099] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 573.687899] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 573.716284] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 573.736467] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 573.762395] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 573.787177] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 573.831937] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 573.880949] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 573.898695] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 573.927151] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 573.950323] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 574.025735] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 574.068992] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 574.102780] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 574.131478] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 574.151566] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 574.168128] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 574.193129] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 574.224562] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 574.242566] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 574.322030] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 574.362311] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 574.503551] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 574.528045] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 574.551249] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 574.568131] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 574.592293] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 574.642310] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 574.682743] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 574.729641] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 574.750742] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 574.810609] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 574.833795] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 574.949952] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 575.117343] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 575.139327] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 575.420439] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 575.481589] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 575.528594] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 575.763711] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 575.782077] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 575.845086] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 575.888409] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 575.910080] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 575.985119] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 576.104455] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 576.317908] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 576.341106] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 576.362872] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 576.397142] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 576.419278] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 576.436637] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 576.683251] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 576.756487] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 576.806226] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 576.895845] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 576.915622] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 577.068504] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 577.176897] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 577.323457] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 577.349100] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 577.488748] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 577.525756] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 577.684262] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 577.700492] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 577.720609] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 577.897965] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 577.918266] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 577.974203] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 578.001385] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 578.155889] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 578.256595] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 578.287703] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 578.320930] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 578.363825] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 578.382133] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 578.441042] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 578.470797] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 578.489558] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 578.517252] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 578.604601] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 578.777631] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 578.798667] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 578.929997] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 579.032403] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 579.063878] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 579.132397] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 579.156970] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 579.176996] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 579.240321] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 579.262571] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 579.538570] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 579.747039] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 580.016035] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 580.167806] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 580.196641] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 580.421959] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 580.561517] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 580.597974] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 580.696852] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 580.965747] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 581.186606] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 581.255186] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 581.290881] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 581.537973] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 581.621021] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 581.730788] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 581.765763] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 582.027618] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 582.223874] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 582.251230] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 582.272576] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 582.306766] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 582.328659] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 582.353753] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 582.562423] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 582.605046] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 582.683727] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 582.862700] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 582.899842] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 582.992441] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 583.255565] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 583.398175] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 583.416207] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 583.437436] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 583.469993] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 583.769644] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 583.791778] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 583.904531] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 583.926978] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 584.015813] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 584.044275] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 584.074419] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 584.120643] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 584.158843] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 584.212271] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 584.255970] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 584.411176] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 584.438744] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 584.460281] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 584.504754] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 584.543946] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 584.571920] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 584.591618] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 584.657471] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 584.673251] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 584.690979] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 584.734708] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 584.883656] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 584.933730] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 584.992499] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 585.207322] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 585.241578] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 585.266466] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 585.371669] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 585.418337] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 585.686551] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 585.703886] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 586.034768] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 586.300832] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 586.689397] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 586.722097] Lustre: lustre-OST0001-osc-ffff9e9dcb7c1000: disconnect after 21s idle [ 586.739880] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 586.779198] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 586.862602] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 586.898102] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 586.968544] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 587.085110] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 587.261943] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 587.301304] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 587.326242] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 587.446436] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 587.474091] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 587.505051] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 587.522996] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 587.738364] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 587.762176] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 587.786734] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 587.808165] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 587.865763] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 587.890424] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 587.918120] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 587.952821] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 587.971651] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 587.991926] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 588.113450] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 588.147809] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 588.182470] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 588.205391] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 588.300152] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 588.317699] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 588.336625] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 588.519166] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 588.541166] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 588.726912] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 588.945385] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 589.015892] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 589.041458] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 589.130276] rw_seq_cst_vs_d (29455): drop_caches: 3 [ 591.839348] Lustre: lustre-OST0001-osc-ffff9e9dc54a2000: disconnect after 24s idle [ 592.366118] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 21:40:27 (1767840027) [ 595.129391] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 21:40:30 (1767840030) [ 600.168572] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 21:40:35 (1767840035) [ 613.350157] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 21:40:48 (1767840048) [ 614.439632] Lustre: DEBUG MARKER: SKIP: sanityn test_19 not cache-capable obdfilter [ 615.162911] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 21:40:50 (1767840050) [ 618.020080] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 21:40:53 (1767840053) [ 621.031699] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 21:40:56 (1767840056) [ 632.800660] Lustre: lustre-OST0000-osc-ffff9e9dcb7c1000: disconnect after 24s idle [ 685.226418] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 21:42:00 (1767840120) [ 687.787139] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 21:42:03 (1767840123) [ 690.349158] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 21:42:05 (1767840125) [ 693.312851] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 21:42:08 (1767840128) [ 696.444580] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 21:42:11 (1767840131) [ 700.260271] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 21:42:15 (1767840135) [ 705.307516] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 706.108679] Lustre: DEBUG MARKER: SKIP: sanityn test_28 skipping ALWAYS excluded test 28 [ 706.854851] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 21:42:22 (1767840142) [ 712.395135] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 21:42:27 (1767840147) [ 712.646090] Lustre: *** cfs_fail_loc=314, val=0*** [ 713.695514] Lustre: *** cfs_fail_loc=314, val=0*** [ 713.697045] Lustre: Skipped 2 previous similar messages [ 716.434624] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 21:42:31 (1767840151) [ 719.839535] Lustre: lustre-OST0000-osc-ffff9e9dc54a2000: disconnect after 21s idle [ 719.844451] Lustre: Skipped 3 previous similar messages [ 733.703809] Lustre: *** cfs_fail_loc=314, val=0*** [ 735.206207] Lustre: lustre-OST0000-osc-ffff9e9dcb7c1000: Connection to lustre-OST0000 (at 192.168.203.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 735.226392] LustreError: lustre-OST0000-osc-ffff9e9dcb7c1000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 735.232761] Lustre: 2268:0:(llite_lib.c:3595:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.203.158@tcp:/lustre/fid: [0x240000403:0x2:0x0]// may get corrupted (rc -108) [ 735.240845] Lustre: lustre-OST0000-osc-ffff9e9dcb7c1000: Connection restored to (at 192.168.203.158@tcp) [ 737.110886] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 21:42:52 (1767840172) [ 737.184368] LustreError: 39810:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 740.207222] LustreError: 39810:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 1419 awake [ 742.877261] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 743.772928] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 21:42:58 (1767840178) [ 744.567833] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 745.251201] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-Lock-Cancel ========================================================== 21:43:00 (1767840180) [ 750.564396] LustreError: 166-1: MGC192.168.203.158@tcp: Connection to MGS (at 192.168.203.158@tcp) was lost; in progress operations using this service will fail [ 750.564765] Lustre: lustre-MDT0000-mdc-ffff9e9dc54a2000: Connection to lustre-MDT0000 (at 192.168.203.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 750.578262] Lustre: Evicted from MGS (at 192.168.203.158@tcp) after server handle changed from 0xfc2b4149eb4899d5 to 0xfc2b4149eb54e8e6 [ 750.586987] Lustre: MGC192.168.203.158@tcp: Connection restored to (at 192.168.203.158@tcp) [ 755.133454] Lustre: lustre-MDT0000-mdc-ffff9e9dc54a2000: Connection restored to (at 192.168.203.158@tcp) [ 765.919136] Lustre: lustre-OST0000-osc-ffff9e9dc54a2000: disconnect after 22s idle [ 767.481781] Lustre: DEBUG MARKER: == sanityn test 33d: DNE distributed operation should trigger COS ========================================================== 21:43:22 (1767840202) [ 814.447288] Lustre: DEBUG MARKER: == sanityn test 33e: DNE local operation shouldn't trigger COS ========================================================== 21:44:09 (1767840249) [ 815.058686] Lustre: DEBUG MARKER: SKIP: sanityn test_33e Need two or more clients, have 1 [ 815.729562] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 21:44:10 (1767840250) [ 862.134206] Lustre: lustre-OST0000-osc-ffff9e9dcb7c1000: Connection to lustre-OST0000 (at 192.168.203.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 862.139581] Lustre: Skipped 1 previous similar message [ 862.143684] LustreError: lustre-OST0000-osc-ffff9e9dc54a2000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 862.150519] LustreError: lustre-OST0000-osc-ffff9e9dcb7c1000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 862.150653] Lustre: lustre-OST0000-osc-ffff9e9dc54a2000: Connection restored to (at 192.168.203.158@tcp) [ 862.159843] Lustre: Skipped 2 previous similar messages [ 867.254625] Lustre: lustre-OST0001-osc-ffff9e9dc54a2000: Connection to lustre-OST0001 (at 192.168.203.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 867.264610] Lustre: Skipped 1 previous similar message [ 867.269133] LustreError: lustre-OST0001-osc-ffff9e9dc54a2000: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 867.277681] Lustre: lustre-OST0001-osc-ffff9e9dc54a2000: Connection restored to (at 192.168.203.158@tcp) [ 880.027927] Lustre: DEBUG MARKER: oleg358-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9e9dc54a2000.ost_server_uuid,osc.lustre-OST0000-osc-ffff9e9dcb7c1000.ost_server_uuid 40 [ 880.643224] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9e9dc54a2000.ost_server_uuid in FULL state after 0 sec [ 881.234074] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9e9dcb7c1000.ost_server_uuid in FULL state after 0 sec [ 883.487062] Lustre: DEBUG MARKER: oleg358-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9e9dc54a2000.ost_server_uuid,osc.lustre-OST0001-osc-ffff9e9dcb7c1000.ost_server_uuid 40 [ 883.680134] Lustre: lustre-OST0000-osc-ffff9e9dc54a2000: disconnect after 22s idle [ 883.683457] Lustre: Skipped 1 previous similar message [ 884.084753] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9e9dc54a2000.ost_server_uuid in FULL state after 0 sec [ 884.671892] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9e9dcb7c1000.ost_server_uuid in FULL state after 0 sec [ 887.361929] Lustre: DEBUG MARKER: oleg358-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9e9dc54a2000.ost_server_uuid,osc.lustre-OST0000-osc-ffff9e9dcb7c1000.ost_server_uuid 40 [ 887.937222] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9e9dc54a2000.ost_server_uuid in IDLE state after 0 sec [ 888.552995] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9e9dcb7c1000.ost_server_uuid in IDLE state after 0 sec [ 890.919879] Lustre: DEBUG MARKER: oleg358-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9e9dc54a2000.ost_server_uuid,osc.lustre-OST0001-osc-ffff9e9dcb7c1000.ost_server_uuid 40 [ 891.733902] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9e9dc54a2000.ost_server_uuid in IDLE state after 0 sec [ 892.422829] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9e9dcb7c1000.ost_server_uuid in FULL state after 0 sec [ 897.088977] Lustre: DEBUG MARKER: oleg358-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9e9dc54a2000.ost_server_uuid,osc.lustre-OST0000-osc-ffff9e9dcb7c1000.ost_server_uuid 40 [ 897.665903] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9e9dc54a2000.ost_server_uuid in IDLE state after 0 sec [ 898.262467] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9e9dcb7c1000.ost_server_uuid in IDLE state after 0 sec [ 900.601588] Lustre: DEBUG MARKER: oleg358-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9e9dc54a2000.ost_server_uuid,osc.lustre-OST0001-osc-ffff9e9dcb7c1000.ost_server_uuid 40 [ 901.234213] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9e9dc54a2000.ost_server_uuid in IDLE state after 0 sec [ 901.926305] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9e9dcb7c1000.ost_server_uuid in FULL state after 0 sec [ 902.654151] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 21:45:37 (1767840337) [ 903.942629] Lustre: DEBUG MARKER: Race attempt 0 [ 905.025107] Lustre: *** cfs_fail_loc=317, val=2147484440*** [ 905.028549] LustreError: 52440:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 318 sleeping [ 905.045628] LustreError: 18585:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 318 waking [ 905.048888] LustreError: 52440:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 318 awake: rc=4986 [ 905.053675] LustreError: 52440:0:(mdc_request.c:1484:mdc_read_page()) lustre-MDT0000-mdc-ffff9e9dc54a2000: [0x200000401:0xf:0x0] lock enqueue fails: rc = -4 [ 905.062082] Lustre: dir [0x240000402:0x2b:0x0] stripe 1 readdir failed: -4, directory is partially accessed! [ 905.564593] Lustre: DEBUG MARKER: Wait for 52311 52440 for 60 sec... [ 967.879093] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 21:46:43 (1767840403) [ 974.114125] Lustre: DEBUG MARKER: start test - cycle (0) [ 998.008485] Lustre: DEBUG MARKER: start test - cycle (1) [ 1018.956300] Lustre: DEBUG MARKER: start test - cycle (2) [ 1038.595551] Lustre: DEBUG MARKER: start test - cycle (3) [ 1059.447414] Lustre: DEBUG MARKER: start test - cycle (4) [ 1080.137586] Lustre: DEBUG MARKER: start test - cycle (5) [ 1099.797698] Lustre: DEBUG MARKER: start test - cycle (6) [ 1120.865938] Lustre: DEBUG MARKER: start test - cycle (7) [ 1140.589108] Lustre: DEBUG MARKER: start test - cycle (8) [ 1161.655708] Lustre: DEBUG MARKER: start test - cycle (9) [ 1182.829304] Lustre: DEBUG MARKER: start test - cycle (10) [ 1204.239320] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 21:50:39 (1767840639) [ 1211.359383] Lustre: lustre-OST0000-osc-ffff9e9dcb7c1000: disconnect after 23s idle [ 1211.362536] Lustre: Skipped 2 previous similar messages [ 1220.693442] Lustre: DEBUG MARKER: == sanityn test 39a: test from 11063 ============================================================================================ 21:50:56 (1767840656) [ 1223.026363] Lustre: DEBUG MARKER: == sanityn test 39b: 11063 problem 1 ============================================================================================ 21:50:58 (1767840658) [ 1226.188754] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 21:51:01 (1767840661) [ 1229.225490] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 21:51:04 (1767840664) [ 1229.293964] Lustre: *** cfs_fail_loc=411, val=0*** [ 1231.232331] Lustre: DEBUG MARKER: == sanityn test 40a: pdirops: create vs others ======================================================================== 21:51:06 (1767840666) [ 1239.633366] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 21:51:14 (1767840674) [ 1247.571535] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 21:51:22 (1767840682) [ 1255.303600] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 21:51:30 (1767840690) [ 1263.177510] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 21:51:38 (1767840698) [ 1270.690570] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 21:51:45 (1767840705) [ 1275.769425] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 21:51:51 (1767840711) [ 1280.901701] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 21:51:56 (1767840716) [ 1285.961934] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 21:52:01 (1767840721) [ 1291.046086] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 21:52:06 (1767840726) [ 1296.308545] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 21:52:11 (1767840731) [ 1301.338769] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 21:52:16 (1767840736) [ 1306.410636] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 21:52:21 (1767840741) [ 1311.522904] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 21:52:26 (1767840746) [ 2034.397568] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 22:04:29 (1767841469) [ 2039.683439] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 22:04:35 (1767841475) [ 2044.935081] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 22:04:40 (1767841480) [ 2049.968253] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 22:04:45 (1767841485) [ 2054.948779] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 22:04:50 (1767841490) [ 2059.863946] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 22:04:55 (1767841495) [ 2064.928443] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 22:05:00 (1767841500) [ 2070.513220] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 22:05:05 (1767841505) [ 2076.639511] Lustre: lustre-OST0001-osc-ffff9e9dc54a2000: disconnect after 23s idle [ 2076.643298] Lustre: Skipped 4 previous similar messages [ 2079.656792] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 22:05:14 (1767841514) [ 2175.916632] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 22:06:50 (1767841610) [ 2183.107536] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 22:06:58 (1767841618) [ 2190.471208] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 22:07:05 (1767841625) [ 2198.189712] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 22:07:13 (1767841633) [ 2205.403546] Lustre: DEBUG MARKER: == sanityn test 43f: pdirops: unlink and rename (src) ======================================================================== 22:07:20 (1767841640) [ 2212.174429] Lustre: DEBUG MARKER: == sanityn test 43g: pdirops: unlink vs getattr ======================================================================== 22:07:27 (1767841647) [ 2219.645878] Lustre: DEBUG MARKER: == sanityn test 43h: pdirops: unlink vs readdir ======================================================================== 22:07:34 (1767841654) [ 2226.895365] Lustre: DEBUG MARKER: == sanityn test 43i: pdirops: unlink vs remote mkdir ===== 22:07:42 (1767841662) [ 2233.541121] Lustre: DEBUG MARKER: == sanityn test 43j: racy mkdir return EEXIST ======================================================================== 22:07:48 (1767841668) [ 2296.192436] Lustre: DEBUG MARKER: == sanityn test 43k: unlink vs create ==================== 22:08:51 (1767841731) [ 2954.486038] Lustre: DEBUG MARKER: == sanityn test 44a: pdirops: rename tgt vs mkdir ======================================================================== 22:19:49 (1767842389) [ 2960.438879] Lustre: DEBUG MARKER: == sanityn test 44b: pdirops: rename tgt vs create ======================================================================== 22:19:55 (1767842395) [ 2966.418220] Lustre: DEBUG MARKER: == sanityn test 44c: pdirops: rename tgt vs link ======================================================================== 22:20:01 (1767842401) [ 2972.404767] Lustre: DEBUG MARKER: == sanityn test 44d: pdirops: rename tgt vs unlink ======================================================================== 22:20:07 (1767842407) [ 2978.310263] Lustre: DEBUG MARKER: == sanityn test 44e: pdirops: rename tgt and rename (tgt) ======================================================================== 22:20:13 (1767842413) [ 2984.417567] Lustre: DEBUG MARKER: == sanityn test 44f: pdirops: rename tgt and rename (src) ======================================================================== 22:20:19 (1767842419) [ 2990.528255] Lustre: DEBUG MARKER: == sanityn test 44g: pdirops: rename tgt vs getattr ======================================================================== 22:20:25 (1767842425) [ 2996.677157] Lustre: DEBUG MARKER: == sanityn test 44h: pdirops: rename tgt vs readdir ======================================================================== 22:20:31 (1767842431) [ 3003.018708] Lustre: DEBUG MARKER: == sanityn test 44i: pdirops: rename tgt vs remote mkdir ========================================================== 22:20:38 (1767842438) [ 3009.979389] Lustre: DEBUG MARKER: == sanityn test 45a: rename,mkdir doesn't return -EEXIST ======================================================================== 22:20:45 (1767842445) [ 3023.839162] Lustre: lustre-OST0000-osc-ffff9e9dc54a2000: disconnect after 22s idle [ 3023.841721] Lustre: Skipped 4 previous similar messages [ 3071.649165] Lustre: DEBUG MARKER: == sanityn test 45b: pdirops: rename src vs create ======================================================================== 22:21:46 (1767842506) [ 3077.169676] Lustre: DEBUG MARKER: == sanityn test 45c: pdirops: rename src vs link ======================================================================== 22:21:52 (1767842512) [ 3082.710737] Lustre: DEBUG MARKER: == sanityn test 45d: pdirops: rename src vs unlink ======================================================================== 22:21:58 (1767842518) [ 3088.273435] Lustre: DEBUG MARKER: == sanityn test 45e: pdirops: rename src and rename (tgt) ======================================================================== 22:22:03 (1767842523) [ 3093.922364] Lustre: DEBUG MARKER: == sanityn test 45f: pdirops: rename src and rename (src) ======================================================================== 22:22:09 (1767842529) [ 3099.611844] Lustre: DEBUG MARKER: == sanityn test 45g: pdirops: rename src vs getattr ======================================================================== 22:22:14 (1767842534) [ 3105.316389] Lustre: DEBUG MARKER: == sanityn test 45h: pdirops: unlink vs readdir ======================================================================== 22:22:20 (1767842540) [ 3110.614107] Lustre: DEBUG MARKER: == sanityn test 45i: pdirops: rename src vs remote mkdir ========================================================== 22:22:25 (1767842545) [ 3116.426764] Lustre: DEBUG MARKER: == sanityn test 45j: read vs rename ====================== 22:22:31 (1767842551) [ 3597.804339] Lustre: DEBUG MARKER: == sanityn test 46a: pdirops: link vs mkdir ======================================================================== 22:30:33 (1767843033) [ 3603.070781] Lustre: DEBUG MARKER: == sanityn test 46b: pdirops: link vs create ======================================================================== 22:30:38 (1767843038) [ 3608.095860] Lustre: DEBUG MARKER: == sanityn test 46c: pdirops: link vs link ======================================================================== 22:30:43 (1767843043) [ 3613.263841] Lustre: DEBUG MARKER: == sanityn test 46d: pdirops: link vs unlink ======================================================================== 22:30:48 (1767843048) [ 3618.363140] Lustre: DEBUG MARKER: == sanityn test 46e: pdirops: link and rename (tgt) ======================================================================== 22:30:53 (1767843053) [ 3623.473507] Lustre: DEBUG MARKER: == sanityn test 46f: pdirops: link and rename (src) ======================================================================== 22:30:58 (1767843058) [ 3628.670906] Lustre: DEBUG MARKER: == sanityn test 46g: pdirops: link vs getattr ======================================================================== 22:31:04 (1767843064) [ 3633.777645] Lustre: DEBUG MARKER: == sanityn test 46h: pdirops: link vs readdir ======================================================================== 22:31:09 (1767843069) [ 3638.917113] Lustre: DEBUG MARKER: == sanityn test 46i: pdirops: link vs remote mkdir ======= 22:31:14 (1767843074) [ 3644.232967] Lustre: DEBUG MARKER: == sanityn test 47a: pdirops: remote mkdir vs mkdir ====== 22:31:19 (1767843079) [ 3649.416534] Lustre: DEBUG MARKER: == sanityn test 47b: pdirops: remote mkdir vs create ===== 22:31:24 (1767843084) [ 3655.547478] Lustre: DEBUG MARKER: == sanityn test 47c: pdirops: remote mkdir vs link ======= 22:31:30 (1767843090) [ 3660.903545] Lustre: DEBUG MARKER: == sanityn test 47d: pdirops: remote mkdir vs unlink ===== 22:31:36 (1767843096) [ 3666.330793] Lustre: DEBUG MARKER: == sanityn test 47e: pdirops: remote mkdir and rename (tgt) ========================================================== 22:31:41 (1767843101) [ 3671.779948] Lustre: DEBUG MARKER: == sanityn test 47f: pdirops: remote mkdir and rename (src) ========================================================== 22:31:47 (1767843107) [ 3676.851303] Lustre: DEBUG MARKER: == sanityn test 47g: pdirops: remote mkdir vs getattr ==== 22:31:52 (1767843112) [ 3682.679362] Lustre: DEBUG MARKER: == sanityn test 50: osc lvb attrs: enqueue vs. CP AST ======================================================================== 22:31:58 (1767843118) [ 3682.761044] LustreError: 5696:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 410 sleeping for 2000ms [ 3684.847120] LustreError: 5696:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 410 awake [ 3689.822297] Lustre: DEBUG MARKER: == sanityn test 51a: layout lock: refresh layout should work ========================================================== 22:32:05 (1767843125) [ 3693.716325] Lustre: DEBUG MARKER: == sanityn test 51b: layout lock: glimpse should be able to restart if layout changed ========================================================== 22:32:09 (1767843129) [ 3693.782738] LustreError: 264320:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 3697.839139] LustreError: 264320:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 1404 awake [ 3697.845798] LustreError: 264320:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 3701.903130] LustreError: 264320:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 1404 awake [ 3701.915204] LustreError: 264327:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 3705.975118] LustreError: 264327:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 1404 awake [ 3708.011906] Lustre: DEBUG MARKER: == sanityn test 51c: layout lock: IT_LAYOUT blocked and correct layout can be returned ========================================================== 22:32:23 (1767843143) [ 3714.454560] Lustre: DEBUG MARKER: == sanityn test 51d: layout lock: losing layout lock should clean up memory map region ========================================================== 22:32:29 (1767843149) [ 3717.456086] Lustre: DEBUG MARKER: == sanityn test 51e: lfs getstripe does not break leases, part 2 ========================================================== 22:32:32 (1767843152) [ 3721.334069] Lustre: DEBUG MARKER: == sanityn test 54: rename locking ======================= 22:32:36 (1767843156) [ 3730.399203] Lustre: lustre-OST0001-osc-ffff9e9dc54a2000: disconnect after 22s idle [ 3730.401646] Lustre: Skipped 1 previous similar message [ 3745.582863] Lustre: DEBUG MARKER: == sanityn test 55a: rename vs unlink target dir ========= 22:33:00 (1767843180) [ 3753.170357] Lustre: DEBUG MARKER: == sanityn test 55b: rename vs unlink source dir ========= 22:33:08 (1767843188) [ 3760.663864] Lustre: DEBUG MARKER: == sanityn test 55c: rename vs unlink orphan target dir == 22:33:16 (1767843196) [ 3773.386726] Lustre: DEBUG MARKER: == sanityn test 55d: rename file vs link ================= 22:33:28 (1767843208) [ 3783.023563] Lustre: DEBUG MARKER: == sanityn test 60: Verify data_version behaviour ======== 22:33:38 (1767843218) [ 3785.090686] Lustre: DEBUG MARKER: == sanityn test 70a: cd directory [ 3787.089350] Lustre: DEBUG MARKER: == sanityn test 70b: remove files after calling rm_entry ========================================================== 22:33:42 (1767843222) [ 3789.534698] Lustre: DEBUG MARKER: == sanityn test 71a: correct file map just after write operation is finished ========================================================== 22:33:44 (1767843224) [ 3790.121659] Lustre: DEBUG MARKER: SKIP: sanityn test_71a ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 3790.733766] Lustre: DEBUG MARKER: == sanityn test 71b: check fiemap support for stripecount > 1 ========================================================== 22:33:46 (1767843226) [ 3791.334780] Lustre: DEBUG MARKER: SKIP: sanityn test_71b ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 3791.884917] Lustre: DEBUG MARKER: == sanityn test 71c: check FIEMAP_EXTENT_LAST flag with different extents number ========================================================== 22:33:47 (1767843227) [ 3792.386978] Lustre: DEBUG MARKER: SKIP: sanityn test_71c support only ldiskfs ost [ 3792.971282] Lustre: DEBUG MARKER: == sanityn test 71d: fiemap corruption test with fm_extent_count=0 ========================================================== 22:33:48 (1767843228) [ 3793.567895] Lustre: DEBUG MARKER: SKIP: sanityn test_71d ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 3794.124099] Lustre: DEBUG MARKER: == sanityn test 72: getxattr/setxattr cache should be consistent between nodes ========================================================== 22:33:49 (1767843229) [ 3796.548849] Lustre: DEBUG MARKER: == sanityn test 73: getxattr should not cause xattr lock cancellation ========================================================== 22:33:51 (1767843231) [ 3798.947482] Lustre: DEBUG MARKER: == sanityn test 74: flock deadlock: different mounts ======================================================================== 22:33:54 (1767843234) [ 3802.005418] LustreError: 11-0: lustre-MDT0000-mdc-ffff9e9dcb7c1000: operation ldlm_enqueue to node 192.168.203.158@tcp failed: rc = -35 [ 3802.009668] LustreError: Skipped 1 previous similar message [ 3804.813707] Lustre: DEBUG MARKER: == sanityn test 75: osc: upcall after unuse lock============================================================================= 22:34:00 (1767843240) [ 3804.928674] LustreError: 2266:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 31d sleeping for 2000ms [ 3807.007047] LustreError: 2266:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 31d awake [ 3811.811168] Lustre: DEBUG MARKER: == sanityn test 76: Verify MDT open_files listing ======== 22:34:07 (1767843247) [ 3856.833564] Lustre: DEBUG MARKER: == sanityn test 77a: check FIFO NRS policy =============== 22:34:52 (1767843292) [ 3859.899125] Lustre: DEBUG MARKER: == sanityn test 77b: check CRR-N NRS policy ============== 22:34:55 (1767843295) [ 3863.990560] Lustre: DEBUG MARKER: == sanityn test 77c: check ORR NRS policy ================ 22:34:59 (1767843299) [ 3869.979077] Lustre: DEBUG MARKER: == sanityn test 77d: check TRR nrs policy ================ 22:35:05 (1767843305) [ 3875.697198] Lustre: DEBUG MARKER: == sanityn test 77e: check TBF NID nrs policy ============ 22:35:10 (1767843310) [ 3883.876785] Lustre: DEBUG MARKER: == sanityn test 77f: check TBF JobID nrs policy ========== 22:35:19 (1767843319) [ 3892.005781] Lustre: DEBUG MARKER: == sanityn test 77g: Change TBF type directly ============ 22:35:27 (1767843327) [ 3895.719259] Lustre: DEBUG MARKER: == sanityn test 77h: Wrong policy name should report error, not LBUG ========================================================== 22:35:31 (1767843331) [ 3899.786887] Lustre: DEBUG MARKER: == sanityn test 77i: Change rank of TBF rule ============= 22:35:35 (1767843335) [ 3907.760754] Lustre: DEBUG MARKER: == sanityn test 77j: check TBF-OPCode NRS policy ========= 22:35:43 (1767843343) [ 3957.402674] Lustre: DEBUG MARKER: == sanityn test 77ja: check TBF-UID/GID NRS policy ======= 22:36:32 (1767843392) [ 4096.010170] Lustre: DEBUG MARKER: == sanityn test 77k: check TBF policy with NID/JobID/OPCode expression ========================================================== 22:38:51 (1767843531) [ 4355.039117] Lustre: lustre-OST0001-osc-ffff9e9dc54a2000: disconnect after 23s idle [ 4355.041219] Lustre: Skipped 15 previous similar messages [ 4455.531058] Lustre: DEBUG MARKER: == sanityn test 77l: check the output of NRS policies for generic TBF ========================================================== 22:44:50 (1767843890) [ 4458.818284] Lustre: DEBUG MARKER: == sanityn test 77m: check NRS Delay slows write RPC processing ========================================================== 22:44:54 (1767843894) [ 4509.428651] Lustre: DEBUG MARKER: == sanityn test 77n: check wildcard support for TBF JobID NRS policy ========================================================== 22:45:44 (1767843944) [ 4582.321437] Lustre: DEBUG MARKER: == sanityn test 77o: Changing rank should not panic ====== 22:46:57 (1767844017) [ 4586.185790] Lustre: DEBUG MARKER: == sanityn test 77q: Parallel TBF rule definitions should not panic ========================================================== 22:47:01 (1767844021) [ 4623.665334] Lustre: DEBUG MARKER: == sanityn test 77p: Check validity of rule names for TBF policies ========================================================== 22:47:38 (1767844058) [ 4635.373336] Lustre: DEBUG MARKER: == sanityn test 78: Enable policy and specify tunings right away ========================================================== 22:47:50 (1767844070) [ 4638.534853] Lustre: DEBUG MARKER: == sanityn test 79: xattr: intent error ================== 22:47:53 (1767844073) [ 4651.162634] Lustre: DEBUG MARKER: == sanityn test 80a: migrate directory when some children is being opened ========================================================== 22:48:06 (1767844086) [ 4652.370220] LustreError: 302760:0:(file.c:246:ll_close_inode_openhandle()) lustre-clilmv-ffff9e9dc54a2000: inode [0x200000402:0x7b0:0x0] mdc close failed: rc = -116 [ 4652.989986] LustreError: 302761:0:(file.c:246:ll_close_inode_openhandle()) lustre-clilmv-ffff9e9dc54a2000: inode [0x240000402:0x5f8:0x0] mdc close failed: rc = -116 [ 4652.993346] LustreError: 302761:0:(file.c:246:ll_close_inode_openhandle()) Skipped 1 previous similar message [ 4655.396636] Lustre: DEBUG MARKER: == sanityn test 80b: Accessing directory during migration ========================================================== 22:48:10 (1767844090) [ 4660.880788] LustreError: 303616:0:(file.c:246:ll_close_inode_openhandle()) lustre-clilmv-ffff9e9dc54a2000: inode [0x200000402:0x7ff:0x0] mdc close failed: rc = -116 [ 4660.885344] LustreError: 303616:0:(file.c:246:ll_close_inode_openhandle()) Skipped 9 previous similar messages [ 4662.718746] Lustre: dir [0x240000402:0x673:0x0] stripe 1 readdir failed: -2, directory is partially accessed! [ 4663.508263] Lustre: dir [0x240000402:0x67f:0x0] stripe 1 readdir failed: -2, directory is partially accessed! [ 4664.503987] LustreError: 303750:0:(file.c:246:ll_close_inode_openhandle()) lustre-clilmv-ffff9e9dc54a2000: inode [0x200000402:0x820:0x0] mdc close failed: rc = -116 [ 4668.712028] LustreError: 303949:0:(file.c:246:ll_close_inode_openhandle()) lustre-clilmv-ffff9e9dc54a2000: inode [0x240000402:0x6ca:0x0] mdc close failed: rc = -116 [ 4668.717948] LustreError: 303949:0:(file.c:246:ll_close_inode_openhandle()) Skipped 2 previous similar messages [ 4671.470313] Lustre: dir [0x200000402:0x864:0x0] stripe 1 readdir failed: -2, directory is partially accessed! [ 4676.869964] Lustre: dir [0x240000402:0x72c:0x0] stripe 1 readdir failed: -2, directory is partially accessed! [ 4680.529127] LustreError: 304540:0:(file.c:246:ll_close_inode_openhandle()) lustre-clilmv-ffff9e9dc54a2000: inode [0x240000402:0x748:0x0] mdc close failed: rc = -116 [ 4683.635103] Lustre: dir [0x240000402:0x77d:0x0] stripe 1 readdir failed: -2, directory is partially accessed! [ 4683.637214] Lustre: Skipped 1 previous similar message [ 4693.398273] Lustre: dir [0x240000402:0x7f0:0x0] stripe 1 readdir failed: -2, directory is partially accessed! [ 4693.400504] Lustre: Skipped 3 previous similar messages [ 4698.952361] LustreError: 305387:0:(file.c:246:ll_close_inode_openhandle()) lustre-clilmv-ffff9e9dc54a2000: inode [0x240000402:0x853:0x0] mdc close failed: rc = -116 [ 4698.957748] LustreError: 305387:0:(file.c:246:ll_close_inode_openhandle()) Skipped 7 previous similar messages [ 4711.353700] Lustre: dir [0x240000402:0x8f9:0x0] stripe 1 readdir failed: -2, directory is partially accessed! [ 4711.358062] Lustre: Skipped 3 previous similar messages [ 4717.444497] Lustre: DEBUG MARKER: == sanityn test 81a: rename and stat under striped directory ========================================================== 22:49:12 (1767844152) [ 4719.536740] Lustre: DEBUG MARKER: == sanityn test 81b: rename under striped directory doesn't deadlock ========================================================== 22:49:14 (1767844154) [ 4746.873633] Lustre: DEBUG MARKER: == sanityn test 81c: rename revoke LOOKUP lock for remote object ========================================================== 22:49:42 (1767844182) [ 4747.308781] Lustre: DEBUG MARKER: SKIP: sanityn test_81c needs >= 4 MDTs [ 4747.804969] Lustre: DEBUG MARKER: == sanityn test 82: fsetxattr and fgetxattr on orphan files ========================================================== 22:49:43 (1767844183) [ 4749.714534] Lustre: DEBUG MARKER: == sanityn test 83: access striped directory while it is being created/unlinked ========================================================== 22:49:45 (1767844185) [ 4871.743843] Lustre: DEBUG MARKER: == sanityn test 84: 0-nlink race in lu_object_find() ===== 22:51:47 (1767844307) [ 4878.781064] Lustre: DEBUG MARKER: == sanityn test 90: open/create and unlink striped directory ========================================================== 22:51:54 (1767844314) [ 5060.976209] Lustre: DEBUG MARKER: == sanityn test 91: chmod and unlink striped directory === 22:54:56 (1767844496) [ 5243.133099] Lustre: DEBUG MARKER: == sanityn test 92: create remote directory under orphan directory ========================================================== 22:57:58 (1767844678) [ 5245.208117] Lustre: DEBUG MARKER: == sanityn test 93: alloc_rr should not allocate on same ost ========================================================== 22:58:00 (1767844680) [ 5253.781250] Lustre: DEBUG MARKER: == sanityn test 94: signal vs CP callback race =========== 22:58:09 (1767844689) [ 5253.859499] Lustre: DEBUG MARKER: write [ 5253.871767] LustreError: 5705:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 312 sleeping for 5000ms [ 5255.876976] Lustre: DEBUG MARKER: kill 331000 [ 5255.878585] LustreError: 331000:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 329 sleeping for 6000ms [ 5258.967133] LustreError: 5705:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 312 awake [ 5261.911065] LustreError: 331000:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 329 awake [ 5263.891778] Lustre: DEBUG MARKER: == sanityn test 95b: Check readpage() on a page that is no longer uptodate ========================================================== 22:58:19 (1767844699) [ 5263.980201] LustreError: 331597:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 1424 sleeping for 5000ms [ 5266.063076] LustreError: 331597:0:(fail.c:144:__cfs_fail_timeout_set()) cfs_fail_timeout interrupted [ 5271.885684] Lustre: DEBUG MARKER: == sanityn test 100a: DoM: glimpse RPCs for stat without IO lock (DoM only file) ========================================================== 22:58:27 (1767844707) [ 5272.360866] Lustre: DEBUG MARKER: SKIP: sanityn test_100a Reserved for glimpse-ahead [ 5272.868702] Lustre: DEBUG MARKER: == sanityn test 100b: DoM: no glimpse RPC for stat with IO lock (DoM only file) ========================================================== 22:58:28 (1767844708) [ 5274.941774] Lustre: DEBUG MARKER: == sanityn test 100c: DoM: write vs stat without IO lock (combined file) ========================================================== 22:58:30 (1767844710) [ 5276.937787] Lustre: DEBUG MARKER: == sanityn test 100d: DoM: write+truncate vs stat without IO lock (combined file) ========================================================== 22:58:32 (1767844712) [ 5279.229159] Lustre: DEBUG MARKER: == sanityn test 100e: DoM: read on open and file size ==== 22:58:34 (1767844714) [ 5281.311673] Lustre: DEBUG MARKER: == sanityn test 101a: Discard DoM data on unlink ========= 22:58:36 (1767844716) [ 5283.343522] Lustre: DEBUG MARKER: == sanityn test 101b: Discard DoM data on rename ========= 22:58:38 (1767844718) [ 5285.547109] Lustre: DEBUG MARKER: == sanityn test 101c: Discard DoM data on close-unlink === 22:58:40 (1767844720) [ 5288.775235] Lustre: DEBUG MARKER: SKIP: sanityn test_102 skipping ALWAYS excluded test 102 [ 5289.312232] Lustre: DEBUG MARKER: == sanityn test 103: Test size correctness with lockahead ========================================================== 22:58:44 (1767844724) [ 5289.937122] Lustre: *** cfs_fail_loc=415, val=0*** [ 5296.288351] Lustre: DEBUG MARKER: == sanityn test 104: Verify that MDS stores atime/mtime/ctime during close ========================================================== 22:58:51 (1767844731) [ 5296.779546] Lustre: DEBUG MARKER: SKIP: sanityn test_104 ldiskfs only test [ 5297.332400] Lustre: DEBUG MARKER: == sanityn test 105: Glimpse and lock cancel race ======== 22:58:52 (1767844732) [ 5297.438203] LustreError: 9658:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 sleeping for 5000ms [ 5302.535107] LustreError: 9658:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 awake [ 5307.639097] LustreError: 9658:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 awake [ 5307.641105] LustreError: 9658:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 sleeping for 5000ms [ 5307.643264] LustreError: 9658:0:(fail.c:138:__cfs_fail_timeout_set()) Skipped 1 previous similar message [ 5317.839103] LustreError: 9658:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 awake [ 5317.840965] LustreError: 9658:0:(fail.c:149:__cfs_fail_timeout_set()) Skipped 1 previous similar message [ 5328.032573] LustreError: 20973:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 sleeping for 5000ms [ 5328.037027] LustreError: 20973:0:(fail.c:138:__cfs_fail_timeout_set()) Skipped 3 previous similar messages [ 5338.231097] LustreError: 20973:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 awake [ 5338.233669] LustreError: 20973:0:(fail.c:149:__cfs_fail_timeout_set()) Skipped 3 previous similar messages [ 5363.719142] LustreError: 20973:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 sleeping for 5000ms [ 5363.722310] LustreError: 20973:0:(fail.c:138:__cfs_fail_timeout_set()) Skipped 6 previous similar messages [ 5373.919141] LustreError: 20973:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 awake [ 5373.924137] LustreError: 20973:0:(fail.c:149:__cfs_fail_timeout_set()) Skipped 6 previous similar messages [ 5376.510172] Lustre: DEBUG MARKER: == sanityn test 106a: Verify the btime via statx() ======= 23:00:11 (1767844811) [ 5377.055186] Lustre: DEBUG MARKER: SKIP: sanityn test_106a Test only for ldiskfs and statx() supported [ 5377.641618] Lustre: DEBUG MARKER: == sanityn test 106b: Glimpse RPCs test for statx ======== 23:00:12 (1767844812) [ 5380.219487] Lustre: DEBUG MARKER: == sanityn test 106c: Verify statx attributes mask ======= 23:00:15 (1767844815) [ 5382.291375] Lustre: DEBUG MARKER: == sanityn test 107a: Basic grouplock conflict =========== 23:00:17 (1767844817) [ 5386.489328] Lustre: DEBUG MARKER: == sanityn test 107b: Grouplock is added to the head of waiting list ========================================================== 23:00:21 (1767844821) [ 5395.016574] Lustre: DEBUG MARKER: == sanityn test 108a: lseek: parallel updates ============ 23:00:30 (1767844830) [ 5401.661975] Lustre: DEBUG MARKER: == sanityn test 109: Race with several mount instances on 1 node ========================================================== 23:00:36 (1767844836) [ 5402.534452] Lustre: Unmounted lustre-client [ 5403.365518] Lustre: Unmounted lustre-client [ 5403.934685] Lustre: DEBUG MARKER: Iteration 1 [ 5404.098966] LustreError: 341778:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5404.099273] LustreError: 341781:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5404.104710] LustreError: 341778:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 5404.153363] Lustre: Mounted lustre-client [ 5404.154720] Lustre: Skipped 1 previous similar message [ 5404.766388] Lustre: Unmounted lustre-client [ 5405.789390] Key type lgssc unregistered [ 5405.888253] LNet: 342128:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5406.943962] LNet: Removed LNI 192.168.203.58@tcp [ 5407.185227] Key type .llcrypt unregistered [ 5407.187120] Key type ._llcrypt unregistered [ 5407.534384] alg: No test for adler32 (adler32-zlib) [ 5408.286268] Key type ._llcrypt registered [ 5408.287212] Key type .llcrypt registered [ 5408.405899] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5408.611778] Lustre: Lustre: Build Version: 2.15.8_1_g5e2d639 [ 5408.868133] LNet: Added LNI 192.168.203.58@tcp [8/256/0/180] [ 5408.869626] LNet: Accept secure, port 988 [ 5410.479113] Key type lgssc registered [ 5410.946639] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5414.906155] Lustre: DEBUG MARKER: Iteration 2 [ 5415.009214] LustreError: 342922:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5415.010819] LustreError: 342923:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5415.015965] LustreError: 342922:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 5416.063676] Lustre: Mounted lustre-client [ 5416.578708] Lustre: Unmounted lustre-client [ 5417.394485] Key type lgssc unregistered [ 5417.500562] LNet: 343272:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5418.528599] LNet: Removed LNI 192.168.203.58@tcp [ 5418.884754] Key type .llcrypt unregistered [ 5418.886788] Key type ._llcrypt unregistered [ 5419.191517] alg: No test for adler32 (adler32-zlib) [ 5419.943333] Key type ._llcrypt registered [ 5419.944277] Key type .llcrypt registered [ 5420.055722] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5420.222392] Lustre: Lustre: Build Version: 2.15.8_1_g5e2d639 [ 5420.333951] LNet: Added LNI 192.168.203.58@tcp [8/256/0/180] [ 5420.336464] LNet: Accept secure, port 988 [ 5421.943137] Key type lgssc registered [ 5422.408909] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5426.205335] Lustre: DEBUG MARKER: Iteration 3 [ 5426.302931] LustreError: 344075:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5426.304055] LustreError: 344076:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5426.309189] LustreError: 344075:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 5427.363290] Lustre: Mounted lustre-client [ 5427.832787] Lustre: Unmounted lustre-client [ 5428.865217] Key type lgssc unregistered [ 5428.984582] LNet: 344426:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5430.048467] LNet: Removed LNI 192.168.203.58@tcp [ 5430.410494] Key type .llcrypt unregistered [ 5430.412315] Key type ._llcrypt unregistered [ 5430.720381] alg: No test for adler32 (adler32-zlib) [ 5431.472411] Key type ._llcrypt registered [ 5431.473447] Key type .llcrypt registered [ 5431.544423] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5431.650040] Lustre: Lustre: Build Version: 2.15.8_1_g5e2d639 [ 5431.731986] LNet: Added LNI 192.168.203.58@tcp [8/256/0/180] [ 5431.733515] LNet: Accept secure, port 988 [ 5433.335100] Key type lgssc registered [ 5433.833879] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5438.032175] Lustre: DEBUG MARKER: Iteration 4 [ 5438.140846] LustreError: 345219:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5438.140888] LustreError: 345218:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5438.145673] LustreError: 345219:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 5439.202731] Lustre: Mounted lustre-client [ 5439.203781] Lustre: Skipped 1 previous similar message [ 5439.700125] Lustre: Unmounted lustre-client [ 5439.701138] Lustre: Skipped 1 previous similar message [ 5440.910777] Key type lgssc unregistered [ 5441.038630] LNet: 345567:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5442.080548] LNet: Removed LNI 192.168.203.58@tcp [ 5442.438433] Key type .llcrypt unregistered [ 5442.441247] Key type ._llcrypt unregistered [ 5442.762963] alg: No test for adler32 (adler32-zlib) [ 5443.514255] Key type ._llcrypt registered [ 5443.515180] Key type .llcrypt registered [ 5443.585878] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5443.676234] Lustre: Lustre: Build Version: 2.15.8_1_g5e2d639 [ 5443.748368] LNet: Added LNI 192.168.203.58@tcp [8/256/0/180] [ 5443.750132] LNet: Accept secure, port 988 [ 5445.327142] Key type lgssc registered [ 5445.873887] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5450.051496] Lustre: DEBUG MARKER: Iteration 5 [ 5450.187940] LustreError: 346359:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5450.188180] LustreError: 346360:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5450.192698] LustreError: 346359:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 5451.239617] Lustre: Mounted lustre-client [ 5451.240862] Lustre: Skipped 1 previous similar message [ 5451.733646] Lustre: Unmounted lustre-client [ 5452.665757] Key type lgssc unregistered [ 5452.762609] LNet: 346708:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5453.792444] LNet: Removed LNI 192.168.203.58@tcp [ 5454.086978] Key type .llcrypt unregistered [ 5454.088627] Key type ._llcrypt unregistered [ 5454.321552] alg: No test for adler32 (adler32-zlib) [ 5455.072285] Key type ._llcrypt registered [ 5455.073190] Key type .llcrypt registered [ 5455.136417] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5455.227479] Lustre: Lustre: Build Version: 2.15.8_1_g5e2d639 [ 5455.301601] LNet: Added LNI 192.168.203.58@tcp [8/256/0/180] [ 5455.303441] LNet: Accept secure, port 988 [ 5456.879094] Key type lgssc registered [ 5457.301158] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5461.069369] Lustre: DEBUG MARKER: Iteration 6 [ 5461.168641] LustreError: 347502:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5461.168707] LustreError: 347503:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5461.174606] LustreError: 347502:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 5462.221905] Lustre: Mounted lustre-client [ 5462.224146] Lustre: Skipped 1 previous similar message [ 5462.775064] Lustre: Unmounted lustre-client [ 5463.637903] Key type lgssc unregistered [ 5463.729560] LNet: 347850:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5464.736334] LNet: Removed LNI 192.168.203.58@tcp [ 5465.138062] Key type .llcrypt unregistered [ 5465.140282] Key type ._llcrypt unregistered [ 5465.641206] alg: No test for adler32 (adler32-zlib) [ 5466.392365] Key type ._llcrypt registered [ 5466.393445] Key type .llcrypt registered [ 5466.470540] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5466.580076] Lustre: Lustre: Build Version: 2.15.8_1_g5e2d639 [ 5466.665934] LNet: Added LNI 192.168.203.58@tcp [8/256/0/180] [ 5466.668425] LNet: Accept secure, port 988 [ 5468.263123] Key type lgssc registered [ 5468.790127] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5472.693250] Lustre: DEBUG MARKER: Iteration 7 [ 5472.790337] LustreError: 348643:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5472.790766] LustreError: 348644:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5472.795287] LustreError: 348643:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 5473.840839] Lustre: Mounted lustre-client [ 5473.841769] Lustre: Skipped 1 previous similar message [ 5474.390112] Lustre: Unmounted lustre-client [ 5474.391216] Lustre: Skipped 1 previous similar message [ 5475.335869] Key type lgssc unregistered [ 5475.433416] LNet: 348990:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5476.447765] LNet: Removed LNI 192.168.203.58@tcp [ 5476.678586] Key type .llcrypt unregistered [ 5476.680060] Key type ._llcrypt unregistered [ 5476.938737] alg: No test for adler32 (adler32-zlib) [ 5477.691281] Key type ._llcrypt registered [ 5477.692270] Key type .llcrypt registered [ 5477.760352] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5477.863467] Lustre: Lustre: Build Version: 2.15.8_1_g5e2d639 [ 5477.939260] LNet: Added LNI 192.168.203.58@tcp [8/256/0/180] [ 5477.941798] LNet: Accept secure, port 988 [ 5479.535097] Key type lgssc registered [ 5479.990426] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5483.594327] Lustre: DEBUG MARKER: Iteration 8 [ 5483.725451] LustreError: 349783:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5483.726489] LustreError: 349784:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5483.730937] LustreError: 349783:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 5484.779432] Lustre: Mounted lustre-client [ 5484.780880] Lustre: Skipped 1 previous similar message [ 5485.303337] Lustre: Unmounted lustre-client [ 5486.257529] Key type lgssc unregistered [ 5486.361497] LNet: 350131:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5487.391764] LNet: Removed LNI 192.168.203.58@tcp [ 5487.623524] Key type .llcrypt unregistered [ 5487.624624] Key type ._llcrypt unregistered [ 5487.911897] alg: No test for adler32 (adler32-zlib) [ 5488.663292] Key type ._llcrypt registered [ 5488.664250] Key type .llcrypt registered [ 5488.732774] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5488.867820] Lustre: Lustre: Build Version: 2.15.8_1_g5e2d639 [ 5488.965843] LNet: Added LNI 192.168.203.58@tcp [8/256/0/180] [ 5488.967623] LNet: Accept secure, port 988 [ 5490.551199] Key type lgssc registered [ 5491.139297] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5494.536888] Lustre: DEBUG MARKER: Iteration 9 [ 5494.634349] LustreError: 350936:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5494.634400] LustreError: 350937:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5494.638876] LustreError: 350936:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 5495.692215] Lustre: Mounted lustre-client [ 5495.693248] Lustre: Skipped 1 previous similar message [ 5496.198108] Lustre: Unmounted lustre-client [ 5497.244765] Key type lgssc unregistered [ 5497.359533] LNet: 351287:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5498.400266] LNet: Removed LNI 192.168.203.58@tcp [ 5498.680442] Key type .llcrypt unregistered [ 5498.681634] Key type ._llcrypt unregistered [ 5498.961490] alg: No test for adler32 (adler32-zlib) [ 5499.712307] Key type ._llcrypt registered [ 5499.713746] Key type .llcrypt registered [ 5499.784557] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5499.885767] Lustre: Lustre: Build Version: 2.15.8_1_g5e2d639 [ 5499.957318] LNet: Added LNI 192.168.203.58@tcp [8/256/0/180] [ 5499.959747] LNet: Accept secure, port 988 [ 5501.543077] Key type lgssc registered [ 5501.880450] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5505.245401] Lustre: DEBUG MARKER: Iteration 10 [ 5505.348561] LustreError: 352080:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5505.351200] LustreError: 352079:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5505.354254] LustreError: 352080:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 5506.403586] Lustre: Mounted lustre-client [ 5506.888081] Lustre: Unmounted lustre-client [ 5507.842383] Key type lgssc unregistered [ 5507.928510] LNet: 352429:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5508.959841] LNet: Removed LNI 192.168.203.58@tcp [ 5509.261670] Key type .llcrypt unregistered [ 5509.263406] Key type ._llcrypt unregistered [ 5509.556673] alg: No test for adler32 (adler32-zlib) [ 5510.309295] Key type ._llcrypt registered [ 5510.310624] Key type .llcrypt registered [ 5510.374882] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5510.470684] Lustre: Lustre: Build Version: 2.15.8_1_g5e2d639 [ 5510.539419] LNet: Added LNI 192.168.203.58@tcp [8/256/0/180] [ 5510.540847] LNet: Accept secure, port 988 [ 5512.119131] Key type lgssc registered [ 5512.696303] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5517.282252] Lustre: DEBUG MARKER: Iteration 11 [ 5517.415400] LustreError: 353236:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5517.415615] LustreError: 353235:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5517.421461] LustreError: 353236:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 5518.475129] Lustre: Mounted lustre-client [ 5518.980834] Lustre: Unmounted lustre-client [ 5520.260398] Key type lgssc unregistered [ 5520.402604] LNet: 353584:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5521.440457] LNet: Removed LNI 192.168.203.58@tcp [ 5521.809139] Key type .llcrypt unregistered [ 5521.810954] Key type ._llcrypt unregistered [ 5522.129058] alg: No test for adler32 (adler32-zlib) [ 5522.884269] Key type ._llcrypt registered [ 5522.885211] Key type .llcrypt registered [ 5522.953167] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5523.057990] Lustre: Lustre: Build Version: 2.15.8_1_g5e2d639 [ 5523.133669] LNet: Added LNI 192.168.203.58@tcp [8/256/0/180] [ 5523.136106] LNet: Accept secure, port 988 [ 5524.711150] Key type lgssc registered [ 5525.290673] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5529.425714] Lustre: DEBUG MARKER: Iteration 12 [ 5529.556273] LustreError: 354376:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5529.556449] LustreError: 354377:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5529.561995] LustreError: 354376:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 5530.612934] Lustre: Mounted lustre-client [ 5530.614023] Lustre: Skipped 1 previous similar message [ 5531.132749] Lustre: Unmounted lustre-client [ 5531.133774] Lustre: Skipped 1 previous similar message [ 5532.040169] Key type lgssc unregistered [ 5532.139540] LNet: 354724:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5533.151712] LNet: Removed LNI 192.168.203.58@tcp [ 5533.432145] Key type .llcrypt unregistered [ 5533.434207] Key type ._llcrypt unregistered [ 5533.779049] alg: No test for adler32 (adler32-zlib) [ 5534.530269] Key type ._llcrypt registered [ 5534.531214] Key type .llcrypt registered [ 5534.606640] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5534.748083] Lustre: Lustre: Build Version: 2.15.8_1_g5e2d639 [ 5534.841826] LNet: Added LNI 192.168.203.58@tcp [8/256/0/180] [ 5534.844527] LNet: Accept secure, port 988 [ 5536.439126] Key type lgssc registered [ 5536.815502] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5540.568673] Lustre: DEBUG MARKER: Iteration 13 [ 5540.667519] LustreError: 355516:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5540.668133] LustreError: 355517:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5540.671526] LustreError: 355516:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 5541.713689] Lustre: Mounted lustre-client [ 5541.715056] Lustre: Skipped 1 previous similar message [ 5542.209926] Lustre: Unmounted lustre-client [ 5542.211016] Lustre: Skipped 1 previous similar message [ 5543.070807] Key type lgssc unregistered [ 5543.165612] LNet: 355863:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5544.224363] LNet: Removed LNI 192.168.203.58@tcp [ 5544.522932] Key type .llcrypt unregistered [ 5544.524802] Key type ._llcrypt unregistered [ 5544.763603] alg: No test for adler32 (adler32-zlib) [ 5545.514269] Key type ._llcrypt registered [ 5545.515493] Key type .llcrypt registered [ 5545.582829] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5545.704631] Lustre: Lustre: Build Version: 2.15.8_1_g5e2d639 [ 5545.790821] LNet: Added LNI 192.168.203.58@tcp [8/256/0/180] [ 5545.792950] LNet: Accept secure, port 988 [ 5547.391164] Key type lgssc registered [ 5547.964581] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5551.724830] Lustre: DEBUG MARKER: Iteration 14 [ 5551.853144] LustreError: 356658:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5551.853180] LustreError: 356659:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5551.859767] LustreError: 356658:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 5552.913442] Lustre: Mounted lustre-client [ 5552.914753] Lustre: Skipped 1 previous similar message [ 5553.472864] Lustre: Unmounted lustre-client [ 5554.557703] Key type lgssc unregistered [ 5554.691760] LNet: 357008:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5555.744475] LNet: Removed LNI 192.168.203.58@tcp [ 5556.022620] Key type .llcrypt unregistered [ 5556.024059] Key type ._llcrypt unregistered [ 5556.323724] alg: No test for adler32 (adler32-zlib) [ 5557.075271] Key type ._llcrypt registered [ 5557.076220] Key type .llcrypt registered [ 5557.153685] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5557.283791] Lustre: Lustre: Build Version: 2.15.8_1_g5e2d639 [ 5557.376248] LNet: Added LNI 192.168.203.58@tcp [8/256/0/180] [ 5557.377719] LNet: Accept secure, port 988 [ 5558.959138] Key type lgssc registered [ 5559.511689] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5563.241422] Lustre: DEBUG MARKER: Iteration 15 [ 5563.365378] LustreError: 357801:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5563.365440] LustreError: 357802:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5563.370768] LustreError: 357801:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 5564.426038] Lustre: Mounted lustre-client [ 5564.427067] Lustre: Skipped 1 previous similar message [ 5564.996172] Lustre: Unmounted lustre-client [ 5564.997488] Lustre: Skipped 1 previous similar message [ 5565.981125] Key type lgssc unregistered [ 5566.084678] LNet: 358150:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5567.136652] LNet: Removed LNI 192.168.203.58@tcp [ 5567.448712] Key type .llcrypt unregistered [ 5567.449735] Key type ._llcrypt unregistered [ 5567.670987] alg: No test for adler32 (adler32-zlib) [ 5568.422337] Key type ._llcrypt registered [ 5568.423264] Key type .llcrypt registered [ 5568.500491] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5568.610506] Lustre: Lustre: Build Version: 2.15.8_1_g5e2d639 [ 5568.703365] LNet: Added LNI 192.168.203.58@tcp [8/256/0/180] [ 5568.705209] LNet: Accept secure, port 988 [ 5570.287143] Key type lgssc registered [ 5570.690643] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5574.411630] Lustre: DEBUG MARKER: Iteration 16 [ 5574.509849] LustreError: 358943:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5574.509900] LustreError: 358944:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5574.515617] LustreError: 358943:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 5575.562908] Lustre: Mounted lustre-client [ 5575.563909] Lustre: Skipped 1 previous similar message [ 5576.099787] Lustre: Unmounted lustre-client [ 5577.018619] Key type lgssc unregistered [ 5577.116303] LNet: 359290:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5578.143765] LNet: Removed LNI 192.168.203.58@tcp [ 5578.367235] Key type .llcrypt unregistered [ 5578.368312] Key type ._llcrypt unregistered [ 5578.655590] alg: No test for adler32 (adler32-zlib) [ 5579.407287] Key type ._llcrypt registered [ 5579.408250] Key type .llcrypt registered [ 5579.481454] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5579.606748] Lustre: Lustre: Build Version: 2.15.8_1_g5e2d639 [ 5579.696915] LNet: Added LNI 192.168.203.58@tcp [8/256/0/180] [ 5579.698672] LNet: Accept secure, port 988 [ 5581.287135] Key type lgssc registered [ 5581.849145] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5585.679185] Lustre: DEBUG MARKER: Iteration 17 [ 5585.783300] LustreError: 360083:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5585.783346] LustreError: 360082:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5585.788937] LustreError: 360083:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 5586.839294] Lustre: Mounted lustre-client [ 5586.840937] Lustre: Skipped 1 previous similar message [ 5587.322167] Lustre: Unmounted lustre-client [ 5588.395652] Key type lgssc unregistered [ 5588.522762] LNet: 360430:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5589.536385] LNet: Removed LNI 192.168.203.58@tcp [ 5589.831299] Key type .llcrypt unregistered [ 5589.832924] Key type ._llcrypt unregistered [ 5590.138663] alg: No test for adler32 (adler32-zlib) [ 5590.890292] Key type ._llcrypt registered [ 5590.891228] Key type .llcrypt registered [ 5590.960883] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5591.058803] Lustre: Lustre: Build Version: 2.15.8_1_g5e2d639 [ 5591.134173] LNet: Added LNI 192.168.203.58@tcp [8/256/0/180] [ 5591.135722] LNet: Accept secure, port 988 [ 5592.711105] Key type lgssc registered [ 5593.076875] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5596.424584] Lustre: DEBUG MARKER: Iteration 18 [ 5596.520216] LustreError: 361222:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5596.520265] LustreError: 361223:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5596.524875] LustreError: 361222:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 5597.576280] Lustre: Mounted lustre-client [ 5597.577577] Lustre: Skipped 1 previous similar message [ 5598.076781] Lustre: Unmounted lustre-client [ 5599.045641] Key type lgssc unregistered [ 5599.151440] LNet: 361571:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5600.159661] LNet: Removed LNI 192.168.203.58@tcp [ 5600.448456] Key type .llcrypt unregistered [ 5600.449843] Key type ._llcrypt unregistered [ 5600.710549] alg: No test for adler32 (adler32-zlib) [ 5601.464279] Key type ._llcrypt registered [ 5601.465178] Key type .llcrypt registered [ 5601.535955] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5601.635130] Lustre: Lustre: Build Version: 2.15.8_1_g5e2d639 [ 5601.710177] LNet: Added LNI 192.168.203.58@tcp [8/256/0/180] [ 5601.711575] LNet: Accept secure, port 988 [ 5603.295120] Key type lgssc registered [ 5603.762053] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5607.560804] Lustre: DEBUG MARKER: Iteration 19 [ 5607.676855] LustreError: 362364:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5607.677073] LustreError: 362365:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5607.682184] LustreError: 362364:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 5608.728039] Lustre: Mounted lustre-client [ 5608.729102] Lustre: Skipped 1 previous similar message [ 5609.232611] Lustre: Unmounted lustre-client [ 5610.156868] Key type lgssc unregistered [ 5610.264519] LNet: 362709:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5611.295735] LNet: Removed LNI 192.168.203.58@tcp [ 5611.529631] Key type .llcrypt unregistered [ 5611.530833] Key type ._llcrypt unregistered [ 5611.780723] alg: No test for adler32 (adler32-zlib) [ 5612.534311] Key type ._llcrypt registered [ 5612.535249] Key type .llcrypt registered [ 5612.598298] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5612.692891] Lustre: Lustre: Build Version: 2.15.8_1_g5e2d639 [ 5612.767476] LNet: Added LNI 192.168.203.58@tcp [8/256/0/180] [ 5612.768959] LNet: Accept secure, port 988 [ 5614.343111] Key type lgssc registered [ 5614.900405] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5619.262196] Lustre: DEBUG MARKER: Iteration 20 [ 5619.396272] LustreError: 363503:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5619.396520] LustreError: 363502:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5619.402512] LustreError: 363503:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 5620.449672] Lustre: Mounted lustre-client [ 5620.451391] Lustre: Skipped 1 previous similar message [ 5620.984901] Lustre: Unmounted lustre-client [ 5621.928193] Key type lgssc unregistered [ 5622.051650] LNet: 363851:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5623.071855] LNet: Removed LNI 192.168.203.58@tcp [ 5623.457580] Key type .llcrypt unregistered [ 5623.459744] Key type ._llcrypt unregistered [ 5623.747818] alg: No test for adler32 (adler32-zlib) [ 5624.499333] Key type ._llcrypt registered [ 5624.500548] Key type .llcrypt registered [ 5624.562720] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5624.668006] Lustre: Lustre: Build Version: 2.15.8_1_g5e2d639 [ 5624.763603] LNet: Added LNI 192.168.203.58@tcp [8/256/0/180] [ 5624.765385] LNet: Accept secure, port 988 [ 5626.351060] Key type lgssc registered [ 5626.832108] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5630.479527] Lustre: DEBUG MARKER: Iteration 21 [ 5630.598078] LustreError: 364651:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5630.598132] LustreError: 364652:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5630.603936] LustreError: 364651:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 5631.649719] Lustre: Mounted lustre-client [ 5632.119497] Lustre: Unmounted lustre-client [ 5632.121867] Lustre: Skipped 1 previous similar message [ 5633.141395] Key type lgssc unregistered [ 5633.234474] LNet: 365000:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5634.271561] LNet: Removed LNI 192.168.203.58@tcp [ 5634.512713] Key type .llcrypt unregistered [ 5634.514027] Key type ._llcrypt unregistered [ 5634.750650] alg: No test for adler32 (adler32-zlib) [ 5635.501298] Key type ._llcrypt registered [ 5635.502249] Key type .llcrypt registered [ 5635.579052] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5635.699882] Lustre: Lustre: Build Version: 2.15.8_1_g5e2d639 [ 5635.791353] LNet: Added LNI 192.168.203.58@tcp [8/256/0/180] [ 5635.792776] LNet: Accept secure, port 988 [ 5637.383118] Key type lgssc registered [ 5637.890369] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5641.787982] Lustre: DEBUG MARKER: Iteration 22 [ 5641.881952] LustreError: 365794:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5641.881990] LustreError: 365793:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5641.888353] LustreError: 365794:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 5642.932821] Lustre: Mounted lustre-client [ 5642.934443] Lustre: Skipped 1 previous similar message [ 5643.446161] Lustre: Unmounted lustre-client [ 5644.338310] Key type lgssc unregistered [ 5644.439464] LNet: 366144:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5645.471710] LNet: Removed LNI 192.168.203.58@tcp [ 5645.688124] Key type .llcrypt unregistered [ 5645.689906] Key type ._llcrypt unregistered [ 5645.943125] alg: No test for adler32 (adler32-zlib) [ 5646.694295] Key type ._llcrypt registered [ 5646.695199] Key type .llcrypt registered [ 5646.754246] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5646.840174] Lustre: Lustre: Build Version: 2.15.8_1_g5e2d639 [ 5646.907497] LNet: Added LNI 192.168.203.58@tcp [8/256/0/180] [ 5646.909569] LNet: Accept secure, port 988 [ 5648.487107] Key type lgssc registered [ 5648.835079] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5651.978638] Lustre: DEBUG MARKER: Iteration 23 [ 5652.075140] LustreError: 366936:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5652.076280] LustreError: 366937:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5652.080140] LustreError: 366936:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 5653.122131] Lustre: Mounted lustre-client [ 5653.671732] Lustre: Unmounted lustre-client [ 5654.574381] Key type lgssc unregistered [ 5654.689562] LNet: 367288:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5655.711544] LNet: Removed LNI 192.168.203.58@tcp [ 5655.925884] Key type .llcrypt unregistered [ 5655.926757] Key type ._llcrypt unregistered [ 5656.186330] alg: No test for adler32 (adler32-zlib) [ 5656.937248] Key type ._llcrypt registered [ 5656.938161] Key type .llcrypt registered [ 5657.003961] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5657.100386] Lustre: Lustre: Build Version: 2.15.8_1_g5e2d639 [ 5657.172873] LNet: Added LNI 192.168.203.58@tcp [8/256/0/180] [ 5657.175540] LNet: Accept secure, port 988 [ 5658.767175] Key type lgssc registered [ 5659.303745] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5662.635300] Lustre: DEBUG MARKER: Iteration 24 [ 5662.723419] LustreError: 368081:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5662.723512] LustreError: 368082:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5662.729514] LustreError: 368081:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 5663.775769] Lustre: Mounted lustre-client [ 5663.777241] Lustre: Skipped 1 previous similar message [ 5664.255503] Lustre: Unmounted lustre-client [ 5664.256413] Lustre: Skipped 1 previous similar message [ 5665.152094] Key type lgssc unregistered [ 5665.250537] LNet: 368428:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5666.271753] LNet: Removed LNI 192.168.203.58@tcp [ 5666.471836] Key type .llcrypt unregistered [ 5666.473424] Key type ._llcrypt unregistered [ 5666.721628] alg: No test for adler32 (adler32-zlib) [ 5667.474260] Key type ._llcrypt registered [ 5667.475195] Key type .llcrypt registered [ 5667.535980] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5667.630360] Lustre: Lustre: Build Version: 2.15.8_1_g5e2d639 [ 5667.717343] LNet: Added LNI 192.168.203.58@tcp [8/256/0/180] [ 5667.719116] LNet: Accept secure, port 988 [ 5669.303165] Key type lgssc registered [ 5669.744308] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5673.309808] Lustre: DEBUG MARKER: Iteration 25 [ 5673.403989] LustreError: 369220:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5673.404038] LustreError: 369221:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5673.408101] LustreError: 369220:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 5674.460697] Lustre: Mounted lustre-client [ 5674.461627] Lustre: Skipped 1 previous similar message [ 5675.030851] Lustre: Unmounted lustre-client [ 5675.032083] Lustre: Skipped 1 previous similar message [ 5676.012508] Key type lgssc unregistered [ 5676.126593] LNet: 369570:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5677.151494] LNet: Removed LNI 192.168.203.58@tcp [ 5677.360385] Key type .llcrypt unregistered [ 5677.361326] Key type ._llcrypt unregistered [ 5677.659909] alg: No test for adler32 (adler32-zlib) [ 5678.412356] Key type ._llcrypt registered [ 5678.413897] Key type .llcrypt registered [ 5678.478174] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5678.572664] Lustre: Lustre: Build Version: 2.15.8_1_g5e2d639 [ 5678.648566] LNet: Added LNI 192.168.203.58@tcp [8/256/0/180] [ 5678.650045] LNet: Accept secure, port 988 [ 5680.231103] Key type lgssc registered [ 5680.856680] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5684.794530] Lustre: DEBUG MARKER: Iteration 26 [ 5684.924127] LustreError: 370363:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5684.924612] LustreError: 370364:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5684.930230] LustreError: 370363:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 5685.983649] Lustre: Mounted lustre-client [ 5686.467689] Lustre: Unmounted lustre-client [ 5686.468990] Lustre: Skipped 1 previous similar message [ 5687.292967] Key type lgssc unregistered [ 5687.407481] LNet: 370712:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5688.416636] LNet: Removed LNI 192.168.203.58@tcp [ 5688.762504] Key type .llcrypt unregistered [ 5688.764720] Key type ._llcrypt unregistered [ 5689.074359] alg: No test for adler32 (adler32-zlib) [ 5689.825306] Key type ._llcrypt registered [ 5689.826304] Key type .llcrypt registered [ 5689.926575] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5690.071088] Lustre: Lustre: Build Version: 2.15.8_1_g5e2d639 [ 5690.155826] LNet: Added LNI 192.168.203.58@tcp [8/256/0/180] [ 5690.157512] LNet: Accept secure, port 988 [ 5691.735182] Key type lgssc registered [ 5692.323169] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5696.319253] Lustre: DEBUG MARKER: Iteration 27 [ 5696.446253] LustreError: 371504:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5696.448079] LustreError: 371506:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5696.452070] LustreError: 371504:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 5697.508197] Lustre: Mounted lustre-client [ 5697.509093] Lustre: Skipped 1 previous similar message [ 5698.017789] Lustre: Unmounted lustre-client [ 5698.865865] Key type lgssc unregistered [ 5698.963391] LNet: 371856:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5699.999700] LNet: Removed LNI 192.168.203.58@tcp [ 5700.232518] Key type .llcrypt unregistered [ 5700.234052] Key type ._llcrypt unregistered [ 5700.587700] alg: No test for adler32 (adler32-zlib) [ 5701.340410] Key type ._llcrypt registered [ 5701.341874] Key type .llcrypt registered [ 5701.410418] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5701.508843] Lustre: Lustre: Build Version: 2.15.8_1_g5e2d639 [ 5701.584483] LNet: Added LNI 192.168.203.58@tcp [8/256/0/180] [ 5701.586472] LNet: Accept secure, port 988 [ 5703.175087] Key type lgssc registered [ 5703.531624] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5707.016933] Lustre: DEBUG MARKER: Iteration 28 [ 5707.109169] LustreError: 372648:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5707.109228] LustreError: 372650:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5707.115240] LustreError: 372648:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 5708.161875] Lustre: Mounted lustre-client [ 5708.163725] Lustre: Skipped 1 previous similar message [ 5708.698110] Lustre: Unmounted lustre-client [ 5709.823624] Key type lgssc unregistered [ 5709.928418] LNet: 372998:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5710.944574] LNet: Removed LNI 192.168.203.58@tcp [ 5711.315766] Key type .llcrypt unregistered [ 5711.317873] Key type ._llcrypt unregistered [ 5711.663881] alg: No test for adler32 (adler32-zlib) [ 5712.415309] Key type ._llcrypt registered [ 5712.416310] Key type .llcrypt registered [ 5712.483037] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5712.584734] Lustre: Lustre: Build Version: 2.15.8_1_g5e2d639 [ 5712.671937] LNet: Added LNI 192.168.203.58@tcp [8/256/0/180] [ 5712.673560] LNet: Accept secure, port 988 [ 5714.255088] Key type lgssc registered [ 5714.834489] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5719.048517] Lustre: DEBUG MARKER: Iteration 29 [ 5719.185091] LustreError: 373794:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5719.185147] LustreError: 373793:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5719.190766] LustreError: 373794:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 5720.236439] Lustre: Mounted lustre-client [ 5720.237720] Lustre: Skipped 1 previous similar message [ 5720.737026] Lustre: Unmounted lustre-client [ 5720.737930] Lustre: Skipped 1 previous similar message [ 5721.960124] Key type lgssc unregistered [ 5722.121461] LNet: 374141:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5723.168722] LNet: Removed LNI 192.168.203.58@tcp [ 5723.478178] Key type .llcrypt unregistered [ 5723.480349] Key type ._llcrypt unregistered [ 5723.795219] alg: No test for adler32 (adler32-zlib) [ 5724.546251] Key type ._llcrypt registered [ 5724.547201] Key type .llcrypt registered [ 5724.668912] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5724.860850] Lustre: Lustre: Build Version: 2.15.8_1_g5e2d639 [ 5724.986481] LNet: Added LNI 192.168.203.58@tcp [8/256/0/180] [ 5724.990270] LNet: Accept secure, port 988 [ 5726.607153] Key type lgssc registered [ 5727.327618] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5731.833853] Lustre: DEBUG MARKER: Iteration 30 [ 5731.963759] LustreError: 374933:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5731.963773] LustreError: 374934:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5731.968343] LustreError: 374933:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=5000 [ 5733.010137] Lustre: Mounted lustre-client [ 5733.011553] Lustre: Skipped 1 previous similar message [ 5733.581113] Lustre: Unmounted lustre-client [ 5734.450464] Key type lgssc unregistered [ 5734.542466] LNet: 375282:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5735.583807] LNet: Removed LNI 192.168.203.58@tcp [ 5735.813635] Key type .llcrypt unregistered [ 5735.814613] Key type ._llcrypt unregistered [ 5736.032185] alg: No test for adler32 (adler32-zlib) [ 5736.783307] Key type ._llcrypt registered [ 5736.784276] Key type .llcrypt registered [ 5736.871943] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5736.975514] Lustre: Lustre: Build Version: 2.15.8_1_g5e2d639 [ 5737.055140] LNet: Added LNI 192.168.203.58@tcp [8/256/0/180] [ 5737.057727] LNet: Accept secure, port 988 [ 5738.655088] Key type lgssc registered [ 5739.196627] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5742.940163] Lustre: DEBUG MARKER: Iteration 31 [ 5743.092708] LustreError: 376075:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5743.092754] LustreError: 376076:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5743.099978] LustreError: 376075:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 5744.147686] Lustre: Mounted lustre-client [ 5744.672481] Lustre: Unmounted lustre-client [ 5745.737893] Key type lgssc unregistered [ 5745.842618] LNet: 376421:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5746.912134] LNet: Removed LNI 192.168.203.58@tcp [ 5747.123820] Key type .llcrypt unregistered [ 5747.124919] Key type ._llcrypt unregistered [ 5747.441055] alg: No test for adler32 (adler32-zlib) [ 5748.192293] Key type ._llcrypt registered [ 5748.193689] Key type .llcrypt registered [ 5748.267863] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5748.399255] Lustre: Lustre: Build Version: 2.15.8_1_g5e2d639 [ 5748.507333] LNet: Added LNI 192.168.203.58@tcp [8/256/0/180] [ 5748.508869] LNet: Accept secure, port 988 [ 5750.103080] Key type lgssc registered [ 5750.473690] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5754.469577] Lustre: DEBUG MARKER: Iteration 32 [ 5754.599925] LustreError: 377214:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5754.602500] LustreError: 377215:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5754.605615] LustreError: 377214:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 5755.652222] Lustre: Mounted lustre-client [ 5755.653210] Lustre: Skipped 1 previous similar message [ 5756.162080] Lustre: Unmounted lustre-client [ 5757.026920] Key type lgssc unregistered [ 5757.118371] LNet: 377561:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5758.175735] LNet: Removed LNI 192.168.203.58@tcp [ 5758.388825] Key type .llcrypt unregistered [ 5758.390026] Key type ._llcrypt unregistered [ 5758.654888] alg: No test for adler32 (adler32-zlib) [ 5759.406338] Key type ._llcrypt registered [ 5759.407203] Key type .llcrypt registered [ 5759.481078] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5759.578245] Lustre: Lustre: Build Version: 2.15.8_1_g5e2d639 [ 5759.654148] LNet: Added LNI 192.168.203.58@tcp [8/256/0/180] [ 5759.655644] LNet: Accept secure, port 988 [ 5761.239133] Key type lgssc registered [ 5761.865210] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5766.020315] Lustre: DEBUG MARKER: Iteration 33 [ 5766.130440] LustreError: 378353:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5766.130501] LustreError: 378354:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5766.135961] LustreError: 378353:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 5767.183145] Lustre: Mounted lustre-client [ 5767.185071] Lustre: Skipped 1 previous similar message [ 5767.737776] Lustre: Unmounted lustre-client [ 5768.831972] Key type lgssc unregistered [ 5768.935490] LNet: 378702:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5769.951811] LNet: Removed LNI 192.168.203.58@tcp [ 5770.247852] Key type .llcrypt unregistered [ 5770.249888] Key type ._llcrypt unregistered [ 5770.503119] alg: No test for adler32 (adler32-zlib) [ 5771.255341] Key type ._llcrypt registered [ 5771.256269] Key type .llcrypt registered [ 5771.331502] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5771.439480] Lustre: Lustre: Build Version: 2.15.8_1_g5e2d639 [ 5771.524671] LNet: Added LNI 192.168.203.58@tcp [8/256/0/180] [ 5771.526987] LNet: Accept secure, port 988 [ 5773.119096] Key type lgssc registered [ 5773.693124] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5777.612926] Lustre: DEBUG MARKER: Iteration 34 [ 5777.749956] LustreError: 379507:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5777.749991] LustreError: 379509:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5777.755402] LustreError: 379507:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 5778.800919] Lustre: Mounted lustre-client [ 5779.357125] Lustre: Unmounted lustre-client [ 5779.358139] Lustre: Skipped 1 previous similar message [ 5780.576942] Key type lgssc unregistered [ 5780.703708] LNet: 379855:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5781.728573] LNet: Removed LNI 192.168.203.58@tcp [ 5782.025889] Key type .llcrypt unregistered [ 5782.027641] Key type ._llcrypt unregistered [ 5782.423247] alg: No test for adler32 (adler32-zlib) [ 5783.174255] Key type ._llcrypt registered [ 5783.175168] Key type .llcrypt registered [ 5783.235417] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5783.324391] Lustre: Lustre: Build Version: 2.15.8_1_g5e2d639 [ 5783.394896] LNet: Added LNI 192.168.203.58@tcp [8/256/0/180] [ 5783.396588] LNet: Accept secure, port 988 [ 5784.975060] Key type lgssc registered [ 5785.325379] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5789.212373] Lustre: DEBUG MARKER: Iteration 35 [ 5789.309123] LustreError: 380648:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5789.312319] LustreError: 380649:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5789.315772] LustreError: 380648:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 5790.359432] Lustre: Mounted lustre-client [ 5790.845913] Lustre: Unmounted lustre-client [ 5791.715620] Key type lgssc unregistered [ 5791.803496] LNet: 380995:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5792.863831] LNet: Removed LNI 192.168.203.58@tcp [ 5793.081312] Key type .llcrypt unregistered [ 5793.082526] Key type ._llcrypt unregistered [ 5793.319565] alg: No test for adler32 (adler32-zlib) [ 5794.070285] Key type ._llcrypt registered [ 5794.071366] Key type .llcrypt registered [ 5794.142048] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5794.244948] Lustre: Lustre: Build Version: 2.15.8_1_g5e2d639 [ 5794.331986] LNet: Added LNI 192.168.203.58@tcp [8/256/0/180] [ 5794.333643] LNet: Accept secure, port 988 [ 5795.935099] Key type lgssc registered [ 5796.461317] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5800.547172] Lustre: DEBUG MARKER: Iteration 36 [ 5800.730866] LustreError: 381789:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5800.732102] LustreError: 381788:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5800.738777] LustreError: 381789:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 5801.796852] Lustre: Mounted lustre-client [ 5801.798029] Lustre: Skipped 1 previous similar message [ 5802.279678] Lustre: Unmounted lustre-client [ 5803.268229] Key type lgssc unregistered [ 5803.373624] LNet: 382136:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5804.383951] LNet: Removed LNI 192.168.203.58@tcp [ 5804.745715] Key type .llcrypt unregistered [ 5804.747985] Key type ._llcrypt unregistered [ 5805.051708] alg: No test for adler32 (adler32-zlib) [ 5805.802338] Key type ._llcrypt registered [ 5805.803249] Key type .llcrypt registered [ 5805.871542] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5805.992299] Lustre: Lustre: Build Version: 2.15.8_1_g5e2d639 [ 5806.081546] LNet: Added LNI 192.168.203.58@tcp [8/256/0/180] [ 5806.083518] LNet: Accept secure, port 988 [ 5807.663138] Key type lgssc registered [ 5808.211063] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5813.231118] Lustre: DEBUG MARKER: Iteration 37 [ 5813.363949] LustreError: 382941:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5813.364058] LustreError: 382942:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5813.370508] LustreError: 382941:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [ 5814.433988] Lustre: Mounted lustre-client [ 5815.081662] Lustre: Unmounted lustre-client [ 5816.396764] Key type lgssc unregistered [ 5816.536072] LNet: 383290:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5817.567896] LNet: Removed LNI 192.168.203.58@tcp [ 5817.779271] Key type .llcrypt unregistered [ 5817.780213] Key type ._llcrypt unregistered [ 5818.031868] alg: No test for adler32 (adler32-zlib) [ 5818.783325] Key type ._llcrypt registered [ 5818.784262] Key type .llcrypt registered [ 5818.900694] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5819.071840] Lustre: Lustre: Build Version: 2.15.8_1_g5e2d639 [ 5819.188406] LNet: Added LNI 192.168.203.58@tcp [8/256/0/180] [ 5819.190346] LNet: Accept secure, port 988 [ 5820.791148] Key type lgssc registered [ 5821.471770] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5825.434351] Lustre: DEBUG MARKER: Iteration 38 [ 5825.590358] LustreError: 384083:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5825.590396] LustreError: 384084:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5825.594519] LustreError: 384083:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 5826.654511] Lustre: Mounted lustre-client [ 5826.655469] Lustre: Skipped 1 previous similar message [ 5827.353679] Lustre: Unmounted lustre-client [ 5828.305636] Key type lgssc unregistered [ 5828.433992] LNet: 384431:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5829.472572] LNet: Removed LNI 192.168.203.58@tcp [ 5829.851343] Key type .llcrypt unregistered [ 5829.853369] Key type ._llcrypt unregistered [ 5830.196600] alg: No test for adler32 (adler32-zlib) [ 5830.947321] Key type ._llcrypt registered [ 5830.948278] Key type .llcrypt registered [ 5831.020613] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5831.128206] Lustre: Lustre: Build Version: 2.15.8_1_g5e2d639 [ 5831.214059] LNet: Added LNI 192.168.203.58@tcp [8/256/0/180] [ 5831.215600] LNet: Accept secure, port 988 [ 5832.815075] Key type lgssc registered [ 5833.231123] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5837.680878] Lustre: DEBUG MARKER: Iteration 39 [ 5837.773572] LustreError: 385224:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5837.774617] LustreError: 385225:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5837.779197] LustreError: 385224:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 5838.834709] Lustre: Mounted lustre-client [ 5839.355781] Lustre: Unmounted lustre-client [ 5840.541460] Key type lgssc unregistered [ 5840.690938] LNet: 385575:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5841.761513] LNet: Removed LNI 192.168.203.58@tcp [ 5842.151309] Key type .llcrypt unregistered [ 5842.153377] Key type ._llcrypt unregistered [ 5842.519080] alg: No test for adler32 (adler32-zlib) [ 5843.271305] Key type ._llcrypt registered [ 5843.272223] Key type .llcrypt registered [ 5843.340528] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5843.443912] Lustre: Lustre: Build Version: 2.15.8_1_g5e2d639 [ 5843.525672] LNet: Added LNI 192.168.203.58@tcp [8/256/0/180] [ 5843.527475] LNet: Accept secure, port 988 [ 5845.111125] Key type lgssc registered [ 5845.733765] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5849.904137] Lustre: DEBUG MARKER: Iteration 40 [ 5850.057041] LustreError: 386368:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5850.057101] LustreError: 386369:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5850.064518] LustreError: 386368:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 5851.123480] Lustre: Mounted lustre-client [ 5851.124526] Lustre: Skipped 1 previous similar message [ 5851.806762] Lustre: Unmounted lustre-client [ 5851.808191] Lustre: Skipped 1 previous similar message [ 5852.799465] Key type lgssc unregistered [ 5852.903422] LNet: 386719:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5853.921634] LNet: Removed LNI 192.168.203.58@tcp [ 5854.274553] Key type .llcrypt unregistered [ 5854.275588] Key type ._llcrypt unregistered [ 5854.626230] alg: No test for adler32 (adler32-zlib) [ 5855.378337] Key type ._llcrypt registered [ 5855.379330] Key type .llcrypt registered [ 5855.459981] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5855.621783] Lustre: Lustre: Build Version: 2.15.8_1_g5e2d639 [ 5855.734000] LNet: Added LNI 192.168.203.58@tcp [8/256/0/180] [ 5855.737270] LNet: Accept secure, port 988 [ 5857.351056] Key type lgssc registered [ 5857.761513] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5861.458876] Lustre: DEBUG MARKER: Iteration 41 [ 5861.584086] LustreError: 387512:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5861.584131] LustreError: 387513:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5861.589688] LustreError: 387512:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 5862.640051] Lustre: Mounted lustre-client [ 5863.229586] Lustre: Unmounted lustre-client [ 5864.339085] Key type lgssc unregistered [ 5864.482128] LNet: 387861:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5865.505453] LNet: Removed LNI 192.168.203.58@tcp [ 5865.862558] Key type .llcrypt unregistered [ 5865.864416] Key type ._llcrypt unregistered [ 5866.166562] alg: No test for adler32 (adler32-zlib) [ 5866.918322] Key type ._llcrypt registered [ 5866.919226] Key type .llcrypt registered [ 5866.986361] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5867.094465] Lustre: Lustre: Build Version: 2.15.8_1_g5e2d639 [ 5867.165575] LNet: Added LNI 192.168.203.58@tcp [8/256/0/180] [ 5867.166965] LNet: Accept secure, port 988 [ 5868.751167] Key type lgssc registered [ 5869.166106] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5873.058834] Lustre: DEBUG MARKER: Iteration 42 [ 5873.161250] LustreError: 388655:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5873.161280] LustreError: 388656:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5873.166451] LustreError: 388655:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 5874.216554] Lustre: Mounted lustre-client [ 5874.217462] Lustre: Skipped 1 previous similar message [ 5874.734594] Lustre: Unmounted lustre-client [ 5875.994329] Key type lgssc unregistered [ 5876.129124] LNet: 389006:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5877.153255] LNet: Removed LNI 192.168.203.58@tcp [ 5877.521449] Key type .llcrypt unregistered [ 5877.523487] Key type ._llcrypt unregistered [ 5877.837856] alg: No test for adler32 (adler32-zlib) [ 5878.589311] Key type ._llcrypt registered [ 5878.590519] Key type .llcrypt registered [ 5878.652919] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5878.744348] Lustre: Lustre: Build Version: 2.15.8_1_g5e2d639 [ 5878.816845] LNet: Added LNI 192.168.203.58@tcp [8/256/0/180] [ 5878.818475] LNet: Accept secure, port 988 [ 5880.399068] Key type lgssc registered [ 5880.849937] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5884.639523] Lustre: DEBUG MARKER: Iteration 43 [ 5884.752569] LustreError: 389797:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5884.756949] LustreError: 389804:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5884.763074] LustreError: 389797:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [ 5885.818842] Lustre: Mounted lustre-client [ 5885.819803] Lustre: Skipped 1 previous similar message [ 5886.444034] Lustre: Unmounted lustre-client [ 5887.583341] Key type lgssc unregistered [ 5887.692474] LNet: 390147:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5888.736387] LNet: Removed LNI 192.168.203.58@tcp [ 5889.052874] Key type .llcrypt unregistered [ 5889.054162] Key type ._llcrypt unregistered [ 5889.412587] alg: No test for adler32 (adler32-zlib) [ 5890.163454] Key type ._llcrypt registered [ 5890.164615] Key type .llcrypt registered [ 5890.250173] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5890.367134] Lustre: Lustre: Build Version: 2.15.8_1_g5e2d639 [ 5890.448555] LNet: Added LNI 192.168.203.58@tcp [8/256/0/180] [ 5890.450687] LNet: Accept secure, port 988 [ 5892.039106] Key type lgssc registered [ 5892.618775] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5896.750308] Lustre: DEBUG MARKER: Iteration 44 [ 5896.886716] LustreError: 390941:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5896.886799] LustreError: 390940:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5896.890941] LustreError: 390941:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 5897.940284] Lustre: Mounted lustre-client [ 5898.435123] Lustre: Unmounted lustre-client [ 5898.436775] Lustre: Skipped 1 previous similar message [ 5899.368942] Key type lgssc unregistered [ 5899.461395] LNet: 391287:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5900.511921] LNet: Removed LNI 192.168.203.58@tcp [ 5900.745433] Key type .llcrypt unregistered [ 5900.746487] Key type ._llcrypt unregistered [ 5901.003257] alg: No test for adler32 (adler32-zlib) [ 5901.754273] Key type ._llcrypt registered [ 5901.755457] Key type .llcrypt registered [ 5901.827429] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5901.928665] Lustre: Lustre: Build Version: 2.15.8_1_g5e2d639 [ 5901.998357] LNet: Added LNI 192.168.203.58@tcp [8/256/0/180] [ 5902.001075] LNet: Accept secure, port 988 [ 5903.583090] Key type lgssc registered [ 5903.967115] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5908.137423] Lustre: DEBUG MARKER: Iteration 45 [ 5908.323509] LustreError: 392080:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5908.325106] LustreError: 392081:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5908.333516] LustreError: 392080:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [ 5909.393798] Lustre: Mounted lustre-client [ 5909.394841] Lustre: Skipped 1 previous similar message [ 5909.899392] Lustre: Unmounted lustre-client [ 5909.900390] Lustre: Skipped 1 previous similar message [ 5911.069693] Key type lgssc unregistered [ 5911.201627] LNet: 392430:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5912.224075] LNet: Removed LNI 192.168.203.58@tcp [ 5912.533066] Key type .llcrypt unregistered [ 5912.534254] Key type ._llcrypt unregistered [ 5912.904148] alg: No test for adler32 (adler32-zlib) [ 5913.655299] Key type ._llcrypt registered [ 5913.656266] Key type .llcrypt registered [ 5913.731572] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5913.863094] Lustre: Lustre: Build Version: 2.15.8_1_g5e2d639 [ 5913.952247] LNet: Added LNI 192.168.203.58@tcp [8/256/0/180] [ 5913.954467] LNet: Accept secure, port 988 [ 5915.551128] Key type lgssc registered [ 5916.163680] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5920.645605] Lustre: DEBUG MARKER: Iteration 46 [ 5920.750971] LustreError: 393222:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5920.751030] LustreError: 393223:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5920.758296] LustreError: 393222:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 5921.806445] Lustre: Mounted lustre-client [ 5921.807627] Lustre: Skipped 1 previous similar message [ 5922.323212] Lustre: Unmounted lustre-client [ 5922.324115] Lustre: Skipped 1 previous similar message [ 5923.400854] Key type lgssc unregistered [ 5923.531478] LNet: 393568:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5924.575868] LNet: Removed LNI 192.168.203.58@tcp [ 5924.942176] Key type .llcrypt unregistered [ 5924.944184] Key type ._llcrypt unregistered [ 5925.231524] alg: No test for adler32 (adler32-zlib) [ 5925.984243] Key type ._llcrypt registered [ 5925.985137] Key type .llcrypt registered [ 5926.066747] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5926.187207] Lustre: Lustre: Build Version: 2.15.8_1_g5e2d639 [ 5926.270864] LNet: Added LNI 192.168.203.58@tcp [8/256/0/180] [ 5926.272462] LNet: Accept secure, port 988 [ 5927.855174] Key type lgssc registered [ 5928.408568] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5932.674876] Lustre: DEBUG MARKER: Iteration 47 [ 5932.787031] LustreError: 394361:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5932.787060] LustreError: 394362:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5932.793126] LustreError: 394361:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 5933.846269] Lustre: Mounted lustre-client [ 5933.847186] Lustre: Skipped 1 previous similar message [ 5934.331288] Lustre: Unmounted lustre-client [ 5935.166339] Key type lgssc unregistered [ 5935.284666] LNet: 394708:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5936.351878] LNet: Removed LNI 192.168.203.58@tcp [ 5936.598020] Key type .llcrypt unregistered [ 5936.599037] Key type ._llcrypt unregistered [ 5936.961379] alg: No test for adler32 (adler32-zlib) [ 5937.713274] Key type ._llcrypt registered [ 5937.714266] Key type .llcrypt registered [ 5937.780382] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5937.876206] Lustre: Lustre: Build Version: 2.15.8_1_g5e2d639 [ 5937.951448] LNet: Added LNI 192.168.203.58@tcp [8/256/0/180] [ 5937.952934] LNet: Accept secure, port 988 [ 5939.527102] Key type lgssc registered [ 5939.873625] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5943.420935] Lustre: DEBUG MARKER: Iteration 48 [ 5943.551107] LustreError: 395502:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5943.551469] LustreError: 395503:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5943.558505] LustreError: 395502:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 5944.610693] Lustre: Mounted lustre-client [ 5944.611603] Lustre: Skipped 1 previous similar message [ 5945.124882] Lustre: Unmounted lustre-client [ 5946.068748] Key type lgssc unregistered [ 5946.196431] LNet: 395851:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5947.231512] LNet: Removed LNI 192.168.203.58@tcp [ 5947.450503] Key type .llcrypt unregistered [ 5947.451526] Key type ._llcrypt unregistered [ 5947.702416] alg: No test for adler32 (adler32-zlib) [ 5948.457342] Key type ._llcrypt registered [ 5948.458265] Key type .llcrypt registered [ 5948.568927] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5948.741381] Lustre: Lustre: Build Version: 2.15.8_1_g5e2d639 [ 5948.815954] LNet: Added LNI 192.168.203.58@tcp [8/256/0/180] [ 5948.817374] LNet: Accept secure, port 988 [ 5950.391110] Key type lgssc registered [ 5950.817405] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5954.268688] Lustre: DEBUG MARKER: Iteration 49 [ 5954.394651] LustreError: 396643:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5954.394705] LustreError: 396644:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5954.400532] LustreError: 396643:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 5955.459240] Lustre: Mounted lustre-client [ 5955.460284] Lustre: Skipped 1 previous similar message [ 5956.092183] Lustre: Unmounted lustre-client [ 5957.357262] Key type lgssc unregistered [ 5957.466950] LNet: 396995:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5958.497485] LNet: Removed LNI 192.168.203.58@tcp [ 5958.878454] Key type .llcrypt unregistered [ 5958.880286] Key type ._llcrypt unregistered [ 5959.246601] alg: No test for adler32 (adler32-zlib) [ 5959.997290] Key type ._llcrypt registered [ 5959.998170] Key type .llcrypt registered [ 5960.097669] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5960.236189] Lustre: Lustre: Build Version: 2.15.8_1_g5e2d639 [ 5960.361616] LNet: Added LNI 192.168.203.58@tcp [8/256/0/180] [ 5960.364277] LNet: Accept secure, port 988 [ 5961.975253] Key type lgssc registered [ 5962.559225] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5966.211865] Lustre: DEBUG MARKER: Iteration 50 [ 5966.300525] LustreError: 397787:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 5966.304334] LustreError: 397788:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 5966.307764] LustreError: 397787:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 5967.353517] Lustre: Mounted lustre-client [ 5967.354640] Lustre: Skipped 1 previous similar message [ 5967.887038] Lustre: Unmounted lustre-client [ 5968.885802] Key type lgssc unregistered [ 5969.004684] LNet: 398135:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5970.015916] LNet: Removed LNI 192.168.203.58@tcp [ 5970.240513] Key type .llcrypt unregistered [ 5970.241390] Key type ._llcrypt unregistered [ 5970.500652] alg: No test for adler32 (adler32-zlib) [ 5971.251300] Key type ._llcrypt registered [ 5971.252215] Key type .llcrypt registered [ 5971.317077] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5971.418458] Lustre: Lustre: Build Version: 2.15.8_1_g5e2d639 [ 5971.495650] LNet: Added LNI 192.168.203.58@tcp [8/256/0/180] [ 5971.497052] LNet: Accept secure, port 988 [ 5973.087089] Key type lgssc registered [ 5973.456419] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5977.732315] Lustre: Mounted lustre-client [ 5979.985667] Lustre: DEBUG MARKER: == sanityn test 110: do not grant another lock on resend ========================================================== 23:10:15 (1767845415) [ 5987.807096] Lustre: 399438:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1767845416/real 1767845416] req@00000000f483829c x1853720265495296/t0(0) o36->lustre-MDT0000-mdc-ffff9e9dc66b9800@192.168.203.158@tcp:12/10 lens 496/440 e 0 to 1 dl 1767845423 ref 2 fl Rpc:XQr/0/ffffffff rc 0/-1 job:'ln.0' [ 5987.812757] Lustre: lustre-MDT0000-mdc-ffff9e9dc66b9800: Connection to lustre-MDT0000 (at 192.168.203.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5987.826175] Lustre: lustre-MDT0000-mdc-ffff9e9dc66b9800: Connection restored to (at 192.168.203.158@tcp) [ 5994.975172] Lustre: 399438:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1767845423/real 1767845423] req@00000000f483829c x1853720265495296/t0(0) o36->lustre-MDT0000-mdc-ffff9e9dc66b9800@192.168.203.158@tcp:12/10 lens 496/440 e 0 to 1 dl 1767845430 ref 2 fl Rpc:XQr/2/ffffffff rc 0/-1 job:'ln.0' [ 5994.975206] Lustre: lustre-MDT0000-mdc-ffff9e9dc66b9800: Connection to lustre-MDT0000 (at 192.168.203.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5994.983423] Lustre: 399438:0:(client.c:2295:ptlrpc_expire_one_request()) Skipped 1 previous similar message [ 5994.994886] Lustre: lustre-MDT0000-mdc-ffff9e9dc66b9800: Connection restored to (at 192.168.203.158@tcp) [ 6002.143175] Lustre: 399438:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1767845430/real 1767845430] req@00000000f483829c x1853720265495296/t0(0) o36->lustre-MDT0000-mdc-ffff9e9dc66b9800@192.168.203.158@tcp:12/10 lens 496/440 e 0 to 1 dl 1767845437 ref 2 fl Rpc:XQr/2/ffffffff rc 0/-1 job:'ln.0' [ 6002.159691] Lustre: lustre-MDT0000-mdc-ffff9e9dc66b9800: Connection to lustre-MDT0000 (at 192.168.203.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6002.178542] Lustre: lustre-MDT0000-mdc-ffff9e9dc66b9800: Connection restored to (at 192.168.203.158@tcp) [ 6008.287211] Lustre: 399438:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1767845437/real 1767845437] req@00000000f483829c x1853720265495296/t0(0) o36->lustre-MDT0000-mdc-ffff9e9dc66b9800@192.168.203.158@tcp:12/10 lens 496/440 e 0 to 1 dl 1767845444 ref 2 fl Rpc:XQr/2/ffffffff rc 0/-1 job:'ln.0' [ 6008.308363] Lustre: lustre-MDT0000-mdc-ffff9e9dc66b9800: Connection to lustre-MDT0000 (at 192.168.203.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6008.328405] Lustre: lustre-MDT0000-mdc-ffff9e9dc66b9800: Connection restored to (at 192.168.203.158@tcp) [ 6015.455112] Lustre: 399438:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1767845444/real 1767845444] req@00000000f483829c x1853720265495296/t0(0) o36->lustre-MDT0000-mdc-ffff9e9dc66b9800@192.168.203.158@tcp:12/10 lens 496/440 e 0 to 1 dl 1767845451 ref 2 fl Rpc:XQr/2/ffffffff rc 0/-1 job:'ln.0' [ 6015.461497] Lustre: lustre-MDT0000-mdc-ffff9e9dc66b9800: Connection to lustre-MDT0000 (at 192.168.203.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6015.471888] Lustre: lustre-MDT0000-mdc-ffff9e9dc66b9800: Connection restored to (at 192.168.203.158@tcp) [ 6023.938048] Lustre: DEBUG MARKER: == sanityn test 111: A racy rename/link an open file should not cause fs corruption ========================================================== 23:10:59 (1767845459) [ 6030.268546] Lustre: DEBUG MARKER: == sanityn test 112: update max-inherit in default LMV === 23:11:05 (1767845465) [ 6035.339293] Lustre: DEBUG MARKER: == sanityn test 113: check servers of specified fs ======= 23:11:10 (1767845470) [ 6037.766415] Lustre: DEBUG MARKER: cleanup: ====================================================== [ 6038.359731] Lustre: DEBUG MARKER: == sanityn test complete, duration 5852 sec ============== 23:11:13 (1767845473) [ 6110.771440] Lustre: Unmounted lustre-client [ 6138.202860] Key type lgssc unregistered [ 6138.311333] LNet: 402552:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6139.361125] LNet: Removed LNI 192.168.203.58@tcp [ 6139.707643] Key type .llcrypt unregistered [ 6139.709349] Key type ._llcrypt unregistered