[ 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 489520271 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.001011] APIC: Switch to symmetric I/O mode setup [ 0.002365] x2apic enabled [ 0.003009] Switched APIC routing to physical x2apic. [ 0.004015] kvm-guest: setup PV IPIs [ 0.007332] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.008000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.008032] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.010008] pid_max: default: 32768 minimum: 301 [ 0.011129] LSM: Security Framework initializing [ 0.012073] Yama: becoming mindful. [ 0.013042] SELinux: Initializing. [ 0.014071] *** VALIDATE selinux *** [ 0.021758] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.026368] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.027148] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.028113] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029121] *** VALIDATE tmpfs *** [ 0.030492] *** VALIDATE proc *** [ 0.031269] *** VALIDATE cgroup *** [ 0.032017] *** VALIDATE cgroup2 *** [ 0.033282] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.035082] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.036012] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.037032] Spectre V2 : User space: Vulnerable [ 0.038010] Speculative Store Bypass: Vulnerable [ 0.041162] debug: unmapping init [mem 0xffffffff97a59000-0xffffffff97a60fff] [ 0.043952] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.044711] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.045023] ... version: 2 [ 0.046013] ... bit width: 48 [ 0.047023] ... generic registers: 4 [ 0.048012] ... value mask: 0000ffffffffffff [ 0.049015] ... max period: 00007fffffffffff [ 0.050014] ... fixed-purpose events: 3 [ 0.051011] ... event mask: 000000070000000f [ 0.052309] rcu: Hierarchical SRCU implementation. [ 0.054500] smp: Bringing up secondary CPUs ... [ 0.055592] x86: Booting SMP configuration: [ 0.056027] .... node #0, CPUs: #1 #2 #3 [ 0.065512] smp: Brought up 1 node, 4 CPUs [ 0.067014] smpboot: Max logical packages: 1 [ 0.068014] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.229121] node 0 deferred pages initialised in 159ms [ 0.233010] devtmpfs: initialized [ 0.234261] x86/mm: Memory block size: 128MB [ 0.237000] gcov: version magic: 0x41383552 [ 0.240459] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.244138] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.246402] pinctrl core: initialized pinctrl subsystem [ 0.248192] [ 0.248750] ************************************************************* [ 0.251022] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.252018] ** ** [ 0.254020] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.256019] ** ** [ 0.258017] ** This means that this kernel is built to expose internal ** [ 0.260017] ** IOMMU data structures, which may compromise security on ** [ 0.262022] ** your system. ** [ 0.264025] ** ** [ 0.266020] ** If you see this message and you are not debugging the ** [ 0.267017] ** kernel, report this immediately to your vendor! ** [ 0.269018] ** ** [ 0.271021] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.273020] ************************************************************* [ 0.275909] NET: Registered protocol family 16 [ 0.277495] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.279079] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.282094] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.285361] cpuidle: using governor menu [ 0.287282] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.288584] PCI: Using configuration type 1 for base access [ 0.289140] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.300163] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.302035] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.308450] cryptd: max_cpu_qlen set to 1000 [ 0.313248] ACPI: Added _OSI(Module Device) [ 0.315023] ACPI: Added _OSI(Processor Device) [ 0.317018] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.319015] ACPI: Added _OSI(Processor Aggregator Device) [ 0.323061] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.327570] ACPI: Interpreter enabled [ 0.329080] ACPI: PM: (supports S0 S3 S4 S5) [ 0.330000] ACPI: Using IOAPIC for interrupt routing [ 0.330000] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.333423] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.341000] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.343082] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.346031] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.349082] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.353403] acpiphp: Slot [2] registered [ 0.355192] acpiphp: Slot [3] registered [ 0.357092] acpiphp: Slot [4] registered [ 0.358086] acpiphp: Slot [5] registered [ 0.359169] acpiphp: Slot [6] registered [ 0.360117] acpiphp: Slot [7] registered [ 0.361104] acpiphp: Slot [8] registered [ 0.362103] acpiphp: Slot [9] registered [ 0.364103] acpiphp: Slot [10] registered [ 0.365081] acpiphp: Slot [11] registered [ 0.367150] acpiphp: Slot [12] registered [ 0.369081] acpiphp: Slot [13] registered [ 0.370113] acpiphp: Slot [14] registered [ 0.372170] acpiphp: Slot [15] registered [ 0.374158] acpiphp: Slot [16] registered [ 0.375413] acpiphp: Slot [17] registered [ 0.377151] acpiphp: Slot [18] registered [ 0.379225] acpiphp: Slot [19] registered [ 0.381139] acpiphp: Slot [20] registered [ 0.382138] acpiphp: Slot [21] registered [ 0.383000] acpiphp: Slot [22] registered [ 0.383000] acpiphp: Slot [23] registered [ 0.385121] acpiphp: Slot [24] registered [ 0.389143] acpiphp: Slot [25] registered [ 0.391137] acpiphp: Slot [26] registered [ 0.392106] acpiphp: Slot [27] registered [ 0.394130] acpiphp: Slot [28] registered [ 0.395131] acpiphp: Slot [29] registered [ 0.397112] acpiphp: Slot [30] registered [ 0.398118] acpiphp: Slot [31] registered [ 0.399057] PCI host bridge to bus 0000:00 [ 0.401023] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.403033] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.406028] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.408025] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.411026] pci_bus 0000:00: root bus resource [mem 0x180000000-0x1ffffffff window] [ 0.413032] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.415212] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.419000] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.423542] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.431016] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.435061] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.438035] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.441027] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.443028] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.446287] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.449787] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.452048] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.454751] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.459018] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.470025] pci 0000:00:02.0: reg 0x20: [mem 0xfebf4000-0xfebf7fff 64bit pref] [ 0.475033] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.480681] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.487019] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.492017] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.501019] pci 0000:00:05.0: reg 0x20: [mem 0xfebf8000-0xfebfbfff 64bit pref] [ 0.508000] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.514018] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.520016] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.535026] pci 0000:00:06.0: reg 0x20: [mem 0xfebfc000-0xfebfffff 64bit pref] [ 0.546432] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.551521] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.554570] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.557430] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.559419] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.565168] iommu: Default domain type: Passthrough [ 0.566000] SCSI subsystem initialized [ 0.567321] ACPI: bus type USB registered [ 0.570224] usbcore: registered new interface driver usbfs [ 0.573512] usbcore: registered new interface driver hub [ 0.576144] usbcore: registered new device driver usb [ 0.579474] pps_core: LinuxPPS API ver. 1 registered [ 0.582020] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.585106] PTP clock support registered [ 0.588096] EDAC MC: Ver: 3.0.0 [ 0.590150] PCI: Using ACPI for IRQ routing [ 0.591892] NetLabel: Initializing [ 0.593021] NetLabel: domain hash size = 128 [ 0.595016] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.598125] NetLabel: unlabeled traffic allowed by default [ 0.601048] vgaarb: loaded [ 0.602323] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.603011] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.612405] clocksource: Switched to clocksource kvm-clock [ 0.737360] VFS: Disk quotas dquot_6.6.0 [ 0.739591] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.742571] *** VALIDATE ramfs *** [ 0.743869] *** VALIDATE hugetlbfs *** [ 0.745856] pnp: PnP ACPI init [ 0.749727] pnp: PnP ACPI: found 6 devices [ 0.772594] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.775979] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.777881] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.779728] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.781673] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.783852] pci_bus 0000:00: resource 8 [mem 0x180000000-0x1ffffffff window] [ 0.786372] NET: Registered protocol family 2 [ 0.788776] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.793383] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.797779] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.803313] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.807476] TCP: Hash tables configured (established 65536 bind 65536) [ 0.810955] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.814259] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.817313] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.820459] NET: Registered protocol family 1 [ 0.824402] RPC: Registered named UNIX socket transport module. [ 0.826721] RPC: Registered udp transport module. [ 0.828313] RPC: Registered tcp transport module. [ 0.829593] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.831493] NET: Registered protocol family 44 [ 0.832769] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.834524] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.836143] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.837900] PCI: CLS 0 bytes, default 64 [ 0.839287] Unpacking initramfs... [ 2.362873] debug: unmapping init [mem 0xffff9e2bfcc64000-0xffff9e2bfffcffff] [ 2.369069] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.372259] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.376244] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.879881] Initialise system trusted keyrings [ 2.881608] Key type blacklist registered [ 2.883955] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.892430] zbud: loaded [ 2.895482] *** VALIDATE nfs *** [ 2.896891] *** VALIDATE nfs4 *** [ 2.898687] pstore: using deflate compression [ 2.905765] Platform Keyring initialized [ 3.051127] NET: Registered protocol family 38 [ 3.052687] Key type asymmetric registered [ 3.053968] Asymmetric key parser 'x509' registered [ 3.059927] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 3.066425] io scheduler mq-deadline registered [ 3.067934] io scheduler kyber registered [ 3.069403] io scheduler bfq registered [ 3.075636] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 3.078281] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 3.083116] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 3.086576] ACPI: Power Button [PWRF] [ 3.186844] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.279462] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.373693] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.404328] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.434594] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.441711] Non-volatile memory driver v1.3 [ 3.443575] Linux agpgart interface v0.103 [ 3.481147] virtio_blk virtio1: [vda] 67984 512-byte logical blocks (34.8 MB/33.2 MiB) [ 3.484017] vda: detected capacity change from 0 to 34807808 [ 3.499908] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.503077] vdb: detected capacity change from 0 to 1073741824 [ 3.511488] libphy: Fixed MDIO Bus: probed [ 3.519902] usbcore: registered new interface driver usbserial_generic [ 3.523190] usbserial: USB Serial support registered for generic [ 3.525220] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.529836] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.531383] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.533580] mousedev: PS/2 mouse device common for all mice [ 3.536474] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.537753] rtc_cmos 00:05: RTC can wake from S4 [ 3.542926] rtc_cmos 00:05: registered as rtc0 [ 3.545804] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.549471] intel_pstate: CPU model not supported [ 3.550413] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.556800] hid: raw HID events driver (C) Jiri Kosina [ 3.560162] usbcore: registered new interface driver usbhid [ 3.560263] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.563945] usbhid: USB HID core driver [ 3.568827] drop_monitor: Initializing network drop monitor service [ 3.571411] Initializing XFRM netlink socket [ 3.573406] NET: Registered protocol family 10 [ 3.576534] Segment Routing with IPv6 [ 3.578089] NET: Registered protocol family 17 [ 3.580689] mpls_gso: MPLS GSO support [ 3.586654] RAS: Correctable Errors collector initialized. [ 3.588800] AVX version of gcm_enc/dec engaged. [ 3.590435] AES CTR mode by8 optimization enabled [ 3.695077] sched_clock: Marking stable (3695047267, 0)->(4652337130, -957289863) [ 3.698852] registered taskstats version 1 [ 3.701302] Loading compiled-in X.509 certificates [ 3.703568] zswap: loaded using pool lzo/zbud [ 3.735570] Key type big_key registered [ 3.756905] Key type encrypted registered [ 3.758521] ima: No TPM chip found, activating TPM-bypass! [ 3.760337] ima: Allocated hash algorithm: sha1 [ 3.762051] ima: No architecture policies found [ 3.763609] evm: Initialising EVM extended attributes: [ 3.765420] evm: security.selinux [ 3.766598] evm: security.ima [ 3.767635] evm: security.capability [ 3.768918] evm: HMAC attrs: 0x1 [ 3.771927] rtc_cmos 00:05: setting system clock to 2025-10-24 13:51:20 UTC (1761313880) [ 3.779193] debug: unmapping init [mem 0xffffffff98a03000-0xffffffff98bfffff] [ 3.782954] debug: unmapping init [mem 0xffffffff97782000-0xffffffff97a58fff] [ 3.791925] Write protecting the kernel read-only data: 28672k [ 3.795327] debug: unmapping init [mem 0xffffffff95e03000-0xffffffff95ffffff] [ 3.800293] debug: unmapping init [mem 0xffffffff96714000-0xffffffff967fffff] [ 3.836134] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 3.845896] systemd[1]: Detected virtualization kvm. [ 3.849387] systemd[1]: Detected architecture x86-64. [ 3.851360] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.877188] systemd[1]: No hostname configured. [ 3.879621] systemd[1]: Set hostname to . [ 3.881767] random: systemd: uninitialized urandom read (16 bytes read) [ 3.885029] systemd[1]: Initializing machine ID from random generator. [ 3.950511] random: ln: uninitialized urandom read (6 bytes read) [ 4.059895] random: systemd: uninitialized urandom read (16 bytes read) [ 4.063321] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ 4.068405] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ 4.072881] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Listening on Journal Socket. [ OK ] Started Memstrack Anylazing Service. Starting Journal Service... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Slices. [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... [ OK ] Listening on udev Control Socket. Starting Apply Kernel Variables... [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Timers. Starting Setup Virtual Console... [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Sockets. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Apply Kernel Variables. [ OK ] Started Setup Virtual Console. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Journal Service. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.835263] device-mapper: uevent: version 1.0.3 [ 4.837599] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting dracut initqueue hook... [ 5.652179] virtio_net virtio0 ens2: renamed from eth0 [ 5.737114] scsi host0: ata_piix [ 5.745171] scsi host1: ata_piix [ 5.747136] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.749833] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 10.104517] dracut-initqueue[595]: RTNETLINK answers: File exists [ 10.274387] random: crng init done [ 10.276041] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ 10.841647] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Reached target Remote File Systems. [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Timers. [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Paths. [ OK ] Stopped target Sockets. [ OK ] Stopped target Slices. [ OK ] Stopped target System Initialization. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ 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 Kernel Device Manager. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 12.137576] printk: systemd: 25 output lines suppressed due to ratelimiting [ 12.435399] SELinux: Disabled at runtime. [ 12.509177] 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) [ 12.518914] systemd[1]: Detected virtualization kvm. [ 12.521058] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 13.069918] systemd[1]: initrd-switch-root.service: Succeeded. [ 13.073425] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 13.083395] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 13.087638] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 13.091220] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 13.100572] systemd[1]: Starting Journal Service... Starting Journal Service... [ 13.106802] systemd[1]: Created slice system-serial\x2dgetty.slice. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Reached target rpc_pipefs.target. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Created slice system-getty.slice. [ OK ] Listening on udev Control Socket. [ OK ] Listening on Process Core Dump Socket. Mounting Huge Pages File System... [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. Mounting Kernel Debug File System... Mounting POSIX Message Queue File System... Activating swap /dev/disk/by-label/SWAP... [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. Starting Apply Kernel Variables... Starting Remount Root and Kernel File Systems... [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Stopped target Switch Root. [ 13.253390] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Stopped target Initrd File Systems. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Stopped target Initrd Root File System. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Listening on udev Kernel Socket. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. Starting udev Coldplug all Devices... [ OK ] Started Journal Service. [ OK ] Mounted Huge Pages File System. [ OK ] Mounted Kernel Debug File System. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Started Apply Kernel Variables. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Started Create list of required sta…vice nodes for the current kernel. 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. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... [ OK ] Started udev Coldplug all Devices. [ OK ] Mounted /mnt. [ 13.681796] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 14.063475] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 14.066208] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 14.227463] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 14.243418] EDAC sbridge: Ver: 1.1.2 [ 15.529208] Key type dns_resolver registered [ 15.839943] NFS: Registering the id_resolver key type [ 15.841986] Key type id_resolver registered [ 15.843620] Key type id_legacy registered [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... 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 ] Started Daily Cleanup of Temporary Directories. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. Starting Restore /run/initramfs on shutdown... Starting Login Service... [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Reached target sshd-keygen.target. [ OK ] Started irqbalance daemon. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started dnf makecache --timer. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Timers. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... Starting OpenSSH server daemon... Starting Hostname Service... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Started OpenSSH server daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Getty on tty1. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting System Logging Service... Starting Notify NFS peers of a restart... Starting Crash recovery kernel arming... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started Authorization Manager. [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg203-client login: [ 43.723861] libcfs: loading out-of-tree module taints kernel. [ 43.877385] alg: No test for adler32 (adler32-zlib) [ 44.637072] Key type ._llcrypt registered [ 44.640285] Key type .llcrypt registered [ 45.041455] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 45.613403] Lustre: Lustre: Build Version: 2.15.7_9_g4830cc0 [ 46.315984] LNet: Added LNI 192.168.202.3@tcp [8/256/0/180] [ 46.329479] LNet: Accept secure, port 988 [ 48.128893] Key type lgssc registered [ 49.682969] Lustre: Echo OBD driver; http://www.lustre.org/ [ 182.281499] Lustre: Mounted lustre-client [ 186.952701] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 199.687149] Lustre: DEBUG MARKER: oleg203-client.virtnet: executing check_logdir /tmp/testlogs/ [ 203.978292] Lustre: DEBUG MARKER: oleg203-client.virtnet: executing yml_node [ 207.841300] Lustre: lustre-OST0000-osc-ffff9e2c47d08000: disconnect after 23s idle [ 209.296986] Lustre: DEBUG MARKER: Client: 2.15.7.9 [ 211.967816] Lustre: DEBUG MARKER: MDS: 2.15.7.9 [ 214.640738] Lustre: DEBUG MARKER: OSS: 2.15.7.9 [ 216.465570] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Fri Oct 24 09:54:51 EDT 2025 [ 224.366719] Lustre: DEBUG MARKER: excepting tests: 27 28 [ 225.880200] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 226.610537] Lustre: Mounted lustre-client [ 231.758790] Lustre: DEBUG MARKER: oleg203-client.virtnet: executing check_config_client /mnt/lustre [ 249.096401] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 258.948394] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 09:55:34 (1761314134) [ 262.952283] hrtimer: interrupt took 3145877 ns [ 265.263960] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 09:55:40 (1761314140) [ 271.576908] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 09:55:46 (1761314146) [ 278.051866] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 09:55:53 (1761314153) [ 284.649672] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 09:56:00 (1761314160) [ 290.581654] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 09:56:06 (1761314166) [ 296.682252] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 09:56:12 (1761314172) [ 303.273691] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 09:56:18 (1761314178) [ 310.093435] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 09:56:25 (1761314185) [ 316.028142] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 09:56:31 (1761314191) [ 322.834141] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 09:56:38 (1761314198) [ 324.067423] Lustre: lustre-OST0000-osc-ffff9e2c47d08000: disconnect after 20s idle [ 324.069540] Lustre: Skipped 1 previous similar message [ 327.844537] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 09:56:43 (1761314203) [ 332.112399] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 09:56:47 (1761314207) [ 338.828536] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 09:56:54 (1761314214) [ 339.429981] Lustre: lustre-OST0001-osc-ffff9e2c509b0000: disconnect after 23s idle [ 345.440188] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 09:57:00 (1761314220) [ 352.877684] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 09:57:08 (1761314228) [ 358.514546] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 09:57:14 (1761314234) [ 366.347675] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 09:57:21 (1761314241) [ 373.627353] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 09:57:28 (1761314248) [ 380.952709] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 09:57:36 (1761314256) [ 381.623633] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=144115205289279507 file: /mnt/lustre/lockdir/lockfile=144115205289279506 [ 519.738835] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 09:59:55 (1761314395) [ 526.021913] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 10:00:01 (1761314401) [ 532.157563] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 10:00:07 (1761314407) [ 537.960921] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 10:00:13 (1761314413) [ 543.828238] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 10:00:19 (1761314419) [ 550.124939] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 10:00:25 (1761314425) [ 552.053756] Lustre: DEBUG MARKER: chmod [ 557.674407] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 10:00:33 (1761314433) [ 563.245335] Lustre: DEBUG MARKER: SKIP: oos2 test_15 oos2.sh: 7207548kB free gt MAXFREE 800000kB, increase 800000 (or reduce test fs size) to proceed [ 571.525731] Lustre: DEBUG MARKER: == sanityn test 16a: 2500 iterations of dual-mount fsx === 10:00:47 (1761314447) [ 808.534301] Lustre: DEBUG MARKER: == sanityn test 16b: 2500 iterations of dual-mount fsx at small size ========================================================== 10:04:44 (1761314684) [ 913.917245] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 10:06:29 (1761314789) [ 1065.316530] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 10:09:00 (1761314940) [ 1096.628784] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 10:09:32 (1761314972) [ 1102.306025] Lustre: lustre-OST0001-osc-ffff9e2c509b0000: disconnect after 24s idle [ 1102.937465] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 10:09:38 (1761314978) [ 1103.835435] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1103.891301] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1103.936302] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1103.983865] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1104.053986] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1104.131601] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1104.185631] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1104.229899] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1104.281350] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1104.327793] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1104.384966] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1104.457193] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1104.502952] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1104.550867] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1104.599962] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1104.655291] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1104.699809] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1104.746570] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1104.791604] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1104.829879] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1104.880583] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1104.929911] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1104.984614] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1105.032481] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1105.093499] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1105.189191] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1105.292308] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1105.365575] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1105.446259] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1105.504106] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1105.571766] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1105.626742] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1105.690915] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1105.753595] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1105.807509] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1105.852896] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1105.911092] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1105.971701] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1106.035532] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1106.113671] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1106.171402] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1106.233905] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1106.311322] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1106.396876] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1106.462662] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1106.513515] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1106.556407] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1106.607301] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1106.649726] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1106.703233] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1106.760959] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1106.809716] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1106.857963] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1106.902354] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1106.943042] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1106.979466] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1107.031071] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1107.087805] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1107.139923] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1107.197246] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1107.243874] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1107.283755] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1107.342213] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1107.396849] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1107.458956] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1107.512767] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1107.568756] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1107.611301] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1107.681370] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1107.758899] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1107.799964] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1107.861341] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1107.917320] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1107.969619] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1108.023395] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1108.079269] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1108.133632] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1108.193442] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1108.239351] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1108.294404] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1108.345661] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1108.404844] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1108.498437] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1108.565241] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1108.638629] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1108.686768] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1108.766783] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1108.833948] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1108.900278] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1108.990238] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1109.054259] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1109.125439] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1109.176199] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1109.230207] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1109.289640] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1109.355507] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1109.408659] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1109.449749] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1109.503464] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1109.565526] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1109.613496] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1109.667198] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1109.721916] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1109.763614] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1109.813022] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1109.854557] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1109.941864] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1110.006915] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1110.071229] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1110.159762] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1110.234596] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1110.316929] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1110.390456] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1110.439822] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1110.509437] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1110.557196] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1110.603498] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1110.686467] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1110.741467] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1110.800949] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1110.845168] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1110.915683] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1110.966901] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1111.006359] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1111.070929] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1111.129283] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1111.206504] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1111.289009] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1111.344736] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1111.428034] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1111.488234] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1111.545942] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1111.572899] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1111.610608] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1111.645620] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1111.693407] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1111.735130] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1111.793762] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1111.852434] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1111.913263] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1112.002167] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1112.065680] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1112.122510] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1112.191864] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1112.242748] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1112.295816] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1112.362222] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1112.437972] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1112.513274] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1112.586736] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1112.639988] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1112.695321] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1112.732426] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1112.799860] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1112.845670] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1112.890407] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1112.956953] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1113.021159] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1113.095423] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1113.198777] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1113.269467] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1113.345601] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1113.399350] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1113.448743] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1113.486994] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1113.545040] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1113.602289] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1113.686308] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1113.760635] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1113.878134] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1113.966994] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1114.048867] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1114.103609] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1114.171843] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1114.277763] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1114.335352] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1114.428923] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1114.558621] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1114.643820] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1114.715295] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1114.787725] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1114.846933] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1114.911475] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1114.979634] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1115.024378] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1115.122971] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1115.228459] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1115.312247] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1115.384704] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1115.460508] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1115.546960] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1115.634199] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1115.683116] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1115.748581] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1115.837725] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1115.948393] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1116.034232] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1116.129420] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1116.196141] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1116.252910] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1116.321423] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1116.425914] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1116.490725] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1116.562476] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1116.645645] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1116.725356] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1116.809576] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1116.889281] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1116.941405] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1117.013590] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1117.095595] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1117.185150] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1117.307860] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1117.427559] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1117.496600] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1117.581281] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1117.650457] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1117.666736] Lustre: lustre-OST0000-osc-ffff9e2c509b0000: disconnect after 20s idle [ 1117.747942] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1117.815348] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1117.913448] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1117.976168] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1118.035234] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1118.120312] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1118.207464] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1118.289851] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1118.368376] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1118.412402] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1118.483000] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1118.573630] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1118.660499] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1118.723534] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1118.812400] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1118.881424] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1118.961699] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1119.044554] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1119.151374] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1119.233784] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1119.277708] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1119.359034] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1119.436917] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1119.536643] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1119.616055] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1119.713941] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1119.801085] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1119.894704] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1119.974973] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1120.054300] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1120.119408] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1120.179451] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1120.221954] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1120.316217] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1120.416336] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1120.511906] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1120.598562] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1120.700776] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1120.769085] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1120.835779] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1120.923400] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1121.014652] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1121.103377] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1121.159680] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1121.244147] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1121.332639] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1121.385823] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1121.453045] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1121.501367] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1121.579303] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1121.624983] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1121.698940] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1121.775308] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1121.876954] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1121.943905] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1122.032487] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1122.131949] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1122.200120] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1122.228271] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1122.300592] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1122.344455] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1122.400926] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1122.464188] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1122.544573] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1122.599184] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1122.646604] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1122.731308] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1122.794046] Lustre: lustre-OST0000-osc-ffff9e2c47d08000: disconnect after 24s idle [ 1122.865526] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1122.946297] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1123.007983] rw_seq_cst_vs_d (29908): drop_caches: 3 [ 1129.438611] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 10:10:04 (1761315004) [ 1129.821043] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1129.861725] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1129.934206] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1129.969908] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1130.120431] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1130.269078] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1130.333562] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1130.514316] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1130.578735] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1130.743215] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1130.898164] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1130.929304] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1130.981857] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1131.034771] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1131.103298] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1131.236103] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1131.436058] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1131.483359] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1131.596324] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1131.699538] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1131.835581] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1131.946814] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1132.025634] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1132.112241] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1132.226299] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1132.360624] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1132.629825] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1132.672936] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1132.899991] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1133.032886] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1133.127277] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1133.214833] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1133.253075] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1133.330271] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1133.476741] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1133.519160] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1133.646204] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1133.765610] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1133.818361] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1134.029021] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1134.182357] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1134.244410] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1134.321534] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1134.382897] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1134.478871] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1134.771681] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1134.812108] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1134.851807] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1134.971985] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1135.068339] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1135.228718] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1135.720521] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1135.828601] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1136.454701] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1136.506575] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1136.646053] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1136.737134] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1136.806904] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1136.836386] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1136.951320] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1137.037775] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1137.153274] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1137.197139] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1137.227941] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1137.480561] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1137.525315] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1137.592966] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1137.672358] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1137.779758] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1137.824299] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1137.974155] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1138.019247] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1138.161817] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1138.255881] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1138.305872] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1138.441339] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1138.553533] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1138.870962] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1138.902828] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1138.992696] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1139.152866] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1139.200950] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1139.297391] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1139.374130] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1139.436123] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1139.687274] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1139.736930] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1139.822251] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1139.957814] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1140.042684] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1140.119350] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1140.252333] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1140.409831] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1140.505639] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1140.614715] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1140.673162] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1140.801894] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1140.844990] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1140.962622] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1141.068617] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1141.100815] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1141.193677] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1141.320318] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1141.350700] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1141.537611] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1141.589385] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1141.627995] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1141.783171] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1141.890100] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1141.980988] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1142.091554] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1142.221120] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1142.601245] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1142.731216] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1142.777498] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1142.859389] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1142.898162] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1142.933067] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1143.054654] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1143.183070] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1143.263626] Lustre: lustre-OST0001-osc-ffff9e2c509b0000: disconnect after 20s idle [ 1143.436210] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1143.566919] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1143.736572] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1143.922721] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1144.182886] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1144.273414] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1144.400970] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1144.465626] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1144.518337] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1144.565238] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1144.604236] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1144.725581] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1144.797598] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1144.868467] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1145.012179] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1145.115966] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1145.327393] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1145.403089] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1145.458143] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1145.559176] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1145.708938] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1145.844279] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1145.911689] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1146.032145] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1146.123768] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1146.231493] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1146.276098] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1146.431337] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1146.644309] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1146.813953] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1146.895658] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1147.008391] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1147.099669] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1147.197425] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1147.286546] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1147.372600] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1147.473230] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1147.582540] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1147.654522] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1147.756522] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1147.808424] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1147.902168] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1148.002624] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1148.062154] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1148.119489] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1148.211215] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1148.249972] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1148.365944] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1148.384442] Lustre: lustre-OST0001-osc-ffff9e2c47d08000: disconnect after 20s idle [ 1148.460094] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1148.491639] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1148.564647] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1148.717308] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1148.773801] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1148.853348] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1148.917790] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1148.989721] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1149.031873] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1149.228868] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1149.298598] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1149.535081] rw_seq_cst_vs_d (30501): drop_caches: 3 [ 1156.989856] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 10:10:32 (1761315032) [ 1163.870995] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 10:10:39 (1761315039) [ 1173.467941] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 10:10:49 (1761315049) [ 1198.834262] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 10:11:14 (1761315074) [ 1206.430666] Lustre: DEBUG MARKER: loop 5 [ 1211.107088] Lustre: DEBUG MARKER: loop 10 [ 1214.952992] Lustre: lustre-OST0001-osc-ffff9e2c47d08000: disconnect after 24s idle [ 1215.332837] Lustre: DEBUG MARKER: loop 15 [ 1219.683458] Lustre: DEBUG MARKER: loop 20 [ 1226.888146] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 10:11:42 (1761315102) [ 1233.222906] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 10:11:48 (1761315108) [ 1240.284499] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 10:11:55 (1761315115) [ 1250.785625] Lustre: lustre-OST0001-osc-ffff9e2c47d08000: disconnect after 23s idle [ 1250.788238] Lustre: Skipped 1 previous similar message [ 1308.664763] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 10:13:04 (1761315184) [ 1315.228816] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 10:13:10 (1761315190) [ 1320.592523] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 10:13:16 (1761315196) [ 1327.537695] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 10:13:22 (1761315202) [ 1335.305209] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 10:13:30 (1761315210) [ 1342.564167] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 10:13:38 (1761315218) [ 1351.209119] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 1353.036791] Lustre: DEBUG MARKER: SKIP: sanityn test_28 skipping ALWAYS excluded test 28 [ 1354.798782] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 10:13:50 (1761315230) [ 1358.304278] Lustre: lustre-OST0000-osc-ffff9e2c47d08000: disconnect after 25s idle [ 1358.307165] Lustre: Skipped 3 previous similar messages [ 1363.944207] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 10:13:59 (1761315239) [ 1364.307455] Lustre: *** cfs_fail_loc=314, val=0*** [ 1365.343288] Lustre: *** cfs_fail_loc=314, val=0*** [ 1365.345154] Lustre: Skipped 2 previous similar messages [ 1371.967742] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 10:14:07 (1761315247) [ 1380.964553] Lustre: *** cfs_fail_loc=314, val=0*** [ 1381.037741] LustreError: 11-0: lustre-OST0000-osc-ffff9e2c509b0000: operation ldlm_enqueue to node 192.168.202.103@tcp failed: rc = -107 [ 1381.049799] Lustre: lustre-OST0000-osc-ffff9e2c509b0000: Connection to lustre-OST0000 (at 192.168.202.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1381.075357] LustreError: lustre-OST0000-osc-ffff9e2c509b0000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1381.089706] Lustre: 2274:0:(llite_lib.c:3595:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.202.103@tcp:/lustre/fid: [0x240000403:0x1:0x0]// may get corrupted (rc -108) [ 1381.116632] LustreError: 41314:0:(ldlm_resource.c:1127:ldlm_resource_complain()) lustre-OST0000-osc-ffff9e2c509b0000: namespace resource [0x280000400:0x5:0x0].0x0 (00000000bed58e46) refcount nonzero (1) after lock cleanup; forcing cleanup. [ 1381.127226] Lustre: lustre-OST0000-osc-ffff9e2c509b0000: Connection restored to (at 192.168.202.103@tcp) [ 1387.486858] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 10:14:23 (1761315263) [ 1387.757270] LustreError: 41903:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 1390.807168] LustreError: 41903:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 1419 awake [ 1397.319490] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 1398.955965] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 10:14:34 (1761315274) [ 1400.777287] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 1402.809288] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-Lock-Cancel ========================================================== 10:14:38 (1761315278) [ 1409.541929] Lustre: lustre-MDT0000-mdc-ffff9e2c47d08000: Connection to lustre-MDT0000 (at 192.168.202.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1416.607234] Lustre: 2274:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761315286/real 1761315286] req@00000000698ba715 x1846871515942144/t0(0) o400->MGC192.168.202.103@tcp@192.168.202.103@tcp:26/25 lens 224/224 e 0 to 1 dl 1761315293 ref 1 fl Rpc:XNQr/0/ffffffff rc 0/-1 job:'kworker/u8:4.0' [ 1416.625967] LustreError: 166-1: MGC192.168.202.103@tcp: Connection to MGS (at 192.168.202.103@tcp) was lost; in progress operations using this service will fail [ 1422.457419] Lustre: lustre-MDT0000-mdc-ffff9e2c509b0000: Connection restored to (at 192.168.202.103@tcp) [ 1422.759793] Lustre: Evicted from MGS (at 192.168.202.103@tcp) after server handle changed from 0x8ad05676f1b64879 to 0x8ad05676f1c0e06d [ 1444.173974] Lustre: DEBUG MARKER: == sanityn test 33d: DNE distributed operation should trigger COS ========================================================== 10:15:19 (1761315319) [ 1546.519754] Lustre: DEBUG MARKER: == sanityn test 33e: DNE local operation shouldn't trigger COS ========================================================== 10:17:02 (1761315422) [ 1547.845595] Lustre: DEBUG MARKER: SKIP: sanityn test_33e Need two or more clients, have 1 [ 1549.658864] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 10:17:04 (1761315424) [ 1550.821785] Lustre: lustre-OST0000-osc-ffff9e2c47d08000: disconnect after 24s idle [ 1550.824478] Lustre: Skipped 2 previous similar messages [ 1600.975848] Lustre: lustre-OST0001-osc-ffff9e2c509b0000: Connection to lustre-OST0001 (at 192.168.202.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1600.985854] Lustre: Skipped 1 previous similar message [ 1601.016284] LustreError: lustre-OST0001-osc-ffff9e2c509b0000: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 1601.036338] LustreError: lustre-OST0001-osc-ffff9e2c47d08000: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 1601.040928] Lustre: lustre-OST0001-osc-ffff9e2c47d08000: Connection restored to (at 192.168.202.103@tcp) [ 1601.072687] Lustre: Skipped 3 previous similar messages [ 1606.112656] Lustre: lustre-OST0000-osc-ffff9e2c47d08000: Connection to lustre-OST0000 (at 192.168.202.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1606.138069] Lustre: Skipped 1 previous similar message [ 1606.147904] LustreError: lustre-OST0000-osc-ffff9e2c47d08000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1606.169241] Lustre: lustre-OST0000-osc-ffff9e2c47d08000: Connection restored to (at 192.168.202.103@tcp) [ 1623.349712] Lustre: DEBUG MARKER: oleg203-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9e2c47d08000.ost_server_uuid,osc.lustre-OST0000-osc-ffff9e2c509b0000.ost_server_uuid 40 [ 1624.367689] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9e2c47d08000.ost_server_uuid in FULL state after 0 sec [ 1625.506485] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9e2c509b0000.ost_server_uuid in FULL state after 0 sec [ 1630.730969] Lustre: DEBUG MARKER: oleg203-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9e2c47d08000.ost_server_uuid,osc.lustre-OST0001-osc-ffff9e2c509b0000.ost_server_uuid 40 [ 1632.144112] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9e2c47d08000.ost_server_uuid in IDLE state after 0 sec [ 1633.680193] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9e2c509b0000.ost_server_uuid in IDLE state after 0 sec [ 1639.816565] Lustre: DEBUG MARKER: oleg203-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9e2c47d08000.ost_server_uuid,osc.lustre-OST0000-osc-ffff9e2c509b0000.ost_server_uuid 40 [ 1640.994225] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9e2c47d08000.ost_server_uuid in IDLE state after 0 sec [ 1642.549345] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9e2c509b0000.ost_server_uuid in FULL state after 0 sec [ 1647.612433] Lustre: DEBUG MARKER: oleg203-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9e2c47d08000.ost_server_uuid,osc.lustre-OST0001-osc-ffff9e2c509b0000.ost_server_uuid 40 [ 1649.120795] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9e2c47d08000.ost_server_uuid in IDLE state after 0 sec [ 1650.675825] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9e2c509b0000.ost_server_uuid in IDLE state after 0 sec [ 1661.517964] Lustre: DEBUG MARKER: oleg203-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9e2c47d08000.ost_server_uuid,osc.lustre-OST0000-osc-ffff9e2c509b0000.ost_server_uuid 40 [ 1662.998330] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9e2c47d08000.ost_server_uuid in IDLE state after 0 sec [ 1664.488553] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9e2c509b0000.ost_server_uuid in FULL state after 0 sec [ 1669.799102] Lustre: DEBUG MARKER: oleg203-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9e2c47d08000.ost_server_uuid,osc.lustre-OST0001-osc-ffff9e2c509b0000.ost_server_uuid 40 [ 1671.525328] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9e2c47d08000.ost_server_uuid in IDLE state after 0 sec [ 1673.121184] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9e2c509b0000.ost_server_uuid in IDLE state after 0 sec [ 1674.740305] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 10:19:10 (1761315550) [ 1677.485260] Lustre: DEBUG MARKER: Race attempt 0 [ 1680.167674] Lustre: DEBUG MARKER: Wait for 54421 54462 for 60 sec... [ 1746.627316] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 10:20:21 (1761315621) [ 1755.494066] Lustre: DEBUG MARKER: start test - cycle (0) [ 1773.904486] Lustre: DEBUG MARKER: start test - cycle (1) [ 1794.190570] Lustre: DEBUG MARKER: start test - cycle (2) [ 1813.976362] Lustre: DEBUG MARKER: start test - cycle (3) [ 1835.057700] Lustre: DEBUG MARKER: start test - cycle (4) [ 1856.336974] Lustre: DEBUG MARKER: start test - cycle (5) [ 1876.197510] Lustre: DEBUG MARKER: start test - cycle (6) [ 1897.118570] Lustre: DEBUG MARKER: start test - cycle (7) [ 1917.661484] Lustre: DEBUG MARKER: start test - cycle (8) [ 1936.873698] Lustre: DEBUG MARKER: start test - cycle (9) [ 1958.719837] Lustre: DEBUG MARKER: start test - cycle (10) [ 1989.591623] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 10:24:25 (1761315865) [ 1991.136534] Lustre: lustre-OST0001-osc-ffff9e2c509b0000: disconnect after 20s idle [ 1991.142283] Lustre: Skipped 3 previous similar messages [ 2063.223236] Lustre: DEBUG MARKER: == sanityn test 39a: test from 11063 ============================================================================================ 10:25:38 (1761315938) [ 2069.421916] Lustre: DEBUG MARKER: == sanityn test 39b: 11063 problem 1 ============================================================================================ 10:25:45 (1761315945) [ 2076.504822] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 10:25:52 (1761315952) [ 2083.118706] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 10:25:58 (1761315958) [ 2083.576325] Lustre: *** cfs_fail_loc=411, val=0*** [ 2088.877167] Lustre: DEBUG MARKER: == sanityn test 40a: pdirops: create vs others ======================================================================== 10:26:04 (1761315964) [ 2104.686731] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 10:26:20 (1761315980) [ 2120.658768] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 10:26:36 (1761315996) [ 2135.985142] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 10:26:51 (1761316011) [ 2151.070888] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 10:27:06 (1761316026) [ 2165.542742] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 10:27:21 (1761316041) [ 2177.607771] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 10:27:33 (1761316053) [ 2190.258328] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 10:27:45 (1761316065) [ 2203.852182] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 10:27:59 (1761316079) [ 2214.862408] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 10:28:10 (1761316090) [ 2227.406068] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 10:28:22 (1761316102) [ 2239.119060] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 10:28:34 (1761316114) [ 2247.139897] Lustre: lustre-OST0000-osc-ffff9e2c509b0000: disconnect after 24s idle [ 2247.142213] Lustre: Skipped 9 previous similar messages [ 2252.124423] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 10:28:47 (1761316127) [ 2268.053933] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 10:29:03 (1761316143) [ 3288.883527] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 10:46:04 (1761317164) [ 3300.273487] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 10:46:15 (1761317175) [ 3311.410212] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 10:46:27 (1761317187) [ 3322.761524] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 10:46:38 (1761317198) [ 3334.154429] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 10:46:49 (1761317209) [ 3342.829767] Lustre: lustre-OST0001-osc-ffff9e2c47d08000: disconnect after 24s idle [ 3342.831832] Lustre: Skipped 3 previous similar messages [ 3345.318957] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 10:47:00 (1761317220) [ 3356.169686] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 10:47:11 (1761317231) [ 3367.155491] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 10:47:22 (1761317242) [ 3378.019844] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 10:47:33 (1761317253) [ 3440.428285] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 10:48:36 (1761317316) [ 3450.723563] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 10:48:46 (1761317326) [ 3461.999952] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 10:48:57 (1761317337) [ 3476.406232] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 10:49:11 (1761317351) [ 3489.960086] Lustre: DEBUG MARKER: == sanityn test 43f: pdirops: unlink and rename (src) ======================================================================== 10:49:25 (1761317365) [ 3502.016152] Lustre: DEBUG MARKER: == sanityn test 43g: pdirops: unlink vs getattr ======================================================================== 10:49:37 (1761317377) [ 3513.508990] Lustre: DEBUG MARKER: == sanityn test 43h: pdirops: unlink vs readdir ======================================================================== 10:49:49 (1761317389) [ 3524.058828] Lustre: DEBUG MARKER: == sanityn test 43i: pdirops: unlink vs remote mkdir ===== 10:49:59 (1761317399) [ 3535.115890] Lustre: DEBUG MARKER: == sanityn test 43j: racy mkdir return EEXIST ======================================================================== 10:50:10 (1761317410) [ 3647.150339] Lustre: DEBUG MARKER: == sanityn test 43k: unlink vs create ==================== 10:52:02 (1761317522) [ 3967.458109] Lustre: lustre-OST0000-osc-ffff9e2c47d08000: disconnect after 20s idle [ 3967.464317] Lustre: Skipped 8 previous similar messages [ 4590.806901] Lustre: DEBUG MARKER: == sanityn test 44a: pdirops: rename tgt vs mkdir ======================================================================== 11:07:46 (1761318466) [ 4600.282372] Lustre: DEBUG MARKER: == sanityn test 44b: pdirops: rename tgt vs create ======================================================================== 11:07:56 (1761318476) [ 4608.841340] Lustre: DEBUG MARKER: == sanityn test 44c: pdirops: rename tgt vs link ======================================================================== 11:08:04 (1761318484) [ 4617.460746] Lustre: DEBUG MARKER: == sanityn test 44d: pdirops: rename tgt vs unlink ======================================================================== 11:08:13 (1761318493) [ 4626.720948] Lustre: DEBUG MARKER: == sanityn test 44e: pdirops: rename tgt and rename (tgt) ======================================================================== 11:08:22 (1761318502) [ 4635.693466] Lustre: DEBUG MARKER: == sanityn test 44f: pdirops: rename tgt and rename (src) ======================================================================== 11:08:31 (1761318511) [ 4644.491968] Lustre: DEBUG MARKER: == sanityn test 44g: pdirops: rename tgt vs getattr ======================================================================== 11:08:40 (1761318520) [ 4653.806514] Lustre: DEBUG MARKER: == sanityn test 44h: pdirops: rename tgt vs readdir ======================================================================== 11:08:49 (1761318529) [ 4662.904142] Lustre: DEBUG MARKER: == sanityn test 44i: pdirops: rename tgt vs remote mkdir ========================================================== 11:08:58 (1761318538) [ 4671.820882] Lustre: DEBUG MARKER: == sanityn test 45a: rename,mkdir doesn't return -EEXIST ======================================================================== 11:09:07 (1761318547) [ 4684.255476] Lustre: lustre-OST0000-osc-ffff9e2c47d08000: disconnect after 20s idle [ 4684.258623] Lustre: Skipped 2 previous similar messages [ 4772.432980] Lustre: DEBUG MARKER: == sanityn test 45b: pdirops: rename src vs create ======================================================================== 11:10:48 (1761318648) [ 4781.697555] Lustre: DEBUG MARKER: == sanityn test 45c: pdirops: rename src vs link ======================================================================== 11:10:57 (1761318657) [ 4789.977352] Lustre: DEBUG MARKER: == sanityn test 45d: pdirops: rename src vs unlink ======================================================================== 11:11:05 (1761318665) [ 4799.450145] Lustre: DEBUG MARKER: == sanityn test 45e: pdirops: rename src and rename (tgt) ======================================================================== 11:11:15 (1761318675) [ 4808.738873] Lustre: DEBUG MARKER: == sanityn test 45f: pdirops: rename src and rename (src) ======================================================================== 11:11:24 (1761318684) [ 4816.761430] Lustre: DEBUG MARKER: == sanityn test 45g: pdirops: rename src vs getattr ======================================================================== 11:11:32 (1761318692) [ 4825.650494] Lustre: DEBUG MARKER: == sanityn test 45h: pdirops: unlink vs readdir ======================================================================== 11:11:41 (1761318701) [ 4833.931279] Lustre: DEBUG MARKER: == sanityn test 45i: pdirops: rename src vs remote mkdir ========================================================== 11:11:49 (1761318709) [ 4841.627445] Lustre: DEBUG MARKER: == sanityn test 45j: read vs rename ====================== 11:11:57 (1761318717) [ 5535.259543] Lustre: DEBUG MARKER: == sanityn test 46a: pdirops: link vs mkdir ======================================================================== 11:23:31 (1761319411) [ 5541.791251] Lustre: DEBUG MARKER: == sanityn test 46b: pdirops: link vs create ======================================================================== 11:23:37 (1761319417) [ 5548.225982] Lustre: DEBUG MARKER: == sanityn test 46c: pdirops: link vs link ======================================================================== 11:23:44 (1761319424) [ 5554.614387] Lustre: DEBUG MARKER: == sanityn test 46d: pdirops: link vs unlink ======================================================================== 11:23:50 (1761319430) [ 5560.815083] Lustre: DEBUG MARKER: == sanityn test 46e: pdirops: link and rename (tgt) ======================================================================== 11:23:56 (1761319436) [ 5567.413592] Lustre: DEBUG MARKER: == sanityn test 46f: pdirops: link and rename (src) ======================================================================== 11:24:03 (1761319443) [ 5574.320573] Lustre: DEBUG MARKER: == sanityn test 46g: pdirops: link vs getattr ======================================================================== 11:24:10 (1761319450) [ 5581.098265] Lustre: DEBUG MARKER: == sanityn test 46h: pdirops: link vs readdir ======================================================================== 11:24:17 (1761319457) [ 5587.767301] Lustre: DEBUG MARKER: == sanityn test 46i: pdirops: link vs remote mkdir ======= 11:24:23 (1761319463) [ 5594.318646] Lustre: DEBUG MARKER: == sanityn test 47a: pdirops: remote mkdir vs mkdir ====== 11:24:30 (1761319470) [ 5600.625195] Lustre: DEBUG MARKER: == sanityn test 47b: pdirops: remote mkdir vs create ===== 11:24:36 (1761319476) [ 5607.929375] Lustre: DEBUG MARKER: == sanityn test 47c: pdirops: remote mkdir vs link ======= 11:24:44 (1761319484) [ 5614.540793] Lustre: DEBUG MARKER: == sanityn test 47d: pdirops: remote mkdir vs unlink ===== 11:24:50 (1761319490) [ 5616.095147] Lustre: lustre-OST0000-osc-ffff9e2c47d08000: disconnect after 20s idle [ 5616.097900] Lustre: Skipped 1 previous similar message [ 5621.780929] Lustre: DEBUG MARKER: == sanityn test 47e: pdirops: remote mkdir and rename (tgt) ========================================================== 11:24:57 (1761319497) [ 5628.926096] Lustre: DEBUG MARKER: == sanityn test 47f: pdirops: remote mkdir and rename (src) ========================================================== 11:25:04 (1761319504) [ 5635.416455] Lustre: DEBUG MARKER: == sanityn test 47g: pdirops: remote mkdir vs getattr ==== 11:25:11 (1761319511) [ 5642.740678] Lustre: DEBUG MARKER: == sanityn test 50: osc lvb attrs: enqueue vs. CP AST ======================================================================== 11:25:18 (1761319518) [ 5642.862740] LustreError: 5584:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 410 sleeping for 2000ms [ 5644.943143] LustreError: 5584:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 410 awake [ 5650.887095] Lustre: DEBUG MARKER: == sanityn test 51a: layout lock: refresh layout should work ========================================================== 11:25:26 (1761319526) [ 5656.044566] Lustre: DEBUG MARKER: == sanityn test 51b: layout lock: glimpse should be able to restart if layout changed ========================================================== 11:25:32 (1761319532) [ 5656.194057] LustreError: 267240:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 5660.263554] LustreError: 267240:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 1404 awake [ 5660.281146] LustreError: 267240:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 5664.343152] LustreError: 267240:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 1404 awake [ 5664.369896] LustreError: 267246:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 5668.431133] LustreError: 267246:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 1404 awake [ 5671.576913] Lustre: DEBUG MARKER: == sanityn test 51c: layout lock: IT_LAYOUT blocked and correct layout can be returned ========================================================== 11:25:47 (1761319547) [ 5679.346301] Lustre: DEBUG MARKER: == sanityn test 51d: layout lock: losing layout lock should clean up memory map region ========================================================== 11:25:55 (1761319555) [ 5683.444931] Lustre: DEBUG MARKER: == sanityn test 51e: lfs getstripe does not break leases, part 2 ========================================================== 11:25:59 (1761319559) [ 5688.154474] Lustre: DEBUG MARKER: == sanityn test 54: rename locking ======================= 11:26:04 (1761319564) [ 5713.614530] Lustre: DEBUG MARKER: == sanityn test 55a: rename vs unlink target dir ========= 11:26:29 (1761319589) [ 5726.026181] Lustre: DEBUG MARKER: == sanityn test 55b: rename vs unlink source dir ========= 11:26:40 (1761319600) [ 5742.210746] Lustre: DEBUG MARKER: == sanityn test 55c: rename vs unlink orphan target dir == 11:26:56 (1761319616) [ 5760.942266] Lustre: DEBUG MARKER: == sanityn test 55d: rename file vs link ================= 11:27:16 (1761319636) [ 5775.168372] Lustre: DEBUG MARKER: == sanityn test 60: Verify data_version behaviour ======== 11:27:30 (1761319650) [ 5781.089619] Lustre: DEBUG MARKER: == sanityn test 70a: cd directory [ 5786.465897] Lustre: DEBUG MARKER: == sanityn test 70b: remove files after calling rm_entry ========================================================== 11:27:41 (1761319661) [ 5792.483659] Lustre: DEBUG MARKER: == sanityn test 71a: correct file map just after write operation is finished ========================================================== 11:27:48 (1761319668) [ 5798.087527] Lustre: DEBUG MARKER: == sanityn test 71b: check fiemap support for stripecount > 1 ========================================================== 11:27:53 (1761319673) [ 5803.022173] Lustre: DEBUG MARKER: == sanityn test 71c: check FIEMAP_EXTENT_LAST flag with different extents number ========================================================== 11:27:58 (1761319678) [ 5824.577898] Lustre: DEBUG MARKER: == sanityn test 71d: fiemap corruption test with fm_extent_count=0 ========================================================== 11:28:20 (1761319700) [ 5853.068257] Lustre: DEBUG MARKER: == sanityn test 72: getxattr/setxattr cache should be consistent between nodes ========================================================== 11:28:48 (1761319728) [ 5858.077238] Lustre: DEBUG MARKER: == sanityn test 73: getxattr should not cause xattr lock cancellation ========================================================== 11:28:53 (1761319733) [ 5863.170994] Lustre: DEBUG MARKER: == sanityn test 74: flock deadlock: different mounts ======================================================================== 11:28:58 (1761319738) [ 5866.352707] LustreError: 11-0: lustre-MDT0000-mdc-ffff9e2c47d08000: operation ldlm_enqueue to node 192.168.202.103@tcp failed: rc = -35 [ 5871.524442] Lustre: DEBUG MARKER: == sanityn test 75: osc: upcall after unuse lock============================================================================= 11:29:07 (1761319747) [ 5871.993324] LustreError: 2274:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 31d sleeping for 2000ms [ 5874.080048] LustreError: 2274:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 31d awake [ 5881.296835] Lustre: DEBUG MARKER: == sanityn test 76: Verify MDT open_files listing ======== 11:29:17 (1761319757) [ 6004.803087] Lustre: DEBUG MARKER: == sanityn test 77a: check FIFO NRS policy =============== 11:31:20 (1761319880) [ 6013.824670] Lustre: DEBUG MARKER: == sanityn test 77b: check CRR-N NRS policy ============== 11:31:29 (1761319889) [ 6023.634575] Lustre: DEBUG MARKER: == sanityn test 77c: check ORR NRS policy ================ 11:31:39 (1761319899) [ 6037.140251] Lustre: DEBUG MARKER: == sanityn test 77d: check TRR nrs policy ================ 11:31:52 (1761319912) [ 6048.592867] Lustre: DEBUG MARKER: == sanityn test 77e: check TBF NID nrs policy ============ 11:32:04 (1761319924) [ 6067.278900] Lustre: DEBUG MARKER: == sanityn test 77f: check TBF JobID nrs policy ========== 11:32:22 (1761319942) [ 6086.128414] Lustre: DEBUG MARKER: == sanityn test 77g: Change TBF type directly ============ 11:32:41 (1761319961) [ 6095.539016] Lustre: DEBUG MARKER: == sanityn test 77h: Wrong policy name should report error, not LBUG ========================================================== 11:32:51 (1761319971) [ 6105.371633] Lustre: DEBUG MARKER: == sanityn test 77i: Change rank of TBF rule ============= 11:33:00 (1761319980) [ 6124.192961] Lustre: DEBUG MARKER: == sanityn test 77j: check TBF-OPCode NRS policy ========= 11:33:19 (1761319999) [ 6172.415495] Lustre: DEBUG MARKER: == sanityn test 77ja: check TBF-UID/GID NRS policy ======= 11:34:08 (1761320048) [ 6256.096205] Lustre: lustre-OST0001-osc-ffff9e2c47d08000: disconnect after 20s idle [ 6256.102489] Lustre: Skipped 12 previous similar messages [ 6290.498505] Lustre: DEBUG MARKER: == sanityn test 77k: check TBF policy with NID/JobID/OPCode expression ========================================================== 11:36:06 (1761320166) [ 6606.041828] Lustre: DEBUG MARKER: == sanityn test 77l: check the output of NRS policies for generic TBF ========================================================== 11:41:21 (1761320481) [ 6613.182144] Lustre: DEBUG MARKER: == sanityn test 77m: check NRS Delay slows write RPC processing ========================================================== 11:41:29 (1761320489) [ 6665.605655] Lustre: DEBUG MARKER: == sanityn test 77n: check wildcard support for TBF JobID NRS policy ========================================================== 11:42:21 (1761320541) [ 6721.024107] Lustre: DEBUG MARKER: == sanityn test 77o: Changing rank should not panic ====== 11:43:16 (1761320596) [ 6727.190512] Lustre: DEBUG MARKER: == sanityn test 77q: Parallel TBF rule definitions should not panic ========================================================== 11:43:23 (1761320603) [ 6783.027469] Lustre: DEBUG MARKER: == sanityn test 77p: Check validity of rule names for TBF policies ========================================================== 11:44:18 (1761320658) [ 6801.431577] Lustre: DEBUG MARKER: == sanityn test 78: Enable policy and specify tunings right away ========================================================== 11:44:37 (1761320677) [ 6806.276497] Lustre: DEBUG MARKER: == sanityn test 79: xattr: intent error ================== 11:44:42 (1761320682) [ 6820.660670] Lustre: DEBUG MARKER: == sanityn test 80a: migrate directory when some children is being opened ========================================================== 11:44:56 (1761320696) [ 6822.795407] LustreError: 307879:0:(file.c:246:ll_close_inode_openhandle()) lustre-clilmv-ffff9e2c47d08000: inode [0x200000402:0x7ba:0x0] mdc close failed: rc = -116 [ 6823.309600] LustreError: 307880:0:(file.c:246:ll_close_inode_openhandle()) lustre-clilmv-ffff9e2c47d08000: inode [0x240000402:0x600:0x0] mdc close failed: rc = -116 [ 6823.317341] LustreError: 307880:0:(file.c:246:ll_close_inode_openhandle()) Skipped 7 previous similar messages [ 6827.116432] Lustre: DEBUG MARKER: == sanityn test 80b: Accessing directory during migration ========================================================== 11:45:03 (1761320703) [ 6829.443601] Lustre: dir [0x200000402:0x7d3:0x0] stripe 1 readdir failed: -2, directory is partially accessed! [ 6838.189531] LustreError: 308736:0:(file.c:246:ll_close_inode_openhandle()) lustre-clilmv-ffff9e2c509b0000: inode [0x200000402:0x819:0x0] mdc close failed: rc = -2 [ 6838.196599] LustreError: 308736:0:(file.c:246:ll_close_inode_openhandle()) Skipped 3 previous similar messages [ 6848.503870] LustreError: 309054:0:(file.c:246:ll_close_inode_openhandle()) lustre-clilmv-ffff9e2c509b0000: inode [0x200000402:0x864:0x0] mdc close failed: rc = -2 [ 6855.141978] LustreError: 309266:0:(file.c:246:ll_close_inode_openhandle()) lustre-clilmv-ffff9e2c509b0000: inode [0x200000402:0x8ad:0x0] mdc close failed: rc = -2 [ 6876.315645] LustreError: 310052:0:(file.c:246:ll_close_inode_openhandle()) lustre-clilmv-ffff9e2c509b0000: inode [0x200000402:0x9d7:0x0] mdc close failed: rc = -2 [ 6876.327822] LustreError: 310052:0:(file.c:246:ll_close_inode_openhandle()) Skipped 1 previous similar message [ 6884.949612] LustreError: 310314:0:(llite_lib.c:1774:ll_update_lsm_md()) lustre: [0x200000402:0xa33:0x0] dir layout mismatch: [ 6884.955211] LustreError: 310314:0:(lustre_lmv.h:142:lsm_md_dump()) magic 0xcd20cd0 stripe count 1 master mdt 0 hash type crush:0x2000003 max-inherit 0 max-inherit-rr 0 version 1 migrate offset 0 migrate hash 0x0 pool [ 6884.963336] LustreError: 310314:0:(lustre_lmv.h:149:lsm_md_dump()) stripe[0] [0x200000bd0:0x6a:0x0] [ 6884.966816] LustreError: 310314:0:(lustre_lmv.h:142:lsm_md_dump()) magic 0xcd20cd0 stripe count 1 master mdt 0 hash type crush:0x2000003 max-inherit 0 max-inherit-rr 0 version 1 migrate offset 0 migrate hash 0x0 pool [ 6884.974001] LustreError: 310314:0:(lustre_lmv.h:149:lsm_md_dump()) stripe[0] [0x0:0x0:0x0] [ 6884.977710] LustreError: 310314:0:(llite_lib.c:3199:ll_prep_inode()) new_inode -fatal: rc -22 [ 6890.457940] Lustre: DEBUG MARKER: == sanityn test 81a: rename and stat under striped directory ========================================================== 11:46:06 (1761320766) [ 6893.680820] Lustre: DEBUG MARKER: == sanityn test 81b: rename under striped directory doesn't deadlock ========================================================== 11:46:09 (1761320769) [ 6916.576236] Lustre: lustre-OST0001-osc-ffff9e2c509b0000: disconnect after 22s idle [ 6916.579973] Lustre: Skipped 8 previous similar messages [ 6932.165150] Lustre: DEBUG MARKER: == sanityn test 81c: rename revoke LOOKUP lock for remote object ========================================================== 11:46:48 (1761320808) [ 6932.946566] Lustre: DEBUG MARKER: SKIP: sanityn test_81c needs >= 4 MDTs [ 6933.816778] Lustre: DEBUG MARKER: == sanityn test 82: fsetxattr and fgetxattr on orphan files ========================================================== 11:46:49 (1761320809) [ 6936.942595] Lustre: DEBUG MARKER: == sanityn test 83: access striped directory while it is being created/unlinked ========================================================== 11:46:52 (1761320812) [ 7060.044276] Lustre: DEBUG MARKER: == sanityn test 84: 0-nlink race in lu_object_find() ===== 11:48:56 (1761320936) [ 7068.285460] Lustre: DEBUG MARKER: == sanityn test 90: open/create and unlink striped directory ========================================================== 11:49:04 (1761320944) [ 7251.424513] Lustre: DEBUG MARKER: == sanityn test 91: chmod and unlink striped directory === 11:52:07 (1761321127) [ 7434.194599] Lustre: DEBUG MARKER: == sanityn test 92: create remote directory under orphan directory ========================================================== 11:55:10 (1761321310) [ 7436.807422] Lustre: DEBUG MARKER: == sanityn test 93: alloc_rr should not allocate on same ost ========================================================== 11:55:12 (1761321312) [ 7446.589846] Lustre: DEBUG MARKER: == sanityn test 94: signal vs CP callback race =========== 11:55:22 (1761321322) [ 7446.650683] Lustre: DEBUG MARKER: write [ 7446.673975] LustreError: 20973:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 312 sleeping for 5000ms [ 7448.681546] Lustre: DEBUG MARKER: kill 330266 [ 7448.684368] LustreError: 330266:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 329 sleeping for 6000ms [ 7451.775160] LustreError: 20973:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 312 awake [ 7454.719152] LustreError: 330266:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 329 awake [ 7457.413567] Lustre: DEBUG MARKER: == sanityn test 95b: Check readpage() on a page that is no longer uptodate ========================================================== 11:55:33 (1761321333) [ 7457.584381] LustreError: 330865:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 1424 sleeping for 5000ms [ 7459.671162] LustreError: 330865:0:(fail.c:144:__cfs_fail_timeout_set()) cfs_fail_timeout interrupted [ 7466.099556] Lustre: DEBUG MARKER: == sanityn test 100a: DoM: glimpse RPCs for stat without IO lock (DoM only file) ========================================================== 11:55:42 (1761321342) [ 7466.729324] Lustre: DEBUG MARKER: SKIP: sanityn test_100a Reserved for glimpse-ahead [ 7467.418520] Lustre: DEBUG MARKER: == sanityn test 100b: DoM: no glimpse RPC for stat with IO lock (DoM only file) ========================================================== 11:55:43 (1761321343) [ 7470.173767] Lustre: DEBUG MARKER: == sanityn test 100c: DoM: write vs stat without IO lock (combined file) ========================================================== 11:55:46 (1761321346) [ 7472.933073] Lustre: DEBUG MARKER: == sanityn test 100d: DoM: write+truncate vs stat without IO lock (combined file) ========================================================== 11:55:49 (1761321349) [ 7475.876890] Lustre: DEBUG MARKER: == sanityn test 100e: DoM: read on open and file size ==== 11:55:51 (1761321351) [ 7478.732275] Lustre: DEBUG MARKER: == sanityn test 101a: Discard DoM data on unlink ========= 11:55:54 (1761321354) [ 7481.401578] Lustre: DEBUG MARKER: == sanityn test 101b: Discard DoM data on rename ========= 11:55:57 (1761321357) [ 7484.206539] Lustre: DEBUG MARKER: == sanityn test 101c: Discard DoM data on close-unlink === 11:56:00 (1761321360) [ 7487.954925] Lustre: DEBUG MARKER: == sanityn test 102: Test open by handle of unlinked file ========================================================== 11:56:04 (1761321364) [ 7491.596839] Lustre: DEBUG MARKER: == sanityn test 103: Test size correctness with lockahead ========================================================== 11:56:07 (1761321367) [ 7492.342186] Lustre: *** cfs_fail_loc=415, val=0*** [ 7499.577763] Lustre: DEBUG MARKER: == sanityn test 104: Verify that MDS stores atime/mtime/ctime during close ========================================================== 11:56:15 (1761321375) [ 7519.862238] Lustre: DEBUG MARKER: == sanityn test 105: Glimpse and lock cancel race ======== 11:56:36 (1761321396) [ 7519.969103] LustreError: 5592:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 sleeping for 5000ms [ 7525.071183] LustreError: 5592:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 awake [ 7530.167127] LustreError: 5592:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 awake [ 7530.170051] LustreError: 5592:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 sleeping for 5000ms [ 7530.172993] LustreError: 5592:0:(fail.c:138:__cfs_fail_timeout_set()) Skipped 1 previous similar message [ 7540.367275] LustreError: 5591:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 awake [ 7540.370842] LustreError: 5591:0:(fail.c:149:__cfs_fail_timeout_set()) Skipped 1 previous similar message [ 7550.568779] LustreError: 5591:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 sleeping for 5000ms [ 7550.572611] LustreError: 5591:0:(fail.c:138:__cfs_fail_timeout_set()) Skipped 3 previous similar messages [ 7560.767238] LustreError: 5591:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 awake [ 7560.770420] LustreError: 5591:0:(fail.c:149:__cfs_fail_timeout_set()) Skipped 3 previous similar messages [ 7586.264441] LustreError: 5592:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 sleeping for 5000ms [ 7586.268747] LustreError: 5592:0:(fail.c:138:__cfs_fail_timeout_set()) Skipped 6 previous similar messages [ 7596.463396] LustreError: 5592:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 awake [ 7596.465985] LustreError: 5592:0:(fail.c:149:__cfs_fail_timeout_set()) Skipped 6 previous similar messages [ 7609.533397] Lustre: DEBUG MARKER: == sanityn test 106a: Verify the btime via statx() ======= 11:58:05 (1761321485) [ 7612.585138] Lustre: DEBUG MARKER: == sanityn test 106b: Glimpse RPCs test for statx ======== 11:58:08 (1761321488) [ 7615.481397] Lustre: DEBUG MARKER: == sanityn test 106c: Verify statx attributes mask ======= 11:58:11 (1761321491) [ 7618.268566] Lustre: DEBUG MARKER: == sanityn test 107a: Basic grouplock conflict =========== 11:58:14 (1761321494) [ 7623.202371] Lustre: DEBUG MARKER: == sanityn test 107b: Grouplock is added to the head of waiting list ========================================================== 11:58:19 (1761321499) [ 7632.446671] Lustre: DEBUG MARKER: == sanityn test 108a: lseek: parallel updates ============ 11:58:28 (1761321508) [ 7639.532761] Lustre: DEBUG MARKER: == sanityn test 109: Race with several mount instances on 1 node ========================================================== 11:58:35 (1761321515) [ 7640.512116] Lustre: Unmounted lustre-client [ 7641.503540] Lustre: Unmounted lustre-client [ 7642.168387] Lustre: DEBUG MARKER: Iteration 1 [ 7642.377216] LustreError: 342369:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7642.377264] LustreError: 342370:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7642.385555] LustreError: 342369:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 7642.452155] Lustre: Mounted lustre-client [ 7642.453668] Lustre: Skipped 1 previous similar message [ 7643.201088] Lustre: Unmounted lustre-client [ 7644.369824] Key type lgssc unregistered [ 7644.493436] LNet: 342717:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7645.536576] LNet: Removed LNI 192.168.202.3@tcp [ 7645.862841] Key type .llcrypt unregistered [ 7645.864692] Key type ._llcrypt unregistered [ 7646.195089] alg: No test for adler32 (adler32-zlib) [ 7646.954377] Key type ._llcrypt registered [ 7646.955852] Key type .llcrypt registered [ 7647.105343] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7647.382192] Lustre: Lustre: Build Version: 2.15.7_9_g4830cc0 [ 7647.662236] LNet: Added LNI 192.168.202.3@tcp [8/256/0/180] [ 7647.664567] LNet: Accept secure, port 988 [ 7649.287282] Key type lgssc registered [ 7649.920379] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7655.218373] Lustre: DEBUG MARKER: Iteration 2 [ 7655.385850] LustreError: 343511:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7655.386093] LustreError: 343512:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7655.393511] LustreError: 343511:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 7656.454402] Lustre: Mounted lustre-client [ 7656.455928] Lustre: Skipped 1 previous similar message [ 7657.118415] Lustre: Unmounted lustre-client [ 7658.348264] Key type lgssc unregistered [ 7658.484577] LNet: 343861:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7659.551842] LNet: Removed LNI 192.168.202.3@tcp [ 7659.849240] Key type .llcrypt unregistered [ 7659.851146] Key type ._llcrypt unregistered [ 7660.199515] alg: No test for adler32 (adler32-zlib) [ 7660.951356] Key type ._llcrypt registered [ 7660.952743] Key type .llcrypt registered [ 7661.056368] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7661.220776] Lustre: Lustre: Build Version: 2.15.7_9_g4830cc0 [ 7661.339727] LNet: Added LNI 192.168.202.3@tcp [8/256/0/180] [ 7661.342684] LNet: Accept secure, port 988 [ 7662.951355] Key type lgssc registered [ 7663.518560] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7668.411990] Lustre: DEBUG MARKER: Iteration 3 [ 7668.555428] LustreError: 344665:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7668.555646] LustreError: 344666:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7668.562691] LustreError: 344665:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 7669.650400] Lustre: Mounted lustre-client [ 7669.651956] Lustre: Skipped 1 previous similar message [ 7670.418323] Lustre: Unmounted lustre-client [ 7671.639955] Key type lgssc unregistered [ 7671.770753] LNet: 345012:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7672.800677] LNet: Removed LNI 192.168.202.3@tcp [ 7673.091623] Key type .llcrypt unregistered [ 7673.093415] Key type ._llcrypt unregistered [ 7673.422758] alg: No test for adler32 (adler32-zlib) [ 7674.174366] Key type ._llcrypt registered [ 7674.175491] Key type .llcrypt registered [ 7674.268686] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7674.409343] Lustre: Lustre: Build Version: 2.15.7_9_g4830cc0 [ 7674.512813] LNet: Added LNI 192.168.202.3@tcp [8/256/0/180] [ 7674.515051] LNet: Accept secure, port 988 [ 7676.119142] Key type lgssc registered [ 7676.630532] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7681.403756] Lustre: DEBUG MARKER: Iteration 4 [ 7681.553484] LustreError: 345806:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7681.553644] LustreError: 345805:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7681.560520] LustreError: 345806:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 7682.652327] Lustre: Mounted lustre-client [ 7682.653871] Lustre: Skipped 1 previous similar message [ 7683.318236] Lustre: Unmounted lustre-client [ 7684.445851] Key type lgssc unregistered [ 7684.553504] LNet: 346155:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7685.599788] LNet: Removed LNI 192.168.202.3@tcp [ 7685.871510] Key type .llcrypt unregistered [ 7685.873097] Key type ._llcrypt unregistered [ 7686.157633] alg: No test for adler32 (adler32-zlib) [ 7686.911428] Key type ._llcrypt registered [ 7686.912942] Key type .llcrypt registered [ 7687.007905] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7687.160067] Lustre: Lustre: Build Version: 2.15.7_9_g4830cc0 [ 7687.264340] LNet: Added LNI 192.168.202.3@tcp [8/256/0/180] [ 7687.266782] LNet: Accept secure, port 988 [ 7688.871306] Key type lgssc registered [ 7689.328354] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7694.041657] Lustre: DEBUG MARKER: Iteration 5 [ 7694.199419] LustreError: 346947:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7694.199479] LustreError: 346948:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7694.208824] LustreError: 346947:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [ 7695.306484] Lustre: Mounted lustre-client [ 7695.308156] Lustre: Skipped 1 previous similar message [ 7696.079883] Lustre: Unmounted lustre-client [ 7697.431296] Key type lgssc unregistered [ 7697.560599] LNet: 347296:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7698.591995] LNet: Removed LNI 192.168.202.3@tcp [ 7698.917053] Key type .llcrypt unregistered [ 7698.919229] Key type ._llcrypt unregistered [ 7699.395138] alg: No test for adler32 (adler32-zlib) [ 7700.158481] Key type ._llcrypt registered [ 7700.160223] Key type .llcrypt registered [ 7700.263207] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7700.424111] Lustre: Lustre: Build Version: 2.15.7_9_g4830cc0 [ 7700.538584] LNet: Added LNI 192.168.202.3@tcp [8/256/0/180] [ 7700.540975] LNet: Accept secure, port 988 [ 7702.143157] Key type lgssc registered [ 7702.691037] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7707.217694] Lustre: DEBUG MARKER: Iteration 6 [ 7707.345936] LustreError: 348088:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7707.347140] LustreError: 348089:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7707.352110] LustreError: 348088:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [ 7708.424808] Lustre: Mounted lustre-client [ 7708.426050] Lustre: Skipped 1 previous similar message [ 7709.104157] Lustre: Unmounted lustre-client [ 7710.336867] Key type lgssc unregistered [ 7710.456597] LNet: 348437:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7711.519981] LNet: Removed LNI 192.168.202.3@tcp [ 7711.826123] Key type .llcrypt unregistered [ 7711.827634] Key type ._llcrypt unregistered [ 7712.223370] alg: No test for adler32 (adler32-zlib) [ 7712.976515] Key type ._llcrypt registered [ 7712.978048] Key type .llcrypt registered [ 7713.077384] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7713.226186] Lustre: Lustre: Build Version: 2.15.7_9_g4830cc0 [ 7713.334253] LNet: Added LNI 192.168.202.3@tcp [8/256/0/180] [ 7713.336563] LNet: Accept secure, port 988 [ 7714.935243] Key type lgssc registered [ 7715.503785] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7720.394740] Lustre: DEBUG MARKER: Iteration 7 [ 7720.542637] LustreError: 349229:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7720.542686] LustreError: 349230:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7720.551370] LustreError: 349229:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4994 [ 7721.644179] Lustre: Mounted lustre-client [ 7722.345928] Lustre: Unmounted lustre-client [ 7723.594240] Key type lgssc unregistered [ 7723.728982] LNet: 349580:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7724.768528] LNet: Removed LNI 192.168.202.3@tcp [ 7725.075130] Key type .llcrypt unregistered [ 7725.076696] Key type ._llcrypt unregistered [ 7725.537779] alg: No test for adler32 (adler32-zlib) [ 7726.295574] Key type ._llcrypt registered [ 7726.297302] Key type .llcrypt registered [ 7726.408602] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7726.567349] Lustre: Lustre: Build Version: 2.15.7_9_g4830cc0 [ 7726.681682] LNet: Added LNI 192.168.202.3@tcp [8/256/0/180] [ 7726.684615] LNet: Accept secure, port 988 [ 7728.287186] Key type lgssc registered [ 7728.831751] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7733.827603] Lustre: DEBUG MARKER: Iteration 8 [ 7733.965833] LustreError: 350372:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7733.965901] LustreError: 350373:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7733.974257] LustreError: 350372:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [ 7735.042787] Lustre: Mounted lustre-client [ 7735.811215] Lustre: Unmounted lustre-client [ 7737.123734] Key type lgssc unregistered [ 7737.264484] LNet: 350721:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7738.272269] LNet: Removed LNI 192.168.202.3@tcp [ 7738.619159] Key type .llcrypt unregistered [ 7738.620708] Key type ._llcrypt unregistered [ 7739.001884] alg: No test for adler32 (adler32-zlib) [ 7739.754454] Key type ._llcrypt registered [ 7739.756188] Key type .llcrypt registered [ 7739.874737] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7740.035318] Lustre: Lustre: Build Version: 2.15.7_9_g4830cc0 [ 7740.146136] LNet: Added LNI 192.168.202.3@tcp [8/256/0/180] [ 7740.148250] LNet: Accept secure, port 988 [ 7741.759203] Key type lgssc registered [ 7742.321254] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7747.172425] Lustre: DEBUG MARKER: Iteration 9 [ 7747.323616] LustreError: 351513:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7747.323694] LustreError: 351514:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7747.331847] LustreError: 351513:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 7748.433943] Lustre: Mounted lustre-client [ 7748.435679] Lustre: Skipped 1 previous similar message [ 7749.211213] Lustre: Unmounted lustre-client [ 7750.552417] Key type lgssc unregistered [ 7750.672202] LNet: 351862:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7751.713224] LNet: Removed LNI 192.168.202.3@tcp [ 7752.022353] Key type .llcrypt unregistered [ 7752.024108] Key type ._llcrypt unregistered [ 7752.463464] alg: No test for adler32 (adler32-zlib) [ 7753.215350] Key type ._llcrypt registered [ 7753.216863] Key type .llcrypt registered [ 7753.335411] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7753.517927] Lustre: Lustre: Build Version: 2.15.7_9_g4830cc0 [ 7753.628709] LNet: Added LNI 192.168.202.3@tcp [8/256/0/180] [ 7753.631246] LNet: Accept secure, port 988 [ 7755.239149] Key type lgssc registered [ 7755.804508] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7761.024422] Lustre: DEBUG MARKER: Iteration 10 [ 7761.198563] LustreError: 352655:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7761.198767] LustreError: 352656:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7761.208058] LustreError: 352655:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [ 7762.307457] Lustre: Mounted lustre-client [ 7762.309088] Lustre: Skipped 1 previous similar message [ 7763.076720] Lustre: Unmounted lustre-client [ 7763.078303] Lustre: Skipped 1 previous similar message [ 7764.417123] Key type lgssc unregistered [ 7764.543970] LNet: 353004:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7765.600812] LNet: Removed LNI 192.168.202.3@tcp [ 7765.907284] Key type .llcrypt unregistered [ 7765.908952] Key type ._llcrypt unregistered [ 7766.305561] alg: No test for adler32 (adler32-zlib) [ 7767.060529] Key type ._llcrypt registered [ 7767.061918] Key type .llcrypt registered [ 7767.161938] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7767.313626] Lustre: Lustre: Build Version: 2.15.7_9_g4830cc0 [ 7767.418791] LNet: Added LNI 192.168.202.3@tcp [8/256/0/180] [ 7767.421526] LNet: Accept secure, port 988 [ 7769.023147] Key type lgssc registered [ 7769.581864] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7773.965589] Lustre: DEBUG MARKER: Iteration 11 [ 7774.119643] LustreError: 353796:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7774.119674] LustreError: 353798:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7774.127687] LustreError: 353796:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [ 7775.205683] Lustre: Mounted lustre-client [ 7775.207062] Lustre: Skipped 1 previous similar message [ 7775.879571] Lustre: Unmounted lustre-client [ 7776.963433] Key type lgssc unregistered [ 7777.074500] LNet: 354148:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7778.079705] LNet: Removed LNI 192.168.202.3@tcp [ 7778.299149] Key type .llcrypt unregistered [ 7778.300169] Key type ._llcrypt unregistered [ 7778.661428] alg: No test for adler32 (adler32-zlib) [ 7779.412328] Key type ._llcrypt registered [ 7779.413416] Key type .llcrypt registered [ 7779.489324] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7779.596339] Lustre: Lustre: Build Version: 2.15.7_9_g4830cc0 [ 7779.676802] LNet: Added LNI 192.168.202.3@tcp [8/256/0/180] [ 7779.678955] LNet: Accept secure, port 988 [ 7781.263129] Key type lgssc registered [ 7781.702738] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7785.691000] Lustre: DEBUG MARKER: Iteration 12 [ 7785.809428] LustreError: 354951:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7785.809474] LustreError: 354952:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7785.817599] LustreError: 354951:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 7786.868223] Lustre: Mounted lustre-client [ 7786.870083] Lustre: Skipped 1 previous similar message [ 7787.430844] Lustre: Unmounted lustre-client [ 7788.530901] Key type lgssc unregistered [ 7788.655487] LNet: 355297:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7789.663932] LNet: Removed LNI 192.168.202.3@tcp [ 7789.923367] Key type .llcrypt unregistered [ 7789.924737] Key type ._llcrypt unregistered [ 7790.256728] alg: No test for adler32 (adler32-zlib) [ 7791.009331] Key type ._llcrypt registered [ 7791.010850] Key type .llcrypt registered [ 7791.102393] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7791.228364] Lustre: Lustre: Build Version: 2.15.7_9_g4830cc0 [ 7791.323197] LNet: Added LNI 192.168.202.3@tcp [8/256/0/180] [ 7791.325348] LNet: Accept secure, port 988 [ 7792.919137] Key type lgssc registered [ 7793.360987] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7797.852488] Lustre: DEBUG MARKER: Iteration 13 [ 7797.996610] LustreError: 356092:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7797.997086] LustreError: 356093:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7798.005491] LustreError: 356092:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [ 7799.088902] Lustre: Mounted lustre-client [ 7799.090973] Lustre: Skipped 1 previous similar message [ 7799.667084] Lustre: Unmounted lustre-client [ 7800.738973] Key type lgssc unregistered [ 7800.854519] LNet: 356440:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7801.887627] LNet: Removed LNI 192.168.202.3@tcp [ 7802.146110] Key type .llcrypt unregistered [ 7802.147468] Key type ._llcrypt unregistered [ 7802.525545] alg: No test for adler32 (adler32-zlib) [ 7803.277446] Key type ._llcrypt registered [ 7803.279112] Key type .llcrypt registered [ 7803.365304] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7803.493563] Lustre: Lustre: Build Version: 2.15.7_9_g4830cc0 [ 7803.591351] LNet: Added LNI 192.168.202.3@tcp [8/256/0/180] [ 7803.593870] LNet: Accept secure, port 988 [ 7805.191211] Key type lgssc registered [ 7805.655590] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7809.917767] Lustre: DEBUG MARKER: Iteration 14 [ 7810.030457] LustreError: 357233:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7810.030526] LustreError: 357234:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7810.040274] LustreError: 357233:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4993 [ 7811.115252] Lustre: Mounted lustre-client [ 7811.116894] Lustre: Skipped 1 previous similar message [ 7811.721728] Lustre: Unmounted lustre-client [ 7811.723406] Lustre: Skipped 1 previous similar message [ 7812.757542] Key type lgssc unregistered [ 7812.865537] LNet: 357581:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7813.920322] LNet: Removed LNI 192.168.202.3@tcp [ 7814.193424] Key type .llcrypt unregistered [ 7814.194881] Key type ._llcrypt unregistered [ 7814.536091] alg: No test for adler32 (adler32-zlib) [ 7815.288457] Key type ._llcrypt registered [ 7815.289894] Key type .llcrypt registered [ 7815.376717] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7815.500204] Lustre: Lustre: Build Version: 2.15.7_9_g4830cc0 [ 7815.590942] LNet: Added LNI 192.168.202.3@tcp [8/256/0/180] [ 7815.593236] LNet: Accept secure, port 988 [ 7817.183135] Key type lgssc registered [ 7817.618087] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7821.792629] Lustre: DEBUG MARKER: Iteration 15 [ 7821.929834] LustreError: 358374:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7821.930119] LustreError: 358375:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7821.936937] LustreError: 358374:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 7822.996408] Lustre: Mounted lustre-client [ 7822.997962] Lustre: Skipped 1 previous similar message [ 7823.586214] Lustre: Unmounted lustre-client [ 7824.638055] Key type lgssc unregistered [ 7824.735496] LNet: 358723:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7825.760380] LNet: Removed LNI 192.168.202.3@tcp [ 7826.020191] Key type .llcrypt unregistered [ 7826.021657] Key type ._llcrypt unregistered [ 7826.274337] alg: No test for adler32 (adler32-zlib) [ 7827.026438] Key type ._llcrypt registered [ 7827.028046] Key type .llcrypt registered [ 7827.114369] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7827.239711] Lustre: Lustre: Build Version: 2.15.7_9_g4830cc0 [ 7827.334227] LNet: Added LNI 192.168.202.3@tcp [8/256/0/180] [ 7827.336389] LNet: Accept secure, port 988 [ 7828.935168] Key type lgssc registered [ 7829.368131] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7833.575296] Lustre: DEBUG MARKER: Iteration 16 [ 7833.706289] LustreError: 359516:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7833.706598] LustreError: 359517:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7833.713854] LustreError: 359516:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 7834.774783] Lustre: Mounted lustre-client [ 7835.327511] Lustre: Unmounted lustre-client [ 7835.328760] Lustre: Skipped 1 previous similar message [ 7836.368363] Key type lgssc unregistered [ 7836.486490] LNet: 359864:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7837.535794] LNet: Removed LNI 192.168.202.3@tcp [ 7837.778214] Key type .llcrypt unregistered [ 7837.779890] Key type ._llcrypt unregistered [ 7838.062270] alg: No test for adler32 (adler32-zlib) [ 7838.816296] Key type ._llcrypt registered [ 7838.817406] Key type .llcrypt registered [ 7838.890955] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7839.004812] Lustre: Lustre: Build Version: 2.15.7_9_g4830cc0 [ 7839.088103] LNet: Added LNI 192.168.202.3@tcp [8/256/0/180] [ 7839.090071] LNet: Accept secure, port 988 [ 7840.671130] Key type lgssc registered [ 7841.071560] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7845.120852] Lustre: DEBUG MARKER: Iteration 17 [ 7845.262953] LustreError: 360656:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7845.263023] LustreError: 360657:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7845.271576] LustreError: 360656:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [ 7846.323831] Lustre: Mounted lustre-client [ 7846.325325] Lustre: Skipped 1 previous similar message [ 7847.044176] Lustre: Unmounted lustre-client [ 7848.108242] Key type lgssc unregistered [ 7848.218760] LNet: 361007:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7849.248248] LNet: Removed LNI 192.168.202.3@tcp [ 7849.497795] Key type .llcrypt unregistered [ 7849.499281] Key type ._llcrypt unregistered [ 7849.854680] alg: No test for adler32 (adler32-zlib) [ 7850.607414] Key type ._llcrypt registered [ 7850.609209] Key type .llcrypt registered [ 7850.691674] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7850.810672] Lustre: Lustre: Build Version: 2.15.7_9_g4830cc0 [ 7850.895794] LNet: Added LNI 192.168.202.3@tcp [8/256/0/180] [ 7850.897424] LNet: Accept secure, port 988 [ 7852.495173] Key type lgssc registered [ 7852.893736] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7856.871034] Lustre: DEBUG MARKER: Iteration 18 [ 7856.989619] LustreError: 361799:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7856.993223] LustreError: 361800:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7856.996483] LustreError: 361799:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 7858.048425] Lustre: Mounted lustre-client [ 7858.049614] Lustre: Skipped 1 previous similar message [ 7858.612122] Lustre: Unmounted lustre-client [ 7859.594361] Key type lgssc unregistered [ 7859.721586] LNet: 362147:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7860.767807] LNet: Removed LNI 192.168.202.3@tcp [ 7861.005130] Key type .llcrypt unregistered [ 7861.006443] Key type ._llcrypt unregistered [ 7861.334066] alg: No test for adler32 (adler32-zlib) [ 7862.085389] Key type ._llcrypt registered [ 7862.086850] Key type .llcrypt registered [ 7862.171850] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7862.290077] Lustre: Lustre: Build Version: 2.15.7_9_g4830cc0 [ 7862.367547] LNet: Added LNI 192.168.202.3@tcp [8/256/0/180] [ 7862.369436] LNet: Accept secure, port 988 [ 7863.959143] Key type lgssc registered [ 7864.439394] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7868.665343] Lustre: DEBUG MARKER: Iteration 19 [ 7868.779622] LustreError: 362940:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7868.782451] LustreError: 362941:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7868.787526] LustreError: 362940:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [ 7869.851803] Lustre: Mounted lustre-client [ 7870.426895] Lustre: Unmounted lustre-client [ 7871.499825] Key type lgssc unregistered [ 7871.609455] LNet: 363288:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7872.671833] LNet: Removed LNI 192.168.202.3@tcp [ 7872.933590] Key type .llcrypt unregistered [ 7872.935183] Key type ._llcrypt unregistered [ 7873.329398] alg: No test for adler32 (adler32-zlib) [ 7874.080470] Key type ._llcrypt registered [ 7874.082134] Key type .llcrypt registered [ 7874.164554] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7874.295878] Lustre: Lustre: Build Version: 2.15.7_9_g4830cc0 [ 7874.392922] LNet: Added LNI 192.168.202.3@tcp [8/256/0/180] [ 7874.395215] LNet: Accept secure, port 988 [ 7875.991155] Key type lgssc registered [ 7876.433922] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7880.743630] Lustre: DEBUG MARKER: Iteration 20 [ 7880.860939] LustreError: 364097:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7880.861050] LustreError: 364098:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7880.865729] LustreError: 364097:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 7881.938400] Lustre: Mounted lustre-client [ 7881.939834] Lustre: Skipped 1 previous similar message [ 7882.481418] Lustre: Unmounted lustre-client [ 7883.579828] Key type lgssc unregistered [ 7883.689721] LNet: 364445:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7884.704447] LNet: Removed LNI 192.168.202.3@tcp [ 7885.001392] Key type .llcrypt unregistered [ 7885.003119] Key type ._llcrypt unregistered [ 7885.362085] alg: No test for adler32 (adler32-zlib) [ 7886.117468] Key type ._llcrypt registered [ 7886.119570] Key type .llcrypt registered [ 7886.221988] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7886.420959] Lustre: Lustre: Build Version: 2.15.7_9_g4830cc0 [ 7886.538879] LNet: Added LNI 192.168.202.3@tcp [8/256/0/180] [ 7886.541127] LNet: Accept secure, port 988 [ 7888.183821] Key type lgssc registered [ 7889.455304] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7904.264596] Lustre: DEBUG MARKER: Iteration 21 [ 7904.785164] LustreError: 365239:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7904.788556] LustreError: 365240:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7904.820593] LustreError: 365239:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4976 [ 7906.144645] Lustre: Mounted lustre-client [ 7908.043509] Lustre: Unmounted lustre-client [ 7912.063351] Key type lgssc unregistered [ 7912.371728] LNet: 365592:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7913.443266] LNet: Removed LNI 192.168.202.3@tcp [ 7914.412996] Key type .llcrypt unregistered [ 7914.417918] Key type ._llcrypt unregistered [ 7916.015101] alg: No test for adler32 (adler32-zlib) [ 7916.844990] Key type ._llcrypt registered [ 7916.846220] Key type .llcrypt registered [ 7917.132619] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7917.517666] Lustre: Lustre: Build Version: 2.15.7_9_g4830cc0 [ 7917.832554] LNet: Added LNI 192.168.202.3@tcp [8/256/0/180] [ 7917.841259] LNet: Accept secure, port 988 [ 7919.583142] Key type lgssc registered [ 7921.335150] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7932.564644] Lustre: DEBUG MARKER: Iteration 22 [ 7932.981309] LustreError: 366382:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7932.998386] LustreError: 366390:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7933.002286] LustreError: 366382:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4982 [ 7934.268219] Lustre: Mounted lustre-client [ 7935.532866] Lustre: Unmounted lustre-client [ 7937.550918] Key type lgssc unregistered [ 7937.780844] LNet: 366740:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7938.849065] LNet: Removed LNI 192.168.202.3@tcp [ 7939.212695] Key type .llcrypt unregistered [ 7939.214133] Key type ._llcrypt unregistered [ 7939.589176] alg: No test for adler32 (adler32-zlib) [ 7940.340453] Key type ._llcrypt registered [ 7940.345901] Key type .llcrypt registered [ 7940.495191] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7940.672712] Lustre: Lustre: Build Version: 2.15.7_9_g4830cc0 [ 7940.791173] LNet: Added LNI 192.168.202.3@tcp [8/256/0/180] [ 7940.793957] LNet: Accept secure, port 988 [ 7942.426233] Key type lgssc registered [ 7943.120560] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7949.341700] Lustre: DEBUG MARKER: Iteration 23 [ 7949.479691] LustreError: 367534:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7949.479881] LustreError: 367535:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7949.487692] LustreError: 367534:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 7950.597882] Lustre: Mounted lustre-client [ 7951.425629] Lustre: Unmounted lustre-client [ 7952.829832] Key type lgssc unregistered [ 7952.965550] LNet: 367889:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7954.016429] LNet: Removed LNI 192.168.202.3@tcp [ 7954.332515] Key type .llcrypt unregistered [ 7954.334500] Key type ._llcrypt unregistered [ 7954.788583] alg: No test for adler32 (adler32-zlib) [ 7955.550428] Key type ._llcrypt registered [ 7955.551886] Key type .llcrypt registered [ 7955.660830] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7955.805831] Lustre: Lustre: Build Version: 2.15.7_9_g4830cc0 [ 7955.913134] LNet: Added LNI 192.168.202.3@tcp [8/256/0/180] [ 7955.917160] LNet: Accept secure, port 988 [ 7957.527241] Key type lgssc registered [ 7958.183431] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7964.453777] Lustre: DEBUG MARKER: Iteration 24 [ 7964.632335] LustreError: 368681:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7964.632488] LustreError: 368683:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7964.649453] LustreError: 368681:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4992 [ 7965.780672] Lustre: Mounted lustre-client [ 7965.782325] Lustre: Skipped 1 previous similar message [ 7966.636401] Lustre: Unmounted lustre-client [ 7968.048324] Key type lgssc unregistered [ 7968.185740] LNet: 369030:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7969.248576] LNet: Removed LNI 192.168.202.3@tcp [ 7969.576254] Key type .llcrypt unregistered [ 7969.578594] Key type ._llcrypt unregistered [ 7970.088814] alg: No test for adler32 (adler32-zlib) [ 7970.841509] Key type ._llcrypt registered [ 7970.842844] Key type .llcrypt registered [ 7970.950198] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7971.085572] Lustre: Lustre: Build Version: 2.15.7_9_g4830cc0 [ 7971.200512] LNet: Added LNI 192.168.202.3@tcp [8/256/0/180] [ 7971.202819] LNet: Accept secure, port 988 [ 7972.815193] Key type lgssc registered [ 7973.349498] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7979.250234] Lustre: DEBUG MARKER: Iteration 25 [ 7979.507520] LustreError: 369825:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7979.507660] LustreError: 369826:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7979.520743] LustreError: 369825:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4994 [ 7980.651433] Lustre: Mounted lustre-client [ 7980.655595] Lustre: Skipped 1 previous similar message [ 7981.601672] Lustre: Unmounted lustre-client [ 7983.137484] Key type lgssc unregistered [ 7983.269779] LNet: 370175:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7984.288811] LNet: Removed LNI 192.168.202.3@tcp [ 7984.773622] Key type .llcrypt unregistered [ 7984.775033] Key type ._llcrypt unregistered [ 7985.444363] alg: No test for adler32 (adler32-zlib) [ 7986.198587] Key type ._llcrypt registered [ 7986.202077] Key type .llcrypt registered [ 7986.361084] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7986.573727] Lustre: Lustre: Build Version: 2.15.7_9_g4830cc0 [ 7986.729766] LNet: Added LNI 192.168.202.3@tcp [8/256/0/180] [ 7986.732694] LNet: Accept secure, port 988 [ 7988.383132] Key type lgssc registered [ 7989.010908] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7995.389151] Lustre: DEBUG MARKER: Iteration 26 [ 7995.576457] LustreError: 370969:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7995.576518] LustreError: 370970:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7995.584081] LustreError: 370969:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4994 [ 7996.692064] Lustre: Mounted lustre-client [ 7997.522909] Lustre: Unmounted lustre-client [ 7998.953040] Key type lgssc unregistered [ 7999.109472] LNet: 371319:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8000.162031] LNet: Removed LNI 192.168.202.3@tcp [ 8000.509158] Key type .llcrypt unregistered [ 8000.511048] Key type ._llcrypt unregistered [ 8000.863099] alg: No test for adler32 (adler32-zlib) [ 8001.616501] Key type ._llcrypt registered [ 8001.618056] Key type .llcrypt registered [ 8001.735114] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8001.885921] Lustre: Lustre: Build Version: 2.15.7_9_g4830cc0 [ 8001.999383] LNet: Added LNI 192.168.202.3@tcp [8/256/0/180] [ 8002.002137] LNet: Accept secure, port 988 [ 8003.615326] Key type lgssc registered [ 8004.182081] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8010.224856] Lustre: DEBUG MARKER: Iteration 27 [ 8010.410123] LustreError: 372111:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 8010.411233] LustreError: 372112:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 8010.418668] LustreError: 372111:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [ 8011.514118] Lustre: Mounted lustre-client [ 8012.264386] Lustre: Unmounted lustre-client [ 8013.569435] Key type lgssc unregistered [ 8013.695381] LNet: 372460:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8014.753678] LNet: Removed LNI 192.168.202.3@tcp [ 8015.062764] Key type .llcrypt unregistered [ 8015.064624] Key type ._llcrypt unregistered [ 8015.398100] alg: No test for adler32 (adler32-zlib) [ 8016.150398] Key type ._llcrypt registered [ 8016.152134] Key type .llcrypt registered [ 8016.263756] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8016.419124] Lustre: Lustre: Build Version: 2.15.7_9_g4830cc0 [ 8016.542194] LNet: Added LNI 192.168.202.3@tcp [8/256/0/180] [ 8016.546200] LNet: Accept secure, port 988 [ 8018.159179] Key type lgssc registered [ 8018.694451] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8023.779678] Lustre: DEBUG MARKER: Iteration 28 [ 8023.929443] LustreError: 373253:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 8023.932899] LustreError: 373254:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 8023.939145] LustreError: 373253:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4993 [ 8025.046425] Lustre: Mounted lustre-client [ 8025.774346] Lustre: Unmounted lustre-client [ 8025.775957] Lustre: Skipped 1 previous similar message [ 8026.972952] Key type lgssc unregistered [ 8027.086867] LNet: 373603:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8028.130026] LNet: Removed LNI 192.168.202.3@tcp [ 8028.442987] Key type .llcrypt unregistered [ 8028.444857] Key type ._llcrypt unregistered [ 8028.827230] alg: No test for adler32 (adler32-zlib) [ 8029.583437] Key type ._llcrypt registered [ 8029.584890] Key type .llcrypt registered [ 8029.688060] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8029.825758] Lustre: Lustre: Build Version: 2.15.7_9_g4830cc0 [ 8029.932853] LNet: Added LNI 192.168.202.3@tcp [8/256/0/180] [ 8029.935420] LNet: Accept secure, port 988 [ 8031.535245] Key type lgssc registered [ 8032.067042] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8036.992096] Lustre: DEBUG MARKER: Iteration 29 [ 8037.129204] LustreError: 374394:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 8037.130629] LustreError: 374396:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 8037.137789] LustreError: 374394:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [ 8038.227259] Lustre: Mounted lustre-client [ 8039.006676] Lustre: Unmounted lustre-client [ 8040.230947] Key type lgssc unregistered [ 8040.357170] LNet: 374746:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8041.376105] LNet: Removed LNI 192.168.202.3@tcp [ 8041.704211] Key type .llcrypt unregistered [ 8041.705906] Key type ._llcrypt unregistered [ 8042.026760] alg: No test for adler32 (adler32-zlib) [ 8042.778429] Key type ._llcrypt registered [ 8042.780650] Key type .llcrypt registered [ 8042.879130] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8043.045892] Lustre: Lustre: Build Version: 2.15.7_9_g4830cc0 [ 8043.162626] LNet: Added LNI 192.168.202.3@tcp [8/256/0/180] [ 8043.168518] LNet: Accept secure, port 988 [ 8044.783487] Key type lgssc registered [ 8045.286887] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8050.097739] Lustre: DEBUG MARKER: Iteration 30 [ 8050.248126] LustreError: 375554:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 8050.249192] LustreError: 375555:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 8050.258268] LustreError: 375554:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 8051.362565] Lustre: Mounted lustre-client [ 8051.364865] Lustre: Skipped 1 previous similar message [ 8052.067247] Lustre: Unmounted lustre-client [ 8053.361796] Key type lgssc unregistered [ 8053.491617] LNet: 375900:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8054.561260] LNet: Removed LNI 192.168.202.3@tcp [ 8055.032420] Key type .llcrypt unregistered [ 8055.034064] Key type ._llcrypt unregistered [ 8055.538905] alg: No test for adler32 (adler32-zlib) [ 8056.306419] Key type ._llcrypt registered [ 8056.307638] Key type .llcrypt registered [ 8056.455760] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8056.636784] Lustre: Lustre: Build Version: 2.15.7_9_g4830cc0 [ 8056.747582] LNet: Added LNI 192.168.202.3@tcp [8/256/0/180] [ 8056.751121] LNet: Accept secure, port 988 [ 8058.367142] Key type lgssc registered [ 8059.025566] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8065.544920] Lustre: DEBUG MARKER: Iteration 31 [ 8065.754740] LustreError: 376692:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 8065.756189] LustreError: 376693:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 8065.763897] LustreError: 376692:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [ 8066.912658] Lustre: Mounted lustre-client [ 8066.915021] Lustre: Skipped 1 previous similar message [ 8067.669672] Lustre: Unmounted lustre-client [ 8069.027585] Key type lgssc unregistered [ 8069.148854] LNet: 377041:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8070.177601] LNet: Removed LNI 192.168.202.3@tcp [ 8070.570697] Key type .llcrypt unregistered [ 8070.573437] Key type ._llcrypt unregistered [ 8070.997095] alg: No test for adler32 (adler32-zlib) [ 8071.748445] Key type ._llcrypt registered [ 8071.751880] Key type .llcrypt registered [ 8071.953192] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8072.134803] Lustre: Lustre: Build Version: 2.15.7_9_g4830cc0 [ 8072.255636] LNet: Added LNI 192.168.202.3@tcp [8/256/0/180] [ 8072.258534] LNet: Accept secure, port 988 [ 8073.871188] Key type lgssc registered [ 8074.479680] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8080.520291] Lustre: DEBUG MARKER: Iteration 32 [ 8080.695257] LustreError: 377835:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 8080.695408] LustreError: 377836:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 8080.708470] LustreError: 377835:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4994 [ 8081.813685] Lustre: Mounted lustre-client [ 8082.610155] Lustre: Unmounted lustre-client [ 8083.971627] Key type lgssc unregistered [ 8084.119851] LNet: 378187:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8085.152401] LNet: Removed LNI 192.168.202.3@tcp [ 8085.519153] Key type .llcrypt unregistered [ 8085.521030] Key type ._llcrypt unregistered [ 8085.907054] alg: No test for adler32 (adler32-zlib) [ 8086.659299] Key type ._llcrypt registered [ 8086.660918] Key type .llcrypt registered [ 8086.766872] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8086.946980] Lustre: Lustre: Build Version: 2.15.7_9_g4830cc0 [ 8087.060072] LNet: Added LNI 192.168.202.3@tcp [8/256/0/180] [ 8087.062591] LNet: Accept secure, port 988 [ 8088.671180] Key type lgssc registered [ 8089.177990] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8094.196447] Lustre: DEBUG MARKER: Iteration 33 [ 8094.323111] LustreError: 378979:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 8094.323528] LustreError: 378980:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 8094.330232] LustreError: 378979:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 8095.449854] Lustre: Mounted lustre-client [ 8096.197283] Lustre: Unmounted lustre-client [ 8097.590446] Key type lgssc unregistered [ 8097.745709] LNet: 379327:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8098.783962] LNet: Removed LNI 192.168.202.3@tcp [ 8099.156976] Key type .llcrypt unregistered [ 8099.158741] Key type ._llcrypt unregistered [ 8099.665609] alg: No test for adler32 (adler32-zlib) [ 8100.418419] Key type ._llcrypt registered [ 8100.420032] Key type .llcrypt registered [ 8100.520557] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8100.679454] Lustre: Lustre: Build Version: 2.15.7_9_g4830cc0 [ 8100.799483] LNet: Added LNI 192.168.202.3@tcp [8/256/0/180] [ 8100.802975] LNet: Accept secure, port 988 [ 8102.423378] Key type lgssc registered [ 8103.096429] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8108.689586] Lustre: DEBUG MARKER: Iteration 34 [ 8108.838515] LustreError: 380122:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 8108.840123] LustreError: 380123:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 8108.845869] LustreError: 380122:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 8109.979981] Lustre: Mounted lustre-client [ 8110.700577] Lustre: Unmounted lustre-client [ 8111.977309] Key type lgssc unregistered [ 8112.122738] LNet: 380472:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8113.186058] LNet: Removed LNI 192.168.202.3@tcp [ 8113.520147] Key type .llcrypt unregistered [ 8113.523414] Key type ._llcrypt unregistered [ 8114.032408] alg: No test for adler32 (adler32-zlib) [ 8114.784470] Key type ._llcrypt registered [ 8114.786702] Key type .llcrypt registered [ 8114.910139] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8115.099303] Lustre: Lustre: Build Version: 2.15.7_9_g4830cc0 [ 8115.250875] LNet: Added LNI 192.168.202.3@tcp [8/256/0/180] [ 8115.253609] LNet: Accept secure, port 988 [ 8116.873077] Key type lgssc registered [ 8117.454521] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8122.733061] Lustre: DEBUG MARKER: Iteration 35 [ 8122.865302] LustreError: 381266:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 8122.865671] LustreError: 381265:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 8122.875262] LustreError: 381266:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 8123.961746] Lustre: Mounted lustre-client [ 8124.735920] Lustre: Unmounted lustre-client [ 8126.019666] Key type lgssc unregistered [ 8126.141636] LNet: 381612:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8127.200272] LNet: Removed LNI 192.168.202.3@tcp [ 8127.594281] Key type .llcrypt unregistered [ 8127.595988] Key type ._llcrypt unregistered [ 8127.954992] alg: No test for adler32 (adler32-zlib) [ 8128.706360] Key type ._llcrypt registered [ 8128.708429] Key type .llcrypt registered [ 8128.823654] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8129.002775] Lustre: Lustre: Build Version: 2.15.7_9_g4830cc0 [ 8129.139244] LNet: Added LNI 192.168.202.3@tcp [8/256/0/180] [ 8129.141559] LNet: Accept secure, port 988 [ 8130.751203] Key type lgssc registered [ 8131.341924] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8137.254297] Lustre: DEBUG MARKER: Iteration 36 [ 8137.412153] LustreError: 382407:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 8137.412201] LustreError: 382406:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 8137.423586] LustreError: 382407:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4991 [ 8138.550648] Lustre: Mounted lustre-client [ 8139.469992] Lustre: Unmounted lustre-client [ 8140.950123] Key type lgssc unregistered [ 8141.114777] LNet: 382755:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8142.177283] LNet: Removed LNI 192.168.202.3@tcp [ 8142.572297] Key type .llcrypt unregistered [ 8142.573971] Key type ._llcrypt unregistered [ 8142.918432] alg: No test for adler32 (adler32-zlib) [ 8143.675465] Key type ._llcrypt registered [ 8143.676956] Key type .llcrypt registered [ 8143.791471] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8143.949603] Lustre: Lustre: Build Version: 2.15.7_9_g4830cc0 [ 8144.061743] LNet: Added LNI 192.168.202.3@tcp [8/256/0/180] [ 8144.064383] LNet: Accept secure, port 988 [ 8145.671606] Key type lgssc registered [ 8146.307182] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8152.626622] Lustre: DEBUG MARKER: Iteration 37 [ 8152.848473] LustreError: 383549:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 8152.848495] LustreError: 383550:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 8152.858078] LustreError: 383549:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 8154.028371] Lustre: Mounted lustre-client [ 8154.793779] Lustre: Unmounted lustre-client [ 8156.243381] Key type lgssc unregistered [ 8156.386701] LNet: 383901:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8157.407579] LNet: Removed LNI 192.168.202.3@tcp [ 8157.748563] Key type .llcrypt unregistered [ 8157.750395] Key type ._llcrypt unregistered [ 8158.049912] alg: No test for adler32 (adler32-zlib) [ 8158.801370] Key type ._llcrypt registered [ 8158.803119] Key type .llcrypt registered [ 8158.944476] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8159.109206] Lustre: Lustre: Build Version: 2.15.7_9_g4830cc0 [ 8159.228710] LNet: Added LNI 192.168.202.3@tcp [8/256/0/180] [ 8159.231338] LNet: Accept secure, port 988 [ 8160.848667] Key type lgssc registered [ 8161.440547] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8167.170461] Lustre: DEBUG MARKER: Iteration 38 [ 8167.317832] LustreError: 384694:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 8167.317855] LustreError: 384695:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 8167.330082] LustreError: 384694:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4987 [ 8168.473653] Lustre: Mounted lustre-client [ 8168.475463] Lustre: Skipped 1 previous similar message [ 8169.242398] Lustre: Unmounted lustre-client [ 8170.468042] Key type lgssc unregistered [ 8170.614133] LNet: 385043:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8171.681291] LNet: Removed LNI 192.168.202.3@tcp [ 8172.042388] Key type .llcrypt unregistered [ 8172.043976] Key type ._llcrypt unregistered [ 8172.518275] alg: No test for adler32 (adler32-zlib) [ 8173.270422] Key type ._llcrypt registered [ 8173.272143] Key type .llcrypt registered [ 8173.380175] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8173.543724] Lustre: Lustre: Build Version: 2.15.7_9_g4830cc0 [ 8173.646856] LNet: Added LNI 192.168.202.3@tcp [8/256/0/180] [ 8173.649475] LNet: Accept secure, port 988 [ 8175.247614] Key type lgssc registered [ 8175.786755] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8181.343881] Lustre: DEBUG MARKER: Iteration 39 [ 8181.513138] LustreError: 385837:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 8181.513281] LustreError: 385838:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 8181.522408] LustreError: 385837:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4994 [ 8182.629850] Lustre: Mounted lustre-client [ 8182.633580] Lustre: Skipped 1 previous similar message [ 8183.363126] Lustre: Unmounted lustre-client [ 8184.742504] Key type lgssc unregistered [ 8184.878903] LNet: 386189:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8185.888895] LNet: Removed LNI 192.168.202.3@tcp [ 8186.261797] Key type .llcrypt unregistered [ 8186.263573] Key type ._llcrypt unregistered [ 8186.725864] alg: No test for adler32 (adler32-zlib) [ 8187.477415] Key type ._llcrypt registered [ 8187.479695] Key type .llcrypt registered [ 8187.618317] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8187.803218] Lustre: Lustre: Build Version: 2.15.7_9_g4830cc0 [ 8187.950743] LNet: Added LNI 192.168.202.3@tcp [8/256/0/180] [ 8187.952915] LNet: Accept secure, port 988 [ 8189.591474] Key type lgssc registered [ 8190.232337] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8196.108138] Lustre: DEBUG MARKER: Iteration 40 [ 8196.285243] LustreError: 386982:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 8196.290116] LustreError: 386983:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 8196.294182] LustreError: 386982:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 8197.405832] Lustre: Mounted lustre-client [ 8198.151183] Lustre: Unmounted lustre-client [ 8199.612198] Key type lgssc unregistered [ 8199.766160] LNet: 387335:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8200.800568] LNet: Removed LNI 192.168.202.3@tcp [ 8201.161597] Key type .llcrypt unregistered [ 8201.163742] Key type ._llcrypt unregistered [ 8201.530502] alg: No test for adler32 (adler32-zlib) [ 8202.284397] Key type ._llcrypt registered [ 8202.286808] Key type .llcrypt registered [ 8202.412555] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8202.593424] Lustre: Lustre: Build Version: 2.15.7_9_g4830cc0 [ 8202.699026] LNet: Added LNI 192.168.202.3@tcp [8/256/0/180] [ 8202.702458] LNet: Accept secure, port 988 [ 8204.311161] Key type lgssc registered [ 8204.864455] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8210.410320] Lustre: DEBUG MARKER: Iteration 41 [ 8210.554063] LustreError: 388129:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 8210.554686] LustreError: 388128:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 8210.563685] LustreError: 388129:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 8211.660252] Lustre: Mounted lustre-client [ 8211.661921] Lustre: Skipped 1 previous similar message [ 8212.412560] Lustre: Unmounted lustre-client [ 8213.672904] Key type lgssc unregistered [ 8213.819356] LNet: 388481:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8214.881079] LNet: Removed LNI 192.168.202.3@tcp [ 8215.187537] Key type .llcrypt unregistered [ 8215.189387] Key type ._llcrypt unregistered [ 8215.480986] alg: No test for adler32 (adler32-zlib) [ 8216.232364] Key type ._llcrypt registered [ 8216.234632] Key type .llcrypt registered [ 8216.321913] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8216.460724] Lustre: Lustre: Build Version: 2.15.7_9_g4830cc0 [ 8216.564526] LNet: Added LNI 192.168.202.3@tcp [8/256/0/180] [ 8216.567980] LNet: Accept secure, port 988 [ 8218.167254] Key type lgssc registered [ 8218.726329] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8223.822608] Lustre: DEBUG MARKER: Iteration 42 [ 8223.951638] LustreError: 389273:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 8223.952486] LustreError: 389274:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 8223.960538] LustreError: 389273:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4994 [ 8225.048832] Lustre: Mounted lustre-client [ 8225.695492] Lustre: Unmounted lustre-client [ 8226.830664] Key type lgssc unregistered [ 8226.945550] LNet: 389621:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8227.999754] LNet: Removed LNI 192.168.202.3@tcp [ 8228.265635] Key type .llcrypt unregistered [ 8228.267212] Key type ._llcrypt unregistered [ 8228.565182] alg: No test for adler32 (adler32-zlib) [ 8229.318424] Key type ._llcrypt registered [ 8229.320276] Key type .llcrypt registered [ 8229.416716] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8229.567245] Lustre: Lustre: Build Version: 2.15.7_9_g4830cc0 [ 8229.671620] LNet: Added LNI 192.168.202.3@tcp [8/256/0/180] [ 8229.674182] LNet: Accept secure, port 988 [ 8231.279136] Key type lgssc registered [ 8231.733570] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8236.365976] Lustre: DEBUG MARKER: Iteration 43 [ 8236.525596] LustreError: 390413:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 8236.526162] LustreError: 390414:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 8236.531269] LustreError: 390413:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 8237.637670] Lustre: Mounted lustre-client [ 8237.639310] Lustre: Skipped 1 previous similar message [ 8238.358403] Lustre: Unmounted lustre-client [ 8239.613361] Key type lgssc unregistered [ 8239.732843] LNet: 390765:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8240.799505] LNet: Removed LNI 192.168.202.3@tcp [ 8241.095926] Key type .llcrypt unregistered [ 8241.097548] Key type ._llcrypt unregistered [ 8241.433615] alg: No test for adler32 (adler32-zlib) [ 8242.184399] Key type ._llcrypt registered [ 8242.185651] Key type .llcrypt registered [ 8242.282238] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8242.413664] Lustre: Lustre: Build Version: 2.15.7_9_g4830cc0 [ 8242.514676] LNet: Added LNI 192.168.202.3@tcp [8/256/0/180] [ 8242.517478] LNet: Accept secure, port 988 [ 8244.119313] Key type lgssc registered [ 8244.620974] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8249.097137] Lustre: DEBUG MARKER: Iteration 44 [ 8249.215780] LustreError: 391557:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 8249.216774] LustreError: 391558:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 8249.223595] LustreError: 391557:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [ 8250.323093] Lustre: Mounted lustre-client [ 8251.010282] Lustre: Unmounted lustre-client [ 8252.208525] Key type lgssc unregistered [ 8252.339309] LNet: 391911:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8253.344771] LNet: Removed LNI 192.168.202.3@tcp [ 8253.659825] Key type .llcrypt unregistered [ 8253.661636] Key type ._llcrypt unregistered [ 8254.179827] alg: No test for adler32 (adler32-zlib) [ 8254.933417] Key type ._llcrypt registered [ 8254.934884] Key type .llcrypt registered [ 8255.048638] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8255.201698] Lustre: Lustre: Build Version: 2.15.7_9_g4830cc0 [ 8255.310259] LNet: Added LNI 192.168.202.3@tcp [8/256/0/180] [ 8255.313572] LNet: Accept secure, port 988 [ 8256.919253] Key type lgssc registered [ 8257.501187] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8262.623207] Lustre: DEBUG MARKER: Iteration 45 [ 8262.772281] LustreError: 392706:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 8262.773500] LustreError: 392707:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 8262.781877] LustreError: 392706:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [ 8263.871803] Lustre: Mounted lustre-client [ 8264.582563] Lustre: Unmounted lustre-client [ 8265.749388] Key type lgssc unregistered [ 8265.867895] LNet: 393059:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8266.912501] LNet: Removed LNI 192.168.202.3@tcp [ 8267.243183] Key type .llcrypt unregistered [ 8267.244797] Key type ._llcrypt unregistered [ 8267.605438] alg: No test for adler32 (adler32-zlib) [ 8268.354355] Key type ._llcrypt registered [ 8268.356066] Key type .llcrypt registered [ 8268.459365] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8268.611118] Lustre: Lustre: Build Version: 2.15.7_9_g4830cc0 [ 8268.725142] LNet: Added LNI 192.168.202.3@tcp [8/256/0/180] [ 8268.727541] LNet: Accept secure, port 988 [ 8270.335168] Key type lgssc registered [ 8270.905164] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8275.802995] Lustre: DEBUG MARKER: Iteration 46 [ 8275.961896] LustreError: 393853:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 8275.962528] LustreError: 393854:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 8275.970542] LustreError: 393853:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4994 [ 8277.064340] Lustre: Mounted lustre-client [ 8277.065931] Lustre: Skipped 1 previous similar message [ 8277.795733] Lustre: Unmounted lustre-client [ 8279.065293] Key type lgssc unregistered [ 8279.198564] LNet: 394200:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8280.224372] LNet: Removed LNI 192.168.202.3@tcp [ 8280.548754] Key type .llcrypt unregistered [ 8280.551685] Key type ._llcrypt unregistered [ 8280.846235] alg: No test for adler32 (adler32-zlib) [ 8281.598384] Key type ._llcrypt registered [ 8281.599372] Key type .llcrypt registered [ 8281.703987] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8281.838991] Lustre: Lustre: Build Version: 2.15.7_9_g4830cc0 [ 8281.941229] LNet: Added LNI 192.168.202.3@tcp [8/256/0/180] [ 8281.944922] LNet: Accept secure, port 988 [ 8283.543171] Key type lgssc registered [ 8284.163551] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8288.878613] Lustre: DEBUG MARKER: Iteration 47 [ 8289.008981] LustreError: 394992:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 8289.009319] LustreError: 394993:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 8289.017305] LustreError: 394992:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4994 [ 8290.100709] Lustre: Mounted lustre-client [ 8290.102538] Lustre: Skipped 1 previous similar message [ 8290.924269] Lustre: Unmounted lustre-client [ 8292.168853] Key type lgssc unregistered [ 8292.300910] LNet: 395339:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8293.345111] LNet: Removed LNI 192.168.202.3@tcp [ 8293.722542] Key type .llcrypt unregistered [ 8293.724193] Key type ._llcrypt unregistered [ 8294.113905] alg: No test for adler32 (adler32-zlib) [ 8294.867384] Key type ._llcrypt registered [ 8294.869327] Key type .llcrypt registered [ 8294.973185] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8295.116487] Lustre: Lustre: Build Version: 2.15.7_9_g4830cc0 [ 8295.212298] LNet: Added LNI 192.168.202.3@tcp [8/256/0/180] [ 8295.215296] LNet: Accept secure, port 988 [ 8296.823318] Key type lgssc registered [ 8297.434333] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8302.904572] Lustre: DEBUG MARKER: Iteration 48 [ 8303.058306] LustreError: 396132:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 8303.059407] LustreError: 396131:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 8303.067568] LustreError: 396132:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [ 8304.146133] Lustre: Mounted lustre-client [ 8304.147761] Lustre: Skipped 1 previous similar message [ 8304.756359] Lustre: Unmounted lustre-client [ 8306.065295] Key type lgssc unregistered [ 8306.187528] LNet: 396481:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8307.233490] LNet: Removed LNI 192.168.202.3@tcp [ 8307.558158] Key type .llcrypt unregistered [ 8307.559379] Key type ._llcrypt unregistered [ 8307.970106] alg: No test for adler32 (adler32-zlib) [ 8308.722348] Key type ._llcrypt registered [ 8308.724536] Key type .llcrypt registered [ 8308.841500] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8309.008652] Lustre: Lustre: Build Version: 2.15.7_9_g4830cc0 [ 8309.137428] LNet: Added LNI 192.168.202.3@tcp [8/256/0/180] [ 8309.139975] LNet: Accept secure, port 988 [ 8310.751456] Key type lgssc registered [ 8311.236477] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8315.985084] Lustre: DEBUG MARKER: Iteration 49 [ 8316.129448] LustreError: 397273:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 8316.130126] LustreError: 397274:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 8316.140274] LustreError: 397273:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4994 [ 8317.223769] Lustre: Mounted lustre-client [ 8317.907105] Lustre: Unmounted lustre-client [ 8319.156922] Key type lgssc unregistered [ 8319.271460] LNet: 397623:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8320.290554] LNet: Removed LNI 192.168.202.3@tcp [ 8320.626196] Key type .llcrypt unregistered [ 8320.628972] Key type ._llcrypt unregistered [ 8320.977557] alg: No test for adler32 (adler32-zlib) [ 8321.741377] Key type ._llcrypt registered [ 8321.743128] Key type .llcrypt registered [ 8321.835333] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8321.978057] Lustre: Lustre: Build Version: 2.15.7_9_g4830cc0 [ 8322.081566] LNet: Added LNI 192.168.202.3@tcp [8/256/0/180] [ 8322.085077] LNet: Accept secure, port 988 [ 8323.687149] Key type lgssc registered [ 8324.192858] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8328.804475] Lustre: DEBUG MARKER: Iteration 50 [ 8328.937171] LustreError: 398416:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 8328.937217] LustreError: 398417:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 8328.946512] LustreError: 398416:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 8330.031813] Lustre: Mounted lustre-client [ 8330.649771] Lustre: Unmounted lustre-client [ 8331.784497] Key type lgssc unregistered [ 8331.907476] LNet: 398764:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8332.959700] LNet: Removed LNI 192.168.202.3@tcp [ 8333.225986] Key type .llcrypt unregistered [ 8333.227541] Key type ._llcrypt unregistered [ 8333.574894] alg: No test for adler32 (adler32-zlib) [ 8334.326384] Key type ._llcrypt registered [ 8334.327612] Key type .llcrypt registered [ 8334.414597] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8334.541439] Lustre: Lustre: Build Version: 2.15.7_9_g4830cc0 [ 8334.660097] LNet: Added LNI 192.168.202.3@tcp [8/256/0/180] [ 8334.663325] LNet: Accept secure, port 988 [ 8336.271125] Key type lgssc registered [ 8336.746490] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8341.974757] Lustre: Mounted lustre-client [ 8345.686292] Lustre: DEBUG MARKER: == sanityn test 110: do not grant another lock on resend ========================================================== 12:10:21 (1761322221) [ 8352.735281] Lustre: 400069:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761322222/real 1761322222] req@000000003f935e85 x1846880198726656/t0(0) o36->lustre-MDT0000-mdc-ffff9e2c54385000@192.168.202.103@tcp:12/10 lens 496/440 e 0 to 1 dl 1761322229 ref 2 fl Rpc:XQr/0/ffffffff rc 0/-1 job:'ln.0' [ 8352.748359] Lustre: lustre-MDT0000-mdc-ffff9e2c54385000: Connection to lustre-MDT0000 (at 192.168.202.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8352.764653] Lustre: lustre-MDT0000-mdc-ffff9e2c54385000: Connection restored to (at 192.168.202.103@tcp) [ 8359.905481] Lustre: 400069:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761322229/real 1761322229] req@000000003f935e85 x1846880198726656/t0(0) o36->lustre-MDT0000-mdc-ffff9e2c54385000@192.168.202.103@tcp:12/10 lens 496/440 e 0 to 1 dl 1761322236 ref 2 fl Rpc:XQr/2/ffffffff rc 0/-1 job:'ln.0' [ 8359.915859] Lustre: lustre-MDT0000-mdc-ffff9e2c54385000: Connection to lustre-MDT0000 (at 192.168.202.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8359.934428] Lustre: lustre-MDT0000-mdc-ffff9e2c54385000: Connection restored to (at 192.168.202.103@tcp) [ 8367.071185] Lustre: 400069:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761322236/real 1761322236] req@000000003f935e85 x1846880198726656/t0(0) o36->lustre-MDT0000-mdc-ffff9e2c54385000@192.168.202.103@tcp:12/10 lens 496/440 e 0 to 1 dl 1761322243 ref 2 fl Rpc:XQr/2/ffffffff rc 0/-1 job:'ln.0' [ 8367.080912] Lustre: lustre-MDT0000-mdc-ffff9e2c54385000: Connection to lustre-MDT0000 (at 192.168.202.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8367.099596] Lustre: lustre-MDT0000-mdc-ffff9e2c54385000: Connection restored to (at 192.168.202.103@tcp) [ 8374.239269] Lustre: 400069:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761322243/real 1761322243] req@000000003f935e85 x1846880198726656/t0(0) o36->lustre-MDT0000-mdc-ffff9e2c54385000@192.168.202.103@tcp:12/10 lens 496/440 e 0 to 1 dl 1761322250 ref 2 fl Rpc:XQr/2/ffffffff rc 0/-1 job:'ln.0' [ 8374.248328] Lustre: lustre-MDT0000-mdc-ffff9e2c54385000: Connection to lustre-MDT0000 (at 192.168.202.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8374.259701] Lustre: lustre-MDT0000-mdc-ffff9e2c54385000: Connection restored to (at 192.168.202.103@tcp) [ 8380.383101] Lustre: 400069:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761322250/real 1761322250] req@000000003f935e85 x1846880198726656/t0(0) o36->lustre-MDT0000-mdc-ffff9e2c54385000@192.168.202.103@tcp:12/10 lens 496/440 e 0 to 1 dl 1761322257 ref 2 fl Rpc:XQr/2/ffffffff rc 0/-1 job:'ln.0' [ 8380.392097] Lustre: lustre-MDT0000-mdc-ffff9e2c54385000: Connection to lustre-MDT0000 (at 192.168.202.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8380.403152] Lustre: lustre-MDT0000-mdc-ffff9e2c54385000: Connection restored to (at 192.168.202.103@tcp) [ 8382.050149] Lustre: DEBUG MARKER: == sanityn test 111: A racy rename/link an open file should not cause fs corruption ========================================================== 12:10:58 (1761322258) [ 8388.439577] Lustre: DEBUG MARKER: == sanityn test 112: update max-inherit in default LMV === 12:11:04 (1761322264) [ 8393.153547] Lustre: DEBUG MARKER: == sanityn test 113: check servers of specified fs ======= 12:11:09 (1761322269) [ 8395.957663] Lustre: DEBUG MARKER: cleanup: ====================================================== [ 8396.656817] Lustre: DEBUG MARKER: == sanityn test complete, duration 8179 sec ============== 12:11:12 (1761322272) [ 8475.361164] Lustre: Unmounted lustre-client [ 8508.989172] Key type lgssc unregistered [ 8509.099480] LNet: 403330:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8510.111894] LNet: Removed LNI 192.168.202.3@tcp [ 8510.356247] Key type .llcrypt unregistered [ 8510.357577] Key type ._llcrypt unregistered