Starting subtest: flip-vs-expired-vblank
Starting dynamic subtest: A-eDP1
(kms_flip:3276) CRITICAL: Test assertion failure function run_test_step, file ...
(kms_flip:3276) CRITICAL: Failed assertion: end - start < 500
Dynamic subtest A-eDP1 failed.
**** DEBUG ****
(kms_flip:3276) DEBUG: name = flip
last_ts = 106.924156
last_received_ts = 106.923637
last_seq = 1630
current_ts = 106.940826
current_received_ts = 106.940285
current_seq = 1631
count = 247
seq_step = 1
(kms_flip:3276) DEBUG: Vblank took 61us
(kms_flip:3276) DEBUG: name = flip
...

Starting subtest: flip-vs-expired-vblank
Starting dynamic subtest: A-eDP1
(kms_flip:3257) CRITICAL: Test assertion failure function run_test_step, file ...
(kms_flip:3257) CRITICAL: Failed assertion: end - start < 500
Dynamic subtest A-eDP1 failed.
**** DEBUG ****
(kms_flip:3257) DEBUG: name = flip
last_ts = 101.748734
last_received_ts = 101.747986
last_seq = 1463
current_ts = 101.765404
current_received_ts = 101.764893
current_seq = 1464
count = 155
seq_step = 1
(kms_flip:3257) DEBUG: Vblank took 33us
(kms_flip:3257) DEBUG: name = flip
...

Starting subtest: flip-vs-expired-vblank
Starting dynamic subtest: A-eDP1
(kms_flip:3308) CRITICAL: Test assertion failure function run_test_step, file ...
(kms_flip:3308) CRITICAL: Failed assertion: end - start < 500
Dynamic subtest A-eDP1 failed.
**** DEBUG ****
(kms_flip:3308) DEBUG: name = flip
last_ts = 107.803665
last_received_ts = 107.802872
last_seq = 1734
current_ts = 107.820328
current_received_ts = 107.819527
current_seq = 1735
count = 343
seq_step = 1
(kms_flip:3308) DEBUG: Vblank took 59us
(kms_flip:3308) DEBUG: name = flip
...

Starting subtest: flip-vs-expired-vblank
Starting dynamic subtest: A-eDP1
Dynamic subtest A-eDP1: SUCCESS (11.310s)
Starting dynamic subtest: B-eDP1
(kms_flip:3270) CRITICAL: Test assertion failure function run_test_step, file ...
(kms_flip:3270) CRITICAL: Failed assertion: end - start < 500
Dynamic subtest B-eDP1 failed.
**** DEBUG ****
(kms_flip:3270) DEBUG: name = flip
last_ts = 111.212410
last_received_ts = 111.211876
last_seq = 111
current_ts = 111.229080
current_received_ts = 111.228531
current_seq = 112
count = 101
seq_step = 1
...

Starting subtest: flip-vs-expired-vblank
Starting dynamic subtest: A-eDP1
Dynamic subtest A-eDP1: SUCCESS (11.200s)
Starting dynamic subtest: B-eDP1
(kms_flip:3259) CRITICAL: Test assertion failure function run_test_step, file ...
(kms_flip:3259) CRITICAL: Failed assertion: end - start < 500
Dynamic subtest B-eDP1 failed.
**** DEBUG ****
(kms_flip:3259) DEBUG: name = flip
last_ts = 113.237282
last_received_ts = 113.236725
last_seq = 119
current_ts = 113.253952
current_received_ts = 113.253387
current_seq = 120
count = 112
seq_step = 1
...

Starting subtest: flip-vs-expired-vblank
Starting dynamic subtest: A-eDP1
Dynamic subtest A-eDP1: SUCCESS (11.262s)
Starting dynamic subtest: B-eDP1
(kms_flip:3278) CRITICAL: Test assertion failure function run_test_step, file ...
(kms_flip:3278) CRITICAL: Failed assertion: end - start < 500
Dynamic subtest B-eDP1 failed.
**** DEBUG ****
(kms_flip:3278) DEBUG: name = flip
last_ts = 116.557648
last_received_ts = 116.557091
last_seq = 90
current_ts = 116.574310
current_received_ts = 116.573730
current_seq = 91
count = 80
seq_step = 1
...

Starting subtest: flip-vs-expired-vblank
Starting dynamic subtest: A-eDP1
Dynamic subtest A-eDP1: SUCCESS (11.285s)
Starting dynamic subtest: B-eDP1
Dynamic subtest B-eDP1: SUCCESS (11.232s)
Starting dynamic subtest: C-eDP1
(kms_flip:2612) CRITICAL: Test assertion failure function run_test_step, file ...
(kms_flip:2612) CRITICAL: Failed assertion: end - start < 500
Dynamic subtest C-eDP1 failed.
**** DEBUG ****
(kms_flip:2612) DEBUG: name = flip
last_ts = 108.133240
last_received_ts = 108.132744
last_seq = 347
current_ts = 108.149902
current_received_ts = 108.149399
current_seq = 348
...