Starting subtest: flip-vs-expired-vblank
Starting dynamic subtest: A-eDP1
[373.742830] (kms_flip:10136) CRITICAL: Test assertion failure function run_te...
[373.742909] (kms_flip:10136) CRITICAL: Failed assertion: end - start < 500
Dynamic subtest A-eDP1 failed.
**** DEBUG ****
[370.679905] (kms_flip:10136) intel_allocator_simple-DEBUG: <ial: 0x5ea5dbd1c5...
[370.679907] (kms_flip:10136) intel_allocator_simple-DEBUG: <ial: 0x5ea5dbd1c5...
[370.683452] (kms_flip:10136) intel_allocator_simple-DEBUG: Using simple alloc...
[370.686915] (kms_flip:10136) intel_allocator_simple-DEBUG: <ial: 0x5ea5dbd1f7...
[370.686917] (kms_flip:10136) intel_allocator_simple-DEBUG: <ial: 0x5ea5dbd1f7...
[370.700106] (kms_flip:10136) intel_allocator_simple-DEBUG: Using simple alloc...
[370.703086] (kms_flip:10136) intel_allocator_simple-DEBUG: <ial: 0x5ea5dbd1f7...
[370.703088] (kms_flip:10136) intel_allocator_simple-DEBUG: <ial: 0x5ea5dbd1f7...
[370.704440] (kms_flip:10136) igt_kms-INFO:   2880x1800: 60 347710 2880 2928 2...
[370.724551] (kms_flip:10136) DEBUG: No stale events found
[371.797517] (kms_flip:10136) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0
...

Starting subtest: flip-vs-expired-vblank
Starting dynamic subtest: A-eDP1
[374.328833] (kms_flip:9958) CRITICAL: Test assertion failure function run_tes...
[374.328922] (kms_flip:9958) CRITICAL: Failed assertion: end - start < 500
Dynamic subtest A-eDP1 failed.
**** DEBUG ****
[372.311670] (kms_flip:9958) DEBUG: name = flip
last_ts = 373.845764
last_received_ts = 373.845154
last_seq = 764
current_ts = 373.862427
current_received_ts = 373.861877
current_seq = 765
count = 109
seq_step = 1
[372.311739] (kms_flip:9958) DEBUG: Vblank took 61us
[372.328310] (kms_flip:9958) DEBUG: name = flip
...

Starting subtest: flip-vs-expired-vblank
Starting dynamic subtest: A-eDP1
[368.722577] (kms_flip:8441) CRITICAL: Test assertion failure function run_tes...
[368.722671] (kms_flip:8441) CRITICAL: Failed assertion: end - start < 500
Dynamic subtest A-eDP1 failed.
**** DEBUG ****
[366.704977] (kms_flip:8441) DEBUG: name = flip
last_ts = 368.212555
last_received_ts = 368.211975
last_seq = 5669
current_ts = 368.229218
current_received_ts = 368.228668
current_seq = 5670
count = 129
seq_step = 1
[366.705115] (kms_flip:8441) DEBUG: Vblank took 131us
[366.721612] (kms_flip:8441) DEBUG: name = flip
...

Starting subtest: flip-vs-expired-vblank
Starting dynamic subtest: A-eDP1
[246.344421] (kms_flip:5567) CRITICAL: Test assertion failure function run_tes...
[246.344521] (kms_flip:5567) CRITICAL: Failed assertion: end - start < 500
Dynamic subtest A-eDP1 failed.
**** DEBUG ****
[244.225691] (kms_flip:5567) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=28...
[244.225706] (kms_flip:5567) drmtest-DEBUG: Test requirement passed: is_intel_...
[244.225723] (kms_flip:5567) drmtest-DEBUG: Test requirement passed: is_intel_...
[244.226309] (kms_flip:5567) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=1...
[244.226314] (kms_flip:5567) ioctl_wrappers-DEBUG: Test requirement passed: ig...
[244.226359] (kms_flip:5567) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=28...
[244.226367] (kms_flip:5567) drmtest-DEBUG: Test requirement passed: is_intel_...
[244.226383] (kms_flip:5567) drmtest-DEBUG: Test requirement passed: is_intel_...
[244.226773] (kms_flip:5567) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=2...
[244.226775] (kms_flip:5567) ioctl_wrappers-DEBUG: Test requirement passed: ig...
[244.229204] (kms_flip:5567) intel_allocator_simple-DEBUG: Using simple alloca...
...

Starting subtest: flip-vs-expired-vblank
Starting dynamic subtest: A-eDP1
[542.109544] (kms_flip:9696) CRITICAL: Test assertion failure function run_tes...
[542.109678] (kms_flip:9696) CRITICAL: Failed assertion: end - start < 500
Dynamic subtest A-eDP1 failed.
**** DEBUG ****
[540.090711] (kms_flip:9696) DEBUG: name = flip
last_ts = 541.723755
last_received_ts = 541.723083
last_seq = 4249
current_ts = 541.740417
current_received_ts = 541.739807
current_seq = 4250
count = 238
seq_step = 1
[540.090785] (kms_flip:9696) DEBUG: Vblank took 68us
[540.107438] (kms_flip:9696) DEBUG: name = flip
...

Starting subtest: flip-vs-expired-vblank
Starting dynamic subtest: A-eDP1
[546.957544] (kms_flip:9705) CRITICAL: Test assertion failure function run_tes...
[546.957612] (kms_flip:9705) CRITICAL: Failed assertion: end - start < 500
Dynamic subtest A-eDP1 failed.
**** DEBUG ****
[544.940328] (kms_flip:9705) DEBUG: name = flip
last_ts = 545.668518
last_received_ts = 545.667969
last_seq = 4219
current_ts = 545.685242
current_received_ts = 545.684509
current_seq = 4220
count = 201
seq_step = 1
[544.940447] (kms_flip:9705) DEBUG: Vblank took 110us
[544.957018] (kms_flip:9705) DEBUG: name = flip
...