igt@kms_flip@flip-vs-expired-vblank@b-hdmi-a1 on shard-snb@Patchwork

Detail	Value
Duration	1.55 seconds
Hostname	shard-snb7
Igt-Version	IGT-Version: 2.4-g55ccc746a (x86_64) (Linux: 7.0.0-Patchwork_164894v2-g19fc8405447f+ x86_64)
Out	Starting dynamic subtest: B-HDMI-A1 1920x1080: 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5 Stack trace: #0 ../lib/igt_core.c:2075 __igt_fail_assert() #1 ../tests/kms_flip.c:926 run_test_on_crtc_set() #2 ../tests/kms_flip.c:1920 run_test() #3 ../tests/kms_flip.c:2184 __igt_unique____real_main2105() #4 ../tests/kms_flip.c:2105 main() #5 [__libc_init_first+0x8a] #6 [__libc_start_main+0x8b] #7 [_start+0x25] Dynamic subtest B-HDMI-A1: FAIL (1.554s) Subtest flip-vs-expired-vblank: FAIL (24.807s) Stack trace: #0 ../lib/igt_core.c:2075 __igt_fail_assert() #1 ../lib/igt_kms.c:2352 kmstest_set_connector_dpms() #2 ../lib/igt_core.c:2961 igt_atexit_handler() #3 [erand48_r+0x1d6] #4 [exit+0x1e] #5 ../lib/igt_core.c:2416 igt_exit() #6 [main+0x3f] #7 [__libc_init_first+0x8a] #8 [__libc_start_main+0x8b] #9 [_start+0x25]
Err	Starting dynamic subtest: B-HDMI-A1 (kms_flip:4933) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931: (kms_flip:4933) CRITICAL: Failed assertion: end - start < 500 Dynamic subtest B-HDMI-A1 failed. ** DEBUG (kms_flip:4933) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=XR24(0x34325258), modifier=0x100000000000001, size=0) (kms_flip:4933) drmtest-DEBUG: Test requirement passed: is_intel_device(fd) (kms_flip:4933) drmtest-DEBUG: Test requirement passed: is_intel_device(fd) (kms_flip:4933) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=1, pitch=7680) (kms_flip:4933) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd) (kms_flip:4933) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=XR24(0x34325258), modifier=0x100000000000001, size=0) (kms_flip:4933) drmtest-DEBUG: Test requirement passed: is_intel_device(fd) (kms_flip:4933) drmtest-DEBUG: Test requirement passed: is_intel_device(fd) (kms_flip:4933) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=2, pitch=7680) (kms_flip:4933) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd) (kms_flip:4933) intel_blt-DEBUG: Test requirement passed: cmds_info (kms_flip:4933) intel_blt-DEBUG: Test requirement passed: cmds_info (kms_flip:4933) igt_fb-DEBUG: Test requirement passed: cairo_surface_status(fb->cairo_surface) == CAIRO_STATUS_SUCCESS (kms_flip:4933) intel_blt-DEBUG: Test requirement passed: cmds_info (kms_flip:4933) intel_blt-DEBUG: Test requirement passed: cmds_info (kms_flip:4933) igt_fb-DEBUG: Test requirement passed: cairo_surface_status(fb->cairo_surface) == CAIRO_STATUS_SUCCESS (kms_flip:4933) igt_kms-INFO: 1920x1080: 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5 (kms_flip:4933) DEBUG: No stale events found (kms_flip:4933) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0 (kms_flip:4933) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0 (kms_flip:4933) DEBUG: name = flip last_ts = 0.000000 last_received_ts = 0.000000 last_seq = 0 current_ts = 396.161682 current_received_ts = 396.161163 current_seq = 13600 count = 0 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 9us (kms_flip:4933) DEBUG: name = flip last_ts = 396.161682 last_received_ts = 396.161163 last_seq = 13600 current_ts = 396.178314 current_received_ts = 396.177856 current_seq = 13601 count = 1 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 8us (kms_flip:4933) DEBUG: name = flip last_ts = 396.178314 last_received_ts = 396.177856 last_seq = 13601 current_ts = 396.194946 current_received_ts = 396.194489 current_seq = 13602 count = 2 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 8us (kms_flip:4933) DEBUG: name = flip last_ts = 396.194946 last_received_ts = 396.194489 last_seq = 13602 current_ts = 396.211578 current_received_ts = 396.211151 current_seq = 13603 count = 3 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 9us (kms_flip:4933) DEBUG: name = flip last_ts = 396.211578 last_received_ts = 396.211151 last_seq = 13603 current_ts = 396.228210 current_received_ts = 396.227783 current_seq = 13604 count = 4 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 8us (kms_flip:4933) DEBUG: name = flip last_ts = 396.228210 last_received_ts = 396.227783 last_seq = 13604 current_ts = 396.244843 current_received_ts = 396.244415 current_seq = 13605 count = 5 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 8us (kms_flip:4933) DEBUG: name = flip last_ts = 396.244843 last_received_ts = 396.244415 last_seq = 13605 current_ts = 396.261475 current_received_ts = 396.261078 current_seq = 13606 count = 6 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 8us (kms_flip:4933) DEBUG: name = flip last_ts = 396.261475 last_received_ts = 396.261078 last_seq = 13606 current_ts = 396.278137 current_received_ts = 396.277679 current_seq = 13607 count = 7 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 8us (kms_flip:4933) DEBUG: name = flip last_ts = 396.278137 last_received_ts = 396.277679 last_seq = 13607 current_ts = 396.294769 current_received_ts = 396.294250 current_seq = 13608 count = 8 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 8us (kms_flip:4933) DEBUG: name = flip last_ts = 396.294769 last_received_ts = 396.294250 last_seq = 13608 current_ts = 396.311401 current_received_ts = 396.310944 current_seq = 13609 count = 9 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 8us (kms_flip:4933) DEBUG: name = flip last_ts = 396.311401 last_received_ts = 396.310944 last_seq = 13609 current_ts = 396.328033 current_received_ts = 396.327606 current_seq = 13610 count = 10 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 8us (kms_flip:4933) DEBUG: name = flip last_ts = 396.328033 last_received_ts = 396.327606 last_seq = 13610 current_ts = 396.344666 current_received_ts = 396.344238 current_seq = 13611 count = 11 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 8us (kms_flip:4933) DEBUG: name = flip last_ts = 396.344666 last_received_ts = 396.344238 last_seq = 13611 current_ts = 396.361328 current_received_ts = 396.360870 current_seq = 13612 count = 12 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 7us (kms_flip:4933) DEBUG: name = flip last_ts = 396.361328 last_received_ts = 396.360870 last_seq = 13612 current_ts = 396.377960 current_received_ts = 396.377502 current_seq = 13613 count = 13 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 8us (kms_flip:4933) DEBUG: name = flip last_ts = 396.377960 last_received_ts = 396.377502 last_seq = 13613 current_ts = 396.394592 current_received_ts = 396.394165 current_seq = 13614 count = 14 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 8us (kms_flip:4933) DEBUG: name = flip last_ts = 396.394592 last_received_ts = 396.394165 last_seq = 13614 current_ts = 396.411224 current_received_ts = 396.410797 current_seq = 13615 count = 15 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 8us (kms_flip:4933) DEBUG: name = flip last_ts = 396.411224 last_received_ts = 396.410797 last_seq = 13615 current_ts = 396.427856 current_received_ts = 396.427429 current_seq = 13616 count = 16 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 8us (kms_flip:4933) DEBUG: name = flip last_ts = 396.427856 last_received_ts = 396.427429 last_seq = 13616 current_ts = 396.444489 current_received_ts = 396.444092 current_seq = 13617 count = 17 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 8us (kms_flip:4933) DEBUG: name = flip last_ts = 396.444489 last_received_ts = 396.444092 last_seq = 13617 current_ts = 396.461151 current_received_ts = 396.460693 current_seq = 13618 count = 18 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 8us (kms_flip:4933) DEBUG: name = flip last_ts = 396.461151 last_received_ts = 396.460693 last_seq = 13618 current_ts = 396.477783 current_received_ts = 396.477325 current_seq = 13619 count = 19 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 9us (kms_flip:4933) DEBUG: name = flip last_ts = 396.477783 last_received_ts = 396.477325 last_seq = 13619 current_ts = 396.494415 current_received_ts = 396.493988 current_seq = 13620 count = 20 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 33us (kms_flip:4933) DEBUG: name = flip last_ts = 396.494415 last_received_ts = 396.493988 last_seq = 13620 current_ts = 396.511047 current_received_ts = 396.510620 current_seq = 13621 count = 21 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 8us (kms_flip:4933) DEBUG: name = flip last_ts = 396.511047 last_received_ts = 396.510620 last_seq = 13621 current_ts = 396.527679 current_received_ts = 396.527222 current_seq = 13622 count = 22 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 9us (kms_flip:4933) DEBUG: name = flip last_ts = 396.527679 last_received_ts = 396.527222 last_seq = 13622 current_ts = 396.544312 current_received_ts = 396.543884 current_seq = 13623 count = 23 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 7us (kms_flip:4933) DEBUG: name = flip last_ts = 396.544312 last_received_ts = 396.543884 last_seq = 13623 current_ts = 396.560974 current_received_ts = 396.560516 current_seq = 13624 count = 24 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 8us (kms_flip:4933) DEBUG: name = flip last_ts = 396.560974 last_received_ts = 396.560516 last_seq = 13624 current_ts = 396.577606 current_received_ts = 396.577148 current_seq = 13625 count = 25 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 8us (kms_flip:4933) DEBUG: name = flip last_ts = 396.577606 last_received_ts = 396.577148 last_seq = 13625 current_ts = 396.594238 current_received_ts = 396.593811 current_seq = 13626 count = 26 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 8us (kms_flip:4933) DEBUG: name = flip last_ts = 396.594238 last_received_ts = 396.593811 last_seq = 13626 current_ts = 396.610870 current_received_ts = 396.610443 current_seq = 13627 count = 27 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 9us (kms_flip:4933) DEBUG: name = flip last_ts = 396.610870 last_received_ts = 396.610443 last_seq = 13627 current_ts = 396.627502 current_received_ts = 396.627075 current_seq = 13628 count = 28 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 7us (kms_flip:4933) DEBUG: name = flip last_ts = 396.627502 last_received_ts = 396.627075 last_seq = 13628 current_ts = 396.644135 current_received_ts = 396.643707 current_seq = 13629 count = 29 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 8us (kms_flip:4933) DEBUG: name = flip last_ts = 396.644135 last_received_ts = 396.643707 last_seq = 13629 current_ts = 396.660797 current_received_ts = 396.660309 current_seq = 13630 count = 30 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 8us (kms_flip:4933) DEBUG: name = flip last_ts = 396.660797 last_received_ts = 396.660309 last_seq = 13630 current_ts = 396.677429 current_received_ts = 396.676971 current_seq = 13631 count = 31 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 8us (kms_flip:4933) DEBUG: name = flip last_ts = 396.677429 last_received_ts = 396.676971 last_seq = 13631 current_ts = 396.694061 current_received_ts = 396.693604 current_seq = 13632 count = 32 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 9us (kms_flip:4933) DEBUG: name = flip last_ts = 396.694061 last_received_ts = 396.693604 last_seq = 13632 current_ts = 396.710693 current_received_ts = 396.710205 current_seq = 13633 count = 33 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 9us (kms_flip:4933) DEBUG: name = flip last_ts = 396.710693 last_received_ts = 396.710205 last_seq = 13633 current_ts = 396.727325 current_received_ts = 396.726898 current_seq = 13634 count = 34 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 8us (kms_flip:4933) DEBUG: name = flip last_ts = 396.727325 last_received_ts = 396.726898 last_seq = 13634 current_ts = 396.743958 current_received_ts = 396.743530 current_seq = 13635 count = 35 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 9us (kms_flip:4933) DEBUG: name = flip last_ts = 396.743958 last_received_ts = 396.743530 last_seq = 13635 current_ts = 396.760620 current_received_ts = 396.760193 current_seq = 13636 count = 36 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 8us (kms_flip:4933) DEBUG: name = flip last_ts = 396.760620 last_received_ts = 396.760193 last_seq = 13636 current_ts = 396.777252 current_received_ts = 396.776825 current_seq = 13637 count = 37 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 8us (kms_flip:4933) DEBUG: name = flip last_ts = 396.777252 last_received_ts = 396.776825 last_seq = 13637 current_ts = 396.793884 current_received_ts = 396.793457 current_seq = 13638 count = 38 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 8us (kms_flip:4933) DEBUG: name = flip last_ts = 396.793884 last_received_ts = 396.793457 last_seq = 13638 current_ts = 396.810516 current_received_ts = 396.810089 current_seq = 13639 count = 39 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 8us (kms_flip:4933) DEBUG: name = flip last_ts = 396.810516 last_received_ts = 396.810089 last_seq = 13639 current_ts = 396.827148 current_received_ts = 396.826721 current_seq = 13640 count = 40 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 9us (kms_flip:4933) DEBUG: name = flip last_ts = 396.827148 last_received_ts = 396.826721 last_seq = 13640 current_ts = 396.843811 current_received_ts = 396.843384 current_seq = 13641 count = 41 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 8us (kms_flip:4933) DEBUG: name = flip last_ts = 396.843811 last_received_ts = 396.843384 last_seq = 13641 current_ts = 396.860443 current_received_ts = 396.859985 current_seq = 13642 count = 42 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 8us (kms_flip:4933) DEBUG: name = flip last_ts = 396.860443 last_received_ts = 396.859985 last_seq = 13642 current_ts = 396.877075 current_received_ts = 396.876556 current_seq = 13643 count = 43 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 8us (kms_flip:4933) DEBUG: name = flip last_ts = 396.877075 last_received_ts = 396.876556 last_seq = 13643 current_ts = 396.893707 current_received_ts = 396.893280 current_seq = 13644 count = 44 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 8us (kms_flip:4933) DEBUG: name = flip last_ts = 396.893707 last_received_ts = 396.893280 last_seq = 13644 current_ts = 396.910339 current_received_ts = 396.909912 current_seq = 13645 count = 45 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 8us (kms_flip:4933) DEBUG: name = flip last_ts = 396.910339 last_received_ts = 396.909912 last_seq = 13645 current_ts = 396.926971 current_received_ts = 396.926544 current_seq = 13646 count = 46 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 8us (kms_flip:4933) DEBUG: name = flip last_ts = 396.926971 last_received_ts = 396.926544 last_seq = 13646 current_ts = 396.943634 current_received_ts = 396.943176 current_seq = 13647 count = 47 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 9us (kms_flip:4933) DEBUG: name = flip last_ts = 396.943634 last_received_ts = 396.943176 last_seq = 13647 current_ts = 396.960266 current_received_ts = 396.959839 current_seq = 13648 count = 48 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 8us (kms_flip:4933) DEBUG: name = flip last_ts = 396.960266 last_received_ts = 396.959839 last_seq = 13648 current_ts = 396.976898 current_received_ts = 396.976410 current_seq = 13649 count = 49 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 8us (kms_flip:4933) DEBUG: name = flip last_ts = 396.976898 last_received_ts = 396.976410 last_seq = 13649 current_ts = 396.993530 current_received_ts = 396.993103 current_seq = 13650 count = 50 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 8us (kms_flip:4933) DEBUG: name = flip last_ts = 396.993530 last_received_ts = 396.993103 last_seq = 13650 current_ts = 397.010162 current_received_ts = 397.009735 current_seq = 13651 count = 51 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 8us (kms_flip:4933) DEBUG: name = flip last_ts = 397.010162 last_received_ts = 397.009735 last_seq = 13651 current_ts = 397.026794 current_received_ts = 397.026398 current_seq = 13652 count = 52 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 8us (kms_flip:4933) DEBUG: name = flip last_ts = 397.026794 last_received_ts = 397.026398 last_seq = 13652 current_ts = 397.043457 current_received_ts = 397.043030 current_seq = 13653 count = 53 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 8us (kms_flip:4933) DEBUG: name = flip last_ts = 397.043457 last_received_ts = 397.043030 last_seq = 13653 current_ts = 397.060089 current_received_ts = 397.059631 current_seq = 13654 count = 54 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 8us (kms_flip:4933) DEBUG: name = flip last_ts = 397.060089 last_received_ts = 397.059631 last_seq = 13654 current_ts = 397.076721 current_received_ts = 397.076263 current_seq = 13655 count = 55 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 9us (kms_flip:4933) DEBUG: name = flip last_ts = 397.076721 last_received_ts = 397.076263 last_seq = 13655 current_ts = 397.093353 current_received_ts = 397.092896 current_seq = 13656 count = 56 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 9us (kms_flip:4933) DEBUG: name = flip last_ts = 397.093353 last_received_ts = 397.092896 last_seq = 13656 current_ts = 397.109985 current_received_ts = 397.109558 current_seq = 13657 count = 57 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 8us (kms_flip:4933) DEBUG: name = flip last_ts = 397.109985 last_received_ts = 397.109558 last_seq = 13657 current_ts = 397.126648 current_received_ts = 397.126190 current_seq = 13658 count = 58 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 9us (kms_flip:4933) DEBUG: name = flip last_ts = 397.126648 last_received_ts = 397.126190 last_seq = 13658 current_ts = 397.143280 current_received_ts = 397.142822 current_seq = 13659 count = 59 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 8us (kms_flip:4933) DEBUG: name = flip last_ts = 397.143280 last_received_ts = 397.142822 last_seq = 13659 current_ts = 397.159912 current_received_ts = 397.159485 current_seq = 13660 count = 60 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 9us (kms_flip:4933) DEBUG: name = flip last_ts = 397.159912 last_received_ts = 397.159485 last_seq = 13660 current_ts = 397.176544 current_received_ts = 397.176117 current_seq = 13661 count = 61 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 8us (kms_flip:4933) DEBUG: name = flip last_ts = 397.176544 last_received_ts = 397.176117 last_seq = 13661 current_ts = 397.193176 current_received_ts = 397.192749 current_seq = 13662 count = 62 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 8us (kms_flip:4933) DEBUG: name = flip last_ts = 397.193176 last_received_ts = 397.192749 last_seq = 13662 current_ts = 397.209808 current_received_ts = 397.209381 current_seq = 13663 count = 63 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 9us (kms_flip:4933) DEBUG: name = flip last_ts = 397.209808 last_received_ts = 397.209381 last_seq = 13663 current_ts = 397.226440 current_received_ts = 397.226044 current_seq = 13664 count = 64 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 8us (kms_flip:4933) DEBUG: name = flip last_ts = 397.226440 last_received_ts = 397.226044 last_seq = 13664 current_ts = 397.243103 current_received_ts = 397.242676 current_seq = 13665 count = 65 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 8us (kms_flip:4933) DEBUG: name = flip last_ts = 397.243103 last_received_ts = 397.242676 last_seq = 13665 current_ts = 397.259735 current_received_ts = 397.259308 current_seq = 13666 count = 66 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 9us (kms_flip:4933) DEBUG: name = flip last_ts = 397.259735 last_received_ts = 397.259308 last_seq = 13666 current_ts = 397.276367 current_received_ts = 397.275909 current_seq = 13667 count = 67 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 8us (kms_flip:4933) DEBUG: name = flip last_ts = 397.276367 last_received_ts = 397.275909 last_seq = 13667 current_ts = 397.292999 current_received_ts = 397.292572 current_seq = 13668 count = 68 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 8us (kms_flip:4933) DEBUG: name = flip last_ts = 397.292999 last_received_ts = 397.292572 last_seq = 13668 current_ts = 397.309631 current_received_ts = 397.309204 current_seq = 13669 count = 69 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 8us (kms_flip:4933) DEBUG: name = flip last_ts = 397.309631 last_received_ts = 397.309204 last_seq = 13669 current_ts = 397.326294 current_received_ts = 397.325836 current_seq = 13670 count = 70 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 9us (kms_flip:4933) DEBUG: name = flip last_ts = 397.326294 last_received_ts = 397.325836 last_seq = 13670 current_ts = 397.342926 current_received_ts = 397.342499 current_seq = 13671 count = 71 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 9us (kms_flip:4933) DEBUG: name = flip last_ts = 397.342926 last_received_ts = 397.342499 last_seq = 13671 current_ts = 397.359558 current_received_ts = 397.359131 current_seq = 13672 count = 72 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 8us (kms_flip:4933) DEBUG: name = flip last_ts = 397.359558 last_received_ts = 397.359131 last_seq = 13672 current_ts = 397.376190 current_received_ts = 397.375702 current_seq = 13673 count = 73 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 9us (kms_flip:4933) DEBUG: name = flip last_ts = 397.376190 last_received_ts = 397.375702 last_seq = 13673 current_ts = 397.392822 current_received_ts = 397.392395 current_seq = 13674 count = 74 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 8us (kms_flip:4933) DEBUG: name = flip last_ts = 397.392822 last_received_ts = 397.392395 last_seq = 13674 current_ts = 397.409454 current_received_ts = 397.409058 current_seq = 13675 count = 75 seq_step = 1 (kms_flip:4933) DEBUG: Vblank took 675us (kms_flip:4933) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931: (kms_flip:4933) CRITICAL: Failed assertion: end - start < 500 (kms_flip:4933) igt_core-INFO: Stack trace: (kms_flip:4933) igt_core-INFO: #0 ../lib/igt_core.c:2075 __igt_fail_assert() (kms_flip:4933) igt_core-INFO: #1 ../tests/kms_flip.c:926 run_test_on_crtc_set() (kms_flip:4933) igt_core-INFO: #2 ../tests/kms_flip.c:1920 run_test() (kms_flip:4933) igt_core-INFO: #3 ../tests/kms_flip.c:2184 __igt_unique____real_main2105() (kms_flip:4933) igt_core-INFO: #4 ../tests/kms_flip.c:2105 main() (kms_flip:4933) igt_core-INFO: #5 [__libc_init_first+0x8a] (kms_flip:4933) igt_core-INFO: #6 [__libc_start_main+0x8b] (kms_flip:4933) igt_core-INFO: #7 [_start+0x25] END ** Dynamic subtest B-HDMI-A1: FAIL (1.554s) Subtest flip-vs-expired-vblank: FAIL (24.807s) (kms_flip:4933) igt_kms-CRITICAL: Test assertion failure function kmstest_set_connector_dpms, file ../lib/igt_kms.c:2352: (kms_flip:4933) igt_kms-CRITICAL: Failed assertion: found_it (kms_flip:4933) igt_kms-CRITICAL: Last errno: 9, Bad file descriptor (kms_flip:4933) igt_kms-CRITICAL: DPMS property not found on 76
Dmesg	<6> [395.849128] [IGT] kms_flip: starting dynamic subtest B-HDMI-A1 <7> [395.849695] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:90] <7> [395.849839] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:91] <7> [395.902201] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:53:pipe A] <7> [395.902413] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.enable (expected yes, found no) <7> [395.902989] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.active (expected yes, found no) <7> [395.903620] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1) <7> [395.904174] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in has_pch_encoder (expected yes, found no) <7> [395.904710] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in fdi_lanes (expected 3, found 0) <7> [395.905262] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in fdi_m_n (expected tu 64 data 6934582/8388608 link 288940/524288, found tu 0, data 0/0 link 0/0) <7> [395.905752] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in lane_count (expected 4, found 0) <7> [395.906032] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000) <7> [395.906264] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0) <7> [395.906494] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0) <7> [395.906750] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0) <7> [395.907029] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0) <7> [395.907258] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0) <7> [395.907487] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0) <7> [395.907748] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0) <7> [395.908022] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0) <7> [395.908261] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0) <7> [395.908497] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0) <7> [395.908765] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0) <7> [395.909044] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0) <7> [395.909273] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0) <7> [395.909503] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0) <7> [395.909773] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0) <7> [395.910038] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0) <7> [395.910268] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0) <7> [395.910497] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0) <7> [395.910758] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0) <7> [395.911041] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0) <7> [395.911277] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0) <7> [395.911513] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0) <7> [395.911774] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0) <7> [395.912052] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0) <7> [395.912289] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0) <7> [395.912523] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0) <7> [395.912795] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0) <7> [395.913050] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0) <7> [395.913292] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in has_hdmi_sink (expected yes, found no) <7> [395.913561] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in has_infoframe (expected yes, found no) <7> [395.913808] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in pipe_bpp (expected 36, found 0) <7> [395.914051] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148800, found 0) <7> [395.914286] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148800, found 0) <7> [395.914522] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in port_clock (expected 223200, found 0) <7> [395.914794] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000) <7> [395.915055] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in infoframes.gcp (expected 0x00000006, found 0x00000000) <7> [395.915298] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in avi infoframe <7> [395.915571] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected: <7> [395.915817] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13 <7> [395.915820] i915 0000:00:02.0: colorspace: RGB <7> [395.915822] i915 0000:00:02.0: scan mode: Underscan <7> [395.915824] i915 0000:00:02.0: colorimetry: No Data <7> [395.915826] i915 0000:00:02.0: picture aspect: 16:9 <7> [395.915828] i915 0000:00:02.0: active aspect: Same as Picture <7> [395.915830] i915 0000:00:02.0: itc: No Data <7> [395.915832] i915 0000:00:02.0: extended colorimetry: xvYCC 601 <7> [395.915834] i915 0000:00:02.0: quantization range: Default <7> [395.915836] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling <7> [395.915838] i915 0000:00:02.0: video code: 16 <7> [395.915840] i915 0000:00:02.0: ycc quantization range: Full <7> [395.915842] i915 0000:00:02.0: hdmi content type: Graphics <7> [395.915844] i915 0000:00:02.0: pixel repeat: 0 <7> [395.915846] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0 <7> [395.915848] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found: <7> [395.916077] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in spd infoframe <7> [395.916320] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected: <7> [395.916578] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25 <7> [395.916583] i915 0000:00:02.0: vendor: Intel <7> [395.916587] i915 0000:00:02.0: product: Integrated gfx <7> [395.916590] i915 0000:00:02.0: source device information: PC General (0x9) <7> [395.916595] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found: <7> [395.916863] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hdmi infoframe <7> [395.917099] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected: <7> [395.917334] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4 <7> [395.917336] i915 0000:00:02.0: empty frame <7> [395.917339] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found: <7> [395.917615] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:53:pipe A] fastset requirement not met, forcing full modeset <7> [395.917876] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:53:pipe A] releasing PCH DPLL A <7> [395.918139] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:53:pipe A] min cdclk: 165334 kHz -> 0 kHz <7> [395.918380] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:53:pipe A] enable: no [modeset] <7> [395.918679] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:primary A] fb: [NOFB], visible: no <7> [395.918972] i915 0000:00:02.0: [drm:intel_audio_codec_disable [i915]] [CONNECTOR:76:HDMI-A-1][ENCODER:75:HDMI D] Disable audio codec on [CRTC:53:pipe A] <7> [395.946333] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A <7> [395.963203] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port B found <7> [395.963714] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port C found <7> [395.964179] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port D found <7> [395.975806] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable PCH DPLL A (active 0x1, on? 1) for [CRTC:53:pipe A] <7> [395.976322] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling PCH DPLL A <7> [395.977069] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:CRT] <7> [395.977544] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:75:HDMI D] <7> [395.978037] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:83:DP D] <7> [395.978480] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:76:HDMI-A-1] <7> [395.979099] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:53:pipe A] <7> [395.979764] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:72:pipe B] <7> [395.979915] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:72:pipe B] <7> [395.979943] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:76:HDMI-A-1] <7> [395.980038] i915 0000:00:02.0: [drm:intel_hdmi_compute_output_format [i915]] picking 12 bpc for HDMI output (pipe bpp: 36) <7> [395.980293] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:72:pipe B] hw max bpp: 36, pipe bpp: 36, dithering: 0 <7> [395.980550] i915 0000:00:02.0: [drm:intel_fdi_atomic_check_link [i915]] checking fdi config on pipe B, lanes 3 <7> [395.980805] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.enable (expected no, found yes) <7> [395.981043] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.active (expected no, found yes) <7> [395.981279] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in cpu_transcoder (expected -1, found 1) <7> [395.981535] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in has_pch_encoder (expected no, found yes) <7> [395.981799] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in fdi_lanes (expected 0, found 3) <7> [395.982035] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in fdi_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 6934582/8388608 link 288940/524288) <7> [395.982273] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in lane_count (expected 0, found 4) <7> [395.982509] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040) <7> [395.982777] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in framestart_delay (expected 0, found 1) <7> [395.983030] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920) <7> [395.983266] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200) <7> [395.983502] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920) <7> [395.983759] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200) <7> [395.984012] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008) <7> [395.984248] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052) <7> [395.984482] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080) <7> [395.984750] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080) <7> [395.985011] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084) <7> [395.985245] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089) <7> [395.985481] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125) <7> [395.985737] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125) <7> [395.986002] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920) <7> [395.986242] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200) <7> [395.986478] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920) <7> [395.986735] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200) <7> [395.986994] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008) <7> [395.987230] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052) <7> [395.987466] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080) <7> [395.987737] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080) <7> [395.987987] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084) <7> [395.988223] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089) <7> [395.988458] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125) <7> [395.988713] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125) <7> [395.988968] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in pixel_multiplier (expected 0, found 1) <7> [395.989201] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1) <7> [395.989436] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4) <7> [395.989704] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in has_hdmi_sink (expected no, found yes) <7> [395.989960] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in has_infoframe (expected no, found yes) <7> [395.990196] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in dpll_hw_state <7> [395.990430] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected: <7> [395.990697] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: dpll: 0x84100010, dpll_md: 0x0, fp0: 0x10c09, fp1: 0x10c09 <7> [395.990997] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found: <7> [395.991232] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: dpll: 0xc4010001, dpll_md: 0x0, fp0: 0x30f06, fp1: 0x30f06 <7> [395.991467] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in pipe_bpp (expected 0, found 36) <7> [395.991766] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148800) <7> [395.992017] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148800) <7> [395.992253] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in port_clock (expected 0, found 223200) <7> [395.992489] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071) <7> [395.992761] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in infoframes.gcp (expected 0x00000000, found 0x00000006) <7> [395.993019] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in avi infoframe <7> [395.993255] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected: <7> [395.993489] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found: <7> [395.993773] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13 <7> [395.993778] i915 0000:00:02.0: colorspace: RGB <7> [395.993782] i915 0000:00:02.0: scan mode: Underscan <7> [395.993785] i915 0000:00:02.0: colorimetry: No Data <7> [395.993789] i915 0000:00:02.0: picture aspect: 16:9 <7> [395.993792] i915 0000:00:02.0: active aspect: Same as Picture <7> [395.993796] i915 0000:00:02.0: itc: No Data <7> [395.993800] i915 0000:00:02.0: extended colorimetry: xvYCC 601 <7> [395.993803] i915 0000:00:02.0: quantization range: Default <7> [395.993807] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling <7> [395.993811] i915 0000:00:02.0: video code: 16 <7> [395.993815] i915 0000:00:02.0: ycc quantization range: Full <7> [395.993819] i915 0000:00:02.0: hdmi content type: Graphics <7> [395.993823] i915 0000:00:02.0: pixel repeat: 0 <7> [395.993826] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0 <7> [395.993831] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in spd infoframe <7> [395.994087] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected: <7> [395.994321] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found: <7> [395.994594] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25 <7> [395.994599] i915 0000:00:02.0: vendor: Intel <7> [395.994603] i915 0000:00:02.0: product: Integrated gfx <7> [395.994607] i915 0000:00:02.0: source device information: PC General (0x9) <7> [395.994611] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hdmi infoframe <7> [395.994892] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected: <7> [395.995126] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found: <7> [395.995361] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4 <7> [395.995363] i915 0000:00:02.0: empty frame <7> [395.995366] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:72:pipe B] fastset requirement not met, forcing full modeset <7> [395.995682] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:72:pipe B] min cdclk: 0 kHz -> 165334 kHz <7> [395.995923] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:72:pipe B] allocated PCH DPLL A <7> [395.996157] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:72:pipe B] reserving PCH DPLL A <7> [395.996393] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:72:pipe B] enable: yes [modeset] <7> [395.996651] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB <7> [395.996915] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: B, pipe bpp: 36, dithering: 0 <7> [395.997149] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid> <7> [395.997384] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0 <7> [395.997653] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0 <7> [395.997665] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling always-on <7> [395.997922] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0 <7> [395.998157] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] fdi: lanes: 3; data_m: 6934582, data_n: 8388608, link_m: 288940, link_n: 524288, tu: 64 <7> [395.998392] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 1, infoframes enabled: 0x71 <7> [395.998690] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] GCP: 0x6 <7> [395.998942] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13 <7> [395.998945] i915 0000:00:02.0: colorspace: RGB <7> [395.998948] i915 0000:00:02.0: scan mode: Underscan <7> [395.998950] i915 0000:00:02.0: colorimetry: No Data <7> [395.998952] i915 0000:00:02.0: picture aspect: 16:9 <7> [395.998954] i915 0000:00:02.0: active aspect: Same as Picture <7> [395.998956] i915 0000:00:02.0: itc: No Data <7> [395.998958] i915 0000:00:02.0: extended colorimetry: xvYCC 601 <7> [395.998960] i915 0000:00:02.0: quantization range: Default <7> [395.998962] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling <7> [395.998964] i915 0000:00:02.0: video code: 16 <7> [395.998966] i915 0000:00:02.0: ycc quantization range: Full <7> [395.998968] i915 0000:00:02.0: hdmi content type: Graphics <7> [395.998970] i915 0000:00:02.0: pixel repeat: 0 <7> [395.998972] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0 <7> [395.998975] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25 <7> [395.998977] i915 0000:00:02.0: vendor: Intel <7> [395.998979] i915 0000:00:02.0: product: Integrated gfx <7> [395.998981] i915 0000:00:02.0: source device information: PC General (0x9) <7> [395.998984] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4 <7> [395.998986] i915 0000:00:02.0: empty frame <7> [395.998988] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 10 00 08 00 65 32 00 01 00 00 00 00 00 00 00 00 <7> [395.999223] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 61 a4 9a 00 4d 69 20 54 56 09 07 07 11 17 50 51 <7> [395.999457] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 07 00 00 00 <7> [395.999715] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 1 <7> [396.000005] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0 <7> [396.000238] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: no, vmin: 0, vmax: 0, flipline: 0, pipeline full: 0, guardband: 0 vsync start: 0, vsync end: 0 <7> [396.000473] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 0, vmax vblank: 0, vmin vtotal: 0, vmax vtotal: 0 <7> [396.000742] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0 <7> [396.000998] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5 <7> [396.001234] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5 <7> [396.001469] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=148800, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5 <7> [396.001749] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 60 148800 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5 <7> [396.002008] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=148800, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5 <7> [396.002245] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 223200, pixel rate 148800, min cdclk 165334, min voltage level 0 <7> [396.002479] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 0, ips linetime: 0 <7> [396.002761] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1920x1080+0+0 <7> [396.003004] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no <7> [396.003242] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0 <7> [396.003476] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0 <7> [396.003728] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: dpll: 0xc4010001, dpll_md: 0x0, fp0: 0x30f06, fp1: 0x30f06 <7> [396.003988] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x3 gamma_mode: 0x0 gamma_enable: 1 csc_enable: 0 <7> [396.004222] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 256 entries <7> [396.004456] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000 <7> [396.004717] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000 <7> [396.004981] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000 <7> [396.005215] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000 <7> [396.005449] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:54:primary B] fb: [FB:90] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes <7> [396.005731] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0 <7> [396.005984] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0 <7> [396.008259] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling always-on <7> [396.008529] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:CRT] <7> [396.008805] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:75:HDMI D] <7> [396.009028] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:83:DP D] <7> [396.009881] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe B <7> [396.010939] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x100 <7> [396.011177] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 1 done. <7> [396.012067] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x200 <7> [396.012308] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 2 done. <7> [396.012571] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train done. <7> [396.012837] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable PCH DPLL A (active 0x2, on? 0) for [CRTC:72:pipe B] <7> [396.013064] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling PCH DPLL A <7> [396.064798] i915 0000:00:02.0: [drm:intel_audio_codec_enable [i915]] [CONNECTOR:76:HDMI-A-1][ENCODER:75:HDMI D] Enable audio codec on [CRTC:72:pipe B], 36 bytes ELD <7> [396.081434] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] HDMI audio pixel clock setting for 148800 not found, falling back to defaults <7> [396.081963] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] Configuring HDMI audio for pixel clock 25200 (0x00010000) <7> [396.082465] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:76:HDMI-A-1] <7> [396.083077] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:72:pipe B] <6> [397.404116] [IGT] kms_flip: finished subtest B-HDMI-A1, FAIL <6> [397.405259] [IGT] kms_flip: finished subtest flip-vs-expired-vblank, FAIL <7> [397.420848] i915 0000:00:02.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:91] from all active usage due to RMFB ioctl <7> [397.421277] i915 0000:00:02.0: [drm:atomic_remove_fb] Disabling [PLANE:54:primary B] because [FB:91] is removed <6> [397.430245] [IGT] kms_flip: exiting, ret=98 <7> [397.451006] i915 0000:00:02.0: [drm:compute_baseline_pipe_bpp [i915]] [CONNECTOR:73:VGA-1] Limiting target display pipe bpp to 24 (EDID bpp 0, max requested bpp 0, max platform bpp 36) <7> [397.451326] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:53:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0 <7> [397.451561] i915 0000:00:02.0: [drm:intel_fdi_atomic_check_link [i915]] checking fdi config on pipe A, lanes 1 <7> [397.451788] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.enable (expected no, found yes) <7> [397.452019] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.active (expected no, found yes) <7> [397.452382] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0) <7> [397.452659] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in has_pch_encoder (expected no, found yes) <7> [397.452890] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in fdi_lanes (expected 0, found 1) <7> [397.453143] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in fdi_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 6039797/8388608 link 125829/524288) <7> [397.453416] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000002) <7> [397.453646] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1) <7> [397.453876] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1024) <7> [397.454106] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 1344) <7> [397.454370] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1024) <7> [397.454647] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 1344) <7> [397.454877] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 1048) <7> [397.455107] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 1184) <7> [397.455380] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 768) <7> [397.455643] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 768) <7> [397.455873] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 771) <7> [397.456111] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 777) <7> [397.456549] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 806) <7> [397.456781] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 806) <7> [397.457011] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1024) <7> [397.457303] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 1344) <7> [397.457559] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1024) <7> [397.457789] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 1344) <7> [397.458018] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 1048) <7> [397.458283] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 1184) <7> [397.458529] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 768) <7> [397.458759] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 768) <7> [397.458988] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 771) <7> [397.459256] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 777) <7> [397.459502] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 806) <7> [397.459732] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 806) <7> [397.459961] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1) <7> [397.460225] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2) <7> [397.460474] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8) <7> [397.460705] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in dpll_hw_state <7> [397.460933] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected: <7> [397.461237] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: dpll: 0xc4010001, dpll_md: 0x0, fp0: 0x30f06, fp1: 0x30f06 <7> [397.461596] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found: <7> [397.461948] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: dpll: 0x84100010, dpll_md: 0x0, fp0: 0x10c09, fp1: 0x10c09 <7> [397.462328] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24) <7> [397.462636] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 64800) <7> [397.462865] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 64800) <7> [397.463095] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in port_clock (expected 0, found 64800) <7> [397.463371] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:53:pipe A] fastset requirement not met, forcing full modeset <7> [397.463654] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:53:pipe A] min cdclk: 0 kHz -> 72000 kHz <7> [397.463886] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:53:pipe A] allocated PCH DPLL B <7> [397.464134] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:53:pipe A] reserving PCH DPLL B <7> [397.464393] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:53:pipe A] enable: yes [modeset] <7> [397.464622] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: ANALOG (0x2), output format: RGB, sink format: RGB <7> [397.464851] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0 <7> [397.465079] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid> <7> [397.465364] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0 <7> [397.465603] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0 <7> [397.465831] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0 <7> [397.466059] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] fdi: lanes: 1; data_m: 6039797, data_n: 8388608, link_m: 125829, link_n: 524288, tu: 64 <7> [397.466341] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0 <7> [397.466586] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 1 <7> [397.466814] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0 <7> [397.467042] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: no, vmin: 0, vmax: 0, flipline: 0, pipeline full: 0, guardband: 0 vsync start: 0, vsync end: 0 <7> [397.467301] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 0, vmax vblank: 0, vmin vtotal: 0, vmax vtotal: 0 <7> [397.467546] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0 <7> [397.467775] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa <7> [397.468005] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa <7> [397.468268] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=64800, hd=1024 hb=1024-1344 hs=1048-1184 ht=1344, vd=768 vb=768-806 vs=771-777 vt=806, flags=0xa <7> [397.468516] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1024x768": 60 64800 1024 1048 1184 1344 768 771 777 806 0x40 0xa <7> [397.468745] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=64800, hd=1024 hb=1024-1344 hs=1048-1184 ht=1344, vd=768 vb=768-806 vs=771-777 vt=806, flags=0xa <7> [397.468974] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 64800, pixel rate 64800, min cdclk 72000, min voltage level 0 <7> [397.469293] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 0, ips linetime: 0 <7> [397.469555] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1024x768+0+0 <7> [397.469784] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no <7> [397.470012] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0 <7> [397.470273] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0 <7> [397.470519] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: dpll: 0x84100010, dpll_md: 0x0, fp0: 0x10c09, fp1: 0x10c09 <7> [397.470747] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x3 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0 <7> [397.470976] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries <7> [397.471243] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000 <7> [397.471488] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000 <7> [397.471716] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000 <7> [397.471944] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000 <7> [397.472203] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:primary A] fb: [FB:89] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes <7> [397.472450] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0 <7> [397.472678] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1024.000000x768.000000+0.000000+0.000000 dst: 1024x768+0+0 <7> [397.472908] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:40:sprite A] fb: [NOFB], visible: no <7> [397.473170] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:47:cursor A] fb: [NOFB], visible: no <7> [397.473501] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:CRT] <7> [397.473725] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:75:HDMI D] <7> [397.473946] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:83:DP D] <7> [397.474786] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A <7> [397.475853] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x100 <7> [397.476078] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 1 done. <7> [397.477006] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x200 <7> [397.477269] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 2 done. <7> [397.477492] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train done. <7> [397.477720] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable PCH DPLL B (active 0x1, on? 0) for [CRTC:53:pipe A] <7> [397.477947] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling PCH DPLL B <7> [397.529950] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:73:VGA-1] <7> [397.530590] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:53:pipe A] <7> [397.531315] i915 0000:00:02.0: [drm:drm_client_dev_restore] fbdev: ret=0

