Results for igt@kms_flip@wf_vblank-ts-check-interruptible@b-vga1

Machine description: shard-snb1

Result: Fail

integration-manifest git-log-oneline i915_display_info1 igt_runner1 run1 runtimes1 results1.json i915_display_info_post_exec1 boot1 dmesg1

DetailValue
Duration 9.96 seconds
Hostname
shard-snb1
Igt-Version
IGT-Version: 1.29-NO-GIT (x86_64) (Linux: 6.12.0-rc5-CI_DRM_15605-g95fd9af8bb53+ x86_64)
Out
Starting dynamic subtest: B-VGA1
  1024x768: 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa 
Expected frametime: 16666us; measured 16858.2us +- 4.820us accuracy 0.09%
vblank interval differs from modeline! expected 16665.6us, measured 16858us +- 4.820us, difference 192.6us (40.0 sigma)
Stack trace:
  #0 ../lib/igt_core.c:2051 __igt_fail_assert()
  #1 ../tests/kms_flip.c:791 run_test_on_crtc_set.constprop.0()
  #2 ../tests/kms_flip.c:1845 run_test()
  #3 ../tests/kms_flip.c:2114 __igt_unique____real_main2001()
  #4 ../tests/kms_flip.c:2001 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest B-VGA1: FAIL (9.960s)
