[shard-dg2-11] Few tests - timeout - Per-test timeout exceeded. Killing the current test with SIGQUIT.
https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_13947/shard-dg2-11/igt@kms_busy@basic.html
Err
Starting subtest: sysfs
Starting dynamic subtest: gt0
Received signal SIGQUIT.
Stack trace:
#0 [fatal_sig_handler+0x10f]
#1 [killpg+0x40]
#2 [write+0x15]
#3 [igt_writen+0x33]
#4 [igt_sysfs_vprintf+0x9a]
#5 [igt_sysfs_printf+0x93]
#6 [igt_drop_caches_set+0x3c]
#7 [__igt_unique____real_main244+0x4ce]
#8 [main+0x2d]
#9 [__libc_start_main+0xf3]
#10 [_start+0x2e]
Dmesg
Scroll to first warning
<6> [54.041122] Console: switching to colour dummy device 80x25
<6> [54.041165] [IGT] gem_ctx_freq: executing
<7> [54.043739] i915 0000:03:00.0: [drm:i915_gem_open [i915]]
<7> [54.045177] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [54.062783] i915 0000:03:00.0: [drm:i915_gem_open [i915]]
<7> [54.064059] i915 0000:03:00.0: [drm:i915_gem_open [i915]]
<6> [54.069751] [IGT] gem_ctx_freq: starting subtest sysfs
<6> [54.069850] [IGT] gem_ctx_freq: starting dynamic subtest gt0
<7> [54.070032] [drm:eb_lookup_vmas [i915]] EINVAL at eb_validate_vma:511
<7> [54.092523] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<5> [74.075069] Fence expiration time out i915-0000:03:00.0:gem_ctx_freq<945>:2!
<5> [74.077022] Fence expiration time out i915-0000:03:00.0:gem_ctx_freq<945>:2!
<5> [74.079886] Fence expiration time out i915-0000:03:00.0:gem_ctx_freq<945>:2!
<5> [74.089030] Fence expiration time out i915-0000:03:00.0:gem_ctx_freq<945>:2!
<5> [74.092033] Fence expiration time out i915-0000:03:00.0:gem_ctx_freq<945>:2!
<4> [174.230490] [IGT] Per-test timeout exceeded. Killing the current test with SIGQUIT.
<6> [174.230588] 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> [174.231241] sysrq: Show State
<6> [174.231287] task:systemd state:S stack:11752 pid:1 tgid:1 ppid:0 flags:0x00000000
<6> [174.231369] Call Trace:
<6> [174.231377] <TASK>
<6> [174.231394] __schedule+0x3a6/0xd50
<6> [174.231432] schedule+0x43/0x110
<6> [174.231447] schedule_hrtimeout_range_clock+0x13a/0x150
<6> [174.231471] ? _raw_write_unlock_irq+0x23/0x50
<6> [174.231492] do_epoll_wait+0x707/0x8f0
<6> [174.231530] ? __pfx_ep_autoremove_wake_function+0x10/0x10
<6> [174.231552] __x64_sys_epoll_wait+0x54/0x90
<6> [174.231563] ? lockdep_hardirqs_on+0xc3/0x140
<6> [174.231584] do_syscall_64+0x42/0xf0
<6> [174.231597] entry_SYSCALL_64_after_hwframe+0x6e/0x76
<6> [174.231612] RIP: 0033:0x7effa914e46e
<6> [174.231623] RSP: 002b:00007ffcd2d43210 EFLAGS: 00000293 ORIG_RAX: 00000000000000e8
<6> [174.231639] RAX: ffffffffffffffda RBX: 0000559da6eb9140 RCX: 00007effa914e46e
<6> [174.231648] RDX: 0000000000000053 RSI: 0000559da6ff1570 RDI: 0000000000000004
<6> [174.231656] RBP: ffffffffffffffff R08: 0000000000000000 R09: 00000000000008e0
<6> [174.231665] R10: 00000000ffffffff R11: 0000000000000293 R12: 0000000000000001
<6> [174.231673] R13: 0000000000000053 R14: 0000559da6c19863 R15: 0000000000000000
<6> [174.231710] </TASK>
<6> [174.231718] task:kthreadd state:S stack:13752 pid:2 tgid:2 ppid:0 flags:0x00004000
<6> [174.231739] Call Trace:
<6> [174.231745] <TASK>
<6> [174.231761] __schedule+0x3a6/0xd50
<6> [174.231793] schedule+0x43/0x110
<6> [174.231807] kthreadd+0x146/0x160
<6> [174.231823] ? __pfx_kthreadd+0x10/0x10
<6> [174.231844] ret_from_fork+0x2c/0x50
<6> [174.231854] ? __pfx_kthreadd+0x10/0x10
<6> [174.231870] ret_from_fork_asm+0x1b/0x30
<6> [174.231912] </TASK>
<6> [174.231919] task:pool_workqueue_ state:S stack:14952 pid:3 tgid:3 ppid:2 flags:0x00004000
<6> [174.231939] Call Trace:
<6> [174.231945] <TASK>
<6> [174.231960] __schedule+0x3a6/0xd50
<6> [174.231992] schedule+0x43/0x110
<6> [174.232005] kthread_worker_fn+0x196/0x310
<6> [174.232019] ? __pfx_pwq_release_workfn+0x10/0x10
<6> [174.232038] ? __pfx_kthread_worker_fn+0x10/0x10
<6> [174.232052] kthread+0xfe/0x130
<6> [174.232065] ? __pfx_kthread+0x10/0x10
<6> [174.232085] ret_from_fork+0x2c/0x50
<6> [174.232094] ? __pfx_kthread+0x10/0x10
<6> [174.232109] ret_from_fork_asm+0x1b/0x30
<6> [174.232148] </TASK>
<6> [174.232155] task:kworker/R-rcu_g state:I stack:14904 pid:4 tgid:4 ppid:2 flags:0x00004000
<6> [174.232174] Call Trace:
<6> [174.232180] <TASK>
<6> [174.232195] __schedule+0x3a6/0xd50
<6> [174.232222] ? __pfx_rescuer_thread+0x10/0x10
<6> [174.232235] schedule+0x43/0x110
<6> [174.232249] rescuer_thread+0x283/0x340
<6> [174.232266] ? rescuer_thread+0x74/0x340
<6> [174.232279] ? _raw_spin_unlock_irqrestore+0x41/0x70
<6> [174.232297] ? __pfx_rescuer_thread+0x10/0x10
<6> [174.232308] kthread+0xfe/0x130
<6> [174.232348] ? __pfx_kthread+0x10/0x10
<6> [174.232369] ret_from_fork+0x2c/0x50
<6> [174.232378] ? __pfx_kthread+0x10/0x10
<6> [174.232394] ret_from_fork_asm+0x1b/0x30
<6> [174.232433] </TASK>
<6> [174.232440] task:kworker/R-rcu_p state:I stack:15400 pid:5 tgid:5 ppid:2 flags:0x00004000
Edited by Tejasree Illipilli