Detail

Value

Duration

1.55 seconds

Hostname

shard-snb7

Igt-Version

IGT-Version: 2.4-g55ccc746a (x86_64) (Linux: 7.0.0-Patchwork_164894v2-g19fc8405447f+ x86_64)

Out

Starting dynamic subtest: B-HDMI-A1
  1920x1080: 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5 
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../tests/kms_flip.c:926 run_test_on_crtc_set()
  #2 ../tests/kms_flip.c:1920 run_test()
  #3 ../tests/kms_flip.c:2184 __igt_unique____real_main2105()
  #4 ../tests/kms_flip.c:2105 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest B-HDMI-A1: FAIL (1.554s)
Subtest flip-vs-expired-vblank: FAIL (24.807s)
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../lib/igt_kms.c:2352 kmstest_set_connector_dpms()
  #2 ../lib/igt_core.c:2961 igt_atexit_handler()
  #3 [erand48_r+0x1d6]
  #4 [exit+0x1e]
  #5 ../lib/igt_core.c:2416 igt_exit()
  #6 [main+0x3f]
  #7 [__libc_init_first+0x8a]
  #8 [__libc_start_main+0x8b]
  #9 [_start+0x25]

Err

Starting dynamic subtest: B-HDMI-A1
(kms_flip:4933) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931:
(kms_flip:4933) CRITICAL: Failed assertion: end - start < 500
Dynamic subtest B-HDMI-A1 failed.
**** DEBUG ****
(kms_flip:4933) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=XR24(0x34325258), modifier=0x100000000000001, size=0)
(kms_flip:4933) drmtest-DEBUG: Test requirement passed: is_intel_device(fd)
(kms_flip:4933) drmtest-DEBUG: Test requirement passed: is_intel_device(fd)
(kms_flip:4933) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=1, pitch=7680)
(kms_flip:4933) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd)
(kms_flip:4933) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=XR24(0x34325258), modifier=0x100000000000001, size=0)
(kms_flip:4933) drmtest-DEBUG: Test requirement passed: is_intel_device(fd)
(kms_flip:4933) drmtest-DEBUG: Test requirement passed: is_intel_device(fd)
(kms_flip:4933) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=2, pitch=7680)
(kms_flip:4933) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd)
(kms_flip:4933) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:4933) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:4933) igt_fb-DEBUG: Test requirement passed: cairo_surface_status(fb->cairo_surface) == CAIRO_STATUS_SUCCESS
(kms_flip:4933) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:4933) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:4933) igt_fb-DEBUG: Test requirement passed: cairo_surface_status(fb->cairo_surface) == CAIRO_STATUS_SUCCESS
(kms_flip:4933) igt_kms-INFO:   1920x1080: 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5 
(kms_flip:4933) DEBUG: No stale events found
(kms_flip:4933) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0
(kms_flip:4933) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0
(kms_flip:4933) DEBUG: name = flip
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 396.161682
current_received_ts = 396.161163
current_seq = 13600
count = 0
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 9us
(kms_flip:4933) DEBUG: name = flip
last_ts = 396.161682
last_received_ts = 396.161163
last_seq = 13600
current_ts = 396.178314
current_received_ts = 396.177856
current_seq = 13601
count = 1
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 8us
(kms_flip:4933) DEBUG: name = flip
last_ts = 396.178314
last_received_ts = 396.177856
last_seq = 13601
current_ts = 396.194946
current_received_ts = 396.194489
current_seq = 13602
count = 2
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 8us
(kms_flip:4933) DEBUG: name = flip
last_ts = 396.194946
last_received_ts = 396.194489
last_seq = 13602
current_ts = 396.211578
current_received_ts = 396.211151
current_seq = 13603
count = 3
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 9us
(kms_flip:4933) DEBUG: name = flip
last_ts = 396.211578
last_received_ts = 396.211151
last_seq = 13603
current_ts = 396.228210
current_received_ts = 396.227783
current_seq = 13604
count = 4
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 8us
(kms_flip:4933) DEBUG: name = flip
last_ts = 396.228210
last_received_ts = 396.227783
last_seq = 13604
current_ts = 396.244843
current_received_ts = 396.244415
current_seq = 13605
count = 5
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 8us
(kms_flip:4933) DEBUG: name = flip
last_ts = 396.244843
last_received_ts = 396.244415
last_seq = 13605
current_ts = 396.261475
current_received_ts = 396.261078
current_seq = 13606
count = 6
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 8us
(kms_flip:4933) DEBUG: name = flip
last_ts = 396.261475
last_received_ts = 396.261078
last_seq = 13606
current_ts = 396.278137
current_received_ts = 396.277679
current_seq = 13607
count = 7
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 8us
(kms_flip:4933) DEBUG: name = flip
last_ts = 396.278137
last_received_ts = 396.277679
last_seq = 13607
current_ts = 396.294769
current_received_ts = 396.294250
current_seq = 13608
count = 8
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 8us
(kms_flip:4933) DEBUG: name = flip
last_ts = 396.294769
last_received_ts = 396.294250
last_seq = 13608
current_ts = 396.311401
current_received_ts = 396.310944
current_seq = 13609
count = 9
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 8us
(kms_flip:4933) DEBUG: name = flip
last_ts = 396.311401
last_received_ts = 396.310944
last_seq = 13609
current_ts = 396.328033
current_received_ts = 396.327606
current_seq = 13610
count = 10
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 8us
(kms_flip:4933) DEBUG: name = flip
last_ts = 396.328033
last_received_ts = 396.327606
last_seq = 13610
current_ts = 396.344666
current_received_ts = 396.344238
current_seq = 13611
count = 11
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 8us
(kms_flip:4933) DEBUG: name = flip
last_ts = 396.344666
last_received_ts = 396.344238
last_seq = 13611
current_ts = 396.361328
current_received_ts = 396.360870
current_seq = 13612
count = 12
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 7us
(kms_flip:4933) DEBUG: name = flip
last_ts = 396.361328
last_received_ts = 396.360870
last_seq = 13612
current_ts = 396.377960
current_received_ts = 396.377502
current_seq = 13613
count = 13
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 8us
(kms_flip:4933) DEBUG: name = flip
last_ts = 396.377960
last_received_ts = 396.377502
last_seq = 13613
current_ts = 396.394592
current_received_ts = 396.394165
current_seq = 13614
count = 14
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 8us
(kms_flip:4933) DEBUG: name = flip
last_ts = 396.394592
last_received_ts = 396.394165
last_seq = 13614
current_ts = 396.411224
current_received_ts = 396.410797
current_seq = 13615
count = 15
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 8us
(kms_flip:4933) DEBUG: name = flip
last_ts = 396.411224
last_received_ts = 396.410797
last_seq = 13615
current_ts = 396.427856
current_received_ts = 396.427429
current_seq = 13616
count = 16
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 8us
(kms_flip:4933) DEBUG: name = flip
last_ts = 396.427856
last_received_ts = 396.427429
last_seq = 13616
current_ts = 396.444489
current_received_ts = 396.444092
current_seq = 13617
count = 17
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 8us
(kms_flip:4933) DEBUG: name = flip
last_ts = 396.444489
last_received_ts = 396.444092
last_seq = 13617
current_ts = 396.461151
current_received_ts = 396.460693
current_seq = 13618
count = 18
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 8us
(kms_flip:4933) DEBUG: name = flip
last_ts = 396.461151
last_received_ts = 396.460693
last_seq = 13618
current_ts = 396.477783
current_received_ts = 396.477325
current_seq = 13619
count = 19
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 9us
(kms_flip:4933) DEBUG: name = flip
last_ts = 396.477783
last_received_ts = 396.477325
last_seq = 13619
current_ts = 396.494415
current_received_ts = 396.493988
current_seq = 13620
count = 20
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 33us
(kms_flip:4933) DEBUG: name = flip
last_ts = 396.494415
last_received_ts = 396.493988
last_seq = 13620
current_ts = 396.511047
current_received_ts = 396.510620
current_seq = 13621
count = 21
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 8us
(kms_flip:4933) DEBUG: name = flip
last_ts = 396.511047
last_received_ts = 396.510620
last_seq = 13621
current_ts = 396.527679
current_received_ts = 396.527222
current_seq = 13622
count = 22
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 9us
(kms_flip:4933) DEBUG: name = flip
last_ts = 396.527679
last_received_ts = 396.527222
last_seq = 13622
current_ts = 396.544312
current_received_ts = 396.543884
current_seq = 13623
count = 23
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 7us
(kms_flip:4933) DEBUG: name = flip
last_ts = 396.544312
last_received_ts = 396.543884
last_seq = 13623
current_ts = 396.560974
current_received_ts = 396.560516
current_seq = 13624
count = 24
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 8us
(kms_flip:4933) DEBUG: name = flip
last_ts = 396.560974
last_received_ts = 396.560516
last_seq = 13624
current_ts = 396.577606
current_received_ts = 396.577148
current_seq = 13625
count = 25
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 8us
(kms_flip:4933) DEBUG: name = flip
last_ts = 396.577606
last_received_ts = 396.577148
last_seq = 13625
current_ts = 396.594238
current_received_ts = 396.593811
current_seq = 13626
count = 26
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 8us
(kms_flip:4933) DEBUG: name = flip
last_ts = 396.594238
last_received_ts = 396.593811
last_seq = 13626
current_ts = 396.610870
current_received_ts = 396.610443
current_seq = 13627
count = 27
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 9us
(kms_flip:4933) DEBUG: name = flip
last_ts = 396.610870
last_received_ts = 396.610443
last_seq = 13627
current_ts = 396.627502
current_received_ts = 396.627075
current_seq = 13628
count = 28
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 7us
(kms_flip:4933) DEBUG: name = flip
last_ts = 396.627502
last_received_ts = 396.627075
last_seq = 13628
current_ts = 396.644135
current_received_ts = 396.643707
current_seq = 13629
count = 29
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 8us
(kms_flip:4933) DEBUG: name = flip
last_ts = 396.644135
last_received_ts = 396.643707
last_seq = 13629
current_ts = 396.660797
current_received_ts = 396.660309
current_seq = 13630
count = 30
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 8us
(kms_flip:4933) DEBUG: name = flip
last_ts = 396.660797
last_received_ts = 396.660309
last_seq = 13630
current_ts = 396.677429
current_received_ts = 396.676971
current_seq = 13631
count = 31
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 8us
(kms_flip:4933) DEBUG: name = flip
last_ts = 396.677429
last_received_ts = 396.676971
last_seq = 13631
current_ts = 396.694061
current_received_ts = 396.693604
current_seq = 13632
count = 32
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 9us
(kms_flip:4933) DEBUG: name = flip
last_ts = 396.694061
last_received_ts = 396.693604
last_seq = 13632
current_ts = 396.710693
current_received_ts = 396.710205
current_seq = 13633
count = 33
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 9us
(kms_flip:4933) DEBUG: name = flip
last_ts = 396.710693
last_received_ts = 396.710205
last_seq = 13633
current_ts = 396.727325
current_received_ts = 396.726898
current_seq = 13634
count = 34
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 8us
(kms_flip:4933) DEBUG: name = flip
last_ts = 396.727325
last_received_ts = 396.726898
last_seq = 13634
current_ts = 396.743958
current_received_ts = 396.743530
current_seq = 13635
count = 35
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 9us
(kms_flip:4933) DEBUG: name = flip
last_ts = 396.743958
last_received_ts = 396.743530
last_seq = 13635
current_ts = 396.760620
current_received_ts = 396.760193
current_seq = 13636
count = 36
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 8us
(kms_flip:4933) DEBUG: name = flip
last_ts = 396.760620
last_received_ts = 396.760193
last_seq = 13636
current_ts = 396.777252
current_received_ts = 396.776825
current_seq = 13637
count = 37
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 8us
(kms_flip:4933) DEBUG: name = flip
last_ts = 396.777252
last_received_ts = 396.776825
last_seq = 13637
current_ts = 396.793884
current_received_ts = 396.793457
current_seq = 13638
count = 38
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 8us
(kms_flip:4933) DEBUG: name = flip
last_ts = 396.793884
last_received_ts = 396.793457
last_seq = 13638
current_ts = 396.810516
current_received_ts = 396.810089
current_seq = 13639
count = 39
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 8us
(kms_flip:4933) DEBUG: name = flip
last_ts = 396.810516
last_received_ts = 396.810089
last_seq = 13639
current_ts = 396.827148
current_received_ts = 396.826721
current_seq = 13640
count = 40
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 9us
(kms_flip:4933) DEBUG: name = flip
last_ts = 396.827148
last_received_ts = 396.826721
last_seq = 13640
current_ts = 396.843811
current_received_ts = 396.843384
current_seq = 13641
count = 41
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 8us
(kms_flip:4933) DEBUG: name = flip
last_ts = 396.843811
last_received_ts = 396.843384
last_seq = 13641
current_ts = 396.860443
current_received_ts = 396.859985
current_seq = 13642
count = 42
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 8us
(kms_flip:4933) DEBUG: name = flip
last_ts = 396.860443
last_received_ts = 396.859985
last_seq = 13642
current_ts = 396.877075
current_received_ts = 396.876556
current_seq = 13643
count = 43
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 8us
(kms_flip:4933) DEBUG: name = flip
last_ts = 396.877075
last_received_ts = 396.876556
last_seq = 13643
current_ts = 396.893707
current_received_ts = 396.893280
current_seq = 13644
count = 44
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 8us
(kms_flip:4933) DEBUG: name = flip
last_ts = 396.893707
last_received_ts = 396.893280
last_seq = 13644
current_ts = 396.910339
current_received_ts = 396.909912
current_seq = 13645
count = 45
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 8us
(kms_flip:4933) DEBUG: name = flip
last_ts = 396.910339
last_received_ts = 396.909912
last_seq = 13645
current_ts = 396.926971
current_received_ts = 396.926544
current_seq = 13646
count = 46
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 8us
(kms_flip:4933) DEBUG: name = flip
last_ts = 396.926971
last_received_ts = 396.926544
last_seq = 13646
current_ts = 396.943634
current_received_ts = 396.943176
current_seq = 13647
count = 47
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 9us
(kms_flip:4933) DEBUG: name = flip
last_ts = 396.943634
last_received_ts = 396.943176
last_seq = 13647
current_ts = 396.960266
current_received_ts = 396.959839
current_seq = 13648
count = 48
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 8us
(kms_flip:4933) DEBUG: name = flip
last_ts = 396.960266
last_received_ts = 396.959839
last_seq = 13648
current_ts = 396.976898
current_received_ts = 396.976410
current_seq = 13649
count = 49
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 8us
(kms_flip:4933) DEBUG: name = flip
last_ts = 396.976898
last_received_ts = 396.976410
last_seq = 13649
current_ts = 396.993530
current_received_ts = 396.993103
current_seq = 13650
count = 50
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 8us
(kms_flip:4933) DEBUG: name = flip
last_ts = 396.993530
last_received_ts = 396.993103
last_seq = 13650
current_ts = 397.010162
current_received_ts = 397.009735
current_seq = 13651
count = 51
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 8us
(kms_flip:4933) DEBUG: name = flip
last_ts = 397.010162
last_received_ts = 397.009735
last_seq = 13651
current_ts = 397.026794
current_received_ts = 397.026398
current_seq = 13652
count = 52
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 8us
(kms_flip:4933) DEBUG: name = flip
last_ts = 397.026794
last_received_ts = 397.026398
last_seq = 13652
current_ts = 397.043457
current_received_ts = 397.043030
current_seq = 13653
count = 53
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 8us
(kms_flip:4933) DEBUG: name = flip
last_ts = 397.043457
last_received_ts = 397.043030
last_seq = 13653
current_ts = 397.060089
current_received_ts = 397.059631
current_seq = 13654
count = 54
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 8us
(kms_flip:4933) DEBUG: name = flip
last_ts = 397.060089
last_received_ts = 397.059631
last_seq = 13654
current_ts = 397.076721
current_received_ts = 397.076263
current_seq = 13655
count = 55
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 9us
(kms_flip:4933) DEBUG: name = flip
last_ts = 397.076721
last_received_ts = 397.076263
last_seq = 13655
current_ts = 397.093353
current_received_ts = 397.092896
current_seq = 13656
count = 56
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 9us
(kms_flip:4933) DEBUG: name = flip
last_ts = 397.093353
last_received_ts = 397.092896
last_seq = 13656
current_ts = 397.109985
current_received_ts = 397.109558
current_seq = 13657
count = 57
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 8us
(kms_flip:4933) DEBUG: name = flip
last_ts = 397.109985
last_received_ts = 397.109558
last_seq = 13657
current_ts = 397.126648
current_received_ts = 397.126190
current_seq = 13658
count = 58
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 9us
(kms_flip:4933) DEBUG: name = flip
last_ts = 397.126648
last_received_ts = 397.126190
last_seq = 13658
current_ts = 397.143280
current_received_ts = 397.142822
current_seq = 13659
count = 59
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 8us
(kms_flip:4933) DEBUG: name = flip
last_ts = 397.143280
last_received_ts = 397.142822
last_seq = 13659
current_ts = 397.159912
current_received_ts = 397.159485
current_seq = 13660
count = 60
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 9us
(kms_flip:4933) DEBUG: name = flip
last_ts = 397.159912
last_received_ts = 397.159485
last_seq = 13660
current_ts = 397.176544
current_received_ts = 397.176117
current_seq = 13661
count = 61
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 8us
(kms_flip:4933) DEBUG: name = flip
last_ts = 397.176544
last_received_ts = 397.176117
last_seq = 13661
current_ts = 397.193176
current_received_ts = 397.192749
current_seq = 13662
count = 62
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 8us
(kms_flip:4933) DEBUG: name = flip
last_ts = 397.193176
last_received_ts = 397.192749
last_seq = 13662
current_ts = 397.209808
current_received_ts = 397.209381
current_seq = 13663
count = 63
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 9us
(kms_flip:4933) DEBUG: name = flip
last_ts = 397.209808
last_received_ts = 397.209381
last_seq = 13663
current_ts = 397.226440
current_received_ts = 397.226044
current_seq = 13664
count = 64
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 8us
(kms_flip:4933) DEBUG: name = flip
last_ts = 397.226440
last_received_ts = 397.226044
last_seq = 13664
current_ts = 397.243103
current_received_ts = 397.242676
current_seq = 13665
count = 65
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 8us
(kms_flip:4933) DEBUG: name = flip
last_ts = 397.243103
last_received_ts = 397.242676
last_seq = 13665
current_ts = 397.259735
current_received_ts = 397.259308
current_seq = 13666
count = 66
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 9us
(kms_flip:4933) DEBUG: name = flip
last_ts = 397.259735
last_received_ts = 397.259308
last_seq = 13666
current_ts = 397.276367
current_received_ts = 397.275909
current_seq = 13667
count = 67
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 8us
(kms_flip:4933) DEBUG: name = flip
last_ts = 397.276367
last_received_ts = 397.275909
last_seq = 13667
current_ts = 397.292999
current_received_ts = 397.292572
current_seq = 13668
count = 68
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 8us
(kms_flip:4933) DEBUG: name = flip
last_ts = 397.292999
last_received_ts = 397.292572
last_seq = 13668
current_ts = 397.309631
current_received_ts = 397.309204
current_seq = 13669
count = 69
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 8us
(kms_flip:4933) DEBUG: name = flip
last_ts = 397.309631
last_received_ts = 397.309204
last_seq = 13669
current_ts = 397.326294
current_received_ts = 397.325836
current_seq = 13670
count = 70
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 9us
(kms_flip:4933) DEBUG: name = flip
last_ts = 397.326294
last_received_ts = 397.325836
last_seq = 13670
current_ts = 397.342926
current_received_ts = 397.342499
current_seq = 13671
count = 71
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 9us
(kms_flip:4933) DEBUG: name = flip
last_ts = 397.342926
last_received_ts = 397.342499
last_seq = 13671
current_ts = 397.359558
current_received_ts = 397.359131
current_seq = 13672
count = 72
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 8us
(kms_flip:4933) DEBUG: name = flip
last_ts = 397.359558
last_received_ts = 397.359131
last_seq = 13672
current_ts = 397.376190
current_received_ts = 397.375702
current_seq = 13673
count = 73
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 9us
(kms_flip:4933) DEBUG: name = flip
last_ts = 397.376190
last_received_ts = 397.375702
last_seq = 13673
current_ts = 397.392822
current_received_ts = 397.392395
current_seq = 13674
count = 74
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 8us
(kms_flip:4933) DEBUG: name = flip
last_ts = 397.392822
last_received_ts = 397.392395
last_seq = 13674
current_ts = 397.409454
current_received_ts = 397.409058
current_seq = 13675
count = 75
seq_step = 1
(kms_flip:4933) DEBUG: Vblank took 675us
(kms_flip:4933) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931:
(kms_flip:4933) CRITICAL: Failed assertion: end - start < 500
(kms_flip:4933) igt_core-INFO: Stack trace:
(kms_flip:4933) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:4933) igt_core-INFO:   #1 ../tests/kms_flip.c:926 run_test_on_crtc_set()
(kms_flip:4933) igt_core-INFO:   #2 ../tests/kms_flip.c:1920 run_test()
(kms_flip:4933) igt_core-INFO:   #3 ../tests/kms_flip.c:2184 __igt_unique____real_main2105()
(kms_flip:4933) igt_core-INFO:   #4 ../tests/kms_flip.c:2105 main()
(kms_flip:4933) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:4933) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:4933) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest B-HDMI-A1: FAIL (1.554s)
Subtest flip-vs-expired-vblank: FAIL (24.807s)
(kms_flip:4933) igt_kms-CRITICAL: Test assertion failure function kmstest_set_connector_dpms, file ../lib/igt_kms.c:2352:
(kms_flip:4933) igt_kms-CRITICAL: Failed assertion: found_it
(kms_flip:4933) igt_kms-CRITICAL: Last errno: 9, Bad file descriptor
(kms_flip:4933) igt_kms-CRITICAL: DPMS property not found on 76

