igt@gem_busy@close-race - abort - INFO: rcu_preempt detected stalls on CPUs/tasks
Stdout
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 0x40.
Stderr
Starting subtest: close-race
Received signal Received signal SIGQUITSIGQUIT.
.
Stack trace:
Stack trace:
#0 [fatal_sig_ h#a0 n[dflaetra+l0_xs1i0gf_]h
andler+0x10f]
#1 #[1k i[lkliplgl+p0gx+400x]4
0]
#2 [__poll+0x4f]
#2 [wait4+0x5d]
#3 [i g#t3_ f[o_r_ki_ghta_nwga_idtectheicltdorre+n0+x01x45c8]]
#4 [__igt_unique____real_main487+0x1e6c]
#5 [main+ #4 [igt_wait0chixl2ddr]e
n+0x2d]
#5 [__igt_unique____real_main487+0x1b72]
#6 [main+0x2d]
#6 [__libc_start_main+0xf3]
#7 [_start+0x2e]
#7 [__libc_start_main+0xf3]
#8 [_start+0x2e]
Dmesg
<6> [408.019022] Console: switching to colour dummy device 80x25
<6> [408.020433] [IGT] gem_busy: executing
<7> [408.033078] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [408.038740] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [408.040017] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [408.043468] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<6> [408.100375] [IGT] gem_busy: starting subtest close-race
<7> [408.108281] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [408.172196] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [408.233810] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [408.293094] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [408.354414] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [408.413593] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [409.296855] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<6> [409.298917] gem_busy (1695): drop_caches: 4
<7> [409.299137] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x00000070 [0x00000070]
<7> [413.478423] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x00000070 [0x00000070]
<7> [414.029705] [drm:eb_lookup_vmas [i915]] EINVAL at eb_validate_vma:508
<5> [416.013021] sched: RT throttling activated
<3> [500.502484] rcu: INFO: rcu_preempt detected stalls on CPUs/tasks:
<3> [500.502515] rcu: Tasks blocked on level-1 rcu_node (CPUs 0-10): P1706
<3> [500.502589] rcu: (detected by 2, t=65002 jiffies, g=141045, q=2079 ncpus=22)
<6> [500.502605] task:gem_busy state:R running task stack:13848 pid:1706 ppid:1695 flags:0x00004002
<6> [500.502613] Call Trace:
<6> [500.502615] <TASK>
<6> [500.502618] ? __schedule+0x3ac/0xd70
<6> [500.502627] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20
<6> [500.502631] ? lockdep_hardirqs_on+0xc3/0x140
<6> [500.502634] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20
<6> [500.502639] ? lock_is_held_type+0x77/0x150
<6> [500.502642] ? rcu_lockdep_current_cpu_online+0x3c/0x80
<6> [500.502649] ? dma_resv_list_entry+0x61/0xa0
<6> [500.502653] ? rcu_read_lock_held+0x22/0x60
<6> [500.502657] ? dma_resv_iter_walk_unlocked.part.0+0x17f/0x1e0
<6> [500.502660] ? dma_resv_iter_first_unlocked+0x92/0x230
<6> [500.502663] ? i915_gem_busy_ioctl+0xbe/0xa50 [i915]
<6> [500.502833] ? __pfx_i915_gem_busy_ioctl+0x10/0x10 [i915]
<6> [500.502950] ? drm_ioctl_kernel+0xb4/0x150
<6> [500.502955] ? drm_ioctl+0x21d/0x420
<6> [500.502959] ? __pfx_i915_gem_busy_ioctl+0x10/0x10 [i915]
<6> [500.503080] ? __x64_sys_ioctl+0x79/0xb0
<6> [500.503085] ? do_syscall_64+0x3c/0x90
<6> [500.503088] ? entry_SYSCALL_64_after_hwframe+0x6e/0xd8
<6> [500.503095] </TASK>
<4> [590.597001] [IGT] Inactivity timeout exceeded. Killing the current test with SIGQUIT.
<6> [590.597063] 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> [590.597409] sysrq: Show State
<6> [590.597432] task:systemd state:S stack:11896 pid:1 ppid:0 flags:0x00000000
<6> [590.597454] Call Trace:
<6> [590.597458] <TASK>
<6> [590.597467] __schedule+0x3a4/0xd70
<6> [590.597527] schedule+0x5c/0xd0
<6> [590.597537] schedule_hrtimeout_range_clock+0x13c/0x150
<6> [590.597551] ? _raw_write_unlock_irq+0x23/0x50
<6> [590.597563] do_epoll_wait+0x709/0x8f0
<6> [590.597585] ? __pfx_ep_autoremove_wake_function+0x10/0x10
<6> [590.597599] __x64_sys_epoll_wait+0x54/0x90
<6> [590.597607] ? lockdep_hardirqs_on+0xc3/0x140
<6> [590.597619] do_syscall_64+0x3c/0x90
<6> [590.597626] entry_SYSCALL_64_after_hwframe+0x6e/0xd8
<6> [590.597635] RIP: 0033:0x7fe788fce46e
<6> [590.597642] RSP: 002b:00007ffd4d65bf90 EFLAGS: 00000293 ORIG_RAX: 00000000000000e8
<6> [590.597651] RAX: ffffffffffffffda RBX: 0000563407a38ef0 RCX: 00007fe788fce46e