[LU-9722] parallel-scale-nfsv4 test_racer_on_nfs: client hit BUG: unable to handle kernel paging request

• Status: Resolved
• Project: Lustre
• Affects Version/s: Lustre 2.10.0
• Type: Bug
• Priority: Minor
• Reporter: Maloo
• Assignee: WC Triage
• Resolution: Cannot Reproduce
• Severity: 3

Description

This issue was created by maloo for sarah_lw <[e-mail]>

This issue relates to the following test suite run: https://testing.hpdd.intel.com/test_sets/75b8f92e-5c69-11e7-9221-5254006e85c2.

The sub-test test_racer_on_nfs failed with the following error:

test failed to respond and timed out

server: b2_10 tag-rc1 build#2
client: SLES12SP2

client console (this is also NFS client)

23:21:29:[ 1665.887101] Lustre: DEBUG MARKER: == parallel-scale-nfsv4 test racer_on_nfs: racer on NFS client ======================================= 16:17:07 (1498691827)
23:22:20:[ 1920.341917] BUG: unable to handle kernel paging request at 000000400000003f
23:22:20:[ 1920.344585] IP: [<ffffffff815e3cec>] _raw_spin_lock+0xc/0x20
23:22:20:[ 1920.344875] PGD 7b145067 PUD 0 
23:22:20:[ 1920.344875] Oops: 0002 [#1] SMP 
23:22:20:[ 1920.344875] Modules linked in: lustre(OEN) obdecho(OEN) mgc(OEN) lov(OEN) osc(OEN) mdc(OEN) lmv(OEN) fid(OEN) fld(OEN) ptlrpc_gss(OEN) ptlrpc(OEN) obdclass(OEN) ksocklnd(OEN) lnet(OEN) libcfs(OEN) rpcsec_gss_krb5 auth_rpcgss nfsv4 dns_resolver nfs lockd grace sunrpc fscache af_packet iscsi_boot_sysfs ib_ipoib rdma_ucm ib_ucm ib_uverbs ib_umad rdma_cm configfs ib_cm iw_cm ib_sa ib_mad ib_core ib_addr crct10dif_pclmul crc32_pclmul crc32c_intel ghash_clmulni_intel jitterentropy_rng drbg ansi_cprng ppdev aesni_intel aes_x86_64 lrw gf128mul glue_helper ablk_helper cryptd 8139too pcspkr joydev 8139cp virtio_balloon mii i2c_piix4 parport_pc parport pvpanic acpi_cpufreq processor button ata_generic ext4 crc16 jbd2 mbcache virtio_blk floppy ata_piix ahci libahci cirrus serio_raw drm_kms_helper syscopyarea sysfillrect sysimgblt fb_sys_fops ttm uhci_hcd ehci_hcd usbcore libata drm usb_common virtio_pci virtio_ring virtio sg dm_multipath dm_mod scsi_dh_rdac scsi_dh_emc scsi_dh_alua scsi_mod autofs4
23:22:20:[ 1920.356022] Supported: No, Unsupported modules are loaded
23:22:20:[ 1920.356022] CPU: 0 PID: 18995 Comm: kworker/0:1H Tainted: G           OE   N  4.4.59-92.17-default #1
23:22:20:[ 1920.356022] Hardware name: Red Hat KVM, BIOS 0.5.1 01/01/2007
23:22:20:[ 1920.356022] Workqueue: rpciod rpc_async_schedule [sunrpc]
23:22:20:[ 1920.356022] task: ffff88007a6e1900 ti: ffff88006825c000 task.ti: ffff88006825c000
23:22:20:[ 1920.356022] RIP: 0010:[<ffffffff815e3cec>]  [<ffffffff815e3cec>] _raw_spin_lock+0xc/0x20
23:22:20:[ 1920.356022] RSP: 0018:ffff88006825fdb0  EFLAGS: 00010246
23:22:20:[ 1920.356022] RAX: 0000000000000000 RBX: ffff880077815400 RCX: ffff88007fc15818
23:22:20:[ 1920.356022] RDX: 0000000000000001 RSI: ffff880068292500 RDI: 000000400000003f
23:22:20:[ 1920.356022] RBP: ffff88007a835400 R08: 0000000000000001 R09: 0000000000000000
23:22:20:[ 1920.356022] R10: ffff88007ad51b50 R11: 0000000100062e5e R12: ffff880068292500
23:22:20:[ 1920.356022] R13: ffff88006555c1c8 R14: ffffffff81d47500 R15: 0000000000000020
23:22:20:[ 1920.356022] FS:  0000000000000000(0000) GS:ffff88007fc00000(0000) knlGS:0000000000000000
23:22:20:[ 1920.356022] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
23:22:20:[ 1920.356022] CR2: 000000400000003f CR3: 0000000067937000 CR4: 00000000000406f0
23:22:20:[ 1920.356022] Stack:
23:22:20:[ 1920.356022]  ffffffffa06148ef ffff880068292500 ffff88007c35c600 ffffffffa0520340
23:22:20:[ 1920.356022]  ffffffffa0520340 ffffffffa0521c35 ffff880068292570 000000000001cb3a
23:22:20:[ 1920.356022]  0000000000000000 0000000000200000 ffff88007ad51800 ffff880068292588
23:22:20:[ 1920.356022] Call Trace:
23:22:20:[ 1920.356022]  [<ffffffffa06148ef>] nfs4_close_prepare+0x4f/0x230 [nfsv4]
23:22:20:[ 1920.356022]  [<ffffffffa0521c35>] __rpc_execute+0x85/0x440 [sunrpc]
23:22:20:[ 1920.356022]  [<ffffffff81093c9e>] process_one_work+0x14e/0x410
23:22:20:[ 1920.356022]  [<ffffffff810944f6>] worker_thread+0x116/0x490
23:22:20:[ 1920.356022]  [<ffffffff8109999d>] kthread+0xbd/0xe0
23:22:20:[ 1920.356022]  [<ffffffff815e437f>] ret_from_fork+0x3f/0x70
23:22:20:[ 1920.356022] DWARF2 unwinder stuck at ret_from_fork+0x3f/0x70
23:22:20:[ 1920.356022] 
23:22:20:[ 1920.356022] Leftover inexact backtrace:
23:22:20:[ 1920.356022] 
23:22:20:[ 1920.356022]  [<ffffffff810998e0>] ? kthread_park+0x50/0x50
23:22:20:[ 1920.356022] Code: 66 66 66 90 8b 07 85 c0 74 03 31 c0 c3 ba 01 00 00 00 f0 0f b1 17 85 c0 75 f0 b0 01 c3 66 90 66 66 66 66 90 31 c0 ba 01 00 00 00 <f0> 0f b1 17 85 c0 75 02 f3 c3 89 c6 e9 23 f0 b9 ff 0f 1f 00 66 
23:22:20:[ 1920.356022] RIP  [<ffffffff815e3cec>] _raw_spin_lock+0xc/0x20
23:22:20:[ 1920.356022]  RSP <ffff88006825fdb0>
23:22:21:[ 1920.356022] CR2: 000000400000003f
23:22:21:[    0.075835] ioremap error for 0x7ffff000-0x80000000, requested 0x2, got 0x0
23:22:21:[    0.076059] dmi: Firmware registration failed.
23:22:21:[    1.862954] virtio-pci 0000:00:04.0: virtio_pci: leaving for legacy driver
23:22:21:[    1.874890] ACPI: PCI Interrupt Link [LNKA] enabled at IRQ 10
23:22:21:[    1.874919] virtio-pci 0000:00:05.0: virtio_pci: leaving for legacy driver
23:22:21:[    1.879894] [drm] Initialized drm 1.1.0 20060810
23:22:21:[    1.888111] ACPI: bus type USB registered
23:22:21:[    1.888134] usbcore: registered new interface driver usbfs
23:22:21:[    1.888144] usbcore: registered new interface driver hub
23:22:21:[    1.888160] usbcore: registered new device driver usb
23:22:21:[    1.891538] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
23:22:21:[    1.893282] uhci_hcd: USB Universal Host Controller Interface driver
23:22:21:[    1.893471] uhci_hcd 0000:00:01.2: UHCI Host Controller
23:22:21:[    1.893476] uhci_hcd 0000:00:01.2: new USB bus registered, assigned bus number 1
23:22:21:[    1.893487] uhci_hcd 0000:00:01.2: detected 2 ports
23:22:21:[    1.893564] uhci_hcd 0000:00:01.2: irq 11, io base 0x0000c020
23:22:21:[    1.920394] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001
23:22:21:[    1.920396] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
23:22:21:[    1.920397] usb usb1: Product: UHCI Host Controller
23:22:21:[    1.920398] usb usb1: Manufacturer: Linux 4.4.59-92.17-default uhci_hcd
23:22:21:[    1.920398] usb usb1: SerialNumber: 0000:00:01.2
23:22:21:[    1.922896] hub 1-0:1.0: USB hub found
23:22:21:[    1.922901] hub 1-0:1.0: 2 ports detected
23:22:21:[    1.964988] [TTM] Zone  kernel: Available graphics memory: 54438 kiB
23:22:21:[    1.964989] [TTM] Initializing pool allocator
23:22:21:[    1.964993] [TTM] Initializing DMA pool allocator
23:22:21:[    1.965212] [drm] fb mappable at 0xF0000000
23:22:21:[    1.965212] [drm] vram aper at 0xF0000000
23:22:21:[    1.965213] [drm] size 33554432
23:22:21:[    1.965213] [drm] fb depth is 24
23:22:21:[    1.965213] [drm]    pitch is 3072
23:22:21:[    1.968104] fbcon: cirrusdrmfb (fb0) is primary device
23:22:21:[    1.977026] Console: switching to colour frame buffer device 128x48
23:22:21:[    2.080598] cirrus 0000:00:02.0: fb0: cirrusdrmfb frame buffer device
23:22:21:[    2.080609] [drm] Initialized cirrus 1.0.0 20110418 for 0000:00:02.0 on minor 0
23:22:21:[    2.120038] scsi host0: ata_piix
23:22:21:[    2.127582] scsi host1: ata_piix
23:22:21:[    2.127631] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc000 irq 14
23:22:21:[    2.127632] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc008 irq 15
23:22:21:[    2.136844]  vda: vda1 vda2 vda4
23:22:21:[    2.156038] tsc: Refined TSC clocksource calibration: 2693.368 MHz
23:22:21:[    2.156045] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x26d2c56d3d5, max_idle_ns: 440795236509 ns
23:22:21:[    2.456637] EXT4-fs (vda1): mounting ext3 file system using the ext4 subsystem
23:22:21:[    2.458338] EXT4-fs (vda1): mounted filesystem with ordered data mode. Opts: acl,user_xattr
23:22:21:[    5.080061] floppy0: no floppy controllers found
23:22:21:[    5.080093] work still pending
23:22:21:[?25lGNU GRUB  version 2.02~beta2
23:22:21:
23:22:21:
+----------------------------------------------------------------------------+||||||||||||||||||||||||+----------------------------------------------------------------------------+     Use the ^ and v keys to select which entry is highlighted.          
23:22:21:
      Press enter to boot the selected OS, `e' to edit the commands       
00:17:38:********** Timeout by autotest system **********

Comments

[Andreas Dilger]

Closing old issue that has not been seen in a long time.