Starting subtest: flip-vs-expired-vblank-interruptible
Starting dynamic subtest: A-eDP1
(kms_flip:5479) CRITICAL: Test assertion failure function run_test_step, file ...
(kms_flip:5479) CRITICAL: Failed assertion: end - start < 500
(kms_flip:5479) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest A-eDP1 failed.
**** DEBUG ****
(kms_flip:5479) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=2880, height=18...
(kms_flip:5479) drmtest-DEBUG: Test requirement passed: is_intel_device(fd)
(kms_flip:5479) drmtest-DEBUG: Test requirement passed: is_intel_device(fd)
(kms_flip:5479) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=1, pitch=11520)
(kms_flip:5479) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modi...
(kms_flip:5479) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=2880, height=18...
(kms_flip:5479) drmtest-DEBUG: Test requirement passed: is_intel_device(fd)
(kms_flip:5479) drmtest-DEBUG: Test requirement passed: is_intel_device(fd)
(kms_flip:5479) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=2, pitch=11520)
(kms_flip:5479) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modi...
...

Starting subtest: flip-vs-expired-vblank-interruptible
Starting dynamic subtest: A-eDP1
Dynamic subtest A-eDP1: SUCCESS (11.169s)
Starting dynamic subtest: B-eDP1
Dynamic subtest B-eDP1: SUCCESS (11.239s)
Starting dynamic subtest: C-eDP1
(kms_flip:5740) CRITICAL: Test assertion failure function run_test_step, file ...
(kms_flip:5740) CRITICAL: Failed assertion: end - start < 500
(kms_flip:5740) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest C-eDP1 failed.
**** DEBUG ****
(kms_flip:5740) DEBUG: Vblank took 50us
(kms_flip:5740) DEBUG: name = flip
last_ts = 326.702820
last_received_ts = 326.702026
last_seq = 401
current_ts = 326.719482
...

Starting subtest: flip-vs-expired-vblank-interruptible
Starting dynamic subtest: A-eDP1
Dynamic subtest A-eDP1: SUCCESS (11.178s)
Starting dynamic subtest: B-eDP1
Dynamic subtest B-eDP1: SUCCESS (11.202s)
Starting dynamic subtest: C-eDP1
(kms_flip:3568) CRITICAL: Test assertion failure function run_test_step, file ...
(kms_flip:3568) CRITICAL: Failed assertion: end - start < 500
(kms_flip:3568) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest C-eDP1 failed.
**** DEBUG ****
(kms_flip:3568) DEBUG: Vblank took 60us
(kms_flip:3568) DEBUG: name = flip
last_ts = 188.702194
last_received_ts = 188.701462
last_seq = 80
current_ts = 188.718857
...

Starting subtest: flip-vs-expired-vblank-interruptible
Starting dynamic subtest: A-eDP1
(kms_flip:3555) CRITICAL: Test assertion failure function run_test_step, file ...
(kms_flip:3555) CRITICAL: Failed assertion: end - start < 500
(kms_flip:3555) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest A-eDP1 failed.
**** DEBUG ****
(kms_flip:3555) DEBUG: Vblank took 49us
(kms_flip:3555) DEBUG: name = flip
last_ts = 175.246445
last_received_ts = 175.245651
last_seq = 542
current_ts = 175.263107
current_received_ts = 175.262314
current_seq = 543
count = 301
seq_step = 1
...

Starting subtest: flip-vs-expired-vblank-interruptible
Starting dynamic subtest: A-eDP1
(kms_flip:7528) CRITICAL: Test assertion failure function run_test_step, file ...
(kms_flip:7528) CRITICAL: Failed assertion: end - start < 500
(kms_flip:7528) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest A-eDP1 failed.
**** DEBUG ****
(kms_flip:7528) DEBUG: Vblank took 43us
(kms_flip:7528) DEBUG: name = flip
last_ts = 470.624207
last_received_ts = 470.623413
last_seq = 488
current_ts = 470.640869
current_received_ts = 470.640045
current_seq = 489
count = 417
seq_step = 1
...

Starting subtest: flip-vs-expired-vblank-interruptible
Starting dynamic subtest: A-eDP1
Dynamic subtest A-eDP1: SUCCESS (11.189s)
Starting dynamic subtest: B-eDP1
(kms_flip:3771) CRITICAL: Test assertion failure function run_test_step, file ...
(kms_flip:3771) CRITICAL: Failed assertion: end - start < 500
(kms_flip:3771) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest B-eDP1 failed.
**** DEBUG ****
(kms_flip:3771) DEBUG: Vblank took 54us
(kms_flip:3771) DEBUG: name = flip
last_ts = 176.069794
last_received_ts = 176.069031
last_seq = 854
current_ts = 176.086456
current_received_ts = 176.085678
current_seq = 855
...