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

Detail	Value
Duration	1.07 seconds
Hostname	shard-snb1
Igt-Version	IGT-Version: 2.3-g33f5420a2 (x86_64) (Linux: 7.0.0-rc4-Patchwork_163332v1-ga25d8c583e1e+ 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:921 run_test_on_crtc_set.constprop.0() #2 ../tests/kms_flip.c:1887 run_test() #3 ../tests/kms_flip.c:2122 __igt_unique____real_main2043() #4 ../tests/kms_flip.c:2043 main() #5 [__libc_init_first+0x8a] #6 [__libc_start_main+0x8b] #7 [_start+0x25] Dynamic subtest B-HDMI-A1: FAIL (1.066s) Subtest flip-vs-expired-vblank: FAIL (24.327s) Stack trace: #0 ../lib/igt_core.c:2075 __igt_fail_assert() #1 ../lib/igt_kms.c:2351 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:2709) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:926: (kms_flip:2709) CRITICAL: Failed assertion: end - start < 500 Dynamic subtest B-HDMI-A1 failed. ** DEBUG (kms_flip:2709) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=XR24(0x34325258), modifier=0x100000000000001, size=0) (kms_flip:2709) drmtest-DEBUG: Test requirement passed: is_intel_device(fd) (kms_flip:2709) drmtest-DEBUG: Test requirement passed: is_intel_device(fd) (kms_flip:2709) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=1, pitch=7680) (kms_flip:2709) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd) (kms_flip:2709) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=XR24(0x34325258), modifier=0x100000000000001, size=0) (kms_flip:2709) drmtest-DEBUG: Test requirement passed: is_intel_device(fd) (kms_flip:2709) drmtest-DEBUG: Test requirement passed: is_intel_device(fd) (kms_flip:2709) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=2, pitch=7680) (kms_flip:2709) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd) (kms_flip:2709) intel_blt-DEBUG: Test requirement passed: cmds_info (kms_flip:2709) intel_blt-DEBUG: Test requirement passed: cmds_info (kms_flip:2709) igt_fb-DEBUG: Test requirement passed: cairo_surface_status(fb->cairo_surface) == CAIRO_STATUS_SUCCESS (kms_flip:2709) intel_blt-DEBUG: Test requirement passed: cmds_info (kms_flip:2709) intel_blt-DEBUG: Test requirement passed: cmds_info (kms_flip:2709) igt_fb-DEBUG: Test requirement passed: cairo_surface_status(fb->cairo_surface) == CAIRO_STATUS_SUCCESS (kms_flip:2709) igt_kms-INFO: 1920x1080: 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5 (kms_flip:2709) DEBUG: No stale events found (kms_flip:2709) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0 (kms_flip:2709) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0 (kms_flip:2709) DEBUG: name = flip last_ts = 0.000000 last_received_ts = 0.000000 last_seq = 0 current_ts = 337.529663 current_received_ts = 337.529175 current_seq = 10180 count = 0 seq_step = 1 (kms_flip:2709) DEBUG: Vblank took 9us (kms_flip:2709) DEBUG: name = flip last_ts = 337.529663 last_received_ts = 337.529175 last_seq = 10180 current_ts = 337.546295 current_received_ts = 337.545776 current_seq = 10181 count = 1 seq_step = 1 (kms_flip:2709) DEBUG: Vblank took 8us (kms_flip:2709) DEBUG: name = flip last_ts = 337.546295 last_received_ts = 337.545776 last_seq = 10181 current_ts = 337.562927 current_received_ts = 337.562439 current_seq = 10182 count = 2 seq_step = 1 (kms_flip:2709) DEBUG: Vblank took 8us (kms_flip:2709) DEBUG: name = flip last_ts = 337.562927 last_received_ts = 337.562439 last_seq = 10182 current_ts = 337.579529 current_received_ts = 337.579071 current_seq = 10183 count = 3 seq_step = 1 (kms_flip:2709) DEBUG: Vblank took 8us (kms_flip:2709) DEBUG: name = flip last_ts = 337.579529 last_received_ts = 337.579071 last_seq = 10183 current_ts = 337.596161 current_received_ts = 337.595703 current_seq = 10184 count = 4 seq_step = 1 (kms_flip:2709) DEBUG: Vblank took 8us (kms_flip:2709) DEBUG: name = flip last_ts = 337.596161 last_received_ts = 337.595703 last_seq = 10184 current_ts = 337.612793 current_received_ts = 337.612305 current_seq = 10185 count = 5 seq_step = 1 (kms_flip:2709) DEBUG: Vblank took 7us (kms_flip:2709) DEBUG: name = flip last_ts = 337.612793 last_received_ts = 337.612305 last_seq = 10185 current_ts = 337.629425 current_received_ts = 337.628937 current_seq = 10186 count = 6 seq_step = 1 (kms_flip:2709) DEBUG: Vblank took 8us (kms_flip:2709) DEBUG: name = flip last_ts = 337.629425 last_received_ts = 337.628937 last_seq = 10186 current_ts = 337.646057 current_received_ts = 337.645569 current_seq = 10187 count = 7 seq_step = 1 (kms_flip:2709) DEBUG: Vblank took 7us (kms_flip:2709) DEBUG: name = flip last_ts = 337.646057 last_received_ts = 337.645569 last_seq = 10187 current_ts = 337.662659 current_received_ts = 337.662201 current_seq = 10188 count = 8 seq_step = 1 (kms_flip:2709) DEBUG: Vblank took 8us (kms_flip:2709) DEBUG: name = flip last_ts = 337.662659 last_received_ts = 337.662201 last_seq = 10188 current_ts = 337.679291 current_received_ts = 337.678833 current_seq = 10189 count = 9 seq_step = 1 (kms_flip:2709) DEBUG: Vblank took 8us (kms_flip:2709) DEBUG: name = flip last_ts = 337.679291 last_received_ts = 337.678833 last_seq = 10189 current_ts = 337.695923 current_received_ts = 337.695465 current_seq = 10190 count = 10 seq_step = 1 (kms_flip:2709) DEBUG: Vblank took 8us (kms_flip:2709) DEBUG: name = flip last_ts = 337.695923 last_received_ts = 337.695465 last_seq = 10190 current_ts = 337.712555 current_received_ts = 337.712067 current_seq = 10191 count = 11 seq_step = 1 (kms_flip:2709) DEBUG: Vblank took 8us (kms_flip:2709) DEBUG: name = flip last_ts = 337.712555 last_received_ts = 337.712067 last_seq = 10191 current_ts = 337.729156 current_received_ts = 337.728729 current_seq = 10192 count = 12 seq_step = 1 (kms_flip:2709) DEBUG: Vblank took 8us (kms_flip:2709) DEBUG: name = flip last_ts = 337.729156 last_received_ts = 337.728729 last_seq = 10192 current_ts = 337.745789 current_received_ts = 337.745361 current_seq = 10193 count = 13 seq_step = 1 (kms_flip:2709) DEBUG: Vblank took 8us (kms_flip:2709) DEBUG: name = flip last_ts = 337.745789 last_received_ts = 337.745361 last_seq = 10193 current_ts = 337.762421 current_received_ts = 337.761993 current_seq = 10194 count = 14 seq_step = 1 (kms_flip:2709) DEBUG: Vblank took 41us (kms_flip:2709) DEBUG: name = flip last_ts = 337.762421 last_received_ts = 337.761993 last_seq = 10194 current_ts = 337.779053 current_received_ts = 337.778595 current_seq = 10195 count = 15 seq_step = 1 (kms_flip:2709) DEBUG: Vblank took 8us (kms_flip:2709) DEBUG: name = flip last_ts = 337.779053 last_received_ts = 337.778595 last_seq = 10195 current_ts = 337.795685 current_received_ts = 337.795227 current_seq = 10196 count = 16 seq_step = 1 (kms_flip:2709) DEBUG: Vblank took 8us (kms_flip:2709) DEBUG: name = flip last_ts = 337.795685 last_received_ts = 337.795227 last_seq = 10196 current_ts = 337.812317 current_received_ts = 337.811829 current_seq = 10197 count = 17 seq_step = 1 (kms_flip:2709) DEBUG: Vblank took 8us (kms_flip:2709) DEBUG: name = flip last_ts = 337.812317 last_received_ts = 337.811829 last_seq = 10197 current_ts = 337.828918 current_received_ts = 337.828491 current_seq = 10198 count = 18 seq_step = 1 (kms_flip:2709) DEBUG: Vblank took 8us (kms_flip:2709) DEBUG: name = flip last_ts = 337.828918 last_received_ts = 337.828491 last_seq = 10198 current_ts = 337.845551 current_received_ts = 337.845154 current_seq = 10199 count = 19 seq_step = 1 (kms_flip:2709) DEBUG: Vblank took 8us (kms_flip:2709) DEBUG: name = flip last_ts = 337.845551 last_received_ts = 337.845154 last_seq = 10199 current_ts = 337.862183 current_received_ts = 337.861725 current_seq = 10200 count = 20 seq_step = 1 (kms_flip:2709) DEBUG: Vblank took 8us (kms_flip:2709) DEBUG: name = flip last_ts = 337.862183 last_received_ts = 337.861725 last_seq = 10200 current_ts = 337.878784 current_received_ts = 337.878357 current_seq = 10201 count = 21 seq_step = 1 (kms_flip:2709) DEBUG: Vblank took 7us (kms_flip:2709) DEBUG: name = flip last_ts = 337.878784 last_received_ts = 337.878357 last_seq = 10201 current_ts = 337.895416 current_received_ts = 337.894958 current_seq = 10202 count = 22 seq_step = 1 (kms_flip:2709) DEBUG: Vblank took 9us (kms_flip:2709) DEBUG: name = flip last_ts = 337.895416 last_received_ts = 337.894958 last_seq = 10202 current_ts = 337.912048 current_received_ts = 337.911560 current_seq = 10203 count = 23 seq_step = 1 (kms_flip:2709) DEBUG: Vblank took 8us (kms_flip:2709) DEBUG: name = flip last_ts = 337.912048 last_received_ts = 337.911560 last_seq = 10203 current_ts = 337.928680 current_received_ts = 337.928223 current_seq = 10204 count = 24 seq_step = 1 (kms_flip:2709) DEBUG: Vblank took 8us (kms_flip:2709) DEBUG: name = flip last_ts = 337.928680 last_received_ts = 337.928223 last_seq = 10204 current_ts = 337.945312 current_received_ts = 337.944855 current_seq = 10205 count = 25 seq_step = 1 (kms_flip:2709) DEBUG: Vblank took 8us (kms_flip:2709) DEBUG: name = flip last_ts = 337.945312 last_received_ts = 337.944855 last_seq = 10205 current_ts = 337.961914 current_received_ts = 337.961456 current_seq = 10206 count = 26 seq_step = 1 (kms_flip:2709) DEBUG: Vblank took 8us (kms_flip:2709) DEBUG: name = flip last_ts = 337.961914 last_received_ts = 337.961456 last_seq = 10206 current_ts = 337.978577 current_received_ts = 337.978088 current_seq = 10207 count = 27 seq_step = 1 (kms_flip:2709) DEBUG: Vblank took 8us (kms_flip:2709) DEBUG: name = flip last_ts = 337.978577 last_received_ts = 337.978088 last_seq = 10207 current_ts = 337.995178 current_received_ts = 337.994720 current_seq = 10208 count = 28 seq_step = 1 (kms_flip:2709) DEBUG: Vblank took 9us (kms_flip:2709) DEBUG: name = flip last_ts = 337.995178 last_received_ts = 337.994720 last_seq = 10208 current_ts = 338.011810 current_received_ts = 338.011353 current_seq = 10209 count = 29 seq_step = 1 (kms_flip:2709) DEBUG: Vblank took 8us (kms_flip:2709) DEBUG: name = flip last_ts = 338.011810 last_received_ts = 338.011353 last_seq = 10209 current_ts = 338.028442 current_received_ts = 338.027985 current_seq = 10210 count = 30 seq_step = 1 (kms_flip:2709) DEBUG: Vblank took 37us (kms_flip:2709) DEBUG: name = flip last_ts = 338.028442 last_received_ts = 338.027985 last_seq = 10210 current_ts = 338.045044 current_received_ts = 338.044586 current_seq = 10211 count = 31 seq_step = 1 (kms_flip:2709) DEBUG: Vblank took 9us (kms_flip:2709) DEBUG: name = flip last_ts = 338.045044 last_received_ts = 338.044586 last_seq = 10211 current_ts = 338.061676 current_received_ts = 338.061218 current_seq = 10212 count = 32 seq_step = 1 (kms_flip:2709) DEBUG: Vblank took 9us (kms_flip:2709) DEBUG: name = flip last_ts = 338.061676 last_received_ts = 338.061218 last_seq = 10212 current_ts = 338.078308 current_received_ts = 338.077850 current_seq = 10213 count = 33 seq_step = 1 (kms_flip:2709) DEBUG: Vblank took 9us (kms_flip:2709) DEBUG: name = flip last_ts = 338.078308 last_received_ts = 338.077850 last_seq = 10213 current_ts = 338.094940 current_received_ts = 338.094482 current_seq = 10214 count = 34 seq_step = 1 (kms_flip:2709) DEBUG: Vblank took 9us (kms_flip:2709) DEBUG: name = flip last_ts = 338.094940 last_received_ts = 338.094482 last_seq = 10214 current_ts = 338.111572 current_received_ts = 338.111115 current_seq = 10215 count = 35 seq_step = 1 (kms_flip:2709) DEBUG: Vblank took 9us (kms_flip:2709) DEBUG: name = flip last_ts = 338.111572 last_received_ts = 338.111115 last_seq = 10215 current_ts = 338.128174 current_received_ts = 338.127716 current_seq = 10216 count = 36 seq_step = 1 (kms_flip:2709) DEBUG: Vblank took 8us (kms_flip:2709) DEBUG: name = flip last_ts = 338.128174 last_received_ts = 338.127716 last_seq = 10216 current_ts = 338.144806 current_received_ts = 338.144379 current_seq = 10217 count = 37 seq_step = 1 (kms_flip:2709) DEBUG: Vblank took 8us (kms_flip:2709) DEBUG: name = flip last_ts = 338.144806 last_received_ts = 338.144379 last_seq = 10217 current_ts = 338.161438 current_received_ts = 338.161011 current_seq = 10218 count = 38 seq_step = 1 (kms_flip:2709) DEBUG: Vblank took 12us (kms_flip:2709) DEBUG: name = flip last_ts = 338.161438 last_received_ts = 338.161011 last_seq = 10218 current_ts = 338.178070 current_received_ts = 338.177612 current_seq = 10219 count = 39 seq_step = 1 (kms_flip:2709) DEBUG: Vblank took 8us (kms_flip:2709) DEBUG: name = flip last_ts = 338.178070 last_received_ts = 338.177612 last_seq = 10219 current_ts = 338.194702 current_received_ts = 338.194244 current_seq = 10220 count = 40 seq_step = 1 (kms_flip:2709) DEBUG: Vblank took 8us (kms_flip:2709) DEBUG: name = flip last_ts = 338.194702 last_received_ts = 338.194244 last_seq = 10220 current_ts = 338.211304 current_received_ts = 338.210876 current_seq = 10221 count = 41 seq_step = 1 (kms_flip:2709) DEBUG: Vblank took 9us (kms_flip:2709) DEBUG: name = flip last_ts = 338.211304 last_received_ts = 338.210876 last_seq = 10221 current_ts = 338.227936 current_received_ts = 338.227509 current_seq = 10222 count = 42 seq_step = 1 (kms_flip:2709) DEBUG: Vblank took 9us (kms_flip:2709) DEBUG: name = flip last_ts = 338.227936 last_received_ts = 338.227509 last_seq = 10222 current_ts = 338.244568 current_received_ts = 338.244141 current_seq = 10223 count = 43 seq_step = 1 (kms_flip:2709) DEBUG: Vblank took 8us (kms_flip:2709) DEBUG: name = flip last_ts = 338.244568 last_received_ts = 338.244141 last_seq = 10223 current_ts = 338.261200 current_received_ts = 338.260742 current_seq = 10224 count = 44 seq_step = 1 (kms_flip:2709) DEBUG: Vblank took 8us (kms_flip:2709) DEBUG: name = flip last_ts = 338.261200 last_received_ts = 338.260742 last_seq = 10224 current_ts = 338.277832 current_received_ts = 338.277374 current_seq = 10225 count = 45 seq_step = 1 (kms_flip:2709) DEBUG: Vblank took 8us (kms_flip:2709) DEBUG: name = flip last_ts = 338.277832 last_received_ts = 338.277374 last_seq = 10225 current_ts = 338.294434 current_received_ts = 338.293976 current_seq = 10226 count = 46 seq_step = 1 (kms_flip:2709) DEBUG: Vblank took 689us (kms_flip:2709) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:926: (kms_flip:2709) CRITICAL: Failed assertion: end - start < 500 (kms_flip:2709) igt_core-INFO: Stack trace: (kms_flip:2709) igt_core-INFO: #0 ../lib/igt_core.c:2075 __igt_fail_assert() (kms_flip:2709) igt_core-INFO: #1 ../tests/kms_flip.c:921 run_test_on_crtc_set.constprop.0() (kms_flip:2709) igt_core-INFO: #2 ../tests/kms_flip.c:1887 run_test() (kms_flip:2709) igt_core-INFO: #3 ../tests/kms_flip.c:2122 __igt_unique____real_main2043() (kms_flip:2709) igt_core-INFO: #4 ../tests/kms_flip.c:2043 main() (kms_flip:2709) igt_core-INFO: #5 [__libc_init_first+0x8a] (kms_flip:2709) igt_core-INFO: #6 [__libc_start_main+0x8b] (kms_flip:2709) igt_core-INFO: #7 [_start+0x25] END ** Dynamic subtest B-HDMI-A1: FAIL (1.066s) Subtest flip-vs-expired-vblank: FAIL (24.327s) (kms_flip:2709) igt_kms-CRITICAL: Test assertion failure function kmstest_set_connector_dpms, file ../lib/igt_kms.c:2351: (kms_flip:2709) igt_kms-CRITICAL: Failed assertion: found_it (kms_flip:2709) igt_kms-CRITICAL: Last errno: 9, Bad file descriptor (kms_flip:2709) igt_kms-CRITICAL: DPMS property not found on 75
Dmesg	<6> [336.839565] [IGT] kms_flip: starting dynamic subtest B-HDMI-A1 <7> [336.840609] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:89] <7> [336.840765] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:90] <7> [336.894784] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:52:pipe A] <7> [336.894989] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.enable (expected yes, found no) <7> [336.895567] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.active (expected yes, found no) <7> [336.895987] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1) <7> [336.896465] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in has_pch_encoder (expected yes, found no) <7> [336.896976] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in fdi_lanes (expected 3, found 0) <7> [336.897482] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52: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> [336.897953] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in lane_count (expected 4, found 0) <7> [336.898496] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000) <7> [336.898757] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0) <7> [336.898991] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0) <7> [336.899250] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0) <7> [336.899500] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0) <7> [336.899734] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0) <7> [336.899968] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0) <7> [336.900220] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0) <7> [336.900477] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0) <7> [336.900737] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0) <7> [336.900983] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0) <7> [336.901247] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0) <7> [336.901499] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0) <7> [336.901727] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0) <7> [336.901954] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0) <7> [336.902219] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0) <7> [336.902478] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0) <7> [336.902721] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0) <7> [336.902948] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0) <7> [336.903205] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0) <7> [336.903433] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0) <7> [336.903684] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0) <7> [336.903923] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0) <7> [336.904233] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0) <7> [336.904482] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0) <7> [336.904710] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0) <7> [336.904937] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0) <7> [336.905215] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0) <7> [336.905464] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0) <7> [336.905691] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in has_hdmi_sink (expected yes, found no) <7> [336.905923] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in has_infoframe (expected yes, found no) <7> [336.906193] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in pipe_bpp (expected 36, found 0) <7> [336.906436] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148800, found 0) <7> [336.906679] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148800, found 0) <7> [336.906907] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in port_clock (expected 223200, found 0) <7> [336.907164] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000) <7> [336.907409] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in infoframes.gcp (expected 0x00000006, found 0x00000000) <7> [336.907659] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in avi infoframe <7> [336.907898] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected: <7> [336.908220] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13 <7> [336.908223] i915 0000:00:02.0: colorspace: RGB <7> [336.908225] i915 0000:00:02.0: scan mode: Underscan <7> [336.908227] i915 0000:00:02.0: colorimetry: No Data <7> [336.908229] i915 0000:00:02.0: picture aspect: 16:9 <7> [336.908231] i915 0000:00:02.0: active aspect: Same as Picture <7> [336.908233] i915 0000:00:02.0: itc: No Data <7> [336.908235] i915 0000:00:02.0: extended colorimetry: xvYCC 601 <7> [336.908237] i915 0000:00:02.0: quantization range: Default <7> [336.908239] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling <7> [336.908241] i915 0000:00:02.0: video code: 16 <7> [336.908243] i915 0000:00:02.0: ycc quantization range: Full <7> [336.908245] i915 0000:00:02.0: hdmi content type: Graphics <7> [336.908247] i915 0000:00:02.0: pixel repeat: 0 <7> [336.908249] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0 <7> [336.908251] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found: <7> [336.908490] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in spd infoframe <7> [336.908717] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected: <7> [336.908943] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25 <7> [336.908945] i915 0000:00:02.0: vendor: Intel <7> [336.908947] i915 0000:00:02.0: product: Integrated gfx <7> [336.908949] i915 0000:00:02.0: source device information: PC General (0x9) <7> [336.908951] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found: <7> [336.909230] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hdmi infoframe <7> [336.909479] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected: <7> [336.909706] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4 <7> [336.909708] i915 0000:00:02.0: empty frame <7> [336.909710] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found: <7> [336.909937] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:52:pipe A] fastset requirement not met, forcing full modeset <7> [336.910200] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:52:pipe A] releasing PCH DPLL A <7> [336.910464] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:52:pipe A] min cdclk: 165334 kHz -> 0 kHz <7> [336.910714] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:52:pipe A] enable: no [modeset] <7> [336.910940] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:34:primary A] fb: [NOFB], visible: no <7> [336.911256] i915 0000:00:02.0: [drm:intel_audio_codec_disable [i915]] [CONNECTOR:75:HDMI-A-1][ENCODER:74:HDMI D] Disable audio codec on [CRTC:52:pipe A] <7> [336.936211] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A <7> [336.954106] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port B found <7> [336.954611] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port C found <7> [336.955076] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port D found <7> [336.964493] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable PCH DPLL A (active 0x1, on? 1) for [CRTC:52:pipe A] <7> [336.964973] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling PCH DPLL A <7> [336.965697] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:73:CRT] <7> [336.966159] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:HDMI D] <7> [336.966605] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:82:DP D] <7> [336.967094] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:75:HDMI-A-1] <7> [336.967687] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:52:pipe A] <7> [336.968440] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:71:pipe B] <7> [336.968577] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:71:pipe B] <7> [336.968604] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:75:HDMI-A-1] <7> [336.968697] i915 0000:00:02.0: [drm:intel_hdmi_compute_clock [i915]] picking 12 bpc for HDMI output (pipe bpp: 36) <7> [336.968942] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:71:pipe B] hw max bpp: 36, pipe bpp: 36, dithering: 0 <7> [336.969202] i915 0000:00:02.0: [drm:intel_fdi_atomic_check_link [i915]] checking fdi config on pipe B, lanes 3 <7> [336.969469] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.enable (expected no, found yes) <7> [336.969715] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.active (expected no, found yes) <7> [336.969977] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in cpu_transcoder (expected -1, found 1) <7> [336.970244] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in has_pch_encoder (expected no, found yes) <7> [336.970479] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in fdi_lanes (expected 0, found 3) <7> [336.970713] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71: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> [336.970948] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in lane_count (expected 0, found 4) <7> [336.971212] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040) <7> [336.971460] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in framestart_delay (expected 0, found 1) <7> [336.971695] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920) <7> [336.971928] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200) <7> [336.972183] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920) <7> [336.972433] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200) <7> [336.972667] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008) <7> [336.972900] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052) <7> [336.973148] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080) <7> [336.973381] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080) <7> [336.973644] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084) <7> [336.973884] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089) <7> [336.974172] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125) <7> [336.974417] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125) <7> [336.974652] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920) <7> [336.974885] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200) <7> [336.975118] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920) <7> [336.975381] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200) <7> [336.975628] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008) <7> [336.975862] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052) <7> [336.976096] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080) <7> [336.976350] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080) <7> [336.976599] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084) <7> [336.976833] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089) <7> [336.977067] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125) <7> [336.977310] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125) <7> [336.977544] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in pixel_multiplier (expected 0, found 1) <7> [336.977803] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1) <7> [336.978071] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4) <7> [336.978333] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in has_hdmi_sink (expected no, found yes) <7> [336.978578] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in has_infoframe (expected no, found yes) <7> [336.978805] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in dpll_hw_state <7> [336.979032] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected: <7> [336.979301] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: dpll: 0x84100010, dpll_md: 0x0, fp0: 0x10c09, fp1: 0x10c09 <7> [336.979558] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found: <7> [336.979785] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: dpll: 0xc4010001, dpll_md: 0x0, fp0: 0x30f06, fp1: 0x30f06 <7> [336.980012] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in pipe_bpp (expected 0, found 36) <7> [336.980275] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148800) <7> [336.980533] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148800) <7> [336.980777] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in port_clock (expected 0, found 223200) <7> [336.981004] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071) <7> [336.981260] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in infoframes.gcp (expected 0x00000000, found 0x00000006) <7> [336.981529] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in avi infoframe <7> [336.981772] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected: <7> [336.982031] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found: <7> [336.982354] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13 <7> [336.982357] i915 0000:00:02.0: colorspace: RGB <7> [336.982359] i915 0000:00:02.0: scan mode: Underscan <7> [336.982361] i915 0000:00:02.0: colorimetry: No Data <7> [336.982363] i915 0000:00:02.0: picture aspect: 16:9 <7> [336.982365] i915 0000:00:02.0: active aspect: Same as Picture <7> [336.982367] i915 0000:00:02.0: itc: No Data <7> [336.982369] i915 0000:00:02.0: extended colorimetry: xvYCC 601 <7> [336.982370] i915 0000:00:02.0: quantization range: Default <7> [336.982372] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling <7> [336.982374] i915 0000:00:02.0: video code: 16 <7> [336.982376] i915 0000:00:02.0: ycc quantization range: Full <7> [336.982378] i915 0000:00:02.0: hdmi content type: Graphics <7> [336.982380] i915 0000:00:02.0: pixel repeat: 0 <7> [336.982382] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0 <7> [336.982385] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in spd infoframe <7> [336.982631] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected: <7> [336.982864] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found: <7> [336.983096] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25 <7> [336.983099] i915 0000:00:02.0: vendor: Intel <7> [336.983101] i915 0000:00:02.0: product: Integrated gfx <7> [336.983103] i915 0000:00:02.0: source device information: PC General (0x9) <7> [336.983105] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hdmi infoframe <7> [336.983366] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected: <7> [336.983614] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found: <7> [336.983848] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4 <7> [336.983850] i915 0000:00:02.0: empty frame <7> [336.983853] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:71:pipe B] fastset requirement not met, forcing full modeset <7> [336.984115] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:71:pipe B] min cdclk: 0 kHz -> 165334 kHz <7> [336.984360] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:71:pipe B] allocated PCH DPLL A <7> [336.984638] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:71:pipe B] reserving PCH DPLL A <7> [336.984900] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:71:pipe B] enable: yes [modeset] <7> [336.985148] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB <7> [336.985436] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: B, pipe bpp: 36, dithering: 0 <7> [336.985691] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid> <7> [336.985935] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0 <7> [336.986222] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling always-on <7> [336.986284] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0 <7> [336.986564] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0 <7> [336.986808] 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> [336.987035] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 1, infoframes enabled: 0x71 <7> [336.987305] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] GCP: 0x6 <7> [336.987588] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13 <7> [336.987591] i915 0000:00:02.0: colorspace: RGB <7> [336.987593] i915 0000:00:02.0: scan mode: Underscan <7> [336.987594] i915 0000:00:02.0: colorimetry: No Data <7> [336.987596] i915 0000:00:02.0: picture aspect: 16:9 <7> [336.987598] i915 0000:00:02.0: active aspect: Same as Picture <7> [336.987600] i915 0000:00:02.0: itc: No Data <7> [336.987602] i915 0000:00:02.0: extended colorimetry: xvYCC 601 <7> [336.987604] i915 0000:00:02.0: quantization range: Default <7> [336.987605] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling <7> [336.987607] i915 0000:00:02.0: video code: 16 <7> [336.987609] i915 0000:00:02.0: ycc quantization range: Full <7> [336.987611] i915 0000:00:02.0: hdmi content type: Graphics <7> [336.987613] i915 0000:00:02.0: pixel repeat: 0 <7> [336.987615] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0 <7> [336.987617] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25 <7> [336.987619] i915 0000:00:02.0: vendor: Intel <7> [336.987621] i915 0000:00:02.0: product: Integrated gfx <7> [336.987623] i915 0000:00:02.0: source device information: PC General (0x9) <7> [336.987625] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4 <7> [336.987627] i915 0000:00:02.0: empty frame <7> [336.987629] 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> [336.987872] 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> [336.988098] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 07 00 00 00 <7> [336.988357] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 1 <7> [336.988646] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0 <7> [336.988907] 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> [336.989165] 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> [336.989467] 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> [336.989713] 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> [336.989948] 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> [336.990233] 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> [336.990529] 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> [336.990764] 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> [336.990997] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 223200, pipe src: 1920x1080+0+0, pixel rate 148800, min cdclk 165334 <7> [336.991264] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 0, ips linetime: 0 <7> [336.991549] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no <7> [336.991786] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0 <7> [336.992012] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: dpll: 0xc4010001, dpll_md: 0x0, fp0: 0x30f06, fp1: 0x30f06 <7> [336.992270] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x3 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0 <7> [336.992586] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries <7> [336.992829] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000 <7> [336.993089] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000 <7> [336.993361] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000 <7> [336.993622] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000 <7> [336.993848] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0 <7> [336.994073] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:53:primary B] fb: [FB:89] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes <7> [336.994370] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0 <7> [336.994652] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0 <7> [336.996961] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling always-on <7> [336.997246] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:73:CRT] <7> [336.997469] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:HDMI D] <7> [336.997689] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:82:DP D] <7> [336.998543] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe B <7> [336.999606] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x100 <7> [336.999831] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 1 done. <7> [337.000737] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x200 <7> [337.000961] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 2 done. <7> [337.001237] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train done. <7> [337.001491] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable PCH DPLL A (active 0x2, on? 0) for [CRTC:71:pipe B] <7> [337.001717] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling PCH DPLL A <7> [337.053466] i915 0000:00:02.0: [drm:intel_audio_codec_enable [i915]] [CONNECTOR:75:HDMI-A-1][ENCODER:74:HDMI D] Enable audio codec on [CRTC:71:pipe B], 36 bytes ELD <7> [337.070057] 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> [337.070622] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] Configuring HDMI audio for pixel clock 25200 (0x00010000) <7> [337.071139] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:75:HDMI-A-1] <7> [337.071727] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:71:pipe B] <6> [337.906349] [IGT] kms_flip: finished subtest B-HDMI-A1, FAIL <6> [337.907332] [IGT] kms_flip: finished subtest flip-vs-expired-vblank, FAIL <7> [337.920175] i915 0000:00:02.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:89] from all active usage due to RMFB ioctl <7> [337.920587] i915 0000:00:02.0: [drm:atomic_remove_fb] Disabling [PLANE:53:primary B] because [FB:89] is removed <6> [337.937683] [IGT] kms_flip: exiting, ret=98 <7> [337.957044] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:72:VGA-1] Limiting target display pipe bpp to 24 (EDID bpp 0, max requested bpp 0, max platform bpp 36) <7> [337.957616] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:52:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0 <7> [337.957966] i915 0000:00:02.0: [drm:intel_fdi_atomic_check_link [i915]] checking fdi config on pipe A, lanes 1 <7> [337.958419] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.enable (expected no, found yes) <7> [337.958938] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.active (expected no, found yes) <7> [337.959406] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0) <7> [337.959887] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in has_pch_encoder (expected no, found yes) <7> [337.960304] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in fdi_lanes (expected 0, found 1) <7> [337.960735] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52: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> [337.960971] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000002) <7> [337.961206] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1) <7> [337.961440] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1024) <7> [337.961757] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 1344) <7> [337.962015] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1024) <7> [337.962250] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 1344) <7> [337.962488] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 1048) <7> [337.962749] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 1184) <7> [337.962984] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 768) <7> [337.963218] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 768) <7> [337.963451] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 771) <7> [337.963698] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 777) <7> [337.963933] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 806) <7> [337.964166] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 806) <7> [337.964399] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1024) <7> [337.964655] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 1344) <7> [337.964889] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1024) <7> [337.965123] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 1344) <7> [337.965357] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 1048) <7> [337.965636] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 1184) <7> [337.965990] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 768) <7> [337.966320] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 768) <7> [337.966569] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 771) <7> [337.966808] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 777) <7> [337.967035] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 806) <7> [337.967262] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 806) <7> [337.967489] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1) <7> [337.967751] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2) <7> [337.968005] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8) <7> [337.968240] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in dpll_hw_state <7> [337.968473] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected: <7> [337.968733] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: dpll: 0xc4010001, dpll_md: 0x0, fp0: 0x30f06, fp1: 0x30f06 <7> [337.968985] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found: <7> [337.969218] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: dpll: 0x84100010, dpll_md: 0x0, fp0: 0x10c09, fp1: 0x10c09 <7> [337.969451] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24) <7> [337.969713] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 64800) <7> [337.969958] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 64800) <7> [337.970199] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in port_clock (expected 0, found 64800) <7> [337.970433] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:52:pipe A] fastset requirement not met, forcing full modeset <7> [337.970803] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:52:pipe A] min cdclk: 0 kHz -> 72000 kHz <7> [337.971169] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:52:pipe A] allocated PCH DPLL B <7> [337.971468] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:52:pipe A] reserving PCH DPLL B <7> [337.971759] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:52:pipe A] enable: yes [modeset] <7> [337.971996] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: ANALOG (0x2), output format: RGB, sink format: RGB <7> [337.972236] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0 <7> [337.972468] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid> <7> [337.972715] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0 <7> [337.972959] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0 <7> [337.973195] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0 <7> [337.973426] 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> [337.973700] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0 <7> [337.973952] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 1 <7> [337.974186] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0 <7> [337.974418] 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> [337.974681] 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> [337.974925] 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> [337.975150] 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> [337.975388] 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> [337.975643] 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> [337.975887] 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> [337.976113] 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> [337.976339] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 64800, pipe src: 1024x768+0+0, pixel rate 64800, min cdclk 72000 <7> [337.976601] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 0, ips linetime: 0 <7> [337.976843] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no <7> [337.977068] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0 <7> [337.977293] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: dpll: 0x84100010, dpll_md: 0x0, fp0: 0x10c09, fp1: 0x10c09 <7> [337.977519] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x3 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0 <7> [337.977792] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries <7> [337.978035] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000 <7> [337.978261] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000 <7> [337.978486] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000 <7> [337.978754] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000 <7> [337.979005] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0 <7> [337.979230] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:34:primary A] fb: [FB:88] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes <7> [337.979458] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0 <7> [337.979722] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1024.000000x768.000000+0.000000+0.000000 dst: 1024x768+0+0 <7> [337.979966] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:39:sprite A] fb: [NOFB], visible: no <7> [337.980192] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:46:cursor A] fb: [NOFB], visible: no <7> [337.980501] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:73:CRT] <7> [337.980830] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:HDMI D] <7> [337.981067] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:82:DP D] <7> [337.981887] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A <7> [337.982956] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x100 <7> [337.983180] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 1 done. <7> [337.984078] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x200 <7> [337.984302] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 2 done. <7> [337.984522] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train done. <7> [337.984775] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable PCH DPLL B (active 0x1, on? 0) for [CRTC:52:pipe A] <7> [337.985000] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling PCH DPLL B <7> [338.036898] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:72:VGA-1] <7> [338.037225] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:52:pipe A] <7> [338.037627] i915 0000:00:02.0: [drm:drm_client_dev_restore] fbdev: ret=0

