Starting subtest: flip-vs-expired-vblank-interruptible
Starting dynamic subtest: A-eDP1
Dynamic subtest A-eDP1: SUCCESS (8.701s)
Starting dynamic subtest: B-eDP1
Dynamic subtest B-eDP1: SUCCESS (8.623s)
Starting dynamic subtest: C-eDP1
Dynamic subtest C-eDP1: SUCCESS (8.616s)
Starting dynamic subtest: D-eDP1
(kms_flip:2502) CRITICAL: Test assertion failure function run_test_step, file ...
(kms_flip:2502) CRITICAL: Failed assertion: end - start < 500
(kms_flip:2502) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest D-eDP1 failed.
**** DEBUG ****
(kms_flip:2502) DEBUG: Vblank took 6us
(kms_flip:2502) DEBUG: name = flip
last_ts = 271.511780
last_received_ts = 271.511475
...

Starting subtest: flip-vs-expired-vblank-interruptible
Starting dynamic subtest: A-HDMI-A3
Dynamic subtest A-HDMI-A3: SUCCESS (7.719s)
Starting dynamic subtest: B-HDMI-A3
Dynamic subtest B-HDMI-A3: SUCCESS (7.661s)
Starting dynamic subtest: C-HDMI-A3
Dynamic subtest C-HDMI-A3: SUCCESS (7.644s)
Starting dynamic subtest: D-HDMI-A3
(kms_flip:2351) CRITICAL: Test assertion failure function run_test_step, file ...
(kms_flip:2351) CRITICAL: Failed assertion: end - start < 500
(kms_flip:2351) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest D-HDMI-A3 failed.
**** DEBUG ****
(kms_flip:2351) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2351) igt_kms-INFO:   1920x1080: 60 148500 1920 2008 2052 2200 1080 ...
(kms_flip:2351) DEBUG: No stale events found
(kms_flip:2351) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0
...

Starting subtest: flip-vs-expired-vblank-interruptible
Starting dynamic subtest: A-HDMI-A3
Dynamic subtest A-HDMI-A3: SUCCESS (7.777s)
Starting dynamic subtest: B-HDMI-A3
(kms_flip:2133) CRITICAL: Test assertion failure function run_test_step, file ...
(kms_flip:2133) CRITICAL: Failed assertion: end - start < 500
(kms_flip:2133) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest B-HDMI-A3 failed.
**** DEBUG ****
(kms_flip:2133) DEBUG: Vblank took 48us
(kms_flip:2133) DEBUG: name = flip
last_ts = 302.110565
last_received_ts = 302.110168
last_seq = 174
current_ts = 302.127228
current_received_ts = 302.126862
current_seq = 175
...

Starting subtest: flip-vs-expired-vblank-interruptible
Starting dynamic subtest: A-HDMI-A3
(kms_flip:2053) CRITICAL: Test assertion failure function run_test_step, file ...
(kms_flip:2053) CRITICAL: Failed assertion: end - start < 500
(kms_flip:2053) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest A-HDMI-A3 failed.
**** DEBUG ****
(kms_flip:2053) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=10...
(kms_flip:2053) drmtest-DEBUG: Test requirement passed: is_intel_device(fd)
(kms_flip:2053) drmtest-DEBUG: Test requirement passed: is_intel_device(fd)
(kms_flip:2053) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=2, pitch=7680)
(kms_flip:2053) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modi...
(kms_flip:2053) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2053) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2053) igt_fb-DEBUG: Test requirement passed: gem_has_contexts(fb->fd)
(kms_flip:2053) intel_allocator_reloc-DEBUG: Using reloc allocator
(kms_flip:2053) intel_blt-DEBUG: Test requirement passed: cmds_info
...

Starting subtest: flip-vs-expired-vblank-interruptible
Starting dynamic subtest: A-HDMI-A3
Dynamic subtest A-HDMI-A3: SUCCESS (7.733s)
Starting dynamic subtest: B-HDMI-A3
Dynamic subtest B-HDMI-A3: SUCCESS (7.683s)
Starting dynamic subtest: C-HDMI-A3
Dynamic subtest C-HDMI-A3: SUCCESS (7.666s)
Starting dynamic subtest: D-HDMI-A3
(kms_flip:2136) CRITICAL: Test assertion failure function run_test_step, file ...
(kms_flip:2136) CRITICAL: Failed assertion: end - start < 500
(kms_flip:2136) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest D-HDMI-A3 failed.
**** DEBUG ****
(kms_flip:2136) DEBUG: Vblank took 57us
(kms_flip:2136) DEBUG: name = flip
last_ts = 465.942993
last_received_ts = 465.942627
...