Dmesg

<6> [395.849128] [IGT] kms_flip: starting dynamic subtest B-HDMI-A1

<7> [395.849695] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:90]

<7> [395.849839] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:91]

<7> [395.902201] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:53:pipe A]

<7> [395.902413] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.enable (expected yes, found no)

<7> [395.902989] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.active (expected yes, found no)

<7> [395.903620] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)

<7> [395.904174] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in has_pch_encoder (expected yes, found no)

<7> [395.904710] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in fdi_lanes (expected 3, found 0)

<7> [395.905262] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in fdi_m_n (expected tu 64 data 6934582/8388608 link 288940/524288, found tu 0, data 0/0 link 0/0)

<7> [395.905752] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in lane_count (expected 4, found 0)

<7> [395.906032] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)

<7> [395.906264] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)

<7> [395.906494] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)

<7> [395.906750] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)

<7> [395.907029] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)

<7> [395.907258] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)

<7> [395.907487] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)

<7> [395.907748] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)

<7> [395.908022] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)

<7> [395.908261] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)

<7> [395.908497] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)

<7> [395.908765] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)

<7> [395.909044] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)

<7> [395.909273] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)

<7> [395.909503] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)

<7> [395.909773] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)

<7> [395.910038] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)

<7> [395.910268] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)

