Bug 110853

Summary: [CI][DRMTIP] igt@gem_create@create-clear - dmesg-warn - WARNING: suspicious RCU usage
Product: DRI Reporter: Lakshmi <lakshminarayana.vudum>
Component: DRM/IntelAssignee: Intel GFX Bugs mailing list <intel-gfx-bugs>
Status: RESOLVED WORKSFORME QA Contact: Intel GFX Bugs mailing list <intel-gfx-bugs>
Severity: normal    
Priority: medium CC: intel-gfx-bugs
Version: DRI git   
Hardware: Other   
OS: All   
Whiteboard: ReadyForDev
i915 platform: CFL i915 features: GEM/Other

Description Lakshmi 2019-06-07 11:44:42 UTC 
https://intel-gfx-ci.01.org/tree/drm-tip/drmtip_301/fi-cfl-8700k/igt@gem_create@create-clear.html

<6> [244.290357] Console: switching to colour dummy device 80x25
<6> [244.290390] [IGT] gem_create: executing
<6> [244.292838] [IGT] gem_create: starting subtest create-clear
<4> [251.224775] 
<4> [251.224812] =============================
<4> [251.224813] WARNING: suspicious RCU usage
<4> [251.224815] 5.2.0-rc2-g63e1cb5d17f9-drmtip_301+ #1 Tainted: G     U           
<4> [251.224817] -----------------------------
<4> [251.224819] ./include/linux/xarray.h:1136 suspicious rcu_dereference_protected() usage!
<4> [251.224821] 
other info that might help us debug this:

<4> [251.224823] 
rcu_scheduler_active = 2, debug_locks = 1
<4> [251.224825] 5 locks held by gem_create/2826:
<4> [251.224827]  #0: 00000000fdbd653a (reservation_ww_class_mutex){+.+.}, at: i915_gem_object_prepare_read+0x35/0x240 [i915]
<4> [251.224869]  #1: 000000001b16d3b5 (fs_reclaim){+.+.}, at: fs_reclaim_acquire.part.24+0x0/0x30
<4> [251.224875]  #2: 0000000023e7ceb4 (shrinker_rwsem){++++}, at: shrink_slab+0x1cb/0x2c0
<4> [251.224880]  #3: 00000000db617beb (&anon_vma->rwsem){++++}, at: split_huge_page_to_list+0x265/0x7f0
<4> [251.224883]  #4: 000000002336d838 (&(&pgdat->lru_lock)->rlock){-.-.}, at: split_huge_page_to_list+0x116/0x7f0
<4> [251.224887] 
stack backtrace:
<4> [251.224889] CPU: 9 PID: 2826 Comm: gem_create Tainted: G     U            5.2.0-rc2-g63e1cb5d17f9-drmtip_301+ #1
<4> [251.224891] Hardware name: Micro-Star International Co., Ltd. MS-7B54/Z370M MORTAR (MS-7B54), BIOS 1.00 10/31/2017
<4> [251.224892] Call Trace:
<4> [251.224896]  dump_stack+0x67/0x9b
<4> [251.224900]  xas_create+0x48a/0x4e0
<4> [251.224906]  xas_store+0x52/0x5e0
<4> [251.224913]  __xa_store+0x98/0x120
<4> [251.224919]  split_huge_page_to_list+0x5b1/0x7f0
<4> [251.224924]  ? mark_held_locks+0x49/0x70
<4> [251.224928]  ? _raw_spin_unlock_irqrestore+0x4c/0x60
<4> [251.224933]  deferred_split_scan+0x198/0x2b0
<4> [251.224938]  do_shrink_slab+0x131/0x3e0
<4> [251.224944]  shrink_slab+0x228/0x2c0
<4> [251.224951]  shrink_node+0x177/0x460
<4> [251.224960]  do_try_to_free_pages+0xc4/0x340
<4> [251.224964]  try_to_free_pages+0xc9/0x2c0
<4> [251.224971]  __alloc_pages_nodemask+0x665/0x1130
<4> [251.224977]  ? __lock_acquire+0x530/0x24c0
<4> [251.224980]  ? __lock_acquire+0x530/0x24c0
<4> [251.224986]  ? mark_held_locks+0x49/0x70
<4> [251.224991]  new_slab+0x323/0x440
<4> [251.224996]  ___slab_alloc.constprop.35+0x2d3/0x380
<4> [251.225027]  ? i915_gem_object_lock_fence+0x2b/0x110 [i915]
<4> [251.225033]  ? lock_acquire+0xa6/0x1c0
<4> [251.225062]  ? i915_gem_object_lock_fence+0x2b/0x110 [i915]
<4> [251.225065]  ? __slab_alloc.isra.28.constprop.34+0x3d/0x70
<4> [251.225067]  __slab_alloc.isra.28.constprop.34+0x3d/0x70
<4> [251.225095]  ? i915_gem_object_lock_fence+0x2b/0x110 [i915]
<4> [251.225097]  kmem_cache_alloc_trace+0x225/0x290
<4> [251.225125]  i915_gem_object_lock_fence+0x2b/0x110 [i915]
<4> [251.225155]  i915_gem_pread_ioctl+0x478/0x770 [i915]
<4> [251.225159]  ? drm_dev_exit+0x8/0x40
<4> [251.225191]  ? i915_gem_gtt_pread+0x6b0/0x6b0 [i915]
<4> [251.225195]  drm_ioctl_kernel+0x83/0xf0
<4> [251.225199]  drm_ioctl+0x2f3/0x3b0
<4> [251.225230]  ? i915_gem_gtt_pread+0x6b0/0x6b0 [i915]
<4> [251.225238]  ? lock_acquire+0xa6/0x1c0
<4> [251.225243]  do_vfs_ioctl+0xa0/0x6e0
<4> [251.225247]  ? __fget+0x10f/0x200
<4> [251.225253]  ksys_ioctl+0x35/0x60
<4> [251.225257]  __x64_sys_ioctl+0x11/0x20
<4> [251.225260]  do_syscall_64+0x55/0x1c0
<4> [251.225263]  entry_SYSCALL_64_after_hwframe+0x49/0xbe
<4> [251.225265] RIP: 0033:0x7f4802ed75d7
<4> [251.225267] Code: b3 66 90 48 8b 05 b1 48 2d 00 64 c7 00 26 00 00 00 48 c7 c0 ff ff ff ff c3 66 2e 0f 1f 84 00 00 00 00 00 b8 10 00 00 00 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8b 0d 81 48 2d 00 f7 d8 64 89 01 48
<4> [251.225269] RSP: 002b:00007f47f2a1fbb8 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
<4> [251.225270] RAX: ffffffffffffffda RBX: 00000000000077be RCX: 00007f4802ed75d7
<4> [251.225272] RDX: 00007f47f2a1fbf0 RSI: 000000004020645c RDI: 0000000000000005
<4> [251.225274] RBP: 00007f47f2a1fbf0 R08: 0000000000000008 R09: 00007f48031ac240
<4> [251.225276] R10: 00007f47f2a209d0 R11: 0000000000000246 R12: 000000004020645c
<4> [251.225278] R13: 0000000000000005 R14: 00007f47f2a1fc48 R15: 0000000000000e46
<4> [251.225287] 
<4> [251.225288] =============================
<4> [251.225290] WARNING: suspicious RCU usage
<4> [251.225291] 5.2.0-rc2-g63e1cb5d17f9-drmtip_301+ #1 Tainted: G     U           
<4> [251.225293] -----------------------------
<4> [251.225294] ./include/linux/xarray.h:1145 suspicious rcu_dereference_check() usage!
<4> [251.225295] 
other info that might help us debug this:

<4> [251.225297] 
rcu_scheduler_active = 2, debug_locks = 1
<4> [251.225299] 5 locks held by gem_create/2826:
<4> [251.225301]  #0: 00000000fdbd653a (reservation_ww_class_mutex){+.+.}, at: i915_gem_object_prepare_read+0x35/0x240 [i915]
<4> [251.225331]  #1: 000000001b16d3b5 (fs_reclaim){+.+.}, at: fs_reclaim_acquire.part.24+0x0/0x30
<4> [251.225334]  #2: 0000000023e7ceb4 (shrinker_rwsem){++++}, at: shrink_slab+0x1cb/0x2c0
<4> [251.225338]  #3: 00000000db617beb (&anon_vma->rwsem){++++}, at: split_huge_page_to_list+0x265/0x7f0
<4> [251.225341]  #4: 000000002336d838 (&(&pgdat->lru_lock)->rlock){-.-.}, at: split_huge_page_to_list+0x116/0x7f0
<4> [251.225344] 
stack backtrace:
<4> [251.225346] CPU: 9 PID: 2826 Comm: gem_create Tainted: G     U            5.2.0-rc2-g63e1cb5d17f9-drmtip_301+ #1
<4> [251.225348] Hardware name: Micro-Star International Co., Ltd. MS-7B54/Z370M MORTAR (MS-7B54), BIOS 1.00 10/31/2017
<4> [251.225350] Call Trace:
<4> [251.225354]  dump_stack+0x67/0x9b
<4> [251.225357]  xas_descend+0x114/0x120
<4> [251.225360]  xas_create+0xeb/0x4e0
<4> [251.225366]  xas_store+0x52/0x5e0
<4> [251.225372]  __xa_store+0x98/0x120
<4> [251.225379]  split_huge_page_to_list+0x5b1/0x7f0
<4> [251.225384]  ? mark_held_locks+0x49/0x70
<4> [251.225386]  ? _raw_spin_unlock_irqrestore+0x4c/0x60
<4> [251.225391]  deferred_split_scan+0x198/0x2b0
<4> [251.225396]  do_shrink_slab+0x131/0x3e0
<4> [251.225404]  shrink_slab+0x228/0x2c0
<4> [251.225410]  shrink_node+0x177/0x460
<4> [251.225417]  do_try_to_free_pages+0xc4/0x340
<4> [251.225423]  try_to_free_pages+0xc9/0x2c0
<4> [251.225430]  __alloc_pages_nodemask+0x665/0x1130
<4> [251.225436]  ? __lock_acquire+0x530/0x24c0
<4> [251.225438]  ? __lock_acquire+0x530/0x24c0
<4> [251.225444]  ? mark_held_locks+0x49/0x70
<4> [251.225450]  new_slab+0x323/0x440
<4> [251.225455]  ___slab_alloc.constprop.35+0x2d3/0x380
<4> [251.225482]  ? i915_gem_object_lock_fence+0x2b/0x110 [i915]
<4> [251.225487]  ? lock_acquire+0xa6/0x1c0
<4> [251.225515]  ? i915_gem_object_lock_fence+0x2b/0x110 [i915]
<4> [251.225519]  ? __slab_alloc.isra.28.constprop.34+0x3d/0x70
<4> [251.225521]  __slab_alloc.isra.28.constprop.34+0x3d/0x70
<4> [251.225549]  ? i915_gem_object_lock_fence+0x2b/0x110 [i915]
<4> [251.225551]  kmem_cache_alloc_trace+0x225/0x290
<4> [251.225579]  i915_gem_object_lock_fence+0x2b/0x110 [i915]
<4> [251.225609]  i915_gem_pread_ioctl+0x478/0x770 [i915]
<4> [251.225612]  ? drm_dev_exit+0x8/0x40
<4> [251.225644]  ? i915_gem_gtt_pread+0x6b0/0x6b0 [i915]
<4> [251.225648]  drm_ioctl_kernel+0x83/0xf0
<4> [251.225652]  drm_ioctl+0x2f3/0x3b0
<4> [251.225683]  ? i915_gem_gtt_pread+0x6b0/0x6b0 [i915]
<4> [251.225692]  ? lock_acquire+0xa6/0x1c0
<4> [251.225696]  do_vfs_ioctl+0xa0/0x6e0
<4> [251.225700]  ? __fget+0x10f/0x200
<4> [251.225706]  ksys_ioctl+0x35/0x60
<4> [251.225711]  __x64_sys_ioctl+0x11/0x20
<4> [251.225713]  do_syscall_64+0x55/0x1c0
<4> [251.225716]  entry_SYSCALL_64_after_hwframe+0x49/0xbe
<4> [251.225718] RIP: 0033:0x7f4802ed75d7
<4> [251.225720] Code: b3 66 90 48 8b 05 b1 48 2d 00 64 c7 00 26 00 00 00 48 c7 c0 ff ff ff ff c3 66 2e 0f 1f 84 00 00 00 00 00 b8 10 00 00 00 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8b 0d 81 48 2d 00 f7 d8 64 89 01 48
<4> [251.225721] RSP: 002b:00007f47f2a1fbb8 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
<4> [251.225723] RAX: ffffffffffffffda RBX: 00000000000077be RCX: 00007f4802ed75d7
<4> [251.225725] RDX: 00007f47f2a1fbf0 RSI: 000000004020645c RDI: 0000000000000005
<4> [251.225727] RBP: 00007f47f2a1fbf0 R08: 0000000000000008 R09: 00007f48031ac240
<4> [251.225729] R10: 00007f47f2a209d0 R11: 0000000000000246 R12: 000000004020645c
<4> [251.225731] R13: 0000000000000005 R14: 00007f47f2a1fc48 R15: 0000000000000e46
<6> [276.582132] gem_create (2820) used greatest stack depth: 11656 bytes left
<6> [277.108127] gem_create (2817) used greatest stack depth: 11448 bytes left
<6> [277.844989] gem_create (2826) used greatest stack depth: 11224 bytes left
<6> [278.472930] gem_create (2818) used greatest stack depth: 11016 bytes left
<6> [278.845177] [IGT] gem_create: exiting, ret=0
<5> [278.845772] Setting dangerous option reset - tainting kernel
<6> [278.924538] Console: switching to colour frame buffer device 240x67
Comment 1 CI Bug Log 2019-06-07 11:45:08 UTC 
The CI Bug Log issue associated to this bug has been updated.

