[PATCH 11/20] drm/i915/gt: Ratelimit heartbeat completion probing
Chris Wilson
chris at chris-wilson.co.uk
Fri Feb 12 13:28:13 UTC 2021
The heartbeat runs through a few phases that we expect to complete
within a certain number of heartbeat intervals. First we must submit the
heartbeat to the queue, and if the queue is occupied it may take a
couple of intervals before the heartbeat preempts the workload and is
submitted to HW. Once running on HW, completion is not instantaneous as
it may have to first reset the current workload before it itself runs
through the empty request and signals completion. As such, we know that
the heartbeat must take at least the preempt reset timeout and before we
have had a chance to reset the engine, we do not want to issue a global
reset ourselves (simply so that we only try to do one reset at a time
and not confuse ourselves by resetting twice and hitting an innocent.)
So by taking into consideration that once running the request must take
a finite amount of time, we can delay the final completion check to
accommodate that and avoid checking too early (before we've had a chance
to handle any engine resets required).
v3: Now with more tracking for selftests and detection of
false/unexpected hang reports.
v2,4,5: Add a completion handler for the heartbeat to speed up discovery
of a successful heartbeat. Remove it, but then put it back to handle
large mismatches between the sysfs properties and reality.
Closes: https://gitlab.freedesktop.org/drm/intel/-/issues/2853
Suggested-by: CQ Tang <cq.tang at intel.com>
Signed-off-by: Chris Wilson <chris at chris-wilson.co.uk>
---
.../gpu/drm/i915/gt/intel_engine_heartbeat.c | 107 +++++++++++++++---
drivers/gpu/drm/i915/gt/intel_engine_types.h | 8 ++
.../drm/i915/gt/selftest_engine_heartbeat.c | 103 +++++++++++------
drivers/gpu/drm/i915/gt/selftest_execlists.c | 5 +-
4 files changed, 173 insertions(+), 50 deletions(-)
diff --git a/drivers/gpu/drm/i915/gt/intel_engine_heartbeat.c b/drivers/gpu/drm/i915/gt/intel_engine_heartbeat.c
index 0b062fad1837..7e7d2ff2299b 100644
--- a/drivers/gpu/drm/i915/gt/intel_engine_heartbeat.c
+++ b/drivers/gpu/drm/i915/gt/intel_engine_heartbeat.c
@@ -20,6 +20,18 @@
* issue a reset -- in the hope that restores progress.
*/
+#define HEARTBEAT_COMPLETION 50u /* milliseconds */
+
+static long completion_timeout(const struct intel_engine_cs *engine)
+{
+ long timeout = HEARTBEAT_COMPLETION;
+
+ if (intel_engine_has_preempt_reset(engine))
+ timeout += READ_ONCE(engine->props.preempt_timeout_ms);
+
+ return msecs_to_jiffies(timeout);
+}
+
static bool next_heartbeat(struct intel_engine_cs *engine)
{
long delay;
@@ -29,6 +41,26 @@ static bool next_heartbeat(struct intel_engine_cs *engine)
return false;
delay = msecs_to_jiffies_timeout(delay);
+
+ /*
+ * Once we submit a heartbeat to the HW, we know that it will take
+ * at least a certain amount of time to complete. On a hanging system
+ * it will first have to wait for the preempt reset timeout, and
+ * then it will take some time for the reset to resume with the
+ * heartbeat and for it to complete. So once we have submitted the
+ * heartbeat to HW, we can wait a while longer before declaring the
+ * engine stuck and forcing a reset ourselves. If we do a reset
+ * and the engine is also doing a reset, it is possible that we
+ * reset the engine twice, harming an innocent.
+ *
+ * Before we have sumitted the heartbeat, we do not want to change
+ * the interval as we want to promote the heartbeat and trigger
+ * preemption in a deterministic time frame.
+ */
+ if (engine->heartbeat.systole &&
+ i915_request_is_active(engine->heartbeat.systole))
+ delay = max(delay, completion_timeout(engine));
+
if (delay >= HZ)
delay = round_jiffies_up_relative(delay);
mod_delayed_work(system_highpri_wq, &engine->heartbeat.work, delay + 1);
@@ -48,12 +80,52 @@ heartbeat_create(struct intel_context *ce, gfp_t gfp)
return rq;
}
+static void
+untrack_heartbeat(struct intel_engine_cs *engine)
+{
+ struct i915_request *rq;
+
+ rq = engine->heartbeat.systole;
+ if (!rq)
+ return;
+
+ ENGINE_TRACE(engine, "heartbeat " RQ_FMT " completed\n", RQ_ARG(rq));
+ I915_SELFTEST_ONLY(engine->heartbeat.completed++);
+
+ dma_fence_remove_callback(&rq->fence, &engine->heartbeat.cb);
+ WRITE_ONCE(engine->heartbeat.systole, NULL);
+
+ i915_request_put(rq);
+}
+
+static void defibrillator(struct dma_fence *f, struct dma_fence_cb *cb)
+{
+ struct intel_engine_cs *engine =
+ container_of(cb, typeof(*engine), heartbeat.cb);
+
+ if (READ_ONCE(engine->heartbeat.systole))
+ mod_delayed_work(system_highpri_wq, &engine->heartbeat.work, 0);
+}
+
+static void
+track_heartbeat(struct intel_engine_cs *engine, struct i915_request *rq)
+{
+ ENGINE_TRACE(engine, "heartbeat " RQ_FMT " started\n", RQ_ARG(rq));
+ I915_SELFTEST_ONLY(engine->heartbeat.submitted++);
+
+ if (!dma_fence_add_callback(&rq->fence, &engine->heartbeat.cb, defibrillator))
+ WRITE_ONCE(engine->heartbeat.systole, i915_request_get(rq));
+
+ if (!next_heartbeat(engine))
+ untrack_heartbeat(engine);
+}
+
static void idle_pulse(struct intel_engine_cs *engine, struct i915_request *rq)
{
engine->wakeref_serial = READ_ONCE(engine->serial) + 1;
i915_request_add_active_barriers(rq);
if (!engine->heartbeat.systole && intel_engine_has_heartbeat(engine))
- engine->heartbeat.systole = i915_request_get(rq);
+ track_heartbeat(engine, rq);
}
static void heartbeat_commit(struct i915_request *rq,
@@ -106,13 +178,8 @@ static void heartbeat(struct work_struct *wrk)
intel_engine_flush_scheduler(engine);
rq = engine->heartbeat.systole;
- if (rq && i915_request_completed(rq)) {
- ENGINE_TRACE(engine,
- "heartbeat " RQ_FMT "completed\n",
- RQ_ARG(rq));
- i915_request_put(rq);
- engine->heartbeat.systole = NULL;
- }
+ if (rq && i915_request_completed(rq))
+ untrack_heartbeat(engine);
if (!intel_engine_pm_get_if_awake(engine))
return;
@@ -120,9 +187,15 @@ static void heartbeat(struct work_struct *wrk)
if (intel_gt_is_wedged(engine->gt))
goto out;
+ I915_SELFTEST_ONLY(engine->heartbeat.ticks++);
+
if (engine->heartbeat.systole) {
long delay = READ_ONCE(engine->props.heartbeat_interval_ms);
+ if (rq->sched.attr.priority == I915_PRIORITY_BARRIER &&
+ i915_request_is_active(rq))
+ delay += completion_timeout(engine);
+
/* Safeguard against too-fast worker invocations */
if (!time_after(jiffies,
rq->emitted_jiffies + msecs_to_jiffies(delay)))
@@ -152,8 +225,10 @@ static void heartbeat(struct work_struct *wrk)
attr.priority = 0;
if (rq->sched.attr.priority >= attr.priority)
attr.priority = I915_PRIORITY_HEARTBEAT;
- if (rq->sched.attr.priority >= attr.priority)
+ if (rq->sched.attr.priority >= attr.priority) {
+ I915_SELFTEST_ONLY(engine->heartbeat.barrier++);
attr.priority = I915_PRIORITY_BARRIER;
+ }
ENGINE_TRACE(engine,
"bumping heartbeat " RQ_FMT " prio:%d\n",
@@ -166,6 +241,7 @@ static void heartbeat(struct work_struct *wrk)
ENGINE_TRACE(engine,
"heartbeat " RQ_FMT " stuck\n",
RQ_ARG(rq));
+ I915_SELFTEST_ONLY(engine->heartbeat.reset++);
if (IS_ENABLED(CONFIG_DRM_I915_DEBUG_GEM))
show_heartbeat(rq, engine);
@@ -180,6 +256,11 @@ static void heartbeat(struct work_struct *wrk)
goto out;
}
+ /* Just completed one heartbeat, wait a tick before the next */
+ if (rq)
+ goto out;
+
+ /* The engine is parking. We can rest until the next user */
serial = READ_ONCE(engine->serial);
if (engine->wakeref_serial == serial)
goto out;
@@ -198,14 +279,14 @@ static void heartbeat(struct work_struct *wrk)
if (IS_ERR(rq))
goto unlock;
- ENGINE_TRACE(engine, "heartbeat " RQ_FMT "started\n", RQ_ARG(rq));
heartbeat_commit(rq, &attr);
unlock:
mutex_unlock(&ce->timeline->mutex);
out:
+ intel_engine_flush_scheduler(engine);
if (!engine->i915->params.enable_hangcheck || !next_heartbeat(engine))
- i915_request_put(fetch_and_zero(&engine->heartbeat.systole));
+ untrack_heartbeat(engine);
intel_engine_pm_put(engine);
}
@@ -219,8 +300,8 @@ void intel_engine_unpark_heartbeat(struct intel_engine_cs *engine)
void intel_engine_park_heartbeat(struct intel_engine_cs *engine)
{
- if (cancel_delayed_work(&engine->heartbeat.work))
- i915_request_put(fetch_and_zero(&engine->heartbeat.systole));
+ while (cancel_delayed_work(&engine->heartbeat.work))
+ untrack_heartbeat(engine);
}
void intel_engine_init_heartbeat(struct intel_engine_cs *engine)
diff --git a/drivers/gpu/drm/i915/gt/intel_engine_types.h b/drivers/gpu/drm/i915/gt/intel_engine_types.h
index 7efa6290cc3e..5ad8d26e2fa0 100644
--- a/drivers/gpu/drm/i915/gt/intel_engine_types.h
+++ b/drivers/gpu/drm/i915/gt/intel_engine_types.h
@@ -322,7 +322,15 @@ struct intel_engine_cs {
struct {
struct delayed_work work;
struct i915_request *systole;
+ struct dma_fence_cb cb;
unsigned long blocked;
+ I915_SELFTEST_DECLARE(struct {
+ unsigned long submitted;
+ unsigned long completed;
+ unsigned long barrier;
+ unsigned long reset;
+ unsigned long ticks;
+ };)
} heartbeat;
unsigned long serial;
diff --git a/drivers/gpu/drm/i915/gt/selftest_engine_heartbeat.c b/drivers/gpu/drm/i915/gt/selftest_engine_heartbeat.c
index b2c369317bf1..e20a73ad9968 100644
--- a/drivers/gpu/drm/i915/gt/selftest_engine_heartbeat.c
+++ b/drivers/gpu/drm/i915/gt/selftest_engine_heartbeat.c
@@ -202,47 +202,58 @@ static int cmp_u32(const void *_a, const void *_b)
static int __live_heartbeat_fast(struct intel_engine_cs *engine)
{
- const unsigned int error_threshold = max(20000u, jiffies_to_usecs(6));
- struct intel_context *ce;
- struct i915_request *rq;
- ktime_t t0, t1;
+ const unsigned int error_threshold =
+ max_t(unsigned int,
+ 3 * completion_timeout(engine) * 1000,
+ jiffies_to_usecs(6));
+ struct intel_context *ce = engine->kernel_context;
+ unsigned long resets;
u32 times[5];
int err;
int i;
- ce = intel_context_create(engine);
- if (IS_ERR(ce))
- return PTR_ERR(ce);
-
intel_engine_pm_get(engine);
err = intel_engine_set_heartbeat(engine, 1);
if (err)
goto err_pm;
+ flush_delayed_work(&engine->heartbeat.work);
+ while (engine->heartbeat.systole)
+ intel_engine_park_heartbeat(engine);
+
+ resets = engine->heartbeat.reset;
for (i = 0; i < ARRAY_SIZE(times); i++) {
- do {
- /* Manufacture a tick */
- intel_engine_park_heartbeat(engine);
- GEM_BUG_ON(engine->heartbeat.systole);
- engine->serial++; /* pretend we are not idle! */
- intel_engine_unpark_heartbeat(engine);
+ struct i915_sched_attr attr = { .priority = I915_PRIORITY_MIN };
+ unsigned long submits = engine->heartbeat.submitted;
+ unsigned long completed = engine->heartbeat.completed;
+ struct i915_request *rq;
+ ktime_t t0, t1;
- flush_delayed_work(&engine->heartbeat.work);
- if (!delayed_work_pending(&engine->heartbeat.work)) {
- pr_err("%s: heartbeat %d did not start\n",
- engine->name, i);
- err = -EINVAL;
- goto err_pm;
- }
+ GEM_BUG_ON(READ_ONCE(engine->heartbeat.systole));
- rcu_read_lock();
- rq = READ_ONCE(engine->heartbeat.systole);
- if (rq)
- rq = i915_request_get_rcu(rq);
- rcu_read_unlock();
- } while (!rq);
+ /* Manufacture a tick */
+ mutex_lock(&ce->timeline->mutex);
+ rq = heartbeat_create(ce, GFP_KERNEL);
+ if (!IS_ERR(rq)) {
+ i915_request_get(rq);
+ heartbeat_commit(rq, &attr);
+ }
+ mutex_unlock(&ce->timeline->mutex);
+ if (IS_ERR(rq)) {
+ err = PTR_ERR(rq);
+ goto err_reset;
+ }
+ if (engine->heartbeat.submitted != submits + 1) {
+ GEM_TRACE_ERR("Heartbeat was not tracked!\n");
+ GEM_TRACE_DUMP();
+ i915_request_put(rq);
+ err = -EIO;
+ goto err_reset;
+ }
+
+ /* Time how long before the heartbeat monitor checks */
t0 = ktime_get();
while (rq == READ_ONCE(engine->heartbeat.systole))
yield(); /* work is on the local cpu! */
@@ -250,6 +261,20 @@ static int __live_heartbeat_fast(struct intel_engine_cs *engine)
i915_request_put(rq);
times[i] = ktime_us_delta(t1, t0);
+
+ if (engine->heartbeat.reset != resets) {
+ GEM_TRACE_ERR("False reset from a fast heartbeat!\n");
+ GEM_TRACE_DUMP();
+ err = -EIO;
+ goto err_reset;
+ }
+
+ if (engine->heartbeat.completed != completed + 1) {
+ GEM_TRACE_ERR("Completed heartbeat was not recorded!\n");
+ GEM_TRACE_DUMP();
+ err = -EIO;
+ goto err_reset;
+ }
}
sort(times, ARRAY_SIZE(times), sizeof(times[0]), cmp_u32, NULL);
@@ -275,10 +300,10 @@ static int __live_heartbeat_fast(struct intel_engine_cs *engine)
err = -EINVAL;
}
+err_reset:
reset_heartbeat(engine);
err_pm:
intel_engine_pm_put(engine);
- intel_context_put(ce);
return err;
}
@@ -304,24 +329,23 @@ static int live_heartbeat_fast(void *arg)
static int __live_heartbeat_off(struct intel_engine_cs *engine)
{
+ unsigned long submits;
int err;
intel_engine_pm_get(engine);
+ /* Kick once, so that we change an active heartbeat */
engine->serial++;
- flush_delayed_work(&engine->heartbeat.work);
- if (!delayed_work_pending(&engine->heartbeat.work)) {
- pr_err("%s: heartbeat not running\n",
- engine->name);
- err = -EINVAL;
- goto err_pm;
- }
+ intel_engine_unpark_heartbeat(engine);
err = intel_engine_set_heartbeat(engine, 0);
if (err)
goto err_pm;
- engine->serial++;
+ submits = READ_ONCE(engine->heartbeat.submitted);
+
+ /* The next heartbeat work should cancel the heartbeat */
+ engine->serial++; /* pretend the engine is still active */
flush_delayed_work(&engine->heartbeat.work);
if (delayed_work_pending(&engine->heartbeat.work)) {
pr_err("%s: heartbeat still running\n",
@@ -337,6 +361,13 @@ static int __live_heartbeat_off(struct intel_engine_cs *engine)
goto err_beat;
}
+ if (READ_ONCE(engine->heartbeat.submitted) != submits) {
+ pr_err("%s: heartbeat was submitted after being turned off\n",
+ engine->name);
+ err = -EINVAL;
+ goto err_beat;
+ }
+
err_beat:
reset_heartbeat(engine);
err_pm:
diff --git a/drivers/gpu/drm/i915/gt/selftest_execlists.c b/drivers/gpu/drm/i915/gt/selftest_execlists.c
index 5c8a6ccdf024..660240550097 100644
--- a/drivers/gpu/drm/i915/gt/selftest_execlists.c
+++ b/drivers/gpu/drm/i915/gt/selftest_execlists.c
@@ -2325,13 +2325,16 @@ static int __cancel_fail(struct live_preempt_cancel *arg)
del_timer_sync(&engine->execlists.preempt);
intel_engine_flush_scheduler(engine);
+ engine->props.preempt_timeout_ms = 0;
cancel_reset_timeout(engine);
- /* after failure, require heartbeats to reset device */
+ /* after failure, require fast heartbeats to reset device */
intel_engine_set_heartbeat(engine, 1);
err = wait_for_reset(engine, rq, HZ / 2);
intel_engine_set_heartbeat(engine,
engine->defaults.heartbeat_interval_ms);
+
+ engine->props.preempt_timeout_ms = engine->defaults.preempt_timeout_ms;
if (err) {
pr_err("Cancelled inflight0 request did not reset\n");
goto out;
--
2.20.1
More information about the Intel-gfx-trybot
mailing list