[ 0.000000] Linux version 4.18.0rh8.10-debug (green@maintenance) (gcc version 8.5.0 20210514 (Red Hat 8.5.0-26) (GCC)) #2 SMP Mon Jul 14 01:24:22 EDT 2025 [ 0.000000] Command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' [ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 [ 0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. [ 0.000000] signal: max sigframe size: 1776 [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bffd9fff] usable [ 0.000000] BIOS-e820: [mem 0x00000000bffda000-0x00000000bfffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x0000000146dfffff] usable [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] SMBIOS 2.8 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.16.2-1.fc38 04/01/2014 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 482346499 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f5b50-0x000f5b5f] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F5970 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE1BB7 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE1A53 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 001A13 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE1AC7 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE1B57 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE1B8F 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe1a53-0xbffe1ac6] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe1a52] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe1ac7-0xbffe1b56] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe1b57-0xbffe1b8e] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe1b8f-0xbffe1bb6] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001014] APIC: Switch to symmetric I/O mode setup [ 0.002448] x2apic enabled [ 0.003012] Switched APIC routing to physical x2apic. [ 0.004017] kvm-guest: setup PV IPIs [ 0.007543] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.008000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.008029] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009016] pid_max: default: 32768 minimum: 301 [ 0.010139] LSM: Security Framework initializing [ 0.011057] Yama: becoming mindful. [ 0.012000] SELinux: Initializing. [ 0.012068] *** VALIDATE selinux *** [ 0.020746] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.028935] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.029343] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.030231] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.031202] *** VALIDATE tmpfs *** [ 0.033343] *** VALIDATE proc *** [ 0.035221] *** VALIDATE cgroup *** [ 0.036000] *** VALIDATE cgroup2 *** [ 0.037079] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.038187] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.039020] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.040040] Spectre V2 : User space: Vulnerable [ 0.042005] Speculative Store Bypass: Vulnerable [ 0.045540] debug: unmapping init [mem 0xffffffff94059000-0xffffffff94060fff] [ 0.048109] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.049647] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.050029] ... version: 2 [ 0.051029] ... bit width: 48 [ 0.052021] ... generic registers: 4 [ 0.053013] ... value mask: 0000ffffffffffff [ 0.054020] ... max period: 00007fffffffffff [ 0.055027] ... fixed-purpose events: 3 [ 0.056018] ... event mask: 000000070000000f [ 0.057352] rcu: Hierarchical SRCU implementation. [ 0.059560] smp: Bringing up secondary CPUs ... [ 0.060691] x86: Booting SMP configuration: [ 0.061046] .... node #0, CPUs: #1 #2 #3 [ 0.070069] smp: Brought up 1 node, 4 CPUs [ 0.072038] smpboot: Max logical packages: 1 [ 0.073015] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.159262] node 0 deferred pages initialised in 81ms [ 0.163171] devtmpfs: initialized [ 0.164215] x86/mm: Memory block size: 128MB [ 0.166922] gcov: version magic: 0x41383552 [ 0.168376] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.169102] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.170392] pinctrl core: initialized pinctrl subsystem [ 0.171277] [ 0.171847] ************************************************************* [ 0.172017] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.173021] ** ** [ 0.174018] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.175017] ** ** [ 0.176018] ** This means that this kernel is built to expose internal ** [ 0.177016] ** IOMMU data structures, which may compromise security on ** [ 0.178019] ** your system. ** [ 0.179029] ** ** [ 0.180023] ** If you see this message and you are not debugging the ** [ 0.181024] ** kernel, report this immediately to your vendor! ** [ 0.182015] ** ** [ 0.183021] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.184020] ************************************************************* [ 0.185766] NET: Registered protocol family 16 [ 0.186506] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.187081] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.188105] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.189575] cpuidle: using governor menu [ 0.193000] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.196583] PCI: Using configuration type 1 for base access [ 0.198114] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.210060] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.211027] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.212338] cryptd: max_cpu_qlen set to 1000 [ 0.213266] ACPI: Added _OSI(Module Device) [ 0.214021] ACPI: Added _OSI(Processor Device) [ 0.215017] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.216015] ACPI: Added _OSI(Processor Aggregator Device) [ 0.219540] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.222631] ACPI: Interpreter enabled [ 0.223081] ACPI: PM: (supports S0 S3 S4 S5) [ 0.224015] ACPI: Using IOAPIC for interrupt routing [ 0.225125] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.226417] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.236113] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.237059] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.238024] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.239081] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.241433] acpiphp: Slot [2] registered [ 0.242115] acpiphp: Slot [3] registered [ 0.243106] acpiphp: Slot [4] registered [ 0.244095] acpiphp: Slot [5] registered [ 0.245198] acpiphp: Slot [6] registered [ 0.246108] acpiphp: Slot [7] registered [ 0.247533] acpiphp: Slot [8] registered [ 0.248000] acpiphp: Slot [9] registered [ 0.248000] acpiphp: Slot [10] registered [ 0.249099] acpiphp: Slot [11] registered [ 0.251109] acpiphp: Slot [12] registered [ 0.252000] acpiphp: Slot [13] registered [ 0.252000] acpiphp: Slot [14] registered [ 0.254160] acpiphp: Slot [15] registered [ 0.256109] acpiphp: Slot [16] registered [ 0.258137] acpiphp: Slot [17] registered [ 0.260214] acpiphp: Slot [18] registered [ 0.262103] acpiphp: Slot [19] registered [ 0.264124] acpiphp: Slot [20] registered [ 0.266127] acpiphp: Slot [21] registered [ 0.268099] acpiphp: Slot [22] registered [ 0.269112] acpiphp: Slot [23] registered [ 0.270107] acpiphp: Slot [24] registered [ 0.273141] acpiphp: Slot [25] registered [ 0.274123] acpiphp: Slot [26] registered [ 0.276143] acpiphp: Slot [27] registered [ 0.277116] acpiphp: Slot [28] registered [ 0.279103] acpiphp: Slot [29] registered [ 0.281129] acpiphp: Slot [30] registered [ 0.282090] acpiphp: Slot [31] registered [ 0.283111] PCI host bridge to bus 0000:00 [ 0.285028] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.288028] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.291032] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.293022] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.296213] pci_bus 0000:00: root bus resource [mem 0x180000000-0x1ffffffff window] [ 0.299026] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.302209] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.307224] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.314223] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.330021] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.338176] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.344030] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.348036] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.354060] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.361098] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.362864] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.363044] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.364617] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.367021] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.378019] pci 0000:00:02.0: reg 0x20: [mem 0xfebf4000-0xfebf7fff 64bit pref] [ 0.382022] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.387561] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.394024] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.400024] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.413022] pci 0000:00:05.0: reg 0x20: [mem 0xfebf8000-0xfebfbfff 64bit pref] [ 0.422289] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.429015] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.435020] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.448026] pci 0000:00:06.0: reg 0x20: [mem 0xfebfc000-0xfebfffff 64bit pref] [ 0.458019] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.460451] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.462426] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.465420] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.468349] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.472181] iommu: Default domain type: Passthrough [ 0.474481] SCSI subsystem initialized [ 0.476213] ACPI: bus type USB registered [ 0.477136] usbcore: registered new interface driver usbfs [ 0.479121] usbcore: registered new interface driver hub [ 0.481135] usbcore: registered new device driver usb [ 0.483243] pps_core: LinuxPPS API ver. 1 registered [ 0.485014] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.488104] PTP clock support registered [ 0.491087] EDAC MC: Ver: 3.0.0 [ 0.492407] PCI: Using ACPI for IRQ routing [ 0.493511] NetLabel: Initializing [ 0.494000] NetLabel: domain hash size = 128 [ 0.494000] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.496118] NetLabel: unlabeled traffic allowed by default [ 0.498320] vgaarb: loaded [ 0.500086] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.501010] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.508593] clocksource: Switched to clocksource kvm-clock [ 0.629408] VFS: Disk quotas dquot_6.6.0 [ 0.631066] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.633293] *** VALIDATE ramfs *** [ 0.634443] *** VALIDATE hugetlbfs *** [ 0.636232] pnp: PnP ACPI init [ 0.638819] pnp: PnP ACPI: found 6 devices [ 0.661562] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.665181] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.667623] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.670037] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.672293] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.674699] pci_bus 0000:00: resource 8 [mem 0x180000000-0x1ffffffff window] [ 0.678759] NET: Registered protocol family 2 [ 0.681351] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.686407] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.690054] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.695834] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.700065] TCP: Hash tables configured (established 65536 bind 65536) [ 0.703201] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.706568] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.709513] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.712337] NET: Registered protocol family 1 [ 0.714535] RPC: Registered named UNIX socket transport module. [ 0.716821] RPC: Registered udp transport module. [ 0.718652] RPC: Registered tcp transport module. [ 0.720533] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.722941] NET: Registered protocol family 44 [ 0.724356] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.726581] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.728797] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.730727] PCI: CLS 0 bytes, default 64 [ 0.733765] Unpacking initramfs... [ 2.152520] debug: unmapping init [mem 0xffff9e36bcc64000-0xffff9e36bffcffff] [ 2.156273] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.158643] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.161288] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.682940] Initialise system trusted keyrings [ 2.684476] Key type blacklist registered [ 2.686399] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.696153] zbud: loaded [ 2.700122] *** VALIDATE nfs *** [ 2.701656] *** VALIDATE nfs4 *** [ 2.703488] pstore: using deflate compression [ 2.707146] Platform Keyring initialized [ 2.809535] NET: Registered protocol family 38 [ 2.811483] Key type asymmetric registered [ 2.812769] Asymmetric key parser 'x509' registered [ 2.814312] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.818992] io scheduler mq-deadline registered [ 2.821549] io scheduler kyber registered [ 2.823929] io scheduler bfq registered [ 2.826080] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.830319] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.833712] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.836736] ACPI: Power Button [PWRF] [ 2.926768] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.017759] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.111390] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.147787] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.186171] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.191039] Non-volatile memory driver v1.3 [ 3.192649] Linux agpgart interface v0.103 [ 3.226831] virtio_blk virtio1: [vda] 132696 512-byte logical blocks (67.9 MB/64.8 MiB) [ 3.230985] vda: detected capacity change from 0 to 67940352 [ 3.247258] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.251635] vdb: detected capacity change from 0 to 1073741824 [ 3.259495] libphy: Fixed MDIO Bus: probed [ 3.265863] usbcore: registered new interface driver usbserial_generic [ 3.268102] usbserial: USB Serial support registered for generic [ 3.270115] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.274460] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.276376] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.279336] mousedev: PS/2 mouse device common for all mice [ 3.282525] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.286396] rtc_cmos 00:05: RTC can wake from S4 [ 3.292930] rtc_cmos 00:05: registered as rtc0 [ 3.295103] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.295804] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.300343] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.301089] intel_pstate: CPU model not supported [ 3.306868] hid: raw HID events driver (C) Jiri Kosina [ 3.309537] usbcore: registered new interface driver usbhid [ 3.312620] usbhid: USB HID core driver [ 3.315082] drop_monitor: Initializing network drop monitor service [ 3.317567] Initializing XFRM netlink socket [ 3.319474] NET: Registered protocol family 10 [ 3.322897] Segment Routing with IPv6 [ 3.324597] NET: Registered protocol family 17 [ 3.327454] mpls_gso: MPLS GSO support [ 3.331638] RAS: Correctable Errors collector initialized. [ 3.333928] AVX version of gcm_enc/dec engaged. [ 3.335873] AES CTR mode by8 optimization enabled [ 3.415549] sched_clock: Marking stable (3415513979, 0)->(4446646545, -1031132566) [ 3.419358] registered taskstats version 1 [ 3.421373] Loading compiled-in X.509 certificates [ 3.423519] zswap: loaded using pool lzo/zbud [ 3.451337] Key type big_key registered [ 3.464688] Key type encrypted registered [ 3.466123] ima: No TPM chip found, activating TPM-bypass! [ 3.467833] ima: Allocated hash algorithm: sha1 [ 3.469417] ima: No architecture policies found [ 3.470884] evm: Initialising EVM extended attributes: [ 3.472406] evm: security.selinux [ 3.473295] evm: security.ima [ 3.474192] evm: security.capability [ 3.475216] evm: HMAC attrs: 0x1 [ 3.477175] rtc_cmos 00:05: setting system clock to 2025-07-28 22:23:21 UTC (1753741401) [ 3.483161] debug: unmapping init [mem 0xffffffff95003000-0xffffffff951fffff] [ 3.485926] debug: unmapping init [mem 0xffffffff93d82000-0xffffffff94058fff] [ 3.495139] Write protecting the kernel read-only data: 28672k [ 3.498552] debug: unmapping init [mem 0xffffffff92403000-0xffffffff925fffff] [ 3.501996] debug: unmapping init [mem 0xffffffff92d14000-0xffffffff92dfffff] [ 3.535735] 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.543447] systemd[1]: Detected virtualization kvm. [ 3.545152] systemd[1]: Detected architecture x86-64. [ 3.546789] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.575491] systemd[1]: No hostname configured. [ 3.577322] systemd[1]: Set hostname to . [ 3.579330] random: systemd: uninitialized urandom read (16 bytes read) [ 3.581904] systemd[1]: Initializing machine ID from random generator. [ 3.629693] random: ln: uninitialized urandom read (6 bytes read) [ 3.728090] random: systemd: uninitialized urandom read (16 bytes read) [ 3.730113] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ 3.735202] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. [ 3.740253] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Started Memstrack Anylazing Service. [ OK ] Reached target Slices. Starting Apply Kernel Variables... Starting Setup Virtual Console... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Timers. [ OK ] Reached target Local File Systems. [ OK ] Listening on udev Kernel Socket. Starting Create Volatile Files and Directories... [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Sockets. Starting Journal Service... [ OK ] Reached target Paths. [ OK ] Reached target Swap. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. [ OK ] Started Setup Virtual Console. [ OK ] Started Create Volatile Files and Directories. 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.376041] device-mapper: uevent: version 1.0.3 [ 4.378526] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 5.117266] virtio_net virtio0 ens2: renamed from eth0 [ 5.177361] scsi host0: ata_piix [ 5.189695] scsi host1: ata_piix [ 5.191436] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.193897] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 9.577385] dracut-initqueue[586]: RTNETLINK answers: File exists [ 9.979954] random: crng init done [ 9.981557] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 10.448798] 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... Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Timers. [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Sockets. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Swap. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Local Encrypted Volumes. [ 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 ] Stopped target Slices. [ OK ] Stopped target Paths. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 11.574782] printk: systemd: 26 output lines suppressed due to ratelimiting [ 11.859662] SELinux: Disabled at runtime. [ 11.915566] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 11.924419] systemd[1]: Detected virtualization kvm. [ 11.926295] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 12.439177] systemd[1]: initrd-switch-root.service: Succeeded. [ 12.442293] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 12.452430] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 12.455837] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 12.458844] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 12.468180] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.474433] systemd[1]: proc-sys-fs-binfmt_misc.automount: Refusing to start, unit to trigger not loaded. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Listening on udev Control Socket. [ OK ] Listening on initctl Compatibility Named Pipe. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Started Dispatch Password Requests to Console Directory Watch. Mounting Huge Pages File System... [ OK ] Created slice system-sshd\x2dkeygen.slice. Starting Remount Root and Kernel File Systems... [ OK ] Reached target rpc_pipefs.target. [ OK ] Listening on Process Core Dump Socket. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. Mounting POSIX Message Queue File System... [ OK ] Created slice system-getty.slice. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd Root File System. [ OK ] Stopped target Initrd File Systems. [ OK ] Listening on udev Kernel Socket. Starting udev Coldplug all Devices... Activating swap /dev/disk/by-label/SWAP... Starting Apply Kernel Variables... Mounting Kernel Debug File System... [ OK ] Started Journal Service. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ 12.703924] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Mounted Huge Pages File System. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted Kernel Debug File System. [ 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 Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... [ OK ] Started udev Coldplug all Devices. [ OK ] Mounted /mnt. [ 13.033638] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 13.327479] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 13.348741] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 13.533186] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 13.556478] EDAC sbridge: Ver: 1.1.2 [ 14.754846] Key type dns_resolver registered [ 15.058987] NFS: Registering the id_resolver key type [ 15.060891] Key type id_resolver registered [ 15.062340] 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 Load/Save Random Seed... Starting Create Volatile Files and Directories... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Load/Save Random Seed. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started 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. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started irqbalance daemon. [ OK ] Reached target sshd-keygen.target. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... Starting Login Service... Starting Restore /run/initramfs on shutdown... [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... Starting OpenSSH server daemon... Starting Network Manager Wait Online... Starting Hostname Service... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started OpenSSH server daemon. [ OK ] Started Permit User Sessions. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS0. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Crash recovery kernel arming... Starting System Logging Service... Starting Notify NFS peers of a restart... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started Authorization Manager. [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg209-client login: [ 44.560655] hrtimer: interrupt took 9555626 ns [ 55.314418] libcfs: loading out-of-tree module taints kernel. [ 55.464556] Key type ._llcrypt registered [ 55.466200] Key type .llcrypt registered [ 56.354811] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 56.392962] alg: No test for adler32 (adler32-zlib) [ 57.808661] Lustre: Lustre: Build Version: 2.16.56_105_g447ed16 [ 58.820174] LNet: Added LNI 192.168.202.9@tcp [8/256/0/180] [ 58.834328] LNet: Accept secure, port 988 [ 60.720862] Key type lgssc registered [ 62.720467] Lustre: Echo OBD driver; http://www.lustre.org/ [ 241.666378] Lustre: Mounted lustre-client [ 246.534840] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 261.818769] Lustre: DEBUG MARKER: oleg209-client.virtnet: executing check_logdir /tmp/testlogs/ [ 265.548270] Lustre: DEBUG MARKER: oleg209-client.virtnet: executing yml_node [ 267.234272] Lustre: lustre-OST0000-osc-ffff9e3718246000: disconnect after 23s idle [ 270.329885] Lustre: DEBUG MARKER: Client: 2.16.56.105 [ 273.024726] Lustre: DEBUG MARKER: MDS: 2.16.56.105 [ 275.917150] Lustre: DEBUG MARKER: OSS: 2.16.56.105 [ 277.257951] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Mon Jul 28 18:27:54 EDT 2025 [ 295.120064] Lustre: DEBUG MARKER: excepting tests: 27 40a [ 296.631709] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 298.037546] Lustre: DEBUG MARKER: === sanityn: start setup 18:28:14 (1753741694) === [ 298.747278] Lustre: Mounted lustre-client [ 301.730215] Lustre: DEBUG MARKER: oleg209-client.virtnet: executing check_config_client /mnt/lustre [ 319.352565] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 330.922206] Lustre: DEBUG MARKER: === sanityn: finish setup 18:28:47 (1753741727) === [ 333.573350] Lustre: DEBUG MARKER: == sanityn test 0: do_node_vp() and do_facet_vp() do the right thing ========================================================== 18:28:49 (1753741729) [ 343.058530] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 18:28:59 (1753741739) [ 352.040046] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 18:29:08 (1753741748) [ 360.182440] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 18:29:16 (1753741756) [ 367.424946] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 18:29:23 (1753741763) [ 373.896583] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 18:29:30 (1753741770) [ 380.138983] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 18:29:36 (1753741776) [ 386.715607] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 18:29:43 (1753741783) [ 394.725601] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 18:29:51 (1753741791) [ 401.521511] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 18:29:57 (1753741797) [ 407.739755] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 18:30:04 (1753741804) [ 414.872865] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 18:30:11 (1753741811) [ 416.739177] Lustre: lustre-OST0000-osc-ffff9e3718246000: disconnect after 21s idle [ 416.741952] Lustre: Skipped 1 previous similar message [ 421.431117] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 18:30:18 (1753741818) [ 427.402599] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 18:30:24 (1753741824) [ 432.097268] Lustre: lustre-OST0001-osc-ffff9e3709ed4000: disconnect after 24s idle [ 433.860816] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 18:30:30 (1753741830) [ 440.610561] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 18:30:37 (1753741837) [ 447.458448] Lustre: lustre-OST0000-osc-ffff9e3718246000: disconnect after 20s idle [ 448.777591] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 18:30:45 (1753741845) [ 455.572909] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 18:30:52 (1753741852) [ 463.643437] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 18:30:59 (1753741859) [ 470.920987] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 18:31:07 (1753741867) [ 478.236314] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 18:31:14 (1753741874) [ 478.800598] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=144115205289279518 file: /mnt/lustre/lockdir/lockfile=144115205289279517 [ 619.919191] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 18:33:36 (1753742016) [ 627.937372] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 18:33:44 (1753742024) [ 635.556591] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 18:33:52 (1753742032) [ 642.819251] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 18:33:59 (1753742039) [ 650.633398] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 18:34:07 (1753742047) [ 657.362060] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 18:34:14 (1753742054) [ 659.372845] Lustre: DEBUG MARKER: chmod [ 666.599394] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 18:34:23 (1753742063) [ 1456.454082] Lustre: DEBUG MARKER: == sanityn test 16a: 2500 iterations of dual-mount fsx === 18:47:32 (1753742852) [ 1614.816573] Lustre: lustre-OST0001-osc-ffff9e3709ed4000: disconnect after 21s idle [ 1707.507653] Lustre: DEBUG MARKER: == sanityn test 16b: 2500 iterations of dual-mount fsx at small size ========================================================== 18:51:43 (1753743103) [ 1845.712689] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 18:54:01 (1753743241) [ 2012.589601] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 18:56:49 (1753743409) [ 2049.301384] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 18:57:25 (1753743445) [ 2050.019560] Lustre: lustre-OST0001-osc-ffff9e3709ed4000: disconnect after 20s idle [ 2056.536401] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 18:57:32 (1753743452) [ 2057.680720] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2057.776267] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2057.844972] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2057.940222] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2058.050428] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2058.207545] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2058.319542] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2058.424397] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2058.487790] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2058.591348] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2058.754855] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2058.864470] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2058.964402] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2059.060883] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2059.128032] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2059.222478] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2059.321871] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2059.437148] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2059.529911] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2059.626904] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2059.728855] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2059.804254] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2059.893042] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2059.993692] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2060.124183] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2060.230571] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2060.325667] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2060.400294] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2060.456903] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2060.538292] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2060.633298] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2060.719241] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2060.779805] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2060.868070] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2060.946337] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2061.052788] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2061.167682] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2061.271166] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2061.362755] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2061.487049] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2061.598964] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2061.691959] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2061.792234] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2061.852039] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2061.898215] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2062.011378] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2062.106174] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2062.189969] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2062.305315] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2062.476550] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2062.633816] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2062.737897] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2062.792031] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2062.847496] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2062.893355] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2062.962740] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2063.055545] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2063.152109] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2063.236497] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2063.380628] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2063.496167] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2063.588333] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2063.706387] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2063.821835] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2063.956864] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2064.064087] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2064.143051] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2064.199232] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2064.332290] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2064.429039] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2064.513245] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2064.623467] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2064.734887] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2064.822660] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2064.944330] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2065.048655] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2065.167569] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2065.253520] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2065.353735] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2065.424800] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2065.536775] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2065.591040] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2065.692316] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2065.795558] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2065.887157] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2065.999727] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2066.074358] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2066.157168] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2066.240090] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2066.321197] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2066.399582] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2066.517470] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2066.631751] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2066.725156] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2066.847845] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2066.927535] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2067.023769] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2067.129731] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2067.223838] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2067.332183] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2067.459072] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2067.559455] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2067.628759] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2067.725402] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2067.789411] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2067.909393] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2067.996329] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2068.087852] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2068.191650] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2068.289643] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2068.357047] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2068.400769] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2068.528449] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2068.639688] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2068.750637] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2068.877740] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2068.994759] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2069.094903] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2069.177461] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2069.248570] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2069.342829] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2069.454454] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2069.544409] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2069.608909] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2069.696864] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2069.754388] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2069.851709] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2069.946432] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2070.032382] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2070.110219] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2070.171992] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2070.218493] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2070.282708] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2070.353557] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2070.401319] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2070.452680] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2070.497147] Lustre: lustre-OST0000-osc-ffff9e3709ed4000: disconnect after 21s idle [ 2070.518244] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2070.569535] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2070.621508] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2070.705918] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2070.817556] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2070.894064] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2070.972907] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2071.066086] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2071.148720] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2071.271910] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2071.345791] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2071.420982] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2071.509795] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2071.617062] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2071.739568] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2071.817338] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2071.897238] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2071.981260] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2072.032824] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2072.108307] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2072.195831] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2072.261763] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2072.331759] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2072.442313] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2072.554862] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2072.635160] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2072.718839] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2072.818108] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2072.919716] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2073.015188] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2073.086884] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2073.174036] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2073.259308] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2073.344937] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2073.404097] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2073.517490] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2073.569207] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2073.638270] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2073.711649] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2073.803075] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2073.875128] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2073.956061] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2074.056921] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2074.111961] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2074.193669] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2074.248858] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2074.334332] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2074.420727] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2074.463160] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2074.534289] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2074.617142] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2074.718219] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2074.777718] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2074.857831] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2074.966906] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2075.092794] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2075.188114] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2075.283352] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2075.368674] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2075.443698] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2075.530577] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2075.596781] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2075.616181] Lustre: lustre-OST0000-osc-ffff9e3718246000: disconnect after 23s idle [ 2075.690056] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2075.762852] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2075.826817] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2075.898729] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2075.955655] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2076.051929] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2076.174598] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2076.308200] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2076.393027] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2076.485400] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2076.554245] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2076.650462] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2076.722636] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2076.796124] rw_seq_cst_vs_d (32258): drop_caches: 3 [ 2085.014901] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 18:58:01 (1753743481) [ 2085.557069] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2085.706156] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2085.922844] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2085.958461] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2086.120755] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2086.220217] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2086.287844] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2086.401979] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2086.463403] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2086.525744] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2086.649662] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2086.799356] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2086.874082] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2086.958802] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2087.043325] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2087.089380] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2087.287652] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2087.425878] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2087.603166] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2087.682136] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2087.841686] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2087.993567] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2088.117732] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2088.309303] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2088.422650] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2088.521597] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2088.578779] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2088.701628] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2088.787793] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2088.909536] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2089.006721] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2089.119942] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2089.271486] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2089.475277] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2089.583687] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2089.619316] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2089.802318] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2089.995926] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2090.139646] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2090.281357] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2090.372055] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2090.503184] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2090.728488] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2090.793365] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2090.859398] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2091.081843] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2091.185926] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2091.341514] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2091.426888] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2091.544275] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2091.657682] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2091.780194] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2091.973762] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2092.080417] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2092.200826] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2092.363309] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2092.408686] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2092.583634] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2092.773360] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2092.900369] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2093.044954] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2093.158805] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2093.285231] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2093.395538] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2093.573341] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2093.665609] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2093.799399] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2093.929574] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2094.065512] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2094.207194] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2094.305253] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2094.504244] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2094.601967] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2094.808106] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2094.954703] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2095.006587] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2095.088574] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2095.244570] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2095.367986] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2095.522493] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2095.767586] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2095.888854] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2096.057788] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2096.097431] Lustre: lustre-OST0001-osc-ffff9e3709ed4000: disconnect after 20s idle [ 2096.236842] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2096.439401] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2096.537181] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2096.620518] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2096.768922] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2096.862468] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2096.978262] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2097.036234] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2097.107814] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2097.193787] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2097.283557] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2097.437886] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2097.524742] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2097.636282] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2097.826180] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2097.931352] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2098.051829] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2098.172385] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2098.241366] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2098.306991] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2098.462728] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2098.567250] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2098.682958] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2098.903683] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2099.053893] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2099.234204] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2099.397608] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2099.442934] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2099.588433] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2099.637983] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2099.935676] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2100.072305] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2100.201989] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2100.358564] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2100.445426] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2100.553466] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2100.625630] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2100.716686] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2100.782822] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2100.838179] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2100.973157] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2101.259750] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2101.450460] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2101.538954] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2101.697229] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2101.860588] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2101.972135] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2102.062405] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2102.110119] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2102.272931] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2102.359848] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2102.426603] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2102.605038] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2102.778476] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2102.876164] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2103.029844] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2103.103720] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2103.331883] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2103.505473] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2103.608369] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2103.817477] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2103.889620] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2104.049354] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2104.282214] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2104.448909] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2104.632534] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2104.675653] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2104.852850] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2105.023183] rw_seq_cst_vs_d (32834): drop_caches: 3 [ 2106.342690] Lustre: lustre-OST0001-osc-ffff9e3718246000: disconnect after 24s idle [ 2113.416624] Lustre: DEBUG MARKER: == sanityn test 16h: mmap read after truncate file ======= 18:58:30 (1753743510) [ 2120.471984] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 18:58:37 (1753743517) [ 2127.742308] Lustre: DEBUG MARKER: == sanityn test 16j: race dio with buffered i/o ========== 18:58:44 (1753743524) [ 2161.272576] Lustre: DEBUG MARKER: == sanityn test 16k: Parallel FSX and drop caches should not panic ========================================================== 18:59:17 (1753743557) [ 2161.808350] bash (35330): drop_caches: 3 [ 2165.051482] bash (35330): drop_caches: 3 [ 2168.411145] bash (35330): drop_caches: 3 [ 2171.636152] bash (35330): drop_caches: 3 [ 2174.816476] bash (35330): drop_caches: 3 [ 2178.044771] bash (35330): drop_caches: 3 [ 2181.248116] bash (35330): drop_caches: 3 [ 2184.395910] bash (35330): drop_caches: 3 [ 2187.651305] bash (35330): drop_caches: 3 [ 2190.869267] bash (35330): drop_caches: 3 [ 2194.297930] bash (35330): drop_caches: 3 [ 2197.505094] bash (35330): drop_caches: 3 [ 2200.701928] bash (35330): drop_caches: 3 [ 2203.935232] bash (35330): drop_caches: 3 [ 2207.245502] bash (35330): drop_caches: 3 [ 2211.979785] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 19:00:08 (1753743608) [ 2223.297297] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 19:00:19 (1753743619) [ 2282.822605] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 19:01:19 (1753743679) [ 2291.303327] Lustre: DEBUG MARKER: loop 5 [ 2295.776670] Lustre: lustre-OST0001-osc-ffff9e3718246000: disconnect after 22s idle [ 2296.687707] Lustre: DEBUG MARKER: loop 10 [ 2301.961631] Lustre: DEBUG MARKER: loop 15 [ 2306.735734] Lustre: DEBUG MARKER: loop 20 [ 2314.426483] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 19:01:51 (1753743711) [ 2319.943967] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 19:01:56 (1753743716) [ 2325.555214] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 19:02:02 (1753743722) [ 2336.738430] Lustre: lustre-OST0000-osc-ffff9e3718246000: disconnect after 22s idle [ 2336.749856] Lustre: Skipped 1 previous similar message [ 2395.682606] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 19:03:12 (1753743792) [ 2403.519802] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 19:03:19 (1753743799) [ 2411.615697] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 19:03:27 (1753743807) [ 2419.938863] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 19:03:36 (1753743816) [ 2428.875694] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 19:03:44 (1753743824) [ 2438.723807] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 19:03:54 (1753743834) [ 2449.378959] Lustre: lustre-OST0001-osc-ffff9e3718246000: disconnect after 23s idle [ 2449.381391] Lustre: Skipped 3 previous similar messages [ 2449.763450] Lustre: DEBUG MARKER: == sanityn test 26c: set-in-past on open file is not lost on close ========================================================== 19:04:06 (1753743846) [ 2458.744159] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 2460.442743] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 19:04:17 (1753743857) [ 2469.132879] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 19:04:25 (1753743865) [ 2469.737750] Lustre: *** cfs_fail_loc=314, val=0*** [ 2470.816898] Lustre: *** cfs_fail_loc=314, val=0*** [ 2470.819389] Lustre: Skipped 2 previous similar messages [ 2477.269226] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 19:04:34 (1753743874) [ 2486.385432] Lustre: *** cfs_fail_loc=314, val=0*** [ 2486.472280] LustreError: lustre-OST0000-osc-ffff9e3709ed4000: operation ldlm_enqueue to node 192.168.202.109@tcp failed: rc = -107 [ 2486.478083] Lustre: lustre-OST0000-osc-ffff9e3709ed4000: Connection to lustre-OST0000 (at 192.168.202.109@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2486.503492] LustreError: lustre-OST0000-osc-ffff9e3709ed4000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 2486.518390] LustreError: 46269:0:(ldlm_resource.c:981:ldlm_resource_complain()) lustre-OST0000-osc-ffff9e3709ed4000: namespace resource [0x280000401:0x35:0x0].0x0 (ffff9e372102ed00) refcount nonzero (1) after lock cleanup; forcing cleanup. [ 2486.532334] Lustre: lustre-OST0000-osc-ffff9e3709ed4000: Connection restored to (at 192.168.202.109@tcp) [ 2493.810833] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 19:04:50 (1753743890) [ 2494.125293] LustreError: 46860:0:(file.c:760:ll_intent_file_open()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 2497.161068] LustreError: 46860:0:(file.c:760:ll_intent_file_open()) cfs_fail_timeout id 1419 awake [ 2503.867309] Lustre: DEBUG MARKER: == sanityn test 31s: open should not revalidate invalid dentry ========================================================== 19:05:00 (1753743900) [ 2511.657566] Lustre: DEBUG MARKER: == sanityn test 31t: getattr should not revalidate invalid dentry ========================================================== 19:05:08 (1753743908) [ 2520.617461] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 2522.370149] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 19:05:18 (1753743918) [ 2524.749362] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 2526.978664] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-on-Lock-Cancel ========================================================== 19:05:23 (1753743923) [ 2531.334796] Lustre: lustre-MDT0000-mdc-ffff9e3718246000: Connection to lustre-MDT0000 (at 192.168.202.109@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2541.549646] LustreError: MGC192.168.202.109@tcp: Connection to MGS (at 192.168.202.109@tcp) was lost; in progress operations using this service will fail [ 2541.566736] Lustre: Evicted from MGS (at 192.168.202.109@tcp) after server handle changed from 0xf600a0f796b1a1fb to 0xf600a0f796bc4a42 [ 2541.583509] Lustre: MGC192.168.202.109@tcp: Connection restored to (at 192.168.202.109@tcp) [ 2544.301419] Lustre: lustre-MDT0000-mdc-ffff9e3718246000: Connection restored to (at 192.168.202.109@tcp) [ 2573.463074] Lustre: DEBUG MARKER: == sanityn test 33d: dependent transactions should trigger COS ========================================================== 19:06:10 (1753743970) [ 2625.961623] Lustre: DEBUG MARKER: == sanityn test 33e: independent transactions shouldn't trigger COS ========================================================== 19:07:02 (1753744022) [ 2650.553648] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 19:07:26 (1753744046) [ 2664.416335] Lustre: lustre-OST0000-osc-ffff9e3709ed4000: disconnect after 23s idle [ 2664.428084] Lustre: Skipped 4 previous similar messages [ 2704.301396] Lustre: lustre-OST0000-osc-ffff9e3718246000: Connection to lustre-OST0000 (at 192.168.202.109@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2704.322522] Lustre: Skipped 1 previous similar message [ 2704.346325] LustreError: lustre-OST0000-osc-ffff9e3718246000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 2704.373459] Lustre: lustre-OST0000-osc-ffff9e3718246000: Connection restored to (at 192.168.202.109@tcp) [ 2704.393746] Lustre: Skipped 1 previous similar message [ 2704.396975] LustreError: lustre-OST0000-osc-ffff9e3709ed4000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 2719.720195] Lustre: lustre-OST0001-osc-ffff9e3718246000: Connection to lustre-OST0001 (at 192.168.202.109@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2719.730398] Lustre: Skipped 1 previous similar message [ 2719.746414] LustreError: lustre-OST0001-osc-ffff9e3718246000: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 2719.760356] Lustre: lustre-OST0001-osc-ffff9e3718246000: Connection restored to (at 192.168.202.109@tcp) [ 2719.773157] Lustre: Skipped 1 previous similar message [ 2738.819840] Lustre: DEBUG MARKER: oleg209-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9e3709ed4000.ost_server_uuid 50 [ 2740.299634] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9e3709ed4000.ost_server_uuid in IDLE state after 0 sec [ 2743.795022] Lustre: DEBUG MARKER: oleg209-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9e3709ed4000.ost_server_uuid 50 [ 2745.408475] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9e3709ed4000.ost_server_uuid in FULL state after 0 sec [ 2750.907654] Lustre: DEBUG MARKER: oleg209-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9e3709ed4000.ost_server_uuid 50 [ 2752.746281] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9e3709ed4000.ost_server_uuid in IDLE state after 0 sec [ 2757.288257] Lustre: DEBUG MARKER: oleg209-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9e3709ed4000.ost_server_uuid 50 [ 2758.827425] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9e3709ed4000.ost_server_uuid in FULL state after 0 sec [ 2768.817526] Lustre: DEBUG MARKER: oleg209-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9e3709ed4000.ost_server_uuid 50 [ 2770.459535] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9e3709ed4000.ost_server_uuid in IDLE state after 0 sec [ 2773.980275] Lustre: DEBUG MARKER: oleg209-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9e3709ed4000.ost_server_uuid 50 [ 2775.371444] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9e3709ed4000.ost_server_uuid in FULL state after 0 sec [ 2776.837820] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 19:09:33 (1753744173) [ 2779.919966] Lustre: DEBUG MARKER: Race attempt 0 [ 2782.934079] Lustre: DEBUG MARKER: Wait for 57432 57471 for 60 sec... [ 2849.072555] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 19:10:45 (1753744245) [ 2857.234808] Lustre: DEBUG MARKER: start test - cycle (0) [ 2876.814937] Lustre: DEBUG MARKER: start test - cycle (1) [ 2898.497574] Lustre: DEBUG MARKER: start test - cycle (2) [ 2925.536367] Lustre: lustre-OST0000-osc-ffff9e3718246000: disconnect after 21s idle [ 2925.539054] Lustre: Skipped 3 previous similar messages [ 2932.582588] Lustre: DEBUG MARKER: start test - cycle (3) [ 2955.073674] Lustre: DEBUG MARKER: start test - cycle (4) [ 2975.181228] Lustre: DEBUG MARKER: start test - cycle (5) [ 2994.899911] Lustre: DEBUG MARKER: start test - cycle (6) [ 3016.867867] Lustre: DEBUG MARKER: start test - cycle (7) [ 3038.920277] Lustre: DEBUG MARKER: start test - cycle (8) [ 3059.773536] Lustre: DEBUG MARKER: start test - cycle (9) [ 3083.229970] Lustre: DEBUG MARKER: start test - cycle (10) [ 3114.260967] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 19:15:10 (1753744510) [ 3197.582711] Lustre: DEBUG MARKER: == sanityn test 39a: file mtime does not change after rename ========================================================== 19:16:34 (1753744594) [ 3203.951429] Lustre: DEBUG MARKER: == sanityn test 39b: file mtime the same on clients with/out lock ========================================================== 19:16:40 (1753744600) [ 3212.504740] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 19:16:49 (1753744609) [ 3220.609670] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 19:16:57 (1753744617) [ 3220.927818] Lustre: *** cfs_fail_loc=411, val=0*** [ 3227.980148] Lustre: DEBUG MARKER: SKIP: sanityn test_40a skipping ALWAYS excluded test 40a [ 3229.651580] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 19:17:06 (1753744626) [ 3246.547314] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 19:17:23 (1753744643) [ 3262.226214] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 19:17:39 (1753744659) [ 3277.147989] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 19:17:53 (1753744673) [ 3290.868394] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 19:18:07 (1753744687) [ 3300.765491] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 19:18:17 (1753744697) [ 3311.182911] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 19:18:27 (1753744707) [ 3321.690452] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 19:18:38 (1753744718) [ 3332.034634] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 19:18:49 (1753744729) [ 3343.232970] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 19:19:00 (1753744740) [ 3354.599492] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 19:19:11 (1753744751) [ 3366.055542] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 19:19:22 (1753744762) [ 3376.973389] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 19:19:34 (1753744774) [ 4000.736644] Lustre: lustre-OST0000-osc-ffff9e3709ed4000: disconnect after 24s idle [ 4000.739637] Lustre: Skipped 14 previous similar messages [ 4413.530163] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 19:36:50 (1753745810) [ 4425.033570] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 19:37:01 (1753745821) [ 4437.147577] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 19:37:13 (1753745833) [ 4449.697715] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 19:37:26 (1753745846) [ 4461.960804] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 19:37:38 (1753745858) [ 4472.856259] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 19:37:49 (1753745869) [ 4485.286259] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 19:38:02 (1753745882) [ 4498.172662] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 19:38:14 (1753745894) [ 4510.652417] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 19:38:27 (1753745907) [ 4599.692580] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 19:39:56 (1753745996) [ 4610.144959] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 19:40:07 (1753746007) [ 4620.289913] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 19:40:17 (1753746017) [ 4630.659809] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 19:40:27 (1753746027) [ 4641.829992] Lustre: DEBUG MARKER: == sanityn test 43f: pdirops: unlink and rename (src) ======================================================================== 19:40:38 (1753746038) [ 4651.176549] Lustre: DEBUG MARKER: == sanityn test 43g: pdirops: unlink vs getattr ======================================================================== 19:40:48 (1753746048) [ 4661.549591] Lustre: DEBUG MARKER: == sanityn test 43h: pdirops: unlink vs readdir ======================================================================== 19:40:58 (1753746058) [ 4671.085691] Lustre: DEBUG MARKER: == sanityn test 43i: pdirops: unlink vs remote mkdir ===== 19:41:08 (1753746068) [ 4679.890558] Lustre: DEBUG MARKER: == sanityn test 43j: racy mkdir return EEXIST ======================================================================== 19:41:17 (1753746077) [ 4691.938159] Lustre: lustre-OST0001-osc-ffff9e3718246000: disconnect after 24s idle [ 4691.941964] Lustre: Skipped 4 previous similar messages [ 4787.522806] Lustre: DEBUG MARKER: == sanityn test 43k: unlink vs create ==================== 19:43:04 (1753746184) [ 5612.248598] Lustre: DEBUG MARKER: == sanityn test 44a: pdirops: rename tgt vs mkdir ======================================================================== 19:56:49 (1753747009) [ 5620.113115] Lustre: DEBUG MARKER: == sanityn test 44b: pdirops: rename tgt vs create ======================================================================== 19:56:57 (1753747017) [ 5623.776329] Lustre: lustre-OST0000-osc-ffff9e3709ed4000: disconnect after 22s idle [ 5623.779994] Lustre: Skipped 5 previous similar messages [ 5628.137700] Lustre: DEBUG MARKER: == sanityn test 44c: pdirops: rename tgt vs link ======================================================================== 19:57:05 (1753747025) [ 5636.106573] Lustre: DEBUG MARKER: == sanityn test 44d: pdirops: rename tgt vs unlink ======================================================================== 19:57:13 (1753747033) [ 5643.431864] Lustre: DEBUG MARKER: == sanityn test 44e: pdirops: rename tgt and rename (tgt) ======================================================================== 19:57:20 (1753747040) [ 5651.560173] Lustre: DEBUG MARKER: == sanityn test 44f: pdirops: rename tgt and rename (src) ======================================================================== 19:57:28 (1753747048) [ 5659.225622] Lustre: DEBUG MARKER: == sanityn test 44g: pdirops: rename tgt vs getattr ======================================================================== 19:57:36 (1753747056) [ 5666.926811] Lustre: DEBUG MARKER: == sanityn test 44h: pdirops: rename tgt vs readdir ======================================================================== 19:57:44 (1753747064) [ 5674.587126] Lustre: DEBUG MARKER: == sanityn test 44i: pdirops: rename tgt vs remote mkdir ========================================================== 19:57:51 (1753747071) [ 5682.598376] Lustre: DEBUG MARKER: == sanityn test 45a: rename,mkdir doesn't return -EEXIST ======================================================================== 19:57:59 (1753747079) [ 5765.114359] Lustre: DEBUG MARKER: == sanityn test 45b: pdirops: rename src vs create ======================================================================== 19:59:22 (1753747162) [ 5772.656075] Lustre: DEBUG MARKER: == sanityn test 45c: pdirops: rename src vs link ======================================================================== 19:59:29 (1753747169) [ 5780.729694] Lustre: DEBUG MARKER: == sanityn test 45d: pdirops: rename src vs unlink ======================================================================== 19:59:37 (1753747177) [ 5788.745815] Lustre: DEBUG MARKER: == sanityn test 45e: pdirops: rename src and rename (tgt) ======================================================================== 19:59:46 (1753747186) [ 5796.424461] Lustre: DEBUG MARKER: == sanityn test 45f: pdirops: rename src and rename (src) ======================================================================== 19:59:53 (1753747193) [ 5804.231692] Lustre: DEBUG MARKER: == sanityn test 45g: pdirops: rename src vs getattr ======================================================================== 20:00:01 (1753747201) [ 5811.948415] Lustre: DEBUG MARKER: == sanityn test 45h: pdirops: unlink vs readdir ======================================================================== 20:00:09 (1753747209) [ 5818.887049] Lustre: DEBUG MARKER: == sanityn test 45i: pdirops: rename src vs remote mkdir ========================================================== 20:00:16 (1753747216) [ 5826.851812] Lustre: DEBUG MARKER: == sanityn test 45j: read vs rename ====================== 20:00:23 (1753747223) [ 6437.856664] Lustre: lustre-OST0001-osc-ffff9e3718246000: disconnect after 23s idle [ 6437.861294] Lustre: Skipped 5 previous similar messages [ 6464.842463] Lustre: DEBUG MARKER: == sanityn test 46a: pdirops: link vs mkdir ======================================================================== 20:11:02 (1753747862) [ 6471.544646] Lustre: DEBUG MARKER: == sanityn test 46b: pdirops: link vs create ======================================================================== 20:11:08 (1753747868) [ 6478.598687] Lustre: DEBUG MARKER: == sanityn test 46c: pdirops: link vs link ======================================================================== 20:11:15 (1753747875) [ 6485.913727] Lustre: DEBUG MARKER: == sanityn test 46d: pdirops: link vs unlink ======================================================================== 20:11:23 (1753747883) [ 6492.486944] Lustre: DEBUG MARKER: == sanityn test 46e: pdirops: link and rename (tgt) ======================================================================== 20:11:29 (1753747889) [ 6499.661376] Lustre: DEBUG MARKER: == sanityn test 46f: pdirops: link and rename (src) ======================================================================== 20:11:36 (1753747896) [ 6506.420146] Lustre: DEBUG MARKER: == sanityn test 46g: pdirops: link vs getattr ======================================================================== 20:11:43 (1753747903) [ 6513.044418] Lustre: DEBUG MARKER: == sanityn test 46h: pdirops: link vs readdir ======================================================================== 20:11:50 (1753747910) [ 6519.089778] Lustre: DEBUG MARKER: == sanityn test 46i: pdirops: link vs remote mkdir ======= 20:11:56 (1753747916) [ 6525.451707] Lustre: DEBUG MARKER: == sanityn test 47a: pdirops: remote mkdir vs mkdir ====== 20:12:02 (1753747922) [ 6531.915025] Lustre: DEBUG MARKER: == sanityn test 47b: pdirops: remote mkdir vs create ===== 20:12:09 (1753747929) [ 6539.005952] Lustre: DEBUG MARKER: == sanityn test 47c: pdirops: remote mkdir vs link ======= 20:12:16 (1753747936) [ 6544.965420] Lustre: DEBUG MARKER: == sanityn test 47d: pdirops: remote mkdir vs unlink ===== 20:12:22 (1753747942) [ 6550.892110] Lustre: DEBUG MARKER: == sanityn test 47e: pdirops: remote mkdir and rename (tgt) ========================================================== 20:12:28 (1753747948) [ 6557.193624] Lustre: DEBUG MARKER: == sanityn test 47f: pdirops: remote mkdir and rename (src) ========================================================== 20:12:34 (1753747954) [ 6563.418822] Lustre: DEBUG MARKER: == sanityn test 47g: pdirops: remote mkdir vs getattr ==== 20:12:40 (1753747960) [ 6571.806923] Lustre: DEBUG MARKER: == sanityn test 50: osc lvb attrs: enqueue vs. CP AST ======================================================================== 20:12:48 (1753747968) [ 6571.944729] LustreError: 6128:0:(ldlm_lockd.c:2073:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 sleeping for 2000ms [ 6574.032225] LustreError: 6128:0:(ldlm_lockd.c:2073:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 awake [ 6580.276136] Lustre: DEBUG MARKER: == sanityn test 51a: layout lock: refresh layout should work ========================================================== 20:12:57 (1753747977) [ 6585.603587] Lustre: DEBUG MARKER: == sanityn test 51b: layout lock: glimpse should be able to restart if layout changed ========================================================== 20:13:02 (1753747982) [ 6585.769981] LustreError: 284716:0:(glimpse.c:207:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 6589.832181] LustreError: 284716:0:(glimpse.c:207:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 6589.845218] LustreError: 284716:0:(glimpse.c:207:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 6593.912825] LustreError: 284716:0:(glimpse.c:207:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 6593.934865] LustreError: 284722:0:(glimpse.c:207:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 6597.992170] LustreError: 284722:0:(glimpse.c:207:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 6601.299385] Lustre: DEBUG MARKER: == sanityn test 51c: layout lock: IT_LAYOUT blocked and correct layout can be returned ========================================================== 20:13:18 (1753747998) [ 6608.799416] Lustre: DEBUG MARKER: == sanityn test 51d: layout lock: losing layout lock should clean up memory map region ========================================================== 20:13:26 (1753748006) [ 6612.804055] Lustre: DEBUG MARKER: == sanityn test 51e: lfs getstripe does not break leases, part 2 ========================================================== 20:13:30 (1753748010) [ 6617.739779] Lustre: DEBUG MARKER: == sanityn test 54: rename locking ======================= 20:13:35 (1753748015) [ 6642.729601] Lustre: DEBUG MARKER: == sanityn test 55a: rename vs unlink target dir ========= 20:14:00 (1753748040) [ 6650.768214] Lustre: DEBUG MARKER: == sanityn test 55b: rename vs unlink source dir ========= 20:14:08 (1753748048) [ 6658.966636] Lustre: DEBUG MARKER: == sanityn test 55c: rename vs unlink orphan target dir == 20:14:16 (1753748056) [ 6672.555537] Lustre: DEBUG MARKER: == sanityn test 55d: rename file vs link ================= 20:14:30 (1753748070) [ 6682.875378] Lustre: DEBUG MARKER: == sanityn test 55e: rename race AB/BA under the same parent dir ========================================================== 20:14:40 (1753748080) [ 6696.223927] Lustre: DEBUG MARKER: == sanityn test 55f: rename: (P1/A -> P2/B) race with (P2/B -> P1/A) ========================================================== 20:14:53 (1753748093) [ 6709.367125] Lustre: DEBUG MARKER: == sanityn test 55g: rename: race with trylock =========== 20:15:06 (1753748106) [ 6723.934704] Lustre: DEBUG MARKER: == sanityn test 56a: test llverdev with single large stripe ========================================================== 20:15:21 (1753748121) [ 6731.727579] Lustre: DEBUG MARKER: == sanityn test 56b: test llverdev and partial verify of wide stripe file ========================================================== 20:15:29 (1753748129) [ 6761.474908] Lustre: DEBUG MARKER: == sanityn test 60: Verify data_version behaviour ======== 20:15:59 (1753748159) [ 6763.852502] Lustre: DEBUG MARKER: == sanityn test 70a: cd directory [ 6767.330814] Lustre: DEBUG MARKER: == sanityn test 70b: remove files after calling rm_entry ========================================================== 20:16:04 (1753748164) [ 6769.872644] Lustre: DEBUG MARKER: == sanityn test 71a: correct file map just after write operation is finished ========================================================== 20:16:07 (1753748167) [ 6772.187217] Lustre: DEBUG MARKER: == sanityn test 71b: check fiemap support for stripecount > 1 ========================================================== 20:16:09 (1753748169) [ 6774.290755] Lustre: DEBUG MARKER: == sanityn test 71c: check FIEMAP_EXTENT_LAST flag with different extents number ========================================================== 20:16:11 (1753748171) [ 6785.901995] Lustre: DEBUG MARKER: == sanityn test 71d: fiemap corruption test with fm_extent_count=0 ========================================================== 20:16:23 (1753748183) [ 6800.370095] Lustre: DEBUG MARKER: == sanityn test 72: getxattr/setxattr cache should be consistent between nodes ========================================================== 20:16:37 (1753748197) [ 6802.910870] Lustre: DEBUG MARKER: == sanityn test 73: getxattr should not cause xattr lock cancellation ========================================================== 20:16:40 (1753748200) [ 6805.374254] Lustre: DEBUG MARKER: == sanityn test 74: flock deadlock: different mounts ======================================================================== 20:16:42 (1753748202) [ 6808.449132] LustreError: lustre-MDT0000-mdc-ffff9e3718246000: operation ldlm_enqueue to node 192.168.202.109@tcp failed: rc = -35 [ 6811.703644] Lustre: DEBUG MARKER: == sanityn test 75: osc: upcall after unuse lock============================================================================= 20:16:49 (1753748209) [ 6811.871364] LustreError: 2401:0:(osc_request.c:3086:osc_enqueue_interpret()) cfs_fail_timeout id 31d sleeping for 2000ms [ 6813.952129] LustreError: 2401:0:(osc_request.c:3086:osc_enqueue_interpret()) cfs_fail_timeout id 31d awake [ 6818.968174] Lustre: DEBUG MARKER: == sanityn test 76: Verify MDT open_files listing ======== 20:16:56 (1753748216) [ 6869.084305] Lustre: DEBUG MARKER: == sanityn test 77a: check FIFO NRS policy =============== 20:17:46 (1753748266) [ 6872.454493] Lustre: DEBUG MARKER: == sanityn test 77b: check CRR-N NRS policy ============== 20:17:49 (1753748269) [ 6877.243403] Lustre: DEBUG MARKER: == sanityn test 77c: check ORR NRS policy ================ 20:17:54 (1753748274) [ 6882.815911] Lustre: DEBUG MARKER: == sanityn test 77d: check TRR nrs policy ================ 20:18:00 (1753748280) [ 6888.513230] Lustre: DEBUG MARKER: == sanityn test 77e: check TBF NID nrs policy ============ 20:18:06 (1753748286) [ 6896.147989] Lustre: DEBUG MARKER: == sanityn test 77f: check TBF JobID nrs policy ========== 20:18:13 (1753748293) [ 6904.104689] Lustre: DEBUG MARKER: == sanityn test 77g: Change TBF type directly ============ 20:18:21 (1753748301) [ 6907.735475] Lustre: DEBUG MARKER: == sanityn test 77h: Wrong policy name should report error, not LBUG ========================================================== 20:18:25 (1753748305) [ 6911.763928] Lustre: DEBUG MARKER: == sanityn test 77i: Change rank of TBF rule ============= 20:18:29 (1753748309) [ 6919.914568] Lustre: DEBUG MARKER: == sanityn test 77j: check TBF-OPCode NRS policy ========= 20:18:37 (1753748317) [ 6962.539844] Lustre: DEBUG MARKER: == sanityn test 77ja: check TBF-UID/GID NRS policy ======= 20:19:20 (1753748360) [ 7042.016205] Lustre: lustre-OST0001-osc-ffff9e3718246000: disconnect after 24s idle [ 7042.017947] Lustre: Skipped 12 previous similar messages [ 7075.342289] Lustre: DEBUG MARKER: == sanityn test 77jb: check TBF-UID/GID NRS policy on files that don't belong to us ========================================================== 20:21:12 (1753748472) [ 7187.644308] Lustre: DEBUG MARKER: == sanityn test 77k: check TBF policy with UID/GID/JobID/OPCode expression ========================================================== 20:23:05 (1753748585) [ 7460.087117] Lustre: DEBUG MARKER: == sanityn test 77l: check the output of NRS policies for generic TBF ========================================================== 20:27:37 (1753748857) [ 7463.378842] Lustre: DEBUG MARKER: == sanityn test 77m: check NRS Delay slows write RPC processing ========================================================== 20:27:40 (1753748860) [ 7513.913320] Lustre: DEBUG MARKER: == sanityn test 77n: check wildcard support for TBF JobID NRS policy ========================================================== 20:28:31 (1753748911) [ 7555.937699] Lustre: DEBUG MARKER: == sanityn test 77o: Changing rank should not panic ====== 20:29:13 (1753748953) [ 7559.823712] Lustre: DEBUG MARKER: == sanityn test 77q: Parallel TBF rule definitions should not panic ========================================================== 20:29:17 (1753748957) [ 7600.257863] Lustre: DEBUG MARKER: == sanityn test 77p: Check validity of rule names for TBF policies ========================================================== 20:29:57 (1753748997) [ 7611.439221] Lustre: DEBUG MARKER: == sanityn test 77r: Change type of tbf policy at run time ========================================================== 20:30:09 (1753749009) [ 7653.269922] Lustre: DEBUG MARKER: == sanityn test 78: Enable policy and specify tunings right away ========================================================== 20:30:50 (1753749050) [ 7656.433628] Lustre: DEBUG MARKER: == sanityn test 79: xattr: intent error ================== 20:30:53 (1753749053) [ 7669.300710] Lustre: DEBUG MARKER: == sanityn test 80a: migrate directory when some children is being opened ========================================================== 20:31:06 (1753749066) [ 7673.260022] Lustre: DEBUG MARKER: == sanityn test 80b: Accessing directory during migration ========================================================== 20:31:10 (1753749070) [ 7673.575091] LustreError: 340128:0:(lcommon_cl.c:188:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000402:0x98e:0x0]: rc = -5 [ 7673.580267] LustreError: 340128:0:(llite_lib.c:3770:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 7674.075895] LustreError: 339968:0:(lcommon_cl.c:188:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000402:0x9a9:0x0]: rc = -5 [ 7674.079072] LustreError: 339968:0:(lcommon_cl.c:188:cl_file_inode_init()) Skipped 13 previous similar messages [ 7674.081907] LustreError: 339968:0:(llite_lib.c:3770:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 7674.084479] LustreError: 339968:0:(llite_lib.c:3770:ll_prep_inode()) Skipped 13 previous similar messages [ 7675.140670] LustreError: 340251:0:(lcommon_cl.c:188:cl_file_inode_init()) lustre: failed to initialize cl_object [0x200000402:0xd06:0x0]: rc = -5 [ 7675.146256] LustreError: 340251:0:(lcommon_cl.c:188:cl_file_inode_init()) Skipped 21 previous similar messages [ 7675.150447] LustreError: 340251:0:(llite_lib.c:3770:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 7675.153135] LustreError: 340251:0:(llite_lib.c:3770:ll_prep_inode()) Skipped 21 previous similar messages [ 7677.215175] LustreError: 340438:0:(lcommon_cl.c:188:cl_file_inode_init()) lustre: failed to initialize cl_object [0x200000402:0xd3a:0x0]: rc = -5 [ 7677.218300] LustreError: 340438:0:(lcommon_cl.c:188:cl_file_inode_init()) Skipped 53 previous similar messages [ 7677.220891] LustreError: 340438:0:(llite_lib.c:3770:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 7677.222945] LustreError: 340438:0:(llite_lib.c:3770:ll_prep_inode()) Skipped 53 previous similar messages [ 7681.355568] LustreError: 340768:0:(lcommon_cl.c:188:cl_file_inode_init()) lustre: failed to initialize cl_object [0x200000402:0xd9f:0x0]: rc = -5 [ 7681.360733] LustreError: 340768:0:(lcommon_cl.c:188:cl_file_inode_init()) Skipped 95 previous similar messages [ 7681.363473] LustreError: 340768:0:(llite_lib.c:3770:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 7681.365611] LustreError: 340768:0:(llite_lib.c:3770:ll_prep_inode()) Skipped 95 previous similar messages [ 7712.736229] Lustre: lustre-OST0001-osc-ffff9e3709ed4000: disconnect after 23s idle [ 7712.738476] Lustre: Skipped 7 previous similar messages [ 7785.100315] LustreError: 340974:0:(lcommon_cl.c:188:cl_file_inode_init()) lustre: failed to initialize cl_object [0x200000402:0xdd1:0x0]: rc = -5 [ 7785.103612] LustreError: 340974:0:(lcommon_cl.c:188:cl_file_inode_init()) Skipped 57 previous similar messages [ 7785.105720] LustreError: 340974:0:(llite_lib.c:3770:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 7785.107720] LustreError: 340974:0:(llite_lib.c:3770:ll_prep_inode()) Skipped 57 previous similar messages [ 7785.112956] LustreError: lustre-MDT0000-mdc-ffff9e3718246000: operation mds_getattr_lock to node 192.168.202.109@tcp failed: rc = -107 [ 7785.116469] Lustre: lustre-MDT0000-mdc-ffff9e3718246000: Connection to lustre-MDT0000 (at 192.168.202.109@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7785.121578] LustreError: lustre-MDT0000-mdc-ffff9e3718246000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 7785.126576] LustreError: 340970:0:(file.c:248:ll_close_inode_openhandle()) lustre-clilmv-ffff9e3718246000: inode [0x200000402:0xdce:0x0] mdc close failed: rc = -108 [ 7785.131269] Lustre: lustre-MDT0000-mdc-ffff9e3718246000: Connection restored to (at 192.168.202.109@tcp) [ 7787.423018] Lustre: DEBUG MARKER: == sanityn test 81a: rename and stat under striped directory ========================================================== 20:33:04 (1753749184) [ 7789.720739] Lustre: DEBUG MARKER: == sanityn test 81b: rename under striped directory doesn't deadlock ========================================================== 20:33:07 (1753749187) [ 7838.961081] Lustre: DEBUG MARKER: == sanityn test 81c: rename revoke LOOKUP lock for remote object ========================================================== 20:33:56 (1753749236) [ 7839.484832] Lustre: DEBUG MARKER: SKIP: sanityn test_81c needs >= 4 MDTs [ 7840.100869] Lustre: DEBUG MARKER: == sanityn test 81d: parallel rename file cross-dir on same MDT ========================================================== 20:33:57 (1753749237) [ 7886.797234] Lustre: DEBUG MARKER: == sanityn test 82: fsetxattr and fgetxattr on orphan files ========================================================== 20:34:44 (1753749284) [ 7889.193608] Lustre: DEBUG MARKER: == sanityn test 83: access striped directory while it is being created/unlinked ========================================================== 20:34:46 (1753749286) [ 8011.414072] Lustre: DEBUG MARKER: == sanityn test 84: 0-nlink race in lu_object_find() ===== 20:36:48 (1753749408) [ 8018.867981] Lustre: DEBUG MARKER: == sanityn test 85: Lustre API root cache race =========== 20:36:56 (1753749416) [ 8021.843702] Lustre: DEBUG MARKER: == sanityn test 90: open/create and unlink striped directory ========================================================== 20:36:59 (1753749419) [ 8204.167933] Lustre: DEBUG MARKER: == sanityn test 91: chmod and unlink striped directory === 20:40:01 (1753749601) [ 8386.577040] Lustre: DEBUG MARKER: == sanityn test 92: create remote directory under orphan directory ========================================================== 20:43:04 (1753749784) [ 8388.957382] Lustre: DEBUG MARKER: == sanityn test 93: alloc_rr should not allocate on same ost ========================================================== 20:43:06 (1753749786) [ 8397.960593] Lustre: DEBUG MARKER: == sanityn test 94: signal vs CP callback race =========== 20:43:15 (1753749795) [ 8398.033701] Lustre: DEBUG MARKER: write [ 8398.049793] LustreError: 34683:0:(ldlm_request.c:1401:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 sleeping for 5000ms [ 8400.055209] Lustre: DEBUG MARKER: kill 400608 [ 8400.057893] LustreError: 400608:0:(ldlm_request.c:1286:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 sleeping for 6000ms [ 8403.152139] LustreError: 34683:0:(ldlm_request.c:1401:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 awake [ 8406.096135] LustreError: 400608:0:(ldlm_request.c:1286:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 awake [ 8408.479137] Lustre: DEBUG MARKER: == sanityn test 95a: Check readpage() on a page that was removed from page cache ========================================================== 20:43:25 (1753749805) [ 8408.656236] LustreError: 6137:0:(rw26.c:151:ll_invalidatepage()) cfs_fail_timeout id 1421 sleeping for 7000ms [ 8415.728122] LustreError: 6137:0:(rw26.c:151:ll_invalidatepage()) cfs_fail_timeout id 1421 awake [ 8415.732510] LustreError: 401221:0:(rw.c:1967:ll_readpage()) cfs_fail_timeout id 1422 sleeping for 10000ms [ 8425.824141] LustreError: 401221:0:(rw.c:1967:ll_readpage()) cfs_fail_timeout id 1422 awake [ 8428.142939] Lustre: DEBUG MARKER: == sanityn test 95b: Check readpage() on a page that is no longer uptodate ========================================================== 20:43:45 (1753749825) [ 8428.243080] LustreError: 401809:0:(rw.c:2201:ll_readpage()) cfs_fail_timeout id 1424 sleeping for 5000ms [ 8430.328067] LustreError: 401809:0:(rw.c:2201:ll_readpage()) cfs_fail_timeout interrupted [ 8436.273860] Lustre: DEBUG MARKER: == sanityn test 100a: DoM: glimpse RPCs for stat without IO lock (DoM only file) ========================================================== 20:43:53 (1753749833) [ 8436.767534] Lustre: DEBUG MARKER: SKIP: sanityn test_100a Reserved for glimpse-ahead [ 8437.325349] Lustre: DEBUG MARKER: == sanityn test 100b: DoM: no glimpse RPC for stat with IO lock (DoM only file) ========================================================== 20:43:54 (1753749834) [ 8439.792668] Lustre: DEBUG MARKER: == sanityn test 100c: DoM: write vs stat without IO lock (combined file) ========================================================== 20:43:57 (1753749837) [ 8442.185619] Lustre: DEBUG MARKER: == sanityn test 100d: DoM: write+truncate vs stat without IO lock (combined file) ========================================================== 20:43:59 (1753749839) [ 8444.562107] Lustre: DEBUG MARKER: == sanityn test 100e: DoM: read on open and file size ==== 20:44:02 (1753749842) [ 8447.028313] Lustre: DEBUG MARKER: == sanityn test 101a: Discard DoM data on unlink ========= 20:44:04 (1753749844) [ 8449.341953] Lustre: DEBUG MARKER: == sanityn test 101b: Discard DoM data on rename ========= 20:44:06 (1753749846) [ 8451.745175] Lustre: DEBUG MARKER: == sanityn test 101c: Discard DoM data on close-unlink === 20:44:09 (1753749849) [ 8455.015839] Lustre: DEBUG MARKER: == sanityn test 102: Test open by handle of unlinked file ========================================================== 20:44:12 (1753749852) [ 8457.639296] Lustre: DEBUG MARKER: == sanityn test 103: Test size correctness with lockahead ========================================================== 20:44:15 (1753749855) [ 8458.309099] Lustre: *** cfs_fail_loc=415, val=0*** [ 8465.065470] Lustre: DEBUG MARKER: == sanityn test 104: Verify that MDS stores atime/mtime/ctime during close ========================================================== 20:44:22 (1753749862) [ 8484.624151] Lustre: DEBUG MARKER: == sanityn test 105: Glimpse and lock cancel race ======== 20:44:42 (1753749882) [ 8484.743091] LustreError: 6137:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 sleeping for 5000ms [ 8484.747842] LustreError: 6137:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 3 previous similar messages [ 8489.744096] LustreError: 6137:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [ 8507.326316] Lustre: DEBUG MARKER: == sanityn test 106a: Verify the btime via statx() ======= 20:45:04 (1753749904) [ 8509.927584] Lustre: DEBUG MARKER: == sanityn test 106b: Glimpse RPCs test for statx ======== 20:45:07 (1753749907) [ 8512.400422] Lustre: DEBUG MARKER: == sanityn test 106c: Verify statx attributes mask ======= 20:45:09 (1753749909) [ 8514.609435] Lustre: DEBUG MARKER: == sanityn test 107a: Basic grouplock conflict =========== 20:45:12 (1753749912) [ 8518.991172] Lustre: DEBUG MARKER: == sanityn test 107b: Grouplock is added to the head of waiting list ========================================================== 20:45:16 (1753749916) [ 8527.349309] Lustre: DEBUG MARKER: == sanityn test 108a: lseek: parallel updates ============ 20:45:24 (1753749924) [ 8527.507281] LustreError: 412536:0:(osc_request.c:2937:osc_build_rpc()) cfs_fail_timeout id 414 sleeping for 4000ms [ 8527.509510] LustreError: 412536:0:(osc_request.c:2937:osc_build_rpc()) Skipped 7 previous similar messages [ 8531.568107] LustreError: 412536:0:(osc_request.c:2937:osc_build_rpc()) cfs_fail_timeout id 414 awake [ 8531.570293] LustreError: 412536:0:(osc_request.c:2937:osc_build_rpc()) Skipped 9 previous similar messages [ 8533.838175] Lustre: DEBUG MARKER: == sanityn test 109: Race with several mount instances on 1 node ========================================================== 20:45:31 (1753749931) [ 8535.089925] LustreError: 413244:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e3718246000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8535.094497] LustreError: 413244:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 8535.116086] Lustre: Unmounted lustre-client [ 8535.949438] LustreError: 413264:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e3709ed4000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8535.952221] LustreError: 413264:0:(lov_obd.c:784:lov_cleanup()) Skipped 1 previous similar message [ 8535.958144] LustreError: 413264:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 8535.959849] LustreError: 413264:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 8535.996997] Lustre: Unmounted lustre-client [ 8536.463311] Lustre: DEBUG MARKER: Iteration 1 [ 8536.636487] LustreError: 413427:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 8536.639060] LustreError: 413428:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 8536.642992] LustreError: 413427:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8536.680566] Lustre: Mounted lustre-client [ 8537.130985] LustreError: 413529:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e37357ab000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8537.133051] LustreError: 413528:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 8537.136245] LustreError: 413529:0:(lov_obd.c:784:lov_cleanup()) Skipped 3 previous similar messages [ 8537.137856] LustreError: 413528:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 8537.158064] Lustre: Unmounted lustre-client [ 8538.182736] Key type lgssc unregistered [ 8538.316462] LNet: 413771:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8539.366973] LNet: Removed LNI 192.168.202.9@tcp [ 8539.694846] Key type .llcrypt unregistered [ 8539.696043] Key type ._llcrypt unregistered [ 8539.953163] Key type ._llcrypt registered [ 8539.954255] Key type .llcrypt registered [ 8540.245682] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8540.250289] alg: No test for adler32 (adler32-zlib) [ 8541.228726] Lustre: Lustre: Build Version: 2.16.56_105_g447ed16 [ 8541.485454] LNet: Added LNI 192.168.202.9@tcp [8/256/0/180] [ 8541.487063] LNet: Accept secure, port 988 [ 8543.096165] Key type lgssc registered [ 8543.689205] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8548.315886] Lustre: DEBUG MARKER: Iteration 2 [ 8548.427656] LustreError: 414597:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 8548.427972] LustreError: 414596:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 8548.433771] LustreError: 414597:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 8549.488286] Lustre: Mounted lustre-client [ 8549.489565] Lustre: Skipped 1 previous similar message [ 8549.989970] LustreError: 414697:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e37183b9800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8549.996591] LustreError: 414697:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 8549.999289] LustreError: 414697:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [ 8550.035068] Lustre: Unmounted lustre-client [ 8551.106330] Key type lgssc unregistered [ 8551.237507] LNet: 414939:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8552.295282] LNet: Removed LNI 192.168.202.9@tcp [ 8552.558072] Key type .llcrypt unregistered [ 8552.559731] Key type ._llcrypt unregistered [ 8552.828442] Key type ._llcrypt registered [ 8552.830090] Key type .llcrypt registered [ 8553.066327] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8553.072198] alg: No test for adler32 (adler32-zlib) [ 8553.935867] Lustre: Lustre: Build Version: 2.16.56_105_g447ed16 [ 8554.030130] LNet: Added LNI 192.168.202.9@tcp [8/256/0/180] [ 8554.032857] LNet: Accept secure, port 988 [ 8555.624129] Key type lgssc registered [ 8556.023598] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8560.042490] Lustre: DEBUG MARKER: Iteration 3 [ 8560.153232] LustreError: 415763:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 8560.153271] LustreError: 415764:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 8560.158127] LustreError: 415763:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8561.217672] Lustre: Mounted lustre-client [ 8561.219703] Lustre: Skipped 1 previous similar message [ 8561.749925] LustreError: 415868:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e37357ac000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8561.753644] LustreError: 415869:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 8561.755775] LustreError: 415868:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 8561.806222] Lustre: Unmounted lustre-client [ 8562.845128] Key type lgssc unregistered [ 8562.973537] LNet: 416111:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8564.007407] LNet: Removed LNI 192.168.202.9@tcp [ 8564.294311] Key type .llcrypt unregistered [ 8564.295884] Key type ._llcrypt unregistered [ 8564.557429] Key type ._llcrypt registered [ 8564.558553] Key type .llcrypt registered [ 8564.856616] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8564.872714] alg: No test for adler32 (adler32-zlib) [ 8565.742891] Lustre: Lustre: Build Version: 2.16.56_105_g447ed16 [ 8565.825981] LNet: Added LNI 192.168.202.9@tcp [8/256/0/180] [ 8565.827644] LNet: Accept secure, port 988 [ 8567.408328] Key type lgssc registered [ 8567.850150] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8571.622542] Lustre: DEBUG MARKER: Iteration 4 [ 8571.720840] LustreError: 416936:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 8571.720885] LustreError: 416937:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 8571.725055] LustreError: 416936:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8572.788980] Lustre: Mounted lustre-client [ 8572.789953] Lustre: Skipped 1 previous similar message [ 8573.339675] LustreError: 417038:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e37187f7000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8573.345138] LustreError: 417038:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 8573.347461] LustreError: 417038:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 8573.393125] Lustre: Unmounted lustre-client [ 8574.484044] Key type lgssc unregistered [ 8574.619502] LNet: 417282:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8575.654719] LNet: Removed LNI 192.168.202.9@tcp [ 8575.922619] Key type .llcrypt unregistered [ 8575.924328] Key type ._llcrypt unregistered [ 8576.217431] Key type ._llcrypt registered [ 8576.219293] Key type .llcrypt registered [ 8576.461955] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8576.467625] alg: No test for adler32 (adler32-zlib) [ 8577.331654] Lustre: Lustre: Build Version: 2.16.56_105_g447ed16 [ 8577.421461] LNet: Added LNI 192.168.202.9@tcp [8/256/0/180] [ 8577.423223] LNet: Accept secure, port 988 [ 8579.008215] Key type lgssc registered [ 8579.425420] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8583.267314] Lustre: DEBUG MARKER: Iteration 5 [ 8583.381051] LustreError: 418106:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 8583.381439] LustreError: 418107:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 8583.385566] LustreError: 418106:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8584.438872] Lustre: Mounted lustre-client [ 8584.440041] Lustre: Skipped 1 previous similar message [ 8585.013660] LustreError: 418206:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e37357ae800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8585.017412] LustreError: 418207:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 8585.017534] LustreError: 418206:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 8585.047330] Lustre: Unmounted lustre-client [ 8586.071420] Key type lgssc unregistered [ 8586.192382] LNet: 418448:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8587.239331] LNet: Removed LNI 192.168.202.9@tcp [ 8587.521182] Key type .llcrypt unregistered [ 8587.522467] Key type ._llcrypt unregistered [ 8587.918286] Key type ._llcrypt registered [ 8587.920144] Key type .llcrypt registered [ 8588.115467] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8588.122514] alg: No test for adler32 (adler32-zlib) [ 8589.002260] Lustre: Lustre: Build Version: 2.16.56_105_g447ed16 [ 8589.096383] LNet: Added LNI 192.168.202.9@tcp [8/256/0/180] [ 8589.098846] LNet: Accept secure, port 988 [ 8590.696164] Key type lgssc registered [ 8591.098708] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8594.852393] Lustre: DEBUG MARKER: Iteration 6 [ 8594.950378] LustreError: 419272:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 8594.950414] LustreError: 419274:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 8594.955285] LustreError: 419272:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 8596.001669] Lustre: Mounted lustre-client [ 8596.474620] LustreError: 419376:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e372f259000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8596.478637] LustreError: 419376:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 8596.479541] LustreError: 419377:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 8596.483406] LustreError: 419377:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 8596.523135] Lustre: Unmounted lustre-client [ 8597.625795] Key type lgssc unregistered [ 8597.742418] LNet: 419618:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8598.758754] LNet: Removed LNI 192.168.202.9@tcp [ 8598.981906] Key type .llcrypt unregistered [ 8598.982951] Key type ._llcrypt unregistered [ 8599.251597] Key type ._llcrypt registered [ 8599.252578] Key type .llcrypt registered [ 8599.418848] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8599.423791] alg: No test for adler32 (adler32-zlib) [ 8600.287052] Lustre: Lustre: Build Version: 2.16.56_105_g447ed16 [ 8600.375231] LNet: Added LNI 192.168.202.9@tcp [8/256/0/180] [ 8600.376720] LNet: Accept secure, port 988 [ 8601.968098] Key type lgssc registered [ 8602.375591] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8606.056283] Lustre: DEBUG MARKER: Iteration 7 [ 8606.158149] LustreError: 420443:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 8606.158192] LustreError: 420444:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 8606.163766] LustreError: 420443:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 8607.218104] Lustre: Mounted lustre-client [ 8607.745709] LustreError: 420543:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e3732041000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8607.748332] LustreError: 420543:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 8607.750867] LustreError: 420544:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 8607.754049] LustreError: 420544:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [ 8607.791098] Lustre: Unmounted lustre-client [ 8608.852333] Key type lgssc unregistered [ 8608.981700] LNet: 420786:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8610.022736] LNet: Removed LNI 192.168.202.9@tcp [ 8610.275068] Key type .llcrypt unregistered [ 8610.276106] Key type ._llcrypt unregistered [ 8610.580904] Key type ._llcrypt registered [ 8610.583175] Key type .llcrypt registered [ 8610.802170] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8610.806966] alg: No test for adler32 (adler32-zlib) [ 8611.675366] Lustre: Lustre: Build Version: 2.16.56_105_g447ed16 [ 8611.758069] LNet: Added LNI 192.168.202.9@tcp [8/256/0/180] [ 8611.759676] LNet: Accept secure, port 988 [ 8613.344171] Key type lgssc registered [ 8613.715730] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8617.168950] Lustre: DEBUG MARKER: Iteration 8 [ 8617.282727] LustreError: 421611:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 8617.283147] LustreError: 421612:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 8617.288197] LustreError: 421611:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 8618.354818] Lustre: Mounted lustre-client [ 8618.356210] Lustre: Skipped 1 previous similar message [ 8618.849505] LustreError: 421712:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e37183f9800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8618.857129] LustreError: 421712:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 8618.932738] Lustre: Unmounted lustre-client [ 8619.921482] Key type lgssc unregistered [ 8620.042472] LNet: 421955:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8621.094700] LNet: Removed LNI 192.168.202.9@tcp [ 8621.346986] Key type .llcrypt unregistered [ 8621.348426] Key type ._llcrypt unregistered [ 8621.642778] Key type ._llcrypt registered [ 8621.643745] Key type .llcrypt registered [ 8621.875505] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8621.881474] alg: No test for adler32 (adler32-zlib) [ 8622.746760] Lustre: Lustre: Build Version: 2.16.56_105_g447ed16 [ 8622.835285] LNet: Added LNI 192.168.202.9@tcp [8/256/0/180] [ 8622.837058] LNet: Accept secure, port 988 [ 8624.424194] Key type lgssc registered [ 8624.839902] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8628.597803] Lustre: DEBUG MARKER: Iteration 9 [ 8628.701263] LustreError: 422779:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 8628.701307] LustreError: 422780:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 8628.705654] LustreError: 422779:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8629.759757] Lustre: Mounted lustre-client [ 8629.760976] Lustre: Skipped 1 previous similar message [ 8630.242619] LustreError: 422884:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e37183f8800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8630.248512] LustreError: 422884:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 8630.250993] LustreError: 422884:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [ 8630.311754] Lustre: Unmounted lustre-client [ 8631.435133] Key type lgssc unregistered [ 8631.559436] LNet: 423126:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8632.614781] LNet: Removed LNI 192.168.202.9@tcp [ 8632.874346] Key type .llcrypt unregistered [ 8632.876072] Key type ._llcrypt unregistered [ 8633.240821] Key type ._llcrypt registered [ 8633.251788] Key type .llcrypt registered [ 8633.519841] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8633.524371] alg: No test for adler32 (adler32-zlib) [ 8634.395357] Lustre: Lustre: Build Version: 2.16.56_105_g447ed16 [ 8634.492870] LNet: Added LNI 192.168.202.9@tcp [8/256/0/180] [ 8634.494760] LNet: Accept secure, port 988 [ 8636.088144] Key type lgssc registered [ 8636.474593] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8640.332154] Lustre: DEBUG MARKER: Iteration 10 [ 8640.458501] LustreError: 423950:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 8640.458571] LustreError: 423951:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 8640.463048] LustreError: 423950:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 8641.517951] Lustre: Mounted lustre-client [ 8641.519311] Lustre: Skipped 1 previous similar message [ 8642.145324] LustreError: 424052:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e3717c74000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8642.149294] LustreError: 424052:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 8642.149551] LustreError: 424051:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 8642.195345] Lustre: Unmounted lustre-client [ 8642.196636] Lustre: Skipped 1 previous similar message [ 8643.280384] Key type lgssc unregistered [ 8643.407360] LNet: 424294:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8644.454745] LNet: Removed LNI 192.168.202.9@tcp [ 8644.713249] Key type .llcrypt unregistered [ 8644.714353] Key type ._llcrypt unregistered [ 8644.964683] Key type ._llcrypt registered [ 8644.965750] Key type .llcrypt registered [ 8645.156673] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8645.161513] alg: No test for adler32 (adler32-zlib) [ 8646.023640] Lustre: Lustre: Build Version: 2.16.56_105_g447ed16 [ 8646.118975] LNet: Added LNI 192.168.202.9@tcp [8/256/0/180] [ 8646.121547] LNet: Accept secure, port 988 [ 8647.704160] Key type lgssc registered [ 8648.083533] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8651.849364] Lustre: DEBUG MARKER: Iteration 11 [ 8651.954706] LustreError: 425118:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 8651.954759] LustreError: 425119:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 8651.960952] LustreError: 425118:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8653.024774] Lustre: Mounted lustre-client [ 8653.026780] Lustre: Skipped 1 previous similar message [ 8653.633693] LustreError: 425221:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e37357a9000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8653.635652] LustreError: 425222:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 8653.638859] LustreError: 425221:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 8653.677861] Lustre: Unmounted lustre-client [ 8654.813302] Key type lgssc unregistered [ 8654.941560] LNet: 425464:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8655.974800] LNet: Removed LNI 192.168.202.9@tcp [ 8656.266303] Key type .llcrypt unregistered [ 8656.268337] Key type ._llcrypt unregistered [ 8656.539241] Key type ._llcrypt registered [ 8656.540488] Key type .llcrypt registered [ 8656.741171] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8656.748741] alg: No test for adler32 (adler32-zlib) [ 8657.630159] Lustre: Lustre: Build Version: 2.16.56_105_g447ed16 [ 8657.722335] LNet: Added LNI 192.168.202.9@tcp [8/256/0/180] [ 8657.724930] LNet: Accept secure, port 988 [ 8659.320141] Key type lgssc registered [ 8659.701016] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8663.497148] Lustre: DEBUG MARKER: Iteration 12 [ 8663.606103] LustreError: 426290:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 8663.606358] LustreError: 426291:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 8663.610193] LustreError: 426290:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8664.663767] Lustre: Mounted lustre-client [ 8665.135526] LustreError: 426393:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e37187f7000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8665.137722] LustreError: 426392:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 8665.140902] LustreError: 426393:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 8665.168932] Lustre: Unmounted lustre-client [ 8666.234275] Key type lgssc unregistered [ 8666.367528] LNet: 426634:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8667.430812] LNet: Removed LNI 192.168.202.9@tcp [ 8667.677445] Key type .llcrypt unregistered [ 8667.678560] Key type ._llcrypt unregistered [ 8667.971596] Key type ._llcrypt registered [ 8667.972644] Key type .llcrypt registered [ 8668.172669] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8668.187498] alg: No test for adler32 (adler32-zlib) [ 8669.058178] Lustre: Lustre: Build Version: 2.16.56_105_g447ed16 [ 8669.154771] LNet: Added LNI 192.168.202.9@tcp [8/256/0/180] [ 8669.157572] LNet: Accept secure, port 988 [ 8670.736187] Key type lgssc registered [ 8671.108409] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8675.098643] Lustre: DEBUG MARKER: Iteration 13 [ 8675.201125] LustreError: 427459:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 8675.201474] LustreError: 427460:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 8675.205883] LustreError: 427459:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8676.277569] Lustre: Mounted lustre-client [ 8676.874601] LustreError: 427563:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e3705cb9000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8676.876757] LustreError: 427564:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 8676.877967] LustreError: 427563:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 8676.913790] Lustre: Unmounted lustre-client [ 8678.088703] Key type lgssc unregistered [ 8678.221501] LNet: 427805:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8679.270830] LNet: Removed LNI 192.168.202.9@tcp [ 8679.533097] Key type .llcrypt unregistered [ 8679.534373] Key type ._llcrypt unregistered [ 8679.776854] Key type ._llcrypt registered [ 8679.777987] Key type .llcrypt registered [ 8680.110496] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8680.117475] alg: No test for adler32 (adler32-zlib) [ 8680.983402] Lustre: Lustre: Build Version: 2.16.56_105_g447ed16 [ 8681.070862] LNet: Added LNI 192.168.202.9@tcp [8/256/0/180] [ 8681.072374] LNet: Accept secure, port 988 [ 8682.656154] Key type lgssc registered [ 8683.037655] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8686.896824] Lustre: DEBUG MARKER: Iteration 14 [ 8687.013456] LustreError: 428631:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 8687.013529] LustreError: 428632:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 8687.018804] LustreError: 428631:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8688.078096] Lustre: Mounted lustre-client [ 8688.079569] Lustre: Skipped 1 previous similar message [ 8688.623847] LustreError: 428736:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e3705720000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8688.631198] LustreError: 428736:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 8688.716602] Lustre: Unmounted lustre-client [ 8689.744882] Key type lgssc unregistered [ 8689.860508] LNet: 428979:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8690.918771] LNet: Removed LNI 192.168.202.9@tcp [ 8691.173878] Key type .llcrypt unregistered [ 8691.175554] Key type ._llcrypt unregistered [ 8691.442858] Key type ._llcrypt registered [ 8691.444691] Key type .llcrypt registered [ 8691.626875] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8691.635933] alg: No test for adler32 (adler32-zlib) [ 8692.492893] Lustre: Lustre: Build Version: 2.16.56_105_g447ed16 [ 8692.574775] LNet: Added LNI 192.168.202.9@tcp [8/256/0/180] [ 8692.576497] LNet: Accept secure, port 988 [ 8694.160189] Key type lgssc registered [ 8694.520329] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8698.466698] Lustre: DEBUG MARKER: Iteration 15 [ 8698.598905] LustreError: 429804:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 8698.600170] LustreError: 429805:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 8698.605356] LustreError: 429804:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 8699.665629] Lustre: Mounted lustre-client [ 8699.667512] Lustre: Skipped 1 previous similar message [ 8700.190924] LustreError: 429905:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e3717c81800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8700.197081] LustreError: 429905:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 8700.199274] LustreError: 429905:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 8700.242632] Lustre: Unmounted lustre-client [ 8701.365761] Key type lgssc unregistered [ 8701.490567] LNet: 430150:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8702.502908] LNet: Removed LNI 192.168.202.9@tcp [ 8702.778158] Key type .llcrypt unregistered [ 8702.779206] Key type ._llcrypt unregistered [ 8702.991239] Key type ._llcrypt registered [ 8702.992559] Key type .llcrypt registered [ 8703.225233] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8703.231916] alg: No test for adler32 (adler32-zlib) [ 8704.091914] Lustre: Lustre: Build Version: 2.16.56_105_g447ed16 [ 8704.179351] LNet: Added LNI 192.168.202.9@tcp [8/256/0/180] [ 8704.181201] LNet: Accept secure, port 988 [ 8705.768173] Key type lgssc registered [ 8706.136974] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8710.136137] Lustre: DEBUG MARKER: Iteration 16 [ 8710.270192] LustreError: 430975:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 8710.271838] LustreError: 430976:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 8710.275660] LustreError: 430975:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 8711.338478] Lustre: Mounted lustre-client [ 8711.899050] LustreError: 431077:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e3739c8b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8711.901480] LustreError: 431076:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 8711.903158] LustreError: 431077:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 8711.939225] Lustre: Unmounted lustre-client [ 8713.008486] Key type lgssc unregistered [ 8713.129372] LNet: 431318:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8714.150696] LNet: Removed LNI 192.168.202.9@tcp [ 8714.388970] Key type .llcrypt unregistered [ 8714.390309] Key type ._llcrypt unregistered [ 8714.638781] Key type ._llcrypt registered [ 8714.640464] Key type .llcrypt registered [ 8714.877076] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8714.883205] alg: No test for adler32 (adler32-zlib) [ 8715.760359] Lustre: Lustre: Build Version: 2.16.56_105_g447ed16 [ 8715.846067] LNet: Added LNI 192.168.202.9@tcp [8/256/0/180] [ 8715.848021] LNet: Accept secure, port 988 [ 8717.440210] Key type lgssc registered [ 8717.873200] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8722.048280] Lustre: DEBUG MARKER: Iteration 17 [ 8722.186095] LustreError: 432144:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 8722.188063] LustreError: 432145:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 8722.191128] LustreError: 432144:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 8723.250843] Lustre: Mounted lustre-client [ 8723.252732] Lustre: Skipped 1 previous similar message [ 8723.800649] LustreError: 432246:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e37292ef000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8723.804319] LustreError: 432245:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 8723.805712] LustreError: 432246:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 8723.844914] Lustre: Unmounted lustre-client [ 8724.917648] Key type lgssc unregistered [ 8725.036512] LNet: 432488:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8726.054878] LNet: Removed LNI 192.168.202.9@tcp [ 8726.347438] Key type .llcrypt unregistered [ 8726.348927] Key type ._llcrypt unregistered [ 8726.711045] Key type ._llcrypt registered [ 8726.715239] Key type .llcrypt registered [ 8726.960431] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8726.966185] alg: No test for adler32 (adler32-zlib) [ 8727.837521] Lustre: Lustre: Build Version: 2.16.56_105_g447ed16 [ 8727.935729] LNet: Added LNI 192.168.202.9@tcp [8/256/0/180] [ 8727.937797] LNet: Accept secure, port 988 [ 8729.536185] Key type lgssc registered [ 8730.010095] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8733.883178] Lustre: DEBUG MARKER: Iteration 18 [ 8733.995979] LustreError: 433315:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 8733.996053] LustreError: 433316:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 8734.003147] LustreError: 433315:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 8735.060495] Lustre: Mounted lustre-client [ 8735.062571] Lustre: Skipped 1 previous similar message [ 8735.521977] LustreError: 433417:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e3731fbd000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8735.524431] LustreError: 433416:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 8735.527395] LustreError: 433417:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 8735.577550] Lustre: Unmounted lustre-client [ 8735.579293] Lustre: Skipped 1 previous similar message [ 8736.625356] Key type lgssc unregistered [ 8736.749533] LNet: 433659:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8737.766843] LNet: Removed LNI 192.168.202.9@tcp [ 8738.005187] Key type .llcrypt unregistered [ 8738.006923] Key type ._llcrypt unregistered [ 8738.262609] Key type ._llcrypt registered [ 8738.263757] Key type .llcrypt registered [ 8738.497861] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8738.505480] alg: No test for adler32 (adler32-zlib) [ 8739.357708] Lustre: Lustre: Build Version: 2.16.56_105_g447ed16 [ 8739.433664] LNet: Added LNI 192.168.202.9@tcp [8/256/0/180] [ 8739.435355] LNet: Accept secure, port 988 [ 8741.016188] Key type lgssc registered [ 8741.413446] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8745.245533] Lustre: DEBUG MARKER: Iteration 19 [ 8745.366747] LustreError: 434483:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 8745.367053] LustreError: 434484:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 8745.372524] LustreError: 434483:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8746.428528] Lustre: Mounted lustre-client [ 8746.429916] Lustre: Skipped 1 previous similar message [ 8746.948324] LustreError: 434588:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e37382ef800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8746.952314] LustreError: 434589:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 8746.953947] LustreError: 434588:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 8746.987080] Lustre: Unmounted lustre-client [ 8748.119550] Key type lgssc unregistered [ 8748.261658] LNet: 434831:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8749.286461] LNet: Removed LNI 192.168.202.9@tcp [ 8749.539021] Key type .llcrypt unregistered [ 8749.539977] Key type ._llcrypt unregistered [ 8749.860608] Key type ._llcrypt registered [ 8749.861914] Key type .llcrypt registered [ 8750.035854] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8750.041450] alg: No test for adler32 (adler32-zlib) [ 8750.913464] Lustre: Lustre: Build Version: 2.16.56_105_g447ed16 [ 8750.997618] LNet: Added LNI 192.168.202.9@tcp [8/256/0/180] [ 8750.999407] LNet: Accept secure, port 988 [ 8752.592218] Key type lgssc registered [ 8753.024400] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8756.978417] Lustre: DEBUG MARKER: Iteration 20 [ 8757.098984] LustreError: 435655:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 8757.099081] LustreError: 435656:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 8757.104881] LustreError: 435655:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 8758.158406] Lustre: Mounted lustre-client [ 8758.646479] LustreError: 435757:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e37183f9800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8758.650133] LustreError: 435757:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 8758.650899] LustreError: 435758:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8758.726814] Lustre: Unmounted lustre-client [ 8759.778237] Key type lgssc unregistered [ 8759.907461] LNet: 436001:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8760.934684] LNet: Removed LNI 192.168.202.9@tcp [ 8761.170908] Key type .llcrypt unregistered [ 8761.171959] Key type ._llcrypt unregistered [ 8761.485360] Key type ._llcrypt registered [ 8761.486251] Key type .llcrypt registered [ 8761.722199] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8761.727172] alg: No test for adler32 (adler32-zlib) [ 8762.606842] Lustre: Lustre: Build Version: 2.16.56_105_g447ed16 [ 8762.697700] LNet: Added LNI 192.168.202.9@tcp [8/256/0/180] [ 8762.699293] LNet: Accept secure, port 988 [ 8764.288130] Key type lgssc registered [ 8764.671530] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8768.629326] Lustre: DEBUG MARKER: Iteration 21 [ 8768.739773] LustreError: 436827:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 8768.739829] LustreError: 436826:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 8768.746639] LustreError: 436827:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 8769.806193] Lustre: Mounted lustre-client [ 8769.807811] Lustre: Skipped 1 previous similar message [ 8770.403567] LustreError: 436930:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e3739c8b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8770.407707] LustreError: 436930:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 8770.407790] LustreError: 436931:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 8770.412281] LustreError: 436931:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 8770.474946] Lustre: Unmounted lustre-client [ 8770.476410] Lustre: Skipped 1 previous similar message [ 8771.618944] Key type lgssc unregistered [ 8771.743784] LNet: 437172:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8772.774745] LNet: Removed LNI 192.168.202.9@tcp [ 8773.038033] Key type .llcrypt unregistered [ 8773.039116] Key type ._llcrypt unregistered [ 8773.259405] Key type ._llcrypt registered [ 8773.260556] Key type .llcrypt registered [ 8773.592262] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8773.602102] alg: No test for adler32 (adler32-zlib) [ 8774.483495] Lustre: Lustre: Build Version: 2.16.56_105_g447ed16 [ 8774.570244] LNet: Added LNI 192.168.202.9@tcp [8/256/0/180] [ 8774.572730] LNet: Accept secure, port 988 [ 8776.160167] Key type lgssc registered [ 8776.596932] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8780.539705] Lustre: DEBUG MARKER: Iteration 22 [ 8780.678250] LustreError: 437998:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 8780.678315] LustreError: 437999:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 8780.682818] LustreError: 437998:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8781.756112] Lustre: Mounted lustre-client [ 8781.757820] Lustre: Skipped 1 previous similar message [ 8782.362915] LustreError: 438103:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e37382e9000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8782.371132] LustreError: 438103:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 8782.373068] LustreError: 438103:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 8782.438445] Lustre: Unmounted lustre-client [ 8783.506555] Key type lgssc unregistered [ 8783.641431] LNet: 438345:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8784.678759] LNet: Removed LNI 192.168.202.9@tcp [ 8784.955068] Key type .llcrypt unregistered [ 8784.956125] Key type ._llcrypt unregistered [ 8785.271738] Key type ._llcrypt registered [ 8785.275634] Key type .llcrypt registered [ 8785.508608] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8785.516321] alg: No test for adler32 (adler32-zlib) [ 8786.380184] Lustre: Lustre: Build Version: 2.16.56_105_g447ed16 [ 8786.466322] LNet: Added LNI 192.168.202.9@tcp [8/256/0/180] [ 8786.468713] LNet: Accept secure, port 988 [ 8788.056156] Key type lgssc registered [ 8788.473550] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8792.166564] Lustre: DEBUG MARKER: Iteration 23 [ 8792.301652] LustreError: 439171:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 8792.301688] LustreError: 439172:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 8792.305968] LustreError: 439171:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8793.374395] Lustre: Mounted lustre-client [ 8793.375541] Lustre: Skipped 1 previous similar message [ 8794.000727] LustreError: 439271:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e3718735000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8794.004490] LustreError: 439271:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 8794.008888] LustreError: 439270:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 8794.011121] LustreError: 439270:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [ 8794.037754] Lustre: Unmounted lustre-client [ 8795.150761] Key type lgssc unregistered [ 8795.278791] LNet: 439514:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8796.326713] LNet: Removed LNI 192.168.202.9@tcp [ 8796.595456] Key type .llcrypt unregistered [ 8796.596925] Key type ._llcrypt unregistered [ 8797.039081] Key type ._llcrypt registered [ 8797.040114] Key type .llcrypt registered [ 8797.206605] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8797.212500] alg: No test for adler32 (adler32-zlib) [ 8798.089463] Lustre: Lustre: Build Version: 2.16.56_105_g447ed16 [ 8798.190633] LNet: Added LNI 192.168.202.9@tcp [8/256/0/180] [ 8798.192726] LNet: Accept secure, port 988 [ 8799.784228] Key type lgssc registered [ 8800.177084] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8804.085434] Lustre: DEBUG MARKER: Iteration 24 [ 8804.237338] LustreError: 440339:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 8804.237431] LustreError: 440338:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 8804.243492] LustreError: 440339:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 8805.303141] Lustre: Mounted lustre-client [ 8805.304764] Lustre: Skipped 1 previous similar message [ 8805.839750] LustreError: 440439:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e371823d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8805.845188] LustreError: 440439:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 8805.878618] Lustre: Unmounted lustre-client [ 8807.025169] Key type lgssc unregistered [ 8807.153358] LNet: 440683:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8808.166769] LNet: Removed LNI 192.168.202.9@tcp [ 8808.436321] Key type .llcrypt unregistered [ 8808.437503] Key type ._llcrypt unregistered [ 8808.860734] Key type ._llcrypt registered [ 8808.861806] Key type .llcrypt registered [ 8809.052115] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8809.059065] alg: No test for adler32 (adler32-zlib) [ 8809.929618] Lustre: Lustre: Build Version: 2.16.56_105_g447ed16 [ 8810.022404] LNet: Added LNI 192.168.202.9@tcp [8/256/0/180] [ 8810.024041] LNet: Accept secure, port 988 [ 8811.608228] Key type lgssc registered [ 8812.039553] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8815.844997] Lustre: DEBUG MARKER: Iteration 25 [ 8815.975599] LustreError: 441509:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 8815.975693] LustreError: 441508:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 8815.981296] LustreError: 441509:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 8817.057882] Lustre: Mounted lustre-client [ 8817.622642] LustreError: 441612:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e3737295000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8817.625676] LustreError: 441612:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 8817.630922] LustreError: 441612:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 8817.634631] LustreError: 441612:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [ 8817.656592] Lustre: Unmounted lustre-client [ 8818.780411] Key type lgssc unregistered [ 8818.926410] LNet: 441855:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8819.943607] LNet: Removed LNI 192.168.202.9@tcp [ 8820.187157] Key type .llcrypt unregistered [ 8820.188433] Key type ._llcrypt unregistered [ 8820.457433] Key type ._llcrypt registered [ 8820.467360] Key type .llcrypt registered [ 8820.796742] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8820.802904] alg: No test for adler32 (adler32-zlib) [ 8821.669236] Lustre: Lustre: Build Version: 2.16.56_105_g447ed16 [ 8821.762717] LNet: Added LNI 192.168.202.9@tcp [8/256/0/180] [ 8821.765155] LNet: Accept secure, port 988 [ 8823.360186] Key type lgssc registered [ 8823.721593] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8827.333195] Lustre: DEBUG MARKER: Iteration 26 [ 8827.447338] LustreError: 442680:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 8827.447385] LustreError: 442681:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 8827.454812] LustreError: 442680:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 8828.511587] Lustre: Mounted lustre-client [ 8828.512565] Lustre: Skipped 1 previous similar message [ 8829.045778] LustreError: 442780:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e3720f19800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8829.048567] LustreError: 442780:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 8829.050968] LustreError: 442781:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 8829.053034] LustreError: 442781:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 8829.082113] Lustre: Unmounted lustre-client [ 8830.166289] Key type lgssc unregistered [ 8830.297563] LNet: 443024:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8831.334784] LNet: Removed LNI 192.168.202.9@tcp [ 8831.578147] Key type .llcrypt unregistered [ 8831.579116] Key type ._llcrypt unregistered [ 8831.877958] Key type ._llcrypt registered [ 8831.879250] Key type .llcrypt registered [ 8832.067688] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8832.072441] alg: No test for adler32 (adler32-zlib) [ 8832.940828] Lustre: Lustre: Build Version: 2.16.56_105_g447ed16 [ 8833.022750] LNet: Added LNI 192.168.202.9@tcp [8/256/0/180] [ 8833.025503] LNet: Accept secure, port 988 [ 8834.616127] Key type lgssc registered [ 8834.982911] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8838.677401] Lustre: DEBUG MARKER: Iteration 27 [ 8838.790607] LustreError: 443849:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 8838.790651] LustreError: 443850:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 8838.798155] LustreError: 443849:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 8839.852344] Lustre: Mounted lustre-client [ 8839.854215] Lustre: Skipped 1 previous similar message [ 8840.399501] LustreError: 443953:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e3704226800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8840.404642] LustreError: 443953:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 8840.409957] LustreError: 443954:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 8840.412249] LustreError: 443954:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 8840.459834] Lustre: Unmounted lustre-client [ 8841.526801] Key type lgssc unregistered [ 8841.651684] LNet: 444195:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8842.663514] LNet: Removed LNI 192.168.202.9@tcp [ 8842.922923] Key type .llcrypt unregistered [ 8842.924298] Key type ._llcrypt unregistered [ 8843.201329] Key type ._llcrypt registered [ 8843.207525] Key type .llcrypt registered [ 8843.449837] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8843.456700] alg: No test for adler32 (adler32-zlib) [ 8844.316725] Lustre: Lustre: Build Version: 2.16.56_105_g447ed16 [ 8844.403408] LNet: Added LNI 192.168.202.9@tcp [8/256/0/180] [ 8844.406039] LNet: Accept secure, port 988 [ 8846.000172] Key type lgssc registered [ 8846.405499] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8850.102881] Lustre: DEBUG MARKER: Iteration 28 [ 8850.228944] LustreError: 445018:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 8850.229065] LustreError: 445020:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 8850.236555] LustreError: 445018:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 8851.297780] Lustre: Mounted lustre-client [ 8851.298867] Lustre: Skipped 1 previous similar message [ 8851.835556] LustreError: 445119:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e3718242800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8851.839352] LustreError: 445119:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 8851.839521] LustreError: 445120:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 8851.843939] LustreError: 445120:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 8851.881634] Lustre: Unmounted lustre-client [ 8852.902778] Key type lgssc unregistered [ 8853.028774] LNet: 445362:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8854.054424] LNet: Removed LNI 192.168.202.9@tcp [ 8854.302094] Key type .llcrypt unregistered [ 8854.303110] Key type ._llcrypt unregistered [ 8854.605286] Key type ._llcrypt registered [ 8854.606681] Key type .llcrypt registered [ 8854.879529] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8854.884223] alg: No test for adler32 (adler32-zlib) [ 8855.753316] Lustre: Lustre: Build Version: 2.16.56_105_g447ed16 [ 8855.841799] LNet: Added LNI 192.168.202.9@tcp [8/256/0/180] [ 8855.844248] LNet: Accept secure, port 988 [ 8857.432154] Key type lgssc registered [ 8857.864357] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8861.495204] Lustre: DEBUG MARKER: Iteration 29 [ 8861.613198] LustreError: 446186:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 8861.613245] LustreError: 446187:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 8861.617772] LustreError: 446186:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 8862.681461] Lustre: Mounted lustre-client [ 8863.230952] LustreError: 446289:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e370a061000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8863.236736] LustreError: 446289:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 8863.238638] LustreError: 446289:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 8863.282264] Lustre: Unmounted lustre-client [ 8864.324523] Key type lgssc unregistered [ 8864.451754] LNet: 446532:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8865.511491] LNet: Removed LNI 192.168.202.9@tcp [ 8865.753034] Key type .llcrypt unregistered [ 8865.754460] Key type ._llcrypt unregistered [ 8866.050338] Key type ._llcrypt registered [ 8866.052604] Key type .llcrypt registered [ 8866.291596] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8866.299260] alg: No test for adler32 (adler32-zlib) [ 8867.159456] Lustre: Lustre: Build Version: 2.16.56_105_g447ed16 [ 8867.244200] LNet: Added LNI 192.168.202.9@tcp [8/256/0/180] [ 8867.245910] LNet: Accept secure, port 988 [ 8868.832176] Key type lgssc registered [ 8869.223031] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8872.981546] Lustre: DEBUG MARKER: Iteration 30 [ 8873.081428] LustreError: 447357:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 8873.082074] LustreError: 447358:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 8873.085356] LustreError: 447357:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8874.138677] Lustre: Mounted lustre-client [ 8874.139589] Lustre: Skipped 1 previous similar message [ 8874.641242] LustreError: 447459:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e3710d81000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8874.646521] LustreError: 447459:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 8874.648735] LustreError: 447459:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 8874.679607] Lustre: Unmounted lustre-client [ 8875.703242] Key type lgssc unregistered [ 8875.835609] LNet: 447701:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8876.902704] LNet: Removed LNI 192.168.202.9@tcp [ 8877.132885] Key type .llcrypt unregistered [ 8877.133883] Key type ._llcrypt unregistered [ 8877.380275] Key type ._llcrypt registered [ 8877.385809] Key type .llcrypt registered [ 8877.530399] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8877.536630] alg: No test for adler32 (adler32-zlib) [ 8878.392235] Lustre: Lustre: Build Version: 2.16.56_105_g447ed16 [ 8878.472154] LNet: Added LNI 192.168.202.9@tcp [8/256/0/180] [ 8878.473738] LNet: Accept secure, port 988 [ 8880.048155] Key type lgssc registered [ 8880.393799] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8883.691675] Lustre: DEBUG MARKER: Iteration 31 [ 8883.796777] LustreError: 448524:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 8883.796816] LustreError: 448525:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 8883.800707] LustreError: 448524:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8884.849573] Lustre: Mounted lustre-client [ 8885.285382] LustreError: 448627:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e37187f6000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8885.287248] LustreError: 448628:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 8885.288370] LustreError: 448627:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 8885.319033] Lustre: Unmounted lustre-client [ 8886.231546] Key type lgssc unregistered [ 8886.353418] LNet: 448870:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8887.398755] LNet: Removed LNI 192.168.202.9@tcp [ 8887.633831] Key type .llcrypt unregistered [ 8887.635617] Key type ._llcrypt unregistered [ 8887.891905] Key type ._llcrypt registered [ 8887.894021] Key type .llcrypt registered [ 8888.068049] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8888.073037] alg: No test for adler32 (adler32-zlib) [ 8888.931887] Lustre: Lustre: Build Version: 2.16.56_105_g447ed16 [ 8889.015035] LNet: Added LNI 192.168.202.9@tcp [8/256/0/180] [ 8889.016692] LNet: Accept secure, port 988 [ 8890.608170] Key type lgssc registered [ 8890.987327] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8894.546656] Lustre: DEBUG MARKER: Iteration 32 [ 8894.652298] LustreError: 449693:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 8894.652333] LustreError: 449695:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 8894.656080] LustreError: 449693:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8895.722381] Lustre: Mounted lustre-client [ 8895.723331] Lustre: Skipped 1 previous similar message [ 8896.259758] LustreError: 449800:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e3729390000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8896.262544] LustreError: 449800:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 8896.267071] LustreError: 449801:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 8896.268797] LustreError: 449801:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 8896.297968] Lustre: Unmounted lustre-client [ 8897.404945] Key type lgssc unregistered [ 8897.533944] LNet: 450043:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8898.599657] LNet: Removed LNI 192.168.202.9@tcp [ 8898.859078] Key type .llcrypt unregistered [ 8898.860101] Key type ._llcrypt unregistered [ 8899.144928] Key type ._llcrypt registered [ 8899.145917] Key type .llcrypt registered [ 8899.425234] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8899.431918] alg: No test for adler32 (adler32-zlib) [ 8900.303043] Lustre: Lustre: Build Version: 2.16.56_105_g447ed16 [ 8900.390892] LNet: Added LNI 192.168.202.9@tcp [8/256/0/180] [ 8900.392282] LNet: Accept secure, port 988 [ 8901.976156] Key type lgssc registered [ 8902.380156] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8906.071699] Lustre: DEBUG MARKER: Iteration 33 [ 8906.193706] LustreError: 450868:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 8906.193771] LustreError: 450869:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 8906.198365] LustreError: 450868:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8907.251656] Lustre: Mounted lustre-client [ 8907.807933] LustreError: 450971:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e3704825000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8907.811441] LustreError: 450971:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 8907.813103] LustreError: 450972:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 8907.816655] LustreError: 450972:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 8907.874902] Lustre: Unmounted lustre-client [ 8908.932882] Key type lgssc unregistered [ 8909.056449] LNet: 451214:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8910.122789] LNet: Removed LNI 192.168.202.9@tcp [ 8910.366075] Key type .llcrypt unregistered [ 8910.367089] Key type ._llcrypt unregistered [ 8910.757289] Key type ._llcrypt registered [ 8910.758354] Key type .llcrypt registered [ 8910.938794] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8910.944910] alg: No test for adler32 (adler32-zlib) [ 8911.797929] Lustre: Lustre: Build Version: 2.16.56_105_g447ed16 [ 8911.877346] LNet: Added LNI 192.168.202.9@tcp [8/256/0/180] [ 8911.878736] LNet: Accept secure, port 988 [ 8913.456166] Key type lgssc registered [ 8913.816975] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8917.203709] Lustre: DEBUG MARKER: Iteration 34 [ 8917.311528] LustreError: 452038:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 8917.311568] LustreError: 452039:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 8917.315552] LustreError: 452038:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8918.378880] Lustre: Mounted lustre-client [ 8918.380776] Lustre: Skipped 1 previous similar message [ 8918.912558] LustreError: 452141:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e3704827000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8918.915243] LustreError: 452142:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 8918.917859] LustreError: 452141:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 8918.923533] LustreError: 452142:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [ 8918.961868] Lustre: Unmounted lustre-client [ 8920.001346] Key type lgssc unregistered [ 8920.120811] LNet: 452384:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8921.190782] LNet: Removed LNI 192.168.202.9@tcp [ 8921.453984] Key type .llcrypt unregistered [ 8921.455031] Key type ._llcrypt unregistered [ 8921.780926] Key type ._llcrypt registered [ 8921.782486] Key type .llcrypt registered [ 8921.996602] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8922.003217] alg: No test for adler32 (adler32-zlib) [ 8922.875447] Lustre: Lustre: Build Version: 2.16.56_105_g447ed16 [ 8922.963200] LNet: Added LNI 192.168.202.9@tcp [8/256/0/180] [ 8922.964937] LNet: Accept secure, port 988 [ 8924.544140] Key type lgssc registered [ 8924.912696] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8928.703432] Lustre: DEBUG MARKER: Iteration 35 [ 8928.843901] LustreError: 453210:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 8928.845188] LustreError: 453209:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 8928.847812] LustreError: 453210:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8929.905425] Lustre: Mounted lustre-client [ 8930.453126] LustreError: 453310:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e3710d81000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8930.458487] LustreError: 453310:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 8930.460269] LustreError: 453310:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 8930.508388] Lustre: Unmounted lustre-client [ 8931.554860] Key type lgssc unregistered [ 8931.679781] LNet: 453553:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8932.710771] LNet: Removed LNI 192.168.202.9@tcp [ 8932.962269] Key type .llcrypt unregistered [ 8932.963272] Key type ._llcrypt unregistered [ 8933.242552] Key type ._llcrypt registered [ 8933.243473] Key type .llcrypt registered [ 8933.563618] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8933.568604] alg: No test for adler32 (adler32-zlib) [ 8934.430534] Lustre: Lustre: Build Version: 2.16.56_105_g447ed16 [ 8934.526043] LNet: Added LNI 192.168.202.9@tcp [8/256/0/180] [ 8934.528052] LNet: Accept secure, port 988 [ 8936.120125] Key type lgssc registered [ 8936.468644] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8939.686355] Lustre: DEBUG MARKER: Iteration 36 [ 8939.777826] LustreError: 454378:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 8939.777877] LustreError: 454379:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 8939.781814] LustreError: 454378:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8940.842486] Lustre: Mounted lustre-client [ 8940.843445] Lustre: Skipped 1 previous similar message [ 8941.392351] LustreError: 454481:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e372a0d9000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8941.397057] LustreError: 454481:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 8941.441911] Lustre: Unmounted lustre-client [ 8942.493626] Key type lgssc unregistered [ 8942.618558] LNet: 454725:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8943.655539] LNet: Removed LNI 192.168.202.9@tcp [ 8943.909187] Key type .llcrypt unregistered [ 8943.910208] Key type ._llcrypt unregistered [ 8944.151908] Key type ._llcrypt registered [ 8944.154090] Key type .llcrypt registered [ 8944.440833] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8944.445821] alg: No test for adler32 (adler32-zlib) [ 8945.309900] Lustre: Lustre: Build Version: 2.16.56_105_g447ed16 [ 8945.396771] LNet: Added LNI 192.168.202.9@tcp [8/256/0/180] [ 8945.398314] LNet: Accept secure, port 988 [ 8946.984148] Key type lgssc registered [ 8947.343675] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8950.658761] Lustre: DEBUG MARKER: Iteration 37 [ 8950.783627] LustreError: 455550:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 8950.784932] LustreError: 455551:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 8950.787627] LustreError: 455550:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8951.853342] Lustre: Mounted lustre-client [ 8952.461060] LustreError: 455652:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e3704fe4800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8952.463092] LustreError: 455651:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 8952.464866] LustreError: 455652:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 8952.514493] Lustre: Unmounted lustre-client [ 8953.592399] Key type lgssc unregistered [ 8953.727407] LNet: 455894:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8954.790871] LNet: Removed LNI 192.168.202.9@tcp [ 8955.025262] Key type .llcrypt unregistered [ 8955.026297] Key type ._llcrypt unregistered [ 8955.314823] Key type ._llcrypt registered [ 8955.316233] Key type .llcrypt registered [ 8955.566730] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8955.572490] alg: No test for adler32 (adler32-zlib) [ 8956.431978] Lustre: Lustre: Build Version: 2.16.56_105_g447ed16 [ 8956.516632] LNet: Added LNI 192.168.202.9@tcp [8/256/0/180] [ 8956.518890] LNet: Accept secure, port 988 [ 8958.104184] Key type lgssc registered [ 8958.460647] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8962.083826] Lustre: DEBUG MARKER: Iteration 38 [ 8962.205791] LustreError: 456719:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 8962.206614] LustreError: 456720:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 8962.211847] LustreError: 456719:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 8963.283463] Lustre: Mounted lustre-client [ 8963.869494] LustreError: 456826:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e3704820000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8963.871465] LustreError: 456825:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 8963.872288] LustreError: 456826:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 8963.920136] Lustre: Unmounted lustre-client [ 8965.013913] Key type lgssc unregistered [ 8965.138752] LNet: 457068:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8966.183907] LNet: Removed LNI 192.168.202.9@tcp [ 8966.467076] Key type .llcrypt unregistered [ 8966.468102] Key type ._llcrypt unregistered [ 8966.783350] Key type ._llcrypt registered [ 8966.784307] Key type .llcrypt registered [ 8966.992807] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8966.997735] alg: No test for adler32 (adler32-zlib) [ 8967.858546] Lustre: Lustre: Build Version: 2.16.56_105_g447ed16 [ 8967.940674] LNet: Added LNI 192.168.202.9@tcp [8/256/0/180] [ 8967.942360] LNet: Accept secure, port 988 [ 8969.528106] Key type lgssc registered [ 8969.888313] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8973.340873] Lustre: DEBUG MARKER: Iteration 39 [ 8973.474662] LustreError: 457895:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 8973.474713] LustreError: 457896:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 8973.479151] LustreError: 457895:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 8974.556983] Lustre: Mounted lustre-client [ 8975.090421] LustreError: 458000:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e371823b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8975.093806] LustreError: 458000:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 8975.094853] LustreError: 458001:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 8975.097927] LustreError: 458001:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 8975.139061] Lustre: Unmounted lustre-client [ 8976.162845] Key type lgssc unregistered [ 8976.284745] LNet: 458241:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8977.318532] LNet: Removed LNI 192.168.202.9@tcp [ 8977.564182] Key type .llcrypt unregistered [ 8977.565213] Key type ._llcrypt unregistered [ 8977.830601] Key type ._llcrypt registered [ 8977.831877] Key type .llcrypt registered [ 8978.084635] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8978.089915] alg: No test for adler32 (adler32-zlib) [ 8978.953782] Lustre: Lustre: Build Version: 2.16.56_105_g447ed16 [ 8979.052416] LNet: Added LNI 192.168.202.9@tcp [8/256/0/180] [ 8979.055560] LNet: Accept secure, port 988 [ 8980.648164] Key type lgssc registered [ 8981.006648] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8984.542575] Lustre: DEBUG MARKER: Iteration 40 [ 8984.674757] LustreError: 459065:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 8984.676573] LustreError: 459066:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 8984.680451] LustreError: 459065:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8985.745981] Lustre: Mounted lustre-client [ 8986.201175] LustreError: 459169:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e37326e3800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8986.208892] LustreError: 459169:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 8986.212201] LustreError: 459169:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 8986.247711] Lustre: Unmounted lustre-client [ 8987.265332] Key type lgssc unregistered [ 8987.397950] LNet: 459411:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8988.456055] LNet: Removed LNI 192.168.202.9@tcp [ 8988.805520] Key type .llcrypt unregistered [ 8988.806558] Key type ._llcrypt unregistered [ 8989.206022] Key type ._llcrypt registered [ 8989.206991] Key type .llcrypt registered [ 8989.422040] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8989.427399] alg: No test for adler32 (adler32-zlib) [ 8990.293636] Lustre: Lustre: Build Version: 2.16.56_105_g447ed16 [ 8990.381879] LNet: Added LNI 192.168.202.9@tcp [8/256/0/180] [ 8990.383577] LNet: Accept secure, port 988 [ 8991.968178] Key type lgssc registered [ 8992.392861] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8996.181671] Lustre: DEBUG MARKER: Iteration 41 [ 8996.300799] LustreError: 460236:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 8996.300861] LustreError: 460237:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 8996.305132] LustreError: 460236:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8997.356738] Lustre: Mounted lustre-client [ 8997.931506] LustreError: 460338:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e3706364800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8997.939070] LustreError: 460338:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 8997.942766] LustreError: 460338:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 8997.995193] Lustre: Unmounted lustre-client [ 8999.137609] Key type lgssc unregistered [ 8999.270728] LNet: 460582:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9000.295521] LNet: Removed LNI 192.168.202.9@tcp [ 9000.602269] Key type .llcrypt unregistered [ 9000.603464] Key type ._llcrypt unregistered [ 9000.977277] Key type ._llcrypt registered [ 9000.979524] Key type .llcrypt registered [ 9001.230309] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9001.235244] alg: No test for adler32 (adler32-zlib) [ 9002.113720] Lustre: Lustre: Build Version: 2.16.56_105_g447ed16 [ 9002.220843] LNet: Added LNI 192.168.202.9@tcp [8/256/0/180] [ 9002.223361] LNet: Accept secure, port 988 [ 9003.816211] Key type lgssc registered [ 9004.221660] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9007.752818] Lustre: DEBUG MARKER: Iteration 42 [ 9007.884286] LustreError: 461409:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 9007.885980] LustreError: 461408:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 9007.890211] LustreError: 461409:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 9008.954690] Lustre: Mounted lustre-client [ 9009.516912] LustreError: 461511:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e371823f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9009.518943] LustreError: 461512:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 9009.520825] LustreError: 461511:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 9009.561726] Lustre: Unmounted lustre-client [ 9010.717203] Key type lgssc unregistered [ 9010.856399] LNet: 461756:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9011.879336] LNet: Removed LNI 192.168.202.9@tcp [ 9012.149413] Key type .llcrypt unregistered [ 9012.151188] Key type ._llcrypt unregistered [ 9012.420078] Key type ._llcrypt registered [ 9012.427911] Key type .llcrypt registered [ 9012.796062] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9012.801366] alg: No test for adler32 (adler32-zlib) [ 9013.668637] Lustre: Lustre: Build Version: 2.16.56_105_g447ed16 [ 9013.758849] LNet: Added LNI 192.168.202.9@tcp [8/256/0/180] [ 9013.760329] LNet: Accept secure, port 988 [ 9015.344180] Key type lgssc registered [ 9015.700952] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9019.377655] Lustre: DEBUG MARKER: Iteration 43 [ 9019.483594] LustreError: 462580:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 9019.484692] LustreError: 462581:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 9019.488731] LustreError: 462580:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 9020.537230] Lustre: Mounted lustre-client [ 9020.538702] Lustre: Skipped 1 previous similar message [ 9020.980344] LustreError: 462684:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e371823c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9020.982569] LustreError: 462685:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 9020.984112] LustreError: 462684:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 9021.015535] Lustre: Unmounted lustre-client [ 9022.041507] Key type lgssc unregistered [ 9022.157455] LNet: 462927:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9023.206678] LNet: Removed LNI 192.168.202.9@tcp [ 9023.452187] Key type .llcrypt unregistered [ 9023.453675] Key type ._llcrypt unregistered [ 9023.717287] Key type ._llcrypt registered [ 9023.718599] Key type .llcrypt registered [ 9023.910742] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9023.917260] alg: No test for adler32 (adler32-zlib) [ 9024.778551] Lustre: Lustre: Build Version: 2.16.56_105_g447ed16 [ 9024.862650] LNet: Added LNI 192.168.202.9@tcp [8/256/0/180] [ 9024.864797] LNet: Accept secure, port 988 [ 9026.440215] Key type lgssc registered [ 9026.806853] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9030.386207] Lustre: DEBUG MARKER: Iteration 44 [ 9030.490322] LustreError: 463753:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 9030.490374] LustreError: 463754:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 9030.494536] LustreError: 463753:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 9031.547291] Lustre: Mounted lustre-client [ 9031.548638] Lustre: Skipped 1 previous similar message [ 9031.990349] LustreError: 463855:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e371871a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9031.995663] LustreError: 463855:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 9031.997653] LustreError: 463855:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 9032.034424] Lustre: Unmounted lustre-client [ 9033.066449] Key type lgssc unregistered [ 9033.181485] LNet: 464097:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9034.216040] LNet: Removed LNI 192.168.202.9@tcp [ 9034.538172] Key type .llcrypt unregistered [ 9034.539522] Key type ._llcrypt unregistered [ 9034.948225] Key type ._llcrypt registered [ 9034.949326] Key type .llcrypt registered [ 9035.112364] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9035.117234] alg: No test for adler32 (adler32-zlib) [ 9036.024717] Lustre: Lustre: Build Version: 2.16.56_105_g447ed16 [ 9036.119212] LNet: Added LNI 192.168.202.9@tcp [8/256/0/180] [ 9036.121314] LNet: Accept secure, port 988 [ 9037.712148] Key type lgssc registered [ 9038.175132] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9041.844153] Lustre: DEBUG MARKER: Iteration 45 [ 9041.965512] LustreError: 464922:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 9041.965543] LustreError: 464923:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 9041.969697] LustreError: 464922:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 9043.035839] Lustre: Mounted lustre-client [ 9043.037114] Lustre: Skipped 1 previous similar message [ 9043.555334] LustreError: 465027:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e3704220800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9043.560765] LustreError: 465027:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 9043.603570] Lustre: Unmounted lustre-client [ 9044.826646] Key type lgssc unregistered [ 9044.982617] LNet: 465267:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9046.056144] LNet: Removed LNI 192.168.202.9@tcp [ 9046.407577] Key type .llcrypt unregistered [ 9046.409677] Key type ._llcrypt unregistered [ 9046.667276] Key type ._llcrypt registered [ 9046.668513] Key type .llcrypt registered [ 9046.956076] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9046.960973] alg: No test for adler32 (adler32-zlib) [ 9047.844720] Lustre: Lustre: Build Version: 2.16.56_105_g447ed16 [ 9047.958131] LNet: Added LNI 192.168.202.9@tcp [8/256/0/180] [ 9047.959784] LNet: Accept secure, port 988 [ 9049.552119] Key type lgssc registered [ 9049.904015] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9053.692214] Lustre: DEBUG MARKER: Iteration 46 [ 9053.818729] LustreError: 466093:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 9053.823837] LustreError: 466094:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 9053.827369] LustreError: 466093:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [ 9054.887142] Lustre: Mounted lustre-client [ 9054.888111] Lustre: Skipped 1 previous similar message [ 9055.407163] LustreError: 466196:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e3718718800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9055.410184] LustreError: 466195:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 9055.410745] LustreError: 466196:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 9055.449338] Lustre: Unmounted lustre-client [ 9056.500876] Key type lgssc unregistered [ 9056.623670] LNet: 466437:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9057.639219] LNet: Removed LNI 192.168.202.9@tcp [ 9057.892810] Key type .llcrypt unregistered [ 9057.893750] Key type ._llcrypt unregistered [ 9058.201979] Key type ._llcrypt registered [ 9058.203063] Key type .llcrypt registered [ 9058.452077] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9058.457459] alg: No test for adler32 (adler32-zlib) [ 9059.326068] Lustre: Lustre: Build Version: 2.16.56_105_g447ed16 [ 9059.427096] LNet: Added LNI 192.168.202.9@tcp [8/256/0/180] [ 9059.429207] LNet: Accept secure, port 988 [ 9061.016113] Key type lgssc registered [ 9061.364363] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9064.598824] Lustre: DEBUG MARKER: Iteration 47 [ 9064.736537] LustreError: 467261:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 9064.736656] LustreError: 467262:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 9064.740986] LustreError: 467261:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 9065.814771] Lustre: Mounted lustre-client [ 9065.815772] Lustre: Skipped 1 previous similar message [ 9066.418807] LustreError: 467366:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e372ff1f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9066.421774] LustreError: 467366:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 9066.424339] LustreError: 467367:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 9066.482591] Lustre: Unmounted lustre-client [ 9067.739698] Key type lgssc unregistered [ 9067.859807] LNet: 467610:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9068.903385] LNet: Removed LNI 192.168.202.9@tcp [ 9069.158111] Key type .llcrypt unregistered [ 9069.159342] Key type ._llcrypt unregistered [ 9069.428224] Key type ._llcrypt registered [ 9069.434580] Key type .llcrypt registered [ 9069.686323] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9069.698757] alg: No test for adler32 (adler32-zlib) [ 9070.565823] Lustre: Lustre: Build Version: 2.16.56_105_g447ed16 [ 9070.657947] LNet: Added LNI 192.168.202.9@tcp [8/256/0/180] [ 9070.660533] LNet: Accept secure, port 988 [ 9072.248207] Key type lgssc registered [ 9072.725731] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9076.788727] Lustre: DEBUG MARKER: Iteration 48 [ 9076.921099] LustreError: 468434:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 9076.921216] LustreError: 468435:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 9076.924941] LustreError: 468434:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 9077.982458] Lustre: Mounted lustre-client [ 9077.984332] Lustre: Skipped 1 previous similar message [ 9078.592357] LustreError: 468536:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e3719a41000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9078.595535] LustreError: 468536:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 9078.596214] LustreError: 468535:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 9078.657433] Lustre: Unmounted lustre-client [ 9079.719280] Key type lgssc unregistered [ 9079.847754] LNet: 468778:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9080.871443] LNet: Removed LNI 192.168.202.9@tcp [ 9081.128072] Key type .llcrypt unregistered [ 9081.129091] Key type ._llcrypt unregistered [ 9081.505273] Key type ._llcrypt registered [ 9081.506189] Key type .llcrypt registered [ 9081.706334] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9081.711482] alg: No test for adler32 (adler32-zlib) [ 9082.575127] Lustre: Lustre: Build Version: 2.16.56_105_g447ed16 [ 9082.661518] LNet: Added LNI 192.168.202.9@tcp [8/256/0/180] [ 9082.663184] LNet: Accept secure, port 988 [ 9084.256214] Key type lgssc registered [ 9084.699732] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9088.247643] Lustre: DEBUG MARKER: Iteration 49 [ 9088.358627] LustreError: 469603:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 9088.358672] LustreError: 469604:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 9088.362907] LustreError: 469603:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 9089.418500] Lustre: Mounted lustre-client [ 9089.419417] Lustre: Skipped 1 previous similar message [ 9089.914137] LustreError: 469702:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e37035f5800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9089.915919] LustreError: 469703:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 9089.918152] LustreError: 469702:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 9089.966077] Lustre: Unmounted lustre-client [ 9089.967409] Lustre: Skipped 1 previous similar message [ 9091.182834] Key type lgssc unregistered [ 9091.308111] LNet: 469945:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9092.327787] LNet: Removed LNI 192.168.202.9@tcp [ 9092.618213] Key type .llcrypt unregistered [ 9092.619774] Key type ._llcrypt unregistered [ 9092.972369] Key type ._llcrypt registered [ 9092.973379] Key type .llcrypt registered [ 9093.171857] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9093.177038] alg: No test for adler32 (adler32-zlib) [ 9094.036351] Lustre: Lustre: Build Version: 2.16.56_105_g447ed16 [ 9094.124131] LNet: Added LNI 192.168.202.9@tcp [8/256/0/180] [ 9094.125735] LNet: Accept secure, port 988 [ 9095.712214] Key type lgssc registered [ 9096.093271] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9099.908119] Lustre: DEBUG MARKER: Iteration 50 [ 9100.025463] LustreError: 470770:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 9100.025542] LustreError: 470771:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 9100.031347] LustreError: 470770:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 9101.098887] Lustre: Mounted lustre-client [ 9101.606886] LustreError: 470868:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e37035f3800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9101.612461] LustreError: 470868:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 9101.615996] LustreError: 470868:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [ 9101.648350] Lustre: Unmounted lustre-client [ 9102.748671] Key type lgssc unregistered [ 9102.881464] LNet: 471115:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9103.911764] LNet: Removed LNI 192.168.202.9@tcp [ 9104.174120] Key type .llcrypt unregistered [ 9104.175052] Key type ._llcrypt unregistered [ 9104.507826] Key type ._llcrypt registered [ 9104.509821] Key type .llcrypt registered [ 9104.762827] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9104.769314] alg: No test for adler32 (adler32-zlib) [ 9105.633396] Lustre: Lustre: Build Version: 2.16.56_105_g447ed16 [ 9105.720647] LNet: Added LNI 192.168.202.9@tcp [8/256/0/180] [ 9105.722498] LNet: Accept secure, port 988 [ 9107.312152] Key type lgssc registered [ 9107.759331] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9112.252150] Lustre: Mounted lustre-client [ 9114.703272] Lustre: DEBUG MARKER: == sanityn test 110: do not grant another lock on resend ========================================================== 20:55:12 (1753750512) [ 9131.488136] Lustre: 472452:0:(client.c:2453:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1753750513/real 1753750513] req@ffff9e373e791f80 x1838940687443840/t0(0) o36->lustre-MDT0000-mdc-ffff9e373f1e7000@192.168.202.109@tcp:12/10 lens 496/440 e 0 to 1 dl 1753750529 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 9131.508597] Lustre: lustre-MDT0000-mdc-ffff9e373f1e7000: Connection to lustre-MDT0000 (at 192.168.202.109@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9131.528908] Lustre: lustre-MDT0000-mdc-ffff9e373f1e7000: Connection restored to (at 192.168.202.109@tcp) [ 9147.872191] Lustre: 472452:0:(client.c:2453:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1753750529/real 1753750529] req@ffff9e373e791f80 x1838940687443840/t0(0) o36->lustre-MDT0000-mdc-ffff9e373f1e7000@192.168.202.109@tcp:12/10 lens 496/440 e 0 to 1 dl 1753750545 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 9147.883345] Lustre: lustre-MDT0000-mdc-ffff9e373f1e7000: Connection to lustre-MDT0000 (at 192.168.202.109@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9147.894729] Lustre: lustre-MDT0000-mdc-ffff9e373f1e7000: Connection restored to (at 192.168.202.109@tcp) [ 9163.232151] Lustre: 472452:0:(client.c:2453:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1753750545/real 1753750545] req@ffff9e373e791f80 x1838940687443840/t0(0) o36->lustre-MDT0000-mdc-ffff9e373f1e7000@192.168.202.109@tcp:12/10 lens 496/440 e 0 to 1 dl 1753750561 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 9163.240111] Lustre: lustre-MDT0000-mdc-ffff9e373f1e7000: Connection to lustre-MDT0000 (at 192.168.202.109@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9163.247862] Lustre: lustre-MDT0000-mdc-ffff9e373f1e7000: Connection restored to (at 192.168.202.109@tcp) [ 9179.616186] Lustre: 472452:0:(client.c:2453:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1753750561/real 1753750561] req@ffff9e373e791f80 x1838940687443840/t0(0) o36->lustre-MDT0000-mdc-ffff9e373f1e7000@192.168.202.109@tcp:12/10 lens 496/440 e 0 to 1 dl 1753750577 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 9179.631635] Lustre: lustre-MDT0000-mdc-ffff9e373f1e7000: Connection to lustre-MDT0000 (at 192.168.202.109@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9179.650136] Lustre: lustre-MDT0000-mdc-ffff9e373f1e7000: Connection restored to (at 192.168.202.109@tcp) [ 9180.206289] Lustre: DEBUG MARKER: == sanityn test 111: A racy rename/link an open file should not cause fs corruption ========================================================== 20:56:17 (1753750577) [ 9185.818480] Lustre: DEBUG MARKER: == sanityn test 112: update max-inherit in default LMV === 20:56:23 (1753750583) [ 9189.135649] Lustre: DEBUG MARKER: == sanityn test 113: check servers of specified fs ======= 20:56:26 (1753750586) [ 9191.199376] Lustre: DEBUG MARKER: == sanityn test 114: implicit default LMV inherit ======== 20:56:28 (1753750588) [ 9197.989491] Lustre: DEBUG MARKER: == sanityn test 115: ldiskfs doesn't check direntry for uniqueness ========================================================== 20:56:35 (1753750595) [ 9210.060911] Lustre: DEBUG MARKER: == sanityn test 116: DNE: Set default LMV layout from a remote client ========================================================== 20:56:47 (1753750607) [ 9212.411567] Lustre: DEBUG MARKER: == sanityn test 200: service remains healthy while able to process request ========================================================== 20:56:49 (1753750609) [ 9230.752184] Lustre: 471305:0:(client.c:2453:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1753750612/real 1753750612] req@ffff9e372d82a680 x1838940688471040/t0(0) o4->lustre-OST0000-osc-ffff9e373f1e7000@192.168.202.109@tcp:6/4 lens 4584/448 e 0 to 1 dl 1753750628 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 9230.760028] Lustre: lustre-OST0000-osc-ffff9e373f1e7000: Connection to lustre-OST0000 (at 192.168.202.109@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9297.560959] Lustre: DEBUG MARKER: oleg209-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9e37060d8800.ost_server_uuid 50 [ 9298.054468] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9e37060d8800.ost_server_uuid in IDLE state after 0 sec [ 9298.601865] Lustre: DEBUG MARKER: cleanup: ====================================================== [ 9299.182251] Lustre: DEBUG MARKER: == sanityn test complete, duration 9021 sec ============== 20:58:16 (1753750696) [ 9299.730129] Lustre: DEBUG MARKER: === sanityn: start cleanup 20:58:17 (1753750697) === [ 9363.612412] LustreError: 478711:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e37060d8800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9363.616166] LustreError: 478711:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 9363.632308] Lustre: Unmounted lustre-client [ 9364.810756] Lustre: DEBUG MARKER: === sanityn: finish cleanup 20:59:22 (1753750762) === [ 9365.133629] LustreError: 479015:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e373f1e7000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9365.136605] LustreError: 479015:0:(lov_obd.c:784:lov_cleanup()) Skipped 1 previous similar message [ 9365.141827] LustreError: 479015:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 9365.143455] LustreError: 479015:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 9365.175106] Lustre: Unmounted lustre-client [ 9404.728661] Key type lgssc unregistered [ 9404.867741] LNet: 479695:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9405.927058] LNet: Removed LNI 192.168.202.9@tcp [ 9406.194056] Key type .llcrypt unregistered [ 9406.195151] Key type ._llcrypt unregistered