drm/i915: Also record time difference if vblank evasion fails, v2.
authorMaarten Lankhorst <maarten.lankhorst@linux.intel.com>
Tue, 1 Sep 2015 10:15:33 +0000 (12:15 +0200)
committerDaniel Vetter <daniel.vetter@ffwll.ch>
Wed, 2 Sep 2015 11:51:40 +0000 (13:51 +0200)
This makes the error message slightly more useful.

Changes since v1:
- Use ktime_get() while irqs are still disabled. (vsyrjala)

Signed-off-by: Maarten Lankhorst <maarten.lankhorst@linux.intel.com>
Reviewed-by: Ville Syrjälä <ville.syrjala@linux.intel.com>
Signed-off-by: Daniel Vetter <daniel.vetter@ffwll.ch>
drivers/gpu/drm/i915/intel_drv.h
drivers/gpu/drm/i915/intel_sprite.c

index 8f8d320486ff2b5d12ecca9da70c550c27f05697..090d67b0430726ae60f7683459e35f4c1f5cbe7e 100644 (file)
@@ -564,6 +564,8 @@ struct intel_crtc {
        int scanline_offset;
 
        unsigned start_vbl_count;
+       ktime_t start_vbl_time;
+
        struct intel_crtc_atomic_commit atomic;
 
        /* scalers available on this crtc */
index c35c2339f472394189402cc6ad188647f8519f79..ca7e26430e66eeb8a9b60e467144ba6b3b361c47 100644 (file)
@@ -134,6 +134,7 @@ void intel_pipe_update_start(struct intel_crtc *crtc)
 
        drm_crtc_vblank_put(&crtc->base);
 
+       crtc->start_vbl_time = ktime_get();
        crtc->start_vbl_count = dev->driver->get_vblank_counter(dev, pipe);
 
        trace_i915_pipe_update_vblank_evaded(crtc, min, max,
@@ -154,14 +155,16 @@ void intel_pipe_update_end(struct intel_crtc *crtc)
        struct drm_device *dev = crtc->base.dev;
        enum pipe pipe = crtc->pipe;
        u32 end_vbl_count = dev->driver->get_vblank_counter(dev, pipe);
+       ktime_t end_vbl_time = ktime_get();
 
        trace_i915_pipe_update_end(crtc, end_vbl_count);
 
        local_irq_enable();
 
        if (crtc->start_vbl_count && crtc->start_vbl_count != end_vbl_count)
-               DRM_ERROR("Atomic update failure on pipe %c (start=%u end=%u)\n",
-                         pipe_name(pipe), crtc->start_vbl_count, end_vbl_count);
+               DRM_ERROR("Atomic update failure on pipe %c (start=%u end=%u) time %lld us\n",
+                         pipe_name(pipe), crtc->start_vbl_count, end_vbl_count,
+                         ktime_us_delta(end_vbl_time, crtc->start_vbl_time));
 }
 
 static void