[ 0.000000] Linux version 4.18.0rh8.10-debug (green@maintenance) (gcc version 8.5.0 20210514 (Red Hat 8.5.0-26) (GCC)) #2 SMP Mon Jul 14 01:24:22 EDT 2025 [ 0.000000] Command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' [ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 [ 0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. [ 0.000000] signal: max sigframe size: 1776 [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bffd9fff] usable [ 0.000000] BIOS-e820: [mem 0x00000000bffda000-0x00000000bfffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x0000000146dfffff] usable [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] SMBIOS 2.8 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.16.2-1.fc38 04/01/2014 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 541093212 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f5b50-0x000f5b5f] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F5970 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE1BB7 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE1A53 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 001A13 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE1AC7 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE1B57 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE1B8F 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe1a53-0xbffe1ac6] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe1a52] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe1ac7-0xbffe1b56] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe1b57-0xbffe1b8e] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe1b8f-0xbffe1bb6] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 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.001010] APIC: Switch to symmetric I/O mode setup [ 0.002000] x2apic enabled [ 0.002000] Switched APIC routing to physical x2apic. [ 0.002013] kvm-guest: setup PV IPIs [ 0.005000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.005000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.005020] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.006006] pid_max: default: 32768 minimum: 301 [ 0.007113] LSM: Security Framework initializing [ 0.008056] Yama: becoming mindful. [ 0.009023] SELinux: Initializing. [ 0.010045] *** VALIDATE selinux *** [ 0.019244] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.023686] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.024137] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.025091] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.026098] *** VALIDATE tmpfs *** [ 0.027437] *** VALIDATE proc *** [ 0.029181] *** VALIDATE cgroup *** [ 0.030007] *** VALIDATE cgroup2 *** [ 0.031252] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.032165] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.033006] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.034024] Spectre V2 : User space: Vulnerable [ 0.035007] Speculative Store Bypass: Vulnerable [ 0.038366] debug: unmapping init [mem 0xffffffff8aa59000-0xffffffff8aa60fff] [ 0.040122] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.041633] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.042020] ... version: 2 [ 0.043008] ... bit width: 48 [ 0.044008] ... generic registers: 4 [ 0.045010] ... value mask: 0000ffffffffffff [ 0.046011] ... max period: 00007fffffffffff [ 0.047010] ... fixed-purpose events: 3 [ 0.048008] ... event mask: 000000070000000f [ 0.049274] rcu: Hierarchical SRCU implementation. [ 0.051349] smp: Bringing up secondary CPUs ... [ 0.052451] x86: Booting SMP configuration: [ 0.053013] .... node #0, CPUs: #1 #2 #3 [ 0.089528] smp: Brought up 1 node, 4 CPUs [ 0.091013] smpboot: Max logical packages: 1 [ 0.092012] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.133018] node 0 deferred pages initialised in 36ms [ 0.139096] devtmpfs: initialized [ 0.140974] x86/mm: Memory block size: 128MB [ 0.142981] gcov: version magic: 0x41383552 [ 0.144339] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.146084] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.149299] pinctrl core: initialized pinctrl subsystem [ 0.151341] [ 0.151856] ************************************************************* [ 0.156015] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.158136] ** ** [ 0.161014] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.164016] ** ** [ 0.167011] ** This means that this kernel is built to expose internal ** [ 0.169010] ** IOMMU data structures, which may compromise security on ** [ 0.171008] ** your system. ** [ 0.172006] ** ** [ 0.174009] ** If you see this message and you are not debugging the ** [ 0.176008] ** kernel, report this immediately to your vendor! ** [ 0.178017] ** ** [ 0.180007] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.182008] ************************************************************* [ 0.184768] NET: Registered protocol family 16 [ 0.186496] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.188078] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.189059] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.193017] cpuidle: using governor menu [ 0.195083] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.197581] PCI: Using configuration type 1 for base access [ 0.199137] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.209006] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.210040] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.213862] cryptd: max_cpu_qlen set to 1000 [ 0.217823] ACPI: Added _OSI(Module Device) [ 0.218000] ACPI: Added _OSI(Processor Device) [ 0.218000] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.218008] ACPI: Added _OSI(Processor Aggregator Device) [ 0.222540] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.227569] ACPI: Interpreter enabled [ 0.228044] ACPI: PM: (supports S0 S3 S4 S5) [ 0.229007] ACPI: Using IOAPIC for interrupt routing [ 0.230068] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.233379] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.244027] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.245021] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.247011] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.250062] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.254856] acpiphp: Slot [2] registered [ 0.255000] acpiphp: Slot [3] registered [ 0.255000] acpiphp: Slot [4] registered [ 0.257059] acpiphp: Slot [5] registered [ 0.258128] acpiphp: Slot [6] registered [ 0.259000] acpiphp: Slot [7] registered [ 0.259000] acpiphp: Slot [8] registered [ 0.259062] acpiphp: Slot [9] registered [ 0.260000] acpiphp: Slot [10] registered [ 0.260079] acpiphp: Slot [11] registered [ 0.261000] acpiphp: Slot [12] registered [ 0.262065] acpiphp: Slot [13] registered [ 0.264975] acpiphp: Slot [14] registered [ 0.265063] acpiphp: Slot [15] registered [ 0.268118] acpiphp: Slot [16] registered [ 0.269061] acpiphp: Slot [17] registered [ 0.270074] acpiphp: Slot [18] registered [ 0.273356] acpiphp: Slot [19] registered [ 0.274062] acpiphp: Slot [20] registered [ 0.277116] acpiphp: Slot [21] registered [ 0.280111] acpiphp: Slot [22] registered [ 0.281072] acpiphp: Slot [23] registered [ 0.282112] acpiphp: Slot [24] registered [ 0.283081] acpiphp: Slot [25] registered [ 0.286137] acpiphp: Slot [26] registered [ 0.287000] acpiphp: Slot [27] registered [ 0.287000] acpiphp: Slot [28] registered [ 0.287000] acpiphp: Slot [29] registered [ 0.287000] acpiphp: Slot [30] registered [ 0.287126] acpiphp: Slot [31] registered [ 0.288000] PCI host bridge to bus 0000:00 [ 0.290016] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.291015] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.293014] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.297019] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.298012] pci_bus 0000:00: root bus resource [mem 0x180000000-0x1ffffffff window] [ 0.299038] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.300000] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.314431] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.316000] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.338853] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.347044] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.349009] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.352015] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.360019] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.362435] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.363095] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.365038] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.367553] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.372022] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.395015] pci 0000:00:02.0: reg 0x20: [mem 0xfebf4000-0xfebf7fff 64bit pref] [ 0.397000] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.414228] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.422018] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.426016] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.437020] pci 0000:00:05.0: reg 0x20: [mem 0xfebf8000-0xfebfbfff 64bit pref] [ 0.448998] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.453018] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.462018] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.484016] pci 0000:00:06.0: reg 0x20: [mem 0xfebfc000-0xfebfffff 64bit pref] [ 0.512802] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.520360] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.530381] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.532423] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.536269] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.550044] iommu: Default domain type: Passthrough [ 0.553000] SCSI subsystem initialized [ 0.554124] ACPI: bus type USB registered [ 0.557044] usbcore: registered new interface driver usbfs [ 0.559116] usbcore: registered new interface driver hub [ 0.561133] usbcore: registered new device driver usb [ 0.562191] pps_core: LinuxPPS API ver. 1 registered [ 0.565013] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.571076] PTP clock support registered [ 0.577421] EDAC MC: Ver: 3.0.0 [ 0.581149] PCI: Using ACPI for IRQ routing [ 0.587336] NetLabel: Initializing [ 0.589009] NetLabel: domain hash size = 128 [ 0.590000] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.590061] NetLabel: unlabeled traffic allowed by default [ 0.591046] vgaarb: loaded [ 0.597098] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.599012] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.619728] clocksource: Switched to clocksource kvm-clock [ 0.853076] VFS: Disk quotas dquot_6.6.0 [ 0.854392] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.856397] *** VALIDATE ramfs *** [ 0.857419] *** VALIDATE hugetlbfs *** [ 0.858669] pnp: PnP ACPI init [ 0.860821] pnp: PnP ACPI: found 6 devices [ 0.879267] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.889485] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.892496] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.895473] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.898092] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.900692] pci_bus 0000:00: resource 8 [mem 0x180000000-0x1ffffffff window] [ 0.912959] NET: Registered protocol family 2 [ 0.924886] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.935564] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.944775] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.950260] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.954354] TCP: Hash tables configured (established 65536 bind 65536) [ 0.957197] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.960212] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.962941] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.965548] NET: Registered protocol family 1 [ 0.969477] RPC: Registered named UNIX socket transport module. [ 0.971786] RPC: Registered udp transport module. [ 0.973105] RPC: Registered tcp transport module. [ 0.974373] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.976615] NET: Registered protocol family 44 [ 0.978169] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.980097] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.981552] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.983140] PCI: CLS 0 bytes, default 64 [ 0.984411] Unpacking initramfs... [ 3.904970] debug: unmapping init [mem 0xffff9b81bcc64000-0xffff9b81bffcffff] [ 3.912726] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 3.933441] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 3.948413] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 5.248776] Initialise system trusted keyrings [ 5.254368] Key type blacklist registered [ 5.260693] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 5.274774] zbud: loaded [ 5.280661] *** VALIDATE nfs *** [ 5.285930] *** VALIDATE nfs4 *** [ 5.288170] pstore: using deflate compression [ 5.299510] Platform Keyring initialized [ 5.548624] NET: Registered protocol family 38 [ 5.550480] Key type asymmetric registered [ 5.556330] Asymmetric key parser 'x509' registered [ 5.558128] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 5.563950] io scheduler mq-deadline registered [ 5.568771] io scheduler kyber registered [ 5.570297] io scheduler bfq registered [ 5.574467] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 5.583265] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 5.588382] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 5.590707] ACPI: Power Button [PWRF] [ 5.756291] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 5.981883] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 6.202712] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 6.245679] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 6.298096] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 6.310674] Non-volatile memory driver v1.3 [ 6.312428] Linux agpgart interface v0.103 [ 6.367143] virtio_blk virtio1: [vda] 67976 512-byte logical blocks (34.8 MB/33.2 MiB) [ 6.372699] vda: detected capacity change from 0 to 34803712 [ 6.398552] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 6.405407] vdb: detected capacity change from 0 to 1073741824 [ 6.413422] libphy: Fixed MDIO Bus: probed [ 6.438449] usbcore: registered new interface driver usbserial_generic [ 6.444080] usbserial: USB Serial support registered for generic [ 6.454716] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 6.465444] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 6.466881] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 6.478093] mousedev: PS/2 mouse device common for all mice [ 6.488363] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 6.490249] rtc_cmos 00:05: RTC can wake from S4 [ 6.507238] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 6.518169] rtc_cmos 00:05: registered as rtc0 [ 6.518239] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 6.518273] intel_pstate: CPU model not supported [ 6.519579] hid: raw HID events driver (C) Jiri Kosina [ 6.519752] usbcore: registered new interface driver usbhid [ 6.519756] usbhid: USB HID core driver [ 6.519871] drop_monitor: Initializing network drop monitor service [ 6.519997] Initializing XFRM netlink socket [ 6.520381] NET: Registered protocol family 10 [ 6.535414] Segment Routing with IPv6 [ 6.553776] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 6.561737] NET: Registered protocol family 17 [ 6.567013] mpls_gso: MPLS GSO support [ 6.621612] RAS: Correctable Errors collector initialized. [ 6.623827] AVX version of gcm_enc/dec engaged. [ 6.632576] AES CTR mode by8 optimization enabled [ 6.859690] sched_clock: Marking stable (6859647209, 0)->(8011134486, -1151487277) [ 6.875832] registered taskstats version 1 [ 6.881192] Loading compiled-in X.509 certificates [ 6.887789] zswap: loaded using pool lzo/zbud [ 6.948343] Key type big_key registered [ 6.983093] Key type encrypted registered [ 6.984782] ima: No TPM chip found, activating TPM-bypass! [ 6.989573] ima: Allocated hash algorithm: sha1 [ 6.991290] ima: No architecture policies found [ 6.996845] evm: Initialising EVM extended attributes: [ 6.999223] evm: security.selinux [ 7.001122] evm: security.ima [ 7.004347] evm: security.capability [ 7.005555] evm: HMAC attrs: 0x1 [ 7.014246] rtc_cmos 00:05: setting system clock to 2025-10-22 08:14:48 UTC (1761120888) [ 7.030416] debug: unmapping init [mem 0xffffffff8ba03000-0xffffffff8bbfffff] [ 7.041795] debug: unmapping init [mem 0xffffffff8a782000-0xffffffff8aa58fff] [ 7.054213] Write protecting the kernel read-only data: 28672k [ 7.058733] debug: unmapping init [mem 0xffffffff88e03000-0xffffffff88ffffff] [ 7.062877] debug: unmapping init [mem 0xffffffff89714000-0xffffffff897fffff] [ 7.134429] 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) [ 7.152304] systemd[1]: Detected virtualization kvm. [ 7.159667] systemd[1]: Detected architecture x86-64. [ 7.161197] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 7.205583] systemd[1]: No hostname configured. [ 7.207418] systemd[1]: Set hostname to . [ 7.209400] random: systemd: uninitialized urandom read (16 bytes read) [ 7.215800] systemd[1]: Initializing machine ID from random generator. [ 7.670243] random: systemd: uninitialized urandom read (16 bytes read) [ 7.674075] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ 7.678471] random: systemd: uninitialized urandom read (16 bytes read) [ 7.681857] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. [ 7.695971] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Timers. [ OK ] Listening on Journal Socket. Starting Create Volatile Files and Directories... [ OK ] Started Memstrack Anylazing Service. Starting Apply Kernel Variables... Starting Create list of required st…ce nodes for the current kernel... Starting Setup Virtual Console... Starting Journal Service... [ OK ] Reached target Sockets. [ OK ] Reached target Slices. [ OK ] Reached target Initrd Root Device. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Setup Virtual Console. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 10.030951] device-mapper: uevent: version 1.0.3 [ 10.036622] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting dracut initqueue hook... [ 12.125224] virtio_net virtio0 ens2: renamed from eth0 [ 12.616745] scsi host0: ata_piix [ 12.755604] scsi host1: ata_piix [ 12.756838] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 12.758890] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 17.581492] random: crng init done [ 17.582826] random: 7 urandom warning(s) missed due to ratelimiting [ 20.389534] dracut-initqueue[588]: 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... [ 22.382554] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped target Timers. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ OK ] Stopped target Sockets. [ OK ] Stopped target System Initialization. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Swap. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 25.499181] printk: systemd: 26 output lines suppressed due to ratelimiting [ 26.038762] SELinux: Disabled at runtime. [ 26.128748] 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) [ 26.145465] systemd[1]: Detected virtualization kvm. [ 26.148176] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 27.661881] systemd[1]: initrd-switch-root.service: Succeeded. [ 27.665980] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 27.680976] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 27.694487] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 27.706643] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 27.725829] systemd[1]: Starting Journal Service... Starting Journal Service... [ 27.762370] systemd[1]: Listening on RPCbind Server Activation Socket. [ OK ] Listening on RPCbind Server Activation Socket. Mounting POSIX Message Queue File System... [ OK ] Reached target rpc_pipefs.target. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Reached target RPC Port Mapper. [ OK ] Listening on udev Kernel Socket. Activating swap /dev/disk/by-label/SWAP... Mounting Huge Pages File System... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ 28.189991] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Listening on Process Core Dump Socket. [ OK ] Started Forward Password Requests to Wall Directory Watch. Starting Remount Root and Kernel File Systems... [ OK ] Listening on initctl Compatibility Named Pipe. Starting Create list of required st…ce nodes for the current kernel... Mounting Kernel Debug File System... [ OK ] Created slice system-getty.slice. Starting Apply Kernel Variables... [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd Root File System. [ OK ] Stopped target Initrd File Systems. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Started Journal Service. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted Huge Pages File System. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted Kernel Debug File System. [ OK ] Started Apply Kernel Variables. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... [ OK ] Reached target Swap. Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... Starting udev Kernel Device Manager... [ OK ] Mounted /mnt. [ OK ] Started udev Coldplug all Devices. [ 29.936729] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 32.100026] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 32.281550] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 34.149966] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 34.632155] EDAC sbridge: Ver: 1.1.2 [* ] A start job is running for Configur…-only root support (8s / no limit) [** ] A start job is running for Configur…-only root support (9s / no limit) [*** ] A start job is running for Configur…-only root support (9s / no limit) [ *** ] A start job is running for Configur…only root support (10s / no limit) [ *** ] A start job is running for Configur…only root support (11s / no limit)[ 39.110683] Key type dns_resolver registered [ ***] A start job is running for Configur…only root support (11s / no limit) [ **] A start job is running for Configur…only root support (12s / no limit)[ 39.944835] NFS: Registering the id_resolver key type [ 39.946775] Key type id_resolver registered [ 39.957827] Key type id_legacy registered [ *] A start job is running for Configur…only root support (12s / no limit) [ **] A start job is running for Configur…only root support (13s / 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 RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started dnf makecache --timer. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. [ OK ] Reached target Basic System. [ OK ] Started irqbalance daemon. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Reached target sshd-keygen.target. Starting Restore /run/initramfs on shutdown... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Login Service... [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... Starting OpenSSH server daemon... Starting Network Manager Wait Online... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Started Login Service. Starting Hostname Service... [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started OpenSSH server daemon. [ OK ] Started Permit User Sessions. [ OK ] Started Command Scheduler. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Notify NFS peers of a restart... Starting Crash recovery kernel arming... Starting System Logging Service... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg442-client login: [ 59.926323] hrtimer: interrupt took 8312151 ns [ 92.233446] libcfs: loading out-of-tree module taints kernel. [ 92.300204] alg: No test for adler32 (adler32-zlib) [ 93.054525] Key type ._llcrypt registered [ 93.056241] Key type .llcrypt registered [ 93.313086] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 93.619266] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 93.973077] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 93.975311] LNet: Accept secure, port 988 [ 95.607170] Key type lgssc registered [ 96.317828] Lustre: Echo OBD driver; http://www.lustre.org/ [ 179.950095] Lustre: Mounted lustre-client [ 183.387331] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 195.279474] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing check_logdir /tmp/testlogs/ [ 198.677408] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing yml_node [ 202.237616] Lustre: DEBUG MARKER: Client: 2.15.7.10 [ 204.085815] Lustre: DEBUG MARKER: MDS: 2.15.7.10 [ 205.768136] Lustre: DEBUG MARKER: OSS: 2.15.7.10 [ 205.795816] Lustre: lustre-OST0000-osc-ffff9b8219a1c000: disconnect after 24s idle [ 206.976113] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Wed Oct 22 04:18:07 EDT 2025 [ 212.875540] Lustre: DEBUG MARKER: excepting tests: 27 28 [ 213.922528] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 214.509494] Lustre: Mounted lustre-client [ 219.434814] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing check_config_client /mnt/lustre [ 241.149985] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 257.489214] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 04:18:56 (1761121136) [ 268.173223] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 04:19:07 (1761121147) [ 278.585566] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 04:19:17 (1761121157) [ 287.141591] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 04:19:26 (1761121166) [ 296.256575] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 04:19:35 (1761121175) [ 303.352785] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 04:19:43 (1761121183) [ 310.740710] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 04:19:50 (1761121190) [ 316.667451] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 04:19:56 (1761121196) [ 321.531362] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 04:20:02 (1761121202) [ 324.730124] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 04:20:05 (1761121205) [ 328.486805] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 04:20:09 (1761121209) [ 331.757436] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 04:20:12 (1761121212) [ 336.297910] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 04:20:16 (1761121216) [ 343.375619] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 04:20:23 (1761121223) [ 347.615888] Lustre: lustre-OST0001-osc-ffff9b8203331800: disconnect after 22s idle [ 347.618497] Lustre: Skipped 1 previous similar message [ 348.754532] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 04:20:29 (1761121229) [ 353.918911] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 04:20:34 (1761121234) [ 359.123384] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 04:20:39 (1761121239) [ 365.187944] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 04:20:45 (1761121245) [ 370.335377] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 04:20:50 (1761121250) [ 374.113386] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 04:20:54 (1761121254) [ 374.416483] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=144115205289279508 file: /mnt/lustre/lockdir/lockfile=144115205289279507 [ 532.966079] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 04:23:33 (1761121413) [ 536.284926] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 04:23:37 (1761121417) [ 539.009237] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 04:23:39 (1761121419) [ 541.925554] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 04:23:42 (1761121422) [ 544.753687] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 04:23:45 (1761121425) [ 547.531274] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 04:23:48 (1761121428) [ 548.258305] Lustre: DEBUG MARKER: chmod [ 550.738129] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 04:23:51 (1761121431) [ 553.743447] Lustre: DEBUG MARKER: SKIP: oos2 test_15 oos2.sh: 7207500kB free gt MAXFREE 800000kB, increase 800000 (or reduce test fs size) to proceed [ 557.734802] Lustre: DEBUG MARKER: == sanityn test 16a: 2500 iterations of dual-mount fsx === 04:23:58 (1761121438) [ 623.916570] Lustre: DEBUG MARKER: == sanityn test 16b: 2500 iterations of dual-mount fsx at small size ========================================================== 04:25:04 (1761121504) [ 647.240803] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 04:25:28 (1761121528) [ 690.721944] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 04:26:11 (1761121571) [ 701.489413] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 04:26:22 (1761121582) [ 704.062225] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 04:26:25 (1761121585) [ 704.531540] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 704.585341] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 704.622493] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 704.656736] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 704.686194] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 704.710915] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 704.734169] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 704.758480] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 704.781327] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 704.804581] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 704.831040] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 704.851872] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 704.877903] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 704.903415] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 704.928029] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 704.954248] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 704.975729] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 705.003036] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 705.033342] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 705.068170] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 705.095270] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 705.125102] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 705.152869] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 705.181055] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 705.204563] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 705.227776] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 705.250910] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 705.276050] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 705.302907] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 705.328914] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 705.361905] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 705.390431] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 705.418631] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 705.446665] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 705.476812] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 705.508762] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 705.535023] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 705.562128] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 705.591824] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 705.619478] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 705.650738] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 705.680820] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 705.708380] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 705.744394] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 705.773576] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 705.810843] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 705.847241] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 705.873502] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 705.897766] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 705.922837] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 705.945432] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 705.969594] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 706.003163] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 706.032268] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 706.061681] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 706.084794] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 706.109763] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 706.133460] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 706.156104] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 706.184246] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 706.205443] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 706.227854] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 706.250801] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 706.274127] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 706.299595] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 706.323643] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 706.357911] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 706.390444] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 706.419247] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 706.448105] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 706.476125] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 706.515889] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 706.555521] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 706.585043] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 706.616526] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 706.649201] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 706.679026] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 706.705778] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 706.742405] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 706.769663] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 706.793585] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 706.815528] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 706.839338] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 706.874760] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 706.901943] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 706.928445] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 706.949220] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 706.972119] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 706.997733] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 707.024792] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 707.056583] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 707.089122] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 707.124612] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 707.158886] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 707.188716] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 707.218568] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 707.247877] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 707.275852] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 707.301494] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 707.325430] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 707.351995] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 707.379844] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 707.404916] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 707.431455] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 707.458258] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 707.487466] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 707.519333] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 707.542580] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 707.569096] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 707.599034] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 707.625431] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 707.646839] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 707.668376] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 707.690074] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 707.712990] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 707.734679] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 707.758213] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 707.782180] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 707.806632] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 707.843667] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 707.869521] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 707.898911] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 707.927618] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 707.959125] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 707.987239] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 708.016558] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 708.039399] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 708.072909] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 708.102144] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 708.131973] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 708.158506] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 708.183169] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 708.208834] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 708.243647] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 708.274274] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 708.304645] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 708.337028] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 708.367707] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 708.398337] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 708.427692] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 708.457697] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 708.485475] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 708.519404] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 708.548445] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 708.569087] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 708.592492] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 708.616481] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 708.643869] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 708.671872] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 708.693981] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 708.713472] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 708.735808] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 708.758093] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 708.785092] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 708.809772] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 708.832723] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 708.859682] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 708.891541] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 708.917355] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 708.943035] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 708.967759] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 708.997186] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 709.021813] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 709.046861] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 709.080552] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 709.109927] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 709.141340] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 709.172078] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 709.194937] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 709.216367] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 709.239196] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 709.260694] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 709.281886] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 709.304715] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 709.328688] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 709.360271] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 709.391655] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 709.418960] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 709.447840] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 709.474198] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 709.499713] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 709.530126] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 709.559794] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 709.594856] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 709.618788] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 709.640443] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 709.662480] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 709.691381] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 709.715645] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 709.740765] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 709.764095] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 709.786030] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 709.807724] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 709.832832] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 709.856795] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 709.881515] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 709.906714] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 709.935849] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 709.965688] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 709.995043] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 710.034624] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 710.064536] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 710.093286] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 710.117648] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 710.143531] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 710.168703] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 710.194467] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 710.216950] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 710.240788] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 710.265917] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 710.288844] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 710.311186] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 710.332680] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 710.354138] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 710.376250] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 710.398359] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 710.422649] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 710.448184] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 710.471585] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 710.495298] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 710.528614] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 710.554070] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 710.576403] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 710.605053] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 710.629684] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 710.656871] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 710.682807] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 710.704650] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 710.727410] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 710.749453] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 710.776086] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 710.797820] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 710.821367] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 710.849470] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 710.869909] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 710.895322] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 710.919749] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 710.943718] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 710.969460] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 710.991821] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 711.016878] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 711.043143] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 711.067640] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 711.098289] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 711.135889] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 711.173584] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 711.200913] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 711.231474] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 711.257177] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 711.285497] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 711.319395] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 711.352309] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 711.389078] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 711.419270] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 711.455174] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 711.480785] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 711.504538] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 711.528721] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 711.553660] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 711.582416] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 711.607498] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 711.630785] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 711.656967] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 711.683693] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 711.718199] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 711.743630] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 711.770291] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 711.793432] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 711.817276] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 711.845421] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 711.869175] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 711.892781] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 711.916366] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 711.940078] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 711.962903] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 711.989059] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 712.013040] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 712.035052] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 712.065367] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 712.095566] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 712.120226] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 712.145803] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 712.178126] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 712.204113] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 712.226840] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 712.251204] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 712.274295] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 712.299566] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 712.323837] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 712.348638] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 712.368765] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 712.389810] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 712.411378] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 712.436457] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 712.458974] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 712.485108] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 712.509958] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 712.534324] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 712.557928] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 712.579411] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 712.602189] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 712.631123] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 712.658887] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 712.687267] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 712.727002] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 712.765104] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 712.795945] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 712.834314] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 712.866968] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 712.897236] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 712.927438] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 712.952903] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 712.983909] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 713.018496] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 713.048882] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 713.075548] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 713.111780] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 713.143536] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 713.176761] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 713.214823] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 713.244134] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 713.275903] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 713.309915] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 713.337127] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 713.362865] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 713.389133] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 713.413476] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 713.440608] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 713.470051] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 713.504321] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 713.531117] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 713.558772] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 713.580565] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 713.601275] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 713.623628] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 713.649277] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 713.677259] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 713.703530] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 713.729686] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 713.763406] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 713.788113] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 713.813495] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 713.836314] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 713.864633] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 713.895056] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 713.920211] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 713.949264] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 713.973421] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 714.000418] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 714.025833] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 714.049908] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 714.075667] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 714.105441] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 714.135186] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 714.160819] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 714.188911] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 714.219780] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 714.255307] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 714.282507] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 714.303946] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 714.330654] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 714.355735] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 714.385297] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 714.416900] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 714.450958] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 714.473491] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 714.494513] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 714.518806] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 714.551812] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 714.576787] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 714.601076] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 714.630399] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 714.655127] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 714.680138] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 714.704879] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 714.726984] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 714.749034] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 714.770843] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 714.796661] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 714.821322] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 714.848396] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 714.875869] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 714.897555] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 714.919628] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 714.942782] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 714.965431] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 714.988958] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 715.009620] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 715.032391] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 715.060250] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 715.088512] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 715.121558] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 715.154700] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 715.189630] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 715.219753] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 715.244478] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 715.266571] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 715.290137] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 715.315267] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 715.343081] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 715.374590] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 715.396499] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 715.425827] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 715.452056] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 715.482538] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 715.510066] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 715.539524] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 715.576648] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 715.602022] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 715.627630] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 715.658506] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 715.682298] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 715.708523] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 715.736728] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 715.771183] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 715.800712] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 715.826470] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 715.849976] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 715.881644] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 715.914691] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 715.941178] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 715.963293] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 715.987853] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 716.010937] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 716.034809] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 716.060826] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 716.093045] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 716.116935] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 716.142826] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 716.169089] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 716.191352] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 716.214485] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 716.237057] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 716.262915] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 716.286228] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 716.320828] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 716.342758] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 716.373989] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 716.399710] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 716.427463] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 716.452036] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 716.475142] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 716.500099] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 716.531722] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 716.554523] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 716.580368] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 716.615104] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 716.649446] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 716.687333] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 716.720119] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 716.747059] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 716.772836] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 716.795593] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 716.818401] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 716.841687] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 716.875483] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 716.904514] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 716.928118] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 716.950932] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 716.973437] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 717.003995] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 717.034501] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 717.064498] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 717.088314] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 717.108021] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 717.132728] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 717.159053] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 717.185198] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 717.222498] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 717.253199] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 717.283819] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 717.309630] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 717.341855] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 717.367430] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 717.389342] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 717.412390] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 717.434600] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 717.457453] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 717.480962] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 717.503744] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 717.527268] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 717.550752] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 717.574631] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 717.598617] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 717.630510] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 717.653044] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 717.674684] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 717.699519] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 717.723931] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 717.751236] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 717.776211] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 717.801354] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 717.826737] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 717.853087] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 717.877227] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 717.899239] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 717.923202] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 717.949901] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 717.975852] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 718.003039] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 718.037517] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 718.069763] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 718.102310] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 718.132373] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 718.166354] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 718.195469] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 718.220609] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 718.242701] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 718.274506] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 718.298197] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 718.321837] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 718.344431] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 718.369085] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 718.397083] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 718.425193] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 718.453311] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 718.482686] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 718.511833] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 718.550325] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 718.579988] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 718.613727] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 718.636090] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 718.661122] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 718.693627] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 718.717860] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 718.740815] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 718.764040] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 718.788561] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 718.810992] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 718.833128] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 718.856628] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 718.884564] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 718.913031] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 718.937444] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 718.968491] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 718.991955] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 719.017914] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 719.044113] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 719.081479] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 719.114813] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 719.153363] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 719.183232] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 719.210489] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 719.239537] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 719.275312] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 719.305225] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 719.334717] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 719.357977] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 719.389467] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 719.414743] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 719.438349] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 719.463357] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 719.485255] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 719.512521] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 719.537693] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 719.562136] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 719.589603] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 719.611803] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 719.637725] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 719.663844] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 719.695547] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 719.729831] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 719.757052] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 719.779357] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 719.801043] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 719.825786] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 719.855906] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 719.885751] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 719.911433] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 719.934603] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 719.959415] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 719.982406] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 720.005612] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 720.029883] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 720.054791] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 720.079606] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 720.102190] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 720.124809] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 720.147938] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 720.172157] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 720.194621] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 720.218487] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 720.242132] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 720.268097] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 720.290741] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 720.313538] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 720.342872] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 720.371475] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 720.403748] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 720.431049] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 720.460224] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 720.483529] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 720.508387] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 720.531573] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 720.559359] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 720.586195] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 720.607994] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 720.630740] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 720.659239] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 720.687899] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 720.717420] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 720.747155] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 720.777935] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 720.810422] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 720.846437] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 720.878605] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 720.910688] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 720.940882] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 720.971974] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 721.008137] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 721.041787] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 721.075784] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 721.102141] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 721.135604] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 721.175399] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 721.202484] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 721.230533] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 721.258050] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 721.285813] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 721.314971] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 721.341727] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 721.366305] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 721.392340] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 721.420167] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 721.444741] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 721.468951] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 721.491926] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 721.515548] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 721.540303] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 721.566341] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 721.592714] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 721.622877] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 721.649244] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 721.676482] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 721.706032] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 721.732992] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 721.762886] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 721.788530] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 721.813075] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 721.838859] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 721.864813] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 721.893962] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 721.923420] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 721.958399] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 721.985889] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 722.009609] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 722.044514] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 722.070019] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 722.091068] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 722.114524] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 722.137240] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 722.161582] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 722.185160] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 722.207759] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 722.231662] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 722.253240] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 722.276205] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 722.297581] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 722.320513] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 722.341620] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 722.368723] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 722.395199] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 722.419296] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 722.446853] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 722.479955] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 722.502783] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 722.527406] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 722.553697] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 722.579908] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 722.603094] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 722.628781] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 722.651870] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 722.675399] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 722.698928] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 722.723608] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 722.746490] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 722.768455] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 722.791570] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 722.816816] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 722.842301] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 722.866547] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 722.888548] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 722.911472] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 722.936441] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 722.962367] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 722.985631] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 723.008077] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 723.032849] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 723.056400] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 723.079748] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 723.102857] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 723.127083] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 723.151820] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 723.176898] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 723.202793] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 723.225800] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 723.251124] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 723.274640] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 723.296987] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 723.317689] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 723.341064] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 723.364328] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 723.389273] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 723.412860] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 723.438264] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 723.462062] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 723.490482] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 723.517684] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 723.540683] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 723.564184] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 723.590832] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 723.616238] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 723.642325] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 723.668626] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 723.692924] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 723.718141] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 723.749055] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 723.782619] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 723.815606] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 723.845929] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 723.871857] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 723.897444] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 723.925333] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 723.948446] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 723.971305] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 723.995775] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 724.021063] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 724.043805] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 724.065726] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 724.087657] rw_seq_cst_vs_d (29809): drop_caches: 3 [ 726.495198] Lustre: lustre-OST0000-osc-ffff9b8219a1c000: disconnect after 23s idle [ 726.573308] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 04:26:47 (1761121607) [ 726.694608] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 727.021585] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 727.038878] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 727.059736] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 727.074937] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 727.096149] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 727.161615] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 727.234870] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 727.357930] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 727.521707] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 727.538858] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 727.684125] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 727.708050] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 727.725246] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 727.745183] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 727.825783] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 727.846033] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 727.868748] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 727.885832] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 727.904378] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 727.929843] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 727.995374] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 728.015343] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 728.034601] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 728.054980] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 728.072585] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 728.090839] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 728.222609] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 728.243651] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 728.265346] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 728.500953] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 728.519590] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 728.537478] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 728.557691] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 728.581846] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 728.600124] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 728.620949] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 728.643184] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 728.660842] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 728.680554] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 728.810821] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 728.827955] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 728.852048] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 728.873268] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 728.935139] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 728.955899] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 728.977628] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 729.063611] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 729.083184] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 729.108024] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 729.127711] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 729.152618] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 729.171248] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 729.188392] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 729.206863] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 729.225215] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 729.247081] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 729.305518] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 729.578675] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 729.599491] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 729.648268] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 729.673097] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 729.696634] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 729.714448] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 729.732386] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 729.762646] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 729.794637] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 729.820624] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 729.851350] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 729.993731] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 730.009776] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 730.024793] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 730.042685] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 730.068214] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 730.094572] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 730.114265] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 730.286762] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 730.303322] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 730.321662] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 730.344381] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 730.370621] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 730.395492] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 730.812674] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 730.930243] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 730.952484] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 730.991360] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 731.014034] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 731.029770] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 731.090100] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 731.111281] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 731.137778] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 731.157573] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 731.803540] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 731.828891] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 731.847388] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 731.871627] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 731.895379] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 732.050089] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 732.068809] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 732.090233] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 732.116131] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 732.136623] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 732.224078] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 732.244597] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 732.267201] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 732.284246] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 732.302797] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 732.353383] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 732.369509] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 732.386138] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 732.739392] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 732.863563] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 732.880544] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 732.898281] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 733.038590] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 733.062351] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 733.080712] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 733.101132] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 733.123573] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 733.140440] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 733.163825] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 733.337765] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 733.355566] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 733.374494] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 733.392503] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 733.411738] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 733.458653] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 733.478810] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 733.500399] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 733.518849] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 733.535255] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 733.552037] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 733.567933] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 733.584791] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 733.661800] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 733.783433] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 733.802760] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 733.824866] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 733.847725] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 734.068982] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 734.092384] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 734.113528] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 734.384383] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 734.400173] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 734.415211] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 734.429170] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 734.449608] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 734.465347] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 734.486925] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 734.504936] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 734.594242] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 734.609887] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 734.642649] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 734.659562] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 734.715298] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 734.747449] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 734.765592] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 734.920375] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 734.939763] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 734.958295] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 734.976417] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 734.994323] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 735.011312] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 735.029724] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 735.111829] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 735.130428] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 735.148595] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 735.167613] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 735.184508] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 735.201437] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 735.221321] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 735.235971] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 735.256366] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 735.276994] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 735.298125] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 735.318614] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 735.337957] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 735.355647] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 735.372850] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 735.395704] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 735.418836] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 735.440649] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 735.462396] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 735.480096] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 735.502427] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 735.518778] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 735.541171] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 735.560708] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 735.583450] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 735.602374] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 735.617955] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 735.640064] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 735.660471] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 735.681071] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 735.696718] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 735.716299] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 735.739027] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 735.858037] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 735.896707] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 735.921275] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 735.944379] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 735.960390] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 735.977030] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 735.994757] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 736.010458] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 736.027197] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 736.062039] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 736.140887] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 736.175065] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 736.191205] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 736.211961] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 736.235063] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 736.289246] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 736.310368] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 736.363065] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 736.385568] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 736.408458] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 736.559595] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 736.656896] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 736.673499] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 736.707931] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 736.729142] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 736.747448] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 736.794958] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 736.810832] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 736.826369] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 736.843390] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 736.859570] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 736.881669] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 737.060966] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 737.129422] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 737.165293] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 737.196876] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 737.212291] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 737.226690] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 737.248209] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 737.267092] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 737.282735] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 737.301581] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 737.317078] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 737.331645] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 737.369635] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 737.385751] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 737.400583] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 737.417950] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 737.435334] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 737.449902] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 737.464770] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 737.479924] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 737.501516] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 737.519912] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 737.673309] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 737.688883] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 737.703409] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 737.718643] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 737.732975] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 737.753125] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 737.772396] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 737.790485] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 737.861476] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 737.882674] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 737.902659] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 737.917667] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 737.934615] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 737.967900] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 738.140583] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 738.172375] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 738.190125] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 738.206041] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 738.219683] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 738.235082] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 738.256560] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 738.311704] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 738.330414] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 738.347302] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 738.367629] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 738.387556] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 738.423565] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 738.516507] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 738.531562] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 738.556384] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 738.594479] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 738.614099] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 738.628099] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 738.648549] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 738.668492] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 738.701845] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 738.722585] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 738.776138] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 738.791175] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 738.811042] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 738.826353] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 738.845500] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 738.862536] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 738.901640] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 738.954608] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 738.974974] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 738.989234] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 739.004272] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 739.020856] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 739.039156] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 739.059897] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 739.081923] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 739.098037] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 739.481373] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 739.516977] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 739.718996] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 739.743196] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 739.761960] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 739.778457] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 739.794158] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 739.811805] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 739.833389] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 739.854518] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 739.877064] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 739.893921] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 739.930635] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 739.945237] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 739.965724] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 739.987359] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 740.007275] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 740.188558] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 740.227967] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 740.248414] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 740.270851] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 740.407430] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 740.425965] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 740.444133] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 740.489408] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 740.509168] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 740.664980] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 740.685277] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 740.702399] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 740.721130] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 740.892970] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 740.909195] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 740.926223] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 741.034479] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 741.055244] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 741.071915] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 741.089240] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 741.113544] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 741.233510] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 741.251346] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 741.273426] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 741.290042] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 741.307782] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 741.328129] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 741.345555] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 741.363333] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 741.398029] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 741.414259] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 741.435806] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 741.470815] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 741.525739] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 741.558468] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 741.579222] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 741.596657] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 741.615494] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 741.639958] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 741.658136] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 741.675272] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 741.692416] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 741.709206] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 741.726891] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 741.749793] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 741.774161] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 741.833452] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 741.851327] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 741.868151] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 741.886663] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 741.904748] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 741.965133] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 741.983775] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 742.002109] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 742.024549] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 742.065928] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 742.083578] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 742.101137] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 742.117756] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 742.141502] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 742.163632] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 742.185804] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 742.211457] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 742.232395] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 742.251100] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 742.269176] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 742.286367] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 742.310519] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 742.327845] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 742.352496] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 742.371461] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 742.390047] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 742.408902] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 742.428197] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 742.446114] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 742.463190] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 742.484232] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 742.498691] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 742.514844] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 742.535172] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 742.551255] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 742.706165] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 742.723495] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 742.740218] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 742.755937] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 742.772732] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 742.793132] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 742.809638] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 742.830547] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 742.851630] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 742.960667] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 742.981810] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 742.997857] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 743.014276] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 743.161939] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 743.180246] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 743.197693] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 743.216549] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 743.284381] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 743.302207] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 743.319718] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 743.336420] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 743.355559] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 743.378476] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 743.399282] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 743.418584] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 743.563390] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 743.584206] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 743.648256] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 743.665656] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 743.683849] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 743.701292] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 743.719169] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 743.742279] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 743.764129] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 743.779250] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 743.898647] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 743.919809] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 743.937090] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 743.954512] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 743.972225] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 743.990541] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 744.012662] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 744.030297] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 744.065081] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 744.083024] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 744.178629] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 744.199848] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 744.296237] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 744.317063] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 744.331165] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 744.346333] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 744.368205] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 744.388796] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 744.407176] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 744.498336] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 744.513875] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 744.534987] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 744.550638] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 744.572349] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 744.679845] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 744.702208] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 744.799095] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 744.858567] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 745.123573] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 745.142245] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 745.162872] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 745.184264] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 745.210561] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 745.449378] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 745.474192] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 745.498582] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 745.515973] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 745.532792] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 745.555382] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 745.574995] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 745.592955] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 745.612205] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 745.634522] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 745.655853] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 745.672907] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 745.691366] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 745.796514] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 746.014423] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 746.033653] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 746.053639] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 746.078185] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 746.095687] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 746.138266] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 746.154866] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 746.172392] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 746.190660] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 746.210120] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 746.227929] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 746.248110] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 746.267809] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 746.284763] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 746.304695] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 746.324143] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 746.344112] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 746.364705] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 746.384813] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 746.403417] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 746.427974] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 746.450886] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 746.470344] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 746.487086] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 746.501292] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 746.516378] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 746.534939] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 746.559689] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 746.576898] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 746.593057] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 746.609528] rw_seq_cst_vs_d (30394): drop_caches: 3 [ 749.020468] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 04:27:10 (1761121630) [ 751.332931] Lustre: DEBUG MARKER: == sanityn test 16k: Parallel FSX and drop caches should not panic ========================================================== 04:27:12 (1761121632) [ 751.420474] bash (31566): drop_caches: 3 [ 752.095191] Lustre: lustre-OST0001-osc-ffff9b8219a1c000: disconnect after 22s idle [ 752.097409] Lustre: Skipped 1 previous similar message [ 754.796459] bash (31566): drop_caches: 3 [ 757.911708] bash (31566): drop_caches: 3 [ 761.030700] bash (31566): drop_caches: 3 [ 764.619039] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 04:27:25 (1761121645) [ 769.577514] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 04:27:30 (1761121650) [ 779.733938] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 04:27:40 (1761121660) [ 782.697321] Lustre: DEBUG MARKER: loop 5 [ 784.584992] Lustre: DEBUG MARKER: loop 10 [ 786.480569] Lustre: DEBUG MARKER: loop 15 [ 788.324479] Lustre: DEBUG MARKER: loop 20 [ 791.149916] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 04:27:52 (1761121672) [ 793.554692] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 04:27:54 (1761121674) [ 795.748863] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 04:27:56 (1761121676) [ 813.535220] Lustre: lustre-OST0000-osc-ffff9b8219a1c000: disconnect after 23s idle [ 813.537723] Lustre: Skipped 1 previous similar message [ 859.251153] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 04:29:00 (1761121740) [ 861.361473] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 04:29:02 (1761121742) [ 863.344236] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 04:29:04 (1761121744) [ 865.450921] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 04:29:06 (1761121746) [ 867.535204] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 04:29:08 (1761121748) [ 870.564391] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 04:29:11 (1761121751) [ 874.711224] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 875.248295] Lustre: DEBUG MARKER: SKIP: sanityn test_28 skipping ALWAYS excluded test 28 [ 875.802231] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 04:29:16 (1761121756) [ 879.988253] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 04:29:21 (1761121761) [ 880.089746] Lustre: *** cfs_fail_loc=314, val=0*** [ 881.119253] Lustre: *** cfs_fail_loc=314, val=0*** [ 881.121318] Lustre: Skipped 2 previous similar messages [ 883.132295] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 04:29:24 (1761121764) [ 895.632908] Lustre: *** cfs_fail_loc=314, val=0*** [ 895.653617] LustreError: 11-0: lustre-OST0000-osc-ffff9b8203331800: operation ldlm_enqueue to node 192.168.204.142@tcp failed: rc = -107 [ 895.656173] Lustre: lustre-OST0000-osc-ffff9b8203331800: Connection to lustre-OST0000 (at 192.168.204.142@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 895.660963] LustreError: lustre-OST0000-osc-ffff9b8203331800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 895.667407] LustreError: 42073:0:(ldlm_resource.c:1127:ldlm_resource_complain()) lustre-OST0000-osc-ffff9b8203331800: namespace resource [0x29:0x0:0x0].0x0 (0000000044af9957) refcount nonzero (1) after lock cleanup; forcing cleanup. [ 895.673459] Lustre: lustre-OST0000-osc-ffff9b8203331800: Connection restored to (at 192.168.204.142@tcp) [ 897.959596] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 04:29:38 (1761121778) [ 898.007465] LustreError: 42655:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 901.023106] LustreError: 42655:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 1419 awake [ 903.017686] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 903.560397] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 04:29:44 (1761121784) [ 904.103835] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 904.605346] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-Lock-Cancel ========================================================== 04:29:45 (1761121785) [ 905.696831] Lustre: lustre-MDT0000-mdc-ffff9b8219a1c000: Connection to lustre-MDT0000 (at 192.168.204.142@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 910.816928] LustreError: 166-1: MGC192.168.204.142@tcp: Connection to MGS (at 192.168.204.142@tcp) was lost; in progress operations using this service will fail [ 910.822471] Lustre: Evicted from MGS (at 192.168.204.142@tcp) after server handle changed from 0xaded52620cdee2eb to 0xaded52620d047665 [ 910.825414] Lustre: MGC192.168.204.142@tcp: Connection restored to (at 192.168.204.142@tcp) [ 913.608097] Lustre: lustre-MDT0000-mdc-ffff9b8203331800: Connection restored to (at 192.168.204.142@tcp) [ 921.055282] Lustre: lustre-OST0000-osc-ffff9b8203331800: disconnect after 20s idle [ 921.057675] Lustre: Skipped 3 previous similar messages [ 924.653151] Lustre: DEBUG MARKER: == sanityn test 33d: DNE distributed operation should trigger COS ========================================================== 04:30:05 (1761121805) [ 959.917112] Lustre: DEBUG MARKER: == sanityn test 33e: DNE local operation shouldn't trigger COS ========================================================== 04:30:40 (1761121840) [ 960.389257] Lustre: DEBUG MARKER: SKIP: sanityn test_33e Need two or more clients, have 1 [ 960.911197] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 04:30:41 (1761121841) [ 1007.035361] Lustre: lustre-OST0000-osc-ffff9b8219a1c000: Connection to lustre-OST0000 (at 192.168.204.142@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1007.041085] Lustre: Skipped 1 previous similar message [ 1007.045274] LustreError: lustre-OST0000-osc-ffff9b8203331800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1007.049296] LustreError: lustre-OST0000-osc-ffff9b8219a1c000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1007.049584] Lustre: lustre-OST0000-osc-ffff9b8203331800: Connection restored to (at 192.168.204.142@tcp) [ 1007.056554] Lustre: Skipped 2 previous similar messages [ 1012.155271] Lustre: lustre-OST0001-osc-ffff9b8219a1c000: Connection to lustre-OST0001 (at 192.168.204.142@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1012.159441] Lustre: Skipped 1 previous similar message [ 1012.162392] LustreError: lustre-OST0001-osc-ffff9b8219a1c000: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 1012.166145] Lustre: lustre-OST0001-osc-ffff9b8219a1c000: Connection restored to (at 192.168.204.142@tcp) [ 1024.565502] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9b8203331800.ost_server_uuid,osc.lustre-OST0000-osc-ffff9b8219a1c000.ost_server_uuid 40 [ 1025.061646] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9b8203331800.ost_server_uuid in FULL state after 0 sec [ 1025.568761] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9b8219a1c000.ost_server_uuid in FULL state after 0 sec [ 1027.362988] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9b8203331800.ost_server_uuid,osc.lustre-OST0001-osc-ffff9b8219a1c000.ost_server_uuid 40 [ 1027.853894] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9b8203331800.ost_server_uuid in FULL state after 0 sec [ 1028.342297] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9b8219a1c000.ost_server_uuid in FULL state after 0 sec [ 1028.575198] Lustre: lustre-OST0000-osc-ffff9b8219a1c000: disconnect after 22s idle [ 1030.564950] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9b8203331800.ost_server_uuid,osc.lustre-OST0000-osc-ffff9b8219a1c000.ost_server_uuid 40 [ 1031.071878] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9b8203331800.ost_server_uuid in IDLE state after 0 sec [ 1031.567137] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9b8219a1c000.ost_server_uuid in IDLE state after 0 sec [ 1033.436824] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9b8203331800.ost_server_uuid,osc.lustre-OST0001-osc-ffff9b8219a1c000.ost_server_uuid 40 [ 1033.934299] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9b8203331800.ost_server_uuid in FULL state after 0 sec [ 1034.440860] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9b8219a1c000.ost_server_uuid in IDLE state after 0 sec [ 1038.314981] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9b8203331800.ost_server_uuid,osc.lustre-OST0000-osc-ffff9b8219a1c000.ost_server_uuid 40 [ 1038.817552] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9b8203331800.ost_server_uuid in IDLE state after 0 sec [ 1039.316286] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9b8219a1c000.ost_server_uuid in IDLE state after 0 sec [ 1041.204509] Lustre: DEBUG MARKER: oleg442-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9b8203331800.ost_server_uuid,osc.lustre-OST0001-osc-ffff9b8219a1c000.ost_server_uuid 40 [ 1041.719034] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9b8203331800.ost_server_uuid in FULL state after 0 sec [ 1042.271757] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9b8219a1c000.ost_server_uuid in IDLE state after 0 sec [ 1042.796502] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 04:32:03 (1761121923) [ 1043.665847] Lustre: DEBUG MARKER: Race attempt 0 [ 1045.203338] Lustre: DEBUG MARKER: Wait for 55148 55281 for 60 sec... [ 1107.504966] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 04:33:08 (1761121988) [ 1113.750608] Lustre: DEBUG MARKER: start test - cycle (0) [ 1130.142412] Lustre: DEBUG MARKER: start test - cycle (1) [ 1145.163165] Lustre: DEBUG MARKER: start test - cycle (2) [ 1160.704227] Lustre: DEBUG MARKER: start test - cycle (3) [ 1175.815956] Lustre: DEBUG MARKER: start test - cycle (4) [ 1192.258499] Lustre: DEBUG MARKER: start test - cycle (5) [ 1211.806588] Lustre: DEBUG MARKER: start test - cycle (6) [ 1227.015146] Lustre: DEBUG MARKER: start test - cycle (7) [ 1242.344085] Lustre: DEBUG MARKER: start test - cycle (8) [ 1269.215182] Lustre: lustre-OST0000-osc-ffff9b8203331800: disconnect after 20s idle [ 1269.218510] Lustre: Skipped 2 previous similar messages [ 1270.796514] Lustre: DEBUG MARKER: start test - cycle (9) [ 1288.707718] Lustre: DEBUG MARKER: start test - cycle (10) [ 1305.640040] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 04:36:26 (1761122186) [ 1319.050655] Lustre: DEBUG MARKER: == sanityn test 39a: test from 11063 ============================================================================================ 04:36:40 (1761122200) [ 1321.166064] Lustre: DEBUG MARKER: == sanityn test 39b: 11063 problem 1 ============================================================================================ 04:36:42 (1761122202) [ 1324.267877] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 04:36:45 (1761122205) [ 1327.389858] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 04:36:48 (1761122208) [ 1327.450608] Lustre: *** cfs_fail_loc=411, val=0*** [ 1329.345286] Lustre: DEBUG MARKER: == sanityn test 40a: pdirops: create vs others ======================================================================== 04:36:50 (1761122210) [ 1337.385742] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 04:36:58 (1761122218) [ 1345.332330] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 04:37:06 (1761122226) [ 1353.279436] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 04:37:14 (1761122234) [ 1361.139426] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 04:37:22 (1761122242) [ 1366.495197] Lustre: lustre-OST0000-osc-ffff9b8219a1c000: disconnect after 23s idle [ 1366.497509] Lustre: Skipped 1 previous similar message [ 1368.521572] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 04:37:29 (1761122249) [ 1373.526100] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 04:37:34 (1761122254) [ 1378.601637] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 04:37:39 (1761122259) [ 1383.659329] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 04:37:44 (1761122264) [ 1388.764677] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 04:37:49 (1761122269) [ 1393.785442] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 04:37:54 (1761122274) [ 1399.032612] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 04:38:00 (1761122280) [ 1404.070915] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 04:38:05 (1761122285) [ 1409.200729] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 04:38:10 (1761122290) [ 2239.476166] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 04:52:00 (1761123120) [ 2247.271095] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 04:52:07 (1761123127) [ 2254.468951] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 04:52:15 (1761123135) [ 2262.153181] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 04:52:22 (1761123142) [ 2268.869598] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 04:52:29 (1761123149) [ 2275.873223] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 04:52:36 (1761123156) [ 2282.976447] Lustre: lustre-OST0001-osc-ffff9b8219a1c000: disconnect after 21s idle [ 2282.979631] Lustre: Skipped 2 previous similar messages [ 2283.226605] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 04:52:43 (1761123163) [ 2291.637603] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 04:52:52 (1761123172) [ 2299.401916] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 04:53:00 (1761123180) [ 2341.779995] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 04:53:42 (1761123222) [ 2349.222512] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 04:53:50 (1761123230) [ 2356.760650] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 04:53:57 (1761123237) [ 2364.259208] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 04:54:04 (1761123244) [ 2372.521896] Lustre: DEBUG MARKER: == sanityn test 43f: pdirops: unlink and rename (src) ======================================================================== 04:54:13 (1761123253) [ 2380.769261] Lustre: DEBUG MARKER: == sanityn test 43g: pdirops: unlink vs getattr ======================================================================== 04:54:21 (1761123261) [ 2388.885481] Lustre: DEBUG MARKER: == sanityn test 43h: pdirops: unlink vs readdir ======================================================================== 04:54:29 (1761123269) [ 2395.809343] Lustre: DEBUG MARKER: == sanityn test 43i: pdirops: unlink vs remote mkdir ===== 04:54:36 (1761123276) [ 2402.745752] Lustre: DEBUG MARKER: == sanityn test 43j: racy mkdir return EEXIST ======================================================================== 04:54:43 (1761123283) [ 2416.095344] Lustre: lustre-OST0001-osc-ffff9b8219a1c000: disconnect after 22s idle [ 2416.099402] Lustre: Skipped 1 previous similar message [ 2469.668542] Lustre: DEBUG MARKER: == sanityn test 43k: unlink vs create ==================== 04:55:50 (1761123350) [ 2554.337643] Lustre: lustre-OST0000-osc-ffff9b8219a1c000: disconnect after 22s idle [ 2554.340029] Lustre: Skipped 1 previous similar message [ 2692.576144] Lustre: lustre-OST0001-osc-ffff9b8219a1c000: disconnect after 22s idle [ 2692.579913] Lustre: Skipped 1 previous similar message [ 3245.033939] Lustre: DEBUG MARKER: == sanityn test 44a: pdirops: rename tgt vs mkdir ======================================================================== 05:08:45 (1761124125) [ 3255.209581] Lustre: DEBUG MARKER: == sanityn test 44b: pdirops: rename tgt vs create ======================================================================== 05:08:55 (1761124135) [ 3264.540662] Lustre: DEBUG MARKER: == sanityn test 44c: pdirops: rename tgt vs link ======================================================================== 05:09:05 (1761124145) [ 3273.820383] Lustre: DEBUG MARKER: == sanityn test 44d: pdirops: rename tgt vs unlink ======================================================================== 05:09:14 (1761124154) [ 3282.553301] Lustre: DEBUG MARKER: == sanityn test 44e: pdirops: rename tgt and rename (tgt) ======================================================================== 05:09:23 (1761124163) [ 3292.701770] Lustre: DEBUG MARKER: == sanityn test 44f: pdirops: rename tgt and rename (src) ======================================================================== 05:09:33 (1761124173) [ 3301.306214] Lustre: DEBUG MARKER: == sanityn test 44g: pdirops: rename tgt vs getattr ======================================================================== 05:09:41 (1761124181) [ 3309.688533] Lustre: DEBUG MARKER: == sanityn test 44h: pdirops: rename tgt vs readdir ======================================================================== 05:09:50 (1761124190) [ 3318.768222] Lustre: DEBUG MARKER: == sanityn test 44i: pdirops: rename tgt vs remote mkdir ========================================================== 05:09:59 (1761124199) [ 3327.187704] Lustre: DEBUG MARKER: == sanityn test 45a: rename,mkdir doesn't return -EEXIST ======================================================================== 05:10:07 (1761124207) [ 3337.699625] Lustre: lustre-OST0000-osc-ffff9b8219a1c000: disconnect after 21s idle [ 3337.705096] Lustre: Skipped 1 previous similar message [ 3417.384239] Lustre: DEBUG MARKER: == sanityn test 45b: pdirops: rename src vs create ======================================================================== 05:11:38 (1761124298) [ 3426.474633] Lustre: DEBUG MARKER: == sanityn test 45c: pdirops: rename src vs link ======================================================================== 05:11:46 (1761124306) [ 3435.636777] Lustre: DEBUG MARKER: == sanityn test 45d: pdirops: rename src vs unlink ======================================================================== 05:11:56 (1761124316) [ 3444.840178] Lustre: DEBUG MARKER: == sanityn test 45e: pdirops: rename src and rename (tgt) ======================================================================== 05:12:05 (1761124325) [ 3453.182541] Lustre: DEBUG MARKER: == sanityn test 45f: pdirops: rename src and rename (src) ======================================================================== 05:12:13 (1761124333) [ 3462.156955] Lustre: DEBUG MARKER: == sanityn test 45g: pdirops: rename src vs getattr ======================================================================== 05:12:22 (1761124342) [ 3471.633464] Lustre: DEBUG MARKER: == sanityn test 45h: pdirops: unlink vs readdir ======================================================================== 05:12:32 (1761124352) [ 3480.086516] Lustre: DEBUG MARKER: == sanityn test 45i: pdirops: rename src vs remote mkdir ========================================================== 05:12:40 (1761124360) [ 3488.729610] Lustre: DEBUG MARKER: == sanityn test 45j: read vs rename ====================== 05:12:49 (1761124369) [ 3946.975178] Lustre: lustre-OST0000-osc-ffff9b8219a1c000: disconnect after 23s idle [ 3946.978523] Lustre: Skipped 2 previous similar messages [ 4150.118552] Lustre: DEBUG MARKER: == sanityn test 46a: pdirops: link vs mkdir ======================================================================== 05:23:50 (1761125030) [ 4155.899866] Lustre: DEBUG MARKER: == sanityn test 46b: pdirops: link vs create ======================================================================== 05:23:56 (1761125036) [ 4162.221867] Lustre: DEBUG MARKER: == sanityn test 46c: pdirops: link vs link ======================================================================== 05:24:03 (1761125043) [ 4168.595772] Lustre: DEBUG MARKER: == sanityn test 46d: pdirops: link vs unlink ======================================================================== 05:24:09 (1761125049) [ 4174.890229] Lustre: DEBUG MARKER: == sanityn test 46e: pdirops: link and rename (tgt) ======================================================================== 05:24:15 (1761125055) [ 4181.081579] Lustre: DEBUG MARKER: == sanityn test 46f: pdirops: link and rename (src) ======================================================================== 05:24:21 (1761125061) [ 4187.682093] Lustre: DEBUG MARKER: == sanityn test 46g: pdirops: link vs getattr ======================================================================== 05:24:28 (1761125068) [ 4194.018771] Lustre: DEBUG MARKER: == sanityn test 46h: pdirops: link vs readdir ======================================================================== 05:24:34 (1761125074) [ 4200.379984] Lustre: DEBUG MARKER: == sanityn test 46i: pdirops: link vs remote mkdir ======= 05:24:41 (1761125081) [ 4206.505676] Lustre: DEBUG MARKER: == sanityn test 47a: pdirops: remote mkdir vs mkdir ====== 05:24:47 (1761125087) [ 4212.774201] Lustre: DEBUG MARKER: == sanityn test 47b: pdirops: remote mkdir vs create ===== 05:24:53 (1761125093) [ 4220.082377] Lustre: DEBUG MARKER: == sanityn test 47c: pdirops: remote mkdir vs link ======= 05:25:00 (1761125100) [ 4226.454645] Lustre: DEBUG MARKER: == sanityn test 47d: pdirops: remote mkdir vs unlink ===== 05:25:07 (1761125107) [ 4232.592296] Lustre: DEBUG MARKER: == sanityn test 47e: pdirops: remote mkdir and rename (tgt) ========================================================== 05:25:13 (1761125113) [ 4238.555729] Lustre: DEBUG MARKER: == sanityn test 47f: pdirops: remote mkdir and rename (src) ========================================================== 05:25:19 (1761125119) [ 4244.805508] Lustre: DEBUG MARKER: == sanityn test 47g: pdirops: remote mkdir vs getattr ==== 05:25:25 (1761125125) [ 4252.546853] Lustre: DEBUG MARKER: == sanityn test 50: osc lvb attrs: enqueue vs. CP AST ======================================================================== 05:25:33 (1761125133) [ 4252.686665] LustreError: 17986:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 410 sleeping for 2000ms [ 4254.767181] LustreError: 17986:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 410 awake [ 4260.404619] Lustre: DEBUG MARKER: == sanityn test 51a: layout lock: refresh layout should work ========================================================== 05:25:41 (1761125141) [ 4264.974707] Lustre: DEBUG MARKER: == sanityn test 51b: layout lock: glimpse should be able to restart if layout changed ========================================================== 05:25:45 (1761125145) [ 4265.071842] LustreError: 268638:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 4269.127172] LustreError: 268638:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 1404 awake [ 4269.136313] LustreError: 268638:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 4273.199144] LustreError: 268638:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 1404 awake [ 4273.213218] LustreError: 268644:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 4277.271088] LustreError: 268644:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 1404 awake [ 4280.447617] Lustre: DEBUG MARKER: == sanityn test 51c: layout lock: IT_LAYOUT blocked and correct layout can be returned ========================================================== 05:26:01 (1761125161) [ 4287.643598] Lustre: DEBUG MARKER: == sanityn test 51d: layout lock: losing layout lock should clean up memory map region ========================================================== 05:26:08 (1761125168) [ 4291.086638] Lustre: DEBUG MARKER: == sanityn test 51e: lfs getstripe does not break leases, part 2 ========================================================== 05:26:12 (1761125172) [ 4295.828160] Lustre: DEBUG MARKER: == sanityn test 54: rename locking ======================= 05:26:16 (1761125176) [ 4320.719960] Lustre: DEBUG MARKER: == sanityn test 55a: rename vs unlink target dir ========= 05:26:41 (1761125201) [ 4328.470570] Lustre: DEBUG MARKER: == sanityn test 55b: rename vs unlink source dir ========= 05:26:49 (1761125209) [ 4336.420570] Lustre: DEBUG MARKER: == sanityn test 55c: rename vs unlink orphan target dir == 05:26:57 (1761125217) [ 4349.798025] Lustre: DEBUG MARKER: == sanityn test 55d: rename file vs link ================= 05:27:10 (1761125230) [ 4359.732690] Lustre: DEBUG MARKER: == sanityn test 60: Verify data_version behaviour ======== 05:27:20 (1761125240) [ 4362.283718] Lustre: DEBUG MARKER: == sanityn test 70a: cd directory [ 4364.566343] Lustre: DEBUG MARKER: == sanityn test 70b: remove files after calling rm_entry ========================================================== 05:27:25 (1761125245) [ 4367.257960] Lustre: DEBUG MARKER: == sanityn test 71a: correct file map just after write operation is finished ========================================================== 05:27:28 (1761125248) [ 4369.913030] Lustre: DEBUG MARKER: == sanityn test 71b: check fiemap support for stripecount > 1 ========================================================== 05:27:30 (1761125250) [ 4372.674658] Lustre: DEBUG MARKER: == sanityn test 71c: check FIEMAP_EXTENT_LAST flag with different extents number ========================================================== 05:27:33 (1761125253) [ 4383.110133] Lustre: DEBUG MARKER: == sanityn test 71d: fiemap corruption test with fm_extent_count=0 ========================================================== 05:27:44 (1761125264) [ 4397.782086] Lustre: DEBUG MARKER: == sanityn test 72: getxattr/setxattr cache should be consistent between nodes ========================================================== 05:27:58 (1761125278) [ 4400.385996] Lustre: DEBUG MARKER: == sanityn test 73: getxattr should not cause xattr lock cancellation ========================================================== 05:28:01 (1761125281) [ 4403.104482] Lustre: DEBUG MARKER: == sanityn test 74: flock deadlock: different mounts ======================================================================== 05:28:04 (1761125284) [ 4406.222181] LustreError: 11-0: lustre-MDT0000-mdc-ffff9b8219a1c000: operation ldlm_enqueue to node 192.168.204.142@tcp failed: rc = -35 [ 4406.226659] LustreError: Skipped 1 previous similar message [ 4409.649037] Lustre: DEBUG MARKER: == sanityn test 75: osc: upcall after unuse lock============================================================================= 05:28:10 (1761125290) [ 4409.834662] LustreError: 2269:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 31d sleeping for 2000ms [ 4411.919428] LustreError: 2269:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 31d awake [ 4417.407679] Lustre: DEBUG MARKER: == sanityn test 76: Verify MDT open_files listing ======== 05:28:18 (1761125298) [ 4498.184584] Lustre: DEBUG MARKER: == sanityn test 77a: check FIFO NRS policy =============== 05:29:38 (1761125378) [ 4509.094154] Lustre: DEBUG MARKER: == sanityn test 77b: check CRR-N NRS policy ============== 05:29:48 (1761125388) [ 4520.415678] Lustre: lustre-OST0000-osc-ffff9b8203331800: disconnect after 24s idle [ 4520.425831] Lustre: Skipped 5 previous similar messages [ 4523.639656] Lustre: DEBUG MARKER: == sanityn test 77c: check ORR NRS policy ================ 05:30:03 (1761125403) [ 4535.827905] Lustre: DEBUG MARKER: == sanityn test 77d: check TRR nrs policy ================ 05:30:16 (1761125416) [ 4546.417638] Lustre: DEBUG MARKER: == sanityn test 77e: check TBF NID nrs policy ============ 05:30:26 (1761125426) [ 4565.111432] Lustre: DEBUG MARKER: == sanityn test 77f: check TBF JobID nrs policy ========== 05:30:45 (1761125445) [ 4579.004250] Lustre: DEBUG MARKER: == sanityn test 77g: Change TBF type directly ============ 05:30:59 (1761125459) [ 4586.261718] Lustre: DEBUG MARKER: == sanityn test 77h: Wrong policy name should report error, not LBUG ========================================================== 05:31:06 (1761125466) [ 4595.661641] Lustre: DEBUG MARKER: == sanityn test 77i: Change rank of TBF rule ============= 05:31:16 (1761125476) [ 4615.304980] Lustre: DEBUG MARKER: == sanityn test 77j: check TBF-OPCode NRS policy ========= 05:31:35 (1761125495) [ 4661.300955] Lustre: DEBUG MARKER: == sanityn test 77ja: check TBF-UID/GID NRS policy ======= 05:32:22 (1761125542) [ 4789.494679] Lustre: DEBUG MARKER: == sanityn test 77k: check TBF policy with NID/JobID/OPCode expression ========================================================== 05:34:29 (1761125669) [ 5114.909323] Lustre: DEBUG MARKER: == sanityn test 77l: check the output of NRS policies for generic TBF ========================================================== 05:39:55 (1761125995) [ 5122.970201] Lustre: DEBUG MARKER: == sanityn test 77m: check NRS Delay slows write RPC processing ========================================================== 05:40:03 (1761126003) [ 5177.784965] Lustre: DEBUG MARKER: == sanityn test 77n: check wildcard support for TBF JobID NRS policy ========================================================== 05:40:58 (1761126058) [ 5201.375576] Lustre: lustre-OST0001-osc-ffff9b8219a1c000: disconnect after 21s idle [ 5201.387933] Lustre: Skipped 15 previous similar messages [ 5257.709500] Lustre: DEBUG MARKER: == sanityn test 77o: Changing rank should not panic ====== 05:42:18 (1761126138) [ 5266.246772] Lustre: DEBUG MARKER: == sanityn test 77q: Parallel TBF rule definitions should not panic ========================================================== 05:42:26 (1761126146) [ 5349.235288] Lustre: DEBUG MARKER: == sanityn test 77p: Check validity of rule names for TBF policies ========================================================== 05:43:49 (1761126229) [ 5378.402570] Lustre: DEBUG MARKER: == sanityn test 78: Enable policy and specify tunings right away ========================================================== 05:44:18 (1761126258) [ 5386.391778] Lustre: DEBUG MARKER: == sanityn test 79: xattr: intent error ================== 05:44:26 (1761126266) [ 5403.581776] Lustre: DEBUG MARKER: == sanityn test 80a: migrate directory when some children is being opened ========================================================== 05:44:43 (1761126283) [ 5406.573072] LustreError: 308588:0:(file.c:246:ll_close_inode_openhandle()) lustre-clilmv-ffff9b8219a1c000: inode [0x200000402:0x7c9:0x0] mdc close failed: rc = -116 [ 5407.101222] LustreError: 308592:0:(file.c:246:ll_close_inode_openhandle()) lustre-clilmv-ffff9b8219a1c000: inode [0x240000402:0x5fd:0x0] mdc close failed: rc = -116 [ 5407.106107] LustreError: 308592:0:(file.c:246:ll_close_inode_openhandle()) Skipped 2 previous similar messages [ 5415.711526] Lustre: DEBUG MARKER: == sanityn test 80b: Accessing directory during migration ========================================================== 05:44:55 (1761126295) [ 5435.515787] LustreError: 309487:0:(file.c:246:ll_close_inode_openhandle()) lustre-clilmv-ffff9b8219a1c000: inode [0x200000402:0x855:0x0] mdc close failed: rc = -116 [ 5435.521664] LustreError: 309487:0:(file.c:246:ll_close_inode_openhandle()) Skipped 8 previous similar messages [ 5456.737913] Lustre: dir [0x200000402:0x8b6:0x0] stripe 1 readdir failed: -2, directory is partially accessed! [ 5465.512472] LustreError: 309929:0:(file.c:246:ll_close_inode_openhandle()) lustre-clilmv-ffff9b8203331800: inode [0x200000402:0x8f2:0x0] mdc close failed: rc = -2 [ 5482.055793] Lustre: DEBUG MARKER: == sanityn test 81a: rename and stat under striped directory ========================================================== 05:46:02 (1761126362) [ 5488.746672] Lustre: DEBUG MARKER: == sanityn test 81b: rename under striped directory doesn't deadlock ========================================================== 05:46:09 (1761126369) [ 5568.768606] Lustre: DEBUG MARKER: == sanityn test 81c: rename revoke LOOKUP lock for remote object ========================================================== 05:47:28 (1761126448) [ 5569.947989] Lustre: DEBUG MARKER: SKIP: sanityn test_81c needs >= 4 MDTs [ 5571.706295] Lustre: DEBUG MARKER: == sanityn test 82: fsetxattr and fgetxattr on orphan files ========================================================== 05:47:31 (1761126451) [ 5577.425562] Lustre: DEBUG MARKER: == sanityn test 83: access striped directory while it is being created/unlinked ========================================================== 05:47:37 (1761126457) [ 5704.068563] Lustre: DEBUG MARKER: == sanityn test 84: 0-nlink race in lu_object_find() ===== 05:49:44 (1761126584) [ 5715.775693] Lustre: DEBUG MARKER: == sanityn test 90: open/create and unlink striped directory ========================================================== 05:49:55 (1761126595) [ 5903.003641] Lustre: DEBUG MARKER: == sanityn test 91: chmod and unlink striped directory === 05:53:03 (1761126783) [ 6089.436768] Lustre: DEBUG MARKER: == sanityn test 92: create remote directory under orphan directory ========================================================== 05:56:09 (1761126969) [ 6096.030769] Lustre: DEBUG MARKER: == sanityn test 93: alloc_rr should not allocate on same ost ========================================================== 05:56:16 (1761126976) [ 6110.892629] Lustre: DEBUG MARKER: == sanityn test 94: signal vs CP callback race =========== 05:56:31 (1761126991) [ 6111.135282] Lustre: DEBUG MARKER: write [ 6111.169894] LustreError: 5578:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 312 sleeping for 5000ms [ 6113.196240] Lustre: DEBUG MARKER: kill 323338 [ 6113.201574] LustreError: 323338:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 329 sleeping for 6000ms [ 6116.199171] LustreError: 5578:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 312 awake [ 6119.247141] LustreError: 323338:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 329 awake [ 6125.470070] Lustre: DEBUG MARKER: == sanityn test 95b: Check readpage() on a page that is no longer uptodate ========================================================== 05:56:45 (1761127005) [ 6125.697178] LustreError: 323938:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 1424 sleeping for 5000ms [ 6127.791154] LustreError: 323938:0:(fail.c:144:__cfs_fail_timeout_set()) cfs_fail_timeout interrupted [ 6136.835363] Lustre: DEBUG MARKER: == sanityn test 100a: DoM: glimpse RPCs for stat without IO lock (DoM only file) ========================================================== 05:56:57 (1761127017) [ 6138.168889] Lustre: DEBUG MARKER: SKIP: sanityn test_100a Reserved for glimpse-ahead [ 6140.129675] Lustre: DEBUG MARKER: == sanityn test 100b: DoM: no glimpse RPC for stat with IO lock (DoM only file) ========================================================== 05:57:00 (1761127020) [ 6146.401545] Lustre: DEBUG MARKER: == sanityn test 100c: DoM: write vs stat without IO lock (combined file) ========================================================== 05:57:06 (1761127026) [ 6153.239357] Lustre: DEBUG MARKER: == sanityn test 100d: DoM: write+truncate vs stat without IO lock (combined file) ========================================================== 05:57:13 (1761127033) [ 6160.660959] Lustre: DEBUG MARKER: == sanityn test 100e: DoM: read on open and file size ==== 05:57:20 (1761127040) [ 6168.913739] Lustre: DEBUG MARKER: == sanityn test 101a: Discard DoM data on unlink ========= 05:57:28 (1761127048) [ 6176.282329] Lustre: DEBUG MARKER: == sanityn test 101b: Discard DoM data on rename ========= 05:57:36 (1761127056) [ 6184.640913] Lustre: DEBUG MARKER: == sanityn test 101c: Discard DoM data on close-unlink === 05:57:44 (1761127064) [ 6193.217850] Lustre: DEBUG MARKER: == sanityn test 102: Test open by handle of unlinked file ========================================================== 05:57:53 (1761127073) [ 6201.466660] Lustre: DEBUG MARKER: == sanityn test 103: Test size correctness with lockahead ========================================================== 05:58:01 (1761127081) [ 6203.248648] Lustre: *** cfs_fail_loc=415, val=0*** [ 6214.465259] Lustre: DEBUG MARKER: == sanityn test 104: Verify that MDS stores atime/mtime/ctime during close ========================================================== 05:58:14 (1761127094) [ 6243.975300] Lustre: DEBUG MARKER: == sanityn test 105: Glimpse and lock cancel race ======== 05:58:43 (1761127123) [ 6244.289500] LustreError: 5578:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 sleeping for 5000ms [ 6249.295221] LustreError: 5578:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 awake [ 6254.319133] LustreError: 5578:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 awake [ 6254.328997] LustreError: 5578:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 sleeping for 5000ms [ 6254.337023] LustreError: 5578:0:(fail.c:138:__cfs_fail_timeout_set()) Skipped 1 previous similar message [ 6264.482471] LustreError: 5578:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 awake [ 6264.494165] LustreError: 5578:0:(fail.c:149:__cfs_fail_timeout_set()) Skipped 1 previous similar message [ 6274.608276] LustreError: 20959:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 sleeping for 5000ms [ 6274.617921] LustreError: 20959:0:(fail.c:138:__cfs_fail_timeout_set()) Skipped 3 previous similar messages [ 6284.708414] LustreError: 20959:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 awake [ 6284.711473] LustreError: 20959:0:(fail.c:149:__cfs_fail_timeout_set()) Skipped 3 previous similar messages [ 6309.938496] LustreError: 5578:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 sleeping for 5000ms [ 6309.961873] LustreError: 5578:0:(fail.c:138:__cfs_fail_timeout_set()) Skipped 6 previous similar messages [ 6320.015513] LustreError: 5578:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 awake [ 6320.019021] LustreError: 5578:0:(fail.c:149:__cfs_fail_timeout_set()) Skipped 6 previous similar messages [ 6333.379370] Lustre: DEBUG MARKER: == sanityn test 106a: Verify the btime via statx() ======= 06:00:13 (1761127213) [ 6344.389449] Lustre: DEBUG MARKER: == sanityn test 106b: Glimpse RPCs test for statx ======== 06:00:24 (1761127224) [ 6352.675480] Lustre: DEBUG MARKER: == sanityn test 106c: Verify statx attributes mask ======= 06:00:32 (1761127232) [ 6360.826316] Lustre: DEBUG MARKER: == sanityn test 107a: Basic grouplock conflict =========== 06:00:40 (1761127240) [ 6370.479905] Lustre: DEBUG MARKER: == sanityn test 107b: Grouplock is added to the head of waiting list ========================================================== 06:00:50 (1761127250) [ 6383.550711] Lustre: DEBUG MARKER: == sanityn test 108a: lseek: parallel updates ============ 06:01:03 (1761127263) [ 6384.141607] LustreError: 334590:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 414 sleeping for 4000ms [ 6384.145343] LustreError: 334590:0:(fail.c:138:__cfs_fail_timeout_set()) Skipped 2 previous similar messages [ 6388.231110] LustreError: 334590:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 414 awake [ 6388.238127] LustreError: 334590:0:(fail.c:149:__cfs_fail_timeout_set()) Skipped 1 previous similar message [ 6394.492264] Lustre: DEBUG MARKER: == sanityn test 109: Race with several mount instances on 1 node ========================================================== 06:01:14 (1761127274) [ 6397.106269] Lustre: Unmounted lustre-client [ 6399.465024] Lustre: Unmounted lustre-client [ 6400.724042] Lustre: DEBUG MARKER: Iteration 1 [ 6401.316466] LustreError: 335473:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 6401.319111] LustreError: 335472:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 6401.443790] LustreError: 335473:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4877 [ 6401.721942] Lustre: Mounted lustre-client [ 6403.069244] Lustre: Unmounted lustre-client [ 6405.619398] Key type lgssc unregistered [ 6405.881943] LNet: 335829:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6406.946180] LNet: Removed LNI 192.168.204.42@tcp [ 6407.510446] Key type .llcrypt unregistered [ 6407.513101] Key type ._llcrypt unregistered [ 6408.102679] alg: No test for adler32 (adler32-zlib) [ 6408.855677] Key type ._llcrypt registered [ 6408.857157] Key type .llcrypt registered [ 6409.195656] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6409.748678] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 6410.462053] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 6410.465802] LNet: Accept secure, port 988 [ 6412.175175] Key type lgssc registered [ 6413.306491] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6422.748087] Lustre: DEBUG MARKER: Iteration 2 [ 6423.016518] LustreError: 336624:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 6423.021122] LustreError: 336625:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 6423.025403] LustreError: 336624:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=5000 [ 6424.206612] Lustre: Mounted lustre-client [ 6425.119987] Lustre: Unmounted lustre-client [ 6426.880089] Key type lgssc unregistered [ 6427.044689] LNet: 336975:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6428.068397] LNet: Removed LNI 192.168.204.42@tcp [ 6428.476709] Key type .llcrypt unregistered [ 6428.478681] Key type ._llcrypt unregistered [ 6429.103825] alg: No test for adler32 (adler32-zlib) [ 6429.857645] Key type ._llcrypt registered [ 6429.861244] Key type .llcrypt registered [ 6429.995707] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6430.192124] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 6430.318150] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 6430.321137] LNet: Accept secure, port 988 [ 6431.951396] Key type lgssc registered [ 6432.897942] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6442.008881] Lustre: DEBUG MARKER: Iteration 3 [ 6442.209076] LustreError: 337769:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 6442.209966] LustreError: 337774:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 6442.226442] LustreError: 337769:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4990 [ 6443.426167] Lustre: Mounted lustre-client [ 6444.383611] Lustre: Unmounted lustre-client [ 6446.140289] Key type lgssc unregistered [ 6446.296081] LNet: 338118:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6447.328686] LNet: Removed LNI 192.168.204.42@tcp [ 6447.782946] Key type .llcrypt unregistered [ 6447.785116] Key type ._llcrypt unregistered [ 6448.224172] alg: No test for adler32 (adler32-zlib) [ 6448.976805] Key type ._llcrypt registered [ 6448.982347] Key type .llcrypt registered [ 6449.142832] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6449.381835] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 6449.539078] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 6449.543395] LNet: Accept secure, port 988 [ 6451.183792] Key type lgssc registered [ 6452.158321] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6460.516319] Lustre: DEBUG MARKER: Iteration 4 [ 6460.698756] LustreError: 338909:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 6460.704336] LustreError: 338914:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 6460.710242] LustreError: 338909:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4992 [ 6461.904977] Lustre: Mounted lustre-client [ 6462.811659] Lustre: Unmounted lustre-client [ 6464.824989] Key type lgssc unregistered [ 6465.068250] LNet: 339267:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6466.082358] LNet: Removed LNI 192.168.204.42@tcp [ 6466.583531] Key type .llcrypt unregistered [ 6466.584942] Key type ._llcrypt unregistered [ 6467.082054] alg: No test for adler32 (adler32-zlib) [ 6467.835588] Key type ._llcrypt registered [ 6467.837152] Key type .llcrypt registered [ 6468.009119] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6468.213729] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 6468.345526] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 6468.348450] LNet: Accept secure, port 988 [ 6469.983252] Key type lgssc registered [ 6470.672799] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6478.704890] Lustre: DEBUG MARKER: Iteration 5 [ 6478.943168] LustreError: 340061:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 6478.945323] LustreError: 340062:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 6478.958329] LustreError: 340061:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4994 [ 6480.139739] Lustre: Mounted lustre-client [ 6481.391678] Lustre: Unmounted lustre-client [ 6483.437801] Key type lgssc unregistered [ 6483.603784] LNet: 340414:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6484.640857] LNet: Removed LNI 192.168.204.42@tcp [ 6485.079126] Key type .llcrypt unregistered [ 6485.080990] Key type ._llcrypt unregistered [ 6485.540519] alg: No test for adler32 (adler32-zlib) [ 6486.292554] Key type ._llcrypt registered [ 6486.295316] Key type .llcrypt registered [ 6486.462660] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6486.673633] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 6486.822210] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 6486.825550] LNet: Accept secure, port 988 [ 6488.503233] Key type lgssc registered [ 6489.352204] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6498.842433] Lustre: DEBUG MARKER: Iteration 6 [ 6499.299101] LustreError: 341210:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 6499.299137] LustreError: 341209:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 6499.316479] LustreError: 341210:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4989 [ 6500.624254] Lustre: Mounted lustre-client [ 6501.658895] Lustre: Unmounted lustre-client [ 6503.900637] Key type lgssc unregistered [ 6504.142475] LNet: 341561:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6505.185667] LNet: Removed LNI 192.168.204.42@tcp [ 6505.802448] Key type .llcrypt unregistered [ 6505.805292] Key type ._llcrypt unregistered [ 6506.286298] alg: No test for adler32 (adler32-zlib) [ 6507.051529] Key type ._llcrypt registered [ 6507.053122] Key type .llcrypt registered [ 6507.249711] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6507.539489] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 6507.754724] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 6507.762334] LNet: Accept secure, port 988 [ 6509.416626] Key type lgssc registered [ 6510.433913] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6520.290837] Lustre: DEBUG MARKER: Iteration 7 [ 6520.470671] LustreError: 342351:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 6520.474254] LustreError: 342356:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 6520.488281] LustreError: 342351:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4990 [ 6521.702786] Lustre: Mounted lustre-client [ 6522.869531] Lustre: Unmounted lustre-client [ 6525.109552] Key type lgssc unregistered [ 6525.330505] LNet: 342711:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6526.372922] LNet: Removed LNI 192.168.204.42@tcp [ 6526.914249] Key type .llcrypt unregistered [ 6526.921907] Key type ._llcrypt unregistered [ 6527.470760] alg: No test for adler32 (adler32-zlib) [ 6528.227462] Key type ._llcrypt registered [ 6528.233744] Key type .llcrypt registered [ 6528.428166] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6528.692158] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 6528.905768] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 6528.909226] LNet: Accept secure, port 988 [ 6530.567201] Key type lgssc registered [ 6531.335880] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6539.855785] Lustre: DEBUG MARKER: Iteration 8 [ 6540.046890] LustreError: 343505:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 6540.047319] LustreError: 343506:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 6540.060944] LustreError: 343505:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4992 [ 6541.204677] Lustre: Mounted lustre-client [ 6541.206366] Lustre: Skipped 1 previous similar message [ 6542.111208] Lustre: Unmounted lustre-client [ 6543.547188] Key type lgssc unregistered [ 6543.682545] LNet: 343854:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6544.736304] LNet: Removed LNI 192.168.204.42@tcp [ 6545.113806] Key type .llcrypt unregistered [ 6545.115797] Key type ._llcrypt unregistered [ 6545.458316] alg: No test for adler32 (adler32-zlib) [ 6546.209102] Key type ._llcrypt registered [ 6546.211122] Key type .llcrypt registered [ 6546.318824] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6546.502526] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 6546.646126] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 6546.649897] LNet: Accept secure, port 988 [ 6548.295147] Key type lgssc registered [ 6549.039437] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6555.763749] Lustre: DEBUG MARKER: Iteration 9 [ 6556.024515] LustreError: 344648:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 6556.024858] LustreError: 344649:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 6556.033949] LustreError: 344648:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [ 6557.186995] Lustre: Mounted lustre-client [ 6557.186995] Lustre: Mounted lustre-client [ 6557.895280] Lustre: Unmounted lustre-client [ 6559.378681] Key type lgssc unregistered [ 6559.516327] LNet: 344998:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6560.544205] LNet: Removed LNI 192.168.204.42@tcp [ 6560.889499] Key type .llcrypt unregistered [ 6560.891872] Key type ._llcrypt unregistered [ 6561.204717] alg: No test for adler32 (adler32-zlib) [ 6561.957548] Key type ._llcrypt registered [ 6561.959847] Key type .llcrypt registered [ 6562.098740] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6562.314189] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 6562.455280] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 6562.459560] LNet: Accept secure, port 988 [ 6564.087125] Key type lgssc registered [ 6564.834512] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6573.413971] Lustre: DEBUG MARKER: Iteration 10 [ 6573.650793] LustreError: 345793:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 6573.653150] LustreError: 345790:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 6573.663373] LustreError: 345793:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4992 [ 6574.902337] Lustre: Mounted lustre-client [ 6574.904626] Lustre: Skipped 1 previous similar message [ 6575.924204] Lustre: Unmounted lustre-client [ 6578.245365] Key type lgssc unregistered [ 6578.431752] LNet: 346143:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6579.494456] LNet: Removed LNI 192.168.204.42@tcp [ 6579.905763] Key type .llcrypt unregistered [ 6579.908299] Key type ._llcrypt unregistered [ 6580.476506] alg: No test for adler32 (adler32-zlib) [ 6581.229356] Key type ._llcrypt registered [ 6581.233312] Key type .llcrypt registered [ 6581.385864] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6581.620520] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 6581.808610] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 6581.815915] LNet: Accept secure, port 988 [ 6583.455959] Key type lgssc registered [ 6584.434457] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6592.023239] Lustre: DEBUG MARKER: Iteration 11 [ 6592.198615] LustreError: 346938:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 6592.199905] LustreError: 346937:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 6592.209531] LustreError: 346938:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [ 6593.333707] Lustre: Mounted lustre-client [ 6594.195864] Lustre: Unmounted lustre-client [ 6595.777035] Key type lgssc unregistered [ 6595.934166] LNet: 347286:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6596.960602] LNet: Removed LNI 192.168.204.42@tcp [ 6597.407091] Key type .llcrypt unregistered [ 6597.408664] Key type ._llcrypt unregistered [ 6597.866667] alg: No test for adler32 (adler32-zlib) [ 6598.639501] Key type ._llcrypt registered [ 6598.641110] Key type .llcrypt registered [ 6598.788619] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6598.993463] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 6599.115608] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 6599.118438] LNet: Accept secure, port 988 [ 6600.759271] Key type lgssc registered [ 6601.570944] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6608.623598] Lustre: DEBUG MARKER: Iteration 12 [ 6608.760523] LustreError: 348081:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 6608.760901] LustreError: 348082:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 6608.770638] LustreError: 348081:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 6609.890189] Lustre: Mounted lustre-client [ 6610.777698] Lustre: Unmounted lustre-client [ 6612.443474] Key type lgssc unregistered [ 6612.599179] LNet: 348432:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6613.664193] LNet: Removed LNI 192.168.204.42@tcp [ 6614.045848] Key type .llcrypt unregistered [ 6614.047822] Key type ._llcrypt unregistered [ 6614.429161] alg: No test for adler32 (adler32-zlib) [ 6615.185492] Key type ._llcrypt registered [ 6615.186917] Key type .llcrypt registered [ 6615.330231] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6615.532781] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 6615.671211] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 6615.673694] LNet: Accept secure, port 988 [ 6617.287181] Key type lgssc registered [ 6618.009245] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6625.910622] Lustre: DEBUG MARKER: Iteration 13 [ 6626.129084] LustreError: 349227:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 6626.131291] LustreError: 349226:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 6626.138027] LustreError: 349227:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 6627.262731] Lustre: Mounted lustre-client [ 6628.227219] Lustre: Unmounted lustre-client [ 6629.954503] Key type lgssc unregistered [ 6630.094706] LNet: 349577:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6631.137664] LNet: Removed LNI 192.168.204.42@tcp [ 6631.643800] Key type .llcrypt unregistered [ 6631.648434] Key type ._llcrypt unregistered [ 6632.116408] alg: No test for adler32 (adler32-zlib) [ 6632.870639] Key type ._llcrypt registered [ 6632.872232] Key type .llcrypt registered [ 6632.990630] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6633.188746] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 6633.361515] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 6633.364486] LNet: Accept secure, port 988 [ 6634.999618] Key type lgssc registered [ 6635.758924] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6642.769684] Lustre: DEBUG MARKER: Iteration 14 [ 6642.972280] LustreError: 350372:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 6642.973100] LustreError: 350373:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 6642.979955] LustreError: 350372:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 6644.142525] Lustre: Mounted lustre-client [ 6645.283771] Lustre: Unmounted lustre-client [ 6647.146104] Key type lgssc unregistered [ 6647.310806] LNet: 350722:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6648.352285] LNet: Removed LNI 192.168.204.42@tcp [ 6648.723521] Key type .llcrypt unregistered [ 6648.727955] Key type ._llcrypt unregistered [ 6649.194395] alg: No test for adler32 (adler32-zlib) [ 6649.946596] Key type ._llcrypt registered [ 6649.953198] Key type .llcrypt registered [ 6650.112079] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6650.316155] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 6650.472129] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 6650.475619] LNet: Accept secure, port 988 [ 6652.103930] Key type lgssc registered [ 6652.821696] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6660.148230] Lustre: DEBUG MARKER: Iteration 15 [ 6660.397609] LustreError: 351515:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 6660.398457] LustreError: 351516:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 6660.410233] LustreError: 351515:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4990 [ 6661.569544] Lustre: Mounted lustre-client [ 6661.570922] Lustre: Skipped 1 previous similar message [ 6662.574388] Lustre: Unmounted lustre-client [ 6664.193498] Key type lgssc unregistered [ 6664.343566] LNet: 351864:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6665.378393] LNet: Removed LNI 192.168.204.42@tcp [ 6665.840381] Key type .llcrypt unregistered [ 6665.844120] Key type ._llcrypt unregistered [ 6666.368915] alg: No test for adler32 (adler32-zlib) [ 6667.121679] Key type ._llcrypt registered [ 6667.123808] Key type .llcrypt registered [ 6667.291716] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6667.544242] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 6667.706283] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 6667.709491] LNet: Accept secure, port 988 [ 6669.375197] Key type lgssc registered [ 6670.381273] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6679.027259] Lustre: DEBUG MARKER: Iteration 16 [ 6679.377103] LustreError: 352660:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 6679.377837] LustreError: 352661:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 6679.389917] LustreError: 352660:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4994 [ 6680.680175] Lustre: Mounted lustre-client [ 6680.685425] Lustre: Skipped 1 previous similar message [ 6681.917724] Lustre: Unmounted lustre-client [ 6684.240700] Key type lgssc unregistered [ 6684.500814] LNet: 353013:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6685.537631] LNet: Removed LNI 192.168.204.42@tcp [ 6686.088253] Key type .llcrypt unregistered [ 6686.090589] Key type ._llcrypt unregistered [ 6686.594687] alg: No test for adler32 (adler32-zlib) [ 6687.351710] Key type ._llcrypt registered [ 6687.355623] Key type .llcrypt registered [ 6687.507604] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6687.722328] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 6687.927156] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 6687.931910] LNet: Accept secure, port 988 [ 6689.592130] Key type lgssc registered [ 6690.424186] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6698.915787] Lustre: DEBUG MARKER: Iteration 17 [ 6699.230309] LustreError: 353806:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 6699.231150] LustreError: 353807:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 6699.247523] LustreError: 353806:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4993 [ 6700.476601] Lustre: Mounted lustre-client [ 6701.359168] Lustre: Unmounted lustre-client [ 6702.804362] Key type lgssc unregistered [ 6702.939802] LNet: 354162:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6703.968430] LNet: Removed LNI 192.168.204.42@tcp [ 6704.334585] Key type .llcrypt unregistered [ 6704.337331] Key type ._llcrypt unregistered [ 6704.726881] alg: No test for adler32 (adler32-zlib) [ 6705.480389] Key type ._llcrypt registered [ 6705.482230] Key type .llcrypt registered [ 6705.626219] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6705.808468] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 6705.944547] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 6705.947269] LNet: Accept secure, port 988 [ 6707.559659] Key type lgssc registered [ 6708.179935] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6715.501990] Lustre: DEBUG MARKER: Iteration 18 [ 6715.723974] LustreError: 354951:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 6715.728250] LustreError: 354956:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 6715.734295] LustreError: 354951:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4994 [ 6716.871579] Lustre: Mounted lustre-client [ 6717.686236] Lustre: Unmounted lustre-client [ 6719.156800] Key type lgssc unregistered [ 6719.313518] LNet: 355305:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6720.353367] LNet: Removed LNI 192.168.204.42@tcp [ 6720.760481] Key type .llcrypt unregistered [ 6720.761904] Key type ._llcrypt unregistered [ 6721.260075] alg: No test for adler32 (adler32-zlib) [ 6722.013437] Key type ._llcrypt registered [ 6722.015036] Key type .llcrypt registered [ 6722.124640] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6722.309114] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 6722.460747] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 6722.463777] LNet: Accept secure, port 988 [ 6724.087230] Key type lgssc registered [ 6724.797492] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6732.307864] Lustre: DEBUG MARKER: Iteration 19 [ 6732.524253] LustreError: 356098:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 6732.524374] LustreError: 356099:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 6732.536580] LustreError: 356098:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [ 6733.708109] Lustre: Mounted lustre-client [ 6734.569202] Lustre: Unmounted lustre-client [ 6736.155525] Key type lgssc unregistered [ 6736.293165] LNet: 356447:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6737.312366] LNet: Removed LNI 192.168.204.42@tcp [ 6737.741931] Key type .llcrypt unregistered [ 6737.743782] Key type ._llcrypt unregistered [ 6738.248251] alg: No test for adler32 (adler32-zlib) [ 6739.007389] Key type ._llcrypt registered [ 6739.009962] Key type .llcrypt registered [ 6739.144469] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6739.324050] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 6739.458964] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 6739.461720] LNet: Accept secure, port 988 [ 6741.071920] Key type lgssc registered [ 6741.698459] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6747.646940] Lustre: DEBUG MARKER: Iteration 20 [ 6747.811927] LustreError: 357240:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 6747.812303] LustreError: 357241:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 6747.821522] LustreError: 357240:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [ 6748.933465] Lustre: Mounted lustre-client [ 6749.808508] Lustre: Unmounted lustre-client [ 6751.219451] Key type lgssc unregistered [ 6751.356695] LNet: 357592:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6752.416582] LNet: Removed LNI 192.168.204.42@tcp [ 6752.788851] Key type .llcrypt unregistered [ 6752.793079] Key type ._llcrypt unregistered [ 6753.143294] alg: No test for adler32 (adler32-zlib) [ 6753.895822] Key type ._llcrypt registered [ 6753.898529] Key type .llcrypt registered [ 6753.998820] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6754.148184] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 6754.268246] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 6754.270929] LNet: Accept secure, port 988 [ 6755.887241] Key type lgssc registered [ 6756.586850] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6762.788390] Lustre: DEBUG MARKER: Iteration 21 [ 6762.930525] LustreError: 358385:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 6762.931923] LustreError: 358386:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 6762.940420] LustreError: 358385:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4991 [ 6764.089581] Lustre: Mounted lustre-client [ 6764.093912] Lustre: Skipped 1 previous similar message [ 6764.955382] Lustre: Unmounted lustre-client [ 6766.324938] Key type lgssc unregistered [ 6766.477246] LNet: 358734:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6767.521461] LNet: Removed LNI 192.168.204.42@tcp [ 6767.917206] Key type .llcrypt unregistered [ 6767.919096] Key type ._llcrypt unregistered [ 6768.348451] alg: No test for adler32 (adler32-zlib) [ 6769.104407] Key type ._llcrypt registered [ 6769.106158] Key type .llcrypt registered [ 6769.261806] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6769.457683] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 6769.593187] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 6769.597618] LNet: Accept secure, port 988 [ 6771.225049] Key type lgssc registered [ 6771.911524] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6779.237917] Lustre: DEBUG MARKER: Iteration 22 [ 6779.412797] LustreError: 359530:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 6779.412884] LustreError: 359529:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 6779.428057] LustreError: 359530:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 6780.549604] Lustre: Mounted lustre-client [ 6781.400239] Lustre: Unmounted lustre-client [ 6782.908221] Key type lgssc unregistered [ 6783.075339] LNet: 359877:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6784.096153] LNet: Removed LNI 192.168.204.42@tcp [ 6784.498777] Key type .llcrypt unregistered [ 6784.500370] Key type ._llcrypt unregistered [ 6784.921525] alg: No test for adler32 (adler32-zlib) [ 6785.677496] Key type ._llcrypt registered [ 6785.678905] Key type .llcrypt registered [ 6785.803320] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6785.997779] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 6786.128630] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 6786.130738] LNet: Accept secure, port 988 [ 6787.751121] Key type lgssc registered [ 6788.475126] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6795.579689] Lustre: DEBUG MARKER: Iteration 23 [ 6795.781983] LustreError: 360672:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 6795.783163] LustreError: 360673:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 6795.789323] LustreError: 360672:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 6796.976422] Lustre: Mounted lustre-client [ 6797.850202] Lustre: Unmounted lustre-client [ 6799.318486] Key type lgssc unregistered [ 6799.453059] LNet: 361021:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6800.480338] LNet: Removed LNI 192.168.204.42@tcp [ 6800.828969] Key type .llcrypt unregistered [ 6800.830662] Key type ._llcrypt unregistered [ 6801.273087] alg: No test for adler32 (adler32-zlib) [ 6802.028638] Key type ._llcrypt registered [ 6802.030271] Key type .llcrypt registered [ 6802.166298] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6802.365204] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 6802.502192] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 6802.504747] LNet: Accept secure, port 988 [ 6804.143859] Key type lgssc registered [ 6804.971679] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6811.523602] Lustre: DEBUG MARKER: Iteration 24 [ 6811.648996] LustreError: 361816:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 6811.650248] LustreError: 361817:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 6811.659634] LustreError: 361816:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 6812.753976] Lustre: Mounted lustre-client [ 6813.518625] Lustre: Unmounted lustre-client [ 6813.520478] Lustre: Skipped 1 previous similar message [ 6814.819228] Key type lgssc unregistered [ 6814.952169] LNet: 362167:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6815.969478] LNet: Removed LNI 192.168.204.42@tcp [ 6816.306054] Key type .llcrypt unregistered [ 6816.307669] Key type ._llcrypt unregistered [ 6816.829417] alg: No test for adler32 (adler32-zlib) [ 6817.581460] Key type ._llcrypt registered [ 6817.582989] Key type .llcrypt registered [ 6817.687588] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6817.865860] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 6817.974492] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 6817.978429] LNet: Accept secure, port 988 [ 6819.591724] Key type lgssc registered [ 6820.393229] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6826.358571] Lustre: DEBUG MARKER: Iteration 25 [ 6826.524266] LustreError: 362959:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 6826.541771] LustreError: 362978:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 6826.546186] LustreError: 362959:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4981 [ 6827.669390] Lustre: Mounted lustre-client [ 6827.671156] Lustre: Skipped 1 previous similar message [ 6828.468601] Lustre: Unmounted lustre-client [ 6829.880334] Key type lgssc unregistered [ 6830.041809] LNet: 363312:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6831.072238] LNet: Removed LNI 192.168.204.42@tcp [ 6831.444688] Key type .llcrypt unregistered [ 6831.446781] Key type ._llcrypt unregistered [ 6831.845395] alg: No test for adler32 (adler32-zlib) [ 6832.600461] Key type ._llcrypt registered [ 6832.602674] Key type .llcrypt registered [ 6832.730620] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6832.899860] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 6833.015802] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 6833.019417] LNet: Accept secure, port 988 [ 6834.663166] Key type lgssc registered [ 6835.313393] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6841.170282] Lustre: DEBUG MARKER: Iteration 26 [ 6841.322134] LustreError: 364105:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 6841.322553] LustreError: 364104:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 6841.332466] LustreError: 364105:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [ 6842.426991] Lustre: Mounted lustre-client [ 6842.428681] Lustre: Skipped 1 previous similar message [ 6843.175312] Lustre: Unmounted lustre-client [ 6844.404247] Key type lgssc unregistered [ 6844.521847] LNet: 364454:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6845.540947] LNet: Removed LNI 192.168.204.42@tcp [ 6845.875794] Key type .llcrypt unregistered [ 6845.877532] Key type ._llcrypt unregistered [ 6846.249705] alg: No test for adler32 (adler32-zlib) [ 6847.004375] Key type ._llcrypt registered [ 6847.005593] Key type .llcrypt registered [ 6847.106528] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6847.274797] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 6847.395204] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 6847.397888] LNet: Accept secure, port 988 [ 6849.016109] Key type lgssc registered [ 6849.537393] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6854.211835] Lustre: DEBUG MARKER: Iteration 27 [ 6854.366962] LustreError: 365247:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 6854.368119] LustreError: 365248:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 6854.375532] LustreError: 365247:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 6855.476856] Lustre: Mounted lustre-client [ 6855.478081] Lustre: Skipped 1 previous similar message [ 6856.146082] Lustre: Unmounted lustre-client [ 6857.316761] Key type lgssc unregistered [ 6857.430508] LNet: 365596:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6858.464728] LNet: Removed LNI 192.168.204.42@tcp [ 6858.775950] Key type .llcrypt unregistered [ 6858.777326] Key type ._llcrypt unregistered [ 6859.100961] alg: No test for adler32 (adler32-zlib) [ 6859.853503] Key type ._llcrypt registered [ 6859.857677] Key type .llcrypt registered [ 6859.959041] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6860.103299] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 6860.223953] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 6860.226494] LNet: Accept secure, port 988 [ 6861.823270] Key type lgssc registered [ 6862.382769] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6867.501603] Lustre: DEBUG MARKER: Iteration 28 [ 6867.669483] LustreError: 366389:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 6867.670969] LustreError: 366388:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 6867.678593] LustreError: 366389:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [ 6868.771924] Lustre: Mounted lustre-client [ 6869.516359] Lustre: Unmounted lustre-client [ 6870.870335] Key type lgssc unregistered [ 6871.000804] LNet: 366738:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6872.035905] LNet: Removed LNI 192.168.204.42@tcp [ 6872.358087] Key type .llcrypt unregistered [ 6872.359940] Key type ._llcrypt unregistered [ 6872.719834] alg: No test for adler32 (adler32-zlib) [ 6873.471408] Key type ._llcrypt registered [ 6873.473211] Key type .llcrypt registered [ 6873.564070] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6873.742166] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 6873.888365] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 6873.892169] LNet: Accept secure, port 988 [ 6875.519386] Key type lgssc registered [ 6876.088996] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6881.624230] Lustre: DEBUG MARKER: Iteration 29 [ 6881.766399] LustreError: 367531:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 6881.766489] LustreError: 367532:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 6881.774778] LustreError: 367531:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 6882.890619] Lustre: Mounted lustre-client [ 6883.647527] Lustre: Unmounted lustre-client [ 6884.880663] Key type lgssc unregistered [ 6885.009736] LNet: 367881:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6886.047830] LNet: Removed LNI 192.168.204.42@tcp [ 6886.376924] Key type .llcrypt unregistered [ 6886.378240] Key type ._llcrypt unregistered [ 6886.761111] alg: No test for adler32 (adler32-zlib) [ 6887.512422] Key type ._llcrypt registered [ 6887.514761] Key type .llcrypt registered [ 6887.649874] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6887.815565] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 6887.970921] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 6887.974299] LNet: Accept secure, port 988 [ 6889.591242] Key type lgssc registered [ 6890.287601] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6896.085598] Lustre: DEBUG MARKER: Iteration 30 [ 6896.221740] LustreError: 368675:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 6896.221788] LustreError: 368676:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 6896.229756] LustreError: 368675:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [ 6897.308588] Lustre: Mounted lustre-client [ 6898.040114] Lustre: Unmounted lustre-client [ 6899.380783] Key type lgssc unregistered [ 6899.518694] LNet: 369021:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6900.576327] LNet: Removed LNI 192.168.204.42@tcp [ 6900.888756] Key type .llcrypt unregistered [ 6900.890382] Key type ._llcrypt unregistered [ 6901.313975] alg: No test for adler32 (adler32-zlib) [ 6902.065430] Key type ._llcrypt registered [ 6902.067037] Key type .llcrypt registered [ 6902.170673] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6902.318987] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 6902.418281] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 6902.420604] LNet: Accept secure, port 988 [ 6904.015170] Key type lgssc registered [ 6904.527479] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6909.932257] Lustre: DEBUG MARKER: Iteration 31 [ 6910.111709] LustreError: 369813:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 6910.112918] LustreError: 369814:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 6910.121362] LustreError: 369813:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [ 6911.245591] Lustre: Mounted lustre-client [ 6911.247766] Lustre: Skipped 1 previous similar message [ 6912.013070] Lustre: Unmounted lustre-client [ 6913.344793] Key type lgssc unregistered [ 6913.466040] LNet: 370165:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6914.530061] LNet: Removed LNI 192.168.204.42@tcp [ 6914.844801] Key type .llcrypt unregistered [ 6914.846156] Key type ._llcrypt unregistered [ 6915.172714] alg: No test for adler32 (adler32-zlib) [ 6915.925408] Key type ._llcrypt registered [ 6915.927041] Key type .llcrypt registered [ 6916.035127] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6916.175528] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 6916.289411] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 6916.292318] LNet: Accept secure, port 988 [ 6917.903143] Key type lgssc registered [ 6918.432490] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6923.323204] Lustre: DEBUG MARKER: Iteration 32 [ 6923.465623] LustreError: 370958:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 6923.471046] LustreError: 370960:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 6923.474855] LustreError: 370958:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4994 [ 6924.578131] Lustre: Mounted lustre-client [ 6924.580026] Lustre: Skipped 1 previous similar message [ 6925.273211] Lustre: Unmounted lustre-client [ 6926.400832] Key type lgssc unregistered [ 6926.530625] LNet: 371307:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6927.584571] LNet: Removed LNI 192.168.204.42@tcp [ 6927.889428] Key type .llcrypt unregistered [ 6927.890990] Key type ._llcrypt unregistered [ 6928.212538] alg: No test for adler32 (adler32-zlib) [ 6928.964431] Key type ._llcrypt registered [ 6928.966446] Key type .llcrypt registered [ 6929.071964] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6929.231139] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 6929.336430] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 6929.338677] LNet: Accept secure, port 988 [ 6930.943324] Key type lgssc registered [ 6931.446882] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6936.271534] Lustre: DEBUG MARKER: Iteration 33 [ 6936.410463] LustreError: 372099:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 6936.410750] LustreError: 372097:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 6936.419289] LustreError: 372099:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [ 6937.510340] Lustre: Mounted lustre-client [ 6938.242151] Lustre: Unmounted lustre-client [ 6939.593267] Key type lgssc unregistered [ 6939.749548] LNet: 372450:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6940.769690] LNet: Removed LNI 192.168.204.42@tcp [ 6941.111848] Key type .llcrypt unregistered [ 6941.113950] Key type ._llcrypt unregistered [ 6941.537396] alg: No test for adler32 (adler32-zlib) [ 6942.294714] Key type ._llcrypt registered [ 6942.297629] Key type .llcrypt registered [ 6942.487352] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6942.700703] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 6942.840121] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 6942.842733] LNet: Accept secure, port 988 [ 6944.471529] Key type lgssc registered [ 6945.080806] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6950.409901] Lustre: DEBUG MARKER: Iteration 34 [ 6950.567394] LustreError: 373243:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 6950.567599] LustreError: 373244:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 6950.577376] LustreError: 373243:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 6951.697673] Lustre: Mounted lustre-client [ 6952.411173] Lustre: Unmounted lustre-client [ 6953.649145] Key type lgssc unregistered [ 6953.775462] LNet: 373591:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6954.784139] LNet: Removed LNI 192.168.204.42@tcp [ 6955.098296] Key type .llcrypt unregistered [ 6955.100102] Key type ._llcrypt unregistered [ 6955.438364] alg: No test for adler32 (adler32-zlib) [ 6956.190431] Key type ._llcrypt registered [ 6956.192028] Key type .llcrypt registered [ 6956.293135] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6956.463110] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 6956.587348] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 6956.590331] LNet: Accept secure, port 988 [ 6958.207198] Key type lgssc registered [ 6958.729101] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6963.529765] Lustre: DEBUG MARKER: Iteration 35 [ 6963.669104] LustreError: 374388:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 6963.669302] LustreError: 374387:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 6963.676522] LustreError: 374388:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 6964.772781] Lustre: Mounted lustre-client [ 6965.581915] Lustre: Unmounted lustre-client [ 6966.995252] Key type lgssc unregistered [ 6967.138674] LNet: 374736:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6968.160684] LNet: Removed LNI 192.168.204.42@tcp [ 6968.508285] Key type .llcrypt unregistered [ 6968.509625] Key type ._llcrypt unregistered [ 6969.033361] alg: No test for adler32 (adler32-zlib) [ 6969.785372] Key type ._llcrypt registered [ 6969.787034] Key type .llcrypt registered [ 6969.887971] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6970.049959] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 6970.165971] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 6970.168441] LNet: Accept secure, port 988 [ 6971.791538] Key type lgssc registered [ 6972.412873] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6978.127680] Lustre: DEBUG MARKER: Iteration 36 [ 6978.286427] LustreError: 375529:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 6978.286542] LustreError: 375531:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 6978.297216] LustreError: 375529:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4994 [ 6979.414922] Lustre: Mounted lustre-client [ 6979.416542] Lustre: Skipped 1 previous similar message [ 6980.277155] Lustre: Unmounted lustre-client [ 6981.577542] Key type lgssc unregistered [ 6981.725477] LNet: 375882:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6982.753313] LNet: Removed LNI 192.168.204.42@tcp [ 6983.130045] Key type .llcrypt unregistered [ 6983.132039] Key type ._llcrypt unregistered [ 6983.478521] alg: No test for adler32 (adler32-zlib) [ 6984.231462] Key type ._llcrypt registered [ 6984.232894] Key type .llcrypt registered [ 6984.331857] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6984.474038] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 6984.585809] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 6984.589159] LNet: Accept secure, port 988 [ 6986.199294] Key type lgssc registered [ 6986.724224] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6991.439571] Lustre: DEBUG MARKER: Iteration 37 [ 6991.573351] LustreError: 376675:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 6991.573733] LustreError: 376676:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 6991.581862] LustreError: 376675:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [ 6992.646300] Lustre: Mounted lustre-client [ 6992.647921] Lustre: Skipped 1 previous similar message [ 6993.286630] Lustre: Unmounted lustre-client [ 6994.481996] Key type lgssc unregistered [ 6994.610083] LNet: 377023:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6995.615879] LNet: Removed LNI 192.168.204.42@tcp [ 6995.896873] Key type .llcrypt unregistered [ 6995.898652] Key type ._llcrypt unregistered [ 6996.246372] alg: No test for adler32 (adler32-zlib) [ 6996.998452] Key type ._llcrypt registered [ 6997.000360] Key type .llcrypt registered [ 6997.099200] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6997.261765] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 6997.363345] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 6997.366123] LNet: Accept secure, port 988 [ 6998.967190] Key type lgssc registered [ 6999.513463] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7004.359055] Lustre: DEBUG MARKER: Iteration 38 [ 7004.503227] LustreError: 377815:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7004.503311] LustreError: 377817:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7004.513311] LustreError: 377815:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4994 [ 7005.611075] Lustre: Mounted lustre-client [ 7005.612685] Lustre: Skipped 1 previous similar message [ 7006.376412] Lustre: Unmounted lustre-client [ 7007.818763] Key type lgssc unregistered [ 7007.971060] LNet: 378167:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7008.993071] LNet: Removed LNI 192.168.204.42@tcp [ 7009.316324] Key type .llcrypt unregistered [ 7009.318189] Key type ._llcrypt unregistered [ 7009.679560] alg: No test for adler32 (adler32-zlib) [ 7010.433489] Key type ._llcrypt registered [ 7010.435234] Key type .llcrypt registered [ 7010.539305] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7010.685253] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 7010.796168] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 7010.798679] LNet: Accept secure, port 988 [ 7012.407450] Key type lgssc registered [ 7012.914578] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7017.487160] Lustre: DEBUG MARKER: Iteration 39 [ 7017.643305] LustreError: 378960:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7017.643547] LustreError: 378961:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7017.655292] LustreError: 378960:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4992 [ 7018.740402] Lustre: Mounted lustre-client [ 7018.741938] Lustre: Skipped 1 previous similar message [ 7019.531153] Lustre: Unmounted lustre-client [ 7020.824087] Key type lgssc unregistered [ 7020.954234] LNet: 379310:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7021.984278] LNet: Removed LNI 192.168.204.42@tcp [ 7022.273145] Key type .llcrypt unregistered [ 7022.275253] Key type ._llcrypt unregistered [ 7022.597784] alg: No test for adler32 (adler32-zlib) [ 7023.350500] Key type ._llcrypt registered [ 7023.352144] Key type .llcrypt registered [ 7023.447616] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7023.581414] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 7023.697739] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 7023.700764] LNet: Accept secure, port 988 [ 7025.319169] Key type lgssc registered [ 7025.877584] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7033.434726] Lustre: DEBUG MARKER: Iteration 40 [ 7033.842844] LustreError: 380099:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7033.845033] LustreError: 380104:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7033.864347] LustreError: 380099:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4993 [ 7035.238091] Lustre: Mounted lustre-client [ 7037.886747] Lustre: Unmounted lustre-client [ 7041.612547] Key type lgssc unregistered [ 7041.891846] LNet: 380459:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7042.945892] LNet: Removed LNI 192.168.204.42@tcp [ 7043.521578] Key type .llcrypt unregistered [ 7043.523584] Key type ._llcrypt unregistered [ 7045.224292] alg: No test for adler32 (adler32-zlib) [ 7045.989422] Key type ._llcrypt registered [ 7045.991129] Key type .llcrypt registered [ 7046.313654] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7046.805316] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 7047.147773] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 7047.167532] LNet: Accept secure, port 988 [ 7048.889150] Key type lgssc registered [ 7050.679187] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7063.296520] Lustre: DEBUG MARKER: Iteration 41 [ 7063.710631] LustreError: 381256:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7063.713762] LustreError: 381258:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7063.731077] LustreError: 381256:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=5000 [ 7065.101249] Lustre: Mounted lustre-client [ 7066.776136] Lustre: Unmounted lustre-client [ 7070.372592] Key type lgssc unregistered [ 7070.675189] LNet: 381610:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7071.714542] LNet: Removed LNI 192.168.204.42@tcp [ 7072.501050] Key type .llcrypt unregistered [ 7072.502712] Key type ._llcrypt unregistered [ 7073.348909] alg: No test for adler32 (adler32-zlib) [ 7074.136766] Key type ._llcrypt registered [ 7074.138938] Key type .llcrypt registered [ 7074.394993] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7074.836189] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 7075.285951] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 7075.301873] LNet: Accept secure, port 988 [ 7077.351141] Key type lgssc registered [ 7079.222567] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7093.748730] Lustre: DEBUG MARKER: Iteration 42 [ 7094.216768] LustreError: 382405:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7094.219451] LustreError: 382404:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7094.239433] LustreError: 382405:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4979 [ 7095.532209] Lustre: Mounted lustre-client [ 7095.539154] Lustre: Skipped 1 previous similar message [ 7097.092629] Lustre: Unmounted lustre-client [ 7100.176239] Key type lgssc unregistered [ 7100.443819] LNet: 382756:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7101.472418] LNet: Removed LNI 192.168.204.42@tcp [ 7102.186323] Key type .llcrypt unregistered [ 7102.194851] Key type ._llcrypt unregistered [ 7102.979796] alg: No test for adler32 (adler32-zlib) [ 7103.735684] Key type ._llcrypt registered [ 7103.743658] Key type .llcrypt registered [ 7103.967716] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7104.230813] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 7104.444827] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 7104.453289] LNet: Accept secure, port 988 [ 7106.127242] Key type lgssc registered [ 7107.198533] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7116.529838] Lustre: DEBUG MARKER: Iteration 43 [ 7117.009101] LustreError: 383547:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7117.020549] LustreError: 383556:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7117.031603] LustreError: 383547:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4989 [ 7118.327362] Lustre: Mounted lustre-client [ 7119.217367] Lustre: Unmounted lustre-client [ 7121.461941] Key type lgssc unregistered [ 7121.731357] LNet: 383902:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7122.795596] LNet: Removed LNI 192.168.204.42@tcp [ 7123.470370] Key type .llcrypt unregistered [ 7123.480658] Key type ._llcrypt unregistered [ 7124.087029] alg: No test for adler32 (adler32-zlib) [ 7124.837651] Key type ._llcrypt registered [ 7124.840407] Key type .llcrypt registered [ 7125.032551] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7125.299924] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 7125.487449] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 7125.490474] LNet: Accept secure, port 988 [ 7127.169117] Key type lgssc registered [ 7128.333958] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7137.325822] Lustre: DEBUG MARKER: Iteration 44 [ 7137.549594] LustreError: 384695:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7137.549647] LustreError: 384697:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7137.564785] LustreError: 384695:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4992 [ 7138.784947] Lustre: Mounted lustre-client [ 7139.852169] Lustre: Unmounted lustre-client [ 7142.324240] Key type lgssc unregistered [ 7142.588953] LNet: 385047:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7143.648269] LNet: Removed LNI 192.168.204.42@tcp [ 7144.252230] Key type .llcrypt unregistered [ 7144.253596] Key type ._llcrypt unregistered [ 7144.733151] alg: No test for adler32 (adler32-zlib) [ 7145.490593] Key type ._llcrypt registered [ 7145.492747] Key type .llcrypt registered [ 7145.655537] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7145.883318] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 7146.086195] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 7146.088988] LNet: Accept secure, port 988 [ 7147.735765] Key type lgssc registered [ 7148.700916] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7157.677282] Lustre: DEBUG MARKER: Iteration 45 [ 7157.862857] LustreError: 385838:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7157.865799] LustreError: 385841:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7157.870926] LustreError: 385838:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 7159.090697] Lustre: Mounted lustre-client [ 7160.422619] Lustre: Unmounted lustre-client [ 7162.846895] Key type lgssc unregistered [ 7163.079834] LNet: 386193:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7164.133961] LNet: Removed LNI 192.168.204.42@tcp [ 7164.759495] Key type .llcrypt unregistered [ 7164.761703] Key type ._llcrypt unregistered [ 7165.418487] alg: No test for adler32 (adler32-zlib) [ 7166.200937] Key type ._llcrypt registered [ 7166.202473] Key type .llcrypt registered [ 7166.377827] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7166.638955] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 7166.853794] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 7166.857882] LNet: Accept secure, port 988 [ 7168.488553] Key type lgssc registered [ 7169.555568] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7178.421888] Lustre: DEBUG MARKER: Iteration 46 [ 7178.724234] LustreError: 386983:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7178.741673] LustreError: 386999:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7178.745150] LustreError: 386983:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4982 [ 7179.987693] Lustre: Mounted lustre-client [ 7181.205314] Lustre: Unmounted lustre-client [ 7183.635274] Key type lgssc unregistered [ 7183.896693] LNet: 387338:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7184.928614] LNet: Removed LNI 192.168.204.42@tcp [ 7185.544615] Key type .llcrypt unregistered [ 7185.547588] Key type ._llcrypt unregistered [ 7186.097351] alg: No test for adler32 (adler32-zlib) [ 7186.864195] Key type ._llcrypt registered [ 7186.865600] Key type .llcrypt registered [ 7187.062256] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7187.325832] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 7187.539127] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 7187.544224] LNet: Accept secure, port 988 [ 7189.215875] Key type lgssc registered [ 7190.069197] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7199.174563] Lustre: DEBUG MARKER: Iteration 47 [ 7199.400267] LustreError: 388132:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7199.402516] LustreError: 388133:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7199.416338] LustreError: 388132:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4987 [ 7200.702755] Lustre: Mounted lustre-client [ 7202.128361] Lustre: Unmounted lustre-client [ 7204.346609] Key type lgssc unregistered [ 7204.606774] LNet: 388483:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7205.664586] LNet: Removed LNI 192.168.204.42@tcp [ 7206.236525] Key type .llcrypt unregistered [ 7206.241353] Key type ._llcrypt unregistered [ 7206.920375] alg: No test for adler32 (adler32-zlib) [ 7207.709240] Key type ._llcrypt registered [ 7207.711837] Key type .llcrypt registered [ 7207.984566] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7208.297558] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 7208.489294] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 7208.503845] LNet: Accept secure, port 988 [ 7210.167120] Key type lgssc registered [ 7211.079569] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7219.660986] Lustre: DEBUG MARKER: Iteration 48 [ 7219.928171] LustreError: 389275:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7219.931461] LustreError: 389274:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7219.939747] LustreError: 389275:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4991 [ 7221.250436] Lustre: Mounted lustre-client [ 7221.252230] Lustre: Skipped 1 previous similar message [ 7222.501727] Lustre: Unmounted lustre-client [ 7224.738358] Key type lgssc unregistered [ 7224.960179] LNet: 389632:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7226.019347] LNet: Removed LNI 192.168.204.42@tcp [ 7226.569927] Key type .llcrypt unregistered [ 7226.571652] Key type ._llcrypt unregistered [ 7227.438530] alg: No test for adler32 (adler32-zlib) [ 7228.206096] Key type ._llcrypt registered [ 7228.207691] Key type .llcrypt registered [ 7228.431548] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7228.695791] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 7228.886394] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 7228.897976] LNet: Accept secure, port 988 [ 7230.575140] Key type lgssc registered [ 7231.328812] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7240.337652] Lustre: DEBUG MARKER: Iteration 49 [ 7240.574097] LustreError: 390423:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7240.574438] LustreError: 390426:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7240.582469] LustreError: 390423:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [ 7241.799276] Lustre: Mounted lustre-client [ 7242.957032] Lustre: Unmounted lustre-client [ 7245.163713] Key type lgssc unregistered [ 7245.389499] LNet: 390777:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7246.497523] LNet: Removed LNI 192.168.204.42@tcp [ 7247.065917] Key type .llcrypt unregistered [ 7247.067345] Key type ._llcrypt unregistered [ 7247.523448] alg: No test for adler32 (adler32-zlib) [ 7248.281412] Key type ._llcrypt registered [ 7248.283289] Key type .llcrypt registered [ 7248.460271] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7248.668801] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 7248.857273] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 7248.861834] LNet: Accept secure, port 988 [ 7250.503146] Key type lgssc registered [ 7251.589504] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7260.353516] Lustre: DEBUG MARKER: Iteration 50 [ 7260.569908] LustreError: 391566:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7260.572507] LustreError: 391575:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7260.587511] LustreError: 391566:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4993 [ 7261.818624] Lustre: Mounted lustre-client [ 7263.555374] Lustre: Unmounted lustre-client [ 7266.861193] Key type lgssc unregistered [ 7267.157536] LNet: 391921:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7268.197536] LNet: Removed LNI 192.168.204.42@tcp [ 7268.911794] Key type .llcrypt unregistered [ 7268.913498] Key type ._llcrypt unregistered [ 7269.480080] alg: No test for adler32 (adler32-zlib) [ 7270.277424] Key type ._llcrypt registered [ 7270.279796] Key type .llcrypt registered [ 7270.467187] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7270.724991] Lustre: Lustre: Build Version: 2.15.7_10_g2d3cba9 [ 7270.922032] LNet: Added LNI 192.168.204.42@tcp [8/256/0/180] [ 7270.925066] LNet: Accept secure, port 988 [ 7272.591204] Key type lgssc registered [ 7273.603855] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7282.719972] Lustre: Mounted lustre-client [ 7288.453769] Lustre: DEBUG MARKER: == sanityn test 110: do not grant another lock on resend ========================================================== 06:16:08 (1761128168) [ 7333.856106] Lustre: 393229:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761128171/real 1761128171] req@000000001dd02fce x1846676713117248/t0(0) o36->lustre-MDT0000-mdc-ffff9b8229296800@192.168.204.142@tcp:12/10 lens 496/440 e 0 to 1 dl 1761128215 ref 2 fl Rpc:XQr/0/ffffffff rc 0/-1 job:'ln.0' [ 7333.903120] Lustre: lustre-MDT0000-mdc-ffff9b8229296800: Connection to lustre-MDT0000 (at 192.168.204.142@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7333.943268] Lustre: lustre-MDT0000-mdc-ffff9b8229296800: Connection restored to (at 192.168.204.142@tcp) [ 7377.887181] Lustre: 393229:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761128215/real 1761128215] req@000000001dd02fce x1846676713117248/t0(0) o36->lustre-MDT0000-mdc-ffff9b8229296800@192.168.204.142@tcp:12/10 lens 496/440 e 0 to 1 dl 1761128259 ref 2 fl Rpc:XQr/2/ffffffff rc 0/-1 job:'ln.0' [ 7377.908937] Lustre: lustre-MDT0000-mdc-ffff9b8229296800: Connection to lustre-MDT0000 (at 192.168.204.142@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7377.933070] Lustre: lustre-MDT0000-mdc-ffff9b8229296800: Connection restored to (at 192.168.204.142@tcp) [ 7379.384348] Lustre: DEBUG MARKER: == sanityn test 111: A racy rename/link an open file should not cause fs corruption ========================================================== 06:17:39 (1761128259) [ 7389.926582] Lustre: DEBUG MARKER: == sanityn test 112: update max-inherit in default LMV === 06:17:50 (1761128270) [ 7399.610697] Lustre: DEBUG MARKER: == sanityn test 113: check servers of specified fs ======= 06:18:00 (1761128280) [ 7405.204443] Lustre: DEBUG MARKER: cleanup: ====================================================== [ 7406.757562] Lustre: DEBUG MARKER: == sanityn test complete, duration 7198 sec ============== 06:18:07 (1761128287) [ 7640.277879] Lustre: Unmounted lustre-client [ 7641.526156] Lustre: Unmounted lustre-client [ 7690.415479] Key type lgssc unregistered [ 7690.659668] LNet: 396525:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7691.684609] LNet: Removed LNI 192.168.204.42@tcp [ 7692.356199] Key type .llcrypt unregistered [ 7692.360482] Key type ._llcrypt unregistered