# ./kms_flip --run-subtest flip-vs-wf_vblank-interruptible
IGT-Version: 1.14-NOT-GIT (x86_64) (Linux: 4.6.0-rc7-nightly+ x86_64)
Using monotonic timestamps
Beginning flip-vs-wf_vblank-interruptible on pipe A, connector eDP-1
  3200x1800 60 3200 3248 3280 3316 1800 1802 1807 1816 0xa 0x48 361310
..........(kms_flip:1304) CRITICAL: Test assertion failure function check_final_state, file kms_flip.c:1192:
(kms_flip:1304) CRITICAL: Failed assertion: count >= expected * 99/100 && count <= expected * 101/100
(kms_flip:1304) CRITICAL: Last errno: 4, Interrupted system call
(kms_flip:1304) CRITICAL: dropped frames, expected 99, counted 100, encoder type 2
Stack trace:
  #0 [__igt_fail_assert+0xf1]
  #1 [check_final_state.isra.6+0xf7]
  #2 [run_test_on_crtc_set.constprop.12+0x2099]
  #3 [run_test+0x25a]
  #4 [main+0x540]
  #5 [__libc_start_main+0xf0]
  #6 [_start+0x29]
  #7 [<unknown>+0x29]
Subtest flip-vs-wf_vblank-interruptible failed.
**** DEBUG ****
(kms_flip:1304) DEBUG: Test requirement passed: (flags & TEST_HANG) == 0 || !is_wedged(drm_fd)
(kms_flip:1304) DEBUG: Test requirement passed: modes
(kms_flip:1304) INFO: Beginning flip-vs-wf_vblank-interruptible on pipe A, connector eDP-1
(kms_flip:1304) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=3200, height=1800, format=0x34325258, tiling=0x0, size=0)
(kms_flip:1304) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=2, pitch=12800)
(kms_flip:1304) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=3200, height=1800, format=0x34325258, tiling=0x0, size=0)
(kms_flip:1304) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=3, pitch=12800)
(kms_flip:1304) igt-kms-INFO:   3200x1800 60 3200 3248 3280 3316 1800 1802 1807 1816 0xa 0x48 361310
(kms_flip:1304) DEBUG: name = flip
last_ts = 0.0 usec
last_received_ts = 0.0 usec
last_seq = 0
current_ts = 45.535287 usec
current_received_ts = 45.535322 usec
current_seq = 2518
count = 0
seq_step = 1
(kms_flip:1304) DEBUG: name = vblank
last_ts = 0.0 usec
last_received_ts = 0.0 usec
last_seq = 0
current_ts = 45.685291 usec
current_received_ts = 45.685309 usec
current_seq = 2527
count = 0
seq_step = 10
(kms_flip:1304) DEBUG: name = flip
last_ts = 45.535287 usec
last_received_ts = 45.535322 usec
last_seq = 2518
current_ts = 45.701952 usec
current_received_ts = 45.701970 usec
current_seq = 2528
count = 1
seq_step = 1
(kms_flip:1304) DEBUG: name = vblank
last_ts = 45.685291 usec
last_received_ts = 45.685309 usec
last_seq = 2527
current_ts = 45.851954 usec
current_received_ts = 45.851963 usec
current_seq = 2537
count = 1
seq_step = 10
(kms_flip:1304) DEBUG: name = flip
last_ts = 45.701952 usec
last_received_ts = 45.701970 usec
last_seq = 2528
current_ts = 45.868619 usec
current_received_ts = 45.868679 usec
current_seq = 2538
count = 2
seq_step = 1
(kms_flip:1304) DEBUG: name = vblank
last_ts = 45.851954 usec
last_received_ts = 45.851963 usec
last_seq = 2537
current_ts = 46.18626 usec
current_received_ts = 46.18632 usec
current_seq = 2547
count = 2
seq_step = 10
(kms_flip:1304) DEBUG: name = flip
last_ts = 45.868619 usec
last_received_ts = 45.868679 usec
last_seq = 2538
current_ts = 46.35289 usec
current_received_ts = 46.35326 usec
current_seq = 2548
count = 3
seq_step = 1
(kms_flip:1304) DEBUG: name = vblank
last_ts = 46.18626 usec
last_received_ts = 46.18632 usec
last_seq = 2547
current_ts = 46.185285 usec
current_received_ts = 46.185194 usec
current_seq = 2557
count = 3
seq_step = 10
(kms_flip:1304) DEBUG: name = flip
last_ts = 46.35289 usec
last_received_ts = 46.35326 usec
last_seq = 2548
current_ts = 46.201958 usec
current_received_ts = 46.201884 usec
current_seq = 2558
count = 4
seq_step = 1
(kms_flip:1304) DEBUG: name = vblank
last_ts = 46.185285 usec
last_received_ts = 46.185194 usec
last_seq = 2557
current_ts = 46.351956 usec
current_received_ts = 46.352037 usec
current_seq = 2567
count = 4
seq_step = 10
(kms_flip:1304) DEBUG: name = flip
last_ts = 46.201958 usec
last_received_ts = 46.201884 usec
last_seq = 2558
current_ts = 46.368626 usec
current_received_ts = 46.368637 usec
current_seq = 2568
count = 5
seq_step = 1
(kms_flip:1304) DEBUG: name = vblank
last_ts = 46.351956 usec
last_received_ts = 46.352037 usec
last_seq = 2567
current_ts = 46.518618 usec
current_received_ts = 46.518636 usec
current_seq = 2577
count = 5
seq_step = 10
(kms_flip:1304) DEBUG: name = flip
last_ts = 46.368626 usec
last_received_ts = 46.368637 usec
last_seq = 2568
current_ts = 46.535288 usec
current_received_ts = 46.535280 usec
current_seq = 2578
count = 6
seq_step = 1
(kms_flip:1304) DEBUG: name = vblank
last_ts = 46.518618 usec
last_received_ts = 46.518636 usec
last_seq = 2577
current_ts = 46.685286 usec
current_received_ts = 46.685285 usec
current_seq = 2587
count = 6
seq_step = 10
(kms_flip:1304) DEBUG: name = flip
last_ts = 46.535288 usec
last_received_ts = 46.535280 usec
last_seq = 2578
current_ts = 46.701958 usec
current_received_ts = 46.701978 usec
current_seq = 2588
count = 7
seq_step = 1
(kms_flip:1304) DEBUG: name = vblank
last_ts = 46.685286 usec
last_received_ts = 46.685285 usec
last_seq = 2587
current_ts = 46.851959 usec
current_received_ts = 46.851838 usec
current_seq = 2597
count = 7
seq_step = 10
(kms_flip:1304) DEBUG: name = flip
last_ts = 46.701958 usec
last_received_ts = 46.701978 usec
last_seq = 2588
current_ts = 46.868621 usec
current_received_ts = 46.868633 usec
current_seq = 2598
count = 8
seq_step = 1
(kms_flip:1304) DEBUG: name = vblank
last_ts = 46.851959 usec
last_received_ts = 46.851838 usec
last_seq = 2597
current_ts = 47.18623 usec
current_received_ts = 47.18632 usec
current_seq = 2607
count = 8
seq_step = 10
(kms_flip:1304) DEBUG: name = flip
last_ts = 46.868621 usec
last_received_ts = 46.868633 usec
last_seq = 2598
current_ts = 47.35288 usec
current_received_ts = 47.35281 usec
current_seq = 2608
count = 9
seq_step = 1
(kms_flip:1304) DEBUG: name = vblank
last_ts = 47.18623 usec
last_received_ts = 47.18632 usec
last_seq = 2607
current_ts = 47.185293 usec
current_received_ts = 47.185172 usec
current_seq = 2617
count = 9
seq_step = 10
(kms_flip:1304) CRITICAL: Test assertion failure function check_final_state, file kms_flip.c:1192:
(kms_flip:1304) CRITICAL: Failed assertion: count >= expected * 99/100 && count <= expected * 101/100
(kms_flip:1304) CRITICAL: Last errno: 4, Interrupted system call
(kms_flip:1304) CRITICAL: dropped frames, expected 99, counted 100, encoder type 2
****  END  ****
Subtest flip-vs-wf_vblank-interruptible: FAIL (2,850s)