Err
Starting dynamic subtest: B-VGA1
(kms_flip:1517) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1674:
(kms_flip:1517) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon)
(kms_flip:1517) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest B-VGA1 failed.
**** DEBUG ****
(kms_flip:1517) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=1024, height=768, format=XR24(0x34325258), modifier=0x0, size=0)
(kms_flip:1517) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=3, pitch=4096)
(kms_flip:1517) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd)
(kms_flip:1517) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=1024, height=768, format=XR24(0x34325258), modifier=0x0, size=0)
(kms_flip:1517) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=4, pitch=4096)
(kms_flip:1517) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd)
(kms_flip:1517) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:1517) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:1517) igt_fb-DEBUG: Test requirement passed: cairo_surface_status(fb->cairo_surface) == CAIRO_STATUS_SUCCESS
(kms_flip:1517) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:1517) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:1517) igt_fb-DEBUG: Test requirement passed: cairo_surface_status(fb->cairo_surface) == CAIRO_STATUS_SUCCESS
(kms_flip:1517) igt_kms-INFO:   1024x768: 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa 
(kms_flip:1517) DEBUG: No stale events found
(kms_flip:1517) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0
(kms_flip:1517) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0
(kms_flip:1517) INFO: Expected frametime: 16666us; measured 16858.2us +- 4.820us accuracy 0.09%
(kms_flip:1517) INFO: vblank interval differs from modeline! expected 16665.6us, measured 16858us +- 4.820us, difference 192.6us (40.0 sigma)
(kms_flip:1517) DEBUG: name = vblank
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 55.342983
current_received_ts = 55.342381
current_seq = 32
count = 0
seq_step = 10
(kms_flip:1517) DEBUG: name = vblank
last_ts = 55.342983
last_received_ts = 55.342381
last_seq = 32
current_ts = 55.511566
current_received_ts = 55.510967
current_seq = 42
count = 1
seq_step = 10
(kms_flip:1517) DEBUG: vblank ts/seq: last 55.342983/32, current 55.511566/42: elapsed=168584.0us expected=168581.9us +- 842.9us, error 0.0%
(kms_flip:1517) DEBUG: name = vblank
last_ts = 55.511566
last_received_ts = 55.510967
last_seq = 42
current_ts = 55.680031
current_received_ts = 55.679363
current_seq = 52
count = 2
seq_step = 10
(kms_flip:1517) DEBUG: vblank ts/seq: last 55.511566/42, current 55.680031/52: elapsed=168463.0us expected=168581.9us +- 842.9us, error 0.1%
(kms_flip:1517) DEBUG: name = vblank
last_ts = 55.680031
last_received_ts = 55.679363
last_seq = 52
current_ts = 55.848427
current_received_ts = 55.847824
current_seq = 62
count = 3
seq_step = 10
(kms_flip:1517) DEBUG: vblank ts/seq: last 55.680031/52, current 55.848427/62: elapsed=168397.0us expected=168581.9us +- 842.9us, error 0.1%
(kms_flip:1517) DEBUG: name = vblank
last_ts = 55.848427
last_received_ts = 55.847824
last_seq = 62
current_ts = 56.016834
current_received_ts = 56.016232
current_seq = 72
count = 4
seq_step = 10
(kms_flip:1517) DEBUG: vblank ts/seq: last 55.848427/62, current 56.016834/72: elapsed=168406.0us expected=168581.9us +- 842.9us, error 0.1%
(kms_flip:1517) DEBUG: name = vblank
last_ts = 56.016834
last_received_ts = 56.016232
last_seq = 72
current_ts = 56.185234
current_received_ts = 56.184635
current_seq = 82
count = 5
seq_step = 10
(kms_flip:1517) DEBUG: vblank ts/seq: last 56.016834/72, current 56.185234/82: elapsed=168402.0us expected=168581.9us +- 842.9us, error 0.1%
(kms_flip:1517) DEBUG: name = vblank
last_ts = 56.185234
last_received_ts = 56.184635
last_seq = 82
current_ts = 56.353642
current_received_ts = 56.353062
current_seq = 92
count = 6
seq_step = 10
(kms_flip:1517) DEBUG: vblank ts/seq: last 56.185234/82, current 56.353642/92: elapsed=168406.0us expected=168581.9us +- 842.9us, error 0.1%
(kms_flip:1517) DEBUG: name = vblank
last_ts = 56.353642
last_received_ts = 56.353062
last_seq = 92
current_ts = 56.522053
current_received_ts = 56.521454
current_seq = 102
count = 7
seq_step = 10
(kms_flip:1517) DEBUG: vblank ts/seq: last 56.353642/92, current 56.522053/102: elapsed=168412.0us expected=168581.9us +- 842.9us, error 0.1%
(kms_flip:1517) DEBUG: name = vblank
last_ts = 56.522053
last_received_ts = 56.521454
last_seq = 102
current_ts = 56.690342
current_received_ts = 56.689743
current_seq = 112
count = 8
seq_step = 10
(kms_flip:1517) DEBUG: vblank ts/seq: last 56.522053/102, current 56.690342/112: elapsed=168290.0us expected=168581.9us +- 842.9us, error 0.2%
(kms_flip:1517) DEBUG: name = vblank
last_ts = 56.690342
last_received_ts = 56.689743
last_seq = 112
current_ts = 56.858582
current_received_ts = 56.857979
current_seq = 122
count = 9
seq_step = 10
(kms_flip:1517) DEBUG: vblank ts/seq: last 56.690342/112, current 56.858582/122: elapsed=168238.0us expected=168581.9us +- 842.9us, error 0.2%
(kms_flip:1517) DEBUG: name = vblank
last_ts = 56.858582
last_received_ts = 56.857979
last_seq = 122
current_ts = 57.026836
current_received_ts = 57.026234
current_seq = 132
count = 10
seq_step = 10
(kms_flip:1517) DEBUG: vblank ts/seq: last 56.858582/122, current 57.026836/132: elapsed=168255.0us expected=168581.9us +- 842.9us, error 0.2%
(kms_flip:1517) DEBUG: name = vblank
last_ts = 57.026836
last_received_ts = 57.026234
last_seq = 132
current_ts = 57.195087
current_received_ts = 57.194458
current_seq = 142
count = 11
seq_step = 10
(kms_flip:1517) DEBUG: vblank ts/seq: last 57.026836/132, current 57.195087/142: elapsed=168252.0us expected=168581.9us +- 842.9us, error 0.2%
(kms_flip:1517) DEBUG: name = vblank
last_ts = 57.195087
last_received_ts = 57.194458
last_seq = 142
current_ts = 57.363331
current_received_ts = 57.362671
current_seq = 152
count = 12
seq_step = 10
(kms_flip:1517) DEBUG: vblank ts/seq: last 57.195087/142, current 57.363331/152: elapsed=168241.0us expected=168581.9us +- 842.9us, error 0.2%
(kms_flip:1517) DEBUG: name = vblank
last_ts = 57.363331
last_received_ts = 57.362671
last_seq = 152
current_ts = 57.531597
current_received_ts = 57.530907
current_seq = 162
count = 13
seq_step = 10
(kms_flip:1517) DEBUG: vblank ts/seq: last 57.363331/152, current 57.531597/162: elapsed=168270.0us expected=168581.9us +- 842.9us, error 0.2%
(kms_flip:1517) DEBUG: name = vblank
last_ts = 57.531597
last_received_ts = 57.530907
last_seq = 162
current_ts = 57.699726
current_received_ts = 57.699131
current_seq = 172
count = 14
seq_step = 10
(kms_flip:1517) DEBUG: vblank ts/seq: last 57.531597/162, current 57.699726/172: elapsed=168127.0us expected=168581.9us +- 842.9us, error 0.3%
(kms_flip:1517) DEBUG: name = vblank
last_ts = 57.699726
last_received_ts = 57.699131
last_seq = 172
current_ts = 57.867840
current_received_ts = 57.867256
current_seq = 182
count = 15
seq_step = 10
(kms_flip:1517) DEBUG: vblank ts/seq: last 57.699726/172, current 57.867840/182: elapsed=168112.0us expected=168581.9us +- 842.9us, error 0.3%
(kms_flip:1517) DEBUG: name = vblank
last_ts = 57.867840
last_received_ts = 57.867256
last_seq = 182
current_ts = 58.035954
current_received_ts = 58.035332
current_seq = 192
count = 16
seq_step = 10
(kms_flip:1517) DEBUG: vblank ts/seq: last 57.867840/182, current 58.035954/192: elapsed=168114.0us expected=168581.9us +- 842.9us, error 0.3%
(kms_flip:1517) DEBUG: name = vblank
last_ts = 58.035954
last_received_ts = 58.035332
last_seq = 192
current_ts = 58.204075
current_received_ts = 58.203445
current_seq = 202
count = 17
seq_step = 10
(kms_flip:1517) DEBUG: vblank ts/seq: last 58.035954/192, current 58.204075/202: elapsed=168123.0us expected=168581.9us +- 842.9us, error 0.3%
(kms_flip:1517) DEBUG: name = vblank
last_ts = 58.204075
last_received_ts = 58.203445
last_seq = 202
current_ts = 58.372185
current_received_ts = 58.371571
current_seq = 212
count = 18
seq_step = 10
(kms_flip:1517) DEBUG: vblank ts/seq: last 58.204075/202, current 58.372185/212: elapsed=168109.0us expected=168581.9us +- 842.9us, error 0.3%
(kms_flip:1517) DEBUG: name = vblank
last_ts = 58.372185
last_received_ts = 58.371571
last_seq = 212
current_ts = 58.540318
current_received_ts = 58.539646
current_seq = 222
count = 19
seq_step = 10
(kms_flip:1517) DEBUG: vblank ts/seq: last 58.372185/212, current 58.540318/222: elapsed=168132.0us expected=168581.9us +- 842.9us, error 0.3%
(kms_flip:1517) DEBUG: name = vblank
last_ts = 58.540318
last_received_ts = 58.539646
last_seq = 222
current_ts = 58.708317
current_received_ts = 58.707733
current_seq = 232
count = 20
seq_step = 10
(kms_flip:1517) DEBUG: vblank ts/seq: last 58.540318/222, current 58.708317/232: elapsed=168001.0us expected=168581.9us +- 842.9us, error 0.3%
(kms_flip:1517) DEBUG: name = vblank
last_ts = 58.708317
last_received_ts = 58.707733
last_seq = 232
current_ts = 58.876312
current_received_ts = 58.875687
current_seq = 242
count = 21
seq_step = 10
(kms_flip:1517) DEBUG: vblank ts/seq: last 58.708317/232, current 58.876312/242: elapsed=167997.0us expected=168581.9us +- 842.9us, error 0.3%
(kms_flip:1517) DEBUG: name = vblank
last_ts = 58.876312
last_received_ts = 58.875687
last_seq = 242
current_ts = 59.044323
current_received_ts = 59.043697
current_seq = 252
count = 22
seq_step = 10
(kms_flip:1517) DEBUG: vblank ts/seq: last 58.876312/242, current 59.044323/252: elapsed=168009.0us expected=168581.9us +- 842.9us, error 0.3%
(kms_flip:1517) DEBUG: name = vblank
last_ts = 59.044323
last_received_ts = 59.043697
last_seq = 252
current_ts = 59.212307
current_received_ts = 59.211704
current_seq = 262
count = 23
seq_step = 10
(kms_flip:1517) DEBUG: vblank ts/seq: last 59.044323/252, current 59.212307/262: elapsed=167984.0us expected=168581.9us +- 842.9us, error 0.4%
(kms_flip:1517) DEBUG: name = vblank
last_ts = 59.212307
last_received_ts = 59.211704
last_seq = 262
current_ts = 59.380310
current_received_ts = 59.379707
current_seq = 272
count = 24
seq_step = 10
(kms_flip:1517) DEBUG: vblank ts/seq: last 59.212307/262, current 59.380310/272: elapsed=168002.0us expected=168581.9us +- 842.9us, error 0.3%
(kms_flip:1517) DEBUG: name = vblank
last_ts = 59.380310
last_received_ts = 59.379707
last_seq = 272
current_ts = 59.548306
current_received_ts = 59.547707
current_seq = 282
count = 25
seq_step = 10
(kms_flip:1517) DEBUG: vblank ts/seq: last 59.380310/272, current 59.548306/282: elapsed=167997.0us expected=168581.9us +- 842.9us, error 0.3%
(kms_flip:1517) DEBUG: name = vblank
last_ts = 59.548306
last_received_ts = 59.547707
last_seq = 282
current_ts = 59.716206
current_received_ts = 59.715565
current_seq = 292
count = 26
seq_step = 10
(kms_flip:1517) DEBUG: vblank ts/seq: last 59.548306/282, current 59.716206/292: elapsed=167901.0us expected=168581.9us +- 842.9us, error 0.4%
(kms_flip:1517) DEBUG: name = vblank
last_ts = 59.716206
last_received_ts = 59.715565
last_seq = 292
current_ts = 59.884102
current_received_ts = 59.883499
current_seq = 302
count = 27
seq_step = 10
(kms_flip:1517) DEBUG: vblank ts/seq: last 59.716206/292, current 59.884102/302: elapsed=167893.0us expected=168581.9us +- 842.9us, error 0.4%
(kms_flip:1517) DEBUG: name = vblank
last_ts = 59.884102
last_received_ts = 59.883499
last_seq = 302
current_ts = 60.051994
current_received_ts = 60.051392
current_seq = 312
count = 28
seq_step = 10
(kms_flip:1517) DEBUG: vblank ts/seq: last 59.884102/302, current 60.051994/312: elapsed=167893.0us expected=168581.9us +- 842.9us, error 0.4%
(kms_flip:1517) DEBUG: name = vblank
last_ts = 60.051994
last_received_ts = 60.051392
last_seq = 312
current_ts = 60.219898
current_received_ts = 60.219238
current_seq = 322
count = 29
seq_step = 10
(kms_flip:1517) DEBUG: vblank ts/seq: last 60.051994/312, current 60.219898/322: elapsed=167905.0us expected=168581.9us +- 842.9us, error 0.4%
(kms_flip:1517) DEBUG: name = vblank
last_ts = 60.219898
last_received_ts = 60.219238
last_seq = 322
current_ts = 60.387783
current_received_ts = 60.387180
current_seq = 332
count = 30
seq_step = 10
(kms_flip:1517) DEBUG: vblank ts/seq: last 60.219898/322, current 60.387783/332: elapsed=167885.0us expected=168581.9us +- 842.9us, error 0.4%
(kms_flip:1517) DEBUG: name = vblank
last_ts = 60.387783
last_received_ts = 60.387180
last_seq = 332
current_ts = 60.555676
current_received_ts = 60.555077
current_seq = 342
count = 31
seq_step = 10
(kms_flip:1517) DEBUG: vblank ts/seq: last 60.387783/332, current 60.555676/342: elapsed=167894.0us expected=168581.9us +- 842.9us, error 0.4%
(kms_flip:1517) DEBUG: name = vblank
last_ts = 60.555676
last_received_ts = 60.555077
last_seq = 342
current_ts = 60.723480
current_received_ts = 60.722881
current_seq = 352
count = 32
seq_step = 10
(kms_flip:1517) DEBUG: vblank ts/seq: last 60.555676/342, current 60.723480/352: elapsed=167803.0us expected=168581.9us +- 842.9us, error 0.5%
(kms_flip:1517) DEBUG: name = vblank
last_ts = 60.723480
last_received_ts = 60.722881
last_seq = 352
current_ts = 60.891277
current_received_ts = 60.890656
current_seq = 362
count = 33
seq_step = 10
(kms_flip:1517) DEBUG: vblank ts/seq: last 60.723480/352, current 60.891277/362: elapsed=167796.0us expected=168581.9us +- 842.9us, error 0.5%
(kms_flip:1517) DEBUG: name = vblank
last_ts = 60.891277
last_received_ts = 60.890656
last_seq = 362
current_ts = 61.059090
current_received_ts = 61.058483
current_seq = 372
count = 34
seq_step = 10
(kms_flip:1517) DEBUG: vblank ts/seq: last 60.891277/362, current 61.059090/372: elapsed=167812.0us expected=168581.9us +- 842.9us, error 0.5%
(kms_flip:1517) DEBUG: name = vblank
last_ts = 61.059090
last_received_ts = 61.058483
last_seq = 372
current_ts = 61.226891
current_received_ts = 61.226288
current_seq = 382
count = 35
seq_step = 10
(kms_flip:1517) DEBUG: vblank ts/seq: last 61.059090/372, current 61.226891/382: elapsed=167804.0us expected=168581.9us +- 842.9us, error 0.5%
(kms_flip:1517) DEBUG: name = vblank
last_ts = 61.226891
last_received_ts = 61.226288
last_seq = 382
current_ts = 61.394688
current_received_ts = 61.394119
current_seq = 392
count = 36
seq_step = 10
(kms_flip:1517) DEBUG: vblank ts/seq: last 61.226891/382, current 61.394688/392: elapsed=167797.0us expected=168581.9us +- 842.9us, error 0.5%
(kms_flip:1517) DEBUG: name = vblank
last_ts = 61.394688
last_received_ts = 61.394119
last_seq = 392
current_ts = 61.562496
current_received_ts = 61.561871
current_seq = 402
count = 37
seq_step = 10
(kms_flip:1517) DEBUG: vblank ts/seq: last 61.394688/392, current 61.562496/402: elapsed=167806.0us expected=168581.9us +- 842.9us, error 0.5%
(kms_flip:1517) DEBUG: name = vblank
last_ts = 61.562496
last_received_ts = 61.561871
last_seq = 402
current_ts = 61.730221
current_received_ts = 61.729622
current_seq = 412
count = 38
seq_step = 10
(kms_flip:1517) DEBUG: vblank ts/seq: last 61.562496/402, current 61.730221/412: elapsed=167725.0us expected=168581.9us +- 842.9us, error 0.5%
(kms_flip:1517) DEBUG: inconsistent vblank ts/seq: last 61.562496/402, current 61.730221/412: elapsed=167725.0us expected=168581.9us
(kms_flip:1517) DEBUG: expected 0, counted 380, encoder type 1
(kms_flip:1517) DEBUG: dropped frames, expected 0, counted 380, encoder type 1
(kms_flip:1517) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1674:
(kms_flip:1517) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon)
(kms_flip:1517) CRITICAL: Last errno: 4, Interrupted system call
(kms_flip:1517) igt_core-INFO: Stack trace:
(kms_flip:1517) igt_core-INFO:   #0 ../lib/igt_core.c:2051 __igt_fail_assert()
(kms_flip:1517) igt_core-INFO:   #1 ../tests/kms_flip.c:791 run_test_on_crtc_set.constprop.0()
(kms_flip:1517) igt_core-INFO:   #2 ../tests/kms_flip.c:1845 run_test()
(kms_flip:1517) igt_core-INFO:   #3 ../tests/kms_flip.c:2114 __igt_unique____real_main2001()
(kms_flip:1517) igt_core-INFO:   #4 ../tests/kms_flip.c:2001 main()
(kms_flip:1517) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:1517) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:1517) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest B-VGA1: FAIL (9.960s)
Dmesg
<6> [54.441254] [IGT] kms_flip: starting dynamic subtest B-VGA1
<7> [54.441815] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [54.441878] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [54.455400] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:47:pipe A]
<7> [54.455495] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [54.455710] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [54.455889] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [54.456087] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in has_pch_encoder (expected yes, found no)
<7> [54.456359] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in fdi_lanes (expected 1, found 0)
<7> [54.456633] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in fdi_m_n (expected tu 64 data 6039797/8388608 link 125829/524288, found tu 0, data 0/0 link 0/0)
<7> [54.456910] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in output_types (expected 0x00000002, found 0x00000000)
<7> [54.457210] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [54.457486] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1024, found 0)
<7> [54.457762] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 1344, found 0)
<7> [54.458208] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1024, found 0)
<7> [54.458525] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 1344, found 0)
<7> [54.458834] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 1048, found 0)
<7> [54.459161] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 1184, found 0)
<7> [54.459472] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 768, found 0)
<7> [54.459782] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 768, found 0)
<7> [54.460131] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 771, found 0)
<7> [54.460406] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 777, found 0)
<7> [54.460682] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 806, found 0)
<7> [54.460958] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 806, found 0)
<7> [54.461249] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1024, found 0)
<7> [54.461524] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 1344, found 0)
<7> [54.461816] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1024, found 0)
<7> [54.462103] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 1344, found 0)
<7> [54.462376] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 1048, found 0)
<7> [54.462653] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 1184, found 0)
<7> [54.462926] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 768, found 0)
<7> [54.463232] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 768, found 0)
<7> [54.463506] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 771, found 0)
<7> [54.463781] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 777, found 0)
<7> [54.464049] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 806, found 0)
<7> [54.464321] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 806, found 0)
<7> [54.464594] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [54.464867] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)
<7> [54.465154] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [54.465430] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [54.465703] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 64800, found 0)
<7> [54.466030] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 64800, found 0)
<7> [54.466309] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in port_clock (expected 64800, found 0)
<7> [54.466582] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:47:pipe A] fastset requirement not met, forcing full modeset
<7> [54.466855] i915 0000:00:02.0: [drm:intel_unreference_shared_dpll_crtc [i915]] [CRTC:47:pipe A] releasing PCH DPLL A
<7> [54.467204] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 400000 kHz, actual 400000 kHz
<7> [54.467480] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7> [54.467761] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:47:pipe A] enable: no [modeset]
<7> [54.468101] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:32:primary A] fb: [NOFB], visible: no
<7> [54.468536] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [54.481088] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port B found
<7> [54.481295] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port C found
<7> [54.481496] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port D found
<7> [54.496984] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disable PCH DPLL A (active 0x1, on? 1) for [CRTC:47:pipe A]
<7> [54.497328] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disabling PCH DPLL A
<7> [54.497870] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:65:CRT]
<7> [54.498161] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:66:HDMI D]
<7> [54.498465] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:DP D]
<7> [54.498801] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:64:VGA-1]
<7> [54.499148] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:47:pipe A]
<7> [54.499519] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:63:pipe B]
<7> [54.499860] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:63:pipe B]
<7> [54.499896] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:64:VGA-1]
<7> [54.499968] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:64:VGA-1] Limiting display bpp to 24 (EDID bpp 0, max requested bpp 0, max platform bpp 36)
<7> [54.500211] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:63:pipe B] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [54.500427] i915 0000:00:02.0: [drm:intel_fdi_atomic_check_link [i915]] checking fdi config on pipe B, lanes 1
<7> [54.500682] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.enable (expected no, found yes)
<7> [54.500902] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.active (expected no, found yes)
<7> [54.501110] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in has_pch_encoder (expected no, found yes)
<7> [54.501318] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in fdi_lanes (expected 0, found 1)
<7> [54.501514] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] 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> [54.501738] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in output_types (expected 0x00000000, found 0x00000002)
<7> [54.501921] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [54.502130] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1024)
<7> [54.502338] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 1344)
<7> [54.502521] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1024)
<7> [54.502763] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 1344)
<7> [54.502947] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 1048)
<7> [54.503148] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 1184)
<7> [54.503331] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 768)
<7> [54.503541] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 768)
<7> [54.503759] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 771)
<7> [54.503977] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 777)
<7> [54.504167] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 806)
<7> [54.504360] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 806)
<7> [54.504557] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1024)
<7> [54.504830] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 1344)
<7> [54.505039] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1024)
<7> [54.505218] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 1344)
<7> [54.505417] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 1048)
<7> [54.505623] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 1184)
<7> [54.505866] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 768)
<7> [54.506079] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 768)
<7> [54.506283] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 771)
<7> [54.506461] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 777)
<7> [54.506679] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 806)
<7> [54.506916] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 806)
<7> [54.507114] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [54.507316] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [54.507536] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [54.507803] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in dpll_hw_state
<7> [54.507994] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [54.508208] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: dpll: 0x0, dpll_md: 0x0, fp0: 0x0, fp1: 0x0
<7> [54.508409] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [54.508626] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: dpll: 0x84100010, dpll_md: 0x0, fp0: 0x10c09, fp1: 0x10c09
<7> [54.508860] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [54.509052] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 64800)
<7> [54.509229] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 64800)
<7> [54.509426] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in port_clock (expected 0, found 64800)
<7> [54.509675] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:63:pipe B] fastset requirement not met, forcing full modeset
<7> [54.509912] i915 0000:00:02.0: [drm:intel_plane_calc_min_cdclk [i915]] [PLANE:48:primary B] min cdclk (64800 kHz) > [CRTC:63:pipe B] min cdclk (0 kHz)
<7> [54.510117] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 400000 kHz, actual 400000 kHz
<7> [54.510309] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7> [54.510489] i915 0000:00:02.0: [drm:intel_find_shared_dpll [i915]] [CRTC:63:pipe B] allocated PCH DPLL A
<7> [54.510699] i915 0000:00:02.0: [drm:intel_reference_shared_dpll_crtc [i915]] [CRTC:63:pipe B] reserving PCH DPLL A
<7> [54.510930] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:63:pipe B] enable: yes [modeset]
<7> [54.511136] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: ANALOG (0x2), output format: RGB, sink format: RGB
<7> [54.511315] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: B, pipe bpp: 24, dithering: 0
<7> [54.511516] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [54.511746] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [54.511937] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [54.512143] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [54.512363] 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> [54.512586] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0
<7> [54.512781] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [54.512982] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, vmin: 0, vmax: 0, pipeline full: 0, guardband: 0 flipline: 0, vmin vblank: -1, vmax vblank: -2
<7> [54.513173] 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> [54.513371] 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> [54.513594] 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> [54.513833] 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> [54.514013] 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> [54.514216] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 64800, pipe src: 1024x768+0+0, pixel rate 64800
<7> [54.514407] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 0, ips linetime: 0
<7> [54.514617] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [54.514873] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [54.515095] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: dpll: 0x84100010, dpll_md: 0x0, fp0: 0x10c09, fp1: 0x10c09
<7> [54.515314] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x3 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [54.515553] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [54.515752] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [54.515954] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [54.516157] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [54.516343] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [54.516733] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:48:primary B] fb: [FB:84] 1024x768 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [54.516712] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling always-on
<7> [54.516922] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [54.517108] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1024.000000x768.000000+0.000000+0.000000 dst: 1024x768+0+0
<7> [54.518307] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling always-on
<7> [54.518558] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:65:CRT]
<7> [54.518730] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:66:HDMI D]
<7> [54.518924] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:DP D]
<7> [54.519648] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe B
<7> [54.520687] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x100
<7> [54.520864] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 1 done.
<7> [54.521711] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x600
<7> [54.521888] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 2 done.
<7> [54.522060] i915 0000:00:02.0: [drm:ilk_pch_enable [i915]] FDI train done.
<7> [54.522234] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enable PCH DPLL A (active 0x2, on? 0) for [CRTC:63:pipe B]
<7> [54.522410] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enabling PCH DPLL A
<7> [54.574341] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:64:VGA-1]
<7> [54.574774] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:63:pipe B]
<6> [64.401455] [IGT] kms_flip: finished subtest B-VGA1, FAIL
Created at 2024-10-30 21:08:47