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

Result: Fail

i915_display_info3 igt_runner3 results3.json results3-xe-load.json guc_logs3.tar i915_display_info_post_exec3 boot3 dmesg3

DetailValue
Duration 6.82 seconds
Hostname 
shard-bmg-9
Igt-Version 
IGT-Version: 2.3-g88eca2563 (x86_64) (Linux: 7.0.0-rc5-lgci-xe-xe-4764-a5c5cfbd8c7054ac6-debug+ x86_64)
Out 
Starting dynamic subtest: BD-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:925 run_test_on_crtc_set.constprop.0()
  #2 ../tests/kms_flip.c:1976 run_pair()
  #3 ../tests/kms_flip.c:2135 __igt_unique____real_main2047()
  #4 ../tests/kms_flip.c:2047 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest BD-DP2-HDMI-A3: FAIL (6.817s)
Err 
Starting dynamic subtest: BD-DP2-HDMI-A3
(kms_flip:9129) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:930:
(kms_flip:9129) CRITICAL: Failed assertion: end - start < 500
Dynamic subtest BD-DP2-HDMI-A3 failed.
**** DEBUG ****
(kms_flip:9129) DEBUG: name = flip
last_ts = 301.307831
last_received_ts = 301.307465
last_seq = 7083
current_ts = 301.324524
current_received_ts = 301.324158
current_seq = 7084
count = 163
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 26us
(kms_flip:9129) DEBUG: name = flip
last_ts = 301.324524
last_received_ts = 301.324158
last_seq = 7084
current_ts = 301.341187
current_received_ts = 301.340820
current_seq = 7085
count = 164
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 46us
(kms_flip:9129) DEBUG: name = flip
last_ts = 301.341187
last_received_ts = 301.340820
last_seq = 7085
current_ts = 301.357849
current_received_ts = 301.357483
current_seq = 7086
count = 165
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 26us
(kms_flip:9129) DEBUG: name = flip
last_ts = 301.357849
last_received_ts = 301.357483
last_seq = 7086
current_ts = 301.374542
current_received_ts = 301.374146
current_seq = 7087
count = 166
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 26us
(kms_flip:9129) DEBUG: name = flip
last_ts = 301.374542
last_received_ts = 301.374146
last_seq = 7087
current_ts = 301.391205
current_received_ts = 301.390839
current_seq = 7088
count = 167
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 25us
(kms_flip:9129) DEBUG: name = flip
last_ts = 301.391205
last_received_ts = 301.390839
last_seq = 7088
current_ts = 301.407898
current_received_ts = 301.407501
current_seq = 7089
count = 168
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 28us
(kms_flip:9129) DEBUG: name = flip
last_ts = 301.407898
last_received_ts = 301.407501
last_seq = 7089
current_ts = 301.424561
current_received_ts = 301.424164
current_seq = 7090
count = 169
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 28us
(kms_flip:9129) DEBUG: name = flip
last_ts = 301.424561
last_received_ts = 301.424164
last_seq = 7090
current_ts = 301.441223
current_received_ts = 301.440857
current_seq = 7091
count = 170
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 28us
(kms_flip:9129) DEBUG: name = flip
last_ts = 301.441223
last_received_ts = 301.440857
last_seq = 7091
current_ts = 301.457916
current_received_ts = 301.457550
current_seq = 7092
count = 171
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 28us
(kms_flip:9129) DEBUG: name = flip
last_ts = 301.457916
last_received_ts = 301.457550
last_seq = 7092
current_ts = 301.474579
current_received_ts = 301.474213
current_seq = 7093
count = 172
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 28us
(kms_flip:9129) DEBUG: name = flip
last_ts = 301.474579
last_received_ts = 301.474213
last_seq = 7093
current_ts = 301.491272
current_received_ts = 301.490875
current_seq = 7094
count = 173
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 25us
(kms_flip:9129) DEBUG: name = flip
last_ts = 301.491272
last_received_ts = 301.490875
last_seq = 7094
current_ts = 301.507935
current_received_ts = 301.507568
current_seq = 7095
count = 174
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 26us
(kms_flip:9129) DEBUG: name = flip
last_ts = 301.507935
last_received_ts = 301.507568
last_seq = 7095
current_ts = 301.524628
current_received_ts = 301.524231
current_seq = 7096
count = 175
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 25us
(kms_flip:9129) DEBUG: name = flip
last_ts = 301.524628
last_received_ts = 301.524231
last_seq = 7096
current_ts = 301.541290
current_received_ts = 301.540924
current_seq = 7097
count = 176
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 26us
(kms_flip:9129) DEBUG: name = flip
last_ts = 301.541290
last_received_ts = 301.540924
last_seq = 7097
current_ts = 301.557953
current_received_ts = 301.557587
current_seq = 7098
count = 177
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 29us
(kms_flip:9129) DEBUG: name = flip
last_ts = 301.557953
last_received_ts = 301.557587
last_seq = 7098
current_ts = 301.574646
current_received_ts = 301.574249
current_seq = 7099
count = 178
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 29us
(kms_flip:9129) DEBUG: name = flip
last_ts = 301.574646
last_received_ts = 301.574249
last_seq = 7099
current_ts = 301.591309
current_received_ts = 301.590942
current_seq = 7100
count = 179
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 26us
(kms_flip:9129) DEBUG: name = flip
last_ts = 301.591309
last_received_ts = 301.590942
last_seq = 7100
current_ts = 301.608002
current_received_ts = 301.607605
current_seq = 7101
count = 180
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 26us
(kms_flip:9129) DEBUG: name = flip
last_ts = 301.608002
last_received_ts = 301.607605
last_seq = 7101
current_ts = 301.624664
current_received_ts = 301.624268
current_seq = 7102
count = 181
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 27us
(kms_flip:9129) DEBUG: name = flip
last_ts = 301.624664
last_received_ts = 301.624268
last_seq = 7102
current_ts = 301.641327
current_received_ts = 301.640961
current_seq = 7103
count = 182
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 26us
(kms_flip:9129) DEBUG: name = flip
last_ts = 301.641327
last_received_ts = 301.640961
last_seq = 7103
current_ts = 301.658020
current_received_ts = 301.657623
current_seq = 7104
count = 183
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 27us
(kms_flip:9129) DEBUG: name = flip
last_ts = 301.658020
last_received_ts = 301.657623
last_seq = 7104
current_ts = 301.674683
current_received_ts = 301.674316
current_seq = 7105
count = 184
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 27us
(kms_flip:9129) DEBUG: name = flip
last_ts = 301.674683
last_received_ts = 301.674316
last_seq = 7105
current_ts = 301.691345
current_received_ts = 301.690979
current_seq = 7106
count = 185
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 26us
(kms_flip:9129) DEBUG: name = flip
last_ts = 301.691345
last_received_ts = 301.690979
last_seq = 7106
current_ts = 301.708038
current_received_ts = 301.707672
current_seq = 7107
count = 186
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 26us
(kms_flip:9129) DEBUG: name = flip
last_ts = 301.708038
last_received_ts = 301.707672
last_seq = 7107
current_ts = 301.724701
current_received_ts = 301.724335
current_seq = 7108
count = 187
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 28us
(kms_flip:9129) DEBUG: name = flip
last_ts = 301.724701
last_received_ts = 301.724335
last_seq = 7108
current_ts = 301.741394
current_received_ts = 301.741028
current_seq = 7109
count = 188
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 25us
(kms_flip:9129) DEBUG: name = flip
last_ts = 301.741394
last_received_ts = 301.741028
last_seq = 7109
current_ts = 301.758057
current_received_ts = 301.757690
current_seq = 7110
count = 189
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 25us
(kms_flip:9129) DEBUG: name = flip
last_ts = 301.758057
last_received_ts = 301.757690
last_seq = 7110
current_ts = 301.774750
current_received_ts = 301.774353
current_seq = 7111
count = 190
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 28us
(kms_flip:9129) DEBUG: name = flip
last_ts = 301.774750
last_received_ts = 301.774353
last_seq = 7111
current_ts = 301.791412
current_received_ts = 301.791046
current_seq = 7112
count = 191
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 29us
(kms_flip:9129) DEBUG: name = flip
last_ts = 301.791412
last_received_ts = 301.791046
last_seq = 7112
current_ts = 301.808075
current_received_ts = 301.807709
current_seq = 7113
count = 192
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 26us
(kms_flip:9129) DEBUG: name = flip
last_ts = 301.808075
last_received_ts = 301.807709
last_seq = 7113
current_ts = 301.824768
current_received_ts = 301.824402
current_seq = 7114
count = 193
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 30us
(kms_flip:9129) DEBUG: name = flip
last_ts = 301.824768
last_received_ts = 301.824402
last_seq = 7114
current_ts = 301.841431
current_received_ts = 301.841064
current_seq = 7115
count = 194
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 29us
(kms_flip:9129) DEBUG: name = flip
last_ts = 301.841431
last_received_ts = 301.841064
last_seq = 7115
current_ts = 301.858124
current_received_ts = 301.857727
current_seq = 7116
count = 195
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 26us
(kms_flip:9129) DEBUG: name = flip
last_ts = 301.858124
last_received_ts = 301.857727
last_seq = 7116
current_ts = 301.874786
current_received_ts = 301.874420
current_seq = 7117
count = 196
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 25us
(kms_flip:9129) DEBUG: name = flip
last_ts = 301.874786
last_received_ts = 301.874420
last_seq = 7117
current_ts = 301.891479
current_received_ts = 301.891083
current_seq = 7118
count = 197
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 29us
(kms_flip:9129) DEBUG: name = flip
last_ts = 301.891479
last_received_ts = 301.891083
last_seq = 7118
current_ts = 301.908142
current_received_ts = 301.907776
current_seq = 7119
count = 198
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 28us
(kms_flip:9129) DEBUG: name = flip
last_ts = 301.908142
last_received_ts = 301.907776
last_seq = 7119
current_ts = 301.924805
current_received_ts = 301.924438
current_seq = 7120
count = 199
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 28us
(kms_flip:9129) DEBUG: name = flip
last_ts = 301.924805
last_received_ts = 301.924438
last_seq = 7120
current_ts = 301.941498
current_received_ts = 301.941132
current_seq = 7121
count = 200
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 29us
(kms_flip:9129) DEBUG: name = flip
last_ts = 301.941498
last_received_ts = 301.941132
last_seq = 7121
current_ts = 301.958160
current_received_ts = 301.957794
current_seq = 7122
count = 201
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 26us
(kms_flip:9129) DEBUG: name = flip
last_ts = 301.958160
last_received_ts = 301.957794
last_seq = 7122
current_ts = 301.974823
current_received_ts = 301.974457
current_seq = 7123
count = 202
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 25us
(kms_flip:9129) DEBUG: name = flip
last_ts = 301.974823
last_received_ts = 301.974457
last_seq = 7123
current_ts = 301.991516
current_received_ts = 301.991119
current_seq = 7124
count = 203
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 26us
(kms_flip:9129) DEBUG: name = flip
last_ts = 301.991516
last_received_ts = 301.991119
last_seq = 7124
current_ts = 302.008179
current_received_ts = 302.007812
current_seq = 7125
count = 204
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 49us
(kms_flip:9129) DEBUG: name = flip
last_ts = 302.008179
last_received_ts = 302.007812
last_seq = 7125
current_ts = 302.024872
current_received_ts = 302.024475
current_seq = 7126
count = 205
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 26us
(kms_flip:9129) DEBUG: name = flip
last_ts = 302.024872
last_received_ts = 302.024475
last_seq = 7126
current_ts = 302.041565
current_received_ts = 302.041168
current_seq = 7127
count = 206
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 29us
(kms_flip:9129) DEBUG: name = flip
last_ts = 302.041565
last_received_ts = 302.041168
last_seq = 7127
current_ts = 302.058228
current_received_ts = 302.057831
current_seq = 7128
count = 207
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 25us
(kms_flip:9129) DEBUG: name = flip
last_ts = 302.058228
last_received_ts = 302.057831
last_seq = 7128
current_ts = 302.074890
current_received_ts = 302.074524
current_seq = 7129
count = 208
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 26us
(kms_flip:9129) DEBUG: name = flip
last_ts = 302.074890
last_received_ts = 302.074524
last_seq = 7129
current_ts = 302.091583
current_received_ts = 302.091187
current_seq = 7130
count = 209
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 26us
(kms_flip:9129) DEBUG: name = flip
last_ts = 302.091583
last_received_ts = 302.091187
last_seq = 7130
current_ts = 302.108246
current_received_ts = 302.107849
current_seq = 7131
count = 210
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 28us
(kms_flip:9129) DEBUG: name = flip
last_ts = 302.108246
last_received_ts = 302.107849
last_seq = 7131
current_ts = 302.124908
current_received_ts = 302.124512
current_seq = 7132
count = 211
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 25us
(kms_flip:9129) DEBUG: name = flip
last_ts = 302.124908
last_received_ts = 302.124512
last_seq = 7132
current_ts = 302.141602
current_received_ts = 302.141327
current_seq = 7133
count = 212
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 40us
(kms_flip:9129) DEBUG: name = flip
last_ts = 302.141602
last_received_ts = 302.141327
last_seq = 7133
current_ts = 302.158264
current_received_ts = 302.157990
current_seq = 7134
count = 213
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 37us
(kms_flip:9129) DEBUG: name = flip
last_ts = 302.158264
last_received_ts = 302.157990
last_seq = 7134
current_ts = 302.174927
current_received_ts = 302.174683
current_seq = 7135
count = 214
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 38us
(kms_flip:9129) DEBUG: name = flip
last_ts = 302.174927
last_received_ts = 302.174683
last_seq = 7135
current_ts = 302.191620
current_received_ts = 302.191284
current_seq = 7136
count = 215
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 44us
(kms_flip:9129) DEBUG: name = flip
last_ts = 302.191620
last_received_ts = 302.191284
last_seq = 7136
current_ts = 302.208282
current_received_ts = 302.207916
current_seq = 7137
count = 216
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 30us
(kms_flip:9129) DEBUG: name = flip
last_ts = 302.208282
last_received_ts = 302.207916
last_seq = 7137
current_ts = 302.224976
current_received_ts = 302.224609
current_seq = 7138
count = 217
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 29us
(kms_flip:9129) DEBUG: name = flip
last_ts = 302.224976
last_received_ts = 302.224609
last_seq = 7138
current_ts = 302.241638
current_received_ts = 302.241241
current_seq = 7139
count = 218
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 29us
(kms_flip:9129) DEBUG: name = flip
last_ts = 302.241638
last_received_ts = 302.241241
last_seq = 7139
current_ts = 302.258331
current_received_ts = 302.257935
current_seq = 7140
count = 219
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 26us
(kms_flip:9129) DEBUG: name = flip
last_ts = 302.258331
last_received_ts = 302.257935
last_seq = 7140
current_ts = 302.274994
current_received_ts = 302.274597
current_seq = 7141
count = 220
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 27us
(kms_flip:9129) DEBUG: name = flip
last_ts = 302.274994
last_received_ts = 302.274597
last_seq = 7141
current_ts = 302.291687
current_received_ts = 302.291290
current_seq = 7142
count = 221
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 26us
(kms_flip:9129) DEBUG: name = flip
last_ts = 302.291687
last_received_ts = 302.291290
last_seq = 7142
current_ts = 302.308350
current_received_ts = 302.307953
current_seq = 7143
count = 222
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 27us
(kms_flip:9129) DEBUG: name = flip
last_ts = 302.308350
last_received_ts = 302.307953
last_seq = 7143
current_ts = 302.325012
current_received_ts = 302.324615
current_seq = 7144
count = 223
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 26us
(kms_flip:9129) DEBUG: name = flip
last_ts = 302.325012
last_received_ts = 302.324615
last_seq = 7144
current_ts = 302.341705
current_received_ts = 302.341309
current_seq = 7145
count = 224
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 26us
(kms_flip:9129) DEBUG: name = flip
last_ts = 302.341705
last_received_ts = 302.341309
last_seq = 7145
current_ts = 302.358368
current_received_ts = 302.358002
current_seq = 7146
count = 225
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 29us
(kms_flip:9129) DEBUG: name = flip
last_ts = 302.358368
last_received_ts = 302.358002
last_seq = 7146
current_ts = 302.375031
current_received_ts = 302.374664
current_seq = 7147
count = 226
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 26us
(kms_flip:9129) DEBUG: name = flip
last_ts = 302.375031
last_received_ts = 302.374664
last_seq = 7147
current_ts = 302.391724
current_received_ts = 302.391357
current_seq = 7148
count = 227
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 26us
(kms_flip:9129) DEBUG: name = flip
last_ts = 302.391724
last_received_ts = 302.391357
last_seq = 7148
current_ts = 302.408386
current_received_ts = 302.408051
current_seq = 7149
count = 228
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 27us
(kms_flip:9129) DEBUG: name = flip
last_ts = 302.408386
last_received_ts = 302.408051
last_seq = 7149
current_ts = 302.425049
current_received_ts = 302.424683
current_seq = 7150
count = 229
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 26us
(kms_flip:9129) DEBUG: name = flip
last_ts = 302.425049
last_received_ts = 302.424683
last_seq = 7150
current_ts = 302.441742
current_received_ts = 302.441376
current_seq = 7151
count = 230
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 28us
(kms_flip:9129) DEBUG: name = flip
last_ts = 302.441742
last_received_ts = 302.441376
last_seq = 7151
current_ts = 302.458405
current_received_ts = 302.458038
current_seq = 7152
count = 231
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 28us
(kms_flip:9129) DEBUG: name = flip
last_ts = 302.458405
last_received_ts = 302.458038
last_seq = 7152
current_ts = 302.475098
current_received_ts = 302.474701
current_seq = 7153
count = 232
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 26us
(kms_flip:9129) DEBUG: name = flip
last_ts = 302.475098
last_received_ts = 302.474701
last_seq = 7153
current_ts = 302.491760
current_received_ts = 302.491272
current_seq = 7154
count = 233
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 26us
(kms_flip:9129) DEBUG: name = flip
last_ts = 302.491760
last_received_ts = 302.491272
last_seq = 7154
current_ts = 302.508453
current_received_ts = 302.508087
current_seq = 7155
count = 234
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 25us
(kms_flip:9129) DEBUG: name = flip
last_ts = 302.508453
last_received_ts = 302.508087
last_seq = 7155
current_ts = 302.525116
current_received_ts = 302.524719
current_seq = 7156
count = 235
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 26us
(kms_flip:9129) DEBUG: name = flip
last_ts = 302.525116
last_received_ts = 302.524719
last_seq = 7156
current_ts = 302.541809
current_received_ts = 302.541412
current_seq = 7157
count = 236
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 25us
(kms_flip:9129) DEBUG: name = flip
last_ts = 302.541809
last_received_ts = 302.541412
last_seq = 7157
current_ts = 302.558472
current_received_ts = 302.558075
current_seq = 7158
count = 237
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 26us
(kms_flip:9129) DEBUG: name = flip
last_ts = 302.558472
last_received_ts = 302.558075
last_seq = 7158
current_ts = 302.575134
current_received_ts = 302.574707
current_seq = 7159
count = 238
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 26us
(kms_flip:9129) DEBUG: name = flip
last_ts = 302.575134
last_received_ts = 302.574707
last_seq = 7159
current_ts = 302.591827
current_received_ts = 302.591431
current_seq = 7160
count = 239
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 26us
(kms_flip:9129) DEBUG: name = flip
last_ts = 302.591827
last_received_ts = 302.591431
last_seq = 7160
current_ts = 302.608490
current_received_ts = 302.608093
current_seq = 7161
count = 240
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 26us
(kms_flip:9129) DEBUG: name = flip
last_ts = 302.608490
last_received_ts = 302.608093
last_seq = 7161
current_ts = 302.625153
current_received_ts = 302.624786
current_seq = 7162
count = 241
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 29us
(kms_flip:9129) DEBUG: name = flip
last_ts = 302.625153
last_received_ts = 302.624786
last_seq = 7162
current_ts = 302.641846
current_received_ts = 302.641479
current_seq = 7163
count = 242
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 29us
(kms_flip:9129) DEBUG: name = flip
last_ts = 302.641846
last_received_ts = 302.641479
last_seq = 7163
current_ts = 302.658508
current_received_ts = 302.658142
current_seq = 7164
count = 243
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 30us
(kms_flip:9129) DEBUG: name = flip
last_ts = 302.658508
last_received_ts = 302.658142
last_seq = 7164
current_ts = 302.675201
current_received_ts = 302.674835
current_seq = 7165
count = 244
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 57us
(kms_flip:9129) DEBUG: name = flip
last_ts = 302.675201
last_received_ts = 302.674835
last_seq = 7165
current_ts = 302.691864
current_received_ts = 302.691467
current_seq = 7166
count = 245
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 27us
(kms_flip:9129) DEBUG: name = flip
last_ts = 302.691864
last_received_ts = 302.691467
last_seq = 7166
current_ts = 302.708527
current_received_ts = 302.708160
current_seq = 7167
count = 246
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 26us
(kms_flip:9129) DEBUG: name = flip
last_ts = 302.708527
last_received_ts = 302.708160
last_seq = 7167
current_ts = 302.725220
current_received_ts = 302.724854
current_seq = 7168
count = 247
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 25us
(kms_flip:9129) DEBUG: name = flip
last_ts = 302.725220
last_received_ts = 302.724854
last_seq = 7168
current_ts = 302.741882
current_received_ts = 302.741516
current_seq = 7169
count = 248
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 25us
(kms_flip:9129) DEBUG: name = flip
last_ts = 302.741882
last_received_ts = 302.741516
last_seq = 7169
current_ts = 302.758575
current_received_ts = 302.758209
current_seq = 7170
count = 249
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 39us
(kms_flip:9129) DEBUG: name = flip
last_ts = 302.758575
last_received_ts = 302.758209
last_seq = 7170
current_ts = 302.775238
current_received_ts = 302.774963
current_seq = 7171
count = 250
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 39us
(kms_flip:9129) DEBUG: name = flip
last_ts = 302.775238
last_received_ts = 302.774963
last_seq = 7171
current_ts = 302.791931
current_received_ts = 302.791656
current_seq = 7172
count = 251
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 39us
(kms_flip:9129) DEBUG: name = flip
last_ts = 302.791931
last_received_ts = 302.791656
last_seq = 7172
current_ts = 302.808594
current_received_ts = 302.808258
current_seq = 7173
count = 252
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 37us
(kms_flip:9129) DEBUG: name = flip
last_ts = 302.808594
last_received_ts = 302.808258
last_seq = 7173
current_ts = 302.825256
current_received_ts = 302.824921
current_seq = 7174
count = 253
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 27us
(kms_flip:9129) DEBUG: name = flip
last_ts = 302.825256
last_received_ts = 302.824921
last_seq = 7174
current_ts = 302.841949
current_received_ts = 302.841583
current_seq = 7175
count = 254
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 26us
(kms_flip:9129) DEBUG: name = flip
last_ts = 302.841949
last_received_ts = 302.841583
last_seq = 7175
current_ts = 302.858643
current_received_ts = 302.858246
current_seq = 7176
count = 255
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 26us
(kms_flip:9129) DEBUG: name = flip
last_ts = 302.858643
last_received_ts = 302.858246
last_seq = 7176
current_ts = 302.875305
current_received_ts = 302.874908
current_seq = 7177
count = 256
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 29us
(kms_flip:9129) DEBUG: name = flip
last_ts = 302.875305
last_received_ts = 302.874908
last_seq = 7177
current_ts = 302.891968
current_received_ts = 302.891602
current_seq = 7178
count = 257
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 26us
(kms_flip:9129) DEBUG: name = flip
last_ts = 302.891968
last_received_ts = 302.891602
last_seq = 7178
current_ts = 302.908630
current_received_ts = 302.908264
current_seq = 7179
count = 258
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 29us
(kms_flip:9129) DEBUG: name = flip
last_ts = 302.908630
last_received_ts = 302.908264
last_seq = 7179
current_ts = 302.925323
current_received_ts = 302.924927
current_seq = 7180
count = 259
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 28us
(kms_flip:9129) DEBUG: name = flip
last_ts = 302.925323
last_received_ts = 302.924927
last_seq = 7180
current_ts = 302.942017
current_received_ts = 302.941620
current_seq = 7181
count = 260
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 29us
(kms_flip:9129) DEBUG: name = flip
last_ts = 302.942017
last_received_ts = 302.941620
last_seq = 7181
current_ts = 302.958679
current_received_ts = 302.958282
current_seq = 7182
count = 261
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 27us
(kms_flip:9129) DEBUG: name = flip
last_ts = 302.958679
last_received_ts = 302.958282
last_seq = 7182
current_ts = 302.975342
current_received_ts = 302.974976
current_seq = 7183
count = 262
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 31us
(kms_flip:9129) DEBUG: name = flip
last_ts = 302.975342
last_received_ts = 302.974976
last_seq = 7183
current_ts = 302.992004
current_received_ts = 302.991638
current_seq = 7184
count = 263
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 29us
(kms_flip:9129) DEBUG: name = flip
last_ts = 302.992004
last_received_ts = 302.991638
last_seq = 7184
current_ts = 303.008698
current_received_ts = 303.008331
current_seq = 7185
count = 264
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 28us
(kms_flip:9129) DEBUG: name = flip
last_ts = 303.008698
last_received_ts = 303.008331
last_seq = 7185
current_ts = 303.025360
current_received_ts = 303.024994
current_seq = 7186
count = 265
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 30us
(kms_flip:9129) DEBUG: name = flip
last_ts = 303.025360
last_received_ts = 303.024994
last_seq = 7186
current_ts = 303.042053
current_received_ts = 303.041656
current_seq = 7187
count = 266
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 26us
(kms_flip:9129) DEBUG: name = flip
last_ts = 303.042053
last_received_ts = 303.041656
last_seq = 7187
current_ts = 303.058716
current_received_ts = 303.058319
current_seq = 7188
count = 267
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 30us
(kms_flip:9129) DEBUG: name = flip
last_ts = 303.058716
last_received_ts = 303.058319
last_seq = 7188
current_ts = 303.075409
current_received_ts = 303.075043
current_seq = 7189
count = 268
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 29us
(kms_flip:9129) DEBUG: name = flip
last_ts = 303.075409
last_received_ts = 303.075043
last_seq = 7189
current_ts = 303.092072
current_received_ts = 303.091705
current_seq = 7190
count = 269
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 26us
(kms_flip:9129) DEBUG: name = flip
last_ts = 303.092072
last_received_ts = 303.091705
last_seq = 7190
current_ts = 303.108765
current_received_ts = 303.108368
current_seq = 7191
count = 270
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 25us
(kms_flip:9129) DEBUG: name = flip
last_ts = 303.108765
last_received_ts = 303.108368
last_seq = 7191
current_ts = 303.125427
current_received_ts = 303.125061
current_seq = 7192
count = 271
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 25us
(kms_flip:9129) DEBUG: name = flip
last_ts = 303.125427
last_received_ts = 303.125061
last_seq = 7192
current_ts = 303.142090
current_received_ts = 303.141693
current_seq = 7193
count = 272
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 26us
(kms_flip:9129) DEBUG: name = flip
last_ts = 303.142090
last_received_ts = 303.141693
last_seq = 7193
current_ts = 303.158752
current_received_ts = 303.158386
current_seq = 7194
count = 273
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 26us
(kms_flip:9129) DEBUG: name = flip
last_ts = 303.158752
last_received_ts = 303.158386
last_seq = 7194
current_ts = 303.175446
current_received_ts = 303.175079
current_seq = 7195
count = 274
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 29us
(kms_flip:9129) DEBUG: name = flip
last_ts = 303.175446
last_received_ts = 303.175079
last_seq = 7195
current_ts = 303.192108
current_received_ts = 303.191742
current_seq = 7196
count = 275
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 27us
(kms_flip:9129) DEBUG: name = flip
last_ts = 303.192108
last_received_ts = 303.191742
last_seq = 7196
current_ts = 303.208801
current_received_ts = 303.208435
current_seq = 7197
count = 276
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 26us
(kms_flip:9129) DEBUG: name = flip
last_ts = 303.208801
last_received_ts = 303.208435
last_seq = 7197
current_ts = 303.225464
current_received_ts = 303.225098
current_seq = 7198
count = 277
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 27us
(kms_flip:9129) DEBUG: name = flip
last_ts = 303.225464
last_received_ts = 303.225098
last_seq = 7198
current_ts = 303.242157
current_received_ts = 303.241760
current_seq = 7199
count = 278
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 25us
(kms_flip:9129) DEBUG: name = flip
last_ts = 303.242157
last_received_ts = 303.241760
last_seq = 7199
current_ts = 303.258820
current_received_ts = 303.258423
current_seq = 7200
count = 279
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 26us
(kms_flip:9129) DEBUG: name = flip
last_ts = 303.258820
last_received_ts = 303.258423
last_seq = 7200
current_ts = 303.275482
current_received_ts = 303.275116
current_seq = 7201
count = 280
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 26us
(kms_flip:9129) DEBUG: name = flip
last_ts = 303.275482
last_received_ts = 303.275116
last_seq = 7201
current_ts = 303.292175
current_received_ts = 303.291809
current_seq = 7202
count = 281
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 29us
(kms_flip:9129) DEBUG: name = flip
last_ts = 303.292175
last_received_ts = 303.291809
last_seq = 7202
current_ts = 303.308838
current_received_ts = 303.308472
current_seq = 7203
count = 282
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 27us
(kms_flip:9129) DEBUG: name = flip
last_ts = 303.308838
last_received_ts = 303.308472
last_seq = 7203
current_ts = 303.325531
current_received_ts = 303.325134
current_seq = 7204
count = 283
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 26us
(kms_flip:9129) DEBUG: name = flip
last_ts = 303.325531
last_received_ts = 303.325134
last_seq = 7204
current_ts = 303.342194
current_received_ts = 303.341827
current_seq = 7205
count = 284
seq_step = 1
(kms_flip:9129) DEBUG: Vblank took 1022us
(kms_flip:9129) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:930:
(kms_flip:9129) CRITICAL: Failed assertion: end - start < 500
(kms_flip:9129) igt_core-INFO: Stack trace:
(kms_flip:9129) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:9129) igt_core-INFO:   #1 ../tests/kms_flip.c:925 run_test_on_crtc_set.constprop.0()
(kms_flip:9129) igt_core-INFO:   #2 ../tests/kms_flip.c:1976 run_pair()
(kms_flip:9129) igt_core-INFO:   #3 ../tests/kms_flip.c:2135 __igt_unique____real_main2047()
(kms_flip:9129) igt_core-INFO:   #4 ../tests/kms_flip.c:2047 main()
(kms_flip:9129) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:9129) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:9129) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest BD-DP2-HDMI-A3: FAIL (6.817s)
Dmesg
<6> [327.349046] [IGT] kms_flip: starting dynamic subtest BD-DP2-HDMI-A3
<7> [327.350237] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:559]
<7> [327.350928] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:560]
<7> [327.412881] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:151:pipe A]
<7> [327.413784] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:269:pipe B]
<7> [327.414381] 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> [327.415083] 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> [327.415713] 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> [327.416222] 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> [327.416616] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] 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> [327.417003] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in output_types (expected 0x00000080, found 0x00000000)
<7> [327.417336] 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> [327.417667] 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> [327.418026] 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> [327.418325] 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> [327.418614] 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> [327.418904] 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> [327.419176] 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> [327.419435] 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> [327.419687] 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> [327.419962] 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 1084, found 0)
<7> [327.420213] 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 1089, found 0)
<7> [327.420450] 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> [327.420684] 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> [327.420932] 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> [327.421157] 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> [327.421382] 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> [327.421599] 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> [327.421816] 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> [327.422022] 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> [327.422218] 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> [327.422415] 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> [327.422613] 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 1084, found 0)
<7> [327.422804] 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 1089, found 0)
<7> [327.422996] 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> [327.423175] 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> [327.423350] 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> [327.423527] 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> [327.423704] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [327.423895] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in enhanced_framing (expected yes, found no)
<7> [327.424060] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in pipe_bpp (expected 36, found 0)
<7> [327.424217] 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> [327.424371] 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> [327.424526] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in port_clock (expected 270000, found 0)
<7> [327.424675] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:269:pipe B] fastset requirement not met, forcing full modeset
<7> [327.424828] xe 0000:03:00.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:269:pipe B] releasing TC PLL 2
<7> [327.425005] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:269:pipe B] dbuf slices 0xc -> 0x0, ddb (0 - 1024) -> (0 - 0), active pipes 0x6 -> 0x4
<7> [327.425139] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:387:pipe C] dbuf slices 0xc -> 0xc, ddb (1024 - 2048) -> (0 - 2048), active pipes 0x6 -> 0x4
<7> [327.425293] xe 0000:03:00.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:263:cursor B] ddb ( 965 - 1024) -> ( 0 - 0), size 59 -> 0
<7> [327.425451] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:269:pipe B] min cdclk: 192000 kHz -> 0 kHz
<7> [327.425632] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:269:pipe B] enable: no [modeset]
<7> [327.425782] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:153:plane 1B] fb: [NOFB], visible: no
<7> [327.425944] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:183:plane 2B] fb: [NOFB], visible: no
<7> [327.426084] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:213:plane 3B] fb: [NOFB], visible: no
<7> [327.426217] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:243:plane 4B] fb: [NOFB], visible: no
<7> [327.426344] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:253:plane 5B] fb: [NOFB], visible: no
<7> [327.426469] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:263:cursor B] fb: [NOFB], visible: no
<7> [327.427278] 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:269:pipe B]
<7> [327.445853] xe 0000:03:00.0: [drm:intel_audio_component_get_eld [xe]] Not valid for port E
<7> [327.446251] xe 0000:03:00.0: [drm:intel_disable_transcoder [xe]] disabling pipe B
<7> [327.467726] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling AUX_TC2
<7> [327.470467] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disable TC PLL 2 (active 0x2, on? 1) for [CRTC:269:pipe B]
<7> [327.471079] xe 0000:03:00.0: [drm:intel_audio_component_get_power [xe]] restored AUD_FREQ_CNTRL to 0x8010
<7> [327.472688] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disabling TC PLL 2
<7> [327.473415] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:507:DDI TC1/PHY F]
<7> [327.473885] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:509:DP-MST A]
<7> [327.474310] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:510:DP-MST B]
<7> [327.474718] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:511:DP-MST C]
<7> [327.475197] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:512:DP-MST D]
<7> [327.475613] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:526:DDI TC2/PHY G]
<7> [327.476153] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:528:DP-MST A]
<7> [327.476551] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:529:DP-MST B]
<7> [327.476973] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:530:DP-MST C]
<7> [327.477366] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:531:DP-MST D]
<7> [327.477758] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:538:DDI TC3/PHY H]
<7> [327.478167] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:542:DDI TC4/PHY I]
<7> [327.478516] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:544:DP-MST A]
<7> [327.478882] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:545:DP-MST B]
<7> [327.479231] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:546:DP-MST C]
<7> [327.479561] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:547:DP-MST D]
<7> [327.479884] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:527:DP-2]
<7> [327.481969] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 2 (mbus joined: no)
<7> [327.495011] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling PW_B
<7> [327.495755] xe 0000:03:00.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating disabled for pipe_mask=0x2 (CLKGATE_DIS_DSSDSC=0x8a000000)
<7> [327.496584] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:269:pipe B]
<7> [327.497915] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:387:pipe C]
<7> [327.498229] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.enable (expected yes, found no)
<7> [327.498841] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.active (expected yes, found no)
<7> [327.499392] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in cpu_transcoder (expected 2, found -1)
<7> [327.499959] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in lane_count (expected 4, found 0)
<7> [327.500495] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [327.500994] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [327.501460] 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 1920, found 0)
<7> [327.501908] 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 2200, found 0)
<7> [327.502324] 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 1920, found 0)
<7> [327.502711] 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 2200, found 0)
<7> [327.503128] 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 2008, found 0)
<7> [327.503476] 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 2052, found 0)
<7> [327.503828] 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 1080, found 0)
<7> [327.504152] 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 1080, found 0)
<7> [327.504481] 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 1083, found 0)
<7> [327.504789] 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 1088, found 0)
<7> [327.505084] 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 1125, found 0)
<7> [327.505366] 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 1125, found 0)
<7> [327.505634] 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 1920, found 0)
<7> [327.505909] 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 2200, found 0)
<7> [327.506159] 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 1920, found 0)
<7> [327.506397] 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 2200, found 0)
<7> [327.506631] 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 2008, found 0)
<7> [327.506863] 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 2052, found 0)
<7> [327.507070] 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 1080, found 0)
<7> [327.507277] 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 1080, found 0)
<7> [327.507482] 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 1083, found 0)
<7> [327.507681] 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 1088, found 0)
<7> [327.507884] 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 1125, found 0)
<7> [327.508072] 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 1125, found 0)
<7> [327.508253] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [327.508426] 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 1, found 0)
<7> [327.508592] 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 8, found 0)
<7> [327.508771] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [327.508930] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [327.509087] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in pipe_bpp (expected 36, found 0)
<7> [327.509239] 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 148500, found 0)
<7> [327.509389] 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 148500, found 0)
<7> [327.509537] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in port_clock (expected 222750, found 0)
<7> [327.509688] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [327.509840] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in infoframes.gcp (expected 0x00000006, found 0x00000000)
<7> [327.509979] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in avi infoframe
<7> [327.510110] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [327.510240] xe 0000:03:00.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [327.510242] xe 0000:03:00.0: colorspace: RGB
<7> [327.510244] xe 0000:03:00.0: scan mode: Underscan
<7> [327.510246] xe 0000:03:00.0: colorimetry: No Data
<7> [327.510247] xe 0000:03:00.0: picture aspect: No Data
<7> [327.510249] xe 0000:03:00.0: active aspect: Same as Picture
<7> [327.510250] xe 0000:03:00.0: itc: No Data
<7> [327.510252] xe 0000:03:00.0: extended colorimetry: xvYCC 601
<7> [327.510253] xe 0000:03:00.0: quantization range: Full
<7> [327.510255] xe 0000:03:00.0: nups: Unknown Non-uniform Scaling
<7> [327.510256] xe 0000:03:00.0: video code: 0
<7> [327.510258] xe 0000:03:00.0: ycc quantization range: Full
<7> [327.510259] xe 0000:03:00.0: hdmi content type: Graphics
<7> [327.510261] xe 0000:03:00.0: pixel repeat: 0
<7> [327.510262] xe 0000:03:00.0: bar top 0, bottom 0, left 0, right 0
<7> [327.510264] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [327.510392] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in spd infoframe
<7> [327.510516] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [327.510641] xe 0000:03:00.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [327.510642] xe 0000:03:00.0: vendor: Intel
<7> [327.510644] xe 0000:03:00.0: product: Discrete gfx
<7> [327.510645] xe 0000:03:00.0: source device information: PC General (0x9)
<7> [327.510647] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [327.510768] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hdmi infoframe
<7> [327.510888] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [327.511003] xe 0000:03:00.0: HDMI infoframe: Vendor, version 1, length 4
<7> [327.511004] xe 0000:03:00.0: empty frame
<7> [327.511006] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [327.511123] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:387:pipe C] fastset requirement not met, forcing full modeset
<7> [327.511233] xe 0000:03:00.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:387:pipe C] releasing TC PLL 3
<7> [327.511365] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xd -> 0x1 (total dbuf slices 0xf), mbus joined? no->no
<7> [327.511465] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:387:pipe C] dbuf slices 0xc -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x4 -> 0x0
<7> [327.511586] xe 0000:03:00.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:381:cursor C] ddb (1989 - 2048) -> ( 0 - 0), size 59 -> 0
<7> [327.511696] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:387:pipe C] min cdclk: 192000 kHz -> 0 kHz
<7> [327.511853] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:387:pipe C] enable: no [modeset]
<7> [327.511964] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:271:plane 1C] fb: [NOFB], visible: no
<7> [327.512071] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:301:plane 2C] fb: [NOFB], visible: no
<7> [327.512175] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:331:plane 3C] fb: [NOFB], visible: no
<7> [327.512274] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:361:plane 4C] fb: [NOFB], visible: no
<7> [327.512371] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:371:plane 5C] fb: [NOFB], visible: no
<7> [327.512471] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:381:cursor C] fb: [NOFB], visible: no
<7> [327.513145] 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:387:pipe C]
<7> [327.545066] xe 0000:03:00.0: [drm:intel_audio_component_get_eld [xe]] Not valid for port F
<7> [327.545677] xe 0000:03:00.0: [drm:intel_hdmi_handle_sink_scrambling [xe]] [CONNECTOR:539:HDMI-A-3] scrambling=no, TMDS bit clock ratio=1/10
<7> [327.546528] xe 0000:03:00.0: [drm:do_gmbus_xfer [xe]] GMBUS [i915 gmbus tc3] NAK for addr: 0054 w(1)
<7> [327.547020] xe 0000:03:00.0: [drm:do_gmbus_xfer [xe]] GMBUS [i915 gmbus tc3] NAK on first message, retry
<7> [327.547811] xe 0000:03:00.0: [drm:do_gmbus_xfer [xe]] GMBUS [i915 gmbus tc3] NAK for addr: 0054 w(1)
<7> [327.548198] xe 0000:03:00.0: [drm:drm_scdc_set_high_tmds_clock_ratio [drm_display_helper]] [CONNECTOR:539:HDMI-A-3] Failed to read TMDS config: -6
<7> [327.548250] xe 0000:03:00.0: [drm:intel_ddi_disable [xe]] [CONNECTOR:539:HDMI-A-3] Failed to reset sink scrambling/TMDS bit clock ratio
<7> [327.549003] xe 0000:03:00.0: [drm:intel_disable_transcoder [xe]] disabling pipe C
<7> [327.558276] xe 0000:03:00.0: [drm:intel_audio_component_get_power [xe]] restored AUD_FREQ_CNTRL to 0x8010
<7> [327.570689] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disable TC PLL 3 (active 0x4, on? 1) for [CRTC:387:pipe C]
<7> [327.573442] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disabling TC PLL 3
<7> [327.574309] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:507:DDI TC1/PHY F]
<7> [327.574840] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:509:DP-MST A]
<7> [327.574923] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling AUX_TC2
<7> [327.575521] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:510:DP-MST B]
<7> [327.576246] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:511:DP-MST C]
<7> [327.576860] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:512:DP-MST D]
<7> [327.577407] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:526:DDI TC2/PHY G]
<7> [327.577993] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:528:DP-MST A]
<7> [327.578482] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:529:DP-MST B]
<7> [327.579007] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:530:DP-MST C]
<7> [327.579403] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:531:DP-MST D]
<7> [327.579840] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:538:DDI TC3/PHY H]
<7> [327.580453] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:542:DDI TC4/PHY I]
<7> [327.580867] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:544:DP-MST A]
<7> [327.581224] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:545:DP-MST B]
<7> [327.581562] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:546:DP-MST C]
<7> [327.581929] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:547:DP-MST D]
<7> [327.582239] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:539:HDMI-A-3]
<7> [327.582793] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 2 (mbus joined: no)
<7> [327.583393] xe 0000:03:00.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0x1
<7> [327.583956] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling PW_C
<7> [327.584381] xe 0000:03:00.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating disabled for pipe_mask=0x4 (CLKGATE_DIS_DSSDSC=0xaa000000)
<7> [327.584604] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling PW_2
<7> [327.584960] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:387:pipe C]
<7> [327.585286] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:505:pipe D]
<7> [327.585824] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:505:pipe D]
<7> [327.585852] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CONNECTOR:539:HDMI-A-3]
<7> [327.585933] xe 0000:03:00.0: [drm:intel_hdmi_compute_clock [xe]] picking 12 bpc for HDMI output (pipe bpp: 36)
<7> [327.586095] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:505:pipe D] hw max bpp: 36, pipe bpp: 36, dithering: 0
<7> [327.586250] xe 0000:03:00.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:538:DDI TC3/PHY H] [CRTC:505:pipe D]
<7> [327.586400] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.enable (expected no, found yes)
<7> [327.586549] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.active (expected no, found yes)
<7> [327.586693] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in cpu_transcoder (expected -1, found 3)
<7> [327.586856] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in lane_count (expected 0, found 4)
<7> [327.586987] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [327.587109] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [327.587230] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [327.587347] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [327.587466] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [327.587581] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [327.587695] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [327.587810] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [327.587916] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [327.588019] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [327.588120] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1083)
<7> [327.588217] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1088)
<7> [327.588314] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [327.588412] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [327.588506] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [327.588603] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [327.588698] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [327.588803] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [327.588900] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [327.588993] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [327.589086] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [327.589182] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [327.589278] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1083)
<7> [327.589371] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1088)
<7> [327.589467] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [327.589562] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [327.589661] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [327.589767] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [327.589864] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [327.589954] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in has_hdmi_sink (expected no, found yes)
<7> [327.590044] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in has_infoframe (expected no, found yes)
<7> [327.590133] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in pipe_bpp (expected 0, found 36)
<7> [327.590220] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [327.590309] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [327.590393] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in port_clock (expected 0, found 222750)
<7> [327.590480] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071)
<7> [327.590569] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in infoframes.gcp (expected 0x00000000, found 0x00000006)
<7> [327.590657] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in avi infoframe
<7> [327.590751] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [327.590846] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [327.590933] xe 0000:03:00.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [327.590934] xe 0000:03:00.0: colorspace: RGB
<7> [327.590935] xe 0000:03:00.0: scan mode: Underscan
<7> [327.590936] xe 0000:03:00.0: colorimetry: No Data
<7> [327.590937] xe 0000:03:00.0: picture aspect: No Data
<7> [327.590938] xe 0000:03:00.0: active aspect: Same as Picture
<7> [327.590940] xe 0000:03:00.0: itc: No Data
<7> [327.590941] xe 0000:03:00.0: extended colorimetry: xvYCC 601
<7> [327.590941] xe 0000:03:00.0: quantization range: Full
<7> [327.590942] xe 0000:03:00.0: nups: Unknown Non-uniform Scaling
<7> [327.590943] xe 0000:03:00.0: video code: 0
<7> [327.590944] xe 0000:03:00.0: ycc quantization range: Full
<7> [327.590945] xe 0000:03:00.0: hdmi content type: Graphics
<7> [327.590946] xe 0000:03:00.0: pixel repeat: 0
<7> [327.590947] xe 0000:03:00.0: bar top 0, bottom 0, left 0, right 0
<7> [327.590949] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in spd infoframe
<7> [327.591036] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [327.591123] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [327.591207] xe 0000:03:00.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [327.591208] xe 0000:03:00.0: vendor: Intel
<7> [327.591209] xe 0000:03:00.0: product: Discrete gfx
<7> [327.591210] xe 0000:03:00.0: source device information: PC General (0x9)
<7> [327.591211] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hdmi infoframe
<7> [327.591299] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [327.591386] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [327.591472] xe 0000:03:00.0: HDMI infoframe: Vendor, version 1, length 4
<7> [327.591474] xe 0000:03:00.0: empty frame
<7> [327.591475] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:505:pipe D] fastset requirement not met, forcing full modeset
<7> [327.591581] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0xf), mbus joined? no->no
<7> [327.591662] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:505:pipe D] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x8
<7> [327.591763] xe 0000:03:00.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:389:plane 1D] ddb ( 0 - 0) -> ( 0 - 1989), size 0 -> 1989
<7> [327.591844] xe 0000:03:00.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:499:cursor D] ddb ( 0 - 0) -> (1989 - 2048), size 0 -> 59
<7> [327.591918] xe 0000:03:00.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:389:plane 1D] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm
<7> [327.591991] xe 0000:03:00.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:389:plane 1D] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 4, 4, 9, 9, 17, 17, 17, 17, 0, 15, 0
<7> [327.592063] xe 0000:03:00.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:389:plane 1D] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 62, 62, 139, 139, 261, 261, 261, 261, 137, 230, 244
<7> [327.592136] xe 0000:03:00.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:389:plane 1D] 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> [327.592218] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] [CRTC:505:pipe D] data rate 594000 num active planes 1
<7> [327.592318] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 33600 required 624 qgv_peak_bw: 48000
<7> [327.592413] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 53000 required 624 qgv_peak_bw: 48000
<7> [327.592507] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 48000 for required data rate: 624
<7> [327.592609] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:505:pipe D] min cdclk: 0 kHz -> 192000 kHz
<7> [327.592730] xe 0000:03:00.0: [drm:intel_find_dpll [xe]] [CRTC:505:pipe D] allocated TC PLL 3
<7> [327.592832] xe 0000:03:00.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:505:pipe D] reserving TC PLL 3
<7> [327.592925] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:505:pipe D] enable: yes [modeset]
<7> [327.593023] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [327.593119] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: D, pipe bpp: 36, dithering: 0
<7> [327.593213] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [327.593303] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [327.593395] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [327.593483] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [327.593573] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [327.593664] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] GCP: 0x6
<7> [327.593761] xe 0000:03:00.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [327.593763] xe 0000:03:00.0: colorspace: RGB
<7> [327.593764] xe 0000:03:00.0: scan mode: Underscan
<7> [327.593765] xe 0000:03:00.0: colorimetry: No Data
<7> [327.593767] xe 0000:03:00.0: picture aspect: No Data
<7> [327.593768] xe 0000:03:00.0: active aspect: Same as Picture
<7> [327.593769] xe 0000:03:00.0: itc: No Data
<7> [327.593770] xe 0000:03:00.0: extended colorimetry: xvYCC 601
<7> [327.593772] xe 0000:03:00.0: quantization range: Full
<7> [327.593773] xe 0000:03:00.0: nups: Unknown Non-uniform Scaling
<7> [327.593774] xe 0000:03:00.0: video code: 0
<7> [327.593775] xe 0000:03:00.0: ycc quantization range: Full
<7> [327.593776] xe 0000:03:00.0: hdmi content type: Graphics
<7> [327.593778] xe 0000:03:00.0: pixel repeat: 0
<7> [327.593779] xe 0000:03:00.0: bar top 0, bottom 0, left 0, right 0
<7> [327.593780] xe 0000:03:00.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [327.593782] xe 0000:03:00.0: vendor: Intel
<7> [327.593783] xe 0000:03:00.0: product: Discrete gfx
<7> [327.593785] xe 0000:03:00.0: source device information: PC General (0x9)
<7> [327.593786] xe 0000:03:00.0: HDMI infoframe: Vendor, version 1, length 4
<7> [327.593788] xe 0000:03:00.0: empty frame
<7> [327.593789] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 10 00 08 00 6d 10 00 01 00 00 00 00 00 00 00 00
<7> [327.593882] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 24 96 11 ed 41 4f 43 32 38 45 38 35 30 2e 48 44
<7> [327.593971] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 52 09 07 07
<7> [327.594061] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [327.594150] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [327.594241] 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> [327.594329] 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> [327.594416] 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> [327.594504] 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> [327.594591] 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> [327.594680] 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> [327.594780] 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> [327.594869] 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> [327.594959] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port clock: 222750, pipe src: 1920x1080+0+0, pixel rate 148500, min cdclk 192000
<7> [327.595047] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] linetime: 119, ips linetime: 0
<7> [327.595135] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [327.595225] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [327.595311] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [327.595396] 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> [327.595485] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] c20pll_hw_state:
<7> [327.595573] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] tx[0] = 0xbe88, tx[1] = 0x5800, tx[2] = 0x0000
<7> [327.595662] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cmn[0] = 0x0500, cmn[1] = 0x0005, cmn[2] = 0x0000, cmn[3] = 0x0000
<7> [327.595757] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[0] = 0x40e8
<7> [327.595851] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[1] = 0x7d20
<7> [327.595940] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[2] = 0x0a06
<7> [327.596028] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[3] = 0x8f1c
<7> [327.596115] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[4] = 0x0000
<7> [327.596202] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[5] = 0x0000
<7> [327.596288] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[6] = 0x2200
<7> [327.596374] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[7] = 0xffff
<7> [327.596462] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[8] = 0x0400
<7> [327.596554] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[9] = 0x0000
<7> [327.596641] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[10] = 0x0001
<7> [327.596736] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vdr: custom width: 0x00, serdes rate: 0x00, hdmi rate: 0x00
<7> [327.596835] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [327.596926] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [327.597015] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [327.597104] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [327.597192] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [327.597277] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [327.597366] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [327.597452] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [327.597538] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [327.597625] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [327.597716] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [327.597810] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [327.597895] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [327.597979] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:389:plane 1D] fb: [FB:559] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [327.598068] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [327.598154] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [327.598240] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:419:plane 2D] fb: [NOFB], visible: no
<7> [327.598326] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:449:plane 3D] fb: [NOFB], visible: no
<7> [327.598412] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:479:plane 4D] fb: [NOFB], visible: no
<7> [327.598500] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:489:plane 5D] fb: [NOFB], visible: no
<7> [327.598587] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:499:cursor D] fb: [NOFB], visible: no
<7> [327.599189] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling PW_2
<7> [327.599324] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling PW_D
<7> [327.599660] xe 0000:03:00.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating enabled for pipe_mask=0x8 (CLKGATE_DIS_DSSDSC=0x2a000000)
<7> [327.599775] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:507:DDI TC1/PHY F]
<7> [327.599866] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:509:DP-MST A]
<7> [327.599950] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:510:DP-MST B]
<7> [327.600035] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:511:DP-MST C]
<7> [327.600116] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:512:DP-MST D]
<7> [327.600198] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:526:DDI TC2/PHY G]
<7> [327.600279] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:528:DP-MST A]
<7> [327.600360] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:529:DP-MST B]
<7> [327.600438] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:530:DP-MST C]
<7> [327.600515] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:531:DP-MST D]
<7> [327.600590] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:538:DDI TC3/PHY H]
<7> [327.600667] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:542:DDI TC4/PHY I]
<7> [327.600775] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:544:DP-MST A]
<7> [327.600857] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:545:DP-MST B]
<7> [327.600934] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:546:DP-MST C]
<7> [327.601010] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:547:DP-MST D]
<7> [327.601298] xe 0000:03:00.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0x3
<7> [327.601462] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 2 (mbus joined: no)
<7> [327.601769] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enable TC PLL 3 (active 0x8, on? 0) for [CRTC:505:pipe D]
<7> [327.601865] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enabling TC PLL 3
<7> [327.604342] xe 0000:03:00.0: [drm:intel_enable_transcoder [xe]] enabling pipe D
<7> [327.605518] xe 0000:03:00.0: [drm:intel_hdmi_handle_sink_scrambling [xe]] [CONNECTOR:539:HDMI-A-3] scrambling=no, TMDS bit clock ratio=1/10
<7> [327.605852] xe 0000:03:00.0: [drm:do_gmbus_xfer [xe]] GMBUS [i915 gmbus tc3] NAK for addr: 0054 w(1)
<7> [327.605939] xe 0000:03:00.0: [drm:do_gmbus_xfer [xe]] GMBUS [i915 gmbus tc3] NAK on first message, retry
<7> [327.606260] xe 0000:03:00.0: [drm:do_gmbus_xfer [xe]] GMBUS [i915 gmbus tc3] NAK for addr: 0054 w(1)
<7> [327.606344] xe 0000:03:00.0: [drm:drm_scdc_set_high_tmds_clock_ratio [drm_display_helper]] [CONNECTOR:539:HDMI-A-3] Failed to read TMDS config: -6
<7> [327.606355] xe 0000:03:00.0: [drm:intel_ddi_enable [xe]] [CONNECTOR:539:HDMI-A-3] Failed to configure sink scrambling/TMDS bit clock ratio
<7> [327.623946] 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:505:pipe D], 36 bytes ELD
<7> [327.640873] xe 0000:03:00.0: [drm:audio_config_hdmi_pixel_clock [xe]] Configuring HDMI audio for pixel clock 148500 (0x00090000)
<7> [327.641163] xe 0000:03:00.0: [drm:hsw_audio_config_update [xe]] using automatic N
<7> [327.641802] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:539:HDMI-A-3]
<7> [327.642293] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:505:pipe D]
<7> [327.646569] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:269:pipe B]
<7> [327.646619] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CONNECTOR:527:DP-2]
<7> [327.646802] xe 0000:03:00.0: [drm:intel_dp_compute_config_limits [xe]] [ENCODER:526:DDI TC2/PHY G][CRTC:269:pipe B] 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> [327.647105] 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> [327.647390] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:269:pipe B] hw max bpp: 36, pipe bpp: 36, dithering: 0
<7> [327.647678] xe 0000:03:00.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:526:DDI TC2/PHY G] [CRTC:269:pipe B]
<7> [327.647990] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.enable (expected no, found yes)
<7> [327.648253] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.active (expected no, found yes)
<7> [327.648507] 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> [327.648759] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in lane_count (expected 0, found 4)
<7> [327.648997] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] 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> [327.649227] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in output_types (expected 0x00000000, found 0x00000080)
<7> [327.649447] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [327.649663] 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 0, found 1920)
<7> [327.649894] 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 0, found 2200)
<7> [327.649984] xe 0000:03:00.0: [drm:intel_audio_component_get_power [xe]] restored AUD_FREQ_CNTRL to 0x8010
<7> [327.650100] 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 0, found 1920)
<7> [327.650298] 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 0, found 2200)
<7> [327.650494] 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 0, found 2008)
<7> [327.650686] 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 0, found 2052)
<7> [327.650888] 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 0, found 1080)
<7> [327.651072] 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 0, found 1080)
<7> [327.651251] 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 0, found 1084)
<7> [327.651425] 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 0, found 1089)
<7> [327.651598] 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 0, found 1125)
<7> [327.651784] 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 0, found 1125)
<7> [327.651951] 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 0, found 1920)
<7> [327.652111] 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 0, found 2200)
<7> [327.652272] 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 0, found 1920)
<7> [327.652433] 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 0, found 2200)
<7> [327.652590] 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 0, found 2008)
<7> [327.652744] 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 0, found 2052)
<7> [327.652893] 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 0, found 1080)
<7> [327.653041] 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 0, found 1080)
<7> [327.653180] 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 0, found 1084)
<7> [327.653321] 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 0, found 1089)
<7> [327.653459] 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 0, found 1125)
<7> [327.653594] 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 0, found 1125)
<7> [327.653735] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [327.653865] 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 0, found 1)
<7> [327.653994] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [327.654117] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in enhanced_framing (expected no, found yes)
<7> [327.654241] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in pipe_bpp (expected 0, found 36)
<7> [327.654360] 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 0, found 148500)
<7> [327.654477] 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 0, found 148500)
<7> [327.654595] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe B] fastset requirement not met in port_clock (expected 0, found 270000)
<7> [327.654717] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:269:pipe B] fastset requirement not met, forcing full modeset
<7> [327.654855] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0x3 -> 0xf (total dbuf slices 0xf), mbus joined? no->no
<7> [327.654957] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:269:pipe B] dbuf slices 0x0 -> 0xc, ddb (0 - 0) -> (0 - 2048), active pipes 0x8 -> 0xa
<7> [327.655079] xe 0000:03:00.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:153:plane 1B] ddb ( 0 - 0) -> ( 0 - 1989), size 0 -> 1989
<7> [327.655173] xe 0000:03:00.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:263:cursor B] ddb ( 0 - 0) -> (1989 - 2048), size 0 -> 59
<7> [327.655258] xe 0000:03:00.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:153:plane 1B] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm
<7> [327.655341] xe 0000:03:00.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:153:plane 1B] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 4, 4, 9, 9, 17, 17, 17, 17, 0, 15, 0
<7> [327.655425] xe 0000:03:00.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:153:plane 1B] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 62, 62, 139, 139, 261, 261, 261, 261, 137, 230, 244
<7> [327.655509] xe 0000:03:00.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:153:plane 1B] 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> [327.655604] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] [CRTC:269:pipe B] data rate 594000 num active planes 1
<7> [327.655748] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 33600 required 1248 qgv_peak_bw: 48000
<7> [327.655865] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 53000 required 1248 qgv_peak_bw: 48000
<7> [327.655972] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 48000 for required data rate: 1248
<7> [327.656090] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:269:pipe B] min cdclk: 0 kHz -> 192000 kHz
<7> [327.656206] xe 0000:03:00.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [xe]] dbuf bandwidth min cdclk: 9282 kHz -> 18563 kHz
<7> [327.656321] xe 0000:03:00.0: [drm:intel_find_dpll [xe]] [CRTC:269:pipe B] allocated TC PLL 2
<7> [327.656424] xe 0000:03:00.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:269:pipe B] reserving TC PLL 2
<7> [327.656525] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:269:pipe B] enable: yes [modeset]
<7> [327.656634] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: DP (0x80), output format: RGB, sink format: RGB
<7> [327.656748] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: B, pipe bpp: 36, dithering: 0
<7> [327.656851] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [327.656954] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [327.657054] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [327.657150] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [327.657249] 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> [327.657346] 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> [327.657444] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: enabled
<7> [327.657543] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled
<7> [327.657641] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] psr: disabled, selective update: disabled, panel replay: disabled, selective fetch: disabled
<7> [327.657747] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] minimum HBlank: 0
<7> [327.657845] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] audio: 1, infoframes: 0, infoframes enabled: 0x0
<7> [327.657947] 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> [327.658047] 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> [327.658144] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 17 50 51 07 00 00 00 00
<7> [327.658241] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [327.658337] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [327.658433] 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> [327.658531] 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> [327.658629] 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> [327.658734] 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> [327.658833] 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> [327.658931] 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> [327.659028] 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> [327.659124] 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> [327.659219] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port clock: 270000, pipe src: 1920x1080+0+0, pixel rate 148500, min cdclk 192000
<7> [327.659318] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] linetime: 119, ips linetime: 0
<7> [327.659413] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [327.659506] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [327.659605] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [327.659707] 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> [327.659805] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] c20pll_hw_state:
<7> [327.659901] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] tx[0] = 0xbe88, tx[1] = 0x4800, tx[2] = 0x0000
<7> [327.659998] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cmn[0] = 0x0500, cmn[1] = 0x0005, cmn[2] = 0x0000, cmn[3] = 0x0000
<7> [327.660096] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[0] = 0x308c
<7> [327.660192] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[1] = 0x2110
<7> [327.660287] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[2] = 0xcc9c
<7> [327.660380] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[3] = 0xbfc1
<7> [327.660473] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[4] = 0x4b9a
<7> [327.660570] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[5] = 0x3f81
<7> [327.660663] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[6] = 0x2000
<7> [327.660768] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[7] = 0x0001
<7> [327.660865] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[8] = 0x5000
<7> [327.660961] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[9] = 0x0000
<7> [327.661058] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[10] = 0x0000
<7> [327.661153] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vdr: custom width: 0x00, serdes rate: 0x42, hdmi rate: 0x00
<7> [327.661250] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [327.661347] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [327.661447] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [327.661545] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [327.661644] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [327.661751] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [327.661849] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [327.661945] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [327.662040] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [327.662135] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [327.662229] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [327.662325] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [327.662422] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [327.662519] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:153:plane 1B] fb: [FB:559] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [327.662616] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [327.662716] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [327.662814] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:183:plane 2B] fb: [NOFB], visible: no
<7> [327.662910] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:213:plane 3B] fb: [NOFB], visible: no
<7> [327.663007] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:243:plane 4B] fb: [NOFB], visible: no
<7> [327.663103] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:253:plane 5B] fb: [NOFB], visible: no
<7> [327.663199] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:263:cursor B] fb: [NOFB], visible: no
<7> [327.663810] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling PW_B
<7> [327.663963] xe 0000:03:00.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating enabled for pipe_mask=0x2 (CLKGATE_DIS_DSSDSC=0x22000000)
<7> [327.664286] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:507:DDI TC1/PHY F]
<7> [327.664382] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:509:DP-MST A]
<7> [327.664474] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:510:DP-MST B]
<7> [327.664564] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:511:DP-MST C]
<7> [327.664654] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:512:DP-MST D]
<7> [327.664753] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:526:DDI TC2/PHY G]
<7> [327.664843] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:528:DP-MST A]
<7> [327.664932] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:529:DP-MST B]
<7> [327.665021] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:530:DP-MST C]
<7> [327.665110] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:531:DP-MST D]
<7> [327.665197] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:538:DDI TC3/PHY H]
<7> [327.665284] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:542:DDI TC4/PHY I]
<7> [327.665371] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:544:DP-MST A]
<7> [327.665457] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:545:DP-MST B]
<7> [327.665546] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:546:DP-MST C]
<7> [327.665633] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:547:DP-MST D]
<7> [327.667438] xe 0000:03:00.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xf
<7> [327.667816] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 2 (mbus joined: no)
<7> [327.667927] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enable TC PLL 2 (active 0x2, on? 0) for [CRTC:269:pipe B]
<7> [327.668027] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enabling TC PLL 2
<7> [327.670638] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling AUX_TC2
<7> [327.674375] 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> [327.678887] 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> [327.679892] 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> [327.680324] 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> [327.681817] 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> [327.682369] 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> [327.689515] 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> [327.689743] 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> [327.697607] 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> [327.698026] 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> [327.699186] xe 0000:03:00.0: [drm:intel_enable_transcoder [xe]] enabling pipe B
<7> [327.717095] 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:269:pipe B], 40 bytes ELD
<7> [327.733160] xe 0000:03:00.0: [drm:intel_audio_component_get_power [xe]] restored AUD_FREQ_CNTRL to 0x8010
<7> [327.733734] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:527:DP-2]
<7> [327.734577] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:269:pipe B]
<7> [327.805756] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling AUX_TC2
<7> [329.725774] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling AUX_TC2
<7> [329.828819] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling AUX_TC2
<6> [334.167887] [IGT] kms_flip: finished subtest BD-DP2-HDMI-A3, FAIL
Created at 2026-03-24 01:56:55