igt@gem_pwrite@basic-exhaustion - incomplete/timeout - gem_pwrite/\d+ is trying to acquire lock, at: __might_fault, but task is already holding lock:at: mcopy_atomic
https://intel-gfx-ci.01.org/tree/drm-tip/drmtip_1258/fi-kbl-soraka/igt@gem_pwrite@basic-exhaustion.html
<4> [761.911095] WARNING: possible recursive locking detected
<4> [761.911171] 6.1.0-rc2-drmtip_1258-g4679e20be07a+ #1 Not tainted
<4> [761.911259] --------------------------------------------
<4> [761.911336] gem_pwrite/1167 is trying to acquire lock:
<4> [761.911415] ffff8881002a6e58 (&mm->mmap_lock#2){++++}-{3:3}, at: __might_fault+0x31/0x80
<4> [761.911609]
but task is already holding lock:
<4> [761.911691] ffff8881002a6e58 (&mm->mmap_lock#2){++++}-{3:3}, at: mcopy_atomic+0xb3/0xcb0
<4> [761.911858]
other info that might help us debug this:
<4> [761.911949] Possible unsafe locking scenario:
<4> [761.912031] CPU0
<4> [761.912074] ----
<4> [761.912115] lock(&mm->mmap_lock#2);
<4> [761.912193] lock(&mm->mmap_lock#2);
<4> [761.912271]
*** DEADLOCK ***
<4> [761.912356] May be due to missing lock nesting notation
<4> [761.912452] 1 lock held by gem_pwrite/1167:
<4> [761.912524] #0: ffff8881002a6e58 (&mm->mmap_lock#2){++++}-{3:3}, at: mcopy_atomic+0xb3/0xcb0
<4> [761.912704]
stack backtrace:
<4> [761.912774] CPU: 2 PID: 1167 Comm: gem_pwrite Not tainted 6.1.0-rc2-drmtip_1258-g4679e20be07a+ #1
<4> [761.912911] Hardware name: Google Soraka/Soraka, BIOS MrChromebox-4.10 08/25/2019
<4> [761.913020] Call Trace:
<4> [761.913069] <TASK>
<4> [761.913116] dump_stack_lvl+0x56/0x7f
<4> [761.913202] validate_chain.cold.70+0x122/0x166
<4> [761.913306] ? wake_all_kswapds+0x58/0xc0
<4> [761.913403] __lock_acquire+0x5a4/0xb70
<4> [761.913505] lock_acquire+0xd3/0x310
<4> [761.913577] ? __might_fault+0x31/0x80
<4> [761.913688] __might_fault+0x5b/0x80
<4> [761.913765] ? __might_fault+0x31/0x80
<4> [761.913846] _copy_from_user+0x20/0xa0
<4> [761.913927] shmem_mfill_atomic_pte+0x35b/0x4c0
<4> [761.914034] mcopy_atomic+0x456/0xcb0
<4> [761.914163] userfaultfd_ioctl+0xb6c/0x1ad0
<4> [761.914267] ? find_held_lock+0x2d/0xa0
<4> [761.914388] ? __x64_sys_ioctl+0x6e/0xb0
<4> [761.914461] __x64_sys_ioctl+0x6e/0xb0
<4> [761.914521] do_syscall_64+0x37/0x90
<4> [761.914580] entry_SYSCALL_64_after_hwframe+0x63/0xcd
<4> [761.914654] RIP: 0033:0x7f1e1b47750b
<4> [761.914704] Code: 0f 1e fa 48 8b 05 85 39 0d 00 64 c7 00 26 00 00 00 48 c7 c0 ff ff ff ff c3 66 0f 1f 44 00 00 f3 0f 1e fa b8 10 00 00 00 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8b 0d 55 39 0d 00 f7 d8 64 89 01 48
<4> [761.914875] RSP: 002b:00007fffda031b98 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
<4> [761.914962] RAX: ffffffffffffffda RBX: 00007fffda031d40 RCX: 00007f1e1b47750b
<4> [761.915033] RDX: 00007fffda031d40 RSI: 00000000c028aa03 RDI: 0000000000000009
<4> [761.915104] RBP: 00000000c028aa03 R08: 00007fffda031d40 R09: 0000000000000000
<4> [761.915179] R10: 00007f1e1b635c17 R11: 0000000000000246 R12: 0000000000000001
<4> [761.915269] R13: 0000000000000009 R14: 0000000000000009 R15: 00007f1e18ccdb78
<4> [761.915374] </TASK>
<4> [794.493622] [IGT] Per-test timeout exceeded. Killing the current test with SIGQUIT.
<6> [794.493874] sysrq: Show State
<6> [794.493921] task:systemd state:S stack:12024 pid:1 ppid:0 flags:0x00000000
<6> [794.494033] Call Trace:
<6> [794.494066] <TASK>
<6> [794.494107] __schedule+0x30e/0xa70
<6> [794.494351] schedule+0x4e/0xd0
<6> [794.494562] schedule_hrtimeout_range_clock+0x11d/0x130
<6> [794.494691] ? do_epoll_wait+0x759/0x8f0
<6> [794.494773] ? _raw_write_unlock_irq+0x1f/0x50
<6> [794.494886] ? lockdep_hardirqs_on+0xbf/0x140
<6> [794.494987] do_epoll_wait+0x76a/0x8f0
<6> [794.495102] ? ep_timeout_to_timespec.part.25+0xa0/0xa0
<6> [794.495270] __x64_sys_epoll_wait+0x50/0x90
<6> [794.495362] do_syscall_64+0x37/0x90
<6> [794.495444] entry_SYSCALL_64_after_hwframe+0x63/0xcd
<6> [794.495545] RIP: 0033:0x7f4c0b7435ce
<6> [794.495657] RSP: 002b:00007ffc839152e0 EFLAGS: 00000293 ORIG_RAX: 00000000000000e8
<6> [794.495804] RAX: ffffffffffffffda RBX: 000055a005eef590 RCX: 00007f4c0b7435ce
<6> [794.495908] RDX: 0000000000000054 RSI: 000055a006045610 RDI: 0000000000000004
<6> [794.496003] RBP: ffffffffffffffff R08: 0000000000000000 R09: 0000000000001ca0
<6> [794.496077] R10: 00000000ffffffff R11: 0000000000000293 R12: 0000000000000001
<6> [794.496219] R13: 0000000000000054 R14: 000055a004ff3883 R15: 0000000000000000
<6> [794.496384] </TASK>
<6> [794.496432] task:kthreadd state:S stack:12192 pid:2 ppid:0 flags:0x00004000
<6> [794.496594] Call Trace:
<6> [794.496638] <TASK>
<6> [794.496713] __schedule+0x30e/0xa70
<6> [794.496791] ? lock_release+0x20a/0x2d0
<6> [794.496921] ? kthreadd+0x8b/0x160
<6> [794.496992] ? kthreadd+0x64/0x160
<6> [794.497067] schedule+0x4e/0xd0
<6> [794.497152] kthreadd+0x14b/0x160
<6> [794.497255] ? kthread_is_per_cpu+0x20/0x20
<6> [794.497321] ret_from_fork+0x1f/0x30
<6> [794.497441] </TASK>
<6> [794.497506] task:rcu_gp state:I stack:15032 pid:3 ppid:2 flags:0x00004000
<6> [794.497652] Call Trace: