Results for igt@kms_flip@wf_vblank-ts-check@b-hdmi-a1

Machine description: shard-dg2-4

Result: Fail

git-log-oneline i915_display_info7 igt_runner7 runtimes7 results7.json results7-i915-load.json i915_display_info_post_exec7 boot7 dmesg7

DetailValue
Duration 10.06 seconds
Hostname 
shard-dg2-4
Igt-Version 
IGT-Version: 1.30-g1595d0371 (x86_64) (Linux: 6.14.0-rc5-CI_DRM_16231-g3ccfd8016ad8+ x86_64)
Out 
Starting dynamic subtest: B-HDMI-A1
  1920x1080: 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5 
Expected frametime: 16667us; measured 16664.9us +- 5.841us accuracy 0.11%
Stack trace:
  #0 ../lib/igt_core.c:2055 __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:2078 __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-HDMI-A1: FAIL (10.061s)
Err 
Starting dynamic subtest: B-HDMI-A1
(kms_flip:2547) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1674:
(kms_flip:2547) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon)
Dynamic subtest B-HDMI-A1 failed.
**** DEBUG ****
(kms_flip:2547) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=XR24(0x34325258), modifier=0x0, size=0)
(kms_flip:2547) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=1, pitch=7680)
(kms_flip:2547) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd)
(kms_flip:2547) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=XR24(0x34325258), modifier=0x0, size=0)
(kms_flip:2547) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=2, pitch=7680)
(kms_flip:2547) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd)
(kms_flip:2547) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2547) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2547) igt_fb-DEBUG: Test requirement passed: gem_has_contexts(fb->fd)
(kms_flip:2547) intel_allocator_reloc-DEBUG: Using reloc allocator
(kms_flip:2547) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2547) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2547) igt_fb-DEBUG: Test requirement passed: gem_has_contexts(fb->fd)
(kms_flip:2547) intel_allocator_reloc-DEBUG: Using reloc allocator
(kms_flip:2547) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2547) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2547) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2547) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2547) igt_fb-DEBUG: Test requirement passed: gem_has_contexts(fb->fd)
(kms_flip:2547) intel_allocator_reloc-DEBUG: Using reloc allocator
(kms_flip:2547) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2547) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2547) igt_fb-DEBUG: Test requirement passed: gem_has_contexts(fb->fd)
(kms_flip:2547) intel_allocator_reloc-DEBUG: Using reloc allocator
(kms_flip:2547) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2547) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2547) igt_kms-INFO:   1920x1080: 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5 
(kms_flip:2547) DEBUG: No stale events found
(kms_flip:2547) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0
(kms_flip:2547) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0
(kms_flip:2547) INFO: Expected frametime: 16667us; measured 16664.9us +- 5.841us accuracy 0.11%
(kms_flip:2547) DEBUG: name = vblank
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 508.561615
current_received_ts = 508.561401
current_seq = 610
count = 0
seq_step = 10
(kms_flip:2547) DEBUG: name = vblank
last_ts = 508.561615
last_received_ts = 508.561401
last_seq = 610
current_ts = 508.728271
current_received_ts = 508.728088
current_seq = 620
count = 1
seq_step = 10
(kms_flip:2547) DEBUG: vblank ts/seq: last 508.561615/610, current 508.728271/620: elapsed=166641.0us expected=166648.8us +- 833.2us, error 0.0%
(kms_flip:2547) DEBUG: name = vblank
last_ts = 508.728271
last_received_ts = 508.728088
last_seq = 620
current_ts = 508.894897
current_received_ts = 508.894684
current_seq = 630
count = 2
seq_step = 10
(kms_flip:2547) DEBUG: vblank ts/seq: last 508.728271/620, current 508.894897/630: elapsed=166651.0us expected=166648.8us +- 833.2us, error 0.0%
(kms_flip:2547) DEBUG: name = vblank
last_ts = 508.894897
last_received_ts = 508.894684
last_seq = 630
current_ts = 509.061554
current_received_ts = 509.061310
current_seq = 640
count = 3
seq_step = 10
(kms_flip:2547) DEBUG: vblank ts/seq: last 508.894897/630, current 509.061554/640: elapsed=166644.0us expected=166648.8us +- 833.2us, error 0.0%
(kms_flip:2547) DEBUG: name = vblank
last_ts = 509.061554
last_received_ts = 509.061310
last_seq = 640
current_ts = 509.228210
current_received_ts = 509.227875
current_seq = 650
count = 4
seq_step = 10
(kms_flip:2547) DEBUG: vblank ts/seq: last 509.061554/640, current 509.228210/650: elapsed=166645.0us expected=166648.8us +- 833.2us, error 0.0%
(kms_flip:2547) DEBUG: name = vblank
last_ts = 509.228210
last_received_ts = 509.227875
last_seq = 650
current_ts = 509.394836
current_received_ts = 509.394623
current_seq = 660
count = 5
seq_step = 10
(kms_flip:2547) DEBUG: vblank ts/seq: last 509.228210/650, current 509.394836/660: elapsed=166646.0us expected=166648.8us +- 833.2us, error 0.0%
(kms_flip:2547) DEBUG: name = vblank
last_ts = 509.394836
last_received_ts = 509.394623
last_seq = 660
current_ts = 509.561493
current_received_ts = 509.561279
current_seq = 670
count = 6
seq_step = 10
(kms_flip:2547) DEBUG: vblank ts/seq: last 509.394836/660, current 509.561493/670: elapsed=166652.0us expected=166648.8us +- 833.2us, error 0.0%
(kms_flip:2547) DEBUG: name = vblank
last_ts = 509.561493
last_received_ts = 509.561279
last_seq = 670
current_ts = 509.728149
current_received_ts = 509.727905
current_seq = 680
count = 7
seq_step = 10
(kms_flip:2547) DEBUG: vblank ts/seq: last 509.561493/670, current 509.728149/680: elapsed=166648.0us expected=166648.8us +- 833.2us, error 0.0%
(kms_flip:2547) DEBUG: name = vblank
last_ts = 509.728149
last_received_ts = 509.727905
last_seq = 680
current_ts = 509.894775
current_received_ts = 509.894531
current_seq = 690
count = 8
seq_step = 10
(kms_flip:2547) DEBUG: vblank ts/seq: last 509.728149/680, current 509.894775/690: elapsed=166643.0us expected=166648.8us +- 833.2us, error 0.0%
(kms_flip:2547) DEBUG: name = vblank
last_ts = 509.894775
last_received_ts = 509.894531
last_seq = 690
current_ts = 510.061432
current_received_ts = 510.061188
current_seq = 700
count = 9
seq_step = 10
(kms_flip:2547) DEBUG: vblank ts/seq: last 509.894775/690, current 510.061432/700: elapsed=166644.0us expected=166648.8us +- 833.2us, error 0.0%
(kms_flip:2547) DEBUG: name = vblank
last_ts = 510.061432
last_received_ts = 510.061188
last_seq = 700
current_ts = 510.228088
current_received_ts = 510.227875
current_seq = 710
count = 10
seq_step = 10
(kms_flip:2547) DEBUG: vblank ts/seq: last 510.061432/700, current 510.228088/710: elapsed=166660.0us expected=166648.8us +- 833.2us, error 0.0%
(kms_flip:2547) DEBUG: name = vblank
last_ts = 510.228088
last_received_ts = 510.227875
last_seq = 710
current_ts = 510.394714
current_received_ts = 510.394531
current_seq = 720
count = 11
seq_step = 10
(kms_flip:2547) DEBUG: vblank ts/seq: last 510.228088/710, current 510.394714/720: elapsed=166636.0us expected=166648.8us +- 833.2us, error 0.0%
(kms_flip:2547) DEBUG: name = vblank
last_ts = 510.394714
last_received_ts = 510.394531
last_seq = 720
current_ts = 510.561371
current_received_ts = 510.560944
current_seq = 730
count = 12
seq_step = 10
(kms_flip:2547) DEBUG: vblank ts/seq: last 510.394714/720, current 510.561371/730: elapsed=166653.0us expected=166648.8us +- 833.2us, error 0.0%
(kms_flip:2547) DEBUG: name = vblank
last_ts = 510.561371
last_received_ts = 510.560944
last_seq = 730
current_ts = 510.728027
current_received_ts = 510.727814
current_seq = 740
count = 13
seq_step = 10
(kms_flip:2547) DEBUG: vblank ts/seq: last 510.561371/730, current 510.728027/740: elapsed=166651.0us expected=166648.8us +- 833.2us, error 0.0%
(kms_flip:2547) DEBUG: name = vblank
last_ts = 510.728027
last_received_ts = 510.727814
last_seq = 740
current_ts = 510.894684
current_received_ts = 510.894470
current_seq = 750
count = 14
seq_step = 10
(kms_flip:2547) DEBUG: vblank ts/seq: last 510.728027/740, current 510.894684/750: elapsed=166650.0us expected=166648.8us +- 833.2us, error 0.0%
(kms_flip:2547) DEBUG: name = vblank
last_ts = 510.894684
last_received_ts = 510.894470
last_seq = 750
current_ts = 511.061310
current_received_ts = 511.061096
current_seq = 760
count = 15
seq_step = 10
(kms_flip:2547) DEBUG: vblank ts/seq: last 510.894684/750, current 511.061310/760: elapsed=166641.0us expected=166648.8us +- 833.2us, error 0.0%
(kms_flip:2547) DEBUG: name = vblank
last_ts = 511.061310
last_received_ts = 511.061096
last_seq = 760
current_ts = 511.227966
current_received_ts = 511.227753
current_seq = 770
count = 16
seq_step = 10
(kms_flip:2547) DEBUG: vblank ts/seq: last 511.061310/760, current 511.227966/770: elapsed=166657.0us expected=166648.8us +- 833.2us, error 0.0%
(kms_flip:2547) DEBUG: name = vblank
last_ts = 511.227966
last_received_ts = 511.227753
last_seq = 770
current_ts = 511.394623
current_received_ts = 511.394226
current_seq = 780
count = 17
seq_step = 10
(kms_flip:2547) DEBUG: vblank ts/seq: last 511.227966/770, current 511.394623/780: elapsed=166643.0us expected=166648.8us +- 833.2us, error 0.0%
(kms_flip:2547) DEBUG: name = vblank
last_ts = 511.394623
last_received_ts = 511.394226
last_seq = 780
current_ts = 511.561279
current_received_ts = 511.561096
current_seq = 790
count = 18
seq_step = 10
(kms_flip:2547) DEBUG: vblank ts/seq: last 511.394623/780, current 511.561279/790: elapsed=166654.0us expected=166648.8us +- 833.2us, error 0.0%
(kms_flip:2547) DEBUG: name = vblank
last_ts = 511.561279
last_received_ts = 511.561096
last_seq = 790
current_ts = 511.727936
current_received_ts = 511.727722
current_seq = 800
count = 19
seq_step = 10
(kms_flip:2547) DEBUG: vblank ts/seq: last 511.561279/790, current 511.727936/800: elapsed=166648.0us expected=166648.8us +- 833.2us, error 0.0%
(kms_flip:2547) DEBUG: name = vblank
last_ts = 511.727936
last_received_ts = 511.727722
last_seq = 800
current_ts = 511.894562
current_received_ts = 511.894318
current_seq = 810
count = 20
seq_step = 10
(kms_flip:2547) DEBUG: vblank ts/seq: last 511.727936/800, current 511.894562/810: elapsed=166643.0us expected=166648.8us +- 833.2us, error 0.0%
(kms_flip:2547) DEBUG: name = vblank
last_ts = 511.894562
last_received_ts = 511.894318
last_seq = 810
current_ts = 512.061218
current_received_ts = 512.060974
current_seq = 820
count = 21
seq_step = 10
(kms_flip:2547) DEBUG: vblank ts/seq: last 511.894562/810, current 512.061218/820: elapsed=166660.0us expected=166648.8us +- 833.2us, error 0.0%
(kms_flip:2547) DEBUG: name = vblank
last_ts = 512.061218
last_received_ts = 512.060974
last_seq = 820
current_ts = 512.227844
current_received_ts = 512.227661
current_seq = 830
count = 22
seq_step = 10
(kms_flip:2547) DEBUG: vblank ts/seq: last 512.061218/820, current 512.227844/830: elapsed=166646.0us expected=166648.8us +- 833.2us, error 0.0%
(kms_flip:2547) DEBUG: name = vblank
last_ts = 512.227844
last_received_ts = 512.227661
last_seq = 830
current_ts = 512.394531
current_received_ts = 512.394165
current_seq = 840
count = 23
seq_step = 10
(kms_flip:2547) DEBUG: vblank ts/seq: last 512.227844/830, current 512.394531/840: elapsed=166643.0us expected=166648.8us +- 833.2us, error 0.0%
(kms_flip:2547) DEBUG: name = vblank
last_ts = 512.394531
last_received_ts = 512.394165
last_seq = 840
current_ts = 512.561157
current_received_ts = 512.560913
current_seq = 850
count = 24
seq_step = 10
(kms_flip:2547) DEBUG: vblank ts/seq: last 512.394531/840, current 512.561157/850: elapsed=166657.0us expected=166648.8us +- 833.2us, error 0.0%
(kms_flip:2547) DEBUG: name = vblank
last_ts = 512.561157
last_received_ts = 512.560913
last_seq = 850
current_ts = 512.727844
current_received_ts = 512.727600
current_seq = 860
count = 25
seq_step = 10
(kms_flip:2547) DEBUG: vblank ts/seq: last 512.561157/850, current 512.727844/860: elapsed=166652.0us expected=166648.8us +- 833.2us, error 0.0%
(kms_flip:2547) DEBUG: name = vblank
last_ts = 512.727844
last_received_ts = 512.727600
last_seq = 860
current_ts = 512.894470
current_received_ts = 512.894287
current_seq = 870
count = 26
seq_step = 10
(kms_flip:2547) DEBUG: vblank ts/seq: last 512.727844/860, current 512.894470/870: elapsed=166650.0us expected=166648.8us +- 833.2us, error 0.0%
(kms_flip:2547) DEBUG: name = vblank
last_ts = 512.894470
last_received_ts = 512.894287
last_seq = 870
current_ts = 513.061157
current_received_ts = 513.060852
current_seq = 880
count = 27
seq_step = 10
(kms_flip:2547) DEBUG: vblank ts/seq: last 512.894470/870, current 513.061157/880: elapsed=166652.0us expected=166648.8us +- 833.2us, error 0.0%
(kms_flip:2547) DEBUG: name = vblank
last_ts = 513.061157
last_received_ts = 513.060852
last_seq = 880
current_ts = 513.227783
current_received_ts = 513.227539
current_seq = 890
count = 28
seq_step = 10
(kms_flip:2547) DEBUG: vblank ts/seq: last 513.061157/880, current 513.227783/890: elapsed=166644.0us expected=166648.8us +- 833.2us, error 0.0%
(kms_flip:2547) DEBUG: name = vblank
last_ts = 513.227783
last_received_ts = 513.227539
last_seq = 890
current_ts = 513.394409
current_received_ts = 513.394165
current_seq = 900
count = 29
seq_step = 10
(kms_flip:2547) DEBUG: vblank ts/seq: last 513.227783/890, current 513.394409/900: elapsed=166658.0us expected=166648.8us +- 833.2us, error 0.0%
(kms_flip:2547) DEBUG: name = vblank
last_ts = 513.394409
last_received_ts = 513.394165
last_seq = 900
current_ts = 513.561096
current_received_ts = 513.560852
current_seq = 910
count = 30
seq_step = 10
(kms_flip:2547) DEBUG: vblank ts/seq: last 513.394409/900, current 513.561096/910: elapsed=166650.0us expected=166648.8us +- 833.2us, error 0.0%
(kms_flip:2547) DEBUG: name = vblank
last_ts = 513.561096
last_received_ts = 513.560852
last_seq = 910
current_ts = 513.727722
current_received_ts = 513.727478
current_seq = 920
count = 31
seq_step = 10
(kms_flip:2547) DEBUG: vblank ts/seq: last 513.561096/910, current 513.727722/920: elapsed=166647.0us expected=166648.8us +- 833.2us, error 0.0%
(kms_flip:2547) DEBUG: name = vblank
last_ts = 513.727722
last_received_ts = 513.727478
last_seq = 920
current_ts = 513.894409
current_received_ts = 513.894165
current_seq = 930
count = 32
seq_step = 10
(kms_flip:2547) DEBUG: vblank ts/seq: last 513.727722/920, current 513.894409/930: elapsed=166655.0us expected=166648.8us +- 833.2us, error 0.0%
(kms_flip:2547) DEBUG: name = vblank
last_ts = 513.894409
last_received_ts = 513.894165
last_seq = 930
current_ts = 514.061035
current_received_ts = 514.060730
current_seq = 940
count = 33
seq_step = 10
(kms_flip:2547) DEBUG: vblank ts/seq: last 513.894409/930, current 514.061035/940: elapsed=166653.0us expected=166648.8us +- 833.2us, error 0.0%
(kms_flip:2547) DEBUG: name = vblank
last_ts = 514.061035
last_received_ts = 514.060730
last_seq = 940
current_ts = 514.227661
current_received_ts = 514.227478
current_seq = 950
count = 34
seq_step = 10
(kms_flip:2547) DEBUG: vblank ts/seq: last 514.061035/940, current 514.227661/950: elapsed=166650.0us expected=166648.8us +- 833.2us, error 0.0%
(kms_flip:2547) DEBUG: name = vblank
last_ts = 514.227661
last_received_ts = 514.227478
last_seq = 950
current_ts = 514.394348
current_received_ts = 514.394104
current_seq = 960
count = 35
seq_step = 10
(kms_flip:2547) DEBUG: vblank ts/seq: last 514.227661/950, current 514.394348/960: elapsed=166656.0us expected=166648.8us +- 833.2us, error 0.0%
(kms_flip:2547) DEBUG: name = vblank
last_ts = 514.394348
last_received_ts = 514.394104
last_seq = 960
current_ts = 514.560974
current_received_ts = 514.560791
current_seq = 970
count = 36
seq_step = 10
(kms_flip:2547) DEBUG: vblank ts/seq: last 514.394348/960, current 514.560974/970: elapsed=166644.0us expected=166648.8us +- 833.2us, error 0.0%
(kms_flip:2547) DEBUG: name = vblank
last_ts = 514.560974
last_received_ts = 514.560791
last_seq = 970
current_ts = 514.727661
current_received_ts = 514.727417
current_seq = 980
count = 37
seq_step = 10
(kms_flip:2547) DEBUG: vblank ts/seq: last 514.560974/970, current 514.727661/980: elapsed=166677.0us expected=166648.8us +- 833.2us, error 0.0%
(kms_flip:2547) DEBUG: name = vblank
last_ts = 514.727661
last_received_ts = 514.727417
last_seq = 980
current_ts = 514.895081
current_received_ts = 514.894897
current_seq = 990
count = 38
seq_step = 10
(kms_flip:2547) DEBUG: vblank ts/seq: last 514.727661/980, current 514.895081/990: elapsed=167445.0us expected=166648.8us +- 833.2us, error 0.5%
(kms_flip:2547) DEBUG: name = vblank
last_ts = 514.895081
last_received_ts = 514.894897
last_seq = 990
current_ts = 515.067566
current_received_ts = 515.067322
current_seq = 1000
count = 39
seq_step = 10
(kms_flip:2547) DEBUG: vblank ts/seq: last 514.895081/990, current 515.067566/1000: elapsed=172470.0us expected=166648.8us +- 833.2us, error 3.5%
(kms_flip:2547) DEBUG: inconsistent vblank ts/seq: last 514.895081/990, current 515.067566/1000: elapsed=172470.0us expected=166648.8us
(kms_flip:2547) DEBUG: expected 459, counted 390, encoder type 2
(kms_flip:2547) DEBUG: dropped frames, expected 459, counted 390, encoder type 2
(kms_flip:2547) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1674:
(kms_flip:2547) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon)
(kms_flip:2547) igt_core-INFO: Stack trace:
(kms_flip:2547) igt_core-INFO:   #0 ../lib/igt_core.c:2055 __igt_fail_assert()
(kms_flip:2547) igt_core-INFO:   #1 ../tests/kms_flip.c:791 run_test_on_crtc_set.constprop.0()
(kms_flip:2547) igt_core-INFO:   #2 ../tests/kms_flip.c:1845 run_test()
(kms_flip:2547) igt_core-INFO:   #3 ../tests/kms_flip.c:2078 __igt_unique____real_main2001()
(kms_flip:2547) igt_core-INFO:   #4 ../tests/kms_flip.c:2001 main()
(kms_flip:2547) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:2547) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:2547) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest B-HDMI-A1: FAIL (10.061s)
Dmesg
<6> [507.698751] [IGT] kms_flip: starting dynamic subtest B-HDMI-A1
<7> [507.699187] i915 0000:03:00.0: [drm:drm_mode_addfb2] [FB:295]
<7> [507.699279] i915 0000:03:00.0: [drm:drm_mode_addfb2] [FB:296]
<7> [507.699708] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [507.700267] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [507.714173] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [507.714407] i915 0000:03:00.0: [drm:gen9_enable_dc5 [i915]] Enabling DC5
<7> [507.714554] i915 0000:03:00.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 01
<7> [507.719947] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [507.720309] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [507.724461] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [507.724767] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [507.743391] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [507.743660] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [507.767864] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:82:pipe A]
<7> [507.767957] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [507.768154] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [507.768349] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [507.768492] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [507.768635] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [507.768778] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [507.768935] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [507.769080] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [507.769278] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [507.769449] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [507.769591] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [507.769733] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [507.769904] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [507.770045] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [507.770231] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [507.770409] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [507.770570] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [507.770740] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [507.770881] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [507.771023] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [507.771216] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [507.771416] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [507.771558] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [507.771699] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [507.771841] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [507.771983] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [507.772180] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [507.772402] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [507.772574] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [507.772716] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [507.772857] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [507.773014] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [507.773242] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [507.773420] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [507.773567] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [507.773723] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [507.773892] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in port_clock (expected 148500, found 0)
<7> [507.774034] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:82:pipe A] fastset requirement not met, forcing full modeset
<7> [507.774265] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0xf), mbus joined? no->no
<7> [507.774418] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [CRTC:82:pipe A] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x1 -> 0x0
<7> [507.774619] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [PLANE:77:cursor A] ddb (1963 - 2048) -> ( 0 - 0), size 85 -> 0
<7> [507.774774] i915 0000:03:00.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 163200 kHz, actual 163200 kHz
<7> [507.774915] i915 0000:03:00.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7> [507.775059] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [CRTC:82:pipe A] enable: no [modeset]
<7> [507.775242] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:32:plane 1A] fb: [NOFB], visible: no
<7> [507.775430] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:41:plane 2A] fb: [NOFB], visible: no
<7> [507.775607] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:50:plane 3A] fb: [NOFB], visible: no
<7> [507.775748] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:59:plane 4A] fb: [NOFB], visible: no
<7> [507.775889] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:68:plane 5A] fb: [NOFB], visible: no
<7> [507.776029] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:77:cursor A] fb: [NOFB], visible: no
<7> [507.776288] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [507.776438] i915 0000:03:00.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 01 to 00
<7> [507.776708] i915 0000:03:00.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [507.781243] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_B
<7> [507.781445] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:240:DDI A/PHY A]
<7> [507.781588] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:242:DP-MST A]
<7> [507.781730] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:243:DP-MST B]
<7> [507.781871] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:244:DP-MST C]
<7> [507.782013] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:245:DP-MST D]
<7> [507.782202] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:253:DDI B/PHY B]
<7> [507.782392] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:255:DP-MST A]
<7> [507.782535] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:256:DP-MST B]
<7> [507.782677] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:257:DP-MST C]
<7> [507.782818] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:258:DP-MST D]
<7> [507.782959] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:268:DDI C/PHY C]
<7> [507.783100] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:270:DP-MST A]
<7> [507.783280] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:271:DP-MST B]
<7> [507.783422] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:272:DP-MST C]
<7> [507.783562] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:273:DP-MST D]
<7> [507.783704] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:280:DDI D/PHY D]
<7> [507.783844] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:282:DP-MST A]
<7> [507.783986] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:283:DP-MST B]
<7> [507.784150] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:284:DP-MST C]
<7> [507.784306] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:285:DP-MST D]
<7> [507.784447] i915 0000:03:00.0: [drm:verify_connector_state [i915]] [CONNECTOR:263:HDMI-A-1]
<7> [507.784618] i915 0000:03:00.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [507.784867] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling PW_A
<7> [507.785124] i915 0000:03:00.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:82:pipe A]
<7> [507.785505] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:134:pipe B]
<7> [507.785661] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:186:pipe C]
<7> [507.785799] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:238:pipe D]
<7> [507.785939] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:134:pipe B]
<7> [507.785969] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CONNECTOR:263:HDMI-A-1]
<7> [507.786033] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CONNECTOR:263:HDMI-A-1] Limiting display bpp to 24 (EDID bpp 0, max requested bpp 36, max platform bpp 36)
<7> [507.786232] i915 0000:03:00.0: [drm:intel_hdmi_compute_clock [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [507.786370] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:134:pipe B] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [507.786515] i915 0000:03:00.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:253:DDI B/PHY B] [CRTC:134:pipe B]
<7> [507.786656] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.enable (expected no, found yes)
<7> [507.786801] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.active (expected no, found yes)
<7> [507.786946] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in cpu_transcoder (expected -1, found 1)
<7> [507.787092] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in lane_count (expected 0, found 4)
<7> [507.787278] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [507.787423] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [507.787568] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [507.787712] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [507.787857] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [507.788001] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [507.788202] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [507.788424] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [507.788569] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [507.788714] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [507.788859] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [507.789003] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [507.789186] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [507.789331] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [507.789475] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [507.789621] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [507.789765] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [507.789909] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [507.790054] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [507.790237] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [507.790382] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [507.790527] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [507.790671] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [507.790816] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [507.790960] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [507.791104] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [507.791283] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [507.791427] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [507.791572] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [507.791718] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [507.791863] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [507.792007] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [507.792243] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in port_clock (expected 0, found 148500)
<7> [507.792388] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:134:pipe B] fastset requirement not met, forcing full modeset
<7> [507.792556] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0xf), mbus joined? no->no
<7> [507.792697] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [CRTC:134:pipe B] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x2
<7> [507.792871] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [PLANE:84:plane 1B] ddb ( 0 - 0) -> ( 0 - 1963), size 0 -> 1963
<7> [507.793012] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [PLANE:129:cursor B] ddb ( 0 - 0) -> (1963 - 2048), size 0 -> 85
<7> [507.793180] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [PLANE:84:plane 1B] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm
<7> [507.793335] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [PLANE:84:plane 1B] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 3, 4, 5, 9, 11, 17, 25, 0, 0, 0
<7> [507.793476] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [PLANE:84:plane 1B] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 49, 65, 81, 145, 177, 273, 401, 30, 0, 0
<7> [507.793617] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [PLANE:84:plane 1B] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 19, 55, 73, 91, 161, 196, 302, 443, 31, 0, 0
<7> [507.793758] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] [CRTC:134:pipe B] data rate 594000 num active planes 1
<7> [507.793903] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 50000 required 624
<7> [507.794046] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [507.794239] i915 0000:03:00.0: [drm:intel_plane_calc_min_cdclk [i915]] [PLANE:84:plane 1B] min cdclk (74250 kHz) > [CRTC:134:pipe B] min cdclk (0 kHz)
<7> [507.794384] i915 0000:03:00.0: [drm:intel_bw_calc_min_cdclk [i915]] new bandwidth min cdclk (11602 kHz) > old min cdclk (0 kHz)
<7> [507.794530] i915 0000:03:00.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 163200 kHz, actual 163200 kHz
<7> [507.794674] i915 0000:03:00.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7> [507.794824] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [CRTC:134:pipe B] enable: yes [modeset]
<7> [507.794968] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [507.795112] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: B, pipe bpp: 24, dithering: 0
<7> [507.795299] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [507.795443] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [507.795587] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [507.795730] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [507.795874] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [507.796018] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [507.796199] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] vblank delay: 0, framestart delay: 1, MSA timing delay: 0
<7> [507.796343] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] vrr: no, vmin: 0, vmax: 0, flipline: 0, pipeline full: 0, guardband: 0 vsync start: 0, vsync end: 0
<7> [507.796487] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 0, vmax vblank: 0, vmin vtotal: 0, vmax vtotal: 0
<7> [507.796630] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
<7> [507.796778] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
<7> [507.796922] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [507.797067] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [507.797246] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [507.797392] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] port clock: 148500, pipe src: 1920x1080+0+0, pixel rate 148500
<7> [507.797536] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] linetime: 119, ips linetime: 0
<7> [507.797680] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [507.797824] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [507.797968] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [507.798112] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: dpll: 0x24414, dpll_md: 0x18000000, fp0: 0x8801ef8, fp1: 0x2b000440
<7> [507.798312] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [507.798489] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [507.798638] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [507.798782] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [507.798926] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [507.799070] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [507.799248] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [507.799392] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [507.799536] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [507.799681] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [507.799825] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [507.799969] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [507.800119] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:84:plane 1B] fb: [FB:295] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [507.800299] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [507.800443] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [507.800588] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:93:plane 2B] fb: [NOFB], visible: no
<7> [507.800732] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:102:plane 3B] fb: [NOFB], visible: no
<7> [507.800875] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:111:plane 4B] fb: [NOFB], visible: no
<7> [507.801019] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:120:plane 5B] fb: [NOFB], visible: no
<7> [507.801215] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:129:cursor B] fb: [NOFB], visible: no
<7> [507.801850] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling PW_2
<7> [507.802065] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling PW_B
<7> [507.802331] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:240:DDI A/PHY A]
<7> [507.802473] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:242:DP-MST A]
<7> [507.802615] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:243:DP-MST B]
<7> [507.802756] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:244:DP-MST C]
<7> [507.802896] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:245:DP-MST D]
<7> [507.803037] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:253:DDI B/PHY B]
<7> [507.803219] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:255:DP-MST A]
<7> [507.803399] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:256:DP-MST B]
<7> [507.803539] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:257:DP-MST C]
<7> [507.803680] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:258:DP-MST D]
<7> [507.803821] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:268:DDI C/PHY C]
<7> [507.803962] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:270:DP-MST A]
<7> [507.804103] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:271:DP-MST B]
<7> [507.804281] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:272:DP-MST C]
<7> [507.804422] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:273:DP-MST D]
<7> [507.804563] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:280:DDI D/PHY D]
<7> [507.804704] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:282:DP-MST A]
<7> [507.804844] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:283:DP-MST B]
<7> [507.804985] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:284:DP-MST C]
<7> [507.805130] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:285:DP-MST D]
<7> [507.805308] i915 0000:03:00.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [507.805660] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_B
<7> [507.805923] i915 0000:03:00.0: [drm:intel_enable_transcoder [i915]] enabling pipe B
<7> [507.824916] i915 0000:03:00.0: [drm:verify_connector_state [i915]] [CONNECTOR:263:HDMI-A-1]
<7> [507.825158] i915 0000:03:00.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:134:pipe B]
<6> [517.760309] [IGT] kms_flip: finished subtest B-HDMI-A1, FAIL
Created at 2025-03-06 09:19:48