[Intel-gfx] [PATCH i-g-t v4 4/5] kms_vblank: Switch from using crtc0 statically to explicitly setting mode.
Marius Vlad
marius.c.vlad at intel.com
Tue May 10 17:03:47 UTC 2016
Robert Foss improved test/kms_vblank and in the same time extended it on
all outputs and all pipes. The following seems to happen on pipe B (on
pipe A the test always works):
[46795.932490] [drm:drm_wait_vblank] returning 66 to client
[46795.932509] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.932512] [drm:drm_queue_vblank_event] event on vblank count 132, current 66, crtc 1
[46795.932513] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.932514] [drm:drm_wait_vblank] waiting on vblank count 66, crtc 1
[46795.932515] [drm:drm_wait_vblank] returning 66 to client
[46795.949118] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-41)@ 46798.136518 -> 46798.137125 [e 0 us, 0 rep]
[46795.949121] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=66, diff=1, hw=66 hw_last=65
[46795.965785] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-41)@ 46798.153186 -> 46798.153793 [e 0 us, 0 rep]
[46795.965788] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=67, diff=1, hw=67 hw_last=66
[46795.982450] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-41)@ 46798.169851 -> 46798.170459 [e 0 us, 0 rep]
[46795.982453] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=68, diff=1, hw=68 hw_last=67
[46795.999115] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-41)@ 46798.186518 -> 46798.187125 [e 0 us, 0 rep]
[46795.999119] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=69, diff=1, hw=69 hw_last=68
[46796.015792] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-40)@ 46798.203193 -> 46798.203785 [e 0 us, 0 rep]
[46796.015799] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=70, diff=1, hw=70 hw_last=69
[46796.032459] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-40)@ 46798.219861 -> 46798.220453 [e 0 us, 0 rep]
[46796.032465] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=71, diff=1, hw=71 hw_last=70
[46796.049121] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-41)@ 46798.236524 -> 46798.237131 [e 0 us, 0 rep]
[46796.049127] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=72, diff=1, hw=72 hw_last=71
[46796.049129] [drm:drm_handle_vblank_events] vblank event on 73, current 73
[46796.050217] kms_vblank: exiting, ret=99
and full output of the test:
(kms_vblank:17715) igt-kms-DEBUG: display: HDMI-A-1: set_pipe(A)
(kms_vblank:17715) igt-kms-DEBUG: display: commit {
(kms_vblank:17715) igt-kms-DEBUG: display: HDMI-A-1: Selecting pipe A
(kms_vblank:17715) igt-kms-DEBUG: display: }
(kms_vblank:17715) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=0x34325258, tiling=0x0, size=0)
(kms_vblank:17715) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=1, pitch=7680)
(kms_vblank:17715) igt-kms-DEBUG: display: A.0: plane_set_fb(47)
(kms_vblank:17715) igt-kms-DEBUG: display: commit {
(kms_vblank:17715) igt-kms-DEBUG: display: HDMI-A-1: Selecting pipe A
(kms_vblank:17715) igt-kms-DEBUG: display: HDMI-A-1: SetCrtc pipe A, fb 47, panning (0, 0), mode 1920x1080
(kms_vblank:17715) igt-kms-DEBUG: display: }
(kms_vblank:17715) INFO: Beginning accuracy on pipe A, connector HDMI-A-1
(kms_vblank:17715) INFO:
accuracy on pipe A, connector HDMI-A-1: PASSED
(kms_vblank:17715) igt-kms-DEBUG: display: A.0: plane_set_fb(0)
(kms_vblank:17715) igt-kms-DEBUG: display: HDMI-A-1: set_pipe(any)
(kms_vblank:17715) igt-kms-DEBUG: display: commit {
(kms_vblank:17715) igt-kms-DEBUG: display: HDMI-A-1: Selecting pipe A
(kms_vblank:17715) igt-kms-DEBUG: display: HDMI-A-1: SetCrtc pipe A, disabling
(kms_vblank:17715) igt-kms-DEBUG: display: }
(kms_vblank:17715) igt-kms-DEBUG: display: HDMI-A-1: set_pipe(B)
(kms_vblank:17715) igt-kms-DEBUG: display: commit {
(kms_vblank:17715) igt-kms-DEBUG: display: HDMI-A-1: Selecting pipe B
(kms_vblank:17715) igt-kms-DEBUG: display: }
(kms_vblank:17715) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=0x34325258, tiling=0x0, size=0)
(kms_vblank:17715) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=1, pitch=7680)
(kms_vblank:17715) igt-kms-DEBUG: display: B.0: plane_set_fb(47)
(kms_vblank:17715) igt-kms-DEBUG: display: commit {
(kms_vblank:17715) igt-kms-DEBUG: display: HDMI-A-1: Selecting pipe B
(kms_vblank:17715) igt-kms-DEBUG: display: HDMI-A-1: SetCrtc pipe B, fb 47, panning (0, 0), mode 1920x1080
(kms_vblank:17715) igt-kms-DEBUG: display: }
(kms_vblank:17715) INFO: Beginning accuracy on pipe B, connector HDMI-A-1
(kms_vblank:17715) CRITICAL: Test assertion failure function accuracy, file kms_vblank.c:182:
(kms_vblank:17715) CRITICAL: Failed assertion: ev.sequence == target
(kms_vblank:17715) CRITICAL: error: 73 != 66
**** END ****
Subtest accuracy: FAIL (2,412s)
I've attaching full debug on the kernel side (HSW machine).
Any ideas on why would this happen?
On Mon, May 09, 2016 at 04:39:20PM -0400, Robert Foss wrote:
>
> On 2016-05-09 11:38 AM, Marius Vlad wrote:
> >On Tue, Apr 26, 2016 at 11:54:21AM -0400, robert.foss at collabora.com
> wrote:
> >>From: Robert Foss <robert.foss at collabora.com>
> >>
> >>Previously crtc0 was statically used for VBLANK tests, but
> >>that assumption is not valid for the VC4 platform.
> >>Instead we're now explicitly setting the mode.
> >>
> >>Also add support for testing all connected connectors during
> >>the same test.
> >>
> >>Signed-off-by: Robert Foss <robert.foss at collabora.com>
> >>---
> >> tests/kms_vblank.c | 162
> ++++++++++++++++++++++++++++++++++++++++++++---------
> >> 1 file changed, 137 insertions(+), 25 deletions(-)
> >>
> >>diff --git a/tests/kms_vblank.c b/tests/kms_vblank.c
> >>index 40ab6fd..6c32c57 100644
> >>--- a/tests/kms_vblank.c
> >>+++ b/tests/kms_vblank.c
> >>@@ -44,6 +44,14 @@
> >>
> >> IGT_TEST_DESCRIPTION("Test speed of WaitVblank.");
> >>
> >>+typedef struct {
> >>+ igt_display_t display;
> >>+ struct igt_fb primary_fb;
> >>+ igt_output_t *output;
> >>+ enum pipe pipe;
> >>+ uint8_t mode_busy:1;
> >>+} data_t;
> >>+
> >> static double elapsed(const struct timespec *start,
> >> const struct timespec *end,
> >> int loop)
> >>@@ -51,38 +59,119 @@ static double elapsed(const struct timespec *start,
> >> return (1e6*(end->tv_sec - start->tv_sec) + (end->tv_nsec -
> start->tv_nsec)/1000)/loop;
> >> }
> >>
> >>-static bool crtc0_active(int fd)
> >>+static bool prepare_crtc(data_t *data, int fd, igt_output_t *output)
> >> {
> >>- union drm_wait_vblank vbl;
> >>+ drmModeModeInfo *mode;
> >>+ igt_display_t *display = &data->display;
> >>+ igt_plane_t *primary;
> >>+
> >>+ /* select the pipe we want to use */
> >>+ igt_output_set_pipe(output, data->pipe);
> >>+ igt_display_commit(display);
> >>+
> >>+ if (!output->valid) {
> >>+ igt_output_set_pipe(output, PIPE_ANY);
> >>+ igt_display_commit(display);
> >>+ return false;
> >>+ }
> >>
> >>- memset(&vbl, 0, sizeof(vbl));
> >>- vbl.request.type = DRM_VBLANK_RELATIVE;
> >>- return drmIoctl(fd, DRM_IOCTL_WAIT_VBLANK, &vbl) == 0;
> >>+ /* create and set the primary plane fb */
> >>+ mode = igt_output_get_mode(output);
> >>+ igt_create_color_fb(fd, mode->hdisplay, mode->vdisplay,
> >>+ DRM_FORMAT_XRGB8888,
> >>+ LOCAL_DRM_FORMAT_MOD_NONE,
> >>+ 0.0, 0.0, 0.0,
> >>+ &data->primary_fb);
> >>+
> >>+ primary = igt_output_get_plane(output, IGT_PLANE_PRIMARY);
> >>+ igt_plane_set_fb(primary, &data->primary_fb);
> >>+
> >>+ igt_display_commit(display);
> >>+
> >>+ igt_wait_for_vblank(fd, data->pipe);
> >>+
> >>+ return true;
> >> }
> >>
> >>-static void accuracy(int fd)
> >>+static void cleanup_crtc(data_t *data, int fd, igt_output_t *output)
> >>+{
> >>+ igt_display_t *display = &data->display;
> >>+ igt_plane_t *primary;
> >>+
> >>+ igt_remove_fb(fd, &data->primary_fb);
> >>+
> >>+ primary = igt_output_get_plane(output, IGT_PLANE_PRIMARY);
> >>+ igt_plane_set_fb(primary, NULL);
> >>+
> >>+ igt_output_set_pipe(output, PIPE_ANY);
> >>+ igt_display_commit(display);
> >>+}
> >>+
> >>+static void run_test(data_t *data, int fd, void (*testfunc)(data_t
> *, int))
> >>+{
> >>+ igt_display_t *display = &data->display;
> >>+ igt_output_t *output;
> >>+ enum pipe p;
> >>+ unsigned int valid_tests = 0;
> >>+
> >>+ for_each_connected_output(display, output) {
> >>+ for_each_pipe(display, p) {
> >>+ data->pipe = p;
> >>+
> >>+ if (!prepare_crtc(data, fd, output))
> >>+ continue;
> >>+
> >>+ valid_tests++;
> >>+
> >>+ igt_info("Beginning %s on pipe %s, connector %s\n",
> >>+ igt_subtest_name(),
> >>+ kmstest_pipe_name(data->pipe),
> >>+ igt_output_name(output));
> >>+
> >>+ testfunc(data, fd);
> >>+
> >>+ igt_info("\n%s on pipe %s, connector %s: PASSED\n\n",
> >>+ igt_subtest_name(),
> >>+ kmstest_pipe_name(data->pipe),
> >>+ igt_output_name(output));
> >>+
> >>+ /* cleanup what prepare_crtc() has done */
> >>+ cleanup_crtc(data, fd, output);
> >>+ }
> >>+ }
> >>+
> >>+ igt_require_f(valid_tests, "no valid crtc/connector combinations
> found\n");
> >>+}
> >>+
> >>+static void accuracy(data_t *data, int fd)
> >> {
> >> union drm_wait_vblank vbl;
> >> unsigned long target;
> >>+ uint32_t pipe_id_flag;
> >> int n;
> >>
> >> memset(&vbl, 0, sizeof(vbl));
> >>+ pipe_id_flag = pipe_id_to_vbl_flag(data->pipe);
> >>
> >> vbl.request.type = DRM_VBLANK_RELATIVE;
> >>+ vbl.request.type |= pipe_id_flag;
> >> vbl.request.sequence = 1;
> >> do_ioctl(fd, DRM_IOCTL_WAIT_VBLANK, &vbl);
> >>
> >> target = vbl.reply.sequence + 60;
> >> for (n = 0; n < 60; n++) {
> >> vbl.request.type = DRM_VBLANK_RELATIVE;
> >>+ vbl.request.type |= pipe_id_flag;
> >> vbl.request.sequence = 1;
> >> do_ioctl(fd, DRM_IOCTL_WAIT_VBLANK, &vbl);
> >>
> >> vbl.request.type = DRM_VBLANK_ABSOLUTE | DRM_VBLANK_EVENT;
> >>+ vbl.request.type |= pipe_id_flag;
> >> vbl.request.sequence = target;
> >> do_ioctl(fd, DRM_IOCTL_WAIT_VBLANK, &vbl);
> >> }
> >> vbl.request.type = DRM_VBLANK_RELATIVE;
> >>+ vbl.request.type |= pipe_id_flag;
> >> vbl.request.sequence = 0;
> >> do_ioctl(fd, DRM_IOCTL_WAIT_VBLANK, &vbl);
> >> igt_assert_eq(vbl.reply.sequence, target);
> >>@@ -94,22 +183,26 @@ static void accuracy(int fd)
> >> }
> >> }
> >
> >The accuracy test fails always on pipe B, with Failed assertion:
> >ev.sequence == target. Not sure if this a bug in kernel but it seems to
> >happen on a couple of machines I've tested this (and previously we
> >weren't enabling all pipes)
>
> Thanks for putting this series through some more testing.
> I've only done testing against VC4 on RPi2, and have not observed this
> behavior.
>
> Just by looking at the test-code I can't see a reason for that assert
> ever to fail in a legitimate way.
>
> How do you want to proceed?
Get a machine with Intel? I've CCed a couple of people, maybe they have
some clue on why is this happening.
> >
> >>
> >>-static void vblank_query(int fd, bool busy)
> >>+static void vblank_query(data_t *data, int fd)
> >> {
> >> union drm_wait_vblank vbl;
> >> struct timespec start, end;
> >> unsigned long sq, count = 0;
> >> struct drm_event_vblank buf;
> >>+ uint32_t pipe_id_flag;
> >>
> >> memset(&vbl, 0, sizeof(vbl));
> >>+ pipe_id_flag = pipe_id_to_vbl_flag(data->pipe);
> >>
> >>- if (busy) {
> >>+ if (data->mode_busy) {
> >> vbl.request.type = DRM_VBLANK_RELATIVE | DRM_VBLANK_EVENT;
> >>+ vbl.request.type |= pipe_id_flag;
> >> vbl.request.sequence = 72;
> >> do_ioctl(fd, DRM_IOCTL_WAIT_VBLANK, &vbl);
> >> }
> >>
> >> vbl.request.type = DRM_VBLANK_RELATIVE;
> >>+ vbl.request.type |= pipe_id_flag;
> >> vbl.request.sequence = 0;
> >> do_ioctl(fd, DRM_IOCTL_WAIT_VBLANK, &vbl);
> >>
> >>@@ -118,6 +211,7 @@ static void vblank_query(int fd, bool busy)
> >> clock_gettime(CLOCK_MONOTONIC, &start);
> >> do {
> >> vbl.request.type = DRM_VBLANK_RELATIVE;
> >>+ vbl.request.type |= pipe_id_flag;
> >> vbl.request.sequence = 0;
> >> do_ioctl(fd, DRM_IOCTL_WAIT_VBLANK, &vbl);
> >> count++;
> >>@@ -125,28 +219,32 @@ static void vblank_query(int fd, bool busy)
> >> clock_gettime(CLOCK_MONOTONIC, &end);
> >>
> >> igt_info("Time to query current counter (%s): %7.3fµs\n",
> >>- busy ? "busy" : "idle", elapsed(&start, &end, count));
> >>+ data->mode_busy ? "busy" : "idle", elapsed(&start, &end, count));
> >>
> >>- if (busy)
> >>+ if (data->mode_busy)
> >> igt_assert_eq(read(fd, &buf, sizeof(buf)), sizeof(buf));
> >> }
> >>
> >>-static void vblank_wait(int fd, bool busy)
> >>+static void vblank_wait(data_t *data, int fd)
> >> {
> >> union drm_wait_vblank vbl;
> >> struct timespec start, end;
> >> unsigned long sq, count = 0;
> >> struct drm_event_vblank buf;
> >>+ uint32_t pipe_id_flag;
> >>
> >> memset(&vbl, 0, sizeof(vbl));
> >>+ pipe_id_flag = pipe_id_to_vbl_flag(data->pipe);
> >>
> >>- if (busy) {
> >>+ if (data->mode_busy) {
> >> vbl.request.type = DRM_VBLANK_RELATIVE | DRM_VBLANK_EVENT;
> >>+ vbl.request.type |= pipe_id_flag;
> >> vbl.request.sequence = 72;
> >> do_ioctl(fd, DRM_IOCTL_WAIT_VBLANK, &vbl);
> >> }
> >>
> >> vbl.request.type = DRM_VBLANK_RELATIVE;
> >>+ vbl.request.type |= pipe_id_flag;
> >> vbl.request.sequence = 0;
> >> do_ioctl(fd, DRM_IOCTL_WAIT_VBLANK, &vbl);
> >>
> >>@@ -155,6 +253,7 @@ static void vblank_wait(int fd, bool busy)
> >> clock_gettime(CLOCK_MONOTONIC, &start);
> >> do {
> >> vbl.request.type = DRM_VBLANK_RELATIVE;
> >>+ vbl.request.type |= pipe_id_flag;
> >> vbl.request.sequence = 1;
> >> do_ioctl(fd, DRM_IOCTL_WAIT_VBLANK, &vbl);
> >> count++;
> >>@@ -163,36 +262,49 @@ static void vblank_wait(int fd, bool busy)
> >>
> >> igt_info("Time to wait for %ld/%d vblanks (%s): %7.3fµs\n",
> >> count, (int)(vbl.reply.sequence - sq),
> >>- busy ? "busy" : "idle",
> >>+ data->mode_busy ? "busy" : "idle",
> >> elapsed(&start, &end, count));
> >>
> >>- if (busy)
> >>+ if (data->mode_busy)
> >> igt_assert_eq(read(fd, &buf, sizeof(buf)), sizeof(buf));
> >> }
> >>
> >> igt_main
> >> {
> >> int fd;
> >>+ data_t data;
> >>
> >> igt_skip_on_simulation();
> >>
> >> igt_fixture {
> >> fd = drm_open_driver(DRIVER_ANY);
> >>- igt_require(crtc0_active(fd));
> >>+ kmstest_set_vt_graphics_mode();
> >>+ igt_display_init(&data.display, fd);
> >> }
> >>
> >>- igt_subtest("accuracy")
> >>- accuracy(fd);
> >>+ igt_subtest("accuracy") {
> >>+ data.mode_busy = 0;
> >>+ run_test(&data, fd, accuracy);
> >>+ }
> >>
> >>- igt_subtest("query-idle")
> >>- vblank_query(fd, false);
> >>+ igt_subtest("query-idle") {
> >>+ data.mode_busy = 0;
> >>+ run_test(&data, fd, vblank_query);
> >>+ }
> >>
> >>- igt_subtest("query-busy")
> >>- vblank_query(fd, true);
> >>+ igt_subtest("query-busy") {
> >>+ data.mode_busy = 1;
> >>+ run_test(&data, fd, vblank_query);
> >>+ }
> >>
> >>- igt_subtest("wait-idle")
> >>- vblank_wait(fd, false);
> >>+ igt_subtest("wait-idle") {
> >>+ data.mode_busy = 0;
> >>+ run_test(&data, fd, vblank_wait);
> >>+ }
> >>
> >>- igt_subtest("wait-busy")
> >>- vblank_wait(fd, true);
> >>+ igt_subtest("wait-busy") {
> >>+ data.mode_busy = 1;
> >>+ run_test(&data, fd, vblank_wait);
> >>+ }
> >> }
> >>+
> >white-space.
> >>--
> >>2.5.0
> >>
>
>
-------------- next part --------------
[46793.637286] kms_vblank: executing
[46793.637398] [drm:drm_stub_open]
[46793.637403] [drm:drm_open_helper] pid = 17715, minor = 0
[46793.637407] [drm:i915_gem_open]
[46793.637419] [drm:drm_setup]
[46793.637429] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_VERSION
[46793.637439] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, I915_GETPARAM
[46793.637442] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_VERSION
[46793.637450] [drm:drm_stub_open]
[46793.637452] [drm:drm_open_helper] pid = 17715, minor = 0
[46793.637454] [drm:i915_gem_open]
[46793.637461] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_VERSION
[46793.637463] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, I915_GETPARAM
[46793.637466] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_VERSION
[46793.637494] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETRESOURCES
[46793.637502] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETRESOURCES
[46793.637511] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_SET_CLIENT_CAP
[46793.637514] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_SET_CLIENT_CAP
[46793.637516] [drm:drm_ioctl] ret = -22
[46793.637521] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPLANERESOURCES
[46793.637524] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPLANERESOURCES
[46793.637539] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPLANE
[46793.637543] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPLANE
[46793.637548] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46793.637553] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46793.637559] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.637564] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.637576] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46793.637580] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46793.637585] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.637589] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.637594] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.637598] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.637603] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPLANE
[46793.637606] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPLANE
[46793.637609] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46793.637613] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46793.637617] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.637621] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.637626] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46793.637630] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46793.637634] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.637638] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.637642] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.637646] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.637651] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPLANE
[46793.637654] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPLANE
[46793.637657] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46793.637660] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46793.637664] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.637668] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.637673] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46793.637677] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46793.637681] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.637685] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.637690] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.637694] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.637698] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPLANE
[46793.637701] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPLANE
[46793.637705] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPLANE
[46793.637708] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPLANE
[46793.637710] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPLANE
[46793.637713] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPLANE
[46793.637716] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPLANE
[46793.637718] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPLANE
[46793.637721] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPLANE
[46793.637724] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPLANE
[46793.637726] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPLANE
[46793.637729] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPLANE
[46793.637731] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPLANE
[46793.637734] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPLANE
[46793.637736] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46793.637740] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46793.637744] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.637769] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.637774] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46793.637778] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46793.637782] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.637786] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.637790] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.637794] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.637799] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPLANE
[46793.637802] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPLANE
[46793.637804] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46793.637808] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46793.637812] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.637816] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.637821] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46793.637825] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46793.637829] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.637833] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.637837] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.637841] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.637846] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPLANE
[46793.637849] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPLANE
[46793.637851] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46793.637855] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46793.637859] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.637863] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.637868] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46793.637872] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46793.637876] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.637879] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.637884] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.637888] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.637895] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETRESOURCES
[46793.637898] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETRESOURCES
[46793.637903] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETCONNECTOR
[46793.637906] [drm:drm_mode_object_unreference] OBJ ID: 32 (3)
[46793.637909] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETCONNECTOR
[46793.637912] [drm:drm_mode_object_unreference] OBJ ID: 32 (3)
[46793.637918] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETRESOURCES
[46793.637921] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETRESOURCES
[46793.637924] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETCONNECTOR
[46793.637927] [drm:drm_mode_object_unreference] OBJ ID: 35 (4)
[46793.637932] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETCONNECTOR
[46793.637938] [drm:drm_mode_object_unreference] OBJ ID: 35 (4)
[46793.637943] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETENCODER
[46793.637947] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETCRTC
[46793.637952] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETRESOURCES
[46793.637955] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETRESOURCES
[46793.637959] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETCRTC
[46793.637971] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46793.637975] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46793.637979] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46793.637982] [drm:drm_mode_object_unreference] OBJ ID: 35 (4)
[46793.637985] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46793.637988] [drm:drm_mode_object_unreference] OBJ ID: 35 (4)
[46793.637991] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.637995] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.637999] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.638004] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.638010] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.638014] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.638019] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.638023] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.638028] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.638032] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.638037] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46793.638041] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46793.638045] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46793.638048] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46793.638052] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46793.638055] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46793.638059] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46793.638063] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46793.638066] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46793.638070] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46793.638074] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46793.638077] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46793.638081] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46793.638084] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46793.638088] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46793.638092] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46793.638119] kms_vblank: starting subtest accuracy
[46793.638140] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETRESOURCES
[46793.638144] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETRESOURCES
[46793.638147] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETCONNECTOR
[46793.638150] [drm:drm_mode_object_unreference] OBJ ID: 35 (4)
[46793.638153] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETCONNECTOR
[46793.638159] [drm:drm_mode_object_unreference] OBJ ID: 35 (4)
[46793.638163] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETENCODER
[46793.638166] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETCRTC
[46793.638170] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETRESOURCES
[46793.638172] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETRESOURCES
[46793.638176] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETCRTC
[46793.638184] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46793.638187] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46793.638191] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46793.638194] [drm:drm_mode_object_unreference] OBJ ID: 35 (4)
[46793.638197] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46793.638200] [drm:drm_mode_object_unreference] OBJ ID: 35 (4)
[46793.638203] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.638207] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.638211] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.638215] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.638221] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.638225] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.638230] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.638234] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.638239] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.638243] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.638248] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETRESOURCES
[46793.638251] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETRESOURCES
[46793.638254] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETCONNECTOR
[46793.638257] [drm:drm_mode_object_unreference] OBJ ID: 32 (3)
[46793.638259] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETCONNECTOR
[46793.638261] [drm:drm_mode_object_unreference] OBJ ID: 32 (3)
[46793.638276] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_CREATE_DUMB
[46793.638293] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_ADDFB2
[46793.638301] [drm:drm_mode_addfb2] [FB:47]
[46793.638304] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_MAP_DUMB
[46793.759762] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_DIRTYFB
[46793.759774] [drm:drm_mode_object_unreference] OBJ ID: 47 (2)
[46793.759809] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETRESOURCES
[46793.759816] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETRESOURCES
[46793.759824] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETCONNECTOR
[46793.759831] [drm:drm_mode_object_unreference] OBJ ID: 35 (4)
[46793.759837] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETCONNECTOR
[46793.759847] [drm:drm_mode_object_unreference] OBJ ID: 35 (4)
[46793.759855] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETENCODER
[46793.759860] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETCRTC
[46793.759868] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETRESOURCES
[46793.759873] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETRESOURCES
[46793.759879] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETCRTC
[46793.759893] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46793.759901] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46793.759907] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46793.759912] [drm:drm_mode_object_unreference] OBJ ID: 35 (4)
[46793.759916] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46793.759920] [drm:drm_mode_object_unreference] OBJ ID: 35 (4)
[46793.759926] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.759932] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.759940] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.759947] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.759956] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.759962] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.759970] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.759976] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.759984] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.759990] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46793.759998] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETRESOURCES
[46793.760002] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETRESOURCES
[46793.760007] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETCONNECTOR
[46793.760011] [drm:drm_mode_object_unreference] OBJ ID: 32 (3)
[46793.760015] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETCONNECTOR
[46793.760018] [drm:drm_mode_object_unreference] OBJ ID: 32 (3)
[46793.760035] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_SETCRTC
[46793.760041] [drm:drm_mode_setcrtc] [CRTC:26:crtc-0]
[46793.760050] [drm:drm_mode_setcrtc] [CONNECTOR:35:HDMI-A-1]
[46793.760057] [drm:drm_atomic_state_init] Allocated atomic state ffff8802049b1c00
[46793.760063] [drm:drm_mode_object_reference] OBJ ID: 53 (1)
[46793.760067] [drm:drm_atomic_get_crtc_state] Added [CRTC:26:crtc-0] ffff880206670800 state to ffff8802049b1c00
[46793.760071] [drm:drm_mode_object_reference] OBJ ID: 54 (3)
[46793.760075] [drm:drm_atomic_get_plane_state] Added [PLANE:23:plane-0] ffff880206a27c00 state to ffff8802049b1c00
[46793.760078] [drm:drm_mode_object_unreference] OBJ ID: 53 (2)
[46793.760083] [drm:drm_atomic_set_mode_for_crtc] Set [MODE:1920x1080] for CRTC state ffff880206670800
[46793.760087] [drm:drm_atomic_set_crtc_for_plane] Link plane state ffff880206a27c00 to [CRTC:26:crtc-0]
[46793.760090] [drm:drm_mode_object_unreference] OBJ ID: 54 (4)
[46793.760093] [drm:drm_mode_object_reference] OBJ ID: 47 (2)
[46793.760096] [drm:drm_atomic_set_fb_for_plane] Set [FB:47] for plane state ffff880206a27c00
[46793.760100] [drm:drm_atomic_add_affected_connectors] Adding all current connectors for [CRTC:26:crtc-0] to ffff8802049b1c00
[46793.760104] [drm:drm_mode_object_reference] OBJ ID: 35 (4)
[46793.760107] [drm:drm_mode_object_reference] OBJ ID: 35 (5)
[46793.760110] [drm:drm_atomic_get_connector_state] Added [CONNECTOR:35] ffff880215153fe0 state to ffff8802049b1c00
[46793.760114] [drm:drm_mode_object_unreference] OBJ ID: 35 (6)
[46793.760116] [drm:drm_atomic_set_crtc_for_connector] Link connector state ffff880215153fe0 to [NOCRTC]
[46793.760120] [drm:drm_mode_object_reference] OBJ ID: 35 (5)
[46793.760123] [drm:drm_atomic_set_crtc_for_connector] Link connector state ffff880215153fe0 to [CRTC:26:crtc-0]
[46793.760126] [drm:drm_atomic_check_only] checking ffff8802049b1c00
[46793.760133] [drm:update_connector_routing] Updating routing for [CONNECTOR:35:HDMI-A-1]
[46793.760137] [drm:update_connector_routing] [CONNECTOR:35:HDMI-A-1] keeps [ENCODER:34:TMDS-34], now on [CRTC:26:crtc-0]
[46793.760145] [drm:intel_plane_atomic_calc_changes] [CRTC:26] has [PLANE:23] with fb 47
[46793.760149] [drm:intel_plane_atomic_calc_changes] [PLANE:23] visible 1 -> 1, off 0, on 0, ms 0
[46793.760161] [drm:drm_atomic_commit] commiting ffff8802049b1c00
[46793.768105] [drm:drm_vblank_enable] enabling vblank on crtc 0, ret: 0
[46793.768114] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x5 p(0,662)@ 46795.955406 -> 46795.945598 [e 0 us, 0 rep]
[46793.768118] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=37923, diff=34415, hw=74822 hw_last=40407
[46793.768122] [drm:vblank_disable_fn] disabling vblank on crtc 0
[46793.768128] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x5 p(0,663)@ 46795.955421 -> 46795.945599 [e 0 us, 0 rep]
[46793.768131] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72338, diff=0, hw=74822 hw_last=74822
[46793.768139] [drm:drm_vblank_enable] enabling vblank on crtc 0, ret: 0
[46793.768144] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x5 p(0,664)@ 46795.955437 -> 46795.945600 [e 0 us, 0 rep]
[46793.768147] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72338, diff=0, hw=74822 hw_last=74822
[46793.774372] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-41)@ 46795.961660 -> 46795.962268 [e 0 us, 0 rep]
[46793.774379] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72338, diff=1, hw=74823 hw_last=74822
[46793.774411] [drm:vblank_disable_fn] disabling vblank on crtc 0
[46793.774416] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-38)@ 46795.961710 -> 46795.962273 [e 0 us, 0 rep]
[46793.774419] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72339, diff=0, hw=74823 hw_last=74823
[46793.774429] [drm:drm_atomic_state_default_clear] Clearing atomic state ffff8802049b1c00
[46793.774432] [drm:drm_mode_object_unreference] OBJ ID: 35 (6)
[46793.774436] [drm:drm_mode_object_unreference] OBJ ID: 35 (5)
[46793.774438] [drm:drm_mode_object_unreference] OBJ ID: 53 (1)
[46793.774443] [drm:drm_mode_object_unreference] OBJ ID: 54 (3)
[46793.774445] [drm:drm_atomic_state_free] Freeing atomic state ffff8802049b1c00
[46793.774449] [drm:drm_mode_object_reference] OBJ ID: 47 (3)
[46793.774451] [drm:drm_mode_object_unreference] OBJ ID: 54 (2)
[46793.774454] [drm:drm_mode_object_unreference] OBJ ID: 47 (4)
[46793.774456] [drm:drm_mode_object_unreference] OBJ ID: 35 (4)
[46793.774489] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46793.774493] [drm:drm_vblank_enable] enabling vblank on crtc 0, ret: 0
[46793.774497] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-32)@ 46795.961791 -> 46795.962265 [e 0 us, 0 rep]
[46793.774500] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72339, diff=0, hw=74823 hw_last=74823
[46793.774503] [drm:drm_wait_vblank] waiting on vblank count 72340, crtc 0
[46793.791040] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-41)@ 46795.978330 -> 46795.978937 [e 0 us, 0 rep]
[46793.791046] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72339, diff=1, hw=74824 hw_last=74823
[46793.791070] [drm:drm_wait_vblank] returning 72340 to client
[46793.791072] [drm:vblank_disable_fn] disabling vblank on crtc 0
[46793.791077] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-38)@ 46795.978372 -> 46795.978935 [e 0 us, 0 rep]
[46793.791079] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72340, diff=0, hw=74824 hw_last=74824
[46793.791107] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46793.791111] [drm:drm_vblank_enable] enabling vblank on crtc 0, ret: 0
[46793.791114] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-35)@ 46795.978409 -> 46795.978928 [e 0 us, 0 rep]
[46793.791117] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72340, diff=0, hw=74824 hw_last=74824
[46793.791119] [drm:drm_wait_vblank] waiting on vblank count 72341, crtc 0
[46793.807707] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-41)@ 46795.994997 -> 46795.995605 [e 0 us, 0 rep]
[46793.807713] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72340, diff=1, hw=74825 hw_last=74824
[46793.807749] [drm:drm_wait_vblank] returning 72341 to client
[46793.807752] [drm:vblank_disable_fn] disabling vblank on crtc 0
[46793.807756] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-37)@ 46795.995052 -> 46795.995600 [e 0 us, 0 rep]
[46793.807758] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72341, diff=0, hw=74825 hw_last=74825
[46793.807800] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46793.807805] [drm:drm_vblank_enable] enabling vblank on crtc 0, ret: 0
[46793.807808] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-34)@ 46795.995104 -> 46795.995608 [e 0 us, 0 rep]
[46793.807812] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72341, diff=0, hw=74825 hw_last=74825
[46793.807815] [drm:drm_wait_vblank] waiting on vblank count 72342, crtc 0
[46793.824364] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-41)@ 46796.11659 -> 46796.12266 [e 0 us, 0 rep]
[46793.824368] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72341, diff=1, hw=74826 hw_last=74825
[46793.824394] [drm:drm_wait_vblank] returning 72342 to client
[46793.824395] [drm:vblank_disable_fn] disabling vblank on crtc 0
[46793.824398] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-39)@ 46796.11696 -> 46796.12273 [e 0 us, 0 rep]
[46793.824399] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72342, diff=0, hw=74826 hw_last=74826
[46793.824406] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46793.824408] [drm:drm_vblank_enable] enabling vblank on crtc 0, ret: 0
[46793.824411] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-38)@ 46796.11708 -> 46796.12271 [e 0 us, 0 rep]
[46793.824412] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72342, diff=0, hw=74826 hw_last=74826
[46793.824413] [drm:drm_wait_vblank] waiting on vblank count 72343, crtc 0
[46793.841008] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-43)@ 46796.28303 -> 46796.28940 [e 0 us, 0 rep]
[46793.841011] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72342, diff=1, hw=74827 hw_last=74826
[46793.841036] [drm:drm_wait_vblank] returning 72343 to client
[46793.841037] [drm:vblank_disable_fn] disabling vblank on crtc 0
[46793.841040] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-40)@ 46796.28338 -> 46796.28931 [e 0 us, 0 rep]
[46793.841041] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72343, diff=0, hw=74827 hw_last=74827
[46793.841047] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46793.841049] [drm:drm_vblank_enable] enabling vblank on crtc 0, ret: 0
[46793.841051] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-39)@ 46796.28349 -> 46796.28927 [e 0 us, 0 rep]
[46793.841052] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72343, diff=0, hw=74827 hw_last=74827
[46793.841054] [drm:drm_queue_vblank_event] event on vblank count 72402, current 72343, crtc 0
[46793.841057] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46793.841059] [drm:drm_wait_vblank] waiting on vblank count 72344, crtc 0
[46793.857684] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-42)@ 46796.44980 -> 46796.45602 [e 0 us, 0 rep]
[46793.857687] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72343, diff=1, hw=74828 hw_last=74827
[46793.857711] [drm:drm_wait_vblank] returning 72344 to client
[46793.857717] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46793.857720] [drm:drm_queue_vblank_event] event on vblank count 72402, current 72344, crtc 0
[46793.857721] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46793.857722] [drm:drm_wait_vblank] waiting on vblank count 72345, crtc 0
[46793.874348] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-42)@ 46796.61645 -> 46796.62267 [e 0 us, 0 rep]
[46793.874351] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72344, diff=1, hw=74829 hw_last=74828
[46793.874374] [drm:drm_wait_vblank] returning 72345 to client
[46793.874379] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46793.874382] [drm:drm_queue_vblank_event] event on vblank count 72402, current 72345, crtc 0
[46793.874383] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46793.874384] [drm:drm_wait_vblank] waiting on vblank count 72346, crtc 0
[46793.891018] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-42)@ 46796.78316 -> 46796.78939 [e 0 us, 0 rep]
[46793.891022] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72345, diff=1, hw=74830 hw_last=74829
[46793.891045] [drm:drm_wait_vblank] returning 72346 to client
[46793.891050] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46793.891053] [drm:drm_queue_vblank_event] event on vblank count 72402, current 72346, crtc 0
[46793.891054] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46793.891055] [drm:drm_wait_vblank] waiting on vblank count 72347, crtc 0
[46793.907694] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-41)@ 46796.94993 -> 46796.95600 [e 0 us, 0 rep]
[46793.907697] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72346, diff=1, hw=74831 hw_last=74830
[46793.907721] [drm:drm_wait_vblank] returning 72347 to client
[46793.907726] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46793.907729] [drm:drm_queue_vblank_event] event on vblank count 72402, current 72347, crtc 0
[46793.907731] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46793.907732] [drm:drm_wait_vblank] waiting on vblank count 72348, crtc 0
[46793.924360] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-41)@ 46796.111659 -> 46796.112267 [e 0 us, 0 rep]
[46793.924363] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72347, diff=1, hw=74832 hw_last=74831
[46793.924386] [drm:drm_wait_vblank] returning 72348 to client
[46793.924392] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46793.924395] [drm:drm_queue_vblank_event] event on vblank count 72402, current 72348, crtc 0
[46793.924396] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46793.924397] [drm:drm_wait_vblank] waiting on vblank count 72349, crtc 0
[46793.941016] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-42)@ 46796.128314 -> 46796.128936 [e 0 us, 0 rep]
[46793.941023] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72348, diff=1, hw=74833 hw_last=74832
[46793.941059] [drm:drm_wait_vblank] returning 72349 to client
[46793.941068] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46793.941072] [drm:drm_queue_vblank_event] event on vblank count 72402, current 72349, crtc 0
[46793.941075] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46793.941078] [drm:drm_wait_vblank] waiting on vblank count 72350, crtc 0
[46793.957683] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-42)@ 46796.144984 -> 46796.145606 [e 0 us, 0 rep]
[46793.957687] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72349, diff=1, hw=74834 hw_last=74833
[46793.957713] [drm:drm_wait_vblank] returning 72350 to client
[46793.957719] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46793.957722] [drm:drm_queue_vblank_event] event on vblank count 72402, current 72350, crtc 0
[46793.957723] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46793.957725] [drm:drm_wait_vblank] waiting on vblank count 72351, crtc 0
[46793.974348] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-42)@ 46796.161650 -> 46796.162273 [e 0 us, 0 rep]
[46793.974352] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72350, diff=1, hw=74835 hw_last=74834
[46793.974377] [drm:drm_wait_vblank] returning 72351 to client
[46793.974383] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46793.974385] [drm:drm_queue_vblank_event] event on vblank count 72402, current 72351, crtc 0
[46793.974386] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46793.974388] [drm:drm_wait_vblank] waiting on vblank count 72352, crtc 0
[46793.991013] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-42)@ 46796.178316 -> 46796.178939 [e 0 us, 0 rep]
[46793.991017] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72351, diff=1, hw=74836 hw_last=74835
[46793.991041] [drm:drm_wait_vblank] returning 72352 to client
[46793.991046] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46793.991049] [drm:drm_queue_vblank_event] event on vblank count 72402, current 72352, crtc 0
[46793.991050] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46793.991051] [drm:drm_wait_vblank] waiting on vblank count 72353, crtc 0
[46794.007683] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-41)@ 46796.194987 -> 46796.195594 [e 0 us, 0 rep]
[46794.007686] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72352, diff=1, hw=74837 hw_last=74836
[46794.007702] [drm:drm_wait_vblank] returning 72353 to client
[46794.007707] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.007710] [drm:drm_queue_vblank_event] event on vblank count 72402, current 72353, crtc 0
[46794.007711] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.007712] [drm:drm_wait_vblank] waiting on vblank count 72354, crtc 0
[46794.024351] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-41)@ 46796.211655 -> 46796.212263 [e 0 us, 0 rep]
[46794.024354] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72353, diff=1, hw=74838 hw_last=74837
[46794.024378] [drm:drm_wait_vblank] returning 72354 to client
[46794.024383] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.024386] [drm:drm_queue_vblank_event] event on vblank count 72402, current 72354, crtc 0
[46794.024387] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.024388] [drm:drm_wait_vblank] waiting on vblank count 72355, crtc 0
[46794.040996] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-43)@ 46796.228302 -> 46796.228939 [e 0 us, 0 rep]
[46794.041000] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72354, diff=1, hw=74839 hw_last=74838
[46794.041024] [drm:drm_wait_vblank] returning 72355 to client
[46794.041029] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.041032] [drm:drm_queue_vblank_event] event on vblank count 72402, current 72355, crtc 0
[46794.041033] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.041034] [drm:drm_wait_vblank] waiting on vblank count 72356, crtc 0
[46794.057674] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-42)@ 46796.244980 -> 46796.245602 [e 0 us, 0 rep]
[46794.057677] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72355, diff=1, hw=74840 hw_last=74839
[46794.057701] [drm:drm_wait_vblank] returning 72356 to client
[46794.057708] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.057711] [drm:drm_queue_vblank_event] event on vblank count 72402, current 72356, crtc 0
[46794.057713] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.057714] [drm:drm_wait_vblank] waiting on vblank count 72357, crtc 0
[46794.074353] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-41)@ 46796.261657 -> 46796.262264 [e 0 us, 0 rep]
[46794.074359] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72356, diff=1, hw=74841 hw_last=74840
[46794.074429] [drm:drm_wait_vblank] returning 72357 to client
[46794.074441] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.074446] [drm:drm_queue_vblank_event] event on vblank count 72402, current 72357, crtc 0
[46794.074448] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.074451] [drm:drm_wait_vblank] waiting on vblank count 72358, crtc 0
[46794.091013] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-42)@ 46796.278320 -> 46796.278942 [e 0 us, 0 rep]
[46794.091016] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72357, diff=1, hw=74842 hw_last=74841
[46794.091058] [drm:drm_wait_vblank] returning 72358 to client
[46794.091075] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.091078] [drm:drm_queue_vblank_event] event on vblank count 72402, current 72358, crtc 0
[46794.091080] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.091081] [drm:drm_wait_vblank] waiting on vblank count 72359, crtc 0
[46794.107683] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-41)@ 46796.294992 -> 46796.295599 [e 0 us, 0 rep]
[46794.107687] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72358, diff=1, hw=74843 hw_last=74842
[46794.107726] [drm:drm_wait_vblank] returning 72359 to client
[46794.107744] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.107747] [drm:drm_queue_vblank_event] event on vblank count 72402, current 72359, crtc 0
[46794.107748] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.107749] [drm:drm_wait_vblank] waiting on vblank count 72360, crtc 0
[46794.124350] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-41)@ 46796.311659 -> 46796.312267 [e 0 us, 0 rep]
[46794.124353] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72359, diff=1, hw=74844 hw_last=74843
[46794.124390] [drm:drm_wait_vblank] returning 72360 to client
[46794.124408] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.124410] [drm:drm_queue_vblank_event] event on vblank count 72402, current 72360, crtc 0
[46794.124412] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.124412] [drm:drm_wait_vblank] waiting on vblank count 72361, crtc 0
[46794.140990] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-43)@ 46796.328301 -> 46796.328938 [e 0 us, 0 rep]
[46794.140994] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72360, diff=1, hw=74845 hw_last=74844
[46794.141019] [drm:drm_wait_vblank] returning 72361 to client
[46794.141025] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.141028] [drm:drm_queue_vblank_event] event on vblank count 72402, current 72361, crtc 0
[46794.141031] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.141032] [drm:drm_wait_vblank] waiting on vblank count 72362, crtc 0
[46794.157665] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-42)@ 46796.344976 -> 46796.345598 [e 0 us, 0 rep]
[46794.157668] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72361, diff=1, hw=74846 hw_last=74845
[46794.157693] [drm:drm_wait_vblank] returning 72362 to client
[46794.157719] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.157724] [drm:drm_queue_vblank_event] event on vblank count 72402, current 72362, crtc 0
[46794.157731] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.157734] [drm:drm_wait_vblank] waiting on vblank count 72363, crtc 0
[46794.174347] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-41)@ 46796.361656 -> 46796.362264 [e 0 us, 0 rep]
[46794.174352] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72362, diff=1, hw=74847 hw_last=74846
[46794.174415] [drm:drm_wait_vblank] returning 72363 to client
[46794.174425] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.174430] [drm:drm_queue_vblank_event] event on vblank count 72402, current 72363, crtc 0
[46794.174432] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.174434] [drm:drm_wait_vblank] waiting on vblank count 72364, crtc 0
[46794.191013] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-41)@ 46796.378326 -> 46796.378933 [e 0 us, 0 rep]
[46794.191016] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72363, diff=1, hw=74848 hw_last=74847
[46794.191053] [drm:drm_wait_vblank] returning 72364 to client
[46794.191070] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.191073] [drm:drm_queue_vblank_event] event on vblank count 72402, current 72364, crtc 0
[46794.191074] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.191075] [drm:drm_wait_vblank] waiting on vblank count 72365, crtc 0
[46794.207672] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-42)@ 46796.394986 -> 46796.395608 [e 0 us, 0 rep]
[46794.207675] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72364, diff=1, hw=74849 hw_last=74848
[46794.207710] [drm:drm_wait_vblank] returning 72365 to client
[46794.207728] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.207732] [drm:drm_queue_vblank_event] event on vblank count 72402, current 72365, crtc 0
[46794.207733] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.207734] [drm:drm_wait_vblank] waiting on vblank count 72366, crtc 0
[46794.224338] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-42)@ 46796.411653 -> 46796.412275 [e 0 us, 0 rep]
[46794.224341] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72365, diff=1, hw=74850 hw_last=74849
[46794.224379] [drm:drm_wait_vblank] returning 72366 to client
[46794.224397] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.224400] [drm:drm_queue_vblank_event] event on vblank count 72402, current 72366, crtc 0
[46794.224401] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.224402] [drm:drm_wait_vblank] waiting on vblank count 72367, crtc 0
[46794.240994] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-42)@ 46796.428310 -> 46796.428932 [e 0 us, 0 rep]
[46794.240998] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72366, diff=1, hw=74851 hw_last=74850
[46794.241024] [drm:drm_wait_vblank] returning 72367 to client
[46794.241030] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.241034] [drm:drm_queue_vblank_event] event on vblank count 72402, current 72367, crtc 0
[46794.241035] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.241036] [drm:drm_wait_vblank] waiting on vblank count 72368, crtc 0
[46794.257669] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-42)@ 46796.444985 -> 46796.445608 [e 0 us, 0 rep]
[46794.257672] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72367, diff=1, hw=74852 hw_last=74851
[46794.257709] [drm:drm_wait_vblank] returning 72368 to client
[46794.257726] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.257729] [drm:drm_queue_vblank_event] event on vblank count 72402, current 72368, crtc 0
[46794.257730] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.257731] [drm:drm_wait_vblank] waiting on vblank count 72369, crtc 0
[46794.274332] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-42)@ 46796.461649 -> 46796.462271 [e 0 us, 0 rep]
[46794.274335] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72368, diff=1, hw=74853 hw_last=74852
[46794.274371] [drm:drm_wait_vblank] returning 72369 to client
[46794.274388] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.274391] [drm:drm_queue_vblank_event] event on vblank count 72402, current 72369, crtc 0
[46794.274392] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.274393] [drm:drm_wait_vblank] waiting on vblank count 72370, crtc 0
[46794.290998] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-42)@ 46796.478315 -> 46796.478938 [e 0 us, 0 rep]
[46794.291001] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72369, diff=1, hw=74854 hw_last=74853
[46794.291037] [drm:drm_wait_vblank] returning 72370 to client
[46794.291055] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.291058] [drm:drm_queue_vblank_event] event on vblank count 72402, current 72370, crtc 0
[46794.291059] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.291060] [drm:drm_wait_vblank] waiting on vblank count 72371, crtc 0
[46794.307669] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-41)@ 46796.494988 -> 46796.495595 [e 0 us, 0 rep]
[46794.307672] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72370, diff=1, hw=74855 hw_last=74854
[46794.307709] [drm:drm_wait_vblank] returning 72371 to client
[46794.307726] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.307729] [drm:drm_queue_vblank_event] event on vblank count 72402, current 72371, crtc 0
[46794.307730] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.307731] [drm:drm_wait_vblank] waiting on vblank count 72372, crtc 0
[46794.324340] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-41)@ 46796.511660 -> 46796.512267 [e 0 us, 0 rep]
[46794.324343] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72371, diff=1, hw=74856 hw_last=74855
[46794.324380] [drm:drm_wait_vblank] returning 72372 to client
[46794.324398] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.324401] [drm:drm_queue_vblank_event] event on vblank count 72402, current 72372, crtc 0
[46794.324402] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.324403] [drm:drm_wait_vblank] waiting on vblank count 72373, crtc 0
[46794.340990] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-42)@ 46796.528310 -> 46796.528932 [e 0 us, 0 rep]
[46794.340993] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72372, diff=1, hw=74857 hw_last=74856
[46794.341018] [drm:drm_wait_vblank] returning 72373 to client
[46794.341025] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.341028] [drm:drm_queue_vblank_event] event on vblank count 72402, current 72373, crtc 0
[46794.341030] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.341030] [drm:drm_wait_vblank] waiting on vblank count 72374, crtc 0
[46794.357666] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-41)@ 46796.544987 -> 46796.545595 [e 0 us, 0 rep]
[46794.357669] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72373, diff=1, hw=74858 hw_last=74857
[46794.357706] [drm:drm_wait_vblank] returning 72374 to client
[46794.357724] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.357727] [drm:drm_queue_vblank_event] event on vblank count 72402, current 72374, crtc 0
[46794.357728] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.357729] [drm:drm_wait_vblank] waiting on vblank count 72375, crtc 0
[46794.374325] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-42)@ 46796.561647 -> 46796.562269 [e 0 us, 0 rep]
[46794.374328] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72374, diff=1, hw=74859 hw_last=74858
[46794.374365] [drm:drm_wait_vblank] returning 72375 to client
[46794.374382] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.374385] [drm:drm_queue_vblank_event] event on vblank count 72402, current 72375, crtc 0
[46794.374386] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.374387] [drm:drm_wait_vblank] waiting on vblank count 72376, crtc 0
[46794.390994] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-42)@ 46796.578317 -> 46796.578939 [e 0 us, 0 rep]
[46794.390997] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72375, diff=1, hw=74860 hw_last=74859
[46794.391034] [drm:drm_wait_vblank] returning 72376 to client
[46794.391052] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.391054] [drm:drm_queue_vblank_event] event on vblank count 72402, current 72376, crtc 0
[46794.391056] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.391057] [drm:drm_wait_vblank] waiting on vblank count 72377, crtc 0
[46794.407666] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-41)@ 46796.594990 -> 46796.595597 [e 0 us, 0 rep]
[46794.407669] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72376, diff=1, hw=74861 hw_last=74860
[46794.407706] [drm:drm_wait_vblank] returning 72377 to client
[46794.407724] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.407726] [drm:drm_queue_vblank_event] event on vblank count 72402, current 72377, crtc 0
[46794.407728] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.407728] [drm:drm_wait_vblank] waiting on vblank count 72378, crtc 0
[46794.424328] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-42)@ 46796.611652 -> 46796.612274 [e 0 us, 0 rep]
[46794.424331] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72377, diff=1, hw=74862 hw_last=74861
[46794.424368] [drm:drm_wait_vblank] returning 72378 to client
[46794.424386] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.424389] [drm:drm_queue_vblank_event] event on vblank count 72402, current 72378, crtc 0
[46794.424390] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.424391] [drm:drm_wait_vblank] waiting on vblank count 72379, crtc 0
[46794.440985] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-42)@ 46796.628310 -> 46796.628932 [e 0 us, 0 rep]
[46794.440988] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72378, diff=1, hw=74863 hw_last=74862
[46794.441014] [drm:drm_wait_vblank] returning 72379 to client
[46794.441021] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.441024] [drm:drm_queue_vblank_event] event on vblank count 72402, current 72379, crtc 0
[46794.441025] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.441026] [drm:drm_wait_vblank] waiting on vblank count 72380, crtc 0
[46794.457666] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-41)@ 46796.644989 -> 46796.645597 [e 0 us, 0 rep]
[46794.457673] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72379, diff=1, hw=74864 hw_last=74863
[46794.457713] [drm:drm_wait_vblank] returning 72380 to client
[46794.457725] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.457732] [drm:drm_queue_vblank_event] event on vblank count 72402, current 72380, crtc 0
[46794.457735] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.457737] [drm:drm_wait_vblank] waiting on vblank count 72381, crtc 0
[46794.474323] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-42)@ 46796.661648 -> 46796.662271 [e 0 us, 0 rep]
[46794.474327] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72380, diff=1, hw=74865 hw_last=74864
[46794.474387] [drm:drm_wait_vblank] returning 72381 to client
[46794.474395] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.474399] [drm:drm_queue_vblank_event] event on vblank count 72402, current 72381, crtc 0
[46794.474401] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.474403] [drm:drm_wait_vblank] waiting on vblank count 72382, crtc 0
[46794.490989] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-42)@ 46796.678316 -> 46796.678939 [e 0 us, 0 rep]
[46794.490992] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72381, diff=1, hw=74866 hw_last=74865
[46794.491031] [drm:drm_wait_vblank] returning 72382 to client
[46794.491049] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.491052] [drm:drm_queue_vblank_event] event on vblank count 72402, current 72382, crtc 0
[46794.491053] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.491054] [drm:drm_wait_vblank] waiting on vblank count 72383, crtc 0
[46794.507664] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-41)@ 46796.694992 -> 46796.695600 [e 0 us, 0 rep]
[46794.507667] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72382, diff=1, hw=74867 hw_last=74866
[46794.507705] [drm:drm_wait_vblank] returning 72383 to client
[46794.507722] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.507725] [drm:drm_queue_vblank_event] event on vblank count 72402, current 72383, crtc 0
[46794.507727] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.507728] [drm:drm_wait_vblank] waiting on vblank count 72384, crtc 0
[46794.524317] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-42)@ 46796.711646 -> 46796.712268 [e 0 us, 0 rep]
[46794.524320] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72383, diff=1, hw=74868 hw_last=74867
[46794.524358] [drm:drm_wait_vblank] returning 72384 to client
[46794.524376] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.524379] [drm:drm_queue_vblank_event] event on vblank count 72402, current 72384, crtc 0
[46794.524380] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.524381] [drm:drm_wait_vblank] waiting on vblank count 72385, crtc 0
[46794.540988] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-42)@ 46796.728316 -> 46796.728938 [e 0 us, 0 rep]
[46794.540994] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72384, diff=1, hw=74869 hw_last=74868
[46794.541033] [drm:drm_wait_vblank] returning 72385 to client
[46794.541044] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.541052] [drm:drm_queue_vblank_event] event on vblank count 72402, current 72385, crtc 0
[46794.541055] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.541057] [drm:drm_wait_vblank] waiting on vblank count 72386, crtc 0
[46794.557655] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-42)@ 46796.744985 -> 46796.745607 [e 0 us, 0 rep]
[46794.557659] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72385, diff=1, hw=74870 hw_last=74869
[46794.557715] [drm:drm_wait_vblank] returning 72386 to client
[46794.557723] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.557726] [drm:drm_queue_vblank_event] event on vblank count 72402, current 72386, crtc 0
[46794.557728] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.557729] [drm:drm_wait_vblank] waiting on vblank count 72387, crtc 0
[46794.574325] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-41)@ 46796.761657 -> 46796.762265 [e 0 us, 0 rep]
[46794.574329] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72386, diff=1, hw=74871 hw_last=74870
[46794.574362] [drm:drm_wait_vblank] returning 72387 to client
[46794.574381] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.574384] [drm:drm_queue_vblank_event] event on vblank count 72402, current 72387, crtc 0
[46794.574385] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.574386] [drm:drm_wait_vblank] waiting on vblank count 72388, crtc 0
[46794.590994] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-41)@ 46796.778327 -> 46796.778934 [e 0 us, 0 rep]
[46794.590997] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72387, diff=1, hw=74872 hw_last=74871
[46794.591031] [drm:drm_wait_vblank] returning 72388 to client
[46794.591049] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.591052] [drm:drm_queue_vblank_event] event on vblank count 72402, current 72388, crtc 0
[46794.591053] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.591054] [drm:drm_wait_vblank] waiting on vblank count 72389, crtc 0
[46794.607654] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-42)@ 46796.794987 -> 46796.795609 [e 0 us, 0 rep]
[46794.607657] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72388, diff=1, hw=74873 hw_last=74872
[46794.607680] [drm:drm_wait_vblank] returning 72389 to client
[46794.607687] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.607689] [drm:drm_queue_vblank_event] event on vblank count 72402, current 72389, crtc 0
[46794.607691] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.607692] [drm:drm_wait_vblank] waiting on vblank count 72390, crtc 0
[46794.624317] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-42)@ 46796.811651 -> 46796.812273 [e 0 us, 0 rep]
[46794.624320] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72389, diff=1, hw=74874 hw_last=74873
[46794.624353] [drm:drm_wait_vblank] returning 72390 to client
[46794.624373] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.624375] [drm:drm_queue_vblank_event] event on vblank count 72402, current 72390, crtc 0
[46794.624377] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.624377] [drm:drm_wait_vblank] waiting on vblank count 72391, crtc 0
[46794.640980] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-42)@ 46796.828315 -> 46796.828937 [e 0 us, 0 rep]
[46794.640983] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72390, diff=1, hw=74875 hw_last=74874
[46794.641016] [drm:drm_wait_vblank] returning 72391 to client
[46794.641035] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.641038] [drm:drm_queue_vblank_event] event on vblank count 72402, current 72391, crtc 0
[46794.641040] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.641041] [drm:drm_wait_vblank] waiting on vblank count 72392, crtc 0
[46794.657636] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-43)@ 46796.844972 -> 46796.845609 [e 0 us, 0 rep]
[46794.657639] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72391, diff=1, hw=74876 hw_last=74875
[46794.657676] [drm:drm_wait_vblank] returning 72392 to client
[46794.657693] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.657697] [drm:drm_queue_vblank_event] event on vblank count 72402, current 72392, crtc 0
[46794.657698] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.657699] [drm:drm_wait_vblank] waiting on vblank count 72393, crtc 0
[46794.674311] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-42)@ 46796.861648 -> 46796.862270 [e 0 us, 0 rep]
[46794.674314] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72392, diff=1, hw=74877 hw_last=74876
[46794.674352] [drm:drm_wait_vblank] returning 72393 to client
[46794.674370] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.674373] [drm:drm_queue_vblank_event] event on vblank count 72402, current 72393, crtc 0
[46794.674374] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.674375] [drm:drm_wait_vblank] waiting on vblank count 72394, crtc 0
[46794.690979] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-42)@ 46796.878316 -> 46796.878939 [e 0 us, 0 rep]
[46794.690982] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72393, diff=1, hw=74878 hw_last=74877
[46794.691018] [drm:drm_wait_vblank] returning 72394 to client
[46794.691036] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.691038] [drm:drm_queue_vblank_event] event on vblank count 72402, current 72394, crtc 0
[46794.691040] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.691041] [drm:drm_wait_vblank] waiting on vblank count 72395, crtc 0
[46794.707648] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-42)@ 46796.894987 -> 46796.895609 [e 0 us, 0 rep]
[46794.707652] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72394, diff=1, hw=74879 hw_last=74878
[46794.707688] [drm:drm_wait_vblank] returning 72395 to client
[46794.707706] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.707709] [drm:drm_queue_vblank_event] event on vblank count 72402, current 72395, crtc 0
[46794.707710] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.707711] [drm:drm_wait_vblank] waiting on vblank count 72396, crtc 0
[46794.724305] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-42)@ 46796.911644 -> 46796.912267 [e 0 us, 0 rep]
[46794.724308] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72395, diff=1, hw=74880 hw_last=74879
[46794.724345] [drm:drm_wait_vblank] returning 72396 to client
[46794.724363] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.724366] [drm:drm_queue_vblank_event] event on vblank count 72402, current 72396, crtc 0
[46794.724367] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.724368] [drm:drm_wait_vblank] waiting on vblank count 72397, crtc 0
[46794.740971] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-42)@ 46796.928311 -> 46796.928933 [e 0 us, 0 rep]
[46794.740974] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72396, diff=1, hw=74881 hw_last=74880
[46794.741001] [drm:drm_wait_vblank] returning 72397 to client
[46794.741007] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.741011] [drm:drm_queue_vblank_event] event on vblank count 72402, current 72397, crtc 0
[46794.741013] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.741014] [drm:drm_wait_vblank] waiting on vblank count 72398, crtc 0
[46794.757649] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-42)@ 46796.944987 -> 46796.945609 [e 0 us, 0 rep]
[46794.757655] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72397, diff=1, hw=74882 hw_last=74881
[46794.757696] [drm:drm_wait_vblank] returning 72398 to client
[46794.757707] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.757712] [drm:drm_queue_vblank_event] event on vblank count 72402, current 72398, crtc 0
[46794.757715] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.757717] [drm:drm_wait_vblank] waiting on vblank count 72399, crtc 0
[46794.774304] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-42)@ 46796.961646 -> 46796.962268 [e 0 us, 0 rep]
[46794.774307] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72398, diff=1, hw=74883 hw_last=74882
[46794.774344] [drm:drm_wait_vblank] returning 72399 to client
[46794.774361] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.774364] [drm:drm_queue_vblank_event] event on vblank count 72402, current 72399, crtc 0
[46794.774365] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.774366] [drm:drm_wait_vblank] waiting on vblank count 72400, crtc 0
[46794.790974] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-42)@ 46796.978316 -> 46796.978938 [e 0 us, 0 rep]
[46794.790977] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72399, diff=1, hw=74884 hw_last=74883
[46794.791003] [drm:drm_wait_vblank] returning 72400 to client
[46794.791009] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.791012] [drm:drm_queue_vblank_event] event on vblank count 72402, current 72400, crtc 0
[46794.791013] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.791014] [drm:drm_wait_vblank] waiting on vblank count 72401, crtc 0
[46794.807644] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-42)@ 46796.994987 -> 46796.995610 [e 0 us, 0 rep]
[46794.807647] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72400, diff=1, hw=74885 hw_last=74884
[46794.807685] [drm:drm_wait_vblank] returning 72401 to client
[46794.807702] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.807705] [drm:drm_queue_vblank_event] event on vblank count 72402, current 72401, crtc 0
[46794.807706] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.807707] [drm:drm_wait_vblank] waiting on vblank count 72402, crtc 0
[46794.824308] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-42)@ 46797.11652 -> 46797.12274 [e 0 us, 0 rep]
[46794.824311] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72401, diff=1, hw=74886 hw_last=74885
[46794.824317] [drm:drm_handle_vblank_events] vblank event on 72402, current 72402
[46794.824319] [drm:drm_handle_vblank_events] vblank event on 72402, current 72402
[46794.824320] [drm:drm_handle_vblank_events] vblank event on 72402, current 72402
[46794.824320] [drm:drm_handle_vblank_events] vblank event on 72402, current 72402
[46794.824321] [drm:drm_handle_vblank_events] vblank event on 72402, current 72402
[46794.824322] [drm:drm_handle_vblank_events] vblank event on 72402, current 72402
[46794.824323] [drm:drm_handle_vblank_events] vblank event on 72402, current 72402
[46794.824324] [drm:drm_handle_vblank_events] vblank event on 72402, current 72402
[46794.824325] [drm:drm_handle_vblank_events] vblank event on 72402, current 72402
[46794.824325] [drm:drm_handle_vblank_events] vblank event on 72402, current 72402
[46794.824326] [drm:drm_handle_vblank_events] vblank event on 72402, current 72402
[46794.824327] [drm:drm_handle_vblank_events] vblank event on 72402, current 72402
[46794.824328] [drm:drm_handle_vblank_events] vblank event on 72402, current 72402
[46794.824328] [drm:drm_handle_vblank_events] vblank event on 72402, current 72402
[46794.824329] [drm:drm_handle_vblank_events] vblank event on 72402, current 72402
[46794.824330] [drm:drm_handle_vblank_events] vblank event on 72402, current 72402
[46794.824331] [drm:drm_handle_vblank_events] vblank event on 72402, current 72402
[46794.824332] [drm:drm_handle_vblank_events] vblank event on 72402, current 72402
[46794.824333] [drm:drm_handle_vblank_events] vblank event on 72402, current 72402
[46794.824334] [drm:drm_handle_vblank_events] vblank event on 72402, current 72402
[46794.824334] [drm:drm_handle_vblank_events] vblank event on 72402, current 72402
[46794.824335] [drm:drm_handle_vblank_events] vblank event on 72402, current 72402
[46794.824336] [drm:drm_handle_vblank_events] vblank event on 72402, current 72402
[46794.824338] [drm:drm_wait_vblank] returning 72402 to client
[46794.824338] [drm:drm_handle_vblank_events] vblank event on 72402, current 72402
[46794.824340] [drm:drm_handle_vblank_events] vblank event on 72402, current 72402
[46794.824340] [drm:drm_handle_vblank_events] vblank event on 72402, current 72402
[46794.824341] [drm:drm_handle_vblank_events] vblank event on 72402, current 72402
[46794.824342] [drm:drm_handle_vblank_events] vblank event on 72402, current 72402
[46794.824343] [drm:drm_handle_vblank_events] vblank event on 72402, current 72402
[46794.824343] [drm:drm_handle_vblank_events] vblank event on 72402, current 72402
[46794.824344] [drm:drm_handle_vblank_events] vblank event on 72402, current 72402
[46794.824345] [drm:drm_handle_vblank_events] vblank event on 72402, current 72402
[46794.824346] [drm:drm_handle_vblank_events] vblank event on 72402, current 72402
[46794.824347] [drm:drm_handle_vblank_events] vblank event on 72402, current 72402
[46794.824347] [drm:drm_handle_vblank_events] vblank event on 72402, current 72402
[46794.824348] [drm:drm_handle_vblank_events] vblank event on 72402, current 72402
[46794.824349] [drm:drm_handle_vblank_events] vblank event on 72402, current 72402
[46794.824350] [drm:drm_handle_vblank_events] vblank event on 72402, current 72402
[46794.824350] [drm:drm_handle_vblank_events] vblank event on 72402, current 72402
[46794.824351] [drm:drm_handle_vblank_events] vblank event on 72402, current 72402
[46794.824352] [drm:drm_handle_vblank_events] vblank event on 72402, current 72402
[46794.824353] [drm:drm_handle_vblank_events] vblank event on 72402, current 72402
[46794.824353] [drm:drm_handle_vblank_events] vblank event on 72402, current 72402
[46794.824354] [drm:drm_handle_vblank_events] vblank event on 72402, current 72402
[46794.824355] [drm:drm_handle_vblank_events] vblank event on 72402, current 72402
[46794.824356] [drm:drm_handle_vblank_events] vblank event on 72402, current 72402
[46794.824356] [drm:drm_handle_vblank_events] vblank event on 72402, current 72402
[46794.824357] [drm:drm_handle_vblank_events] vblank event on 72402, current 72402
[46794.824358] [drm:drm_handle_vblank_events] vblank event on 72402, current 72402
[46794.824359] [drm:drm_handle_vblank_events] vblank event on 72402, current 72402
[46794.824359] [drm:drm_handle_vblank_events] vblank event on 72402, current 72402
[46794.824360] [drm:drm_handle_vblank_events] vblank event on 72402, current 72402
[46794.824361] [drm:drm_handle_vblank_events] vblank event on 72402, current 72402
[46794.824362] [drm:drm_handle_vblank_events] vblank event on 72402, current 72402
[46794.824363] [drm:drm_handle_vblank_events] vblank event on 72402, current 72402
[46794.824364] [drm:drm_handle_vblank_events] vblank event on 72402, current 72402
[46794.824364] [drm:drm_handle_vblank_events] vblank event on 72402, current 72402
[46794.824365] [drm:drm_handle_vblank_events] vblank event on 72402, current 72402
[46794.824366] [drm:drm_handle_vblank_events] vblank event on 72402, current 72402
[46794.824367] [drm:vblank_disable_fn] disabling vblank on crtc 0
[46794.824371] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-37)@ 46797.11718 -> 46797.12266 [e 0 us, 0 rep]
[46794.824373] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72402, diff=0, hw=74886 hw_last=74886
[46794.824378] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.824379] [drm:drm_vblank_enable] enabling vblank on crtc 0, ret: 0
[46794.824381] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-36)@ 46797.11728 -> 46797.12262 [e 0 us, 0 rep]
[46794.824382] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72402, diff=0, hw=74886 hw_last=74886
[46794.824384] [drm:drm_queue_vblank_event] event on vblank count 72402, current 72402, crtc 0
[46794.824385] [drm:vblank_disable_fn] disabling vblank on crtc 0
[46794.824386] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-36)@ 46797.11734 -> 46797.12267 [e 0 us, 0 rep]
[46794.824387] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72402, diff=0, hw=74886 hw_last=74886
[46794.824389] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.824390] [drm:drm_vblank_enable] enabling vblank on crtc 0, ret: 0
[46794.824391] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-36)@ 46797.11739 -> 46797.12272 [e 0 us, 0 rep]
[46794.824392] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72402, diff=0, hw=74886 hw_last=74886
[46794.824393] [drm:drm_wait_vblank] waiting on vblank count 72402, crtc 0
[46794.824393] [drm:drm_wait_vblank] returning 72402 to client
[46794.824394] [drm:vblank_disable_fn] disabling vblank on crtc 0
[46794.824396] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-35)@ 46797.11743 -> 46797.12262 [e 0 us, 0 rep]
[46794.824397] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72402, diff=0, hw=74886 hw_last=74886
[46794.824431] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_RMFB
[46794.824433] [drm:drm_mode_object_unreference] OBJ ID: 47 (4)
[46794.824442] [drm:drm_atomic_state_init] Allocated atomic state ffff88020544e800
[46794.824444] [drm:drm_mode_object_reference] OBJ ID: 60 (1)
[46794.824445] [drm:drm_atomic_get_crtc_state] Added [CRTC:26:crtc-0] ffff8802065cf000 state to ffff88020544e800
[46794.824446] [drm:drm_mode_object_reference] OBJ ID: 47 (3)
[46794.824447] [drm:drm_atomic_get_plane_state] Added [PLANE:23:plane-0] ffff8800cbdcc000 state to ffff88020544e800
[46794.824448] [drm:drm_mode_object_unreference] OBJ ID: 60 (2)
[46794.824449] [drm:drm_atomic_set_mode_for_crtc] Set [NOMODE] for CRTC state ffff8802065cf000
[46794.824450] [drm:drm_atomic_set_crtc_for_plane] Link plane state ffff8800cbdcc000 to [NOCRTC]
[46794.824451] [drm:drm_mode_object_unreference] OBJ ID: 47 (4)
[46794.824451] [drm:drm_atomic_set_fb_for_plane] Set [NOFB] for plane state ffff8800cbdcc000
[46794.824453] [drm:drm_atomic_add_affected_connectors] Adding all current connectors for [CRTC:26:crtc-0] to ffff88020544e800
[46794.824455] [drm:drm_mode_object_reference] OBJ ID: 35 (3)
[46794.824455] [drm:drm_mode_object_reference] OBJ ID: 35 (4)
[46794.824456] [drm:drm_atomic_get_connector_state] Added [CONNECTOR:35] ffff88002f35f880 state to ffff88020544e800
[46794.824457] [drm:drm_mode_object_unreference] OBJ ID: 35 (5)
[46794.824458] [drm:drm_atomic_set_crtc_for_connector] Link connector state ffff88002f35f880 to [NOCRTC]
[46794.824459] [drm:drm_atomic_check_only] checking ffff88020544e800
[46794.824460] [drm:drm_atomic_helper_check_modeset] [CRTC:26:crtc-0] mode changed
[46794.824461] [drm:drm_atomic_helper_check_modeset] [CRTC:26:crtc-0] enable changed
[46794.824462] [drm:update_connector_routing] Updating routing for [CONNECTOR:35:HDMI-A-1]
[46794.824463] [drm:update_connector_routing] Disabling [CONNECTOR:35:HDMI-A-1]
[46794.824464] [drm:drm_atomic_helper_check_modeset] [CRTC:26:crtc-0] active changed
[46794.824465] [drm:drm_atomic_helper_check_modeset] [CRTC:26:crtc-0] needs all connectors, enable: n, active: n
[46794.824466] [drm:drm_atomic_add_affected_connectors] Adding all current connectors for [CRTC:26:crtc-0] to ffff88020544e800
[46794.824469] [drm:intel_plane_atomic_calc_changes] [CRTC:26] has [PLANE:23] with fb -1
[46794.824470] [drm:intel_plane_atomic_calc_changes] [PLANE:23] visible 1 -> 0, off 1, on 0, ms 1
[46794.824474] [drm:drm_atomic_commit] commiting ffff88020544e800
[46794.824478] [drm:drm_vblank_enable] enabling vblank on crtc 0, ret: 0
[46794.824480] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-30)@ 46797.11827 -> 46797.12271 [e 0 us, 0 rep]
[46794.824481] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72402, diff=0, hw=74886 hw_last=74886
[46794.840983] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-41)@ 46797.28328 -> 46797.28935 [e 0 us, 0 rep]
[46794.840986] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72402, diff=1, hw=74887 hw_last=74886
[46794.841009] [drm:vblank_disable_fn] disabling vblank on crtc 0
[46794.841013] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-39)@ 46797.28361 -> 46797.28938 [e 0 us, 0 rep]
[46794.841015] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72403, diff=0, hw=74887 hw_last=74887
[46794.841021] [drm:hsw_audio_codec_disable] Disable audio codec on pipe A
[46794.841030] [drm:drm_vblank_off] crtc 0, vblank enabled 0, inmodeset 0
[46794.841032] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-37)@ 46797.28380 -> 46797.28928 [e 0 us, 0 rep]
[46794.841033] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72403, diff=0, hw=74887 hw_last=74887
[46794.841035] [drm:intel_disable_pipe] disabling pipe A
[46794.859147] [drm:__intel_fbc_disable] Disabling FBC on pipe A
[46794.859151] [drm:intel_disable_shared_dpll] disable WRPLL 1 (active 1, on? 1) for crtc 26
[46794.859153] [drm:intel_disable_shared_dpll] disabling WRPLL 1
[46794.859157] [drm:verify_encoder_state] [ENCODER:33:DAC-33]
[46794.859159] [drm:verify_encoder_state] [ENCODER:34:TMDS-34]
[46794.859160] [drm:intel_connector_verify_state] [CONNECTOR:32:VGA-1]
[46794.859161] [drm:intel_connector_verify_state] [CONNECTOR:35:HDMI-A-1]
[46794.859162] [drm:verify_single_dpll_state] WRPLL 1
[46794.859163] [drm:verify_single_dpll_state] WRPLL 2
[46794.859164] [drm:verify_single_dpll_state] SPLL
[46794.859165] [drm:verify_single_dpll_state] LCPLL 810
[46794.859166] [drm:verify_single_dpll_state] LCPLL 1350
[46794.859167] [drm:verify_single_dpll_state] LCPLL 2700
[46794.859171] [drm:drm_mode_object_unreference] OBJ ID: 60 (1)
[46794.859173] [drm:verify_crtc_state] [CRTC:26]
[46794.859177] [drm:drm_atomic_state_default_clear] Clearing atomic state ffff88020544e800
[46794.859178] [drm:drm_mode_object_unreference] OBJ ID: 35 (4)
[46794.859179] [drm:drm_mode_object_unreference] OBJ ID: 35 (3)
[46794.859180] [drm:drm_mode_object_unreference] OBJ ID: 47 (3)
[46794.859181] [drm:drm_atomic_state_free] Freeing atomic state ffff88020544e800
[46794.859183] [drm:drm_mode_object_unreference] OBJ ID: 47 (2)
[46794.859185] [drm:drm_mode_object_unreference] OBJ ID: 47 (1)
[46794.859237] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_GEM_CLOSE
[46794.859882] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETRESOURCES
[46794.859885] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETRESOURCES
[46794.859887] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETCONNECTOR
[46794.859889] [drm:drm_mode_object_unreference] OBJ ID: 32 (3)
[46794.859890] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETCONNECTOR
[46794.859891] [drm:drm_mode_object_unreference] OBJ ID: 32 (3)
[46794.859894] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETRESOURCES
[46794.859896] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETRESOURCES
[46794.859897] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETCONNECTOR
[46794.859899] [drm:drm_mode_object_unreference] OBJ ID: 35 (3)
[46794.859900] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETCONNECTOR
[46794.859903] [drm:drm_mode_object_unreference] OBJ ID: 35 (3)
[46794.859905] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETENCODER
[46794.859906] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETCRTC
[46794.859909] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETRESOURCES
[46794.859910] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETRESOURCES
[46794.859911] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETCRTC
[46794.859915] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46794.859917] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46794.859918] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46794.859920] [drm:drm_mode_object_unreference] OBJ ID: 35 (3)
[46794.859921] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46794.859922] [drm:drm_mode_object_unreference] OBJ ID: 35 (3)
[46794.859923] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46794.859925] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46794.859927] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46794.859929] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46794.859931] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46794.859933] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46794.859935] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46794.859936] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46794.859938] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46794.859940] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46794.859943] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_SETCRTC
[46794.859945] [drm:drm_mode_setcrtc] [CRTC:26:crtc-0]
[46794.859947] [drm:drm_atomic_state_init] Allocated atomic state ffff88002e0b3800
[46794.859949] [drm:drm_atomic_get_crtc_state] Added [CRTC:26:crtc-0] ffff880215228800 state to ffff88002e0b3800
[46794.859951] [drm:drm_atomic_get_plane_state] Added [PLANE:23:plane-0] ffff8802068b8180 state to ffff88002e0b3800
[46794.859952] [drm:drm_atomic_set_mode_for_crtc] Set [NOMODE] for CRTC state ffff880215228800
[46794.859953] [drm:drm_atomic_set_crtc_for_plane] Link plane state ffff8802068b8180 to [NOCRTC]
[46794.859954] [drm:drm_atomic_set_fb_for_plane] Set [NOFB] for plane state ffff8802068b8180
[46794.859955] [drm:drm_atomic_add_affected_connectors] Adding all current connectors for [CRTC:26:crtc-0] to ffff88002e0b3800
[46794.859956] [drm:drm_atomic_check_only] checking ffff88002e0b3800
[46794.859960] [drm:drm_atomic_commit] commiting ffff88002e0b3800
[46794.859963] [drm:drm_atomic_state_default_clear] Clearing atomic state ffff88002e0b3800
[46794.859964] [drm:drm_atomic_state_free] Freeing atomic state ffff88002e0b3800
[46794.859972] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETRESOURCES
[46794.859973] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETRESOURCES
[46794.859975] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETCONNECTOR
[46794.859976] [drm:drm_mode_object_unreference] OBJ ID: 35 (3)
[46794.859977] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETCONNECTOR
[46794.859979] [drm:drm_mode_object_unreference] OBJ ID: 35 (3)
[46794.859981] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETENCODER
[46794.859982] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETCRTC
[46794.859983] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETRESOURCES
[46794.859984] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETRESOURCES
[46794.859985] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETCRTC
[46794.859987] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETCRTC
[46794.859989] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46794.859991] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46794.859992] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46794.859993] [drm:drm_mode_object_unreference] OBJ ID: 35 (3)
[46794.859994] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46794.859995] [drm:drm_mode_object_unreference] OBJ ID: 35 (3)
[46794.859997] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46794.859998] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46794.860000] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46794.860001] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46794.860003] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46794.860005] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46794.860007] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46794.860008] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46794.860010] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46794.860012] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46794.860014] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETRESOURCES
[46794.860015] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETRESOURCES
[46794.860016] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETCONNECTOR
[46794.860017] [drm:drm_mode_object_unreference] OBJ ID: 32 (3)
[46794.860018] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETCONNECTOR
[46794.860018] [drm:drm_mode_object_unreference] OBJ ID: 32 (3)
[46794.860024] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_CREATE_DUMB
[46794.860039] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_ADDFB2
[46794.860042] [drm:drm_mode_addfb2] [FB:47]
[46794.860043] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_MAP_DUMB
[46794.862442] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_DIRTYFB
[46794.862444] [drm:drm_mode_object_unreference] OBJ ID: 47 (2)
[46794.862451] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETRESOURCES
[46794.862452] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETRESOURCES
[46794.862454] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETCONNECTOR
[46794.862456] [drm:drm_mode_object_unreference] OBJ ID: 35 (3)
[46794.862457] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETCONNECTOR
[46794.862460] [drm:drm_mode_object_unreference] OBJ ID: 35 (3)
[46794.862461] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETENCODER
[46794.862463] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETCRTC
[46794.862464] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETRESOURCES
[46794.862465] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETRESOURCES
[46794.862467] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETCRTC
[46794.862468] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETCRTC
[46794.862471] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46794.862473] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46794.862474] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46794.862475] [drm:drm_mode_object_unreference] OBJ ID: 35 (3)
[46794.862476] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_OBJ_GETPROPERTIES
[46794.862477] [drm:drm_mode_object_unreference] OBJ ID: 35 (3)
[46794.862479] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46794.862480] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46794.862482] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46794.862483] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46794.862485] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46794.862487] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46794.862489] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46794.862490] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46794.862492] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46794.862493] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETPROPERTY
[46794.862495] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETRESOURCES
[46794.862496] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETRESOURCES
[46794.862498] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETCONNECTOR
[46794.862498] [drm:drm_mode_object_unreference] OBJ ID: 32 (3)
[46794.862499] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_GETCONNECTOR
[46794.862500] [drm:drm_mode_object_unreference] OBJ ID: 32 (3)
[46794.862503] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_MODE_SETCRTC
[46794.862505] [drm:drm_mode_setcrtc] [CRTC:30:crtc-1]
[46794.862507] [drm:drm_mode_setcrtc] [CONNECTOR:35:HDMI-A-1]
[46794.862509] [drm:drm_atomic_state_init] Allocated atomic state ffff88002e0b3800
[46794.862511] [drm:drm_atomic_get_crtc_state] Added [CRTC:30:crtc-1] ffff880215229000 state to ffff88002e0b3800
[46794.862512] [drm:drm_atomic_get_plane_state] Added [PLANE:28:plane-3] ffff8802068b8780 state to ffff88002e0b3800
[46794.862514] [drm:drm_atomic_set_mode_for_crtc] Set [MODE:1920x1080] for CRTC state ffff880215229000
[46794.862515] [drm:drm_atomic_set_crtc_for_plane] Link plane state ffff8802068b8780 to [CRTC:30:crtc-1]
[46794.862516] [drm:drm_mode_object_reference] OBJ ID: 47 (2)
[46794.862516] [drm:drm_atomic_set_fb_for_plane] Set [FB:47] for plane state ffff8802068b8780
[46794.862517] [drm:drm_atomic_add_affected_connectors] Adding all current connectors for [CRTC:30:crtc-1] to ffff88002e0b3800
[46794.862519] [drm:drm_mode_object_reference] OBJ ID: 35 (3)
[46794.862520] [drm:drm_atomic_get_connector_state] Added [CONNECTOR:35] ffff88002f0b8ba0 state to ffff88002e0b3800
[46794.862520] [drm:drm_mode_object_reference] OBJ ID: 35 (4)
[46794.862521] [drm:drm_atomic_set_crtc_for_connector] Link connector state ffff88002f0b8ba0 to [CRTC:30:crtc-1]
[46794.862522] [drm:drm_atomic_check_only] checking ffff88002e0b3800
[46794.862523] [drm:drm_atomic_helper_check_modeset] [CRTC:30:crtc-1] mode changed
[46794.862524] [drm:drm_atomic_helper_check_modeset] [CRTC:30:crtc-1] enable changed
[46794.862525] [drm:update_connector_routing] Updating routing for [CONNECTOR:35:HDMI-A-1]
[46794.862526] [drm:update_connector_routing] [CONNECTOR:35:HDMI-A-1] using [ENCODER:34:TMDS-34] on [CRTC:30:crtc-1]
[46794.862527] [drm:drm_atomic_helper_check_modeset] [CRTC:30:crtc-1] active changed
[46794.862528] [drm:drm_atomic_helper_check_modeset] [CRTC:30:crtc-1] needs all connectors, enable: y, active: y
[46794.862529] [drm:drm_atomic_add_affected_connectors] Adding all current connectors for [CRTC:30:crtc-1] to ffff88002e0b3800
[46794.862530] [drm:drm_atomic_add_affected_connectors] Adding all current connectors for [CRTC:30:crtc-1] to ffff88002e0b3800
[46794.862531] [drm:connected_sink_compute_bpp] [CONNECTOR:35:HDMI-A-1] checking for sink bpp constrains
[46794.862532] [drm:connected_sink_compute_bpp] clamping display bpp (was 36) to EDID reported max of 24
[46794.862534] [drm:intel_hdmi_compute_config] picking bpc to 8 for HDMI output
[46794.862535] [drm:intel_hdmi_compute_config] forcing pipe bpc to 24 for HDMI
[46794.862536] [drm:intel_modeset_pipe_config] hw max bpp: 36, pipe bpp: 24, dithering: 0
[46794.862538] [drm:intel_dump_pipe_config] [CRTC:30][modeset] config ffff880215229000 for pipe B
[46794.862538] [drm:intel_dump_pipe_config] cpu_transcoder: B
[46794.862539] [drm:intel_dump_pipe_config] pipe bpp: 24, dithering: 0
[46794.862540] [drm:intel_dump_pipe_config] fdi/pch: 0, lanes: 0, gmch_m: 0, gmch_n: 0, link_m: 0, link_n: 0, tu: 0
[46794.862541] [drm:intel_dump_pipe_config] dp: 0, lanes: 4, gmch_m: 0, gmch_n: 0, link_m: 0, link_n: 0, tu: 0
[46794.862542] [drm:intel_dump_pipe_config] dp: 0, lanes: 4, gmch_m2: 0, gmch_n2: 0, link_m2: 0, link_n2: 0, tu2: 0
[46794.862543] [drm:intel_dump_pipe_config] audio: 1, infoframes: 1
[46794.862543] [drm:intel_dump_pipe_config] requested mode:
[46794.862545] [drm:drm_mode_debug_printmodeline] Modeline 0:"1920x1080" 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
[46794.862546] [drm:intel_dump_pipe_config] adjusted mode:
[46794.862547] [drm:drm_mode_debug_printmodeline] Modeline 0:"1920x1080" 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
[46794.862548] [drm:intel_dump_crtc_timings] crtc timings: 148500 1920 2008 2052 2200 1080 1084 1089 1125, type: 0x48 flags: 0x5
[46794.862549] [drm:intel_dump_pipe_config] port clock: 148500
[46794.862549] [drm:intel_dump_pipe_config] pipe src size: 1920x1080
[46794.862550] [drm:intel_dump_pipe_config] num_scalers: 0, scaler_users: 0x0, scaler_id: 0
[46794.862551] [drm:intel_dump_pipe_config] gmch pfit: control: 0x00000000, ratios: 0x00000000, lvds border: 0x00000000
[46794.862552] [drm:intel_dump_pipe_config] pch pfit: pos: 0x00000000, size: 0x00000000, disabled
[46794.862553] [drm:intel_dump_pipe_config] ips: 0
[46794.862553] [drm:intel_dump_pipe_config] double wide: 0
[46794.862554] [drm:intel_dump_pipe_config] ddi_pll_sel: 0x0; dpll_hw_state: wrpll: 0x0 spll: 0x0
[46794.862555] [drm:intel_dump_pipe_config] planes on this crtc
[46794.862556] [drm:intel_dump_pipe_config] STANDARD PLANE:28 plane: 1.0 idx: 3 disabled, scaler_id = 0
[46794.862557] [drm:intel_dump_pipe_config] CURSOR PLANE:29 plane: 1.2 idx: 4 disabled, scaler_id = 0
[46794.862558] [drm:intel_dump_pipe_config] STANDARD PLANE:31 plane: 1.1 idx: 5 disabled, scaler_id = 0
[46794.862559] [drm:drm_atomic_get_crtc_state] Added [CRTC:26:crtc-0] ffff880215229800 state to ffff88002e0b3800
[46794.862561] [drm:intel_plane_atomic_calc_changes] [CRTC:30] has [PLANE:28] with fb 47
[46794.862562] [drm:intel_plane_atomic_calc_changes] [PLANE:28] visible 0 -> 1, off 0, on 1, ms 1
[46794.863221] [drm:intel_find_shared_dpll] CRTC:30 allocated WRPLL 1
[46794.863221] [drm:intel_reference_shared_dpll] using WRPLL 1 for pipe B
[46794.863224] [drm:drm_atomic_commit] commiting ffff88002e0b3800
[46794.865334] [drm:drm_calc_timestamping_constants] crtc 30: hwmode: htotal 2200, vtotal 1125, vdisplay 1080
[46794.865335] [drm:drm_calc_timestamping_constants] crtc 30: clock 148500 kHz framedur 16666666 linedur 14814
[46794.865336] [drm:verify_encoder_state] [ENCODER:33:DAC-33]
[46794.865338] [drm:verify_encoder_state] [ENCODER:34:TMDS-34]
[46794.865338] [drm:intel_connector_verify_state] [CONNECTOR:32:VGA-1]
[46794.865340] [drm:verify_single_dpll_state] WRPLL 1
[46794.865341] [drm:verify_single_dpll_state] WRPLL 2
[46794.865341] [drm:verify_single_dpll_state] SPLL
[46794.865342] [drm:verify_single_dpll_state] LCPLL 810
[46794.865343] [drm:verify_single_dpll_state] LCPLL 1350
[46794.865344] [drm:verify_single_dpll_state] LCPLL 2700
[46794.865345] [drm:intel_enable_shared_dpll] enable WRPLL 1 (active 2, on? 0) for crtc 30
[46794.865346] [drm:intel_enable_shared_dpll] enabling WRPLL 1
[46794.865410] [drm:intel_enable_pipe] enabling pipe B
[46794.865413] [drm:drm_vblank_on] crtc 1, vblank enabled 0, inmodeset 1
[46794.865748] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1: Noisy timestamp 110 us > 20 us [3 reps].
[46794.865749] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x5 p(0,2)@ 46797.53097 -> 46797.53068 [e 110 us, 3 rep]
[46794.865751] [drm:intel_audio_codec_enable] ELD on [CONNECTOR:35:HDMI-A-1], [ENCODER:34:TMDS-34]
[46794.865752] [drm:hsw_audio_codec_enable] Enable audio codec on pipe B, 32 bytes ELD
[46794.865755] [drm:audio_config_hdmi_pixel_clock] Configuring HDMI audio for pixel clock 148500 (0x00090000)
[46794.865763] [drm:drm_vblank_enable] enabling vblank on crtc 1, ret: 0
[46794.865798] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-43)@ 46797.53148 -> 46797.53785 [e 0 us, 1 rep]
[46794.865800] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-43)@ 46797.53149 -> 46797.53786 [e 0 us, 0 rep]
[46794.865801] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=1, diff=1, hw=1 hw_last=0
[46794.865802] [drm:vblank_disable_fn] disabling vblank on crtc 1
[46794.865804] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-43)@ 46797.53153 -> 46797.53790 [e 0 us, 0 rep]
[46794.865805] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=2, diff=0, hw=1 hw_last=1
[46794.865816] [drm:drm_vblank_enable] enabling vblank on crtc 1, ret: 0
[46794.865818] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-42)@ 46797.53167 -> 46797.53789 [e 0 us, 0 rep]
[46794.865819] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=2, diff=0, hw=1 hw_last=1
[46794.882490] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-42)@ 46797.69837 -> 46797.70459 [e 0 us, 0 rep]
[46794.882493] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=2, diff=1, hw=2 hw_last=1
[46794.882530] [drm:vblank_disable_fn] disabling vblank on crtc 1
[46794.882545] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-39)@ 46797.69885 -> 46797.70462 [e 0 us, 0 rep]
[46794.882546] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=3, diff=0, hw=2 hw_last=2
[46794.882551] [drm:intel_connector_verify_state] [CONNECTOR:35:HDMI-A-1]
[46794.882554] [drm:verify_crtc_state] [CRTC:30]
[46794.882562] [drm:verify_single_dpll_state] WRPLL 1
[46794.882564] [drm:drm_atomic_state_default_clear] Clearing atomic state ffff88002e0b3800
[46794.882567] [drm:drm_mode_object_unreference] OBJ ID: 35 (5)
[46794.882569] [drm:drm_atomic_state_free] Freeing atomic state ffff88002e0b3800
[46794.882571] [drm:drm_mode_object_reference] OBJ ID: 47 (3)
[46794.882572] [drm:drm_mode_object_unreference] OBJ ID: 47 (4)
[46794.882572] [drm:drm_mode_object_unreference] OBJ ID: 35 (4)
[46794.882580] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.882582] [drm:drm_vblank_enable] enabling vblank on crtc 1, ret: 0
[46794.882583] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-35)@ 46797.69933 -> 46797.70452 [e 0 us, 0 rep]
[46794.882584] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=3, diff=0, hw=2 hw_last=2
[46794.882585] [drm:drm_wait_vblank] waiting on vblank count 4, crtc 1
[46794.899161] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-42)@ 46797.86509 -> 46797.87131 [e 0 us, 0 rep]
[46794.899164] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=3, diff=1, hw=3 hw_last=2
[46794.899201] [drm:drm_wait_vblank] returning 4 to client
[46794.899203] [drm:vblank_disable_fn] disabling vblank on crtc 1
[46794.899216] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-38)@ 46797.86567 -> 46797.87130 [e 0 us, 0 rep]
[46794.899217] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=4, diff=0, hw=3 hw_last=3
[46794.899234] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.899235] [drm:drm_vblank_enable] enabling vblank on crtc 1, ret: 0
[46794.899237] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-36)@ 46797.86588 -> 46797.87121 [e 0 us, 0 rep]
[46794.899238] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=4, diff=0, hw=3 hw_last=3
[46794.899239] [drm:drm_wait_vblank] waiting on vblank count 5, crtc 1
[46794.915830] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-41)@ 46797.103179 -> 46797.103786 [e 0 us, 0 rep]
[46794.915833] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=4, diff=1, hw=4 hw_last=3
[46794.915866] [drm:drm_wait_vblank] returning 5 to client
[46794.915869] [drm:vblank_disable_fn] disabling vblank on crtc 1
[46794.915873] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-38)@ 46797.103224 -> 46797.103787 [e 0 us, 0 rep]
[46794.915884] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=5, diff=0, hw=4 hw_last=4
[46794.915906] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.915908] [drm:drm_vblank_enable] enabling vblank on crtc 1, ret: 0
[46794.915910] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-36)@ 46797.103261 -> 46797.103795 [e 0 us, 0 rep]
[46794.915911] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=5, diff=0, hw=4 hw_last=4
[46794.915911] [drm:drm_wait_vblank] waiting on vblank count 6, crtc 1
[46794.932501] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-41)@ 46797.119850 -> 46797.120458 [e 0 us, 0 rep]
[46794.932504] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=5, diff=1, hw=5 hw_last=4
[46794.932530] [drm:drm_wait_vblank] returning 6 to client
[46794.932533] [drm:vblank_disable_fn] disabling vblank on crtc 1
[46794.932536] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-38)@ 46797.119888 -> 46797.120451 [e 0 us, 0 rep]
[46794.932537] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=6, diff=0, hw=5 hw_last=5
[46794.932542] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.932544] [drm:drm_vblank_enable] enabling vblank on crtc 1, ret: 0
[46794.932545] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-38)@ 46797.119898 -> 46797.120461 [e 0 us, 0 rep]
[46794.932546] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=6, diff=0, hw=5 hw_last=5
[46794.932547] [drm:drm_wait_vblank] waiting on vblank count 7, crtc 1
[46794.949152] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-42)@ 46797.136503 -> 46797.137125 [e 0 us, 0 rep]
[46794.949156] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=6, diff=1, hw=6 hw_last=5
[46794.949180] [drm:drm_wait_vblank] returning 7 to client
[46794.949183] [drm:vblank_disable_fn] disabling vblank on crtc 1
[46794.949187] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-39)@ 46797.136540 -> 46797.137118 [e 0 us, 0 rep]
[46794.949189] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=7, diff=0, hw=6 hw_last=6
[46794.949194] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.949196] [drm:drm_vblank_enable] enabling vblank on crtc 1, ret: 0
[46794.949200] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-39)@ 46797.136553 -> 46797.137131 [e 0 us, 0 rep]
[46794.949201] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=7, diff=0, hw=6 hw_last=6
[46794.949203] [drm:drm_queue_vblank_event] event on vblank count 73, current 7, crtc 1
[46794.949204] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.949205] [drm:drm_wait_vblank] waiting on vblank count 8, crtc 1
[46794.965811] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-42)@ 46797.153162 -> 46797.153784 [e 0 us, 0 rep]
[46794.965814] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=7, diff=1, hw=7 hw_last=6
[46794.965850] [drm:drm_wait_vblank] returning 8 to client
[46794.965868] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.965871] [drm:drm_queue_vblank_event] event on vblank count 74, current 8, crtc 1
[46794.965872] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.965873] [drm:drm_wait_vblank] waiting on vblank count 9, crtc 1
[46794.982489] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-42)@ 46797.169841 -> 46797.170463 [e 0 us, 0 rep]
[46794.982492] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=8, diff=1, hw=8 hw_last=7
[46794.982529] [drm:drm_wait_vblank] returning 9 to client
[46794.982547] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.982549] [drm:drm_queue_vblank_event] event on vblank count 75, current 9, crtc 1
[46794.982551] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.982551] [drm:drm_wait_vblank] waiting on vblank count 10, crtc 1
[46794.999154] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-42)@ 46797.186507 -> 46797.187129 [e 0 us, 0 rep]
[46794.999158] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=9, diff=1, hw=9 hw_last=8
[46794.999182] [drm:drm_wait_vblank] returning 10 to client
[46794.999189] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.999192] [drm:drm_queue_vblank_event] event on vblank count 76, current 10, crtc 1
[46794.999195] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46794.999196] [drm:drm_wait_vblank] waiting on vblank count 11, crtc 1
[46795.015827] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-41)@ 46797.203181 -> 46797.203788 [e 0 us, 0 rep]
[46795.015830] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=10, diff=1, hw=10 hw_last=9
[46795.015867] [drm:drm_wait_vblank] returning 11 to client
[46795.015885] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.015888] [drm:drm_queue_vblank_event] event on vblank count 77, current 11, crtc 1
[46795.015889] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.015890] [drm:drm_wait_vblank] waiting on vblank count 12, crtc 1
[46795.032493] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-41)@ 46797.219848 -> 46797.220455 [e 0 us, 0 rep]
[46795.032497] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=11, diff=1, hw=11 hw_last=10
[46795.032522] [drm:drm_wait_vblank] returning 12 to client
[46795.032528] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.032531] [drm:drm_queue_vblank_event] event on vblank count 78, current 12, crtc 1
[46795.032534] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.032535] [drm:drm_wait_vblank] waiting on vblank count 13, crtc 1
[46795.049152] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-42)@ 46797.236507 -> 46797.237129 [e 0 us, 0 rep]
[46795.049155] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=12, diff=1, hw=12 hw_last=11
[46795.049182] [drm:drm_wait_vblank] returning 13 to client
[46795.049189] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.049193] [drm:drm_queue_vblank_event] event on vblank count 79, current 13, crtc 1
[46795.049195] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.049196] [drm:drm_wait_vblank] waiting on vblank count 14, crtc 1
[46795.065809] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-42)@ 46797.253165 -> 46797.253787 [e 0 us, 0 rep]
[46795.065812] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=13, diff=1, hw=13 hw_last=12
[46795.065849] [drm:drm_wait_vblank] returning 14 to client
[46795.065867] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.065869] [drm:drm_queue_vblank_event] event on vblank count 80, current 14, crtc 1
[46795.065871] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.065871] [drm:drm_wait_vblank] waiting on vblank count 15, crtc 1
[46795.082488] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-41)@ 46797.269845 -> 46797.270453 [e 0 us, 0 rep]
[46795.082491] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=14, diff=1, hw=14 hw_last=13
[46795.082528] [drm:drm_wait_vblank] returning 15 to client
[46795.082545] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.082548] [drm:drm_queue_vblank_event] event on vblank count 81, current 15, crtc 1
[46795.082549] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.082550] [drm:drm_wait_vblank] waiting on vblank count 16, crtc 1
[46795.099152] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-42)@ 46797.286509 -> 46797.287132 [e 0 us, 0 rep]
[46795.099155] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=15, diff=1, hw=15 hw_last=14
[46795.099191] [drm:drm_wait_vblank] returning 16 to client
[46795.099209] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.099211] [drm:drm_queue_vblank_event] event on vblank count 82, current 16, crtc 1
[46795.099213] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.099214] [drm:drm_wait_vblank] waiting on vblank count 17, crtc 1
[46795.115822] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-41)@ 46797.303180 -> 46797.303788 [e 0 us, 0 rep]
[46795.115825] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=16, diff=1, hw=16 hw_last=15
[46795.115862] [drm:drm_wait_vblank] returning 17 to client
[46795.115879] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.115882] [drm:drm_queue_vblank_event] event on vblank count 83, current 17, crtc 1
[46795.115883] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.115884] [drm:drm_wait_vblank] waiting on vblank count 18, crtc 1
[46795.132488] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-41)@ 46797.319848 -> 46797.320455 [e 0 us, 0 rep]
[46795.132492] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=17, diff=1, hw=17 hw_last=16
[46795.132518] [drm:drm_wait_vblank] returning 18 to client
[46795.132524] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.132527] [drm:drm_queue_vblank_event] event on vblank count 84, current 18, crtc 1
[46795.132529] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.132530] [drm:drm_wait_vblank] waiting on vblank count 19, crtc 1
[46795.149144] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-42)@ 46797.336505 -> 46797.337127 [e 0 us, 0 rep]
[46795.149148] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=18, diff=1, hw=18 hw_last=17
[46795.149174] [drm:drm_wait_vblank] returning 19 to client
[46795.149181] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.149185] [drm:drm_queue_vblank_event] event on vblank count 85, current 19, crtc 1
[46795.149187] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.149188] [drm:drm_wait_vblank] waiting on vblank count 20, crtc 1
[46795.165805] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-42)@ 46797.353166 -> 46797.353788 [e 0 us, 0 rep]
[46795.165808] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=19, diff=1, hw=19 hw_last=18
[46795.165845] [drm:drm_wait_vblank] returning 20 to client
[46795.165863] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.165866] [drm:drm_queue_vblank_event] event on vblank count 86, current 20, crtc 1
[46795.165867] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.165868] [drm:drm_wait_vblank] waiting on vblank count 21, crtc 1
[46795.182481] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-42)@ 46797.369843 -> 46797.370465 [e 0 us, 0 rep]
[46795.182484] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=20, diff=1, hw=20 hw_last=19
[46795.182521] [drm:drm_wait_vblank] returning 21 to client
[46795.182538] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.182541] [drm:drm_queue_vblank_event] event on vblank count 87, current 21, crtc 1
[46795.182542] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.182543] [drm:drm_wait_vblank] waiting on vblank count 22, crtc 1
[46795.199147] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-41)@ 46797.386510 -> 46797.387117 [e 0 us, 0 rep]
[46795.199150] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=21, diff=1, hw=21 hw_last=20
[46795.199187] [drm:drm_wait_vblank] returning 22 to client
[46795.199205] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.199207] [drm:drm_queue_vblank_event] event on vblank count 88, current 22, crtc 1
[46795.199208] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.199209] [drm:drm_wait_vblank] waiting on vblank count 23, crtc 1
[46795.215816] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-41)@ 46797.403180 -> 46797.403787 [e 0 us, 0 rep]
[46795.215819] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=22, diff=1, hw=22 hw_last=21
[46795.215857] [drm:drm_wait_vblank] returning 23 to client
[46795.215874] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.215877] [drm:drm_queue_vblank_event] event on vblank count 89, current 23, crtc 1
[46795.215878] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.215879] [drm:drm_wait_vblank] waiting on vblank count 24, crtc 1
[46795.232482] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-41)@ 46797.419846 -> 46797.420454 [e 0 us, 0 rep]
[46795.232485] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=23, diff=1, hw=23 hw_last=22
[46795.232508] [drm:drm_wait_vblank] returning 24 to client
[46795.232514] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.232517] [drm:drm_queue_vblank_event] event on vblank count 90, current 24, crtc 1
[46795.232518] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.232519] [drm:drm_wait_vblank] waiting on vblank count 25, crtc 1
[46795.249145] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-42)@ 46797.436507 -> 46797.437129 [e 0 us, 0 rep]
[46795.249151] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=24, diff=1, hw=24 hw_last=23
[46795.249189] [drm:drm_wait_vblank] returning 25 to client
[46795.249197] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.249202] [drm:drm_queue_vblank_event] event on vblank count 91, current 25, crtc 1
[46795.249205] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.249208] [drm:drm_wait_vblank] waiting on vblank count 26, crtc 1
[46795.265811] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-42)@ 46797.453176 -> 46797.453799 [e 0 us, 0 rep]
[46795.265815] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=25, diff=1, hw=25 hw_last=24
[46795.265840] [drm:drm_wait_vblank] returning 26 to client
[46795.265846] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.265849] [drm:drm_queue_vblank_event] event on vblank count 92, current 26, crtc 1
[46795.265851] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.265852] [drm:drm_wait_vblank] waiting on vblank count 27, crtc 1
[46795.282475] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-42)@ 46797.469842 -> 46797.470464 [e 0 us, 0 rep]
[46795.282479] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=26, diff=1, hw=26 hw_last=25
[46795.282504] [drm:drm_wait_vblank] returning 27 to client
[46795.282510] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.282513] [drm:drm_queue_vblank_event] event on vblank count 93, current 27, crtc 1
[46795.282514] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.282515] [drm:drm_wait_vblank] waiting on vblank count 28, crtc 1
[46795.299147] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-41)@ 46797.486515 -> 46797.487122 [e 0 us, 0 rep]
[46795.299151] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=27, diff=1, hw=27 hw_last=26
[46795.299176] [drm:drm_wait_vblank] returning 28 to client
[46795.299181] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.299184] [drm:drm_queue_vblank_event] event on vblank count 94, current 28, crtc 1
[46795.299185] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.299186] [drm:drm_wait_vblank] waiting on vblank count 29, crtc 1
[46795.315814] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-41)@ 46797.503182 -> 46797.503790 [e 0 us, 0 rep]
[46795.315817] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=28, diff=1, hw=28 hw_last=27
[46795.315842] [drm:drm_wait_vblank] returning 29 to client
[46795.315848] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.315851] [drm:drm_queue_vblank_event] event on vblank count 95, current 29, crtc 1
[46795.315852] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.315853] [drm:drm_wait_vblank] waiting on vblank count 30, crtc 1
[46795.332480] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-41)@ 46797.519849 -> 46797.520457 [e 0 us, 0 rep]
[46795.332483] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=29, diff=1, hw=29 hw_last=28
[46795.332508] [drm:drm_wait_vblank] returning 30 to client
[46795.332514] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.332516] [drm:drm_queue_vblank_event] event on vblank count 96, current 30, crtc 1
[46795.332518] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.332535] [drm:drm_wait_vblank] waiting on vblank count 31, crtc 1
[46795.349126] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-43)@ 46797.536495 -> 46797.537132 [e 0 us, 0 rep]
[46795.349130] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=30, diff=1, hw=30 hw_last=29
[46795.349156] [drm:drm_wait_vblank] returning 31 to client
[46795.349162] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.349165] [drm:drm_queue_vblank_event] event on vblank count 97, current 31, crtc 1
[46795.349167] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.349168] [drm:drm_wait_vblank] waiting on vblank count 32, crtc 1
[46795.365798] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-42)@ 46797.553169 -> 46797.553791 [e 0 us, 0 rep]
[46795.365802] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=31, diff=1, hw=31 hw_last=30
[46795.365827] [drm:drm_wait_vblank] returning 32 to client
[46795.365833] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.365837] [drm:drm_queue_vblank_event] event on vblank count 98, current 32, crtc 1
[46795.365838] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.365839] [drm:drm_wait_vblank] waiting on vblank count 33, crtc 1
[46795.382496] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-40)@ 46797.569862 -> 46797.570455 [e 0 us, 0 rep]
[46795.382505] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=32, diff=1, hw=32 hw_last=31
[46795.382556] [drm:drm_wait_vblank] returning 33 to client
[46795.382569] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.382578] [drm:drm_queue_vblank_event] event on vblank count 99, current 33, crtc 1
[46795.382582] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.382585] [drm:drm_wait_vblank] waiting on vblank count 34, crtc 1
[46795.399154] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-41)@ 46797.586522 -> 46797.587130 [e 0 us, 0 rep]
[46795.399162] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=33, diff=1, hw=33 hw_last=32
[46795.399206] [drm:drm_wait_vblank] returning 34 to client
[46795.399218] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.399224] [drm:drm_queue_vblank_event] event on vblank count 100, current 34, crtc 1
[46795.399228] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.399232] [drm:drm_wait_vblank] waiting on vblank count 35, crtc 1
[46795.415817] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-41)@ 46797.603186 -> 46797.603794 [e 0 us, 0 rep]
[46795.415824] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=34, diff=1, hw=34 hw_last=33
[46795.415865] [drm:drm_wait_vblank] returning 35 to client
[46795.415875] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.415881] [drm:drm_queue_vblank_event] event on vblank count 101, current 35, crtc 1
[46795.415884] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.415887] [drm:drm_wait_vblank] waiting on vblank count 36, crtc 1
[46795.432488] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-40)@ 46797.619859 -> 46797.620451 [e 0 us, 0 rep]
[46795.432495] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=35, diff=1, hw=35 hw_last=34
[46795.432534] [drm:drm_wait_vblank] returning 36 to client
[46795.432544] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.432549] [drm:drm_queue_vblank_event] event on vblank count 102, current 36, crtc 1
[46795.432552] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.432554] [drm:drm_wait_vblank] waiting on vblank count 37, crtc 1
[46795.449128] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-42)@ 46797.636502 -> 46797.637124 [e 0 us, 0 rep]
[46795.449133] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=36, diff=1, hw=36 hw_last=35
[46795.449160] [drm:drm_wait_vblank] returning 37 to client
[46795.449166] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.449170] [drm:drm_queue_vblank_event] event on vblank count 103, current 37, crtc 1
[46795.449171] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.449173] [drm:drm_wait_vblank] waiting on vblank count 38, crtc 1
[46795.465794] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-42)@ 46797.653170 -> 46797.653792 [e 0 us, 0 rep]
[46795.465798] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=37, diff=1, hw=37 hw_last=36
[46795.465824] [drm:drm_wait_vblank] returning 38 to client
[46795.465830] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.465833] [drm:drm_queue_vblank_event] event on vblank count 104, current 38, crtc 1
[46795.465835] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.465836] [drm:drm_wait_vblank] waiting on vblank count 39, crtc 1
[46795.482464] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-42)@ 46797.669841 -> 46797.670463 [e 0 us, 0 rep]
[46795.482468] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=38, diff=1, hw=38 hw_last=37
[46795.482494] [drm:drm_wait_vblank] returning 39 to client
[46795.482500] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.482503] [drm:drm_queue_vblank_event] event on vblank count 105, current 39, crtc 1
[46795.482505] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.482506] [drm:drm_wait_vblank] waiting on vblank count 40, crtc 1
[46795.499131] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-42)@ 46797.686509 -> 46797.687131 [e 0 us, 0 rep]
[46795.499135] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=39, diff=1, hw=39 hw_last=38
[46795.499160] [drm:drm_wait_vblank] returning 40 to client
[46795.499165] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.499169] [drm:drm_queue_vblank_event] event on vblank count 106, current 40, crtc 1
[46795.499170] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.499171] [drm:drm_wait_vblank] waiting on vblank count 41, crtc 1
[46795.515801] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-41)@ 46797.703180 -> 46797.703787 [e 0 us, 0 rep]
[46795.515805] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=40, diff=1, hw=40 hw_last=39
[46795.515831] [drm:drm_wait_vblank] returning 41 to client
[46795.515837] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.515839] [drm:drm_queue_vblank_event] event on vblank count 107, current 41, crtc 1
[46795.515841] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.515842] [drm:drm_wait_vblank] waiting on vblank count 42, crtc 1
[46795.532470] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-41)@ 46797.719850 -> 46797.720457 [e 0 us, 0 rep]
[46795.532474] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=41, diff=1, hw=41 hw_last=40
[46795.532499] [drm:drm_wait_vblank] returning 42 to client
[46795.532504] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.532507] [drm:drm_queue_vblank_event] event on vblank count 108, current 42, crtc 1
[46795.532524] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.532527] [drm:drm_wait_vblank] waiting on vblank count 43, crtc 1
[46795.549125] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-42)@ 46797.736505 -> 46797.737127 [e 0 us, 0 rep]
[46795.549128] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=42, diff=1, hw=42 hw_last=41
[46795.549152] [drm:drm_wait_vblank] returning 43 to client
[46795.549158] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.549161] [drm:drm_queue_vblank_event] event on vblank count 109, current 43, crtc 1
[46795.549162] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.549163] [drm:drm_wait_vblank] waiting on vblank count 44, crtc 1
[46795.565785] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-42)@ 46797.753166 -> 46797.753788 [e 0 us, 0 rep]
[46795.565789] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=43, diff=1, hw=43 hw_last=42
[46795.565814] [drm:drm_wait_vblank] returning 44 to client
[46795.565820] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.565823] [drm:drm_queue_vblank_event] event on vblank count 110, current 44, crtc 1
[46795.565824] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.565825] [drm:drm_wait_vblank] waiting on vblank count 45, crtc 1
[46795.582477] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-41)@ 46797.769855 -> 46797.770463 [e 0 us, 0 rep]
[46795.582483] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=44, diff=1, hw=44 hw_last=43
[46795.582522] [drm:drm_wait_vblank] returning 45 to client
[46795.582531] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.582537] [drm:drm_queue_vblank_event] event on vblank count 111, current 45, crtc 1
[46795.582541] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.582543] [drm:drm_wait_vblank] waiting on vblank count 46, crtc 1
[46795.599133] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-41)@ 46797.786513 -> 46797.787120 [e 0 us, 0 rep]
[46795.599138] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=45, diff=1, hw=45 hw_last=44
[46795.599175] [drm:drm_wait_vblank] returning 46 to client
[46795.599183] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.599188] [drm:drm_queue_vblank_event] event on vblank count 112, current 46, crtc 1
[46795.599191] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.599194] [drm:drm_wait_vblank] waiting on vblank count 47, crtc 1
[46795.615802] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-41)@ 46797.803185 -> 46797.803792 [e 0 us, 0 rep]
[46795.615806] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=46, diff=1, hw=46 hw_last=45
[46795.615833] [drm:drm_wait_vblank] returning 47 to client
[46795.615840] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.615843] [drm:drm_queue_vblank_event] event on vblank count 113, current 47, crtc 1
[46795.615845] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.615846] [drm:drm_wait_vblank] waiting on vblank count 48, crtc 1
[46795.632465] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-41)@ 46797.819850 -> 46797.820457 [e 0 us, 0 rep]
[46795.632469] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=47, diff=1, hw=47 hw_last=46
[46795.632495] [drm:drm_wait_vblank] returning 48 to client
[46795.632502] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.632505] [drm:drm_queue_vblank_event] event on vblank count 114, current 48, crtc 1
[46795.632507] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.632508] [drm:drm_wait_vblank] waiting on vblank count 49, crtc 1
[46795.649132] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-41)@ 46797.836517 -> 46797.837125 [e 0 us, 0 rep]
[46795.649136] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=48, diff=1, hw=48 hw_last=47
[46795.649161] [drm:drm_wait_vblank] returning 49 to client
[46795.649168] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.649171] [drm:drm_queue_vblank_event] event on vblank count 115, current 49, crtc 1
[46795.649173] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.649174] [drm:drm_wait_vblank] waiting on vblank count 50, crtc 1
[46795.665794] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-42)@ 46797.853177 -> 46797.853799 [e 0 us, 0 rep]
[46795.665800] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=49, diff=1, hw=49 hw_last=48
[46795.665838] [drm:drm_wait_vblank] returning 50 to client
[46795.665850] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.665867] [drm:drm_queue_vblank_event] event on vblank count 116, current 50, crtc 1
[46795.665871] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.665873] [drm:drm_wait_vblank] waiting on vblank count 51, crtc 1
[46795.682461] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-41)@ 46797.869847 -> 46797.870454 [e 0 us, 0 rep]
[46795.682466] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=50, diff=1, hw=50 hw_last=49
[46795.682526] [drm:drm_wait_vblank] returning 51 to client
[46795.682535] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.682539] [drm:drm_queue_vblank_event] event on vblank count 117, current 51, crtc 1
[46795.682541] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.682543] [drm:drm_wait_vblank] waiting on vblank count 52, crtc 1
[46795.699120] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-42)@ 46797.886507 -> 46797.887129 [e 0 us, 0 rep]
[46795.699123] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=51, diff=1, hw=51 hw_last=50
[46795.699165] [drm:drm_wait_vblank] returning 52 to client
[46795.699182] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.699185] [drm:drm_queue_vblank_event] event on vblank count 118, current 52, crtc 1
[46795.699187] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.699188] [drm:drm_wait_vblank] waiting on vblank count 53, crtc 1
[46795.715796] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-41)@ 46797.903184 -> 46797.903791 [e 0 us, 0 rep]
[46795.715800] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=52, diff=1, hw=52 hw_last=51
[46795.715840] [drm:drm_wait_vblank] returning 53 to client
[46795.715857] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.715860] [drm:drm_queue_vblank_event] event on vblank count 119, current 53, crtc 1
[46795.715862] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.715863] [drm:drm_wait_vblank] waiting on vblank count 54, crtc 1
[46795.732448] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-42)@ 46797.919836 -> 46797.920459 [e 0 us, 0 rep]
[46795.732451] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=53, diff=1, hw=53 hw_last=52
[46795.732490] [drm:drm_wait_vblank] returning 54 to client
[46795.732508] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.732511] [drm:drm_queue_vblank_event] event on vblank count 120, current 54, crtc 1
[46795.732513] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.732514] [drm:drm_wait_vblank] waiting on vblank count 55, crtc 1
[46795.749110] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-42)@ 46797.936499 -> 46797.937121 [e 0 us, 0 rep]
[46795.749113] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=54, diff=1, hw=54 hw_last=53
[46795.749143] [drm:drm_wait_vblank] returning 55 to client
[46795.749173] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.749188] [drm:drm_queue_vblank_event] event on vblank count 121, current 55, crtc 1
[46795.749193] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.749196] [drm:drm_wait_vblank] waiting on vblank count 56, crtc 1
[46795.765792] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-41)@ 46797.953182 -> 46797.953789 [e 0 us, 0 rep]
[46795.765798] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=55, diff=1, hw=55 hw_last=54
[46795.765833] [drm:drm_wait_vblank] returning 56 to client
[46795.765843] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.765850] [drm:drm_queue_vblank_event] event on vblank count 122, current 56, crtc 1
[46795.765853] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.765854] [drm:drm_wait_vblank] waiting on vblank count 57, crtc 1
[46795.782453] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-42)@ 46797.969843 -> 46797.970466 [e 0 us, 0 rep]
[46795.782458] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=56, diff=1, hw=56 hw_last=55
[46795.782491] [drm:drm_wait_vblank] returning 57 to client
[46795.782500] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.782504] [drm:drm_queue_vblank_event] event on vblank count 123, current 57, crtc 1
[46795.782506] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.782507] [drm:drm_wait_vblank] waiting on vblank count 58, crtc 1
[46795.799120] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-41)@ 46797.986512 -> 46797.987119 [e 0 us, 0 rep]
[46795.799125] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=57, diff=1, hw=57 hw_last=56
[46795.799156] [drm:drm_wait_vblank] returning 58 to client
[46795.799165] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.799169] [drm:drm_queue_vblank_event] event on vblank count 124, current 58, crtc 1
[46795.799171] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.799172] [drm:drm_wait_vblank] waiting on vblank count 59, crtc 1
[46795.815786] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-41)@ 46798.3179 -> 46798.3787 [e 0 us, 0 rep]
[46795.815789] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=58, diff=1, hw=58 hw_last=57
[46795.815826] [drm:drm_wait_vblank] returning 59 to client
[46795.815844] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.815846] [drm:drm_queue_vblank_event] event on vblank count 125, current 59, crtc 1
[46795.815847] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.815848] [drm:drm_wait_vblank] waiting on vblank count 60, crtc 1
[46795.832456] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-41)@ 46798.19850 -> 46798.20457 [e 0 us, 0 rep]
[46795.832459] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=59, diff=1, hw=59 hw_last=58
[46795.832496] [drm:drm_wait_vblank] returning 60 to client
[46795.832515] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.832517] [drm:drm_queue_vblank_event] event on vblank count 126, current 60, crtc 1
[46795.832518] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.832519] [drm:drm_wait_vblank] waiting on vblank count 61, crtc 1
[46795.849120] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-41)@ 46798.36511 -> 46798.37118 [e 0 us, 0 rep]
[46795.849127] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=60, diff=1, hw=60 hw_last=59
[46795.849174] [drm:drm_wait_vblank] returning 61 to client
[46795.849187] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.849194] [drm:drm_queue_vblank_event] event on vblank count 127, current 61, crtc 1
[46795.849198] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.849201] [drm:drm_wait_vblank] waiting on vblank count 62, crtc 1
[46795.865773] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-42)@ 46798.53169 -> 46798.53791 [e 0 us, 0 rep]
[46795.865777] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=61, diff=1, hw=61 hw_last=60
[46795.865804] [drm:drm_wait_vblank] returning 62 to client
[46795.865812] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.865815] [drm:drm_queue_vblank_event] event on vblank count 128, current 62, crtc 1
[46795.865818] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.865819] [drm:drm_wait_vblank] waiting on vblank count 63, crtc 1
[46795.882443] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-42)@ 46798.69840 -> 46798.70462 [e 0 us, 0 rep]
[46795.882447] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=62, diff=1, hw=62 hw_last=61
[46795.882474] [drm:drm_wait_vblank] returning 63 to client
[46795.882480] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.882483] [drm:drm_queue_vblank_event] event on vblank count 129, current 63, crtc 1
[46795.882485] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.882486] [drm:drm_wait_vblank] waiting on vblank count 64, crtc 1
[46795.899120] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-41)@ 46798.86517 -> 46798.87124 [e 0 us, 0 rep]
[46795.899123] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=63, diff=1, hw=63 hw_last=62
[46795.899160] [drm:drm_wait_vblank] returning 64 to client
[46795.899178] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.899180] [drm:drm_queue_vblank_event] event on vblank count 130, current 64, crtc 1
[46795.899182] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.899183] [drm:drm_wait_vblank] waiting on vblank count 65, crtc 1
[46795.915783] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-41)@ 46798.103181 -> 46798.103788 [e 0 us, 0 rep]
[46795.915786] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=64, diff=1, hw=64 hw_last=63
[46795.915822] [drm:drm_wait_vblank] returning 65 to client
[46795.915840] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.915843] [drm:drm_queue_vblank_event] event on vblank count 131, current 65, crtc 1
[46795.915844] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.915845] [drm:drm_wait_vblank] waiting on vblank count 66, crtc 1
[46795.932451] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-41)@ 46798.119850 -> 46798.120457 [e 0 us, 0 rep]
[46795.932454] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=65, diff=1, hw=65 hw_last=64
[46795.932490] [drm:drm_wait_vblank] returning 66 to client
[46795.932509] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.932512] [drm:drm_queue_vblank_event] event on vblank count 132, current 66, crtc 1
[46795.932513] [drm:drm_ioctl] pid=17715, dev=0xe200, auth=1, DRM_IOCTL_WAIT_VBLANK
[46795.932514] [drm:drm_wait_vblank] waiting on vblank count 66, crtc 1
[46795.932515] [drm:drm_wait_vblank] returning 66 to client
[46795.949118] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-41)@ 46798.136518 -> 46798.137125 [e 0 us, 0 rep]
[46795.949121] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=66, diff=1, hw=66 hw_last=65
[46795.965785] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-41)@ 46798.153186 -> 46798.153793 [e 0 us, 0 rep]
[46795.965788] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=67, diff=1, hw=67 hw_last=66
[46795.982450] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-41)@ 46798.169851 -> 46798.170459 [e 0 us, 0 rep]
[46795.982453] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=68, diff=1, hw=68 hw_last=67
[46795.999115] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-41)@ 46798.186518 -> 46798.187125 [e 0 us, 0 rep]
[46795.999119] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=69, diff=1, hw=69 hw_last=68
[46796.015792] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-40)@ 46798.203193 -> 46798.203785 [e 0 us, 0 rep]
[46796.015799] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=70, diff=1, hw=70 hw_last=69
[46796.032459] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-40)@ 46798.219861 -> 46798.220453 [e 0 us, 0 rep]
[46796.032465] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=71, diff=1, hw=71 hw_last=70
[46796.049121] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-41)@ 46798.236524 -> 46798.237131 [e 0 us, 0 rep]
[46796.049127] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=72, diff=1, hw=72 hw_last=71
[46796.049129] [drm:drm_handle_vblank_events] vblank event on 73, current 73
[46796.050217] kms_vblank: exiting, ret=99
[46796.050252] [drm:drm_atomic_state_init] Allocated atomic state ffff880204a11c00
[46796.050256] [drm:drm_atomic_get_plane_state] Added [PLANE:23:plane-0] ffff8800cbe23840 state to ffff880204a11c00
[46796.050257] [drm:drm_atomic_get_plane_state] Added [PLANE:25:plane-1] ffff8800cbe23c00 state to ffff880204a11c00
[46796.050258] [drm:drm_atomic_set_crtc_for_plane] Link plane state ffff8800cbe23c00 to [NOCRTC]
[46796.050259] [drm:drm_atomic_set_fb_for_plane] Set [NOFB] for plane state ffff8800cbe23c00
[46796.050260] [drm:drm_atomic_get_plane_state] Added [PLANE:27:plane-2] ffff8800cbe23900 state to ffff880204a11c00
[46796.050261] [drm:drm_atomic_set_crtc_for_plane] Link plane state ffff8800cbe23900 to [NOCRTC]
[46796.050262] [drm:drm_atomic_set_fb_for_plane] Set [NOFB] for plane state ffff8800cbe23900
[46796.050263] [drm:drm_mode_object_reference] OBJ ID: 47 (3)
[46796.050264] [drm:drm_atomic_get_plane_state] Added [PLANE:28:plane-3] ffff8800cbe23600 state to ffff880204a11c00
[46796.050266] [drm:drm_mode_object_reference] OBJ ID: 55 (1)
[46796.050267] [drm:drm_atomic_get_crtc_state] Added [CRTC:30:crtc-1] ffff880205e8b000 state to ffff880204a11c00
[46796.050269] [drm:drm_atomic_get_plane_state] Added [PLANE:29:plane-4] ffff8800cbe23240 state to ffff880204a11c00
[46796.050270] [drm:drm_atomic_set_crtc_for_plane] Link plane state ffff8800cbe23240 to [NOCRTC]
[46796.050270] [drm:drm_atomic_set_fb_for_plane] Set [NOFB] for plane state ffff8800cbe23240
[46796.050272] [drm:drm_atomic_get_plane_state] Added [PLANE:31:plane-5] ffff8800cbe23300 state to ffff880204a11c00
[46796.050272] [drm:drm_atomic_set_crtc_for_plane] Link plane state ffff8800cbe23300 to [NOCRTC]
[46796.050273] [drm:drm_atomic_set_fb_for_plane] Set [NOFB] for plane state ffff8800cbe23300
[46796.050275] [drm:drm_atomic_get_crtc_state] Added [CRTC:26:crtc-0] ffff880205e8a800 state to ffff880204a11c00
[46796.050278] [drm:drm_atomic_set_mode_for_crtc] Set [MODE:1920x1080] for CRTC state ffff880205e8a800
[46796.050279] [drm:drm_atomic_set_crtc_for_plane] Link plane state ffff8800cbe23840 to [CRTC:26:crtc-0]
[46796.050280] [drm:drm_mode_object_reference] OBJ ID: 54 (1)
[46796.050281] [drm:drm_atomic_set_fb_for_plane] Set [FB:54] for plane state ffff8800cbe23840
[46796.050282] [drm:drm_atomic_add_affected_connectors] Adding all current connectors for [CRTC:26:crtc-0] to ffff880204a11c00
[46796.050284] [drm:drm_mode_object_reference] OBJ ID: 35 (3)
[46796.050285] [drm:drm_mode_object_reference] OBJ ID: 35 (4)
[46796.050286] [drm:drm_atomic_get_connector_state] Added [CONNECTOR:35] ffff880215ad5480 state to ffff880204a11c00
[46796.050286] [drm:drm_mode_object_reference] OBJ ID: 35 (5)
[46796.050287] [drm:drm_mode_object_unreference] OBJ ID: 35 (6)
[46796.050288] [drm:drm_atomic_set_crtc_for_connector] Link connector state ffff880215ad5480 to [CRTC:26:crtc-0]
[46796.050289] [drm:drm_mode_object_unreference] OBJ ID: 55 (2)
[46796.050290] [drm:drm_atomic_set_mode_prop_for_crtc] Set [NOMODE] for CRTC state ffff880205e8b000
[46796.050291] [drm:drm_atomic_set_mode_for_crtc] Set [NOMODE] for CRTC state ffff880205e8b000
[46796.050291] [drm:drm_atomic_set_crtc_for_plane] Link plane state ffff8800cbe23600 to [NOCRTC]
[46796.050292] [drm:drm_mode_object_unreference] OBJ ID: 47 (4)
[46796.050293] [drm:drm_atomic_set_fb_for_plane] Set [NOFB] for plane state ffff8800cbe23600
[46796.050294] [drm:drm_atomic_add_affected_connectors] Adding all current connectors for [CRTC:30:crtc-1] to ffff880204a11c00
[46796.050295] [drm:drm_atomic_check_only] checking ffff880204a11c00
[46796.050297] [drm:drm_atomic_helper_check_modeset] [CRTC:26:crtc-0] mode changed
[46796.050298] [drm:drm_atomic_helper_check_modeset] [CRTC:26:crtc-0] enable changed
[46796.050298] [drm:drm_atomic_helper_check_modeset] [CRTC:30:crtc-1] mode changed
[46796.050299] [drm:drm_atomic_helper_check_modeset] [CRTC:30:crtc-1] enable changed
[46796.050301] [drm:update_connector_routing] Updating routing for [CONNECTOR:35:HDMI-A-1]
[46796.050303] [drm:update_connector_routing] [CONNECTOR:35:HDMI-A-1] keeps [ENCODER:34:TMDS-34], now on [CRTC:26:crtc-0]
[46796.050303] [drm:drm_atomic_helper_check_modeset] [CRTC:26:crtc-0] active changed
[46796.050304] [drm:drm_atomic_helper_check_modeset] [CRTC:26:crtc-0] needs all connectors, enable: y, active: y
[46796.050305] [drm:drm_atomic_add_affected_connectors] Adding all current connectors for [CRTC:26:crtc-0] to ffff880204a11c00
[46796.050306] [drm:drm_atomic_helper_check_modeset] [CRTC:30:crtc-1] active changed
[46796.050307] [drm:drm_atomic_helper_check_modeset] [CRTC:30:crtc-1] needs all connectors, enable: n, active: n
[46796.050308] [drm:drm_atomic_add_affected_connectors] Adding all current connectors for [CRTC:30:crtc-1] to ffff880204a11c00
[46796.050310] [drm:drm_atomic_add_affected_connectors] Adding all current connectors for [CRTC:26:crtc-0] to ffff880204a11c00
[46796.050311] [drm:connected_sink_compute_bpp] [CONNECTOR:35:HDMI-A-1] checking for sink bpp constrains
[46796.050312] [drm:connected_sink_compute_bpp] clamping display bpp (was 36) to EDID reported max of 24
[46796.050317] [drm:intel_hdmi_compute_config] picking bpc to 8 for HDMI output
[46796.050318] [drm:intel_hdmi_compute_config] forcing pipe bpc to 24 for HDMI
[46796.050319] [drm:intel_modeset_pipe_config] hw max bpp: 36, pipe bpp: 24, dithering: 0
[46796.050321] [drm:intel_dump_pipe_config] [CRTC:26][modeset] config ffff880205e8a800 for pipe A
[46796.050322] [drm:intel_dump_pipe_config] cpu_transcoder: A
[46796.050323] [drm:intel_dump_pipe_config] pipe bpp: 24, dithering: 0
[46796.050324] [drm:intel_dump_pipe_config] fdi/pch: 0, lanes: 0, gmch_m: 0, gmch_n: 0, link_m: 0, link_n: 0, tu: 0
[46796.050325] [drm:intel_dump_pipe_config] dp: 0, lanes: 4, gmch_m: 0, gmch_n: 0, link_m: 0, link_n: 0, tu: 0
[46796.050326] [drm:intel_dump_pipe_config] dp: 0, lanes: 4, gmch_m2: 0, gmch_n2: 0, link_m2: 0, link_n2: 0, tu2: 0
[46796.050327] [drm:intel_dump_pipe_config] audio: 1, infoframes: 1
[46796.050327] [drm:intel_dump_pipe_config] requested mode:
[46796.050330] [drm:drm_mode_debug_printmodeline] Modeline 0:"1920x1080" 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
[46796.050330] [drm:intel_dump_pipe_config] adjusted mode:
[46796.050332] [drm:drm_mode_debug_printmodeline] Modeline 0:"1920x1080" 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
[46796.050333] [drm:intel_dump_crtc_timings] crtc timings: 148500 1920 2008 2052 2200 1080 1084 1089 1125, type: 0x48 flags: 0x5
[46796.050334] [drm:intel_dump_pipe_config] port clock: 148500
[46796.050335] [drm:intel_dump_pipe_config] pipe src size: 1920x1080
[46796.050336] [drm:intel_dump_pipe_config] num_scalers: 0, scaler_users: 0x0, scaler_id: 0
[46796.050337] [drm:intel_dump_pipe_config] gmch pfit: control: 0x00000000, ratios: 0x00000000, lvds border: 0x00000000
[46796.050338] [drm:intel_dump_pipe_config] pch pfit: pos: 0x00000000, size: 0x00000000, disabled
[46796.050338] [drm:intel_dump_pipe_config] ips: 0
[46796.050339] [drm:intel_dump_pipe_config] double wide: 0
[46796.050340] [drm:intel_dump_pipe_config] ddi_pll_sel: 0x80000000; dpll_hw_state: wrpll: 0xb0210614 spll: 0x0
[46796.050340] [drm:intel_dump_pipe_config] planes on this crtc
[46796.050342] [drm:intel_dump_pipe_config] STANDARD PLANE:23 plane: 0.0 idx: 0 disabled, scaler_id = 0
[46796.050343] [drm:intel_dump_pipe_config] CURSOR PLANE:25 plane: 0.1 idx: 1 disabled, scaler_id = 0
[46796.050344] [drm:intel_dump_pipe_config] STANDARD PLANE:27 plane: 0.1 idx: 2 disabled, scaler_id = 0
[46796.050347] [drm:intel_plane_atomic_calc_changes] [CRTC:26] has [PLANE:23] with fb 54
[46796.050348] [drm:intel_plane_atomic_calc_changes] [PLANE:23] visible 0 -> 1, off 0, on 1, ms 1
[46796.050350] [drm:intel_plane_atomic_calc_changes] [CRTC:30] has [PLANE:28] with fb -1
[46796.050351] [drm:intel_plane_atomic_calc_changes] [PLANE:28] visible 1 -> 0, off 1, on 0, ms 1
[46796.051667] [drm:intel_find_shared_dpll] CRTC:26 allocated WRPLL 1
[46796.051668] [drm:intel_reference_shared_dpll] using WRPLL 1 for pipe A
[46796.051674] [drm:drm_atomic_commit] commiting ffff880204a11c00
[46796.065719] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-45)@ 46798.253125 -> 46798.253792 [e 0 us, 0 rep]
[46796.065721] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=73, diff=1, hw=73 hw_last=72
[46796.065724] [drm:drm_handle_vblank_events] vblank event on 74, current 74
[46796.065749] [drm:hsw_audio_codec_disable] Disable audio codec on pipe B
[46796.065754] [drm:drm_vblank_off] crtc 1, vblank enabled 1, inmodeset 0
[46796.065755] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 1 : v 0x7 p(0,-42)@ 46798.253164 -> 46798.253786 [e 0 us, 0 rep]
[46796.065756] [drm:drm_update_vblank_count] updating vblank count on crtc 1: current=74, diff=0, hw=73 hw_last=73
[46796.065757] [drm:drm_vblank_off] Sending premature vblank event on disable: wanted 75, current 74
[46796.065757] [drm:drm_vblank_off] Sending premature vblank event on disable: wanted 76, current 74
[46796.065757] [drm:drm_vblank_off] Sending premature vblank event on disable: wanted 77, current 74
[46796.065758] [drm:drm_vblank_off] Sending premature vblank event on disable: wanted 78, current 74
[46796.065758] [drm:drm_vblank_off] Sending premature vblank event on disable: wanted 79, current 74
[46796.065759] [drm:drm_vblank_off] Sending premature vblank event on disable: wanted 80, current 74
[46796.065759] [drm:drm_vblank_off] Sending premature vblank event on disable: wanted 81, current 74
[46796.065759] [drm:drm_vblank_off] Sending premature vblank event on disable: wanted 82, current 74
[46796.065760] [drm:drm_vblank_off] Sending premature vblank event on disable: wanted 83, current 74
[46796.065760] [drm:drm_vblank_off] Sending premature vblank event on disable: wanted 84, current 74
[46796.065761] [drm:drm_vblank_off] Sending premature vblank event on disable: wanted 85, current 74
[46796.065761] [drm:drm_vblank_off] Sending premature vblank event on disable: wanted 86, current 74
[46796.065761] [drm:drm_vblank_off] Sending premature vblank event on disable: wanted 87, current 74
[46796.065762] [drm:drm_vblank_off] Sending premature vblank event on disable: wanted 88, current 74
[46796.065762] [drm:drm_vblank_off] Sending premature vblank event on disable: wanted 89, current 74
[46796.065762] [drm:drm_vblank_off] Sending premature vblank event on disable: wanted 90, current 74
[46796.065763] [drm:drm_vblank_off] Sending premature vblank event on disable: wanted 91, current 74
[46796.065763] [drm:drm_vblank_off] Sending premature vblank event on disable: wanted 92, current 74
[46796.065764] [drm:drm_vblank_off] Sending premature vblank event on disable: wanted 93, current 74
[46796.065764] [drm:drm_vblank_off] Sending premature vblank event on disable: wanted 94, current 74
[46796.065764] [drm:drm_vblank_off] Sending premature vblank event on disable: wanted 95, current 74
[46796.065765] [drm:drm_vblank_off] Sending premature vblank event on disable: wanted 96, current 74
[46796.065765] [drm:drm_vblank_off] Sending premature vblank event on disable: wanted 97, current 74
[46796.065765] [drm:drm_vblank_off] Sending premature vblank event on disable: wanted 98, current 74
[46796.065766] [drm:drm_vblank_off] Sending premature vblank event on disable: wanted 99, current 74
[46796.065766] [drm:drm_vblank_off] Sending premature vblank event on disable: wanted 100, current 74
[46796.065767] [drm:drm_vblank_off] Sending premature vblank event on disable: wanted 101, current 74
[46796.065767] [drm:drm_vblank_off] Sending premature vblank event on disable: wanted 102, current 74
[46796.065767] [drm:drm_vblank_off] Sending premature vblank event on disable: wanted 103, current 74
[46796.065768] [drm:drm_vblank_off] Sending premature vblank event on disable: wanted 104, current 74
[46796.065768] [drm:drm_vblank_off] Sending premature vblank event on disable: wanted 105, current 74
[46796.065769] [drm:drm_vblank_off] Sending premature vblank event on disable: wanted 106, current 74
[46796.065769] [drm:drm_vblank_off] Sending premature vblank event on disable: wanted 107, current 74
[46796.065769] [drm:drm_vblank_off] Sending premature vblank event on disable: wanted 108, current 74
[46796.065770] [drm:drm_vblank_off] Sending premature vblank event on disable: wanted 109, current 74
[46796.065770] [drm:drm_vblank_off] Sending premature vblank event on disable: wanted 110, current 74
[46796.065771] [drm:drm_vblank_off] Sending premature vblank event on disable: wanted 111, current 74
[46796.065771] [drm:drm_vblank_off] Sending premature vblank event on disable: wanted 112, current 74
[46796.065771] [drm:drm_vblank_off] Sending premature vblank event on disable: wanted 113, current 74
[46796.065772] [drm:drm_vblank_off] Sending premature vblank event on disable: wanted 114, current 74
[46796.065772] [drm:drm_vblank_off] Sending premature vblank event on disable: wanted 115, current 74
[46796.065772] [drm:drm_vblank_off] Sending premature vblank event on disable: wanted 116, current 74
[46796.065773] [drm:drm_vblank_off] Sending premature vblank event on disable: wanted 117, current 74
[46796.065773] [drm:drm_vblank_off] Sending premature vblank event on disable: wanted 118, current 74
[46796.065774] [drm:drm_vblank_off] Sending premature vblank event on disable: wanted 119, current 74
[46796.065774] [drm:drm_vblank_off] Sending premature vblank event on disable: wanted 120, current 74
[46796.065774] [drm:drm_vblank_off] Sending premature vblank event on disable: wanted 121, current 74
[46796.065775] [drm:drm_vblank_off] Sending premature vblank event on disable: wanted 122, current 74
[46796.065775] [drm:drm_vblank_off] Sending premature vblank event on disable: wanted 123, current 74
[46796.065775] [drm:drm_vblank_off] Sending premature vblank event on disable: wanted 124, current 74
[46796.065776] [drm:drm_vblank_off] Sending premature vblank event on disable: wanted 125, current 74
[46796.065776] [drm:drm_vblank_off] Sending premature vblank event on disable: wanted 126, current 74
[46796.065777] [drm:drm_vblank_off] Sending premature vblank event on disable: wanted 127, current 74
[46796.065777] [drm:drm_vblank_off] Sending premature vblank event on disable: wanted 128, current 74
[46796.065777] [drm:drm_vblank_off] Sending premature vblank event on disable: wanted 129, current 74
[46796.065778] [drm:drm_vblank_off] Sending premature vblank event on disable: wanted 130, current 74
[46796.065778] [drm:drm_vblank_off] Sending premature vblank event on disable: wanted 131, current 74
[46796.065778] [drm:drm_vblank_off] Sending premature vblank event on disable: wanted 132, current 74
[46796.065779] [drm:intel_disable_pipe] disabling pipe B
[46796.084369] [drm:intel_disable_shared_dpll] disable WRPLL 1 (active 2, on? 1) for crtc 30
[46796.084371] [drm:intel_disable_shared_dpll] disabling WRPLL 1
[46796.084375] [drm:drm_calc_timestamping_constants] crtc 26: hwmode: htotal 2200, vtotal 1125, vdisplay 1080
[46796.084375] [drm:drm_calc_timestamping_constants] crtc 26: clock 148500 kHz framedur 16666666 linedur 14814
[46796.084376] [drm:verify_encoder_state] [ENCODER:33:DAC-33]
[46796.084377] [drm:verify_encoder_state] [ENCODER:34:TMDS-34]
[46796.084378] [drm:intel_connector_verify_state] [CONNECTOR:32:VGA-1]
[46796.084379] [drm:verify_single_dpll_state] WRPLL 1
[46796.084380] [drm:verify_single_dpll_state] WRPLL 2
[46796.084380] [drm:verify_single_dpll_state] SPLL
[46796.084381] [drm:verify_single_dpll_state] LCPLL 810
[46796.084381] [drm:verify_single_dpll_state] LCPLL 1350
[46796.084381] [drm:verify_single_dpll_state] LCPLL 2700
[46796.084382] [drm:intel_enable_shared_dpll] enable WRPLL 1 (active 1, on? 0) for crtc 26
[46796.084383] [drm:intel_enable_shared_dpll] enabling WRPLL 1
[46796.084448] [drm:intel_enable_pipe] enabling pipe A
[46796.084450] [drm:drm_vblank_on] crtc 0, vblank enabled 0, inmodeset 1
[46796.084452] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-45)@ 46798.271861 -> 46798.272528 [e 0 us, 0 rep]
[46796.084454] [drm:intel_audio_codec_enable] ELD on [CONNECTOR:35:HDMI-A-1], [ENCODER:34:TMDS-34]
[46796.084455] [drm:hsw_audio_codec_enable] Enable audio codec on pipe A, 32 bytes ELD
[46796.084458] [drm:audio_config_hdmi_pixel_clock] Configuring HDMI audio for pixel clock 148500 (0x00090000)
[46796.084468] [drm:intel_fbc_alloc_cfb] reserved 16588800 bytes of contiguous stolen space for FBC, threshold: 1
[46796.084468] [drm:intel_fbc_enable] Enabling FBC on pipe A
[46796.084470] [drm:drm_vblank_enable] enabling vblank on crtc 0, ret: 0
[46796.084471] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-45)@ 46798.271880 -> 46798.272547 [e 0 us, 0 rep]
[46796.084472] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72404, diff=0, hw=74888 hw_last=74888
[46796.084473] [drm:vblank_disable_fn] disabling vblank on crtc 0
[46796.084474] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-45)@ 46798.271884 -> 46798.272551 [e 0 us, 0 rep]
[46796.084475] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72404, diff=0, hw=74888 hw_last=74888
[46796.084478] [drm:drm_vblank_enable] enabling vblank on crtc 0, ret: 0
[46796.084479] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-45)@ 46798.271889 -> 46798.272555 [e 0 us, 0 rep]
[46796.084479] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72404, diff=0, hw=74888 hw_last=74888
[46796.101205] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-41)@ 46798.288612 -> 46798.289220 [e 0 us, 0 rep]
[46796.101206] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72404, diff=1, hw=74889 hw_last=74888
[46796.101245] [drm:vblank_disable_fn] disabling vblank on crtc 0
[46796.101261] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x7 p(0,-38)@ 46798.288670 -> 46798.289233 [e 11 us, 0 rep]
[46796.101263] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72405, diff=0, hw=74889 hw_last=74889
[46796.101270] [drm:intel_connector_verify_state] [CONNECTOR:35:HDMI-A-1]
[46796.101275] [drm:verify_crtc_state] [CRTC:26]
[46796.101285] [drm:verify_single_dpll_state] WRPLL 1
[46796.101288] [drm:drm_mode_object_unreference] OBJ ID: 55 (1)
[46796.101291] [drm:verify_crtc_state] [CRTC:30]
[46796.101295] [drm:drm_atomic_state_default_clear] Clearing atomic state ffff880204a11c00
[46796.101297] [drm:drm_mode_object_unreference] OBJ ID: 35 (5)
[46796.101297] [drm:drm_mode_object_unreference] OBJ ID: 35 (4)
[46796.101300] [drm:drm_mode_object_unreference] OBJ ID: 47 (3)
[46796.101301] [drm:drm_atomic_state_free] Freeing atomic state ffff880204a11c00
[46796.101303] [drm:drm_mode_object_reference] OBJ ID: 54 (2)
[46796.101303] [drm:drm_mode_object_unreference] OBJ ID: 47 (2)
[46796.101447] [drm:asle_set_backlight] bclp = 0x800000ff
[46796.101449] [drm:asle_set_backlight] updating opregion backlight 255/255
[46796.160936] [drm:drm_release] open_count = 2
[46796.160956] [drm:drm_release] pid = 17715, device = 0xe200, open_count = 2
[46796.160964] [drm:drm_release] open_count = 1
[46796.160971] [drm:drm_release] pid = 17715, device = 0xe200, open_count = 1
[46796.160984] [drm:drm_mode_object_unreference] OBJ ID: 47 (1)
[46796.163069] [drm:drm_lastclose]
[46796.163082] [drm:drm_atomic_state_init] Allocated atomic state ffff88002e0b3c00
[46796.163087] [drm:drm_mode_object_reference] OBJ ID: 54 (3)
[46796.163092] [drm:drm_atomic_get_plane_state] Added [PLANE:23:plane-0] ffff8802068b8780 state to ffff88002e0b3c00
[46796.163097] [drm:drm_mode_object_reference] OBJ ID: 53 (1)
[46796.163100] [drm:drm_atomic_get_crtc_state] Added [CRTC:26:crtc-0] ffff880215229000 state to ffff88002e0b3c00
[46796.163104] [drm:drm_atomic_get_plane_state] Added [PLANE:25:plane-1] ffff8802068b8180 state to ffff88002e0b3c00
[46796.163107] [drm:drm_atomic_set_crtc_for_plane] Link plane state ffff8802068b8180 to [NOCRTC]
[46796.163110] [drm:drm_atomic_set_fb_for_plane] Set [NOFB] for plane state ffff8802068b8180
[46796.163114] [drm:drm_atomic_get_plane_state] Added [PLANE:27:plane-2] ffff8802068b8540 state to ffff88002e0b3c00
[46796.163117] [drm:drm_atomic_set_crtc_for_plane] Link plane state ffff8802068b8540 to [NOCRTC]
[46796.163119] [drm:drm_atomic_set_fb_for_plane] Set [NOFB] for plane state ffff8802068b8540
[46796.163123] [drm:drm_atomic_get_plane_state] Added [PLANE:28:plane-3] ffff8802068b8480 state to ffff88002e0b3c00
[46796.163126] [drm:drm_atomic_get_plane_state] Added [PLANE:29:plane-4] ffff8802068b8cc0 state to ffff88002e0b3c00
[46796.163129] [drm:drm_atomic_set_crtc_for_plane] Link plane state ffff8802068b8cc0 to [NOCRTC]
[46796.163131] [drm:drm_atomic_set_fb_for_plane] Set [NOFB] for plane state ffff8802068b8cc0
[46796.163135] [drm:drm_atomic_get_plane_state] Added [PLANE:31:plane-5] ffff8802068b80c0 state to ffff88002e0b3c00
[46796.163137] [drm:drm_atomic_set_crtc_for_plane] Link plane state ffff8802068b80c0 to [NOCRTC]
[46796.163140] [drm:drm_atomic_set_fb_for_plane] Set [NOFB] for plane state ffff8802068b80c0
[46796.163143] [drm:drm_mode_object_unreference] OBJ ID: 53 (2)
[46796.163149] [drm:drm_atomic_set_mode_for_crtc] Set [MODE:1920x1080] for CRTC state ffff880215229000
[46796.163153] [drm:drm_atomic_set_crtc_for_plane] Link plane state ffff8802068b8780 to [CRTC:26:crtc-0]
[46796.163156] [drm:drm_mode_object_unreference] OBJ ID: 54 (4)
[46796.163158] [drm:drm_mode_object_reference] OBJ ID: 54 (3)
[46796.163161] [drm:drm_atomic_set_fb_for_plane] Set [FB:54] for plane state ffff8802068b8780
[46796.163164] [drm:drm_atomic_add_affected_connectors] Adding all current connectors for [CRTC:26:crtc-0] to ffff88002e0b3c00
[46796.163169] [drm:drm_mode_object_reference] OBJ ID: 35 (3)
[46796.163172] [drm:drm_mode_object_reference] OBJ ID: 35 (4)
[46796.163175] [drm:drm_atomic_get_connector_state] Added [CONNECTOR:35] ffff88002f0b8ba0 state to ffff88002e0b3c00
[46796.163177] [drm:drm_mode_object_unreference] OBJ ID: 35 (5)
[46796.163180] [drm:drm_atomic_set_crtc_for_connector] Link connector state ffff88002f0b8ba0 to [NOCRTC]
[46796.163183] [drm:drm_mode_object_reference] OBJ ID: 35 (4)
[46796.163186] [drm:drm_atomic_set_crtc_for_connector] Link connector state ffff88002f0b8ba0 to [CRTC:26:crtc-0]
[46796.163191] [drm:drm_atomic_get_crtc_state] Added [CRTC:30:crtc-1] ffff880215229800 state to ffff88002e0b3c00
[46796.163194] [drm:drm_atomic_set_mode_for_crtc] Set [NOMODE] for CRTC state ffff880215229800
[46796.163196] [drm:drm_atomic_set_crtc_for_plane] Link plane state ffff8802068b8480 to [NOCRTC]
[46796.163199] [drm:drm_atomic_set_fb_for_plane] Set [NOFB] for plane state ffff8802068b8480
[46796.163202] [drm:drm_atomic_add_affected_connectors] Adding all current connectors for [CRTC:30:crtc-1] to ffff88002e0b3c00
[46796.163205] [drm:drm_atomic_check_only] checking ffff88002e0b3c00
[46796.163212] [drm:update_connector_routing] Updating routing for [CONNECTOR:35:HDMI-A-1]
[46796.163216] [drm:update_connector_routing] [CONNECTOR:35:HDMI-A-1] keeps [ENCODER:34:TMDS-34], now on [CRTC:26:crtc-0]
[46796.163223] [drm:intel_plane_atomic_calc_changes] [CRTC:26] has [PLANE:23] with fb 54
[46796.163227] [drm:intel_plane_atomic_calc_changes] [PLANE:23] visible 1 -> 1, off 0, on 0, ms 0
[46796.163240] [drm:drm_atomic_commit] commiting ffff88002e0b3c00
[46796.163258] [drm:drm_vblank_enable] enabling vblank on crtc 0, ret: 0
[46796.163266] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x5 p(0,773)@ 46798.350676 -> 46798.339224 [e 0 us, 0 rep]
[46796.163270] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72405, diff=3, hw=74892 hw_last=74889
[46796.163274] [drm:vblank_disable_fn] disabling vblank on crtc 0
[46796.163279] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v 0x5 p(0,774)@ 46798.350691 -> 46798.339224 [e 0 us, 0 rep]
[46796.163283] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=72408, diff=0, hw=74892 hw_last=74892
[46796.163295] [drm:drm_atomic_state_default_clear] Clearing atomic state ffff88002e0b3c00
[46796.163298] [drm:drm_mode_object_unreference] OBJ ID: 35 (5)
[46796.163301] [drm:drm_mode_object_unreference] OBJ ID: 35 (4)
[46796.163303] [drm:drm_mode_object_unreference] OBJ ID: 53 (1)
[46796.163307] [drm:drm_mode_object_unreference] OBJ ID: 54 (4)
[46796.163310] [drm:drm_atomic_state_free] Freeing atomic state ffff88002e0b3c00
[46796.163314] [drm:drm_mode_object_reference] OBJ ID: 54 (3)
[46796.163317] [drm:drm_mode_object_unreference] OBJ ID: 54 (4)
[46796.163322] [drm:drm_lastclose] driver lastclose completed
-------------- next part --------------
A non-text attachment was scrubbed...
Name: signature.asc
Type: application/pgp-signature
Size: 473 bytes
Desc: Digital signature
URL: <https://lists.freedesktop.org/archives/intel-gfx/attachments/20160510/112e6f9c/attachment-0001.sig>
More information about the Intel-gfx
mailing list