Detail

Value

Duration

1.07 seconds

Hostname

shard-snb1

Igt-Version

IGT-Version: 2.3-g33f5420a2 (x86_64) (Linux: 7.0.0-rc4-Patchwork_163332v1-ga25d8c583e1e+ 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:921 run_test_on_crtc_set.constprop.0()
  #2 ../tests/kms_flip.c:1887 run_test()
  #3 ../tests/kms_flip.c:2122 __igt_unique____real_main2043()
  #4 ../tests/kms_flip.c:2043 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest B-HDMI-A1: FAIL (1.066s)
Subtest flip-vs-expired-vblank: FAIL (24.327s)
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../lib/igt_kms.c:2351 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:2709) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:926:
(kms_flip:2709) CRITICAL: Failed assertion: end - start < 500
Dynamic subtest B-HDMI-A1 failed.
**** DEBUG ****
(kms_flip:2709) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=XR24(0x34325258), modifier=0x100000000000001, size=0)
(kms_flip:2709) drmtest-DEBUG: Test requirement passed: is_intel_device(fd)
(kms_flip:2709) drmtest-DEBUG: Test requirement passed: is_intel_device(fd)
(kms_flip:2709) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=1, pitch=7680)
(kms_flip:2709) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd)
(kms_flip:2709) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=XR24(0x34325258), modifier=0x100000000000001, size=0)
(kms_flip:2709) drmtest-DEBUG: Test requirement passed: is_intel_device(fd)
(kms_flip:2709) drmtest-DEBUG: Test requirement passed: is_intel_device(fd)
(kms_flip:2709) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=2, pitch=7680)
(kms_flip:2709) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd)
(kms_flip:2709) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2709) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2709) igt_fb-DEBUG: Test requirement passed: cairo_surface_status(fb->cairo_surface) == CAIRO_STATUS_SUCCESS
(kms_flip:2709) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2709) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2709) igt_fb-DEBUG: Test requirement passed: cairo_surface_status(fb->cairo_surface) == CAIRO_STATUS_SUCCESS
(kms_flip:2709) igt_kms-INFO:   1920x1080: 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5 
(kms_flip:2709) DEBUG: No stale events found
(kms_flip:2709) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0
(kms_flip:2709) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0
(kms_flip:2709) DEBUG: name = flip
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 337.529663
current_received_ts = 337.529175
current_seq = 10180
count = 0
seq_step = 1
(kms_flip:2709) DEBUG: Vblank took 9us
(kms_flip:2709) DEBUG: name = flip
last_ts = 337.529663
last_received_ts = 337.529175
last_seq = 10180
current_ts = 337.546295
current_received_ts = 337.545776
current_seq = 10181
count = 1
seq_step = 1
(kms_flip:2709) DEBUG: Vblank took 8us
(kms_flip:2709) DEBUG: name = flip
last_ts = 337.546295
last_received_ts = 337.545776
last_seq = 10181
current_ts = 337.562927
current_received_ts = 337.562439
current_seq = 10182
count = 2
seq_step = 1
(kms_flip:2709) DEBUG: Vblank took 8us
(kms_flip:2709) DEBUG: name = flip
last_ts = 337.562927
last_received_ts = 337.562439
last_seq = 10182
current_ts = 337.579529
current_received_ts = 337.579071
current_seq = 10183
count = 3
seq_step = 1
(kms_flip:2709) DEBUG: Vblank took 8us
(kms_flip:2709) DEBUG: name = flip
last_ts = 337.579529
last_received_ts = 337.579071
last_seq = 10183
current_ts = 337.596161
current_received_ts = 337.595703
current_seq = 10184
count = 4
seq_step = 1
(kms_flip:2709) DEBUG: Vblank took 8us
(kms_flip:2709) DEBUG: name = flip
last_ts = 337.596161
last_received_ts = 337.595703
last_seq = 10184
current_ts = 337.612793
current_received_ts = 337.612305
current_seq = 10185
count = 5
seq_step = 1
(kms_flip:2709) DEBUG: Vblank took 7us
(kms_flip:2709) DEBUG: name = flip
last_ts = 337.612793
last_received_ts = 337.612305
last_seq = 10185
current_ts = 337.629425
current_received_ts = 337.628937
current_seq = 10186
count = 6
seq_step = 1
(kms_flip:2709) DEBUG: Vblank took 8us
(kms_flip:2709) DEBUG: name = flip
last_ts = 337.629425
last_received_ts = 337.628937
last_seq = 10186
current_ts = 337.646057
current_received_ts = 337.645569
current_seq = 10187
count = 7
seq_step = 1
(kms_flip:2709) DEBUG: Vblank took 7us
(kms_flip:2709) DEBUG: name = flip
last_ts = 337.646057
last_received_ts = 337.645569
last_seq = 10187
current_ts = 337.662659
current_received_ts = 337.662201
current_seq = 10188
count = 8
seq_step = 1
(kms_flip:2709) DEBUG: Vblank took 8us
(kms_flip:2709) DEBUG: name = flip
last_ts = 337.662659
last_received_ts = 337.662201
last_seq = 10188
current_ts = 337.679291
current_received_ts = 337.678833
current_seq = 10189
count = 9
seq_step = 1
(kms_flip:2709) DEBUG: Vblank took 8us
(kms_flip:2709) DEBUG: name = flip
last_ts = 337.679291
last_received_ts = 337.678833
last_seq = 10189
current_ts = 337.695923
current_received_ts = 337.695465
current_seq = 10190
count = 10
seq_step = 1
(kms_flip:2709) DEBUG: Vblank took 8us
(kms_flip:2709) DEBUG: name = flip
last_ts = 337.695923
last_received_ts = 337.695465
last_seq = 10190
current_ts = 337.712555
current_received_ts = 337.712067
current_seq = 10191
count = 11
seq_step = 1
(kms_flip:2709) DEBUG: Vblank took 8us
(kms_flip:2709) DEBUG: name = flip
last_ts = 337.712555
last_received_ts = 337.712067
last_seq = 10191
current_ts = 337.729156
current_received_ts = 337.728729
current_seq = 10192
count = 12
seq_step = 1
(kms_flip:2709) DEBUG: Vblank took 8us
(kms_flip:2709) DEBUG: name = flip
last_ts = 337.729156
last_received_ts = 337.728729
last_seq = 10192
current_ts = 337.745789
current_received_ts = 337.745361
current_seq = 10193
count = 13
seq_step = 1
(kms_flip:2709) DEBUG: Vblank took 8us
(kms_flip:2709) DEBUG: name = flip
last_ts = 337.745789
last_received_ts = 337.745361
last_seq = 10193
current_ts = 337.762421
current_received_ts = 337.761993
current_seq = 10194
count = 14
seq_step = 1
(kms_flip:2709) DEBUG: Vblank took 41us
(kms_flip:2709) DEBUG: name = flip
last_ts = 337.762421
last_received_ts = 337.761993
last_seq = 10194
current_ts = 337.779053
current_received_ts = 337.778595
current_seq = 10195
count = 15
seq_step = 1
(kms_flip:2709) DEBUG: Vblank took 8us
(kms_flip:2709) DEBUG: name = flip
last_ts = 337.779053
last_received_ts = 337.778595
last_seq = 10195
current_ts = 337.795685
current_received_ts = 337.795227
current_seq = 10196
count = 16
seq_step = 1
(kms_flip:2709) DEBUG: Vblank took 8us
(kms_flip:2709) DEBUG: name = flip
last_ts = 337.795685
last_received_ts = 337.795227
last_seq = 10196
current_ts = 337.812317
current_received_ts = 337.811829
current_seq = 10197
count = 17
seq_step = 1
(kms_flip:2709) DEBUG: Vblank took 8us
(kms_flip:2709) DEBUG: name = flip
last_ts = 337.812317
last_received_ts = 337.811829
last_seq = 10197
current_ts = 337.828918
current_received_ts = 337.828491
current_seq = 10198
count = 18
seq_step = 1
(kms_flip:2709) DEBUG: Vblank took 8us
(kms_flip:2709) DEBUG: name = flip
last_ts = 337.828918
last_received_ts = 337.828491
last_seq = 10198
current_ts = 337.845551
current_received_ts = 337.845154
current_seq = 10199
count = 19
seq_step = 1
(kms_flip:2709) DEBUG: Vblank took 8us
(kms_flip:2709) DEBUG: name = flip
last_ts = 337.845551
last_received_ts = 337.845154
last_seq = 10199
current_ts = 337.862183
current_received_ts = 337.861725
current_seq = 10200
count = 20
seq_step = 1
(kms_flip:2709) DEBUG: Vblank took 8us
(kms_flip:2709) DEBUG: name = flip
last_ts = 337.862183
last_received_ts = 337.861725
last_seq = 10200
current_ts = 337.878784
current_received_ts = 337.878357
current_seq = 10201
count = 21
seq_step = 1
(kms_flip:2709) DEBUG: Vblank took 7us
(kms_flip:2709) DEBUG: name = flip
last_ts = 337.878784
last_received_ts = 337.878357
last_seq = 10201
current_ts = 337.895416
current_received_ts = 337.894958
current_seq = 10202
count = 22
seq_step = 1
(kms_flip:2709) DEBUG: Vblank took 9us
(kms_flip:2709) DEBUG: name = flip
last_ts = 337.895416
last_received_ts = 337.894958
last_seq = 10202
current_ts = 337.912048
current_received_ts = 337.911560
current_seq = 10203
count = 23
seq_step = 1
(kms_flip:2709) DEBUG: Vblank took 8us
(kms_flip:2709) DEBUG: name = flip
last_ts = 337.912048
last_received_ts = 337.911560
last_seq = 10203
current_ts = 337.928680
current_received_ts = 337.928223
current_seq = 10204
count = 24
seq_step = 1
(kms_flip:2709) DEBUG: Vblank took 8us
(kms_flip:2709) DEBUG: name = flip
last_ts = 337.928680
last_received_ts = 337.928223
last_seq = 10204
current_ts = 337.945312
current_received_ts = 337.944855
current_seq = 10205
count = 25
seq_step = 1
(kms_flip:2709) DEBUG: Vblank took 8us
(kms_flip:2709) DEBUG: name = flip
last_ts = 337.945312
last_received_ts = 337.944855
last_seq = 10205
current_ts = 337.961914
current_received_ts = 337.961456
current_seq = 10206
count = 26
seq_step = 1
(kms_flip:2709) DEBUG: Vblank took 8us
(kms_flip:2709) DEBUG: name = flip
last_ts = 337.961914
last_received_ts = 337.961456
last_seq = 10206
current_ts = 337.978577
current_received_ts = 337.978088
current_seq = 10207
count = 27
seq_step = 1
(kms_flip:2709) DEBUG: Vblank took 8us
(kms_flip:2709) DEBUG: name = flip
last_ts = 337.978577
last_received_ts = 337.978088
last_seq = 10207
current_ts = 337.995178
current_received_ts = 337.994720
current_seq = 10208
count = 28
seq_step = 1
(kms_flip:2709) DEBUG: Vblank took 9us
(kms_flip:2709) DEBUG: name = flip
last_ts = 337.995178
last_received_ts = 337.994720
last_seq = 10208
current_ts = 338.011810
current_received_ts = 338.011353
current_seq = 10209
count = 29
seq_step = 1
(kms_flip:2709) DEBUG: Vblank took 8us
(kms_flip:2709) DEBUG: name = flip
last_ts = 338.011810
last_received_ts = 338.011353
last_seq = 10209
current_ts = 338.028442
current_received_ts = 338.027985
current_seq = 10210
count = 30
seq_step = 1
(kms_flip:2709) DEBUG: Vblank took 37us
(kms_flip:2709) DEBUG: name = flip
last_ts = 338.028442
last_received_ts = 338.027985
last_seq = 10210
current_ts = 338.045044
current_received_ts = 338.044586
current_seq = 10211
count = 31
seq_step = 1
(kms_flip:2709) DEBUG: Vblank took 9us
(kms_flip:2709) DEBUG: name = flip
last_ts = 338.045044
last_received_ts = 338.044586
last_seq = 10211
current_ts = 338.061676
current_received_ts = 338.061218
current_seq = 10212
count = 32
seq_step = 1
(kms_flip:2709) DEBUG: Vblank took 9us
(kms_flip:2709) DEBUG: name = flip
last_ts = 338.061676
last_received_ts = 338.061218
last_seq = 10212
current_ts = 338.078308
current_received_ts = 338.077850
current_seq = 10213
count = 33
seq_step = 1
(kms_flip:2709) DEBUG: Vblank took 9us
(kms_flip:2709) DEBUG: name = flip
last_ts = 338.078308
last_received_ts = 338.077850
last_seq = 10213
current_ts = 338.094940
current_received_ts = 338.094482
current_seq = 10214
count = 34
seq_step = 1
(kms_flip:2709) DEBUG: Vblank took 9us
(kms_flip:2709) DEBUG: name = flip
last_ts = 338.094940
last_received_ts = 338.094482
last_seq = 10214
current_ts = 338.111572
current_received_ts = 338.111115
current_seq = 10215
count = 35
seq_step = 1
(kms_flip:2709) DEBUG: Vblank took 9us
(kms_flip:2709) DEBUG: name = flip
last_ts = 338.111572
last_received_ts = 338.111115
last_seq = 10215
current_ts = 338.128174
current_received_ts = 338.127716
current_seq = 10216
count = 36
seq_step = 1
(kms_flip:2709) DEBUG: Vblank took 8us
(kms_flip:2709) DEBUG: name = flip
last_ts = 338.128174
last_received_ts = 338.127716
last_seq = 10216
current_ts = 338.144806
current_received_ts = 338.144379
current_seq = 10217
count = 37
seq_step = 1
(kms_flip:2709) DEBUG: Vblank took 8us
(kms_flip:2709) DEBUG: name = flip
last_ts = 338.144806
last_received_ts = 338.144379
last_seq = 10217
current_ts = 338.161438
current_received_ts = 338.161011
current_seq = 10218
count = 38
seq_step = 1
(kms_flip:2709) DEBUG: Vblank took 12us
(kms_flip:2709) DEBUG: name = flip
last_ts = 338.161438
last_received_ts = 338.161011
last_seq = 10218
current_ts = 338.178070
current_received_ts = 338.177612
current_seq = 10219
count = 39
seq_step = 1
(kms_flip:2709) DEBUG: Vblank took 8us
(kms_flip:2709) DEBUG: name = flip
last_ts = 338.178070
last_received_ts = 338.177612
last_seq = 10219
current_ts = 338.194702
current_received_ts = 338.194244
current_seq = 10220
count = 40
seq_step = 1
(kms_flip:2709) DEBUG: Vblank took 8us
(kms_flip:2709) DEBUG: name = flip
last_ts = 338.194702
last_received_ts = 338.194244
last_seq = 10220
current_ts = 338.211304
current_received_ts = 338.210876
current_seq = 10221
count = 41
seq_step = 1
(kms_flip:2709) DEBUG: Vblank took 9us
(kms_flip:2709) DEBUG: name = flip
last_ts = 338.211304
last_received_ts = 338.210876
last_seq = 10221
current_ts = 338.227936
current_received_ts = 338.227509
current_seq = 10222
count = 42
seq_step = 1
(kms_flip:2709) DEBUG: Vblank took 9us
(kms_flip:2709) DEBUG: name = flip
last_ts = 338.227936
last_received_ts = 338.227509
last_seq = 10222
current_ts = 338.244568
current_received_ts = 338.244141
current_seq = 10223
count = 43
seq_step = 1
(kms_flip:2709) DEBUG: Vblank took 8us
(kms_flip:2709) DEBUG: name = flip
last_ts = 338.244568
last_received_ts = 338.244141
last_seq = 10223
current_ts = 338.261200
current_received_ts = 338.260742
current_seq = 10224
count = 44
seq_step = 1
(kms_flip:2709) DEBUG: Vblank took 8us
(kms_flip:2709) DEBUG: name = flip
last_ts = 338.261200
last_received_ts = 338.260742
last_seq = 10224
current_ts = 338.277832
current_received_ts = 338.277374
current_seq = 10225
count = 45
seq_step = 1
(kms_flip:2709) DEBUG: Vblank took 8us
(kms_flip:2709) DEBUG: name = flip
last_ts = 338.277832
last_received_ts = 338.277374
last_seq = 10225
current_ts = 338.294434
current_received_ts = 338.293976
current_seq = 10226
count = 46
seq_step = 1
(kms_flip:2709) DEBUG: Vblank took 689us
(kms_flip:2709) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:926:
(kms_flip:2709) CRITICAL: Failed assertion: end - start < 500
(kms_flip:2709) igt_core-INFO: Stack trace:
(kms_flip:2709) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:2709) igt_core-INFO:   #1 ../tests/kms_flip.c:921 run_test_on_crtc_set.constprop.0()
(kms_flip:2709) igt_core-INFO:   #2 ../tests/kms_flip.c:1887 run_test()
(kms_flip:2709) igt_core-INFO:   #3 ../tests/kms_flip.c:2122 __igt_unique____real_main2043()
(kms_flip:2709) igt_core-INFO:   #4 ../tests/kms_flip.c:2043 main()
(kms_flip:2709) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:2709) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:2709) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest B-HDMI-A1: FAIL (1.066s)
Subtest flip-vs-expired-vblank: FAIL (24.327s)
(kms_flip:2709) igt_kms-CRITICAL: Test assertion failure function kmstest_set_connector_dpms, file ../lib/igt_kms.c:2351:
(kms_flip:2709) igt_kms-CRITICAL: Failed assertion: found_it
(kms_flip:2709) igt_kms-CRITICAL: Last errno: 9, Bad file descriptor
(kms_flip:2709) igt_kms-CRITICAL: DPMS property not found on 75

