igt@gem_close_race@basic-threads - Received signal SIGQUIT, INFO: task kworker/1:2:212 blocked for more than 61 seconds.
https://intel-gfx-ci.01.org/tree/drm-tip/IGT_5347/fi-byt-j1900/igt@gem_close_race@basic-threads.html
Err
Starting subtest: basic-threads
Received signal SIGQUIT.
Stack trace:
Received signal SIGQUIT.
Stack trace:
#0 [fatal_sig_handler+0xd6]
#0 [fatal_sig_handler+0xd6]
#1 [killpg+0x40]
#2 [__poll+0x14]
#1 [killpg+0x40]
#3 [igt_fork_hang_detector+0x14c]
#4 [__real_main212+0x1cd]
#2 [write+0xf]
#5 [main+0x27]
#6 [__libc_start_main+0xe7]
#3 [writeN+0x2d]
#7 [_start+0x2a]
#4 [igt_sysfs_vprintf+0xf4]
#5 [igt_sysfs_printf+0x8f]
#6 [igt_drop_caches_set+0x2a]
#7 [threads+0x1fa]
#8 [__real_main212+0x6d]
#9 [main+0x27]
#10 [__libc_start_main+0xe7]
#11 [_start+0x2a]
<6> [42.830303] Console: switching to colour dummy device 80x25
<6> [42.830506] [IGT] gem_close_race: executing
<5> [42.841343] Setting dangerous option reset - tainting kernel
<6> [42.851569] [IGT] gem_close_race: starting subtest basic-threads
<3> [124.832723] INFO: task kworker/1:2:212 blocked for more than 61 seconds.
<3> [124.832883] Tainted: G U W 5.5.0-rc1-CI-CI_DRM_7554+ #1
<3> [124.832977] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
<6> [124.833082] kworker/1:2 D12448 212 2 0x80004000
<6> [124.833286] Workqueue: events heartbeat [i915]
<4> [124.833415] Call Trace:
<4> [124.833445] ? __schedule+0x2e5/0x810
<4> [124.833478] schedule+0x37/0xe0
<4> [124.833494] schedule_preempt_disabled+0xf/0x20
<4> [124.833506] __mutex_lock+0x281/0x9c0
<4> [124.833521] ? add_lock_to_list.isra.27+0x9c/0x120
<4> [124.833723] ? heartbeat+0x43f/0x6b0 [i915]
<4> [124.833931] ? heartbeat+0x43f/0x6b0 [i915]
<4> [124.834116] heartbeat+0x43f/0x6b0 [i915]
<4> [124.834150] process_one_work+0x26a/0x620
<4> [124.834183] worker_thread+0x37/0x380
<4> [124.834204] ? process_one_work+0x620/0x620
<4> [124.834215] kthread+0x119/0x130
<4> [124.834228] ? kthread_park+0x80/0x80
<4> [124.834343] ret_from_fork+0x3a/0x50
<4> [124.834412]
Showing all locks held in the system:
<4> [124.834438] 1 lock held by khungtaskd/33:
<4> [124.834447] #0: ffffffff82647d20 (rcu_read_lock){....}, at: debug_show_all_locks+0xe/0x1a0
<4> [124.834490] 3 locks held by kworker/1:2/212:
<4> [124.834498] #0: ffff88813b087528 ((wq_completion)events){+.+.}, at: process_one_work+0x1de/0x620
<4> [124.834520] #1: ffffc9000029fe78 ((work_completion)(&(&engine->heartbeat.work)->work)){+.+.}, at: process_one_work+0x1de/0x620
<4> [124.834541] #2: ffff888136672070 (&kernel#2){+.+.}, at: heartbeat+0x43f/0x6b0 [i915]
<4> [124.834763] 2 locks held by in:imklog/420:
<4> [124.834775] 3 locks held by rs:main Q:Reg/421:
<4> [124.834798] 4 locks held by dmesg/987:
<4> [124.834808] 4 locks held by gem_close_race/1019:
<4> [124.834816] #0: ffff88813a824408 (sb_writers#14){.+.+}, at: vfs_write+0x1a4/0x1d0
<4> [124.834841] #1: ffff88812e20a5b0 (&attr->mutex){+.+.}, at: simple_attr_write+0x36/0xd0
<4> [124.834867] #2: ffff888136672070 (&kernel#2){+.+.}, at: intel_gt_retire_requests_timeout+0xe3/0x640 [i915]
<4> [124.835073] #3: ffff888117bcc5a8 (>->reset.mutex){+.+.}, at: i915_request_wait+0xc9/0x880 [i915]
<4> [124.835374]
<4> [124.835382] =============================================
<6> [124.835389] task PC stack pid father
<6> [124.835400] systemd S11704 1 0 0x00000000
<4> [124.835420] Call Trace:
<4> [124.835448] ? __schedule+0x2e5/0x810
<4> [124.835475] ? ep_poll+0x2c4/0x5a0
<4> [124.835492] schedule+0x37/0xe0
<4> [124.835506] schedule_hrtimeout_range_clock+0x10e/0x120
<4> [124.835518] ? ep_poll+0x56e/0x5a0
<4> [124.835539] ? _raw_spin_unlock_irq+0x1f/0x40
<4> [124.835550] ? lockdep_hardirqs_on+0xe3/0x1c0
<4> [124.835568] ep_poll+0x2a4/0x5a0
<4> [124.835603] ? wake_up_q+0xa0/0xa0
<4> [124.835629] do_epoll_wait+0xd2/0xf0
<4> [124.835650] __x64_sys_epoll_wait+0x15/0x20
<4> [124.835662] do_syscall_64+0x4f/0x220
<4> [124.835676] entry_SYSCALL_64_after_hwframe+0x49/0xbe
<4> [124.835689] RIP: 0033:0x7fec037aabb7
<4> [124.835710] Code: Bad RIP value.
<4> [124.835720] RSP: 002b:00007ffddc2fffc0 EFLAGS: 00000293 ORIG_RAX: 00000000000000e8
<4> [124.835732] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007fec037aabb7
<4> [124.835741] RDX: 0000000000000039 RSI: 00007ffddc300000 RDI: 0000000000000004
<4> [124.835750] RBP: 00007ffddc300000 R08: 0000000000000000 R09: 7465677261742e79
<4> [124.835759] R10: 00000000ffffffff R11: 0000000000000293 R12: 0000000000000039
<4> [124.835767] R13: 00000000ffffffff R14: 00007ffddc300000 R15: 0000000000000001
<6> [124.835810] kthreadd S13832 2 0 0x80004000
````
Edited by Chris Wilson