Results for igt@kms_flip@2x-flip-vs-expired-vblank@ac-dp2-hdmi-a3

Result: Fail

i915_display_info24 igt_runner24 results24.json results24-xe-load.json guc_logs24.tar i915_display_info_post_exec24 boot24 dmesg24

DetailValue
Duration 5.55 seconds
Hostname 
shard-bmg-3
Igt-Version 
IGT-Version: 2.4-gb52b42b0c (x86_64) (Linux: 7.0.0-rc7-lgci-xe-xe-pw-159772v5-debug+ x86_64)
Out 
Starting dynamic subtest: AC-DP2-HDMI-A3
  1920x1080: 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5 
  1920x1080: 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9 
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../tests/kms_flip.c:926 run_test_on_crtc_set()
  #2 ../tests/kms_flip.c:2029 run_pair()
  #3 ../tests/kms_flip.c:2193 __igt_unique____real_main2105()
  #4 ../tests/kms_flip.c:2105 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest AC-DP2-HDMI-A3: FAIL (5.554s)
Err 
Starting dynamic subtest: AC-DP2-HDMI-A3
(kms_flip:13861) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931:
(kms_flip:13861) CRITICAL: Failed assertion: end - start < 500
Dynamic subtest AC-DP2-HDMI-A3 failed.
**** DEBUG ****
(kms_flip:13861) DEBUG: name = flip
last_ts = 494.682831
last_received_ts = 494.682465
last_seq = 17698
current_ts = 494.699493
current_received_ts = 494.699127
current_seq = 17699
count = 83
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 28us
(kms_flip:13861) DEBUG: name = flip
last_ts = 494.699493
last_received_ts = 494.699127
last_seq = 17699
current_ts = 494.716156
current_received_ts = 494.715790
current_seq = 17700
count = 84
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 27us
(kms_flip:13861) DEBUG: name = flip
last_ts = 494.716156
last_received_ts = 494.715790
last_seq = 17700
current_ts = 494.732819
current_received_ts = 494.732452
current_seq = 17701
count = 85
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 28us
(kms_flip:13861) DEBUG: name = flip
last_ts = 494.732819
last_received_ts = 494.732452
last_seq = 17701
current_ts = 494.749481
current_received_ts = 494.749115
current_seq = 17702
count = 86
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 25us
(kms_flip:13861) DEBUG: name = flip
last_ts = 494.749481
last_received_ts = 494.749115
last_seq = 17702
current_ts = 494.766144
current_received_ts = 494.765778
current_seq = 17703
count = 87
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 26us
(kms_flip:13861) DEBUG: name = flip
last_ts = 494.766144
last_received_ts = 494.765778
last_seq = 17703
current_ts = 494.782837
current_received_ts = 494.782440
current_seq = 17704
count = 88
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 26us
(kms_flip:13861) DEBUG: name = flip
last_ts = 494.782837
last_received_ts = 494.782440
last_seq = 17704
current_ts = 494.799500
current_received_ts = 494.798981
current_seq = 17705
count = 89
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 25us
(kms_flip:13861) DEBUG: name = flip
last_ts = 494.799500
last_received_ts = 494.798981
last_seq = 17705
current_ts = 494.816162
current_received_ts = 494.815674
current_seq = 17706
count = 90
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 26us
(kms_flip:13861) DEBUG: name = flip
last_ts = 494.816162
last_received_ts = 494.815674
last_seq = 17706
current_ts = 494.832825
current_received_ts = 494.832336
current_seq = 17707
count = 91
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 25us
(kms_flip:13861) DEBUG: name = flip
last_ts = 494.832825
last_received_ts = 494.832336
last_seq = 17707
current_ts = 494.849487
current_received_ts = 494.848999
current_seq = 17708
count = 92
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 26us
(kms_flip:13861) DEBUG: name = flip
last_ts = 494.849487
last_received_ts = 494.848999
last_seq = 17708
current_ts = 494.866180
current_received_ts = 494.865662
current_seq = 17709
count = 93
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 26us
(kms_flip:13861) DEBUG: name = flip
last_ts = 494.866180
last_received_ts = 494.865662
last_seq = 17709
current_ts = 494.882843
current_received_ts = 494.882446
current_seq = 17710
count = 94
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 26us
(kms_flip:13861) DEBUG: name = flip
last_ts = 494.882843
last_received_ts = 494.882446
last_seq = 17710
current_ts = 494.899506
current_received_ts = 494.899139
current_seq = 17711
count = 95
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 26us
(kms_flip:13861) DEBUG: name = flip
last_ts = 494.899506
last_received_ts = 494.899139
last_seq = 17711
current_ts = 494.916168
current_received_ts = 494.915802
current_seq = 17712
count = 96
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 26us
(kms_flip:13861) DEBUG: name = flip
last_ts = 494.916168
last_received_ts = 494.915802
last_seq = 17712
current_ts = 494.932831
current_received_ts = 494.932465
current_seq = 17713
count = 97
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 29us
(kms_flip:13861) DEBUG: name = flip
last_ts = 494.932831
last_received_ts = 494.932465
last_seq = 17713
current_ts = 494.949493
current_received_ts = 494.949127
current_seq = 17714
count = 98
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 29us
(kms_flip:13861) DEBUG: name = flip
last_ts = 494.949493
last_received_ts = 494.949127
last_seq = 17714
current_ts = 494.966156
current_received_ts = 494.965790
current_seq = 17715
count = 99
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 25us
(kms_flip:13861) DEBUG: name = flip
last_ts = 494.966156
last_received_ts = 494.965790
last_seq = 17715
current_ts = 494.982849
current_received_ts = 494.982452
current_seq = 17716
count = 100
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 25us
(kms_flip:13861) DEBUG: name = flip
last_ts = 494.982849
last_received_ts = 494.982452
last_seq = 17716
current_ts = 494.999512
current_received_ts = 494.999115
current_seq = 17717
count = 101
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 25us
(kms_flip:13861) DEBUG: name = flip
last_ts = 494.999512
last_received_ts = 494.999115
last_seq = 17717
current_ts = 495.016174
current_received_ts = 495.015808
current_seq = 17718
count = 102
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 26us
(kms_flip:13861) DEBUG: name = flip
last_ts = 495.016174
last_received_ts = 495.015808
last_seq = 17718
current_ts = 495.032837
current_received_ts = 495.032471
current_seq = 17719
count = 103
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 25us
(kms_flip:13861) DEBUG: name = flip
last_ts = 495.032837
last_received_ts = 495.032471
last_seq = 17719
current_ts = 495.049500
current_received_ts = 495.049133
current_seq = 17720
count = 104
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 26us
(kms_flip:13861) DEBUG: name = flip
last_ts = 495.049500
last_received_ts = 495.049133
last_seq = 17720
current_ts = 495.066162
current_received_ts = 495.065796
current_seq = 17721
count = 105
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 26us
(kms_flip:13861) DEBUG: name = flip
last_ts = 495.066162
last_received_ts = 495.065796
last_seq = 17721
current_ts = 495.082825
current_received_ts = 495.082458
current_seq = 17722
count = 106
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 26us
(kms_flip:13861) DEBUG: name = flip
last_ts = 495.082825
last_received_ts = 495.082458
last_seq = 17722
current_ts = 495.099518
current_received_ts = 495.099030
current_seq = 17723
count = 107
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 25us
(kms_flip:13861) DEBUG: name = flip
last_ts = 495.099518
last_received_ts = 495.099030
last_seq = 17723
current_ts = 495.116180
current_received_ts = 495.115662
current_seq = 17724
count = 108
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 25us
(kms_flip:13861) DEBUG: name = flip
last_ts = 495.116180
last_received_ts = 495.115662
last_seq = 17724
current_ts = 495.132843
current_received_ts = 495.132355
current_seq = 17725
count = 109
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 26us
(kms_flip:13861) DEBUG: name = flip
last_ts = 495.132843
last_received_ts = 495.132355
last_seq = 17725
current_ts = 495.149506
current_received_ts = 495.149048
current_seq = 17726
count = 110
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 26us
(kms_flip:13861) DEBUG: name = flip
last_ts = 495.149506
last_received_ts = 495.149048
last_seq = 17726
current_ts = 495.166168
current_received_ts = 495.165680
current_seq = 17727
count = 111
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 27us
(kms_flip:13861) DEBUG: name = flip
last_ts = 495.166168
last_received_ts = 495.165680
last_seq = 17727
current_ts = 495.182861
current_received_ts = 495.182465
current_seq = 17728
count = 112
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 25us
(kms_flip:13861) DEBUG: name = flip
last_ts = 495.182861
last_received_ts = 495.182465
last_seq = 17728
current_ts = 495.199524
current_received_ts = 495.199127
current_seq = 17729
count = 113
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 26us
(kms_flip:13861) DEBUG: name = flip
last_ts = 495.199524
last_received_ts = 495.199127
last_seq = 17729
current_ts = 495.216187
current_received_ts = 495.215820
current_seq = 17730
count = 114
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 25us
(kms_flip:13861) DEBUG: name = flip
last_ts = 495.216187
last_received_ts = 495.215820
last_seq = 17730
current_ts = 495.232849
current_received_ts = 495.232483
current_seq = 17731
count = 115
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 26us
(kms_flip:13861) DEBUG: name = flip
last_ts = 495.232849
last_received_ts = 495.232483
last_seq = 17731
current_ts = 495.249512
current_received_ts = 495.249054
current_seq = 17732
count = 116
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 25us
(kms_flip:13861) DEBUG: name = flip
last_ts = 495.249512
last_received_ts = 495.249054
last_seq = 17732
current_ts = 495.266174
current_received_ts = 495.265808
current_seq = 17733
count = 117
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 30us
(kms_flip:13861) DEBUG: name = flip
last_ts = 495.266174
last_received_ts = 495.265808
last_seq = 17733
current_ts = 495.282867
current_received_ts = 495.282471
current_seq = 17734
count = 118
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 26us
(kms_flip:13861) DEBUG: name = flip
last_ts = 495.282867
last_received_ts = 495.282471
last_seq = 17734
current_ts = 495.299530
current_received_ts = 495.299133
current_seq = 17735
count = 119
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 26us
(kms_flip:13861) DEBUG: name = flip
last_ts = 495.299530
last_received_ts = 495.299133
last_seq = 17735
current_ts = 495.316193
current_received_ts = 495.315704
current_seq = 17736
count = 120
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 29us
(kms_flip:13861) DEBUG: name = flip
last_ts = 495.316193
last_received_ts = 495.315704
last_seq = 17736
current_ts = 495.332855
current_received_ts = 495.332428
current_seq = 17737
count = 121
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 25us
(kms_flip:13861) DEBUG: name = flip
last_ts = 495.332855
last_received_ts = 495.332428
last_seq = 17737
current_ts = 495.349518
current_received_ts = 495.349091
current_seq = 17738
count = 122
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 25us
(kms_flip:13861) DEBUG: name = flip
last_ts = 495.349518
last_received_ts = 495.349091
last_seq = 17738
current_ts = 495.366180
current_received_ts = 495.365875
current_seq = 17739
count = 123
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 25us
(kms_flip:13861) DEBUG: name = flip
last_ts = 495.366180
last_received_ts = 495.365875
last_seq = 17739
current_ts = 495.382843
current_received_ts = 495.382385
current_seq = 17740
count = 124
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 26us
(kms_flip:13861) DEBUG: name = flip
last_ts = 495.382843
last_received_ts = 495.382385
last_seq = 17740
current_ts = 495.399536
current_received_ts = 495.399048
current_seq = 17741
count = 125
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 25us
(kms_flip:13861) DEBUG: name = flip
last_ts = 495.399536
last_received_ts = 495.399048
last_seq = 17741
current_ts = 495.416199
current_received_ts = 495.415741
current_seq = 17742
count = 126
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 26us
(kms_flip:13861) DEBUG: name = flip
last_ts = 495.416199
last_received_ts = 495.415741
last_seq = 17742
current_ts = 495.432861
current_received_ts = 495.432434
current_seq = 17743
count = 127
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 25us
(kms_flip:13861) DEBUG: name = flip
last_ts = 495.432861
last_received_ts = 495.432434
last_seq = 17743
current_ts = 495.449524
current_received_ts = 495.449097
current_seq = 17744
count = 128
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 25us
(kms_flip:13861) DEBUG: name = flip
last_ts = 495.449524
last_received_ts = 495.449097
last_seq = 17744
current_ts = 495.466187
current_received_ts = 495.465759
current_seq = 17745
count = 129
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 29us
(kms_flip:13861) DEBUG: name = flip
last_ts = 495.466187
last_received_ts = 495.465759
last_seq = 17745
current_ts = 495.482880
current_received_ts = 495.482391
current_seq = 17746
count = 130
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 26us
(kms_flip:13861) DEBUG: name = flip
last_ts = 495.482880
last_received_ts = 495.482391
last_seq = 17746
current_ts = 495.499542
current_received_ts = 495.499115
current_seq = 17747
count = 131
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 26us
(kms_flip:13861) DEBUG: name = flip
last_ts = 495.499542
last_received_ts = 495.499115
last_seq = 17747
current_ts = 495.516205
current_received_ts = 495.515778
current_seq = 17748
count = 132
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 25us
(kms_flip:13861) DEBUG: name = flip
last_ts = 495.516205
last_received_ts = 495.515778
last_seq = 17748
current_ts = 495.532867
current_received_ts = 495.532440
current_seq = 17749
count = 133
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 25us
(kms_flip:13861) DEBUG: name = flip
last_ts = 495.532867
last_received_ts = 495.532440
last_seq = 17749
current_ts = 495.549530
current_received_ts = 495.549103
current_seq = 17750
count = 134
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 25us
(kms_flip:13861) DEBUG: name = flip
last_ts = 495.549530
last_received_ts = 495.549103
last_seq = 17750
current_ts = 495.566193
current_received_ts = 495.565765
current_seq = 17751
count = 135
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 28us
(kms_flip:13861) DEBUG: name = flip
last_ts = 495.566193
last_received_ts = 495.565765
last_seq = 17751
current_ts = 495.582855
current_received_ts = 495.582458
current_seq = 17752
count = 136
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 28us
(kms_flip:13861) DEBUG: name = flip
last_ts = 495.582855
last_received_ts = 495.582458
last_seq = 17752
current_ts = 495.599518
current_received_ts = 495.599121
current_seq = 17753
count = 137
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 25us
(kms_flip:13861) DEBUG: name = flip
last_ts = 495.599518
last_received_ts = 495.599121
last_seq = 17753
current_ts = 495.616211
current_received_ts = 495.615753
current_seq = 17754
count = 138
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 25us
(kms_flip:13861) DEBUG: name = flip
last_ts = 495.616211
last_received_ts = 495.615753
last_seq = 17754
current_ts = 495.632874
current_received_ts = 495.632446
current_seq = 17755
count = 139
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 25us
(kms_flip:13861) DEBUG: name = flip
last_ts = 495.632874
last_received_ts = 495.632446
last_seq = 17755
current_ts = 495.649536
current_received_ts = 495.649109
current_seq = 17756
count = 140
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 25us
(kms_flip:13861) DEBUG: name = flip
last_ts = 495.649536
last_received_ts = 495.649109
last_seq = 17756
current_ts = 495.666199
current_received_ts = 495.665771
current_seq = 17757
count = 141
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 27us
(kms_flip:13861) DEBUG: name = flip
last_ts = 495.666199
last_received_ts = 495.665771
last_seq = 17757
current_ts = 495.682861
current_received_ts = 495.682465
current_seq = 17758
count = 142
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 27us
(kms_flip:13861) DEBUG: name = flip
last_ts = 495.682861
last_received_ts = 495.682465
last_seq = 17758
current_ts = 495.699554
current_received_ts = 495.699127
current_seq = 17759
count = 143
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 25us
(kms_flip:13861) DEBUG: name = flip
last_ts = 495.699554
last_received_ts = 495.699127
last_seq = 17759
current_ts = 495.716217
current_received_ts = 495.715790
current_seq = 17760
count = 144
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 25us
(kms_flip:13861) DEBUG: name = flip
last_ts = 495.716217
last_received_ts = 495.715790
last_seq = 17760
current_ts = 495.732880
current_received_ts = 495.732452
current_seq = 17761
count = 145
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 25us
(kms_flip:13861) DEBUG: name = flip
last_ts = 495.732880
last_received_ts = 495.732452
last_seq = 17761
current_ts = 495.749542
current_received_ts = 495.749115
current_seq = 17762
count = 146
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 25us
(kms_flip:13861) DEBUG: name = flip
last_ts = 495.749542
last_received_ts = 495.749115
last_seq = 17762
current_ts = 495.766205
current_received_ts = 495.765778
current_seq = 17763
count = 147
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 25us
(kms_flip:13861) DEBUG: name = flip
last_ts = 495.766205
last_received_ts = 495.765778
last_seq = 17763
current_ts = 495.782867
current_received_ts = 495.782440
current_seq = 17764
count = 148
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 25us
(kms_flip:13861) DEBUG: name = flip
last_ts = 495.782867
last_received_ts = 495.782440
last_seq = 17764
current_ts = 495.799530
current_received_ts = 495.799133
current_seq = 17765
count = 149
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 25us
(kms_flip:13861) DEBUG: name = flip
last_ts = 495.799530
last_received_ts = 495.799133
last_seq = 17765
current_ts = 495.816223
current_received_ts = 495.815796
current_seq = 17766
count = 150
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 27us
(kms_flip:13861) DEBUG: name = flip
last_ts = 495.816223
last_received_ts = 495.815796
last_seq = 17766
current_ts = 495.832886
current_received_ts = 495.832458
current_seq = 17767
count = 151
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 24us
(kms_flip:13861) DEBUG: name = flip
last_ts = 495.832886
last_received_ts = 495.832458
last_seq = 17767
current_ts = 495.849548
current_received_ts = 495.849121
current_seq = 17768
count = 152
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 24us
(kms_flip:13861) DEBUG: name = flip
last_ts = 495.849548
last_received_ts = 495.849121
last_seq = 17768
current_ts = 495.866211
current_received_ts = 495.865784
current_seq = 17769
count = 153
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 25us
(kms_flip:13861) DEBUG: name = flip
last_ts = 495.866211
last_received_ts = 495.865784
last_seq = 17769
current_ts = 495.882874
current_received_ts = 495.882446
current_seq = 17770
count = 154
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 25us
(kms_flip:13861) DEBUG: name = flip
last_ts = 495.882874
last_received_ts = 495.882446
last_seq = 17770
current_ts = 495.899536
current_received_ts = 495.899109
current_seq = 17771
count = 155
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 26us
(kms_flip:13861) DEBUG: name = flip
last_ts = 495.899536
last_received_ts = 495.899109
last_seq = 17771
current_ts = 495.916229
current_received_ts = 495.915802
current_seq = 17772
count = 156
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 29us
(kms_flip:13861) DEBUG: name = flip
last_ts = 495.916229
last_received_ts = 495.915802
last_seq = 17772
current_ts = 495.932892
current_received_ts = 495.932465
current_seq = 17773
count = 157
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 28us
(kms_flip:13861) DEBUG: name = flip
last_ts = 495.932892
last_received_ts = 495.932465
last_seq = 17773
current_ts = 495.949554
current_received_ts = 495.949127
current_seq = 17774
count = 158
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 25us
(kms_flip:13861) DEBUG: name = flip
last_ts = 495.949554
last_received_ts = 495.949127
last_seq = 17774
current_ts = 495.966217
current_received_ts = 495.965790
current_seq = 17775
count = 159
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 25us
(kms_flip:13861) DEBUG: name = flip
last_ts = 495.966217
last_received_ts = 495.965790
last_seq = 17775
current_ts = 495.982880
current_received_ts = 495.982452
current_seq = 17776
count = 160
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 27us
(kms_flip:13861) DEBUG: name = flip
last_ts = 495.982880
last_received_ts = 495.982452
last_seq = 17776
current_ts = 495.999542
current_received_ts = 495.999146
current_seq = 17777
count = 161
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 25us
(kms_flip:13861) DEBUG: name = flip
last_ts = 495.999542
last_received_ts = 495.999146
last_seq = 17777
current_ts = 496.016205
current_received_ts = 496.015808
current_seq = 17778
count = 162
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 25us
(kms_flip:13861) DEBUG: name = flip
last_ts = 496.016205
last_received_ts = 496.015808
last_seq = 17778
current_ts = 496.032898
current_received_ts = 496.032471
current_seq = 17779
count = 163
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 27us
(kms_flip:13861) DEBUG: name = flip
last_ts = 496.032898
last_received_ts = 496.032471
last_seq = 17779
current_ts = 496.049561
current_received_ts = 496.049133
current_seq = 17780
count = 164
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 25us
(kms_flip:13861) DEBUG: name = flip
last_ts = 496.049561
last_received_ts = 496.049133
last_seq = 17780
current_ts = 496.066223
current_received_ts = 496.065796
current_seq = 17781
count = 165
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 25us
(kms_flip:13861) DEBUG: name = flip
last_ts = 496.066223
last_received_ts = 496.065796
last_seq = 17781
current_ts = 496.082886
current_received_ts = 496.082458
current_seq = 17782
count = 166
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 27us
(kms_flip:13861) DEBUG: name = flip
last_ts = 496.082886
last_received_ts = 496.082458
last_seq = 17782
current_ts = 496.099548
current_received_ts = 496.099121
current_seq = 17783
count = 167
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 25us
(kms_flip:13861) DEBUG: name = flip
last_ts = 496.099548
last_received_ts = 496.099121
last_seq = 17783
current_ts = 496.116241
current_received_ts = 496.115814
current_seq = 17784
count = 168
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 25us
(kms_flip:13861) DEBUG: name = flip
last_ts = 496.116241
last_received_ts = 496.115814
last_seq = 17784
current_ts = 496.132904
current_received_ts = 496.132477
current_seq = 17785
count = 169
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 25us
(kms_flip:13861) DEBUG: name = flip
last_ts = 496.132904
last_received_ts = 496.132477
last_seq = 17785
current_ts = 496.149567
current_received_ts = 496.149139
current_seq = 17786
count = 170
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 25us
(kms_flip:13861) DEBUG: name = flip
last_ts = 496.149567
last_received_ts = 496.149139
last_seq = 17786
current_ts = 496.166229
current_received_ts = 496.165802
current_seq = 17787
count = 171
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 25us
(kms_flip:13861) DEBUG: name = flip
last_ts = 496.166229
last_received_ts = 496.165802
last_seq = 17787
current_ts = 496.182892
current_received_ts = 496.182465
current_seq = 17788
count = 172
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 25us
(kms_flip:13861) DEBUG: name = flip
last_ts = 496.182892
last_received_ts = 496.182465
last_seq = 17788
current_ts = 496.199554
current_received_ts = 496.199158
current_seq = 17789
count = 173
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 26us
(kms_flip:13861) DEBUG: name = flip
last_ts = 496.199554
last_received_ts = 496.199158
last_seq = 17789
current_ts = 496.216217
current_received_ts = 496.215820
current_seq = 17790
count = 174
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 25us
(kms_flip:13861) DEBUG: name = flip
last_ts = 496.216217
last_received_ts = 496.215820
last_seq = 17790
current_ts = 496.232910
current_received_ts = 496.232483
current_seq = 17791
count = 175
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 25us
(kms_flip:13861) DEBUG: name = flip
last_ts = 496.232910
last_received_ts = 496.232483
last_seq = 17791
current_ts = 496.249573
current_received_ts = 496.249146
current_seq = 17792
count = 176
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 26us
(kms_flip:13861) DEBUG: name = flip
last_ts = 496.249573
last_received_ts = 496.249146
last_seq = 17792
current_ts = 496.266235
current_received_ts = 496.265808
current_seq = 17793
count = 177
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 26us
(kms_flip:13861) DEBUG: name = flip
last_ts = 496.266235
last_received_ts = 496.265808
last_seq = 17793
current_ts = 496.282898
current_received_ts = 496.282471
current_seq = 17794
count = 178
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 25us
(kms_flip:13861) DEBUG: name = flip
last_ts = 496.282898
last_received_ts = 496.282471
last_seq = 17794
current_ts = 496.299561
current_received_ts = 496.299164
current_seq = 17795
count = 179
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 27us
(kms_flip:13861) DEBUG: name = flip
last_ts = 496.299561
last_received_ts = 496.299164
last_seq = 17795
current_ts = 496.316223
current_received_ts = 496.315826
current_seq = 17796
count = 180
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 28us
(kms_flip:13861) DEBUG: name = flip
last_ts = 496.316223
last_received_ts = 496.315826
last_seq = 17796
current_ts = 496.332916
current_received_ts = 496.332489
current_seq = 17797
count = 181
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 25us
(kms_flip:13861) DEBUG: name = flip
last_ts = 496.332916
last_received_ts = 496.332489
last_seq = 17797
current_ts = 496.349579
current_received_ts = 496.349152
current_seq = 17798
count = 182
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 25us
(kms_flip:13861) DEBUG: name = flip
last_ts = 496.349579
last_received_ts = 496.349152
last_seq = 17798
current_ts = 496.366241
current_received_ts = 496.365814
current_seq = 17799
count = 183
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 26us
(kms_flip:13861) DEBUG: name = flip
last_ts = 496.366241
last_received_ts = 496.365814
last_seq = 17799
current_ts = 496.382904
current_received_ts = 496.382477
current_seq = 17800
count = 184
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 25us
(kms_flip:13861) DEBUG: name = flip
last_ts = 496.382904
last_received_ts = 496.382477
last_seq = 17800
current_ts = 496.399567
current_received_ts = 496.399170
current_seq = 17801
count = 185
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 29us
(kms_flip:13861) DEBUG: name = flip
last_ts = 496.399567
last_received_ts = 496.399170
last_seq = 17801
current_ts = 496.416229
current_received_ts = 496.415833
current_seq = 17802
count = 186
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 28us
(kms_flip:13861) DEBUG: name = flip
last_ts = 496.416229
last_received_ts = 496.415833
last_seq = 17802
current_ts = 496.432892
current_received_ts = 496.432495
current_seq = 17803
count = 187
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 25us
(kms_flip:13861) DEBUG: name = flip
last_ts = 496.432892
last_received_ts = 496.432495
last_seq = 17803
current_ts = 496.449585
current_received_ts = 496.449158
current_seq = 17804
count = 188
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 26us
(kms_flip:13861) DEBUG: name = flip
last_ts = 496.449585
last_received_ts = 496.449158
last_seq = 17804
current_ts = 496.466248
current_received_ts = 496.465820
current_seq = 17805
count = 189
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 27us
(kms_flip:13861) DEBUG: name = flip
last_ts = 496.466248
last_received_ts = 496.465820
last_seq = 17805
current_ts = 496.482910
current_received_ts = 496.482483
current_seq = 17806
count = 190
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 27us
(kms_flip:13861) DEBUG: name = flip
last_ts = 496.482910
last_received_ts = 496.482483
last_seq = 17806
current_ts = 496.499573
current_received_ts = 496.499146
current_seq = 17807
count = 191
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 28us
(kms_flip:13861) DEBUG: name = flip
last_ts = 496.499573
last_received_ts = 496.499146
last_seq = 17807
current_ts = 496.516235
current_received_ts = 496.515839
current_seq = 17808
count = 192
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 25us
(kms_flip:13861) DEBUG: name = flip
last_ts = 496.516235
last_received_ts = 496.515839
last_seq = 17808
current_ts = 496.532928
current_received_ts = 496.532501
current_seq = 17809
count = 193
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 25us
(kms_flip:13861) DEBUG: name = flip
last_ts = 496.532928
last_received_ts = 496.532501
last_seq = 17809
current_ts = 496.549591
current_received_ts = 496.549164
current_seq = 17810
count = 194
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 26us
(kms_flip:13861) DEBUG: name = flip
last_ts = 496.549591
last_received_ts = 496.549164
last_seq = 17810
current_ts = 496.566254
current_received_ts = 496.565826
current_seq = 17811
count = 195
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 26us
(kms_flip:13861) DEBUG: name = flip
last_ts = 496.566254
last_received_ts = 496.565826
last_seq = 17811
current_ts = 496.582916
current_received_ts = 496.582489
current_seq = 17812
count = 196
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 25us
(kms_flip:13861) DEBUG: name = flip
last_ts = 496.582916
last_received_ts = 496.582489
last_seq = 17812
current_ts = 496.599579
current_received_ts = 496.599152
current_seq = 17813
count = 197
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 25us
(kms_flip:13861) DEBUG: name = flip
last_ts = 496.599579
last_received_ts = 496.599152
last_seq = 17813
current_ts = 496.616241
current_received_ts = 496.615814
current_seq = 17814
count = 198
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 261us
(kms_flip:13861) DEBUG: name = flip
last_ts = 496.616241
last_received_ts = 496.615814
last_seq = 17814
current_ts = 496.632904
current_received_ts = 496.632477
current_seq = 17815
count = 199
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 232us
(kms_flip:13861) DEBUG: name = flip
last_ts = 496.632904
last_received_ts = 496.632477
last_seq = 17815
current_ts = 496.649597
current_received_ts = 496.649139
current_seq = 17816
count = 200
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 232us
(kms_flip:13861) DEBUG: name = flip
last_ts = 496.649597
last_received_ts = 496.649139
last_seq = 17816
current_ts = 496.666260
current_received_ts = 496.665833
current_seq = 17817
count = 201
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 260us
(kms_flip:13861) DEBUG: name = flip
last_ts = 496.666260
last_received_ts = 496.665833
last_seq = 17817
current_ts = 496.682922
current_received_ts = 496.682495
current_seq = 17818
count = 202
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 241us
(kms_flip:13861) DEBUG: name = flip
last_ts = 496.682922
last_received_ts = 496.682495
last_seq = 17818
current_ts = 496.699585
current_received_ts = 496.699158
current_seq = 17819
count = 203
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 236us
(kms_flip:13861) DEBUG: name = flip
last_ts = 496.699585
last_received_ts = 496.699158
last_seq = 17819
current_ts = 496.716248
current_received_ts = 496.715820
current_seq = 17820
count = 204
seq_step = 1
(kms_flip:13861) DEBUG: Vblank took 518us
(kms_flip:13861) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931:
(kms_flip:13861) CRITICAL: Failed assertion: end - start < 500
(kms_flip:13861) igt_core-INFO: Stack trace:
(kms_flip:13861) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:13861) igt_core-INFO:   #1 ../tests/kms_flip.c:926 run_test_on_crtc_set()
(kms_flip:13861) igt_core-INFO:   #2 ../tests/kms_flip.c:2029 run_pair()
(kms_flip:13861) igt_core-INFO:   #3 ../tests/kms_flip.c:2193 __igt_unique____real_main2105()
(kms_flip:13861) igt_core-INFO:   #4 ../tests/kms_flip.c:2105 main()
(kms_flip:13861) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:13861) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:13861) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest AC-DP2-HDMI-A3: FAIL (5.554s)
Dmesg
<6> [493.405391] [IGT] kms_flip: starting dynamic subtest AC-DP2-HDMI-A3
<7> [493.406385] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:555]
<7> [493.406723] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:560]
<7> [493.466832] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:151:pipe A]
<7> [493.467057] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [493.467333] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [493.467579] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [493.467813] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [493.468074] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in dp_m_n (expected tu 64 data 5190451/8388608 link 288358/524288, found tu 0, data 0/0 link 0/0)
<7> [493.468288] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in output_types (expected 0x00000080, found 0x00000000)
<7> [493.468495] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [493.468690] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [493.468883] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [493.469100] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [493.469295] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [493.469481] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [493.469661] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [493.469837] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [493.470037] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [493.470208] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [493.470376] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [493.470545] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [493.470715] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [493.470877] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [493.471068] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [493.471223] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [493.471373] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [493.471528] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [493.471682] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [493.471835] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [493.471997] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [493.472143] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [493.472287] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [493.472425] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [493.472558] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [493.472694] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [493.472834] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [493.472983] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [493.473118] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in enhanced_framing (expected yes, found no)
<7> [493.473246] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in pipe_bpp (expected 36, found 0)
<7> [493.473370] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [493.473490] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [493.473610] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in port_clock (expected 270000, found 0)
<7> [493.473732] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:151:pipe A] fastset requirement not met, forcing full modeset
<7> [493.473853] xe 0000:03:00.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:151:pipe A] releasing TC PLL 2
<7> [493.474019] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xf -> 0xf (total dbuf slices 0xf), mbus joined? no->yes
<7> [493.474125] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:151:pipe A] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x3 -> 0x2
<7> [493.474231] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:269:pipe B] dbuf slices 0xc -> 0xf, ddb (0 - 2048) -> (0 - 4096), active pipes 0x3 -> 0x2
<7> [493.474355] xe 0000:03:00.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:145:cursor A] ddb (1989 - 2048) -> ( 0 - 0), size 59 -> 0
<7> [493.474448] xe 0000:03:00.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:263:cursor B] ddb (1989 - 2048) -> (4037 - 4096), size 59 -> 59
<7> [493.474545] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:151:pipe A] min cdclk: 192000 kHz -> 0 kHz
<7> [493.474697] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:151:pipe A] enable: no [modeset]
<7> [493.474817] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:35:plane 1A] fb: [NOFB], visible: no
<7> [493.474939] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [493.475049] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [493.475154] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [493.475259] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [493.475363] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:145:cursor A] fb: [NOFB], visible: no
<7> [493.476151] xe 0000:03:00.0: [drm:intel_audio_codec_disable [xe]] [CONNECTOR:527:DP-2][ENCODER:526:DDI TC2/PHY G] Disable audio codec on [CRTC:151:pipe A]
<7> [493.500120] xe 0000:03:00.0: [drm:intel_disable_transcoder [xe]] disabling pipe A
<7> [493.518042] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling AUX_TC2
<7> [493.520680] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disable TC PLL 2 (active 0x1, on? 1) for [CRTC:151:pipe A]
<7> [493.523091] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disabling TC PLL 2
<7> [493.523794] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:507:DDI TC1/PHY F]
<7> [493.524346] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:509:DP-MST A]
<7> [493.524807] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:510:DP-MST B]
<7> [493.525298] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:511:DP-MST C]
<7> [493.525746] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:512:DP-MST D]
<7> [493.526216] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:526:DDI TC2/PHY G]
<7> [493.526865] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:528:DP-MST A]
<7> [493.527360] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:529:DP-MST B]
<7> [493.527800] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:530:DP-MST C]
<7> [493.528241] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:531:DP-MST D]
<7> [493.528626] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:538:DDI TC3/PHY H]
<7> [493.529063] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:542:DDI TC4/PHY I]
<7> [493.529427] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:544:DP-MST A]
<7> [493.529782] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:545:DP-MST B]
<7> [493.530139] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:546:DP-MST C]
<7> [493.530460] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:547:DP-MST D]
<7> [493.530774] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:527:DP-2]
<7> [493.532848] xe 0000:03:00.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: no -> yes (pipe: B)
<7> [493.533195] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: yes)
<7> [493.545443] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling PW_A
<7> [493.546194] xe 0000:03:00.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating disabled for pipe_mask=0x1 (CLKGATE_DIS_DSSDSC=0xa2000000)
<7> [493.546920] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:151:pipe A]
<7> [493.547995] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:269:pipe B]
<7> [493.548409] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.enable (expected yes, found no)
<7> [493.549001] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.active (expected yes, found no)
<7> [493.549530] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in cpu_transcoder (expected 1, found -1)
<7> [493.550057] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in lane_count (expected 4, found 0)
<7> [493.550507] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [493.550981] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [493.551381] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [493.551777] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [493.552176] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [493.552536] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [493.552886] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [493.553254] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [493.553568] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [493.553867] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [493.554199] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1083, found 0)
<7> [493.554473] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1088, found 0)
<7> [493.554745] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [493.555040] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [493.555317] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [493.555580] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [493.555835] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [493.556099] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [493.556326] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [493.556538] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [493.556749] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [493.556968] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [493.557163] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1083, found 0)
<7> [493.557347] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1088, found 0)
<7> [493.557531] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [493.557717] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [493.557903] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [493.558073] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [493.558254] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [493.558424] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [493.558589] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [493.558754] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in pipe_bpp (expected 30, found 0)
<7> [493.558924] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [493.559077] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [493.559227] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in port_clock (expected 185625, found 0)
<7> [493.559372] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [493.559513] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in infoframes.gcp (expected 0x00000006, found 0x00000000)
<7> [493.559648] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in avi infoframe
<7> [493.559782] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [493.559927] xe 0000:03:00.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [493.559929] xe 0000:03:00.0: colorspace: RGB
<7> [493.559931] xe 0000:03:00.0: scan mode: Underscan
<7> [493.559933] xe 0000:03:00.0: colorimetry: No Data
<7> [493.559934] xe 0000:03:00.0: picture aspect: No Data
<7> [493.559936] xe 0000:03:00.0: active aspect: Same as Picture
<7> [493.559937] xe 0000:03:00.0: itc: No Data
<7> [493.559939] xe 0000:03:00.0: extended colorimetry: xvYCC 601
<7> [493.559941] xe 0000:03:00.0: quantization range: Full
<7> [493.559942] xe 0000:03:00.0: nups: Unknown Non-uniform Scaling
<7> [493.559944] xe 0000:03:00.0: video code: 0
<7> [493.559946] xe 0000:03:00.0: ycc quantization range: Full
<7> [493.559948] xe 0000:03:00.0: hdmi content type: Graphics
<7> [493.559949] xe 0000:03:00.0: pixel repeat: 0
<7> [493.559951] xe 0000:03:00.0: bar top 0, bottom 0, left 0, right 0
<7> [493.559953] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [493.560082] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in spd infoframe
<7> [493.560213] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [493.560343] xe 0000:03:00.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [493.560345] xe 0000:03:00.0: vendor: Intel
<7> [493.560347] xe 0000:03:00.0: product: Discrete gfx
<7> [493.560348] xe 0000:03:00.0: source device information: PC General (0x9)
<7> [493.560350] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [493.560467] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hdmi infoframe
<7> [493.560584] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [493.560703] xe 0000:03:00.0: HDMI infoframe: Vendor, version 1, length 4
<7> [493.560705] xe 0000:03:00.0: empty frame
<7> [493.560706] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [493.560825] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:269:pipe B] fastset requirement not met, forcing full modeset
<7> [493.560947] xe 0000:03:00.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:269:pipe B] releasing TC PLL 3
<7> [493.561093] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xf -> 0x1 (total dbuf slices 0xf), mbus joined? yes->no
<7> [493.561184] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:269:pipe B] dbuf slices 0xf -> 0x0, ddb (0 - 4096) -> (0 - 0), active pipes 0x2 -> 0x0
<7> [493.561299] xe 0000:03:00.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:263:cursor B] ddb (4037 - 4096) -> ( 0 - 0), size 59 -> 0
<7> [493.561385] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:269:pipe B] min cdclk: 192000 kHz -> 0 kHz
<7> [493.561518] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:269:pipe B] enable: no [modeset]
<7> [493.561624] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:153:plane 1B] fb: [NOFB], visible: no
<7> [493.561730] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:183:plane 2B] fb: [NOFB], visible: no
<7> [493.561827] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:213:plane 3B] fb: [NOFB], visible: no
<7> [493.561925] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:243:plane 4B] fb: [NOFB], visible: no
<7> [493.562021] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:253:plane 5B] fb: [NOFB], visible: no
<7> [493.562111] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:263:cursor B] fb: [NOFB], visible: no
<7> [493.562768] xe 0000:03:00.0: [drm:intel_audio_codec_disable [xe]] [CONNECTOR:539:HDMI-A-3][ENCODER:538:DDI TC3/PHY H] Disable audio codec on [CRTC:269:pipe B]
<7> [493.595773] xe 0000:03:00.0: [drm:intel_disable_transcoder [xe]] disabling pipe B
<7> [493.612154] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disable TC PLL 3 (active 0x2, on? 1) for [CRTC:269:pipe B]
<7> [493.615005] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disabling TC PLL 3
<7> [493.615872] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:507:DDI TC1/PHY F]
<7> [493.616461] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:509:DP-MST A]
<7> [493.616985] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:510:DP-MST B]
<7> [493.617490] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:511:DP-MST C]
<7> [493.618032] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:512:DP-MST D]
<7> [493.618528] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:526:DDI TC2/PHY G]
<7> [493.619023] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:528:DP-MST A]
<7> [493.619509] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:529:DP-MST B]
<7> [493.620028] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:530:DP-MST C]
<7> [493.620513] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:531:DP-MST D]
<7> [493.621004] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:538:DDI TC3/PHY H]
<7> [493.621713] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:542:DDI TC4/PHY I]
<7> [493.622025] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling AUX_TC2
<7> [493.622188] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:544:DP-MST A]
<7> [493.622614] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:545:DP-MST B]
<7> [493.623068] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:546:DP-MST C]
<7> [493.623467] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:547:DP-MST D]
<7> [493.623842] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:539:HDMI-A-3]
<7> [493.624468] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 2 (mbus joined: no)
<7> [493.624992] xe 0000:03:00.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: yes -> no (pipe: *)
<7> [493.625474] xe 0000:03:00.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0x1
<7> [493.626089] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling PW_B
<7> [493.626598] xe 0000:03:00.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating disabled for pipe_mask=0x2 (CLKGATE_DIS_DSSDSC=0xaa000000)
<7> [493.627118] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling PW_2
<7> [493.627604] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:269:pipe B]
<7> [493.628246] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:387:pipe C]
<7> [493.629017] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:505:pipe D]
<7> [493.629361] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:387:pipe C]
<7> [493.629395] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CONNECTOR:539:HDMI-A-3]
<7> [493.629482] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CONNECTOR:539:HDMI-A-3] Limiting target display pipe bpp to 30 (EDID bpp 36, max requested bpp 30, max platform bpp 36)
<7> [493.629699] xe 0000:03:00.0: [drm:intel_hdmi_compute_clock [xe]] picking 10 bpc for HDMI output (pipe bpp: 30)
<7> [493.629905] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:387:pipe C] hw max bpp: 30, pipe bpp: 30, dithering: 0
<7> [493.630098] xe 0000:03:00.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:538:DDI TC3/PHY H] [CRTC:387:pipe C]
<7> [493.630278] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.enable (expected no, found yes)
<7> [493.630449] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.active (expected no, found yes)
<7> [493.630616] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in cpu_transcoder (expected -1, found 2)
<7> [493.630779] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in lane_count (expected 0, found 4)
<7> [493.630946] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [493.631100] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [493.631252] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [493.631392] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [493.631528] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [493.631660] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [493.631789] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [493.631926] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [493.632048] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [493.632170] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [493.632290] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1083)
<7> [493.632409] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1088)
<7> [493.632523] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [493.632635] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [493.632748] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [493.632856] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [493.632967] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [493.633072] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [493.633171] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [493.633269] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [493.633370] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [493.633468] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [493.633568] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1083)
<7> [493.633668] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1088)
<7> [493.633767] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [493.633860] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [493.633961] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [493.634055] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [493.634148] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [493.634241] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in has_hdmi_sink (expected no, found yes)
<7> [493.634335] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in has_infoframe (expected no, found yes)
<7> [493.634426] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in pipe_bpp (expected 0, found 30)
<7> [493.634518] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [493.634608] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [493.634699] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in port_clock (expected 0, found 185625)
<7> [493.634790] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071)
<7> [493.634881] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in infoframes.gcp (expected 0x00000000, found 0x00000006)
<7> [493.634986] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in avi infoframe
<7> [493.635077] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [493.635168] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [493.635258] xe 0000:03:00.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [493.635260] xe 0000:03:00.0: colorspace: RGB
<7> [493.635261] xe 0000:03:00.0: scan mode: Underscan
<7> [493.635262] xe 0000:03:00.0: colorimetry: No Data
<7> [493.635263] xe 0000:03:00.0: picture aspect: No Data
<7> [493.635265] xe 0000:03:00.0: active aspect: Same as Picture
<7> [493.635266] xe 0000:03:00.0: itc: No Data
<7> [493.635267] xe 0000:03:00.0: extended colorimetry: xvYCC 601
<7> [493.635268] xe 0000:03:00.0: quantization range: Full
<7> [493.635269] xe 0000:03:00.0: nups: Unknown Non-uniform Scaling
<7> [493.635270] xe 0000:03:00.0: video code: 0
<7> [493.635271] xe 0000:03:00.0: ycc quantization range: Full
<7> [493.635272] xe 0000:03:00.0: hdmi content type: Graphics
<7> [493.635273] xe 0000:03:00.0: pixel repeat: 0
<7> [493.635274] xe 0000:03:00.0: bar top 0, bottom 0, left 0, right 0
<7> [493.635276] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in spd infoframe
<7> [493.635372] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [493.635466] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [493.635558] xe 0000:03:00.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [493.635559] xe 0000:03:00.0: vendor: Intel
<7> [493.635560] xe 0000:03:00.0: product: Discrete gfx
<7> [493.635561] xe 0000:03:00.0: source device information: PC General (0x9)
<7> [493.635563] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hdmi infoframe
<7> [493.635655] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [493.635747] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [493.635837] xe 0000:03:00.0: HDMI infoframe: Vendor, version 1, length 4
<7> [493.635839] xe 0000:03:00.0: empty frame
<7> [493.635840] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:387:pipe C] fastset requirement not met, forcing full modeset
<7> [493.635970] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0x1 -> 0xd (total dbuf slices 0xf), mbus joined? no->no
<7> [493.636052] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:387:pipe C] dbuf slices 0x0 -> 0xc, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x4
<7> [493.636153] xe 0000:03:00.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:271:plane 1C] ddb ( 0 - 0) -> ( 0 - 1989), size 0 -> 1989
<7> [493.636231] xe 0000:03:00.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:381:cursor C] ddb ( 0 - 0) -> (1989 - 2048), size 0 -> 59
<7> [493.636308] xe 0000:03:00.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:271:plane 1C] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm
<7> [493.636383] xe 0000:03:00.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:271:plane 1C] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 4, 4, 9, 9, 17, 17, 17, 17, 0, 15, 0
<7> [493.636458] xe 0000:03:00.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:271:plane 1C] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 62, 62, 139, 139, 261, 261, 261, 261, 137, 230, 244
<7> [493.636533] xe 0000:03:00.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:271:plane 1C] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 123, 123, 245, 245, 367, 367, 0, 0, 138, 306, 306
<7> [493.636620] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] [CRTC:387:pipe C] data rate 594000 num active planes 1
<7> [493.636733] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 33600 required 624 qgv_peak_bw: 48000
<7> [493.636839] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 53000 required 624 qgv_peak_bw: 48000
<7> [493.636952] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 48000 for required data rate: 624
<7> [493.637057] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:387:pipe C] min cdclk: 0 kHz -> 192000 kHz
<7> [493.637162] xe 0000:03:00.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [xe]] dbuf bandwidth min cdclk: 18563 kHz -> 9282 kHz
<7> [493.637268] xe 0000:03:00.0: [drm:intel_find_dpll [xe]] [CRTC:387:pipe C] allocated TC PLL 3
<7> [493.637365] xe 0000:03:00.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:387:pipe C] reserving TC PLL 3
<7> [493.637460] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:387:pipe C] enable: yes [modeset]
<7> [493.637561] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [493.637658] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: C, pipe bpp: 30, dithering: 0
<7> [493.637751] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [493.637846] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [493.637955] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [493.638053] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [493.638147] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [493.638239] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] GCP: 0x6
<7> [493.638330] xe 0000:03:00.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [493.638332] xe 0000:03:00.0: colorspace: RGB
<7> [493.638333] xe 0000:03:00.0: scan mode: Underscan
<7> [493.638334] xe 0000:03:00.0: colorimetry: No Data
<7> [493.638335] xe 0000:03:00.0: picture aspect: No Data
<7> [493.638336] xe 0000:03:00.0: active aspect: Same as Picture
<7> [493.638337] xe 0000:03:00.0: itc: No Data
<7> [493.638338] xe 0000:03:00.0: extended colorimetry: xvYCC 601
<7> [493.638340] xe 0000:03:00.0: quantization range: Full
<7> [493.638341] xe 0000:03:00.0: nups: Unknown Non-uniform Scaling
<7> [493.638342] xe 0000:03:00.0: video code: 0
<7> [493.638343] xe 0000:03:00.0: ycc quantization range: Full
<7> [493.638344] xe 0000:03:00.0: hdmi content type: Graphics
<7> [493.638345] xe 0000:03:00.0: pixel repeat: 0
<7> [493.638346] xe 0000:03:00.0: bar top 0, bottom 0, left 0, right 0
<7> [493.638348] xe 0000:03:00.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [493.638349] xe 0000:03:00.0: vendor: Intel
<7> [493.638350] xe 0000:03:00.0: product: Discrete gfx
<7> [493.638351] xe 0000:03:00.0: source device information: PC General (0x9)
<7> [493.638352] xe 0000:03:00.0: HDMI infoframe: Vendor, version 1, length 4
<7> [493.638354] xe 0000:03:00.0: empty frame
<7> [493.638355] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 10 00 07 00 66 10 00 01 00 00 00 00 00 00 00 00
<7> [493.638447] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 05 e3 cd 0c 32 38 45 38 35 30 09 07 07 00 00 00
<7> [493.638538] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [493.638627] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [493.638715] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vrr: no, fixed rr: yes, vmin: 1125, vmax: 1125, flipline: 1125, pipeline full: 0, guardband: 45 vsync start: 42, vsync end: 37
<7> [493.638806] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vrr: vmin vblank: 1080, vmax vblank: 1080, vmin vtotal: 1125, vmax vtotal: 1125
<7> [493.638905] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [493.639002] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] requested mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9
<7> [493.639094] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] adjusted mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9
<7> [493.639186] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1083-1088 vt=1125, flags=0x9
<7> [493.639282] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x40 0x9
<7> [493.639377] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1083-1088 vt=1125, flags=0x9
<7> [493.639470] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port clock: 185625, pixel rate 148500, min cdclk 192000, min voltage level 0
<7> [493.639562] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] linetime: 119, ips linetime: 0
<7> [493.639657] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0
<7> [493.639751] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe src: 1920x1080+0+0
<7> [493.639843] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [493.639955] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [493.640050] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [493.640141] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cx0pll_hw_state: lane_count: 4, ssc_enabled: no, use_c10: no, tbt_mode: no
<7> [493.640232] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] c20pll_hw_state:
<7> [493.640322] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] tx[0] = 0xbe88, tx[1] = 0x5800, tx[2] = 0x0000
<7> [493.640411] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cmn[0] = 0x0500, cmn[1] = 0x0005, cmn[2] = 0x0000, cmn[3] = 0x0000
<7> [493.640501] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[0] = 0x40c0
<7> [493.640592] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[1] = 0x7d20
<7> [493.640681] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[2] = 0x4a06
<7> [493.640770] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[3] = 0x8f1c
<7> [493.640861] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[4] = 0x0000
<7> [493.640971] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[5] = 0x0000
<7> [493.641065] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[6] = 0x2200
<7> [493.641157] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[7] = 0xffff
<7> [493.641247] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[8] = 0xae00
<7> [493.641336] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[9] = 0x0000
<7> [493.641426] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[10] = 0x0001
<7> [493.641517] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vdr: custom width: 0x00, serdes rate: 0x00, hdmi rate: 0x00
<7> [493.641607] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [493.641697] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [493.641788] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [493.641880] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [493.641988] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [493.642078] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [493.642168] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [493.642258] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [493.642348] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [493.642438] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [493.642527] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [493.642618] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [493.642712] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:271:plane 1C] fb: [FB:555] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [493.642806] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [493.642904] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [493.643004] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:301:plane 2C] fb: [NOFB], visible: no
<7> [493.643096] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:331:plane 3C] fb: [NOFB], visible: no
<7> [493.643188] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:361:plane 4C] fb: [NOFB], visible: no
<7> [493.643279] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:371:plane 5C] fb: [NOFB], visible: no
<7> [493.643370] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:381:cursor C] fb: [NOFB], visible: no
<7> [493.643978] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling PW_2
<7> [493.644091] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling PW_C
<7> [493.644419] xe 0000:03:00.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating enabled for pipe_mask=0x4 (CLKGATE_DIS_DSSDSC=0x8a000000)
<7> [493.644524] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:507:DDI TC1/PHY F]
<7> [493.644614] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:509:DP-MST A]
<7> [493.644701] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:510:DP-MST B]
<7> [493.644787] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:511:DP-MST C]
<7> [493.644872] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:512:DP-MST D]
<7> [493.644972] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:526:DDI TC2/PHY G]
<7> [493.645059] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:528:DP-MST A]
<7> [493.645142] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:529:DP-MST B]
<7> [493.645226] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:530:DP-MST C]
<7> [493.645314] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:531:DP-MST D]
<7> [493.645401] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:538:DDI TC3/PHY H]
<7> [493.645486] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:542:DDI TC4/PHY I]
<7> [493.645569] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:544:DP-MST A]
<7> [493.645654] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:545:DP-MST B]
<7> [493.645737] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:546:DP-MST C]
<7> [493.645823] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:547:DP-MST D]
<7> [493.646125] xe 0000:03:00.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xd
<7> [493.646291] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 2 (mbus joined: no)
<7> [493.646594] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enable TC PLL 3 (active 0x4, on? 0) for [CRTC:387:pipe C]
<7> [493.646696] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enabling TC PLL 3
<7> [493.649079] xe 0000:03:00.0: [drm:intel_enable_transcoder [xe]] enabling pipe C
<7> [493.667681] xe 0000:03:00.0: [drm:intel_audio_codec_enable [xe]] [CONNECTOR:539:HDMI-A-3][ENCODER:538:DDI TC3/PHY H] Enable audio codec on [CRTC:387:pipe C], 32 bytes ELD
<7> [493.684341] xe 0000:03:00.0: [drm:audio_config_hdmi_pixel_clock [xe]] Configuring HDMI audio for pixel clock 148500 (0x00090000)
<7> [493.684638] xe 0000:03:00.0: [drm:hsw_audio_config_update [xe]] using automatic N
<7> [493.685311] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:539:HDMI-A-3]
<7> [493.685815] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:387:pipe C]
<7> [493.690193] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:151:pipe A]
<7> [493.690244] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CONNECTOR:527:DP-2]
<7> [493.690415] xe 0000:03:00.0: [drm:intel_dp_compute_config_limits [xe]] [ENCODER:526:DDI TC2/PHY G][CRTC:151:pipe A] DP link limits: pixel clock 148500 kHz DSC off max lanes 4 max rate 540000 max pipe_bpp 36 min link_bpp 18.0000 max link_bpp 36.0000
<7> [493.690711] xe 0000:03:00.0: [drm:intel_dp_compute_link_config [xe]] DP lane count 4 clock 270000 bpp input 36 compressed 0.0000 HDR no link rate required 668250 available 1080000
<7> [493.691018] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:151:pipe A] hw max bpp: 36, pipe bpp: 36, dithering: 0
<7> [493.691301] xe 0000:03:00.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:526:DDI TC2/PHY G] [CRTC:151:pipe A]
<7> [493.691568] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [493.691840] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [493.692115] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [493.692359] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [493.692596] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in dp_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 5190451/8388608 link 288358/524288)
<7> [493.692824] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000080)
<7> [493.693062] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [493.693274] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [493.693489] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [493.693697] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [493.693908] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [493.694100] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [493.694290] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [493.694472] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [493.694650] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [493.694824] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [493.695005] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [493.695178] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [493.695345] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [493.695508] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [493.695673] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [493.695839] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [493.696013] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [493.696169] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [493.696316] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [493.696460] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [493.696602] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [493.696744] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [493.696884] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [493.697070] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [493.697203] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [493.697337] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [493.697465] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [493.697594] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [493.697722] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in enhanced_framing (expected no, found yes)
<7> [493.697844] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 36)
<7> [493.697981] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [493.698098] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [493.698215] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in port_clock (expected 0, found 270000)
<7> [493.698330] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:151:pipe A] fastset requirement not met, forcing full modeset
<7> [493.698469] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xd -> 0xf (total dbuf slices 0xf), mbus joined? no->no
<7> [493.698568] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:151:pipe A] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x4 -> 0x5
<7> [493.698687] xe 0000:03:00.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:35:plane 1A] ddb ( 0 - 0) -> ( 0 - 1989), size 0 -> 1989
<7> [493.698782] xe 0000:03:00.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:145:cursor A] ddb ( 0 - 0) -> (1989 - 2048), size 0 -> 59
<7> [493.698876] xe 0000:03:00.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:35:plane 1A] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm
<7> [493.698979] xe 0000:03:00.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:35:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 4, 4, 9, 9, 17, 17, 17, 17, 0, 15, 0
<7> [493.699068] xe 0000:03:00.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:35:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 62, 62, 139, 139, 261, 261, 261, 261, 137, 230, 244
<7> [493.699155] xe 0000:03:00.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:35:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 123, 123, 245, 245, 367, 367, 0, 0, 138, 306, 306
<7> [493.699250] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] [CRTC:151:pipe A] data rate 594000 num active planes 1
<7> [493.699357] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 33600 required 1248 qgv_peak_bw: 48000
<7> [493.699462] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 53000 required 1248 qgv_peak_bw: 48000
<7> [493.699567] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 48000 for required data rate: 1248
<7> [493.699680] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:151:pipe A] min cdclk: 0 kHz -> 192000 kHz
<7> [493.699795] xe 0000:03:00.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [xe]] dbuf bandwidth min cdclk: 9282 kHz -> 18563 kHz
<7> [493.699914] xe 0000:03:00.0: [drm:intel_find_dpll [xe]] [CRTC:151:pipe A] allocated TC PLL 2
<7> [493.700021] xe 0000:03:00.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:151:pipe A] reserving TC PLL 2
<7> [493.700115] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:151:pipe A] enable: yes [modeset]
<7> [493.700214] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: DP (0x80), output format: RGB, sink format: RGB
<7> [493.700311] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: A, pipe bpp: 36, dithering: 0
<7> [493.700406] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [493.700498] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [493.700591] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [493.700682] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [493.700773] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] dp m_n: lanes: 4; data_m: 5190451, data_n: 8388608, link_m: 288358, link_n: 524288, tu: 64
<7> [493.700864] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] dp m2_n2: lanes: 4; data_m: 0, data_n: 0, link_m: 0, link_n: 0, tu: 0
<7> [493.700996] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: enabled
<7> [493.701087] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled
<7> [493.701177] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] psr: disabled, selective update: disabled, panel replay: disabled, selective fetch: disabled
<7> [493.701268] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] minimum hblank: 0
<7> [493.701358] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] audio: 1, infoframes: 0, infoframes enabled: 0x0
<7> [493.701449] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 10 00 09 00 68 36 00 01 00 00 00 00 00 00 00 00
<7> [493.701539] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 61 a4 9a 00 44 50 46 39 30 34 33 35 09 07 07 11
<7> [493.701629] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 17 50 51 07 00 00 00 00
<7> [493.701719] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [493.701808] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [493.701902] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vrr: no, fixed rr: yes, vmin: 1125, vmax: 1125, flipline: 1125, pipeline full: 0, guardband: 42 vsync start: 41, vsync end: 36
<7> [493.702007] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vrr: vmin vblank: 1083, vmax vblank: 1083, vmin vtotal: 1125, vmax vtotal: 1125
<7> [493.702100] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [493.702193] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] requested mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
<7> [493.702284] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] adjusted mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
<7> [493.702376] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] 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> [493.702469] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [493.702560] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] 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> [493.702650] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port clock: 270000, pixel rate 148500, min cdclk 192000, min voltage level 0
<7> [493.702740] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] linetime: 119, ips linetime: 0
<7> [493.702831] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0
<7> [493.702930] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe src: 1920x1080+0+0
<7> [493.703028] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [493.703118] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [493.703208] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [493.703298] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cx0pll_hw_state: lane_count: 4, ssc_enabled: no, use_c10: no, tbt_mode: no
<7> [493.703388] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] c20pll_hw_state:
<7> [493.703484] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] tx[0] = 0xbe88, tx[1] = 0x4800, tx[2] = 0x0000
<7> [493.703579] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cmn[0] = 0x0500, cmn[1] = 0x0005, cmn[2] = 0x0000, cmn[3] = 0x0000
<7> [493.703671] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[0] = 0x308c
<7> [493.703763] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[1] = 0x2110
<7> [493.703854] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[2] = 0xcc9c
<7> [493.703956] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[3] = 0xbfc1
<7> [493.704050] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[4] = 0x4b9a
<7> [493.704139] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[5] = 0x3f81
<7> [493.704232] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[6] = 0x2000
<7> [493.704322] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[7] = 0x0001
<7> [493.704411] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[8] = 0x5000
<7> [493.704503] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[9] = 0x0000
<7> [493.704596] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[10] = 0x0000
<7> [493.704688] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vdr: custom width: 0x00, serdes rate: 0x42, hdmi rate: 0x00
<7> [493.704779] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [493.704870] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [493.704976] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [493.705067] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [493.705157] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [493.705246] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [493.705336] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [493.705426] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [493.705517] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [493.705607] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [493.705696] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [493.705785] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [493.705875] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:35:plane 1A] fb: [FB:555] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [493.705983] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [493.706073] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [493.706163] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [493.706252] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [493.706341] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [493.706430] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [493.706519] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:145:cursor A] fb: [NOFB], visible: no
<7> [493.707078] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling PW_A
<7> [493.707204] xe 0000:03:00.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating enabled for pipe_mask=0x1 (CLKGATE_DIS_DSSDSC=0x88000000)
<7> [493.707321] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:507:DDI TC1/PHY F]
<7> [493.707411] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:509:DP-MST A]
<7> [493.707499] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:510:DP-MST B]
<7> [493.707585] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:511:DP-MST C]
<7> [493.707671] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:512:DP-MST D]
<7> [493.707755] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:526:DDI TC2/PHY G]
<7> [493.707838] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:528:DP-MST A]
<7> [493.707941] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:529:DP-MST B]
<7> [493.708034] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:530:DP-MST C]
<7> [493.708128] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:531:DP-MST D]
<7> [493.708218] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:538:DDI TC3/PHY H]
<7> [493.708305] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:542:DDI TC4/PHY I]
<7> [493.708389] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:544:DP-MST A]
<7> [493.708472] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:545:DP-MST B]
<7> [493.708557] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:546:DP-MST C]
<7> [493.708643] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:547:DP-MST D]
<7> [493.710441] xe 0000:03:00.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xf
<7> [493.710809] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 2 (mbus joined: no)
<7> [493.710920] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enable TC PLL 2 (active 0x1, on? 0) for [CRTC:151:pipe A]
<7> [493.711024] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enabling TC PLL 2
<7> [493.713502] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling AUX_TC2
<7> [493.717571] xe 0000:03:00.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX USBC2/DDI TC2/PHY G: DPCD: 12 14 c4 01 01 15 01 81 00 01 04 01 0f 00 01
<7> [493.722318] xe 0000:03:00.0: [drm:intel_dp_init_lttpr_and_dprx_caps [xe]] [CONNECTOR:527:DP-2][ENCODER:526:DDI TC2/PHY G][DPRX] LTTPR common capabilities: 00 00 00 00 00 00 00 00
<7> [493.723334] xe 0000:03:00.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX USBC2/DDI TC2/PHY G: DPCD: 12 14 c4 01 01 15 01 81 00 01 04 01 0f 00 01
<7> [493.723774] xe 0000:03:00.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:527:DP-2][ENCODER:526:DDI TC2/PHY G][DPRX] Using LINK_BW_SET value 0a
<7> [493.725288] xe 0000:03:00.0: [drm:intel_dp_set_signal_levels [xe]] [CONNECTOR:527:DP-2][ENCODER:526:DDI TC2/PHY G][DPRX] 8b/10b, lanes: 4, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0
<7> [493.725853] xe 0000:03:00.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:527:DP-2][ENCODER:526:DDI TC2/PHY G][DPRX] Using DP training pattern TPS1
<7> [493.731800] xe 0000:03:00.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:527:DP-2][ENCODER:526:DDI TC2/PHY G][DPRX] Clock recovery OK
<7> [493.731983] xe 0000:03:00.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:527:DP-2][ENCODER:526:DDI TC2/PHY G][DPRX] Using DP training pattern TPS3
<7> [493.738791] xe 0000:03:00.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:527:DP-2][ENCODER:526:DDI TC2/PHY G][DPRX] Channel EQ done. DP Training successful
<7> [493.739110] xe 0000:03:00.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:527:DP-2][ENCODER:526:DDI TC2/PHY G][DPRX] Link Training passed at link rate = 270000, lane count = 4
<7> [493.740478] xe 0000:03:00.0: [drm:intel_enable_transcoder [xe]] enabling pipe A
<7> [493.757723] xe 0000:03:00.0: [drm:intel_audio_codec_enable [xe]] [CONNECTOR:527:DP-2][ENCODER:526:DDI TC2/PHY G] Enable audio codec on [CRTC:151:pipe A], 40 bytes ELD
<7> [493.774749] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:527:DP-2]
<7> [493.775653] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:151:pipe A]
<7> [493.845025] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling AUX_TC2
<7> [495.750148] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling AUX_TC2
<7> [495.853038] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling AUX_TC2
<6> [498.960540] [IGT] kms_flip: finished subtest AC-DP2-HDMI-A3, FAIL
Created at 2026-04-11 04:14:24