Dmesg

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

<7> [336.840609] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:89]

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

<7> [336.894784] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:52:pipe A]

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

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

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

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

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

<7> [336.897482] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52: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> [336.897953] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in lane_count (expected 4, found 0)

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

<7> [336.965697] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:73:CRT]

<7> [336.966159] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:HDMI D]

<7> [336.966605] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:82:DP D]

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

<7> [336.967687] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:52:pipe A]

<7> [336.968440] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:71:pipe B]

<7> [336.968577] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:71:pipe B]

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

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

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

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

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

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

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

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

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

<7> [336.970713] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71: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> [336.970948] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in lane_count (expected 0, found 4)

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

<7> [336.986808] 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> [336.987035] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 1, infoframes enabled: 0x71

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

<7> [336.987629] 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> [336.987872] 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> [336.988098] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 07 00 00 00

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

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

<7> [336.988907] 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> [336.989165] 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> [336.989467] 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> [336.989713] 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> [336.989948] 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> [336.990233] 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> [336.990529] 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> [336.990764] 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> [336.990997] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 223200, pipe src: 1920x1080+0+0, pixel rate 148800, min cdclk 165334

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

<7> [336.997246] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:73:CRT]

<7> [336.997469] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:HDMI D]

<7> [336.997689] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:82:DP D]

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

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

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

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

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

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

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

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

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

<7> [337.070057] 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> [337.070622] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] Configuring HDMI audio for pixel clock 25200 (0x00010000)

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

<7> [337.071727] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:71:pipe B]

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

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

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

<7> [337.920587] i915 0000:00:02.0: [drm:atomic_remove_fb] Disabling [PLANE:53:primary B] because [FB:89] is removed

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

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

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

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

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

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

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

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

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

<7> [337.960735] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52: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> [337.960971] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000002)

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

<7> [337.973426] 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> [337.973700] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0

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

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

<7> [337.974418] 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> [337.974681] 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> [337.974925] 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> [337.975150] 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> [337.975388] 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> [337.975643] 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> [337.975887] 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> [337.976113] 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> [337.976339] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 64800, pipe src: 1024x768+0+0, pixel rate 64800, min cdclk 72000

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

<7> [337.980501] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:73:CRT]

<7> [337.980830] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:HDMI D]

<7> [337.981067] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:82:DP D]

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

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

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

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

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

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

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

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

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

<7> [338.037225] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:52:pipe A]

<7> [338.037627] 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