Starting subtest: flip-vs-expired-vblank-interruptible
Starting dynamic subtest: A-HDMI-A2
(kms_flip:2363) CRITICAL: Test assertion failure function run_test_step, file ...
(kms_flip:2363) CRITICAL: Failed assertion: end - start < 500
(kms_flip:2363) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest A-HDMI-A2 failed.
**** DEBUG ****
(kms_flip:2363) DEBUG: Vblank took 61us
(kms_flip:2363) DEBUG: name = flip
last_ts = 156.616547
last_received_ts = 156.615921
last_seq = 4126
current_ts = 156.635529
current_received_ts = 156.635056
current_seq = 4127
count = 182
seq_step = 1
...

Starting subtest: flip-vs-expired-vblank-interruptible
Starting dynamic subtest: A-HDMI-A2
Dynamic subtest A-HDMI-A2: SUCCESS (10.204s)
Starting dynamic subtest: B-HDMI-A2
(kms_flip:2235) CRITICAL: Test assertion failure function run_test_step, file ...
(kms_flip:2235) CRITICAL: Failed assertion: end - start < 500
(kms_flip:2235) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest B-HDMI-A2 failed.
**** DEBUG ****
(kms_flip:2235) DEBUG: Vblank took 35us
(kms_flip:2235) DEBUG: name = flip
last_ts = 169.815704
last_received_ts = 169.815231
last_seq = 296
current_ts = 169.834671
current_received_ts = 169.834091
current_seq = 297
...

Starting subtest: flip-vs-expired-vblank-interruptible
Starting dynamic subtest: A-HDMI-A2
(kms_flip:2232) CRITICAL: Test assertion failure function run_test_step, file ...
(kms_flip:2232) CRITICAL: Failed assertion: end - start < 500
(kms_flip:2232) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest A-HDMI-A2 failed.
**** DEBUG ****
(kms_flip:2232) DEBUG: Vblank took 27us
(kms_flip:2232) DEBUG: name = flip
last_ts = 206.560715
last_received_ts = 206.560181
last_seq = 9015
current_ts = 206.577393
current_received_ts = 206.577057
current_seq = 9016
count = 316
seq_step = 1
...

Starting subtest: flip-vs-expired-vblank-interruptible
Starting dynamic subtest: A-HDMI-A2
(kms_flip:2213) CRITICAL: Test assertion failure function run_test_step, file ...
(kms_flip:2213) CRITICAL: Failed assertion: end - start < 500
(kms_flip:2213) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest A-HDMI-A2 failed.
**** DEBUG ****
(kms_flip:2213) DEBUG: Vblank took 30us
(kms_flip:2213) DEBUG: name = flip
last_ts = 204.777679
last_received_ts = 204.777100
last_seq = 7984
current_ts = 204.796661
current_received_ts = 204.796143
current_seq = 7985
count = 64
seq_step = 1
...

Starting subtest: flip-vs-expired-vblank-interruptible
Starting dynamic subtest: A-HDMI-A2
(kms_flip:2115) CRITICAL: Test assertion failure function run_test_step, file ...
(kms_flip:2115) CRITICAL: Failed assertion: end - start < 500
(kms_flip:2115) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest A-HDMI-A2 failed.
**** DEBUG ****
(kms_flip:2115) DEBUG: Vblank took 39us
(kms_flip:2115) DEBUG: name = flip
last_ts = 65.598244
last_received_ts = 65.597603
last_seq = 554
current_ts = 65.617218
current_received_ts = 65.616570
current_seq = 555
count = 168
seq_step = 1
...

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

Starting subtest: flip-vs-expired-vblank-interruptible
Starting dynamic subtest: A-HDMI-A1
Dynamic subtest A-HDMI-A1: SUCCESS (7.804s)
Starting dynamic subtest: B-HDMI-A1
(kms_flip:2120) CRITICAL: Test assertion failure function run_test_step, file ...
(kms_flip:2120) CRITICAL: Failed assertion: end - start < 500
(kms_flip:2120) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest B-HDMI-A1 failed.
**** DEBUG ****
(kms_flip:2120) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=10...
(kms_flip:2120) drmtest-DEBUG: Test requirement passed: is_intel_device(fd)
(kms_flip:2120) drmtest-DEBUG: Test requirement passed: is_intel_device(fd)
(kms_flip:2120) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=1, pitch=7680)
(kms_flip:2120) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modi...
(kms_flip:2120) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=10...
(kms_flip:2120) drmtest-DEBUG: Test requirement passed: is_intel_device(fd)
(kms_flip:2120) drmtest-DEBUG: Test requirement passed: is_intel_device(fd)
...

Starting subtest: flip-vs-expired-vblank-interruptible
Starting dynamic subtest: A-HDMI-A3
Dynamic subtest A-HDMI-A3: SUCCESS (7.799s)
Starting dynamic subtest: B-HDMI-A3
(kms_flip:2035) CRITICAL: Test assertion failure function run_test_step, file ...
(kms_flip:2035) CRITICAL: Failed assertion: end - start < 500
(kms_flip:2035) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest B-HDMI-A3 failed.
**** DEBUG ****
(kms_flip:2035) DEBUG: Vblank took 54us
(kms_flip:2035) DEBUG: name = flip
last_ts = 76.892670
last_received_ts = 76.892281
last_seq = 183
current_ts = 76.909340
current_received_ts = 76.908928
current_seq = 184
...

Starting subtest: flip-vs-expired-vblank-interruptible
Starting dynamic subtest: A-HDMI-A1
Dynamic subtest A-HDMI-A1: SUCCESS (7.647s)
Starting dynamic subtest: B-HDMI-A1
(kms_flip:18182) CRITICAL: Test assertion failure function run_test_step, file...
(kms_flip:18182) CRITICAL: Failed assertion: end - start < 500
(kms_flip:18182) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest B-HDMI-A1 failed.
**** DEBUG ****
(kms_flip:18182) DEBUG: Vblank took 23us
(kms_flip:18182) DEBUG: name = flip
last_ts = 214.639267
last_received_ts = 214.638931
last_seq = 889
current_ts = 214.647614
current_received_ts = 214.647293
current_seq = 890
...

Starting subtest: flip-vs-expired-vblank-interruptible
Starting dynamic subtest: A-HDMI-A1
Dynamic subtest A-HDMI-A1: SUCCESS (7.669s)
Starting dynamic subtest: B-HDMI-A1
Dynamic subtest B-HDMI-A1: SUCCESS (7.628s)
Starting dynamic subtest: C-HDMI-A1
(kms_flip:2199) CRITICAL: Test assertion failure function run_test_step, file ...
(kms_flip:2199) CRITICAL: Failed assertion: end - start < 500
(kms_flip:2199) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest C-HDMI-A1 failed.
**** DEBUG ****
(kms_flip:2199) DEBUG: Vblank took 27us
(kms_flip:2199) DEBUG: name = flip
last_ts = 200.489838
last_received_ts = 200.489517
last_seq = 164
current_ts = 200.498169
...

Starting subtest: flip-vs-expired-vblank-interruptible
Starting dynamic subtest: A-HDMI-A1
Dynamic subtest A-HDMI-A1: SUCCESS (7.683s)
Starting dynamic subtest: B-HDMI-A1
Dynamic subtest B-HDMI-A1: SUCCESS (7.633s)
Starting dynamic subtest: C-HDMI-A1
Dynamic subtest C-HDMI-A1: SUCCESS (7.632s)
Starting dynamic subtest: D-HDMI-A1
(kms_flip:2190) CRITICAL: Test assertion failure function run_test_step, file ...
(kms_flip:2190) CRITICAL: Failed assertion: end - start < 500
(kms_flip:2190) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest D-HDMI-A1 failed.
**** DEBUG ****
(kms_flip:2190) DEBUG: Vblank took 29us
(kms_flip:2190) DEBUG: name = flip
last_ts = 238.254852
last_received_ts = 238.254532
...

Starting subtest: flip-vs-expired-vblank-interruptible
Starting dynamic subtest: A-HDMI-A1
Dynamic subtest A-HDMI-A1: SUCCESS (7.700s)
Starting dynamic subtest: B-HDMI-A1
Dynamic subtest B-HDMI-A1: SUCCESS (7.633s)
Starting dynamic subtest: C-HDMI-A1
Dynamic subtest C-HDMI-A1: SUCCESS (7.642s)
Starting dynamic subtest: D-HDMI-A1
(kms_flip:2096) CRITICAL: Test assertion failure function run_test_step, file ...
(kms_flip:2096) CRITICAL: Failed assertion: end - start < 500
(kms_flip:2096) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest D-HDMI-A1 failed.
**** DEBUG ****
(kms_flip:2096) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=10...
(kms_flip:2096) drmtest-DEBUG: Test requirement passed: is_intel_device(fd)
(kms_flip:2096) drmtest-DEBUG: Test requirement passed: is_intel_device(fd)
(kms_flip:2096) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=1, pitch=7680)
...

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

Starting subtest: flip-vs-expired-vblank-interruptible
Starting dynamic subtest: A-HDMI-A1
Dynamic subtest A-HDMI-A1: SUCCESS (5.696s)
Starting dynamic subtest: B-HDMI-A1
Dynamic subtest B-HDMI-A1: SUCCESS (5.344s)
Starting dynamic subtest: C-HDMI-A1
(kms_flip:2306) CRITICAL: Test assertion failure function run_test_step, file ...
(kms_flip:2306) CRITICAL: Failed assertion: end - start < 500
(kms_flip:2306) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest C-HDMI-A1 failed.
**** DEBUG ****
(kms_flip:2306) DEBUG: Vblank took 48us
(kms_flip:2306) DEBUG: name = flip
last_ts = 103.059410
last_received_ts = 103.058929
last_seq = 173
current_ts = 103.076073
...