[ 0.000000] Linux version 4.18.0rh8.10-debug (green@maintenance) (gcc version 8.5.0 20210514 (Red Hat 8.5.0-26) (GCC)) #2 SMP Mon Jul 14 01:24:22 EDT 2025 [ 0.000000] Command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' [ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 [ 0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. [ 0.000000] signal: max sigframe size: 1776 [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bffd9fff] usable [ 0.000000] BIOS-e820: [mem 0x00000000bffda000-0x00000000bfffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x0000000146dfffff] usable [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] SMBIOS 2.8 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.17.0-10.fc44 06/10/2025 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 454524869 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.988 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2464 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE2300 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 0022C0 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2374 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE2404 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE243C 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe2300-0xbffe2373] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22ff] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2374-0xbffe2403] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe2404-0xbffe243b] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe243c-0xbffe2463] [ 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: 2895288K/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.001013] APIC: Switch to symmetric I/O mode setup [ 0.002391] x2apic enabled [ 0.003006] Switched APIC routing to physical x2apic. [ 0.004012] kvm-guest: setup PV IPIs [ 0.007000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.007000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x22982c12b6d, max_idle_ns: 440795281273 ns [ 0.007017] Calibrating delay loop (skipped) preset value.. 4799.97 BogoMIPS (lpj=2399988) [ 0.008006] pid_max: default: 32768 minimum: 301 [ 0.009100] LSM: Security Framework initializing [ 0.010035] Yama: becoming mindful. [ 0.011027] SELinux: Initializing. [ 0.012049] *** VALIDATE selinux *** [ 0.020231] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.024197] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.025143] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.026105] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.027139] *** VALIDATE tmpfs *** [ 0.028438] *** VALIDATE proc *** [ 0.030138] *** VALIDATE cgroup *** [ 0.031000] *** VALIDATE cgroup2 *** [ 0.032127] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.033151] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.034005] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.035022] Spectre V2 : User space: Vulnerable [ 0.036004] Speculative Store Bypass: Vulnerable [ 0.037000] debug: unmapping init [mem 0xffffffffa6a59000-0xffffffffa6a60fff] [ 0.038812] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.039769] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.040024] ... version: 2 [ 0.041016] ... bit width: 48 [ 0.042008] ... generic registers: 4 [ 0.043009] ... value mask: 0000ffffffffffff [ 0.044011] ... max period: 00007fffffffffff [ 0.045009] ... fixed-purpose events: 3 [ 0.046011] ... event mask: 000000070000000f [ 0.047284] rcu: Hierarchical SRCU implementation. [ 0.049738] smp: Bringing up secondary CPUs ... [ 0.050724] x86: Booting SMP configuration: [ 0.051030] .... node #0, CPUs: #1 #2 #3 [ 0.058078] smp: Brought up 1 node, 4 CPUs [ 0.060008] smpboot: Max logical packages: 1 [ 0.061010] smpboot: Total of 4 processors activated (19199.90 BogoMIPS) [ 0.127179] node 0 deferred pages initialised in 64ms [ 0.134095] devtmpfs: initialized [ 0.135372] x86/mm: Memory block size: 128MB [ 0.139477] gcov: version magic: 0x41383552 [ 0.142247] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.146067] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.148319] pinctrl core: initialized pinctrl subsystem [ 0.150143] [ 0.150785] ************************************************************* [ 0.153009] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.156010] ** ** [ 0.158012] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.161011] ** ** [ 0.163010] ** This means that this kernel is built to expose internal ** [ 0.165048] ** IOMMU data structures, which may compromise security on ** [ 0.168010] ** your system. ** [ 0.172010] ** ** [ 0.174011] ** If you see this message and you are not debugging the ** [ 0.176009] ** kernel, report this immediately to your vendor! ** [ 0.178010] ** ** [ 0.180020] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.183077] ************************************************************* [ 0.186684] NET: Registered protocol family 16 [ 0.188495] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.190112] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.193061] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.198124] cpuidle: using governor menu [ 0.200036] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.201414] PCI: Using configuration type 1 for base access [ 0.202000] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.213072] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.216149] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.222028] cryptd: max_cpu_qlen set to 1000 [ 0.224237] ACPI: Added _OSI(Module Device) [ 0.226022] ACPI: Added _OSI(Processor Device) [ 0.227011] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.229015] ACPI: Added _OSI(Processor Aggregator Device) [ 0.235320] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.242009] ACPI: Interpreter enabled [ 0.243119] ACPI: PM: (supports S0 S3 S4 S5) [ 0.246037] ACPI: Using IOAPIC for interrupt routing [ 0.249077] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.250611] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.264846] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.266100] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.268056] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.272232] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.276797] acpiphp: Slot [2] registered [ 0.278134] acpiphp: Slot [5] registered [ 0.279143] acpiphp: Slot [6] registered [ 0.281145] acpiphp: Slot [3] registered [ 0.282076] acpiphp: Slot [4] registered [ 0.283091] acpiphp: Slot [7] registered [ 0.284114] acpiphp: Slot [8] registered [ 0.286108] acpiphp: Slot [9] registered [ 0.287144] acpiphp: Slot [10] registered [ 0.289070] acpiphp: Slot [11] registered [ 0.290070] acpiphp: Slot [12] registered [ 0.291066] acpiphp: Slot [13] registered [ 0.292000] acpiphp: Slot [14] registered [ 0.292000] acpiphp: Slot [15] registered [ 0.292096] acpiphp: Slot [16] registered [ 0.293000] acpiphp: Slot [17] registered [ 0.294083] acpiphp: Slot [18] registered [ 0.295106] acpiphp: Slot [19] registered [ 0.297074] acpiphp: Slot [20] registered [ 0.298054] acpiphp: Slot [21] registered [ 0.299089] acpiphp: Slot [22] registered [ 0.300060] acpiphp: Slot [23] registered [ 0.301075] acpiphp: Slot [24] registered [ 0.302193] acpiphp: Slot [25] registered [ 0.304083] acpiphp: Slot [26] registered [ 0.305134] acpiphp: Slot [27] registered [ 0.307118] acpiphp: Slot [28] registered [ 0.308083] acpiphp: Slot [29] registered [ 0.310082] acpiphp: Slot [30] registered [ 0.311163] acpiphp: Slot [31] registered [ 0.313093] PCI host bridge to bus 0000:00 [ 0.314018] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.316022] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.318022] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.320023] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.323047] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.326027] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.328244] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.331165] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.335591] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.342912] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.347071] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.351029] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.353027] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.356025] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.358663] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.360785] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.363043] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.366008] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.371016] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.382753] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.387070] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.393510] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.403023] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.409073] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.433014] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.443383] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.455014] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.461013] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.486015] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.505629] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.509673] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.512540] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.515406] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.519452] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.527113] iommu: Default domain type: Passthrough [ 0.532567] SCSI subsystem initialized [ 0.534151] ACPI: bus type USB registered [ 0.536120] usbcore: registered new interface driver usbfs [ 0.538086] usbcore: registered new interface driver hub [ 0.540061] usbcore: registered new device driver usb [ 0.542209] pps_core: LinuxPPS API ver. 1 registered [ 0.545009] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.548056] PTP clock support registered [ 0.550374] EDAC MC: Ver: 3.0.0 [ 0.552890] PCI: Using ACPI for IRQ routing [ 0.553647] NetLabel: Initializing [ 0.555007] NetLabel: domain hash size = 128 [ 0.560010] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.562112] NetLabel: unlabeled traffic allowed by default [ 0.564276] vgaarb: loaded [ 0.565360] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.567009] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.574822] clocksource: Switched to clocksource kvm-clock [ 0.710636] VFS: Disk quotas dquot_6.6.0 [ 0.715983] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.723763] *** VALIDATE ramfs *** [ 0.727287] *** VALIDATE hugetlbfs *** [ 0.730291] pnp: PnP ACPI init [ 0.733514] pnp: PnP ACPI: found 6 devices [ 0.753308] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.756496] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.758453] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.760585] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.763269] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.765982] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.768506] NET: Registered protocol family 2 [ 0.770908] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.775414] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.779090] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.784422] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.787877] TCP: Hash tables configured (established 65536 bind 65536) [ 0.790870] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.793951] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.796762] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.799405] NET: Registered protocol family 1 [ 0.802094] RPC: Registered named UNIX socket transport module. [ 0.804212] RPC: Registered udp transport module. [ 0.805951] RPC: Registered tcp transport module. [ 0.807797] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.810258] NET: Registered protocol family 44 [ 0.811995] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.813980] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.815903] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.818102] PCI: CLS 0 bytes, default 64 [ 0.820461] Unpacking initramfs... [ 2.552175] debug: unmapping init [mem 0xffffa0b47cc64000-0xffffa0b47ffcffff] [ 2.556969] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.559216] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.562242] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x22982c12b6d, max_idle_ns: 440795281273 ns [ 3.155927] Initialise system trusted keyrings [ 3.157655] Key type blacklist registered [ 3.159562] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 3.168570] zbud: loaded [ 3.171495] *** VALIDATE nfs *** [ 3.172523] *** VALIDATE nfs4 *** [ 3.174143] pstore: using deflate compression [ 3.177905] Platform Keyring initialized [ 3.297623] NET: Registered protocol family 38 [ 3.299553] Key type asymmetric registered [ 3.301210] Asymmetric key parser 'x509' registered [ 3.303374] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 3.306698] io scheduler mq-deadline registered [ 3.308425] io scheduler kyber registered [ 3.313900] io scheduler bfq registered [ 3.315924] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 3.319465] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 3.325815] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 3.330386] ACPI: Power Button [PWRF] [ 3.337203] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.345616] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.370150] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.410478] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.447593] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.467553] Non-volatile memory driver v1.3 [ 3.469433] Linux agpgart interface v0.103 [ 3.512628] virtio_blk virtio1: [vda] 134864 512-byte logical blocks (69.1 MB/65.9 MiB) [ 3.516676] vda: detected capacity change from 0 to 69050368 [ 3.532772] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.535696] vdb: detected capacity change from 0 to 1073741824 [ 3.542247] libphy: Fixed MDIO Bus: probed [ 3.548970] usbcore: registered new interface driver usbserial_generic [ 3.551695] usbserial: USB Serial support registered for generic [ 3.553920] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.559139] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.560846] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.563569] mousedev: PS/2 mouse device common for all mice [ 3.567043] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.569175] rtc_cmos 00:05: RTC can wake from S4 [ 3.577268] rtc_cmos 00:05: registered as rtc0 [ 3.579767] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.583647] intel_pstate: CPU model not supported [ 3.587149] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.590555] hid: raw HID events driver (C) Jiri Kosina [ 3.594384] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.596674] usbcore: registered new interface driver usbhid [ 3.602784] usbhid: USB HID core driver [ 3.604647] drop_monitor: Initializing network drop monitor service [ 3.607428] Initializing XFRM netlink socket [ 3.609573] NET: Registered protocol family 10 [ 3.612548] Segment Routing with IPv6 [ 3.614237] NET: Registered protocol family 17 [ 3.616205] mpls_gso: MPLS GSO support [ 3.621942] RAS: Correctable Errors collector initialized. [ 3.624791] AVX version of gcm_enc/dec engaged. [ 3.626676] AES CTR mode by8 optimization enabled [ 3.724547] sched_clock: Marking stable (3724415469, 0)->(4592301468, -867885999) [ 3.731182] registered taskstats version 1 [ 3.733815] Loading compiled-in X.509 certificates [ 3.736937] zswap: loaded using pool lzo/zbud [ 3.763800] Key type big_key registered [ 3.781239] Key type encrypted registered [ 3.782788] ima: No TPM chip found, activating TPM-bypass! [ 3.785292] ima: Allocated hash algorithm: sha1 [ 3.787226] ima: No architecture policies found [ 3.789231] evm: Initialising EVM extended attributes: [ 3.791275] evm: security.selinux [ 3.792713] evm: security.ima [ 3.794178] evm: security.capability [ 3.795864] evm: HMAC attrs: 0x1 [ 3.798473] rtc_cmos 00:05: setting system clock to 2026-06-01 15:12:46 UTC (1780326766) [ 3.806652] debug: unmapping init [mem 0xffffffffa7a03000-0xffffffffa7bfffff] [ 3.810265] debug: unmapping init [mem 0xffffffffa6782000-0xffffffffa6a58fff] [ 3.817070] Write protecting the kernel read-only data: 28672k [ 3.820643] debug: unmapping init [mem 0xffffffffa4e03000-0xffffffffa4ffffff] [ 3.823301] debug: unmapping init [mem 0xffffffffa5714000-0xffffffffa57fffff] [ 3.858230] 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.868662] systemd[1]: Detected virtualization kvm. [ 3.870541] systemd[1]: Detected architecture x86-64. [ 3.872386] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.900991] systemd[1]: No hostname configured. [ 3.902508] systemd[1]: Set hostname to . [ 3.904617] random: systemd: uninitialized urandom read (16 bytes read) [ 3.907073] systemd[1]: Initializing machine ID from random generator. [ 4.008972] random: ln: uninitialized urandom read (6 bytes read) [ 4.112953] random: systemd: uninitialized urandom read (16 bytes read) [ 4.115677] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ 4.120374] systemd[1]: Reached target Timers. [ OK ] Reached target Timers. [ 4.123488] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. [ OK ] Reached target Slices. [ OK ] Listening on udev Control Socket. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on Journal Socket. [ OK ] Started Memstrack Anylazing Service. Starting Apply Kernel Variables... [ OK ] Reached target Paths. [ OK ] Listening on Journal Socket (/dev/log). Starting Journal Service... [ OK ] Reached target Sockets. Starting Create list of required st…ce nodes for the current kernel... Starting Create Volatile Files and Directories... [ OK ] Reached target Initrd Root Device. Starting Setup Virtual Console... [ OK ] Started Apply Kernel Variables. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Setup Virtual Console. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.864801] device-mapper: uevent: version 1.0.3 [ 4.867163] 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.689668] virtio_net virtio0 ens2: renamed from eth0 [ 5.785809] scsi host0: ata_piix [ 5.888178] scsi host1: ata_piix [ 5.891127] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.900457] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 10.194346] random: fast init done [ 10.301296] random: crng init done [ 10.303065] random: 7 urandom warning(s) missed due to ratelimiting [ 12.209898] dracut-initqueue[579]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 13.678990] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped target Timers. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Remote File Systems. [ 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 Slices. [ OK ] Stopped target Sockets. [ OK ] Stopped target Paths. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped target Swap. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 15.499715] printk: systemd: 26 output lines suppressed due to ratelimiting [ 15.925294] SELinux: Disabled at runtime. [ 16.021721] 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) [ 16.031980] systemd[1]: Detected virtualization kvm. [ 16.033762] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 17.091437] systemd[1]: initrd-switch-root.service: Succeeded. [ 17.095623] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 17.102268] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 17.106753] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 17.110283] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 17.140434] systemd[1]: Starting Journal Service... Starting Journal Service... [ 17.211993] systemd[1]: Mounting Huge Pages File System... Mounting Huge Pages File System... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on initctl Compatibility Named Pipe. Starting Remount Root and Kernel File Systems... [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Listening on udev Kernel Socket. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd Root 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. [ 17.424666] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Listening on Process Core Dump Socket. Mounting POSIX Message Queue File System... [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target rpc_pipefs.target. Mounting Kernel Debug File System... [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Reached target RPC Port Mapper. Starting Apply Kernel Variables... [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Stopped target Initrd File Systems. [ OK ] Created slice system-getty.slice. [ OK ] Started Journal Service. [ OK ] Mounted Huge Pages File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Mounted Kernel Debug File System. [ OK ] Started Apply Kernel Variables. [ OK ] Reached target Swap. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started udev Coldplug all Devices. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... Starting udev Kernel Device Manager... [ OK ] Mounted /mnt. [ 18.199522] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 19.125356] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 19.207970] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 19.358539] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 19.416808] EDAC sbridge: Ver: 1.1.2 [ 21.714490] Key type dns_resolver registered [ 22.146431] NFS: Registering the id_resolver key type [ 22.148785] Key type id_resolver registered [ 22.150396] Key type id_legacy registered [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Mark the need to relabel after reboot... Starting Rebuild Dynamic Linker Cache... Starting Create Volatile Files and Directories... Starting Load/Save Random Seed... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Load/Save Random Seed. 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 dnf makecache --timer. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. Starting Restore /run/initramfs on shutdown... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Started irqbalance daemon. [ OK ] Reached target sshd-keygen.target. Starting Login Service... [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting OpenSSH server daemon... Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... Starting Network Manager Wait Online... [ OK ] Started OpenSSH server daemon. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Started Login Service. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... Starting Hostname Service... [ OK ] Started Permit User Sessions. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Getty on tty1. [ OK ] Reached target Login Prompts. [ OK ] Started Command Scheduler. [ 26.361795] hrtimer: interrupt took 3883907 ns [ 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 Crash recovery kernel arming... Starting Notify NFS peers of a restart... Starting System Logging Service... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg434-client login: [ 53.409599] libcfs: loading out-of-tree module taints kernel. [ 53.492547] Key type ._llcrypt registered [ 53.502070] Key type .llcrypt registered [ 53.844435] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 53.852752] alg: No test for adler32 (adler32-zlib) [ 55.081962] Lustre: Lustre: Build Version: 2.17.53_33_g3f95abe [ 55.679332] LNet: Added LNI 192.168.204.34@tcp [8/256/0/180] [ 57.392095] Key type lgssc registered [ 58.842596] Lustre: Echo OBD driver; http://www.lustre.org/ [ 147.319050] Lustre: Mounted lustre-client [ 150.390136] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 156.373237] Lustre: DEBUG MARKER: oleg434-client.virtnet: executing check_logdir /tmp/testlogs/ [ 159.024876] Lustre: DEBUG MARKER: oleg434-client.virtnet: executing yml_node [ 161.668370] Lustre: DEBUG MARKER: Client: 2.17.53.33 [ 163.171456] Lustre: DEBUG MARKER: MDS: 2.17.53.33 [ 164.755780] Lustre: DEBUG MARKER: OSS: 2.17.53.33 [ 165.796045] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Mon Jun 1 11:15:27 EDT 2026 [ 173.029229] Lustre: lustre-OST0000-osc-ffffa0b4c3d43000: disconnect after 24s idle [ 175.839545] Lustre: DEBUG MARKER: excepting tests: 27 40a 102 [ 176.655624] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 177.536426] Lustre: DEBUG MARKER: === sanityn: start setup 11:15:39 (1780326939) === [ 178.046250] Lustre: Mounted lustre-client [ 180.100374] Lustre: DEBUG MARKER: oleg434-client.virtnet: executing check_config_client /mnt/lustre [ 191.034592] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 197.132356] Lustre: DEBUG MARKER: === sanityn: finish setup 11:15:59 (1780326959) === [ 198.325780] Lustre: DEBUG MARKER: == sanityn test 0: do_node_vp() and do_facet_vp() do the right thing ========================================================== 11:16:00 (1780326960) [ 198.623225] Lustre: lustre-OST0000-osc-ffffa0b4cc0f2000: disconnect after 21s idle [ 198.626186] Lustre: Skipped 1 previous similar message [ 202.987312] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 11:16:04 (1780326964) [ 206.563528] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 11:16:08 (1780326968) [ 209.777607] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 11:16:11 (1780326971) [ 213.157223] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 11:16:15 (1780326975) [ 216.261374] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 11:16:18 (1780326978) [ 219.195894] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 11:16:21 (1780326981) [ 222.880645] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 11:16:24 (1780326984) [ 223.721773] Lustre: DEBUG MARKER: SKIP: sanityn test_2f needs >= 2 MDTs [ 224.786385] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 11:16:26 (1780326986) [ 228.686909] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 11:16:30 (1780326990) [ 232.338368] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 11:16:34 (1780326994) [ 236.582536] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 11:16:38 (1780326998) [ 239.985907] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 11:16:41 (1780327001) [ 243.234138] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 11:16:45 (1780327005) [ 246.854934] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 11:16:48 (1780327008) [ 251.138482] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 11:16:52 (1780327012) [ 255.424290] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 11:16:57 (1780327017) [ 259.104687] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 11:17:01 (1780327021) [ 263.312173] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 11:17:05 (1780327025) [ 266.976519] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 11:17:08 (1780327028) [ 270.872098] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 11:17:12 (1780327032) [ 271.170736] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=144115205272502304 file: /mnt/lustre/lockdir/lockfile=144115205272502302 [ 426.534592] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 11:19:48 (1780327188) [ 433.604887] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 11:19:55 (1780327195) [ 438.552053] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 11:20:00 (1780327200) [ 443.504497] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 11:20:05 (1780327205) [ 448.462961] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 11:20:10 (1780327210) [ 453.796499] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 11:20:15 (1780327215) [ 455.041846] Lustre: DEBUG MARKER: chmod [ 459.936579] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 11:20:21 (1780327221) [ 489.842632] Lustre: DEBUG MARKER: SKIP: oos2 test_15 oos2.sh: 7531520KB free > 800000KB, increase MAXFREE (or reduce fs size) [ 502.033740] Lustre: DEBUG MARKER: == sanityn test 16a: 500 iterations of dual-mount fsx ==== 11:21:03 (1780327263) [ 536.442378] Lustre: DEBUG MARKER: == sanityn test 16b: 500 iterations of dual-mount fsx at small size ========================================================== 11:21:38 (1780327298) [ 555.213259] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 11:21:57 (1780327317) [ 556.561632] Lustre: DEBUG MARKER: SKIP: sanityn test_16c dio on ldiskfs only [ 557.550895] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 11:21:59 (1780327319) [ 582.977234] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 11:22:24 (1780327344) [ 586.671183] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 11:22:28 (1780327348) [ 587.244983] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 587.291773] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 587.329130] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 587.364540] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 587.397677] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 587.440620] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 587.472089] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 587.503274] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 587.543690] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 587.580643] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 587.615695] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 587.660594] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 587.702839] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 587.738547] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 587.779554] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 587.815308] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 587.855277] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 587.895683] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 587.942988] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 587.990383] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 588.035610] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 588.081689] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 588.116077] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 588.148971] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 588.193621] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 588.227563] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 588.271710] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 588.313249] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 588.352844] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 588.385898] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 588.434544] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 588.475295] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 588.520979] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 588.550403] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 588.599235] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 588.648523] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 588.694030] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 588.735092] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 588.788928] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 588.842901] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 588.879951] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 588.920892] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 588.961145] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 588.998436] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 589.033674] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 589.081634] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 589.118949] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 589.158446] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 589.198819] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 589.230901] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 589.278140] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 589.314951] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 589.353066] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 589.399808] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 589.442224] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 589.479654] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 589.522876] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 589.570456] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 589.618121] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 589.650148] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 589.682465] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 589.726277] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 589.763301] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 589.807716] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 589.849215] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 589.890860] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 589.932703] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 589.969975] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 590.000608] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 590.030937] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 590.065275] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 590.095625] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 590.124289] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 590.156573] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 590.197845] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 590.235033] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 590.285838] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 590.342460] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 590.380531] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 590.415213] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 590.461250] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 590.499535] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 590.548239] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 590.594932] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 590.636644] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 590.674498] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 590.709153] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 590.747073] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 590.774296] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 590.801739] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 590.829040] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 590.857630] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 590.889884] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 590.925437] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 590.956742] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 590.989622] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 591.027239] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 591.061859] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 591.090914] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 591.129317] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 591.175534] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 591.208495] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 591.253083] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 591.292632] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 591.321328] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 591.355447] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 591.384820] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 591.419754] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 591.465238] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 591.509602] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 591.559232] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 591.600050] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 591.647765] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 591.696045] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 591.753503] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 591.799572] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 591.834199] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 591.874163] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 591.909596] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 591.957193] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 591.999158] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 592.029630] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 592.063827] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 592.100651] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 592.141799] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 592.182983] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 592.220978] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 592.279721] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 592.318635] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 592.357813] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 592.391879] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 592.424635] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 592.460531] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 592.498222] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 592.536737] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 592.574600] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 592.632210] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 592.686619] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 592.718626] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 592.752202] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 592.797735] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 592.837872] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 592.870381] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 592.910566] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 592.944758] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 592.991473] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 593.038116] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 593.088107] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 593.140540] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 593.184400] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 593.231121] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 593.270039] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 593.311686] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 593.352725] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 593.394197] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 593.446182] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 593.502629] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 593.559554] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 593.604177] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 593.669409] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 593.732829] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 593.794463] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 593.857225] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 593.920067] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 593.957483] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 593.996920] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 594.034225] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 594.079080] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 594.122196] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 594.170670] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 594.216213] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 594.258251] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 594.309834] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 594.356853] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 594.394434] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 594.443409] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 594.489217] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 594.548300] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 594.599665] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 594.665871] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 594.727956] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 594.785214] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 594.842436] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 594.889911] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 594.951716] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 595.003478] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 595.056057] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 595.113145] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 595.167467] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 595.210733] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 595.255616] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 595.297403] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 595.348900] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 595.398339] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 595.450216] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 595.510691] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 595.558037] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 595.618711] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 595.679174] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 595.723523] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 595.765372] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 595.821745] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 595.863746] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 595.907804] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 595.959972] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 596.010335] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 596.064216] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 596.098508] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 596.135890] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 596.181516] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 596.225381] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 596.276890] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 596.322158] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 596.362825] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 596.414868] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 596.447747] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 596.489174] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 596.524464] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 596.566883] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 596.615369] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 596.661975] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 596.701844] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 596.745850] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 596.791511] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 596.831784] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 596.871101] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 596.904150] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 596.941836] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 596.988105] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 597.035543] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 597.083729] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 597.128783] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 597.178830] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 597.227887] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 597.273691] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 597.321639] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 597.376146] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 597.428824] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 597.469553] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 597.504974] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 597.544543] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 597.577801] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 597.614898] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 597.650908] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 597.687911] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 597.730181] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 597.781144] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 597.832411] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 597.879922] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 597.929431] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 597.978282] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 598.018236] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 598.052156] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 598.091096] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 598.129654] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 598.168406] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 598.204792] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 598.241315] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 598.299551] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 598.339324] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 598.378046] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 598.418186] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 598.471423] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 598.519552] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 598.571885] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 598.623861] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 598.670179] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 598.716125] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 598.761651] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 598.799403] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 598.845489] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 598.886553] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 598.924789] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 598.961313] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 599.008562] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 599.048081] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 599.099151] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 599.141199] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 599.175352] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 599.222716] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 599.274579] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 599.329291] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 599.365060] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 599.400601] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 599.445312] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 599.485173] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 599.525815] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 599.565493] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 599.615643] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 599.656742] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 599.700247] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 599.749353] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 599.791299] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 599.832593] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 599.878549] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 599.921988] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 599.956525] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 599.988952] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 600.032937] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 600.077400] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 600.118660] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 600.151930] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 600.189851] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 600.225426] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 600.273530] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 600.313775] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 600.354456] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 600.398594] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 600.438361] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 600.494282] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 600.548210] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 600.585554] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 600.634655] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 600.689779] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 600.730539] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 600.769363] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 600.811569] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 600.849877] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 600.890102] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 600.928413] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 600.978915] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 601.019378] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 601.056925] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 601.099678] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 601.150657] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 601.196460] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 601.242050] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 601.279229] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 601.320283] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 601.363430] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 601.410792] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 601.452575] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 601.506107] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 601.557338] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 601.602498] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 601.649956] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 601.701395] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 601.753228] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 601.788944] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 601.838658] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 601.884644] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 601.942681] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 601.990296] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 602.026246] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 602.071561] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 602.125301] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 602.184639] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 602.256721] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 602.315760] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 602.365438] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 602.420480] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 602.477891] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 602.529637] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 602.604310] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 602.664142] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 602.739841] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 602.785992] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 602.823129] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 602.861649] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 602.918553] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 602.980416] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 603.024815] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 603.083691] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 603.127724] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 603.213665] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 603.271334] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 603.357659] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 603.421839] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 603.480956] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 603.521264] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 603.581558] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 603.632940] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 603.699588] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 603.763140] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 603.821709] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 603.876957] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 603.934934] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 604.009959] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 604.100932] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 604.157786] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 604.223446] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 604.276588] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 604.324297] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 604.366939] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 604.424747] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 604.475652] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 604.526302] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 604.568347] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 604.635943] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 604.683341] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 604.726827] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 604.782530] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 604.824797] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 604.870768] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 604.947979] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 605.010258] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 605.060339] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 605.110349] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 605.153195] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 605.202674] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 605.245992] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 605.292849] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 605.328602] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 605.367502] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 605.404380] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 605.452493] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 605.509304] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 605.574255] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 605.631583] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 605.675566] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 605.733117] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 605.790385] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 605.834337] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 605.881049] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 605.934974] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 605.989937] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 606.032035] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 606.074042] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 606.126485] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 606.165918] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 606.212948] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 606.263287] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 606.311166] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 606.341641] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 606.385324] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 606.439120] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 606.481175] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 606.561210] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 606.616637] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 606.667262] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 606.750986] rw_seq_cst_vs_d (29541): drop_caches: 3 [ 608.224441] Lustre: lustre-OST0000-osc-ffffa0b4cc0f2000: disconnect after 24s idle [ 608.231442] Lustre: Skipped 1 previous similar message [ 611.692380] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 11:22:53 (1780327373) [ 612.039381] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 612.114339] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 612.230274] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 612.335483] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 612.435055] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 612.513063] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 612.539339] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 612.588360] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 612.618450] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 612.649943] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 612.685333] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 612.744397] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 612.798190] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 613.011622] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 613.058904] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 613.108056] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 613.202512] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 613.366226] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 613.444123] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 613.473984] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 613.581929] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 613.724979] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 613.841821] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 613.879288] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 613.918157] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 614.091720] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 614.129829] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 614.152982] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 614.240071] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 614.450173] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 614.475811] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 614.528474] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 614.571161] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 614.636385] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 614.668898] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 614.713466] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 614.838987] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 614.962534] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 615.033239] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 615.212706] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 615.240423] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 615.542533] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 615.707144] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 615.753877] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 615.785857] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 615.828944] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 615.862489] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 615.891210] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 615.929174] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 615.975021] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 616.008236] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 616.116787] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 616.159273] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 616.208486] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 616.279272] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 616.306623] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 616.444292] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 616.491541] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 616.516511] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 616.541651] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 616.567047] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 616.684876] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 616.711126] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 616.759842] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 616.786489] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 617.239151] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 617.300888] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 617.326745] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 617.362267] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 617.393333] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 617.491662] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 617.588904] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 617.675880] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 617.701614] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 617.753446] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 617.814461] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 617.849147] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 617.932239] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 617.965767] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 617.990499] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 618.024819] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 618.072101] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 618.179604] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 618.251478] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 618.299136] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 618.347203] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 618.382499] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 618.427215] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 618.455973] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 618.549433] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 618.690177] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 618.721275] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 618.749451] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 619.203499] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 619.226973] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 619.400196] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 619.446353] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 619.672336] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 619.729734] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 619.960696] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 619.993211] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 620.216710] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 620.302095] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 620.380136] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 620.408823] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 620.429657] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 620.453477] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 620.540532] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 620.580822] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 620.615377] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 620.645107] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 620.724985] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 620.812497] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 621.014913] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 621.104223] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 621.212142] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 621.414476] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 621.438750] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 621.494124] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 621.570495] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 621.623879] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 621.660591] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 621.712987] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 621.790449] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 621.816666] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 621.846419] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 621.951247] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 622.044093] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 622.070196] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 622.096730] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 622.127384] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 622.336370] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 622.418934] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 622.453724] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 622.493732] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 622.625293] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 622.659987] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 622.685184] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 622.743753] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 622.773831] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 623.078204] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 623.102277] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 623.128732] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 623.479151] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 623.522700] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 623.602191] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 623.640669] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 624.041068] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 624.070285] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 624.169456] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 624.205942] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 624.230928] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 624.280995] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 624.334575] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 624.362600] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 624.464900] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 624.887322] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 624.914720] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 624.944069] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 624.969270] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 625.066219] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 625.106605] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 625.143097] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 625.185144] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 625.216429] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 625.242724] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 625.391946] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 625.414946] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 625.471391] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 625.577793] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 625.598930] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 625.674764] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 625.846480] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 626.126818] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 626.159875] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 626.359187] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 626.520760] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 626.589972] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 626.627411] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 626.654218] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 626.700140] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 626.761702] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 626.947163] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 626.985642] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 627.011440] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 627.594825] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 627.646461] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 627.689524] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 627.738037] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 627.771411] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 628.124474] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 628.162814] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 628.330211] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 628.379905] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 628.591541] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 628.696758] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 628.703225] Lustre: lustre-OST0001-osc-ffffa0b4c3d43000: disconnect after 20s idle [ 628.733316] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 628.753433] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 628.784914] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 628.887900] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 628.936391] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 628.967892] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 629.080835] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 629.114384] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 629.196695] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 629.327435] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 629.364223] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 629.580910] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 629.627348] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 629.660326] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 629.697311] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 629.735626] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 629.836484] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 630.003592] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 630.143377] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 630.196742] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 630.235453] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 630.258292] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 630.308145] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 630.650969] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 630.682857] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 630.710529] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 630.744430] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 630.928799] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 630.955316] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 631.092755] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 631.145526] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 631.172891] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 631.420549] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 631.468492] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 631.498975] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 631.634039] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 631.663371] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 631.694398] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 631.751600] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 631.778891] rw_seq_cst_vs_d (30117): drop_caches: 3 [ 635.896527] Lustre: DEBUG MARKER: == sanityn test 16h: mmap read after truncate file ======= 11:23:17 (1780327397) [ 640.038830] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 11:23:21 (1780327401) [ 644.140556] Lustre: DEBUG MARKER: == sanityn test 16j: race dio with buffered i/o ========== 11:23:25 (1780327405) [ 663.598536] Lustre: DEBUG MARKER: == sanityn test 16k: Parallel FSX and drop caches should not panic ========================================================== 11:23:45 (1780327425) [ 663.784067] bash (32558): drop_caches: 3 [ 666.940120] bash (32558): drop_caches: 3 [ 670.287528] bash (32558): drop_caches: 3 [ 673.438790] bash (32558): drop_caches: 3 [ 676.515304] bash (32558): drop_caches: 3 [ 679.582866] bash (32558): drop_caches: 3 [ 682.675278] bash (32558): drop_caches: 3 [ 685.771198] bash (32558): drop_caches: 3 [ 689.872110] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 11:24:11 (1780327451) [ 696.317065] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 11:24:18 (1780327458) [ 720.787501] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 11:24:42 (1780327482) [ 722.358582] Lustre: DEBUG MARKER: SKIP: sanityn test_19 not cache-capable obdfilter [ 723.496063] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 11:24:45 (1780327485) [ 727.787545] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 11:24:49 (1780327489) [ 731.902181] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 11:24:53 (1780327493) [ 736.223284] Lustre: lustre-OST0000-osc-ffffa0b4cc0f2000: disconnect after 21s idle [ 736.226620] Lustre: Skipped 1 previous similar message [ 746.463260] Lustre: lustre-OST0001-osc-ffffa0b4c3d43000: disconnect after 23s idle [ 797.596536] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 11:25:59 (1780327559) [ 801.839657] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 11:26:03 (1780327563) [ 805.738116] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 11:26:07 (1780327567) [ 810.172925] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 11:26:11 (1780327571) [ 811.105760] Lustre: DEBUG MARKER: SKIP: sanityn test_25b needs >= 2 MDTs [ 812.099620] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 11:26:13 (1780327573) [ 817.012848] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 11:26:18 (1780327578) [ 823.256888] Lustre: DEBUG MARKER: == sanityn test 26c: set-in-past on open file is not lost on close ========================================================== 11:26:25 (1780327585) [ 828.365306] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 829.421954] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 11:26:31 (1780327591) [ 835.350000] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 11:26:37 (1780327597) [ 835.619305] Lustre: *** cfs_fail_loc=314, val=0*** [ 836.639253] Lustre: *** cfs_fail_loc=314, val=0*** [ 836.642662] Lustre: Skipped 2 previous similar messages [ 840.541815] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 11:26:42 (1780327602) [ 852.476284] Lustre: *** cfs_fail_loc=314, val=0*** [ 853.987814] Lustre: lustre-OST0000-osc-ffffa0b4cc0f2000: Connection to lustre-OST0000 (at 192.168.204.134@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 854.003246] LustreError: lustre-OST0000-osc-ffffa0b4cc0f2000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 854.015919] Lustre: lustre-OST0000-osc-ffffa0b4cc0f2000: Connection restored to 192.168.204.134@tcp (at 192.168.204.134@tcp) [ 856.732502] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 11:26:58 (1780327618) [ 856.902240] LustreError: 42422:0:(file.c:822:ll_intent_file_open()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 859.927138] LustreError: 42422:0:(file.c:822:ll_intent_file_open()) cfs_fail_timeout id 1419 awake [ 863.538903] Lustre: DEBUG MARKER: == sanityn test 31s: open should not revalidate invalid dentry ========================================================== 11:27:05 (1780327625) [ 868.039602] Lustre: DEBUG MARKER: == sanityn test 31t: getattr should not revalidate invalid dentry ========================================================== 11:27:09 (1780327629) [ 872.539148] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 873.452518] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 11:27:15 (1780327635) [ 874.365443] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 875.334729] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-on-Lock-Cancel ========================================================== 11:27:17 (1780327637) [ 876.207607] Lustre: DEBUG MARKER: SKIP: sanityn test_33c needs >= 2 MDTs [ 877.191567] Lustre: DEBUG MARKER: == sanityn test 33d: dependent transactions should trigger COS ========================================================== 11:27:19 (1780327639) [ 877.967643] Lustre: DEBUG MARKER: SKIP: sanityn test_33d needs >= 2 MDTs [ 878.872264] Lustre: DEBUG MARKER: == sanityn test 33e: independent transactions shouldn't trigger COS ========================================================== 11:27:20 (1780327640) [ 879.731627] Lustre: DEBUG MARKER: SKIP: sanityn test_33e needs >= 2 MDTs [ 880.574664] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 11:27:22 (1780327642) [ 930.788263] Lustre: lustre-OST0000-osc-ffffa0b4cc0f2000: Connection to lustre-OST0000 (at 192.168.204.134@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 930.804223] LustreError: lustre-OST0000-osc-ffffa0b4c3d43000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 930.821430] LustreError: lustre-OST0000-osc-ffffa0b4cc0f2000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 930.822054] Lustre: lustre-OST0000-osc-ffffa0b4c3d43000: Connection restored to 192.168.204.134@tcp (at 192.168.204.134@tcp) [ 930.847652] Lustre: Skipped 1 previous similar message [ 950.201820] Lustre: lustre-OST0001-osc-ffffa0b4c3d43000: Connection to lustre-OST0001 (at 192.168.204.134@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 950.213033] Lustre: Skipped 1 previous similar message [ 950.223417] LustreError: lustre-OST0001-osc-ffffa0b4c3d43000: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 950.231062] Lustre: lustre-OST0001-osc-ffffa0b4c3d43000: Connection restored to 192.168.204.134@tcp (at 192.168.204.134@tcp) [ 951.264346] Lustre: lustre-OST0000-osc-ffffa0b4c3d43000: disconnect after 20s idle [ 951.271451] Lustre: Skipped 2 previous similar messages [ 963.453435] Lustre: DEBUG MARKER: oleg434-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffffa0b4c3d43000.ost_server_uuid 50 [ 964.244374] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffffa0b4c3d43000.ost_server_uuid in IDLE state after 0 sec [ 966.053815] Lustre: DEBUG MARKER: oleg434-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffffa0b4c3d43000.ost_server_uuid 50 [ 966.813583] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffffa0b4c3d43000.ost_server_uuid in FULL state after 0 sec [ 969.427477] Lustre: DEBUG MARKER: oleg434-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffffa0b4c3d43000.ost_server_uuid 50 [ 970.400753] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffffa0b4c3d43000.ost_server_uuid in IDLE state after 0 sec [ 972.752310] Lustre: DEBUG MARKER: oleg434-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffffa0b4c3d43000.ost_server_uuid 50 [ 973.518863] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffffa0b4c3d43000.ost_server_uuid in IDLE state after 0 sec [ 978.523461] Lustre: DEBUG MARKER: oleg434-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffffa0b4c3d43000.ost_server_uuid 50 [ 979.469375] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffffa0b4c3d43000.ost_server_uuid in IDLE state after 0 sec [ 981.380376] Lustre: DEBUG MARKER: oleg434-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffffa0b4c3d43000.ost_server_uuid 50 [ 982.208825] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffffa0b4c3d43000.ost_server_uuid in IDLE state after 0 sec [ 983.078694] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 11:29:05 (1780327745) [ 984.581836] Lustre: DEBUG MARKER: Race attempt 0 [ 986.396698] Lustre: DEBUG MARKER: Wait for 49046 49147 for 60 sec... [ 1049.955335] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 11:30:11 (1780327811) [ 1055.890854] Lustre: DEBUG MARKER: start test - cycle (0) [ 1082.489371] Lustre: DEBUG MARKER: start test - cycle (1) [ 1084.384123] Lustre: lustre-OST0000-osc-ffffa0b4cc0f2000: disconnect after 22s idle [ 1084.401989] Lustre: Skipped 2 previous similar messages [ 1113.356372] Lustre: DEBUG MARKER: start test - cycle (2) [ 1144.689213] Lustre: DEBUG MARKER: start test - cycle (3) [ 1176.543359] Lustre: lustre-OST0000-osc-ffffa0b4c3d43000: disconnect after 20s idle [ 1176.554835] Lustre: Skipped 2 previous similar messages [ 1186.842349] Lustre: DEBUG MARKER: start test - cycle (4) [ 1214.769276] Lustre: DEBUG MARKER: start test - cycle (5) [ 1245.212867] Lustre: DEBUG MARKER: start test - cycle (6) [ 1275.273993] Lustre: DEBUG MARKER: start test - cycle (7) [ 1293.925392] Lustre: DEBUG MARKER: start test - cycle (8) [ 1320.093405] Lustre: DEBUG MARKER: start test - cycle (9) [ 1325.023682] Lustre: lustre-OST0000-osc-ffffa0b4cc0f2000: disconnect after 22s idle [ 1325.032429] Lustre: Skipped 7 previous similar messages [ 1346.888988] Lustre: DEBUG MARKER: start test - cycle (10) [ 1384.628083] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 11:35:45 (1780328145) [ 1499.447356] Lustre: DEBUG MARKER: == sanityn test 39a: file mtime does not change after rename ========================================================== 11:37:40 (1780328260) [ 1507.866596] Lustre: DEBUG MARKER: == sanityn test 39b: file mtime the same on clients with/out lock ========================================================== 11:37:49 (1780328269) [ 1517.719307] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 11:37:59 (1780328279) [ 1526.937348] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 11:38:07 (1780328287) [ 1527.388968] Lustre: *** cfs_fail_loc=411, val=0*** [ 1538.948633] Lustre: DEBUG MARKER: SKIP: sanityn test_40a skipping ALWAYS excluded test 40a [ 1541.334785] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 11:38:21 (1780328301) [ 1562.278601] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 11:38:43 (1780328323) [ 1580.547869] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 11:39:01 (1780328341) [ 1596.974751] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 11:39:18 (1780328358) [ 1611.000431] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 11:39:32 (1780328372) [ 1622.676448] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 11:39:44 (1780328384) [ 1633.163560] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 11:39:54 (1780328394) [ 1643.678792] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 11:40:05 (1780328405) [ 1653.991224] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 11:40:15 (1780328415) [ 1662.948671] Lustre: lustre-OST0000-osc-ffffa0b4c3d43000: disconnect after 22s idle [ 1662.956841] Lustre: Skipped 11 previous similar messages [ 1664.859471] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 11:40:26 (1780328426) [ 1675.863162] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 11:40:37 (1780328437) [ 1688.008603] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 11:40:49 (1780328449) [ 1701.530317] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 11:41:02 (1780328462) [ 2318.304143] Lustre: lustre-OST0000-osc-ffffa0b4cc0f2000: disconnect after 20s idle [ 2318.308029] Lustre: Skipped 3 previous similar messages [ 2642.139955] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 11:56:44 (1780329404) [ 2647.729401] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 11:56:49 (1780329409) [ 2653.390774] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 11:56:55 (1780329415) [ 2659.175648] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 11:57:01 (1780329421) [ 2664.748926] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 11:57:06 (1780329426) [ 2670.179836] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 11:57:12 (1780329432) [ 2675.583792] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 11:57:17 (1780329437) [ 2680.886562] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 11:57:23 (1780329443) [ 2686.413878] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 11:57:28 (1780329448) [ 2709.416497] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 11:57:51 (1780329471) [ 2715.165453] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 11:57:57 (1780329477) [ 2720.880896] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 11:58:03 (1780329483) [ 2726.672148] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 11:58:08 (1780329488) [ 2732.218902] Lustre: DEBUG MARKER: == sanityn test 43f: pdirops: unlink and rename (src) ======================================================================== 11:58:14 (1780329494) [ 2737.695344] Lustre: DEBUG MARKER: == sanityn test 43g: pdirops: unlink vs getattr ======================================================================== 11:58:19 (1780329499) [ 2743.254178] Lustre: DEBUG MARKER: == sanityn test 43h: pdirops: unlink vs readdir ======================================================================== 11:58:25 (1780329505) [ 2748.784571] Lustre: DEBUG MARKER: == sanityn test 43i: pdirops: unlink vs remote mkdir ===== 11:58:31 (1780329511) [ 2749.324513] Lustre: DEBUG MARKER: SKIP: sanityn test_43i needs >= 2 MDTs [ 2749.925390] Lustre: DEBUG MARKER: == sanityn test 43j: racy mkdir return EEXIST ======================================================================== 11:58:32 (1780329512) [ 2794.236644] Lustre: DEBUG MARKER: == sanityn test 43k: unlink vs create ==================== 11:59:16 (1780329556) [ 3040.228141] Lustre: lustre-OST0000-osc-ffffa0b4c3d43000: disconnect after 21s idle [ 3040.238788] Lustre: Skipped 5 previous similar messages [ 3683.937459] Lustre: DEBUG MARKER: == sanityn test 44a: pdirops: rename tgt vs mkdir ======================================================================== 12:14:06 (1780330446) [ 3689.584829] Lustre: DEBUG MARKER: == sanityn test 44b: pdirops: rename tgt vs create ======================================================================== 12:14:11 (1780330451) [ 3695.597228] Lustre: DEBUG MARKER: == sanityn test 44c: pdirops: rename tgt vs link ======================================================================== 12:14:17 (1780330457) [ 3698.655558] Lustre: lustre-OST0000-osc-ffffa0b4cc0f2000: disconnect after 23s idle [ 3701.490789] Lustre: DEBUG MARKER: == sanityn test 44d: pdirops: rename tgt vs unlink ======================================================================== 12:14:23 (1780330463) [ 3707.176713] Lustre: DEBUG MARKER: == sanityn test 44e: pdirops: rename tgt and rename (tgt) ======================================================================== 12:14:29 (1780330469) [ 3712.576691] Lustre: DEBUG MARKER: == sanityn test 44f: pdirops: rename tgt and rename (src) ======================================================================== 12:14:34 (1780330474) [ 3718.349441] Lustre: DEBUG MARKER: == sanityn test 44g: pdirops: rename tgt vs getattr ======================================================================== 12:14:40 (1780330480) [ 3723.968431] Lustre: DEBUG MARKER: == sanityn test 44h: pdirops: rename tgt vs readdir ======================================================================== 12:14:46 (1780330486) [ 3729.747855] Lustre: DEBUG MARKER: == sanityn test 44i: pdirops: rename tgt vs remote mkdir ========================================================== 12:14:51 (1780330491) [ 3730.339506] Lustre: DEBUG MARKER: SKIP: sanityn test_44i needs >= 2 MDTs [ 3730.974825] Lustre: DEBUG MARKER: == sanityn test 45a: rename,mkdir doesn't return -EEXIST ======================================================================== 12:14:53 (1780330493) [ 3765.403469] Lustre: DEBUG MARKER: == sanityn test 45b: pdirops: rename src vs create ======================================================================== 12:15:27 (1780330527) [ 3770.962275] Lustre: DEBUG MARKER: == sanityn test 45c: pdirops: rename src vs link ======================================================================== 12:15:33 (1780330533) [ 3776.377200] Lustre: DEBUG MARKER: == sanityn test 45d: pdirops: rename src vs unlink ======================================================================== 12:15:38 (1780330538) [ 3781.822936] Lustre: DEBUG MARKER: == sanityn test 45e: pdirops: rename src and rename (tgt) ======================================================================== 12:15:43 (1780330543) [ 3787.738027] Lustre: DEBUG MARKER: == sanityn test 45f: pdirops: rename src and rename (src) ======================================================================== 12:15:49 (1780330549) [ 3796.794489] Lustre: DEBUG MARKER: == sanityn test 45g: pdirops: rename src vs getattr ======================================================================== 12:15:58 (1780330558) [ 3809.355496] Lustre: DEBUG MARKER: == sanityn test 45h: pdirops: unlink vs readdir ======================================================================== 12:16:10 (1780330570) [ 3820.416282] Lustre: DEBUG MARKER: == sanityn test 45i: pdirops: rename src vs remote mkdir ========================================================== 12:16:21 (1780330581) [ 3821.457301] Lustre: DEBUG MARKER: SKIP: sanityn test_45i needs >= 2 MDTs [ 3822.938435] Lustre: DEBUG MARKER: == sanityn test 45j: read vs rename ====================== 12:16:24 (1780330584) [ 4497.392522] Lustre: lustre-OST0000-osc-ffffa0b4c3d43000: disconnect after 20s idle [ 4497.410328] Lustre: Skipped 9 previous similar messages [ 5084.778293] Lustre: DEBUG MARKER: == sanityn test 46a: pdirops: link vs mkdir ======================================================================== 12:37:26 (1780331846) [ 5096.766573] Lustre: DEBUG MARKER: == sanityn test 46b: pdirops: link vs create ======================================================================== 12:37:38 (1780331858) [ 5101.538575] Lustre: lustre-OST0000-osc-ffffa0b4cc0f2000: disconnect after 20s idle [ 5109.984971] Lustre: DEBUG MARKER: == sanityn test 46c: pdirops: link vs link ======================================================================== 12:37:51 (1780331871) [ 5122.898810] Lustre: DEBUG MARKER: == sanityn test 46d: pdirops: link vs unlink ======================================================================== 12:38:04 (1780331884) [ 5135.906600] Lustre: DEBUG MARKER: == sanityn test 46e: pdirops: link and rename (tgt) ======================================================================== 12:38:16 (1780331896) [ 5150.411969] Lustre: DEBUG MARKER: == sanityn test 46f: pdirops: link and rename (src) ======================================================================== 12:38:31 (1780331911) [ 5164.235932] Lustre: DEBUG MARKER: == sanityn test 46g: pdirops: link vs getattr ======================================================================== 12:38:45 (1780331925) [ 5176.711972] Lustre: DEBUG MARKER: == sanityn test 46h: pdirops: link vs readdir ======================================================================== 12:38:58 (1780331938) [ 5191.561584] Lustre: DEBUG MARKER: == sanityn test 46i: pdirops: link vs remote mkdir ======= 12:39:12 (1780331952) [ 5193.471492] Lustre: DEBUG MARKER: SKIP: sanityn test_46i needs >= 2 MDTs [ 5195.352943] Lustre: DEBUG MARKER: == sanityn test 47a: pdirops: remote mkdir vs mkdir ====== 12:39:16 (1780331956) [ 5196.901981] Lustre: DEBUG MARKER: SKIP: sanityn test_47a needs >= 2 MDTs [ 5198.852934] Lustre: DEBUG MARKER: == sanityn test 47b: pdirops: remote mkdir vs create ===== 12:39:20 (1780331960) [ 5200.164754] Lustre: DEBUG MARKER: SKIP: sanityn test_47b needs >= 2 MDTs [ 5201.768330] Lustre: DEBUG MARKER: == sanityn test 47c: pdirops: remote mkdir vs link ======= 12:39:23 (1780331963) [ 5203.064404] Lustre: DEBUG MARKER: SKIP: sanityn test_47c needs >= 2 MDTs [ 5204.609740] Lustre: DEBUG MARKER: == sanityn test 47d: pdirops: remote mkdir vs unlink ===== 12:39:26 (1780331966) [ 5205.907562] Lustre: DEBUG MARKER: SKIP: sanityn test_47d needs >= 2 MDTs [ 5207.209578] Lustre: DEBUG MARKER: == sanityn test 47e: pdirops: remote mkdir and rename (tgt) ========================================================== 12:39:28 (1780331968) [ 5208.568096] Lustre: DEBUG MARKER: SKIP: sanityn test_47e needs >= 2 MDTs [ 5209.944743] Lustre: DEBUG MARKER: == sanityn test 47f: pdirops: remote mkdir and rename (src) ========================================================== 12:39:31 (1780331971) [ 5211.274132] Lustre: DEBUG MARKER: SKIP: sanityn test_47f needs >= 2 MDTs [ 5212.908526] Lustre: DEBUG MARKER: == sanityn test 47g: pdirops: remote mkdir vs getattr ==== 12:39:34 (1780331974) [ 5214.491190] Lustre: DEBUG MARKER: SKIP: sanityn test_47g needs >= 2 MDTs [ 5216.339899] Lustre: DEBUG MARKER: == sanityn test 50: osc lvb attrs: enqueue vs. CP AST ======================================================================== 12:39:37 (1780331977) [ 5216.722332] LustreError: 5556:0:(ldlm_lockd.c:2079:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 sleeping for 2000ms [ 5218.815147] LustreError: 5556:0:(ldlm_lockd.c:2079:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 awake [ 5227.521624] Lustre: DEBUG MARKER: == sanityn test 51a: layout lock: refresh layout should work ========================================================== 12:39:48 (1780331988) [ 5236.663591] Lustre: DEBUG MARKER: == sanityn test 51b: layout lock: glimpse should be able to restart if layout changed ========================================================== 12:39:57 (1780331997) [ 5237.052279] LustreError: 217837:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 5241.127462] LustreError: 217837:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 awake [ 5241.159867] LustreError: 217837:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 5245.225113] LustreError: 217837:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 awake [ 5245.300328] LustreError: 217843:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 5249.375635] LustreError: 217843:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 awake [ 5255.407714] Lustre: DEBUG MARKER: == sanityn test 51c: layout lock: IT_LAYOUT blocked and correct layout can be returned ========================================================== 12:40:16 (1780332016) [ 5267.643690] Lustre: DEBUG MARKER: == sanityn test 51d: layout lock: losing layout lock should clean up memory map region ========================================================== 12:40:29 (1780332029) [ 5275.614229] Lustre: DEBUG MARKER: == sanityn test 51e: lfs getstripe does not break leases, part 2 ========================================================== 12:40:36 (1780332036) [ 5284.133581] Lustre: DEBUG MARKER: == sanityn test 54: rename locking ======================= 12:40:45 (1780332045) [ 5316.042837] Lustre: DEBUG MARKER: == sanityn test 55a: rename vs unlink target dir ========= 12:41:17 (1780332077) [ 5329.368426] Lustre: DEBUG MARKER: == sanityn test 55b: rename vs unlink source dir ========= 12:41:30 (1780332090) [ 5342.430384] Lustre: DEBUG MARKER: == sanityn test 55c: rename vs unlink orphan target dir == 12:41:43 (1780332103) [ 5359.438284] Lustre: DEBUG MARKER: == sanityn test 55d: rename file vs link ================= 12:42:01 (1780332121) [ 5373.722859] Lustre: DEBUG MARKER: == sanityn test 55e: rename race AB/BA under the same parent dir ========================================================== 12:42:15 (1780332135) [ 5375.164811] Lustre: DEBUG MARKER: SKIP: sanityn test_55e needs >= 2 MDTs [ 5376.626480] Lustre: DEBUG MARKER: == sanityn test 55f: rename: (P1/A -> P2/B) race with (P2/B -> P1/A) ========================================================== 12:42:18 (1780332138) [ 5394.613521] Lustre: DEBUG MARKER: == sanityn test 55g: rename: race with trylock =========== 12:42:36 (1780332156) [ 5415.825993] Lustre: DEBUG MARKER: == sanityn test 56a: test llverdev with single large stripe ========================================================== 12:42:57 (1780332177) [ 5525.148723] Lustre: DEBUG MARKER: == sanityn test 56b: test llverdev and partial verify of wide stripe file ========================================================== 12:44:46 (1780332286) [ 5639.765464] Lustre: DEBUG MARKER: == sanityn test 60: Verify data_version behaviour ======== 12:46:41 (1780332401) [ 5646.909750] Lustre: DEBUG MARKER: == sanityn test 70a: cd directory [ 5653.065607] Lustre: DEBUG MARKER: == sanityn test 70b: remove files after calling rm_entry ========================================================== 12:46:54 (1780332414) [ 5660.928574] Lustre: DEBUG MARKER: == sanityn test 71a: correct file map just after write operation is finished ========================================================== 12:47:02 (1780332422) [ 5662.629416] Lustre: DEBUG MARKER: SKIP: sanityn test_71a ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 5664.342646] Lustre: DEBUG MARKER: == sanityn test 71b: check fiemap support for stripecount > 1 ========================================================== 12:47:05 (1780332425) [ 5666.362948] Lustre: DEBUG MARKER: SKIP: sanityn test_71b ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 5668.144290] Lustre: DEBUG MARKER: == sanityn test 71c: check FIEMAP_EXTENT_LAST flag with different extents number ========================================================== 12:47:09 (1780332429) [ 5669.765491] Lustre: DEBUG MARKER: SKIP: sanityn test_71c support only ldiskfs ost [ 5671.464412] Lustre: DEBUG MARKER: == sanityn test 71d: fiemap corruption test with fm_extent_count=0 ========================================================== 12:47:12 (1780332432) [ 5673.097656] Lustre: DEBUG MARKER: SKIP: sanityn test_71d ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 5674.702904] Lustre: DEBUG MARKER: == sanityn test 72: getxattr/setxattr cache should be consistent between nodes ========================================================== 12:47:16 (1780332436) [ 5682.919321] Lustre: DEBUG MARKER: == sanityn test 73: getxattr should not cause xattr lock cancellation ========================================================== 12:47:23 (1780332443) [ 5690.717801] Lustre: DEBUG MARKER: == sanityn test 74: flock deadlock: different mounts ======================================================================== 12:47:31 (1780332451) [ 5694.152043] LustreError: lustre-MDT0000-mdc-ffffa0b4c3d43000: operation ldlm_enqueue to node 192.168.204.134@tcp failed: rc = -35 [ 5701.652649] Lustre: DEBUG MARKER: == sanityn test 75: osc: upcall after unuse lock============================================================================= 12:47:42 (1780332462) [ 5702.112724] LustreError: 2355:0:(osc_request.c:3138:osc_enqueue_interpret()) cfs_fail_timeout id 31d sleeping for 2000ms [ 5704.199137] LustreError: 2355:0:(osc_request.c:3138:osc_enqueue_interpret()) cfs_fail_timeout id 31d awake [ 5713.709902] Lustre: DEBUG MARKER: == sanityn test 76: Verify MDT open_files listing ======== 12:47:55 (1780332475) [ 5726.189626] Lustre: lustre-OST0000-osc-ffffa0b4cc0f2000: disconnect after 24s idle [ 5726.202314] Lustre: Skipped 8 previous similar messages [ 5784.597650] Lustre: DEBUG MARKER: == sanityn test 77a: check FIFO NRS policy =============== 12:49:05 (1780332545) [ 5793.919940] Lustre: DEBUG MARKER: == sanityn test 77b: check CRR-N NRS policy ============== 12:49:14 (1780332554) [ 5805.579717] Lustre: DEBUG MARKER: == sanityn test 77c: check ORR NRS policy ================ 12:49:26 (1780332566) [ 5819.352170] Lustre: DEBUG MARKER: == sanityn test 77d: check TRR nrs policy ================ 12:49:40 (1780332580) [ 5831.572315] Lustre: DEBUG MARKER: == sanityn test 77e: check TBF NID nrs policy ============ 12:49:53 (1780332593) [ 5850.773784] Lustre: DEBUG MARKER: == sanityn test 77f: check TBF JobID nrs policy ========== 12:50:12 (1780332612) [ 5870.560788] Lustre: DEBUG MARKER: == sanityn test 77g: Change TBF type directly ============ 12:50:31 (1780332631) [ 5883.175932] Lustre: DEBUG MARKER: == sanityn test 77h: Wrong policy name should report error, not LBUG ========================================================== 12:50:43 (1780332643) [ 5898.196482] Lustre: DEBUG MARKER: == sanityn test 77i: Change rank of TBF rule ============= 12:50:59 (1780332659) [ 5919.523130] Lustre: DEBUG MARKER: == sanityn test 77j: check TBF-OPCode NRS policy ========= 12:51:20 (1780332680) [ 5982.711583] Lustre: DEBUG MARKER: == sanityn test 77ja: check TBF-UID/GID NRS policy ======= 12:52:24 (1780332744) [ 6136.471285] Lustre: DEBUG MARKER: == sanityn test 77jb: check TBF-UID/GID NRS policy on files that don't belong to us ========================================================== 12:54:57 (1780332897) [ 6287.505311] Lustre: DEBUG MARKER: == sanityn test 77k: check TBF policy with UID/GID/JobID/OPCode expression ========================================================== 12:57:28 (1780333048) [ 6330.335253] Lustre: lustre-OST0000-osc-ffffa0b4c3d43000: disconnect after 21s idle [ 6330.346872] Lustre: Skipped 16 previous similar messages [ 6713.735360] Lustre: DEBUG MARKER: == sanityn test 77kb: Check different granular TBF type combination: jobid+opcode ========================================================== 13:04:35 (1780333475) [ 6762.748423] Lustre: DEBUG MARKER: == sanityn test 77kc: Check different granular TBF type combination: nid+opcode ========================================================== 13:05:24 (1780333524) [ 6812.051911] Lustre: DEBUG MARKER: == sanityn test 77kd: Check different granular TBF type combination: nid+jobid ========================================================== 13:06:13 (1780333573) [ 6858.983738] Lustre: DEBUG MARKER: == sanityn test 77ke: Check different granular TBF type combination: jobid+opcode ========================================================== 13:07:00 (1780333620) [ 6949.857727] Lustre: lustre-OST0000-osc-ffffa0b4c3d43000: disconnect after 23s idle [ 6949.865791] Lustre: Skipped 18 previous similar messages [ 6956.771171] Lustre: DEBUG MARKER: == sanityn test 77kf: Check different granular TBF type combination: uid+gid ========================================================== 13:08:37 (1780333717) [ 7031.732364] Lustre: DEBUG MARKER: == sanityn test 77kg: check different granular TBF type combination: uid+opcode ========================================================== 13:09:53 (1780333793) [ 7181.803507] Lustre: DEBUG MARKER: == sanityn test 77kh: Verify that Project ID support for NRS TBF rule ========================================================== 13:12:22 (1780333942) [ 7186.556760] LustreError: 257884:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffffa0b4c3d43000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7186.602120] Lustre: Unmounted lustre-client [ 7191.621763] LustreError: 257898:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffffa0b4cc0f2000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7191.632710] LustreError: 257898:0:(lov_obd.c:792:lov_cleanup()) Skipped 1 previous similar message [ 7191.669763] Lustre: Unmounted lustre-client [ 7273.468588] Lustre: Mounted lustre-client [ 7276.215201] Lustre: Mounted lustre-client [ 7279.175908] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 7391.245611] Lustre: DEBUG MARKER: == sanityn test 77ki: Add rule with unsupported TBF type should fail for generic TBF ========================================================== 13:15:52 (1780334152) [ 7407.997058] Lustre: DEBUG MARKER: == sanityn test 77l: check the output of NRS policies for generic TBF ========================================================== 13:16:09 (1780334169) [ 7418.225360] Lustre: DEBUG MARKER: == sanityn test 77m: check NRS Delay slows write RPC processing ========================================================== 13:16:19 (1780334179) [ 7475.601995] Lustre: DEBUG MARKER: == sanityn test 77n: check wildcard support for TBF JobID NRS policy ========================================================== 13:17:16 (1780334236) [ 7552.996083] Lustre: lustre-OST0000-osc-ffffa0b4c8c25000: disconnect after 24s idle [ 7553.021733] Lustre: Skipped 14 previous similar messages [ 7560.302769] Lustre: DEBUG MARKER: == sanityn test 77o: Changing rank should not panic ====== 13:18:41 (1780334321) [ 7570.906458] Lustre: DEBUG MARKER: == sanityn test 77q: Parallel TBF rule definitions should not panic ========================================================== 13:18:52 (1780334332) [ 7679.429803] Lustre: DEBUG MARKER: == sanityn test 77p: Check validity of rule names for TBF policies ========================================================== 13:20:40 (1780334440) [ 7722.233576] Lustre: DEBUG MARKER: == sanityn test 77r: Change type of tbf policy at run time ========================================================== 13:21:23 (1780334483) [ 7774.803100] Lustre: DEBUG MARKER: == sanityn test 77s: Check TBF LRU shrinker ============== 13:22:16 (1780334536) [ 7839.075283] Lustre: DEBUG MARKER: == sanityn test 78: Enable policy and specify tunings right away ========================================================== 13:23:20 (1780334600) [ 7848.402851] Lustre: DEBUG MARKER: == sanityn test 79: xattr: intent error ================== 13:23:29 (1780334609) [ 7866.883655] Lustre: DEBUG MARKER: == sanityn test 80a: migrate directory when some children is being opened ========================================================== 13:23:48 (1780334628) [ 7868.715727] Lustre: DEBUG MARKER: SKIP: sanityn test_80a needs >= 2 MDTs [ 7870.856934] Lustre: DEBUG MARKER: == sanityn test 80b: Accessing directory during migration ========================================================== 13:23:51 (1780334631) [ 7872.779727] Lustre: DEBUG MARKER: SKIP: sanityn test_80b needs >= 2 MDTs [ 7874.751644] Lustre: DEBUG MARKER: == sanityn test 81a: rename and stat under striped directory ========================================================== 13:23:55 (1780334635) [ 7876.241846] Lustre: DEBUG MARKER: SKIP: sanityn test_81a needs >= 2 MDTs [ 7877.885802] Lustre: DEBUG MARKER: == sanityn test 81b: rename under striped directory doesn't deadlock ========================================================== 13:23:59 (1780334639) [ 7879.574159] Lustre: DEBUG MARKER: SKIP: sanityn test_81b We need at least 2 MDTs for this test [ 7881.185597] Lustre: DEBUG MARKER: == sanityn test 81c: rename revoke LOOKUP lock for remote object ========================================================== 13:24:02 (1780334642) [ 7882.727486] Lustre: DEBUG MARKER: SKIP: sanityn test_81c needs >= 4 MDTs [ 7884.846269] Lustre: DEBUG MARKER: == sanityn test 81d: parallel rename file cross-dir on same MDT ========================================================== 13:24:05 (1780334645) [ 8032.538883] Lustre: DEBUG MARKER: == sanityn test 82: fsetxattr and fgetxattr on orphan files ========================================================== 13:26:33 (1780334793) [ 8040.042512] Lustre: DEBUG MARKER: == sanityn test 83: access striped directory while it is being created/unlinked ========================================================== 13:26:41 (1780334801) [ 8041.816719] Lustre: DEBUG MARKER: SKIP: sanityn test_83 needs >= 2 MDTs [ 8043.390180] Lustre: DEBUG MARKER: == sanityn test 84: 0-nlink race in lu_object_find() ===== 13:26:44 (1780334804) [ 8056.026334] Lustre: DEBUG MARKER: == sanityn test 85: Lustre API root cache race =========== 13:26:57 (1780334817) [ 8068.543492] Lustre: DEBUG MARKER: == sanityn test 90: open/create and unlink striped directory ========================================================== 13:27:09 (1780334829) [ 8070.342643] Lustre: DEBUG MARKER: SKIP: sanityn test_90 needs >= 2 MDTs [ 8072.340537] Lustre: DEBUG MARKER: == sanityn test 91: chmod and unlink striped directory === 13:27:13 (1780334833) [ 8074.728237] Lustre: DEBUG MARKER: SKIP: sanityn test_91 needs >= 2 MDTs [ 8076.921973] Lustre: DEBUG MARKER: == sanityn test 92: create remote directory under orphan directory ========================================================== 13:27:17 (1780334837) [ 8079.340737] Lustre: DEBUG MARKER: SKIP: sanityn test_92 needs >= 2 MDTs [ 8081.087873] Lustre: DEBUG MARKER: == sanityn test 93: alloc_rr should not allocate on same ost ========================================================== 13:27:22 (1780334842) [ 8099.266589] Lustre: DEBUG MARKER: == sanityn test 94: signal vs CP callback race =========== 13:27:39 (1780334859) [ 8100.137931] Lustre: DEBUG MARKER: write [ 8100.233964] LustreError: 259304:0:(ldlm_request.c:1525:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 sleeping for 5000ms [ 8102.260913] Lustre: DEBUG MARKER: kill 285851 [ 8102.281507] LustreError: 285851:0:(ldlm_request.c:1393:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 sleeping for 6000ms [ 8105.271576] LustreError: 259304:0:(ldlm_request.c:1525:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 awake [ 8108.343376] LustreError: 285851:0:(ldlm_request.c:1393:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 awake [ 8116.423744] Lustre: DEBUG MARKER: == sanityn test 95a: Check readpage() on a page that was removed from page cache ========================================================== 13:27:57 (1780334877) [ 8119.300329] LustreError: 286457:0:(rw.c:1868:ll_readpage()) cfs_fail_timeout id 1422 sleeping for 10000ms [ 8129.311892] LustreError: 286457:0:(rw.c:1868:ll_readpage()) cfs_fail_timeout id 1422 awake [ 8137.691534] Lustre: DEBUG MARKER: == sanityn test 95b: Check readpage() on a page that is no longer uptodate ========================================================== 13:28:18 (1780334898) [ 8138.040312] LustreError: 287037:0:(rw.c:2116:ll_readpage()) cfs_fail_timeout id 1424 sleeping for 5000ms [ 8140.135172] LustreError: 287037:0:(rw.c:2116:ll_readpage()) cfs_fail_timeout interrupted [ 8150.861498] Lustre: DEBUG MARKER: == sanityn test 100a: DoM: glimpse RPCs for stat without IO lock (DoM only file) ========================================================== 13:28:32 (1780334912) [ 8152.524847] Lustre: DEBUG MARKER: SKIP: sanityn test_100a Reserved for glimpse-ahead [ 8154.138989] Lustre: DEBUG MARKER: == sanityn test 100b: DoM: no glimpse RPC for stat with IO lock (DoM only file) ========================================================== 13:28:35 (1780334915) [ 8162.953680] Lustre: DEBUG MARKER: == sanityn test 100c: DoM: write vs stat without IO lock (combined file) ========================================================== 13:28:43 (1780334923) [ 8170.080366] Lustre: DEBUG MARKER: == sanityn test 100d: DoM: write+truncate vs stat without IO lock (combined file) ========================================================== 13:28:51 (1780334931) [ 8176.993563] Lustre: DEBUG MARKER: == sanityn test 100e: DoM: read on open and file size ==== 13:28:58 (1780334938) [ 8184.340229] Lustre: DEBUG MARKER: == sanityn test 101a: Discard DoM data on unlink ========= 13:29:04 (1780334944) [ 8193.212362] Lustre: DEBUG MARKER: == sanityn test 101b: Discard DoM data on rename ========= 13:29:14 (1780334954) [ 8201.168905] Lustre: DEBUG MARKER: == sanityn test 101c: Discard DoM data on close-unlink === 13:29:22 (1780334962) [ 8208.723201] Lustre: DEBUG MARKER: SKIP: sanityn test_102 skipping ALWAYS excluded test 102 [ 8210.922174] Lustre: DEBUG MARKER: == sanityn test 103: Test size correctness with lockahead ========================================================== 13:29:31 (1780334971) [ 8212.735194] Lustre: *** cfs_fail_loc=415, val=0*** [ 8226.405773] Lustre: DEBUG MARKER: == sanityn test 104: Verify that MDS stores atime/mtime/ctime during close ========================================================== 13:29:47 (1780334987) [ 8228.738831] Lustre: DEBUG MARKER: SKIP: sanityn test_104 ldiskfs only test [ 8231.247972] Lustre: DEBUG MARKER: == sanityn test 105: Glimpse and lock cancel race ======== 13:29:51 (1780334991) [ 8231.568738] LustreError: 259303:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) cfs_fail_timeout id 416 sleeping for 5000ms [ 8231.580838] LustreError: 259303:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) Skipped 3 previous similar messages [ 8236.591172] LustreError: 259303:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) cfs_fail_timeout id 416 awake [ 8246.791405] LustreError: 259964:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) cfs_fail_timeout id 416 awake [ 8246.800777] LustreError: 259964:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) Skipped 6 previous similar messages [ 8259.565686] Lustre: DEBUG MARKER: == sanityn test 106a: Verify the btime via statx() ======= 13:30:20 (1780335020) [ 8261.173403] Lustre: DEBUG MARKER: SKIP: sanityn test_106a Test only for ldiskfs and statx() supported [ 8263.417435] Lustre: DEBUG MARKER: == sanityn test 106b: Glimpse RPCs test for statx ======== 13:30:24 (1780335024) [ 8272.079093] Lustre: DEBUG MARKER: == sanityn test 106c: Verify statx attributes mask ======= 13:30:33 (1780335033) [ 8279.127469] Lustre: DEBUG MARKER: == sanityn test 107a: Basic grouplock conflict =========== 13:30:40 (1780335040) [ 8288.005804] Lustre: DEBUG MARKER: == sanityn test 107b: Grouplock is added to the head of waiting list ========================================================== 13:30:49 (1780335049) [ 8301.647606] Lustre: DEBUG MARKER: == sanityn test 108a: lseek: parallel updates ============ 13:31:02 (1780335062) [ 8302.252854] LustreError: 268696:0:(osc_request.c:2989:osc_build_rpc()) cfs_fail_timeout id 414 sleeping for 4000ms [ 8302.260392] LustreError: 268696:0:(osc_request.c:2989:osc_build_rpc()) Skipped 5 previous similar messages [ 8306.337718] LustreError: 268696:0:(osc_request.c:2989:osc_build_rpc()) cfs_fail_timeout id 414 awake [ 8306.350898] LustreError: 268696:0:(osc_request.c:2989:osc_build_rpc()) Skipped 1 previous similar message [ 8313.327107] Lustre: DEBUG MARKER: == sanityn test 109: Race with several mount instances on 1 node ========================================================== 13:31:14 (1780335074) [ 8317.260423] LustreError: 296996:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffffa0b4c8c25000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8317.271558] LustreError: 296996:0:(lov_obd.c:792:lov_cleanup()) Skipped 1 previous similar message [ 8317.336557] Lustre: Unmounted lustre-client [ 8319.364192] LustreError: 297017:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffffa0b4c9c43800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8319.370278] LustreError: 297017:0:(lov_obd.c:792:lov_cleanup()) Skipped 1 previous similar message [ 8319.411661] Lustre: Unmounted lustre-client [ 8320.718737] Lustre: DEBUG MARKER: Iteration 0 [ 8320.934401] LustreError: 297177:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8320.935303] LustreError: 297178:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8320.947082] LustreError: 297177:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4989 [ 8321.107902] Lustre: Mounted lustre-client [ 8322.373616] LustreError: 297277:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffffa0b4cf225000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8322.389588] LustreError: 297277:0:(lov_obd.c:792:lov_cleanup()) Skipped 1 previous similar message [ 8322.429888] Lustre: Unmounted lustre-client [ 8324.965844] Key type lgssc unregistered [ 8325.218612] LNet: 297520:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8325.227920] LNetError: 297520:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8325.266533] LNet: Removed LNI 192.168.204.34@tcp [ 8325.941165] Key type .llcrypt unregistered [ 8325.946617] Key type ._llcrypt unregistered [ 8326.978975] Key type ._llcrypt registered [ 8326.990784] Key type .llcrypt registered [ 8327.685575] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8327.699285] alg: No test for adler32 (adler32-zlib) [ 8329.320391] Lustre: Lustre: Build Version: 2.17.53_33_g3f95abe [ 8330.163414] LNet: Added LNI 192.168.204.34@tcp [8/256/0/180] [ 8331.911260] Key type lgssc registered [ 8333.623936] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8347.289262] Lustre: DEBUG MARKER: Iteration 1 [ 8347.783704] LustreError: 298343:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8347.786164] LustreError: 298345:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8347.800115] LustreError: 298343:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4986 [ 8349.091628] Lustre: Mounted lustre-client [ 8350.377107] LustreError: 298438:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffffa0b4ca6c7800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8350.450199] Lustre: Unmounted lustre-client [ 8353.425595] Key type lgssc unregistered [ 8353.707228] LNet: 298688:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8353.712232] LNetError: 298688:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8353.729861] LNet: Removed LNI 192.168.204.34@tcp [ 8354.339166] Key type .llcrypt unregistered [ 8354.342742] Key type ._llcrypt unregistered [ 8355.071138] Key type ._llcrypt registered [ 8355.077913] Key type .llcrypt registered [ 8355.623636] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8355.635586] alg: No test for adler32 (adler32-zlib) [ 8356.836289] Lustre: Lustre: Build Version: 2.17.53_33_g3f95abe [ 8357.132426] LNet: Added LNI 192.168.204.34@tcp [8/256/0/180] [ 8358.879297] Key type lgssc registered [ 8360.143183] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8371.296506] Lustre: Mounted lustre-client [ 8371.813769] Lustre: Mounted lustre-client [ 8378.348902] Lustre: DEBUG MARKER: == sanityn test 110: do not grant another lock on resend ========================================================== 13:32:19 (1780335139) [ 8435.686860] Lustre: 300013:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1780335142/real 1780335142] req@ffffa0b4ff56e300 x1866816677750400/t0(0) o36->lustre-MDT0000-mdc-ffffa0b4cb983000@192.168.204.134@tcp:12/10 lens 496/440 e 0 to 1 dl 1780335198 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 8435.722548] Lustre: lustre-MDT0000-mdc-ffffa0b4cb983000: Connection to lustre-MDT0000 (at 192.168.204.134@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8435.758052] Lustre: lustre-MDT0000-mdc-ffffa0b4cb983000: Connection restored to 192.168.204.134@tcp (at 192.168.204.134@tcp) [ 8491.999173] Lustre: 300013:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1780335198/real 1780335198] req@ffffa0b4ff56e300 x1866816677750400/t0(0) o36->lustre-MDT0000-mdc-ffffa0b4cb983000@192.168.204.134@tcp:12/10 lens 496/440 e 0 to 1 dl 1780335254 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 8492.050291] Lustre: lustre-MDT0000-mdc-ffffa0b4cb983000: Connection to lustre-MDT0000 (at 192.168.204.134@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8492.091099] Lustre: lustre-MDT0000-mdc-ffffa0b4cb983000: Connection restored to 192.168.204.134@tcp (at 192.168.204.134@tcp) [ 8548.319666] Lustre: 300013:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1780335254/real 1780335254] req@ffffa0b4ff56e300 x1866816677750400/t0(0) o36->lustre-MDT0000-mdc-ffffa0b4cb983000@192.168.204.134@tcp:12/10 lens 496/440 e 0 to 1 dl 1780335310 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 8548.366752] Lustre: lustre-MDT0000-mdc-ffffa0b4cb983000: Connection to lustre-MDT0000 (at 192.168.204.134@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8548.403395] Lustre: lustre-MDT0000-mdc-ffffa0b4cb983000: Connection restored to 192.168.204.134@tcp (at 192.168.204.134@tcp) [ 8550.250781] Lustre: DEBUG MARKER: == sanityn test 111: A racy rename/link an open file should not cause fs corruption ========================================================== 13:35:11 (1780335311) [ 8553.113611] Lustre: DEBUG MARKER: SKIP: sanityn test_111 needs >= 2 MDTs [ 8555.447093] Lustre: DEBUG MARKER: == sanityn test 112: update max-inherit in default LMV === 13:35:16 (1780335316) [ 8557.677367] Lustre: DEBUG MARKER: SKIP: sanityn test_112 We need at least 2 MDTs for this test [ 8560.381318] Lustre: DEBUG MARKER: == sanityn test 113: check servers of specified fs ======= 13:35:21 (1780335321) [ 8568.314913] Lustre: DEBUG MARKER: == sanityn test 114: implicit default LMV inherit ======== 13:35:29 (1780335329) [ 8570.184481] Lustre: DEBUG MARKER: SKIP: sanityn test_114 We need at least 2 MDTs for this test [ 8572.060858] Lustre: DEBUG MARKER: == sanityn test 115: ldiskfs doesn't check direntry for uniqueness ========================================================== 13:35:33 (1780335333) [ 8574.087129] Lustre: DEBUG MARKER: SKIP: sanityn test_115 ldiskfs only test [ 8576.022949] Lustre: DEBUG MARKER: == sanityn test 116: DNE: Set default LMV layout from a remote client ========================================================== 13:35:37 (1780335337) [ 8577.779586] Lustre: DEBUG MARKER: SKIP: sanityn test_116 needs >= 2 MDTs [ 8580.235592] Lustre: DEBUG MARKER: == sanityn test 117a: TCU: Init and enable Trash Can on MDTs ========================================================== 13:35:41 (1780335341) [ 8582.068195] Lustre: DEBUG MARKER: SKIP: sanityn test_117a ldiskfs only test [ 8583.744805] Lustre: DEBUG MARKER: == sanityn test 117b: Move regular file and empty dir into trash can dir ========================================================== 13:35:45 (1780335345) [ 8585.349816] Lustre: DEBUG MARKER: SKIP: sanityn test_117b ldiskfs only test [ 8587.421542] Lustre: DEBUG MARKER: == sanityn test 117c: Move deleted tree with multiple levels into trash ========================================================== 13:35:48 (1780335348) [ 8589.141451] Lustre: DEBUG MARKER: SKIP: sanityn test_117c ldiskfs only test [ 8591.458740] Lustre: DEBUG MARKER: == sanityn test 117d: Per-User Trash can Type testing ==== 13:35:52 (1780335352) [ 8593.208254] Lustre: DEBUG MARKER: SKIP: sanityn test_117d ldiskfs only test [ 8595.052975] Lustre: DEBUG MARKER: == sanityn test 117e: Undeleted dir in trash should keep its original xattrs ========================================================== 13:35:56 (1780335356) [ 8596.758518] Lustre: DEBUG MARKER: SKIP: sanityn test_117e ldiskfs only test [ 8598.522462] Lustre: DEBUG MARKER: == sanityn test 117f: Uncache the dentry under the trash dir ========================================================== 13:35:59 (1780335359) [ 8600.244403] Lustre: DEBUG MARKER: SKIP: sanityn test_117f ldiskfs only test [ 8602.021642] Lustre: DEBUG MARKER: == sanityn test 121: trunc append race =================== 13:36:03 (1780335363) [ 8602.350943] LustreError: 304419:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 sleeping for 2000ms [ 8604.447208] LustreError: 304419:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 awake [ 8612.546940] Lustre: DEBUG MARKER: == sanityn test 200: service remains healthy while able to process request ========================================================== 13:36:13 (1780335373) [ 8635.359150] Lustre: 298881:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1780335382/real 1780335382] req@ffffa0b4ff0f4700 x1866816677797888/t0(0) o4->lustre-OST0000-osc-ffffa0b4cb983000@192.168.204.134@tcp:6/4 lens 4584/448 e 0 to 1 dl 1780335398 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 8635.359294] Lustre: lustre-OST0000-osc-ffffa0b4cb983000: Connection to lustre-OST0000 (at 192.168.204.134@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8635.393658] Lustre: 298881:0:(client.c:2480:ptlrpc_expire_one_request()) Skipped 3 previous similar messages [ 8635.456114] Lustre: lustre-OST0000-osc-ffffa0b4cb983000: Connection restored to 192.168.204.134@tcp (at 192.168.204.134@tcp) [ 8690.711163] Lustre: DEBUG MARKER: oleg434-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffffa0b4c7de9000.ost_server_uuid 50 [ 8692.345701] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffffa0b4c7de9000.ost_server_uuid in IDLE state after 0 sec [ 8693.946512] Lustre: DEBUG MARKER: cleanup: ====================================================== [ 8695.445095] Lustre: DEBUG MARKER: == sanityn test complete, duration 8528 sec ============== 13:37:36 (1780335456) [ 8697.002586] Lustre: DEBUG MARKER: === sanityn: start cleanup 13:37:38 (1780335458) === [ 8942.366345] LustreError: 306445:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffffa0b4c7de9000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8942.425850] Lustre: Unmounted lustre-client [ 8945.869994] Lustre: DEBUG MARKER: === sanityn: finish cleanup 13:41:47 (1780335707) === [ 8948.903811] LustreError: 306748:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffffa0b4cb983000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8948.917439] LustreError: 306748:0:(lov_obd.c:792:lov_cleanup()) Skipped 1 previous similar message [ 8948.965358] Lustre: Unmounted lustre-client [ 8989.710232] Key type lgssc unregistered [ 8989.962903] LNet: 307231:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8989.980586] LNetError: 307231:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8990.000349] LNet: Removed LNI 192.168.204.34@tcp [ 8990.615162] Key type .llcrypt unregistered [ 8990.617841] Key type ._llcrypt unregistered