<7> [395.910497] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)

<7> [395.910758] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)

<7> [395.911041] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)

<7> [395.911277] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)

<7> [395.911513] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)

<7> [395.911774] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)

<7> [395.912052] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)

<7> [395.912289] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)

<7> [395.912523] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)

<7> [395.912795] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)

<7> [395.913050] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)

<7> [395.913292] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in has_hdmi_sink (expected yes, found no)

<7> [395.913561] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in has_infoframe (expected yes, found no)

<7> [395.913808] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in pipe_bpp (expected 36, found 0)

<7> [395.914051] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148800, found 0)

<7> [395.914286] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148800, found 0)

<7> [395.914522] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in port_clock (expected 223200, found 0)

<7> [395.914794] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)

<7> [395.915055] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in infoframes.gcp (expected 0x00000006, found 0x00000000)

<7> [395.915298] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in avi infoframe

<7> [395.915571] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:

<7> [395.915817] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13

<7> [395.915820] i915 0000:00:02.0: colorspace: RGB

<7> [395.915822] i915 0000:00:02.0: scan mode: Underscan

<7> [395.915824] i915 0000:00:02.0: colorimetry: No Data

<7> [395.915826] i915 0000:00:02.0: picture aspect: 16:9

<7> [395.915828] i915 0000:00:02.0: active aspect: Same as Picture

<7> [395.915830] i915 0000:00:02.0: itc: No Data

<7> [395.915832] i915 0000:00:02.0: extended colorimetry: xvYCC 601

<7> [395.915834] i915 0000:00:02.0: quantization range: Default

<7> [395.915836] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling

<7> [395.915838] i915 0000:00:02.0: video code: 16

<7> [395.915840] i915 0000:00:02.0: ycc quantization range: Full

<7> [395.915842] i915 0000:00:02.0: hdmi content type: Graphics

<7> [395.915844] i915 0000:00:02.0: pixel repeat: 0

<7> [395.915846] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0

<7> [395.915848] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:

<7> [395.916077] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in spd infoframe

<7> [395.916320] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:

<7> [395.916578] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25

<7> [395.916583] i915 0000:00:02.0: vendor: Intel

<7> [395.916587] i915 0000:00:02.0: product: Integrated gfx

<7> [395.916590] i915 0000:00:02.0: source device information: PC General (0x9)

<7> [395.916595] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:

<7> [395.916863] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hdmi infoframe

<7> [395.917099] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:

<7> [395.917334] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4

<7> [395.917336] i915 0000:00:02.0: empty frame

<7> [395.917339] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:

<7> [395.917615] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:53:pipe A] fastset requirement not met, forcing full modeset

<7> [395.917876] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:53:pipe A] releasing PCH DPLL A

<7> [395.918139] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:53:pipe A] min cdclk: 165334 kHz -> 0 kHz

<7> [395.918380] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:53:pipe A] enable: no [modeset]

<7> [395.918679] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:primary A] fb: [NOFB], visible: no

<7> [395.918972] i915 0000:00:02.0: [drm:intel_audio_codec_disable [i915]] [CONNECTOR:76:HDMI-A-1][ENCODER:75:HDMI D] Disable audio codec on [CRTC:53:pipe A]

<7> [395.946333] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A

<7> [395.963203] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port B found

<7> [395.963714] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port C found

<7> [395.964179] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port D found

<7> [395.975806] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable PCH DPLL A (active 0x1, on? 1) for [CRTC:53:pipe A]

<7> [395.976322] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling PCH DPLL A

<7> [395.977069] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:CRT]

<7> [395.977544] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:75:HDMI D]

<7> [395.978037] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:83:DP D]

<7> [395.978480] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:76:HDMI-A-1]

<7> [395.979099] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:53:pipe A]

<7> [395.979764] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:72:pipe B]

<7> [395.979915] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:72:pipe B]

<7> [395.979943] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:76:HDMI-A-1]

<7> [395.980038] i915 0000:00:02.0: [drm:intel_hdmi_compute_output_format [i915]] picking 12 bpc for HDMI output (pipe bpp: 36)

<7> [395.980293] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:72:pipe B] hw max bpp: 36, pipe bpp: 36, dithering: 0

<7> [395.980550] i915 0000:00:02.0: [drm:intel_fdi_atomic_check_link [i915]] checking fdi config on pipe B, lanes 3

<7> [395.980805] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.enable (expected no, found yes)

<7> [395.981043] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.active (expected no, found yes)

<7> [395.981279] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in cpu_transcoder (expected -1, found 1)

<7> [395.981535] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in has_pch_encoder (expected no, found yes)

<7> [395.981799] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in fdi_lanes (expected 0, found 3)

<7> [395.982035] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in fdi_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 6934582/8388608 link 288940/524288)

<7> [395.982273] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in lane_count (expected 0, found 4)

<7> [395.982509] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)

<7> [395.982777] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in framestart_delay (expected 0, found 1)

<7> [395.983030] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)

<7> [395.983266] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)

<7> [395.983502] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)

<7> [395.983759] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)

<7> [395.984012] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)

<7> [395.984248] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)

<7> [395.984482] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)

<7> [395.984750] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)

<7> [395.985011] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)

<7> [395.985245] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)

<7> [395.985481] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)

<7> [395.985737] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)

<7> [395.986002] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)

<7> [395.986242] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)

<7> [395.986478] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)

<7> [395.986735] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)

<7> [395.986994] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)

<7> [395.987230] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)

<7> [395.987466] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)

<7> [395.987737] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)

<7> [395.987987] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)

<7> [395.988223] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)

<7> [395.988458] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)

<7> [395.988713] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)

<7> [395.988968] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in pixel_multiplier (expected 0, found 1)

<7> [395.989201] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)

<7> [395.989436] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)

<7> [395.989704] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in has_hdmi_sink (expected no, found yes)

<7> [395.989960] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in has_infoframe (expected no, found yes)

<7> [395.990196] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in dpll_hw_state

<7> [395.990430] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:

<7> [395.990697] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: dpll: 0x84100010, dpll_md: 0x0, fp0: 0x10c09, fp1: 0x10c09

<7> [395.990997] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:

<7> [395.991232] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: dpll: 0xc4010001, dpll_md: 0x0, fp0: 0x30f06, fp1: 0x30f06

<7> [395.991467] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in pipe_bpp (expected 0, found 36)

<7> [395.991766] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148800)

<7> [395.992017] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148800)

<7> [395.992253] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in port_clock (expected 0, found 223200)

<7> [395.992489] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071)

<7> [395.992761] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in infoframes.gcp (expected 0x00000000, found 0x00000006)

<7> [395.993019] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in avi infoframe

<7> [395.993255] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:

<7> [395.993489] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:

<7> [395.993773] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13

<7> [395.993778] i915 0000:00:02.0: colorspace: RGB

<7> [395.993782] i915 0000:00:02.0: scan mode: Underscan

<7> [395.993785] i915 0000:00:02.0: colorimetry: No Data

<7> [395.993789] i915 0000:00:02.0: picture aspect: 16:9

<7> [395.993792] i915 0000:00:02.0: active aspect: Same as Picture

<7> [395.993796] i915 0000:00:02.0: itc: No Data

<7> [395.993800] i915 0000:00:02.0: extended colorimetry: xvYCC 601

<7> [395.993803] i915 0000:00:02.0: quantization range: Default

<7> [395.993807] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling

<7> [395.993811] i915 0000:00:02.0: video code: 16

<7> [395.993815] i915 0000:00:02.0: ycc quantization range: Full

<7> [395.993819] i915 0000:00:02.0: hdmi content type: Graphics

<7> [395.993823] i915 0000:00:02.0: pixel repeat: 0

<7> [395.993826] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0

<7> [395.993831] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in spd infoframe

<7> [395.994087] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:

<7> [395.994321] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:

<7> [395.994594] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25

<7> [395.994599] i915 0000:00:02.0: vendor: Intel

<7> [395.994603] i915 0000:00:02.0: product: Integrated gfx

<7> [395.994607] i915 0000:00:02.0: source device information: PC General (0x9)

<7> [395.994611] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hdmi infoframe

<7> [395.994892] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:

<7> [395.995126] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:

<7> [395.995361] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4

<7> [395.995363] i915 0000:00:02.0: empty frame

<7> [395.995366] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:72:pipe B] fastset requirement not met, forcing full modeset

<7> [395.995682] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:72:pipe B] min cdclk: 0 kHz -> 165334 kHz

<7> [395.995923] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:72:pipe B] allocated PCH DPLL A

<7> [395.996157] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:72:pipe B] reserving PCH DPLL A

<7> [395.996393] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:72:pipe B] enable: yes [modeset]

<7> [395.996651] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB

<7> [395.996915] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: B, pipe bpp: 36, dithering: 0

<7> [395.997149] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>

<7> [395.997384] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0

<7> [395.997653] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0

<7> [395.997665] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling always-on

<7> [395.997922] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0

<7> [395.998157] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] fdi: lanes: 3; data_m: 6934582, data_n: 8388608, link_m: 288940, link_n: 524288, tu: 64

<7> [395.998392] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 1, infoframes enabled: 0x71

<7> [395.998690] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] GCP: 0x6

<7> [395.998942] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13

<7> [395.998945] i915 0000:00:02.0: colorspace: RGB

<7> [395.998948] i915 0000:00:02.0: scan mode: Underscan

<7> [395.998950] i915 0000:00:02.0: colorimetry: No Data

<7> [395.998952] i915 0000:00:02.0: picture aspect: 16:9

<7> [395.998954] i915 0000:00:02.0: active aspect: Same as Picture

<7> [395.998956] i915 0000:00:02.0: itc: No Data

<7> [395.998958] i915 0000:00:02.0: extended colorimetry: xvYCC 601

<7> [395.998960] i915 0000:00:02.0: quantization range: Default

<7> [395.998962] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling

<7> [395.998964] i915 0000:00:02.0: video code: 16

<7> [395.998966] i915 0000:00:02.0: ycc quantization range: Full

<7> [395.998968] i915 0000:00:02.0: hdmi content type: Graphics

<7> [395.998970] i915 0000:00:02.0: pixel repeat: 0

<7> [395.998972] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0

<7> [395.998975] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25

<7> [395.998977] i915 0000:00:02.0: vendor: Intel

<7> [395.998979] i915 0000:00:02.0: product: Integrated gfx

<7> [395.998981] i915 0000:00:02.0: source device information: PC General (0x9)

<7> [395.998984] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4

<7> [395.998986] i915 0000:00:02.0: empty frame

<7> [395.998988] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 10 00 08 00 65 32 00 01 00 00 00 00 00 00 00 00

<7> [395.999223] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 61 a4 9a 00 4d 69 20 54 56 09 07 07 11 17 50 51

<7> [395.999457] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 07 00 00 00

<7> [395.999715] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 1

<7> [396.000005] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0

<7> [396.000238] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: no, vmin: 0, vmax: 0, flipline: 0, pipeline full: 0, guardband: 0 vsync start: 0, vsync end: 0

<7> [396.000473] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 0, vmax vblank: 0, vmin vtotal: 0, vmax vtotal: 0

<7> [396.000742] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0

<7> [396.000998] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5

<7> [396.001234] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5

<7> [396.001469] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=148800, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5

<7> [396.001749] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 60 148800 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5

<7> [396.002008] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=148800, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5

<7> [396.002245] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 223200, pixel rate 148800, min cdclk 165334, min voltage level 0

<7> [396.002479] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 0, ips linetime: 0

<7> [396.002761] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1920x1080+0+0

<7> [396.003004] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no

<7> [396.003242] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0

<7> [396.003476] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0

<7> [396.003728] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: dpll: 0xc4010001, dpll_md: 0x0, fp0: 0x30f06, fp1: 0x30f06

<7> [396.003988] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x3 gamma_mode: 0x0 gamma_enable: 1 csc_enable: 0

<7> [396.004222] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 256 entries

<7> [396.004456] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000

<7> [396.004717] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000

<7> [396.004981] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000

<7> [396.005215] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000

<7> [396.005449] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:54:primary B] fb: [FB:90] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes

<7> [396.005731] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0

<7> [396.005984] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0

<7> [396.008259] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling always-on

<7> [396.008529] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:CRT]

<7> [396.008805] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:75:HDMI D]

<7> [396.009028] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:83:DP D]

<7> [396.009881] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe B

<7> [396.010939] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x100

<7> [396.011177] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 1 done.

<7> [396.012067] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x200

<7> [396.012308] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 2 done.

<7> [396.012571] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train done.

<7> [396.012837] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable PCH DPLL A (active 0x2, on? 0) for [CRTC:72:pipe B]

<7> [396.013064] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling PCH DPLL A

<7> [396.064798] i915 0000:00:02.0: [drm:intel_audio_codec_enable [i915]] [CONNECTOR:76:HDMI-A-1][ENCODER:75:HDMI D] Enable audio codec on [CRTC:72:pipe B], 36 bytes ELD

<7> [396.081434] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] HDMI audio pixel clock setting for 148800 not found, falling back to defaults

<7> [396.081963] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] Configuring HDMI audio for pixel clock 25200 (0x00010000)

<7> [396.082465] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:76:HDMI-A-1]

<7> [396.083077] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:72:pipe B]

<6> [397.404116] [IGT] kms_flip: finished subtest B-HDMI-A1, FAIL

<6> [397.405259] [IGT] kms_flip: finished subtest flip-vs-expired-vblank, FAIL

<7> [397.420848] i915 0000:00:02.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:91] from all active usage due to RMFB ioctl

<7> [397.421277] i915 0000:00:02.0: [drm:atomic_remove_fb] Disabling [PLANE:54:primary B] because [FB:91] is removed

<6> [397.430245] [IGT] kms_flip: exiting, ret=98

<7> [397.451006] i915 0000:00:02.0: [drm:compute_baseline_pipe_bpp [i915]] [CONNECTOR:73:VGA-1] Limiting target display pipe bpp to 24 (EDID bpp 0, max requested bpp 0, max platform bpp 36)

<7> [397.451326] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:53:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0

<7> [397.451561] i915 0000:00:02.0: [drm:intel_fdi_atomic_check_link [i915]] checking fdi config on pipe A, lanes 1

<7> [397.451788] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.enable (expected no, found yes)

<7> [397.452019] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.active (expected no, found yes)

<7> [397.452382] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)

<7> [397.452659] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in has_pch_encoder (expected no, found yes)

<7> [397.452890] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in fdi_lanes (expected 0, found 1)

<7> [397.453143] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in fdi_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 6039797/8388608 link 125829/524288)

<7> [397.453416] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000002)

<7> [397.453646] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)

<7> [397.453876] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1024)

<7> [397.454106] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 1344)

<7> [397.454370] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1024)

<7> [397.454647] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 1344)

<7> [397.454877] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 1048)

<7> [397.455107] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 1184)

<7> [397.455380] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 768)

<7> [397.455643] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 768)

<7> [397.455873] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 771)

<7> [397.456111] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 777)

<7> [397.456549] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 806)

<7> [397.456781] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 806)

<7> [397.457011] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1024)

<7> [397.457303] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 1344)

<7> [397.457559] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1024)

<7> [397.457789] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 1344)

<7> [397.458018] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 1048)

<7> [397.458283] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 1184)

<7> [397.458529] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 768)

<7> [397.458759] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 768)

<7> [397.458988] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 771)

<7> [397.459256] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 777)

<7> [397.459502] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 806)

<7> [397.459732] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 806)

<7> [397.459961] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)

<7> [397.460225] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)

<7> [397.460474] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)

<7> [397.460705] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in dpll_hw_state

<7> [397.460933] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:

<7> [397.461237] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: dpll: 0xc4010001, dpll_md: 0x0, fp0: 0x30f06, fp1: 0x30f06

<7> [397.461596] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:

<7> [397.461948] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: dpll: 0x84100010, dpll_md: 0x0, fp0: 0x10c09, fp1: 0x10c09

<7> [397.462328] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)

<7> [397.462636] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 64800)

<7> [397.462865] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 64800)

<7> [397.463095] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in port_clock (expected 0, found 64800)

<7> [397.463371] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:53:pipe A] fastset requirement not met, forcing full modeset

<7> [397.463654] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:53:pipe A] min cdclk: 0 kHz -> 72000 kHz

<7> [397.463886] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:53:pipe A] allocated PCH DPLL B

<7> [397.464134] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:53:pipe A] reserving PCH DPLL B

<7> [397.464393] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:53:pipe A] enable: yes [modeset]

<7> [397.464622] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: ANALOG (0x2), output format: RGB, sink format: RGB

<7> [397.464851] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0

<7> [397.465079] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>

<7> [397.465364] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0

<7> [397.465603] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0

<7> [397.465831] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0

<7> [397.466059] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] fdi: lanes: 1; data_m: 6039797, data_n: 8388608, link_m: 125829, link_n: 524288, tu: 64

<7> [397.466341] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0

<7> [397.466586] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 1

<7> [397.466814] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0

<7> [397.467042] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: no, vmin: 0, vmax: 0, flipline: 0, pipeline full: 0, guardband: 0 vsync start: 0, vsync end: 0

<7> [397.467301] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 0, vmax vblank: 0, vmin vtotal: 0, vmax vtotal: 0

<7> [397.467546] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0

<7> [397.467775] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa

<7> [397.468005] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa

<7> [397.468268] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=64800, hd=1024 hb=1024-1344 hs=1048-1184 ht=1344, vd=768 vb=768-806 vs=771-777 vt=806, flags=0xa

<7> [397.468516] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1024x768": 60 64800 1024 1048 1184 1344 768 771 777 806 0x40 0xa

<7> [397.468745] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=64800, hd=1024 hb=1024-1344 hs=1048-1184 ht=1344, vd=768 vb=768-806 vs=771-777 vt=806, flags=0xa

<7> [397.468974] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 64800, pixel rate 64800, min cdclk 72000, min voltage level 0

<7> [397.469293] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 0, ips linetime: 0

<7> [397.469555] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1024x768+0+0

<7> [397.469784] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no

<7> [397.470012] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0

<7> [397.470273] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0

<7> [397.470519] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: dpll: 0x84100010, dpll_md: 0x0, fp0: 0x10c09, fp1: 0x10c09

<7> [397.470747] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x3 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0

<7> [397.470976] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries

<7> [397.471243] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000

<7> [397.471488] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000

<7> [397.471716] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000

<7> [397.471944] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000

<7> [397.472203] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:primary A] fb: [FB:89] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes

<7> [397.472450] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0

<7> [397.472678] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1024.000000x768.000000+0.000000+0.000000 dst: 1024x768+0+0

<7> [397.472908] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:40:sprite A] fb: [NOFB], visible: no

<7> [397.473170] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:47:cursor A] fb: [NOFB], visible: no

<7> [397.473501] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:CRT]

<7> [397.473725] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:75:HDMI D]

<7> [397.473946] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:83:DP D]

<7> [397.474786] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A

<7> [397.475853] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x100

<7> [397.476078] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 1 done.

<7> [397.477006] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x200

<7> [397.477269] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 2 done.

<7> [397.477492] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train done.

<7> [397.477720] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable PCH DPLL B (active 0x1, on? 0) for [CRTC:53:pipe A]

<7> [397.477947] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling PCH DPLL B

<7> [397.529950] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:73:VGA-1]

<7> [397.530590] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:53:pipe A]

<7> [397.531315] i915 0000:00:02.0: [drm:drm_client_dev_restore] fbdev: ret=0

Results for igt@kms_flip@flip-vs-expired-vblank@b-hdmi-a1