igt@gem_busy@close-race - abort - This test caused an abort condition: Child refuses to die, tainted, Per-test timeout exceeded. Killing the current test with SIGQUIT
https://gfx-ci.igk.intel.com/cibuglog-ng/testresult/1742914125?query_key=231f5b236d9f6c9665ee0f4ef369fbfb3d20d6de
Stdout
Using IGT_SRANDOM=1712250941 for randomisation
Opened device: /dev/dri/card0
Starting subtest: close-race
Opened device: /dev/dri/card1
This test caused an abort condition: Child refuses to die, tainted 0x0.
Stderr
Starting subtest: close-race
Received signal Received signal SIGQUITSIGQUIT.
Stack trace:
.
Stack trace:
#0 [fatal_sig_hand le#r+00x 10[f]f
atal_sig_handler+0x10f]
#1 [killpg+0x40]
#1 [killpg+0x40]
#2 [__poll+0x4f]
#2 #[3 w[iagti_ftor4k_+h0anxg_5dedte]ct
or+0x14c]
#4 [__igt_unique____real_main471+0x1e64]
#5 [main+0x2d]
#3 [__igt_waitchildren+ #06 x[5_8_l]i
bc_start_main+0xf3]
#7 [_start+0x2e]
#4 [igt_waitchildren+0x2d]
#5 [__igt_unique____real_main471+0x1b69]
#6 [main+0x2d]
#7 [__libc_start_main+0xf3]
#8 [_start+0x2e]
Dmesg
<6> [146.906406] Console: switching to colour dummy device 80x25
<6> [146.906814] [IGT] gem_busy: executing
<7> [146.910086] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [146.910926] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [146.911277] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [146.911738] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<6> [146.923057] [IGT] gem_busy: starting subtest close-race
<7> [146.923849] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [146.926871] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [146.933249] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [146.940060] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [146.940589] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x00000070 [0x00000070]
<7> [147.063960] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x00000070 [0x00000070]
<5> [148.385417] sched: RT throttling activated
<3> [233.629417] rcu: INFO: rcu_preempt detected stalls on CPUs/tasks:
<3> [233.629469] rcu: Tasks blocked on level-0 rcu_node (CPUs 0-7): P1388
<3> [233.629560] rcu: (detected by 5, t=65002 jiffies, g=32105, q=551 ncpus=8)
<6> [233.629581] task:gem_busy state:R running task stack:13848 pid:1388 tgid:1388 ppid:1381 flags:0x00004002
<6> [233.629589] Call Trace:
<6> [233.629591] <TASK>
<6> [233.629595] ? __schedule+0x3c3/0xda0
<6> [233.629603] ? preempt_schedule_irq+0x60/0x70
<6> [233.629608] ? asm_sysvec_irq_work+0x1a/0x20
<6> [233.629613] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20
<6> [233.629616] ? lockdep_hardirqs_on+0xc2/0x140
<6> [233.629621] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20
<6> [233.629630] ? dma_resv_list_entry+0x61/0xa0
<6> [233.629636] ? dma_resv_iter_walk_unlocked.part.0+0x17f/0x1e0
<6> [233.629641] ? dma_resv_iter_first_unlocked+0x91/0x230
<6> [233.629645] ? i915_gem_busy_ioctl+0xba/0xb80 [i915]
<6> [233.629840] ? find_held_lock+0x2b/0x80
<6> [233.629847] ? __pfx_i915_gem_busy_ioctl+0x10/0x10 [i915]
<6> [233.629995] ? drm_ioctl_kernel+0xab/0x100
<6> [233.630001] ? drm_ioctl+0x222/0x430
<6> [233.630004] ? __pfx_i915_gem_busy_ioctl+0x10/0x10 [i915]
<6> [233.630151] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20
<6> [233.630154] ? lockdep_hardirqs_on+0xc2/0x140
<6> [233.630158] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20
<6> [233.630165] ? __x64_sys_ioctl+0x7c/0xb0
<6> [233.630169] ? do_syscall_64+0x71/0x140
<6> [233.630173] ? entry_SYSCALL_64_after_hwframe+0x71/0x79
<6> [233.630182] </TASK>
<4> [267.667817] [IGT] Per-test timeout exceeded. Killing the current test with SIGQUIT.
<6> [267.667849] sysrq: HELP : loglevel(0-9) reboot(b) crash(c) show-all-locks(d) terminate-all-tasks(e) memory-full-oom-kill(f) kill-all-tasks(i) thaw-filesystems(j) sak(k) show-backtrace-all-active-cpus(l) show-memory-usage(m) nice-all-RT-tasks(n) poweroff(o) show-registers(p) show-all-timers(q) unraw(r) sync(s) show-task-states(t) unmount(u) force-fb(v) show-blocked-tasks(w) dump-ftrace-buffer(z)
<6> [267.668067] sysrq: Show State
<6> [267.668081] task:systemd state:S stack:11760 pid:1 tgid:1 ppid:0 flags:0x00000000
<6> [267.668092] Call Trace:
<6> [267.668094] <TASK>
<6> [267.668099] __schedule+0x3bb/0xda0
<6> [267.668112] schedule+0x41/0x110
<6> [267.668116] schedule_hrtimeout_range_clock+0x137/0x150
<6> [267.668124] ? _raw_write_unlock_irq+0x23/0x50
<6> [267.668130] do_epoll_wait+0x712/0x910
<6> [267.668142] ? __pfx_ep_autoremove_wake_function+0x10/0x10
<6> [267.668149] __x64_sys_epoll_wait+0x54/0x90
<6> [267.668152] ? do_syscall_64+0x2e/0x140
<6> [267.668157] do_syscall_64+0x71/0x140
<6> [267.668162] entry_SYSCALL_64_after_hwframe+0x71/0x79
<6> [267.668165] RIP: 0033:0x7f3b8c68368e
<6> [267.668168] RSP: 002b:00007ffc29107990 EFLAGS: 00000293 ORIG_RAX: 00000000000000e8
<6> [267.668173] RAX: ffffffffffffffda RBX: 000055613dd45550 RCX: 00007f3b8c68368e
<6> [267.668175] RDX: 000000000000004c RSI: 000055613de86610 RDI: 0000000000000004
<6> [267.668178] RBP: ffffffffffffffff R08: 0000000000000000 R09: 0000000000001040
<6> [267.668180] R10: 00000000ffffffff R11: 0000000000000293 R12: 0000000000000001
<6> [267.668183] R13: 000000000000004c R14: 000055610965c8c3 R15: 0000000000000000
<6> [267.668194] </TASK>
<6> [267.668196] task:kthreadd state:S stack:13992 pid:2 tgid:2 ppid:0 flags:0x00004000
<6> [267.668202] Call Trace:
<6> [267.668204] <TASK>
<6> [267.668209] __schedule+0x3bb/0xda0
<6> [267.668218] schedule+0x41/0x110
<6> [267.668223] kthreadd+0x145/0x160
<6> [267.668227] ? __pfx_kthreadd+0x10/0x10
<6> [267.668232] ret_from_fork+0x2f/0x50
<6> [267.668235] ? __pfx_kthreadd+0x10/0x10
<6> [267.668239] ret_from_fork_asm+0x1a/0x30
<6> [267.668250] </TASK>
<6> [267.668253] task:pool_workqueue_ state:S stack:13816 pid:3 tgid:3 ppid:2 flags:0x00004000
<6> [267.668259] Call Trace:
<6> [267.668261] <TASK>
<6> [267.668265] __schedule+0x3bb/0xda0
<6> [267.668274] schedule+0x41/0x110
<6> [267.668279] kthread_worker_fn+0x193/0x350
<6> [267.668282] ? __pfx_pwq_release_workfn+0x10/0x10
<6> [267.668288] ? __pfx_kthread_worker_fn+0x10/0x10
<6> [267.668291] kthread+0xfd/0x130
<6> [267.668294] ? __pfx_kthread+0x10/0x10
<6> [267.668298] ret_from_fork+0x2f/0x50
<6> [267.668301] ? __pfx_kthread+0x10/0x10
<6> [267.668304] ret_from_fork_asm+0x1a/0x30
<6> [267.668316] </TASK>
<6> [267.668318] task:kworker/R-rcu_g state:I stack:14904 pid:4 tgid:4 ppid:2 flags:0x00004000
<6> [267.668325] Call Trace:
<6> [267.668327] <TASK>
<6> [267.668331] __schedule+0x3bb/0xda0
<6> [267.668340] ? __pfx_rescuer_thread+0x10/0x10
<6> [267.668344] schedule+0x41/0x110
<6> [267.668348] rescuer_thread+0x282/0x340
<6> [267.668353] ? rescuer_thread+0x73/0x340
<6> [267.668357] ? _raw_spin_unlock_irqrestore+0x41/0x70
<6> [267.668363] ? __pfx_rescuer_thread+0x10/0x10
<6> [267.668366] kthread+0xfd/0x130
<6> [267.668369] ? __pfx_kthread+0x10/0x10
<6> [267.668373] ret_from_fork+0x2f/0x50