### New filters associated

* CFL: igt@gem_create@create-clear - dmesg-warn - WARNING: suspicious RCU usage
  - https://intel-gfx-ci.01.org/tree/drm-tip/drmtip_301/fi-cfl-8700k/igt@gem_create@create-clear.html
Comment 2 Chris Wilson 2019-06-07 12:26:59 UTC 
Nothing to worry about; just

commit 2e5e960659d76767ff9e9ef04306dbf36f07f46f (topic/core-for-CI)
Author: Chris Wilson <chris@chris-wilson.co.uk>
Date:   Mon Jun 3 08:08:43 2019 +0100

    mm: Band aid for 5fd4ca2d84b249f0858ce28cf637cf25b61a398f
    

being lazy while we wait for the proper fix.
Comment 3 CI Bug Log 2019-06-25 06:35:21 UTC 
A CI Bug Log filter associated to this bug has been updated:

{- CFL: igt@gem_create@create-clear - dmesg-warn - WARNING: suspicious RCU usage -}
{+ all machines: random tests - dmesg-warn - WARNING: suspicious RCU usage +}

New failures caught by the filter:

  * https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_6334/shard-snb6/igt@gem_tiled_swapping@non-threaded.html
Comment 4 Chris Wilson 2019-07-08 07:49:10 UTC 
Buggy commit dropped from v5.2, band-aid removed from core-for-CI

Use of freedesktop.org services, including Bugzilla, is subject to our Code of Conduct. How we collect and use information is described in our Privacy Policy.