# ./kms_flip --run-subtest basic-flip-vs-wf_vblank
IGT-Version: 1.14-NOT-GIT (x86_64) (Linux: 4.6.0-rc7-nightly+ x86_64)
Using monotonic timestamps
Beginning basic-flip-vs-wf_vblank on pipe A, connector eDP-1
  3200x1800 60 3200 3248 3280 3316 1800 1802 1807 1816 0xa 0x48 361310
..........(kms_flip:1356) CRITICAL: Test assertion failure function check_final_state, file kms_flip.c:1192:
(kms_flip:1356) CRITICAL: Failed assertion: count >= expected * 99/100 && count <= expected * 101/100
(kms_flip:1356) 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+0x508]
  #5 [__libc_start_main+0xf0]
  #6 [_start+0x29]
  #7 [<unknown>+0x29]
Subtest basic-flip-vs-wf_vblank failed.
**** DEBUG ****
(kms_flip:1356) DEBUG: Test requirement passed: (flags & TEST_HANG) == 0 || !is_wedged(drm_fd)
(kms_flip:1356) DEBUG: Test requirement passed: modes
(kms_flip:1356) INFO: Beginning basic-flip-vs-wf_vblank on pipe A, connector eDP-1
(kms_flip:1356) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=3200, height=1800, format=0x34325258, tiling=0x0, size=0)
(kms_flip:1356) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=2, pitch=12800)
(kms_flip:1356) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=3200, height=1800, format=0x34325258, tiling=0x0, size=0)
(kms_flip:1356) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=3, pitch=12800)
(kms_flip:1356) igt-kms-INFO:   3200x1800 60 3200 3248 3280 3316 1800 1802 1807 1816 0xa 0x48 361310
(kms_flip:1356) DEBUG: name = flip
last_ts = 0.0 usec
last_received_ts = 0.0 usec
last_seq = 0
current_ts = 60.743756 usec
current_received_ts = 60.743867 usec
current_seq = 3437
count = 0
seq_step = 1
(kms_flip:1356) DEBUG: name = vblank
last_ts = 0.0 usec
last_received_ts = 0.0 usec
last_seq = 0
current_ts = 60.893757 usec
current_received_ts = 60.893849 usec
current_seq = 3446
count = 0
seq_step = 10
(kms_flip:1356) DEBUG: name = flip
last_ts = 60.743756 usec
last_received_ts = 60.743867 usec
last_seq = 3437
current_ts = 60.910426 usec
current_received_ts = 60.910500 usec
current_seq = 3447
count = 1
seq_step = 1
(kms_flip:1356) DEBUG: name = vblank
last_ts = 60.893757 usec
last_received_ts = 60.893849 usec
last_seq = 3446
current_ts = 61.60422 usec
current_received_ts = 61.60513 usec
current_seq = 3456
count = 1
seq_step = 10
(kms_flip:1356) DEBUG: name = flip
last_ts = 60.910426 usec
last_received_ts = 60.910500 usec
last_seq = 3447
current_ts = 61.77090 usec
current_received_ts = 61.77200 usec
current_seq = 3457
count = 2
seq_step = 1
(kms_flip:1356) DEBUG: name = vblank
last_ts = 61.60422 usec
last_received_ts = 61.60513 usec
last_seq = 3456
current_ts = 61.227085 usec
current_received_ts = 61.227140 usec
current_seq = 3466
count = 2
seq_step = 10
(kms_flip:1356) DEBUG: name = flip
last_ts = 61.77090 usec
last_received_ts = 61.77200 usec
last_seq = 3457
current_ts = 61.243754 usec
current_received_ts = 61.243863 usec
current_seq = 3467
count = 3
seq_step = 1
(kms_flip:1356) DEBUG: name = vblank
last_ts = 61.227085 usec
last_received_ts = 61.227140 usec
last_seq = 3466
current_ts = 61.393760 usec
current_received_ts = 61.393852 usec
current_seq = 3476
count = 3
seq_step = 10
(kms_flip:1356) DEBUG: name = flip
last_ts = 61.243754 usec
last_received_ts = 61.243863 usec
last_seq = 3467
current_ts = 61.410421 usec
current_received_ts = 61.410531 usec
current_seq = 3477
count = 4
seq_step = 1
(kms_flip:1356) DEBUG: name = vblank
last_ts = 61.393760 usec
last_received_ts = 61.393852 usec
last_seq = 3476
current_ts = 61.560422 usec
current_received_ts = 61.560513 usec
current_seq = 3486
count = 4
seq_step = 10
(kms_flip:1356) DEBUG: name = flip
last_ts = 61.410421 usec
last_received_ts = 61.410531 usec
last_seq = 3477
current_ts = 61.577088 usec
current_received_ts = 61.577199 usec
current_seq = 3487
count = 5
seq_step = 1
(kms_flip:1356) DEBUG: name = vblank
last_ts = 61.560422 usec
last_received_ts = 61.560513 usec
last_seq = 3486
current_ts = 61.727091 usec
current_received_ts = 61.727183 usec
current_seq = 3496
count = 5
seq_step = 10
(kms_flip:1356) DEBUG: name = flip
last_ts = 61.577088 usec
last_received_ts = 61.577199 usec
last_seq = 3487
current_ts = 61.743756 usec
current_received_ts = 61.743866 usec
current_seq = 3497
count = 6
seq_step = 1
(kms_flip:1356) DEBUG: name = vblank
last_ts = 61.727091 usec
last_received_ts = 61.727183 usec
last_seq = 3496
current_ts = 61.893756 usec
current_received_ts = 61.893848 usec
current_seq = 3506
count = 6
seq_step = 10
(kms_flip:1356) DEBUG: name = flip
last_ts = 61.743756 usec
last_received_ts = 61.743866 usec
last_seq = 3497
current_ts = 61.910425 usec
current_received_ts = 61.910536 usec
current_seq = 3507
count = 7
seq_step = 1
(kms_flip:1356) DEBUG: name = vblank
last_ts = 61.893756 usec
last_received_ts = 61.893848 usec
last_seq = 3506
current_ts = 62.60425 usec
current_received_ts = 62.60516 usec
current_seq = 3516
count = 7
seq_step = 10
(kms_flip:1356) DEBUG: name = flip
last_ts = 61.910425 usec
last_received_ts = 61.910536 usec
last_seq = 3507
current_ts = 62.77089 usec
current_received_ts = 62.77199 usec
current_seq = 3517
count = 8
seq_step = 1
(kms_flip:1356) DEBUG: name = vblank
last_ts = 62.60425 usec
last_received_ts = 62.60516 usec
last_seq = 3516
current_ts = 62.227091 usec
current_received_ts = 62.227182 usec
current_seq = 3526
count = 8
seq_step = 10
(kms_flip:1356) DEBUG: name = flip
last_ts = 62.77089 usec
last_received_ts = 62.77199 usec
last_seq = 3517
current_ts = 62.243756 usec
current_received_ts = 62.243866 usec
current_seq = 3527
count = 9
seq_step = 1
(kms_flip:1356) DEBUG: name = vblank
last_ts = 62.227091 usec
last_received_ts = 62.227182 usec
last_seq = 3526
current_ts = 62.393757 usec
current_received_ts = 62.393848 usec
current_seq = 3536
count = 9
seq_step = 10
(kms_flip:1356) CRITICAL: Test assertion failure function check_final_state, file kms_flip.c:1192:
(kms_flip:1356) CRITICAL: Failed assertion: count >= expected * 99/100 && count <= expected * 101/100
(kms_flip:1356) CRITICAL: dropped frames, expected 99, counted 100, encoder type 2
****  END  ****
Subtest basic-flip-vs-wf_vblank: FAIL (3,808s)