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

Result: Fail

i915_display_info10 igt_runner10 results10.json results10-xe-load.json guc_logs10.tar i915_display_info_post_exec10 boot10 dmesg10

DetailValue
Duration 2.52 seconds
Hostname 
shard-bmg-2
Igt-Version 
IGT-Version: 2.4-ge70db143b (x86_64) (Linux: 7.0.0-lgci-xe-xe-pw-165378v1-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 1084 1089 1125 0x48 0x5 
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../tests/kms_flip.c:926 run_test_on_crtc_set()
  #2 ../tests/kms_flip.c:2029 run_pair()
  #3 ../tests/kms_flip.c:2227 __igt_unique____real_main2105()
  #4 ../tests/kms_flip.c:2105 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest BD-DP2-HDMI-A3: FAIL (2.518s)
Err 
Starting dynamic subtest: BD-DP2-HDMI-A3
(kms_flip:4443) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931:
(kms_flip:4443) CRITICAL: Failed assertion: end - start < 500
(kms_flip:4443) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest BD-DP2-HDMI-A3 failed.
**** DEBUG ****
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 122.922279
last_received_ts = 122.921913
last_seq = 3169
current_ts = 122.938950
current_received_ts = 122.938576
current_seq = 3170
count = 4
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 122.938950
last_received_ts = 122.938576
last_seq = 3170
current_ts = 122.955620
current_received_ts = 122.955246
current_seq = 3171
count = 5
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 122.955620
last_received_ts = 122.955246
last_seq = 3171
current_ts = 122.972282
current_received_ts = 122.971909
current_seq = 3172
count = 6
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 27us
(kms_flip:4443) DEBUG: name = flip
last_ts = 122.972282
last_received_ts = 122.971909
last_seq = 3172
current_ts = 122.988953
current_received_ts = 122.988586
current_seq = 3173
count = 7
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 27us
(kms_flip:4443) DEBUG: name = flip
last_ts = 122.988953
last_received_ts = 122.988586
last_seq = 3173
current_ts = 123.005615
current_received_ts = 123.005249
current_seq = 3174
count = 8
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 27us
(kms_flip:4443) DEBUG: name = flip
last_ts = 123.005615
last_received_ts = 123.005249
last_seq = 3174
current_ts = 123.022285
current_received_ts = 123.021919
current_seq = 3175
count = 9
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 123.022285
last_received_ts = 123.021919
last_seq = 3175
current_ts = 123.038956
current_received_ts = 123.038582
current_seq = 3176
count = 10
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 123.038956
last_received_ts = 123.038582
last_seq = 3176
current_ts = 123.055626
current_received_ts = 123.055252
current_seq = 3177
count = 11
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 25us
(kms_flip:4443) DEBUG: name = flip
last_ts = 123.055626
last_received_ts = 123.055252
last_seq = 3177
current_ts = 123.072289
current_received_ts = 123.071922
current_seq = 3178
count = 12
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 123.072289
last_received_ts = 123.071922
last_seq = 3178
current_ts = 123.088959
current_received_ts = 123.088593
current_seq = 3179
count = 13
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 123.088959
last_received_ts = 123.088593
last_seq = 3179
current_ts = 123.105629
current_received_ts = 123.105255
current_seq = 3180
count = 14
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 123.105629
last_received_ts = 123.105255
last_seq = 3180
current_ts = 123.122299
current_received_ts = 123.121925
current_seq = 3181
count = 15
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 27us
(kms_flip:4443) DEBUG: name = flip
last_ts = 123.122299
last_received_ts = 123.121925
last_seq = 3181
current_ts = 123.138962
current_received_ts = 123.138588
current_seq = 3182
count = 16
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 27us
(kms_flip:4443) DEBUG: name = flip
last_ts = 123.138962
last_received_ts = 123.138588
last_seq = 3182
current_ts = 123.155632
current_received_ts = 123.155258
current_seq = 3183
count = 17
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 123.155632
last_received_ts = 123.155258
last_seq = 3183
current_ts = 123.172302
current_received_ts = 123.171791
current_seq = 3184
count = 18
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 30us
(kms_flip:4443) DEBUG: name = flip
last_ts = 123.172302
last_received_ts = 123.171791
last_seq = 3184
current_ts = 123.188965
current_received_ts = 123.188553
current_seq = 3185
count = 19
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 27us
(kms_flip:4443) DEBUG: name = flip
last_ts = 123.188965
last_received_ts = 123.188553
last_seq = 3185
current_ts = 123.205627
current_received_ts = 123.205261
current_seq = 3186
count = 20
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 27us
(kms_flip:4443) DEBUG: name = flip
last_ts = 123.205627
last_received_ts = 123.205261
last_seq = 3186
current_ts = 123.222298
current_received_ts = 123.221924
current_seq = 3187
count = 21
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 123.222298
last_received_ts = 123.221924
last_seq = 3187
current_ts = 123.238968
current_received_ts = 123.238594
current_seq = 3188
count = 22
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 123.238968
last_received_ts = 123.238594
last_seq = 3188
current_ts = 123.255638
current_received_ts = 123.255264
current_seq = 3189
count = 23
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 123.255638
last_received_ts = 123.255264
last_seq = 3189
current_ts = 123.272301
current_received_ts = 123.271935
current_seq = 3190
count = 24
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 123.272301
last_received_ts = 123.271935
last_seq = 3190
current_ts = 123.288971
current_received_ts = 123.288597
current_seq = 3191
count = 25
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 27us
(kms_flip:4443) DEBUG: name = flip
last_ts = 123.288971
last_received_ts = 123.288597
last_seq = 3191
current_ts = 123.305634
current_received_ts = 123.305267
current_seq = 3192
count = 26
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 123.305634
last_received_ts = 123.305267
last_seq = 3192
current_ts = 123.322304
current_received_ts = 123.321938
current_seq = 3193
count = 27
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 123.322304
last_received_ts = 123.321938
last_seq = 3193
current_ts = 123.338974
current_received_ts = 123.338600
current_seq = 3194
count = 28
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 123.338974
last_received_ts = 123.338600
last_seq = 3194
current_ts = 123.355652
current_received_ts = 123.355263
current_seq = 3195
count = 29
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 123.355652
last_received_ts = 123.355263
last_seq = 3195
current_ts = 123.372314
current_received_ts = 123.371941
current_seq = 3196
count = 30
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 123.372314
last_received_ts = 123.371941
last_seq = 3196
current_ts = 123.388969
current_received_ts = 123.388603
current_seq = 3197
count = 31
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 123.388969
last_received_ts = 123.388603
last_seq = 3197
current_ts = 123.405647
current_received_ts = 123.405273
current_seq = 3198
count = 32
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 123.405647
last_received_ts = 123.405273
last_seq = 3198
current_ts = 123.422325
current_received_ts = 123.421936
current_seq = 3199
count = 33
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 123.422325
last_received_ts = 123.421936
last_seq = 3199
current_ts = 123.438980
current_received_ts = 123.438606
current_seq = 3200
count = 34
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 123.438980
last_received_ts = 123.438606
last_seq = 3200
current_ts = 123.455650
current_received_ts = 123.455231
current_seq = 3201
count = 35
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 27us
(kms_flip:4443) DEBUG: name = flip
last_ts = 123.455650
last_received_ts = 123.455231
last_seq = 3201
current_ts = 123.472313
current_received_ts = 123.471947
current_seq = 3202
count = 36
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 123.472313
last_received_ts = 123.471947
last_seq = 3202
current_ts = 123.488976
current_received_ts = 123.488609
current_seq = 3203
count = 37
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 123.488976
last_received_ts = 123.488609
last_seq = 3203
current_ts = 123.505646
current_received_ts = 123.505280
current_seq = 3204
count = 38
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 123.505646
last_received_ts = 123.505280
last_seq = 3204
current_ts = 123.522316
current_received_ts = 123.521950
current_seq = 3205
count = 39
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 123.522316
last_received_ts = 123.521950
last_seq = 3205
current_ts = 123.538979
current_received_ts = 123.538612
current_seq = 3206
count = 40
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 123.538979
last_received_ts = 123.538612
last_seq = 3206
current_ts = 123.555656
current_received_ts = 123.555283
current_seq = 3207
count = 41
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 123.555656
last_received_ts = 123.555283
last_seq = 3207
current_ts = 123.572334
current_received_ts = 123.571945
current_seq = 3208
count = 42
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 123.572334
last_received_ts = 123.571945
last_seq = 3208
current_ts = 123.588997
current_received_ts = 123.588615
current_seq = 3209
count = 43
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 27us
(kms_flip:4443) DEBUG: name = flip
last_ts = 123.588997
last_received_ts = 123.588615
last_seq = 3209
current_ts = 123.605652
current_received_ts = 123.605286
current_seq = 3210
count = 44
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 25us
(kms_flip:4443) DEBUG: name = flip
last_ts = 123.605652
last_received_ts = 123.605286
last_seq = 3210
current_ts = 123.622330
current_received_ts = 123.621918
current_seq = 3211
count = 45
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 27us
(kms_flip:4443) DEBUG: name = flip
last_ts = 123.622330
last_received_ts = 123.621918
last_seq = 3211
current_ts = 123.638992
current_received_ts = 123.638580
current_seq = 3212
count = 46
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 123.638992
last_received_ts = 123.638580
last_seq = 3212
current_ts = 123.655663
current_received_ts = 123.655289
current_seq = 3213
count = 47
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 25us
(kms_flip:4443) DEBUG: name = flip
last_ts = 123.655663
last_received_ts = 123.655289
last_seq = 3213
current_ts = 123.672325
current_received_ts = 123.671951
current_seq = 3214
count = 48
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 25us
(kms_flip:4443) DEBUG: name = flip
last_ts = 123.672325
last_received_ts = 123.671951
last_seq = 3214
current_ts = 123.688995
current_received_ts = 123.688622
current_seq = 3215
count = 49
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 45us
(kms_flip:4443) DEBUG: name = flip
last_ts = 123.688995
last_received_ts = 123.688622
last_seq = 3215
current_ts = 123.705666
current_received_ts = 123.705292
current_seq = 3216
count = 50
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 25us
(kms_flip:4443) DEBUG: name = flip
last_ts = 123.705666
last_received_ts = 123.705292
last_seq = 3216
current_ts = 123.722328
current_received_ts = 123.721962
current_seq = 3217
count = 51
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 123.722328
last_received_ts = 123.721962
last_seq = 3217
current_ts = 123.738991
current_received_ts = 123.738625
current_seq = 3218
count = 52
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 25us
(kms_flip:4443) DEBUG: name = flip
last_ts = 123.738991
last_received_ts = 123.738625
last_seq = 3218
current_ts = 123.755661
current_received_ts = 123.755295
current_seq = 3219
count = 53
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 123.755661
last_received_ts = 123.755295
last_seq = 3219
current_ts = 123.772331
current_received_ts = 123.771957
current_seq = 3220
count = 54
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 123.772331
last_received_ts = 123.771957
last_seq = 3220
current_ts = 123.789001
current_received_ts = 123.788628
current_seq = 3221
count = 55
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 123.789001
last_received_ts = 123.788628
last_seq = 3221
current_ts = 123.805664
current_received_ts = 123.805290
current_seq = 3222
count = 56
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 27us
(kms_flip:4443) DEBUG: name = flip
last_ts = 123.805664
last_received_ts = 123.805290
last_seq = 3222
current_ts = 123.822334
current_received_ts = 123.821968
current_seq = 3223
count = 57
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 123.822334
last_received_ts = 123.821968
last_seq = 3223
current_ts = 123.838997
current_received_ts = 123.838631
current_seq = 3224
count = 58
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 123.838997
last_received_ts = 123.838631
last_seq = 3224
current_ts = 123.855675
current_received_ts = 123.855301
current_seq = 3225
count = 59
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 123.855675
last_received_ts = 123.855301
last_seq = 3225
current_ts = 123.872337
current_received_ts = 123.871971
current_seq = 3226
count = 60
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 28us
(kms_flip:4443) DEBUG: name = flip
last_ts = 123.872337
last_received_ts = 123.871971
last_seq = 3226
current_ts = 123.889008
current_received_ts = 123.888634
current_seq = 3227
count = 61
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 123.889008
last_received_ts = 123.888634
last_seq = 3227
current_ts = 123.905670
current_received_ts = 123.905296
current_seq = 3228
count = 62
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 123.905670
last_received_ts = 123.905296
last_seq = 3228
current_ts = 123.922340
current_received_ts = 123.921967
current_seq = 3229
count = 63
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 123.922340
last_received_ts = 123.921967
last_seq = 3229
current_ts = 123.939011
current_received_ts = 123.938637
current_seq = 3230
count = 64
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 123.939011
last_received_ts = 123.938637
last_seq = 3230
current_ts = 123.955673
current_received_ts = 123.955307
current_seq = 3231
count = 65
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 123.955673
last_received_ts = 123.955307
last_seq = 3231
current_ts = 123.972343
current_received_ts = 123.971977
current_seq = 3232
count = 66
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 123.972343
last_received_ts = 123.971977
last_seq = 3232
current_ts = 123.989014
current_received_ts = 123.988640
current_seq = 3233
count = 67
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 123.989014
last_received_ts = 123.988640
last_seq = 3233
current_ts = 124.005684
current_received_ts = 124.005264
current_seq = 3234
count = 68
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 124.005684
last_received_ts = 124.005264
last_seq = 3234
current_ts = 124.022346
current_received_ts = 124.021973
current_seq = 3235
count = 69
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 124.022346
last_received_ts = 124.021973
last_seq = 3235
current_ts = 124.039017
current_received_ts = 124.038643
current_seq = 3236
count = 70
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 124.039017
last_received_ts = 124.038643
last_seq = 3236
current_ts = 124.055679
current_received_ts = 124.055313
current_seq = 3237
count = 71
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 124.055679
last_received_ts = 124.055313
last_seq = 3237
current_ts = 124.072350
current_received_ts = 124.071976
current_seq = 3238
count = 72
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 124.072350
last_received_ts = 124.071976
last_seq = 3238
current_ts = 124.089020
current_received_ts = 124.088646
current_seq = 3239
count = 73
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 27us
(kms_flip:4443) DEBUG: name = flip
last_ts = 124.089020
last_received_ts = 124.088646
last_seq = 3239
current_ts = 124.105682
current_received_ts = 124.105316
current_seq = 3240
count = 74
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 124.105682
last_received_ts = 124.105316
last_seq = 3240
current_ts = 124.122353
current_received_ts = 124.121979
current_seq = 3241
count = 75
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 124.122353
last_received_ts = 124.121979
last_seq = 3241
current_ts = 124.139023
current_received_ts = 124.138649
current_seq = 3242
count = 76
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 124.139023
last_received_ts = 124.138649
last_seq = 3242
current_ts = 124.155693
current_received_ts = 124.155319
current_seq = 3243
count = 77
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 124.155693
last_received_ts = 124.155319
last_seq = 3243
current_ts = 124.172356
current_received_ts = 124.171982
current_seq = 3244
count = 78
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 124.172356
last_received_ts = 124.171982
last_seq = 3244
current_ts = 124.189026
current_received_ts = 124.188431
current_seq = 3245
count = 79
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 124.189026
last_received_ts = 124.188431
last_seq = 3245
current_ts = 124.205688
current_received_ts = 124.205322
current_seq = 3246
count = 80
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 124.205688
last_received_ts = 124.205322
last_seq = 3246
current_ts = 124.222359
current_received_ts = 124.221985
current_seq = 3247
count = 81
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 124.222359
last_received_ts = 124.221985
last_seq = 3247
current_ts = 124.239029
current_received_ts = 124.238663
current_seq = 3248
count = 82
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 27us
(kms_flip:4443) DEBUG: name = flip
last_ts = 124.239029
last_received_ts = 124.238663
last_seq = 3248
current_ts = 124.255692
current_received_ts = 124.255325
current_seq = 3249
count = 83
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 124.255692
last_received_ts = 124.255325
last_seq = 3249
current_ts = 124.272362
current_received_ts = 124.271988
current_seq = 3250
count = 84
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 27us
(kms_flip:4443) DEBUG: name = flip
last_ts = 124.272362
last_received_ts = 124.271988
last_seq = 3250
current_ts = 124.289032
current_received_ts = 124.288658
current_seq = 3251
count = 85
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 124.289032
last_received_ts = 124.288658
last_seq = 3251
current_ts = 124.305695
current_received_ts = 124.305328
current_seq = 3252
count = 86
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 124.305695
last_received_ts = 124.305328
last_seq = 3252
current_ts = 124.322365
current_received_ts = 124.321999
current_seq = 3253
count = 87
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 124.322365
last_received_ts = 124.321999
last_seq = 3253
current_ts = 124.339035
current_received_ts = 124.338661
current_seq = 3254
count = 88
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 124.339035
last_received_ts = 124.338661
last_seq = 3254
current_ts = 124.355698
current_received_ts = 124.355331
current_seq = 3255
count = 89
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 124.355698
last_received_ts = 124.355331
last_seq = 3255
current_ts = 124.372368
current_received_ts = 124.371994
current_seq = 3256
count = 90
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 124.372368
last_received_ts = 124.371994
last_seq = 3256
current_ts = 124.389038
current_received_ts = 124.388664
current_seq = 3257
count = 91
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 27us
(kms_flip:4443) DEBUG: name = flip
last_ts = 124.389038
last_received_ts = 124.388664
last_seq = 3257
current_ts = 124.405708
current_received_ts = 124.405334
current_seq = 3258
count = 92
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 124.405708
last_received_ts = 124.405334
last_seq = 3258
current_ts = 124.422371
current_received_ts = 124.421997
current_seq = 3259
count = 93
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 27us
(kms_flip:4443) DEBUG: name = flip
last_ts = 124.422371
last_received_ts = 124.421997
last_seq = 3259
current_ts = 124.439034
current_received_ts = 124.438667
current_seq = 3260
count = 94
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 31us
(kms_flip:4443) DEBUG: name = flip
last_ts = 124.439034
last_received_ts = 124.438667
last_seq = 3260
current_ts = 124.455704
current_received_ts = 124.455338
current_seq = 3261
count = 95
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 27us
(kms_flip:4443) DEBUG: name = flip
last_ts = 124.455704
last_received_ts = 124.455338
last_seq = 3261
current_ts = 124.472374
current_received_ts = 124.472046
current_seq = 3262
count = 96
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 27us
(kms_flip:4443) DEBUG: name = flip
last_ts = 124.472374
last_received_ts = 124.472046
last_seq = 3262
current_ts = 124.489037
current_received_ts = 124.488670
current_seq = 3263
count = 97
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 124.489037
last_received_ts = 124.488670
last_seq = 3263
current_ts = 124.505707
current_received_ts = 124.505341
current_seq = 3264
count = 98
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 27us
(kms_flip:4443) DEBUG: name = flip
last_ts = 124.505707
last_received_ts = 124.505341
last_seq = 3264
current_ts = 124.522377
current_received_ts = 124.522049
current_seq = 3265
count = 99
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 124.522377
last_received_ts = 124.522049
last_seq = 3265
current_ts = 124.539040
current_received_ts = 124.538673
current_seq = 3266
count = 100
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 124.539040
last_received_ts = 124.538673
last_seq = 3266
current_ts = 124.555717
current_received_ts = 124.555344
current_seq = 3267
count = 101
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 27us
(kms_flip:4443) DEBUG: name = flip
last_ts = 124.555717
last_received_ts = 124.555344
last_seq = 3267
current_ts = 124.572380
current_received_ts = 124.572029
current_seq = 3268
count = 102
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 124.572380
last_received_ts = 124.572029
last_seq = 3268
current_ts = 124.589050
current_received_ts = 124.588631
current_seq = 3269
count = 103
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 27us
(kms_flip:4443) DEBUG: name = flip
last_ts = 124.589050
last_received_ts = 124.588631
last_seq = 3269
current_ts = 124.605713
current_received_ts = 124.605301
current_seq = 3270
count = 104
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 27us
(kms_flip:4443) DEBUG: name = flip
last_ts = 124.605713
last_received_ts = 124.605301
last_seq = 3270
current_ts = 124.622383
current_received_ts = 124.622047
current_seq = 3271
count = 105
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 124.622383
last_received_ts = 124.622047
last_seq = 3271
current_ts = 124.639046
current_received_ts = 124.638672
current_seq = 3272
count = 106
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 124.639046
last_received_ts = 124.638672
last_seq = 3272
current_ts = 124.655716
current_received_ts = 124.655350
current_seq = 3273
count = 107
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 124.655716
last_received_ts = 124.655350
last_seq = 3273
current_ts = 124.672386
current_received_ts = 124.672043
current_seq = 3274
count = 108
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 124.672386
last_received_ts = 124.672043
last_seq = 3274
current_ts = 124.689056
current_received_ts = 124.688683
current_seq = 3275
count = 109
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 27us
(kms_flip:4443) DEBUG: name = flip
last_ts = 124.689056
last_received_ts = 124.688683
last_seq = 3275
current_ts = 124.705727
current_received_ts = 124.705353
current_seq = 3276
count = 110
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 124.705727
last_received_ts = 124.705353
last_seq = 3276
current_ts = 124.722389
current_received_ts = 124.722038
current_seq = 3277
count = 111
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 124.722389
last_received_ts = 124.722038
last_seq = 3277
current_ts = 124.739059
current_received_ts = 124.738686
current_seq = 3278
count = 112
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 124.739059
last_received_ts = 124.738686
last_seq = 3278
current_ts = 124.755722
current_received_ts = 124.755356
current_seq = 3279
count = 113
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 124.755722
last_received_ts = 124.755356
last_seq = 3279
current_ts = 124.772392
current_received_ts = 124.772049
current_seq = 3280
count = 114
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 124.772392
last_received_ts = 124.772049
last_seq = 3280
current_ts = 124.789070
current_received_ts = 124.788681
current_seq = 3281
count = 115
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 27us
(kms_flip:4443) DEBUG: name = flip
last_ts = 124.789070
last_received_ts = 124.788681
last_seq = 3281
current_ts = 124.805725
current_received_ts = 124.805351
current_seq = 3282
count = 116
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 124.805725
last_received_ts = 124.805351
last_seq = 3282
current_ts = 124.822395
current_received_ts = 124.822067
current_seq = 3283
count = 117
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 30us
(kms_flip:4443) DEBUG: name = flip
last_ts = 124.822395
last_received_ts = 124.822067
last_seq = 3283
current_ts = 124.839066
current_received_ts = 124.838593
current_seq = 3284
count = 118
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 29us
(kms_flip:4443) DEBUG: name = flip
last_ts = 124.839066
last_received_ts = 124.838593
last_seq = 3284
current_ts = 124.855736
current_received_ts = 124.855316
current_seq = 3285
count = 119
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 26us
(kms_flip:4443) DEBUG: name = flip
last_ts = 124.855736
last_received_ts = 124.855316
last_seq = 3285
current_ts = 124.872398
current_received_ts = 124.872063
current_seq = 3286
count = 120
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 28us
(kms_flip:4443) DEBUG: name = flip
last_ts = 124.872398
last_received_ts = 124.872063
last_seq = 3286
current_ts = 124.889061
current_received_ts = 124.888695
current_seq = 3287
count = 121
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 27us
(kms_flip:4443) DEBUG: name = flip
last_ts = 124.889061
last_received_ts = 124.888695
last_seq = 3287
current_ts = 124.905731
current_received_ts = 124.905365
current_seq = 3288
count = 122
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 27us
(kms_flip:4443) DEBUG: name = flip
last_ts = 124.905731
last_received_ts = 124.905365
last_seq = 3288
current_ts = 124.922401
current_received_ts = 124.922050
current_seq = 3289
count = 123
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 27us
(kms_flip:4443) DEBUG: name = flip
last_ts = 124.922401
last_received_ts = 124.922050
last_seq = 3289
current_ts = 124.939072
current_received_ts = 124.938728
current_seq = 3290
count = 124
seq_step = 1
(kms_flip:4443) DEBUG: Vblank took 1241us
(kms_flip:4443) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931:
(kms_flip:4443) CRITICAL: Failed assertion: end - start < 500
(kms_flip:4443) CRITICAL: Last errno: 4, Interrupted system call
(kms_flip:4443) igt_core-INFO: Stack trace:
(kms_flip:4443) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:4443) igt_core-INFO:   #1 ../tests/kms_flip.c:926 run_test_on_crtc_set()
(kms_flip:4443) igt_core-INFO:   #2 ../tests/kms_flip.c:2029 run_pair()
(kms_flip:4443) igt_core-INFO:   #3 ../tests/kms_flip.c:2227 __igt_unique____real_main2105()
(kms_flip:4443) igt_core-INFO:   #4 ../tests/kms_flip.c:2105 main()
(kms_flip:4443) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:4443) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:4443) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest BD-DP2-HDMI-A3: FAIL (2.518s)
Dmesg
<6> [122.746107] [IGT] kms_flip: starting dynamic subtest BD-DP2-HDMI-A3
<7> [122.746989] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:556]
<7> [122.747383] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:560]
<7> [122.791446] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:151:pipe A]
<7> [122.791585] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:269:pipe C]
<7> [122.791647] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.enable (expected yes, found no)
<7> [122.791834] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.active (expected yes, found no)
<7> [122.791941] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in cpu_transcoder (expected 2, found -1)
<7> [122.792042] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in lane_count (expected 4, found 0)
<7> [122.792141] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] 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> [122.792239] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in output_types (expected 0x00000080, found 0x00000000)
<7> [122.792333] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [122.792429] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [122.792530] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [122.792627] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [122.792738] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [122.792835] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [122.792931] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [122.793026] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [122.793121] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [122.793215] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [122.793310] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [122.793405] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [122.793504] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [122.793605] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [122.793726] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [122.793824] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [122.793921] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [122.794016] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [122.794111] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [122.794207] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [122.794302] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [122.794395] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [122.794488] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [122.794583] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [122.794683] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [122.794779] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [122.794875] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [122.794970] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [122.795065] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in enhanced_framing (expected yes, found no)
<7> [122.795161] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in pipe_bpp (expected 36, found 0)
<7> [122.795257] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [122.795353] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [122.795449] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in port_clock (expected 270000, found 0)
<7> [122.795544] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:269:pipe C] fastset requirement not met, forcing full modeset
<7> [122.795640] xe 0000:03:00.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:269:pipe C] releasing TC PLL 2
<7> [122.795778] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xd -> 0xf (total dbuf slices 0xf), mbus joined? no->yes
<7> [122.795872] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:387:pipe B] dbuf slices 0xc -> 0xf, ddb (0 - 1024) -> (0 - 4096), active pipes 0x6 -> 0x2
<7> [122.795956] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:269:pipe C] dbuf slices 0xc -> 0x0, ddb (1024 - 2048) -> (0 - 0), active pipes 0x6 -> 0x2
<7> [122.796062] xe 0000:03:00.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:381:cursor B] ddb ( 965 - 1024) -> (4037 - 4096), size 59 -> 59
<7> [122.796142] xe 0000:03:00.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:263:cursor C] ddb (1989 - 2048) -> ( 0 - 0), size 59 -> 0
<7> [122.796224] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:269:pipe C] min cdclk: 192000 kHz -> 0 kHz
<7> [122.796354] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:269:pipe C] enable: no [modeset]
<7> [122.796460] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:153:plane 1C] fb: [NOFB], visible: no
<7> [122.796562] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:183:plane 2C] fb: [NOFB], visible: no
<7> [122.796660] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:213:plane 3C] fb: [NOFB], visible: no
<7> [122.796772] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:243:plane 4C] fb: [NOFB], visible: no
<7> [122.796870] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:253:plane 5C] fb: [NOFB], visible: no
<7> [122.796969] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:263:cursor C] fb: [NOFB], visible: no
<7> [122.797455] 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 C]
<7> [122.823699] xe 0000:03:00.0: [drm:intel_audio_component_get_eld [xe]] Not valid for port E
<7> [122.824471] xe 0000:03:00.0: [drm:intel_disable_transcoder [xe]] disabling pipe C
<7> [122.841829] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling AUX_TC2
<7> [122.844492] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disable TC PLL 2 (active 0x4, on? 1) for [CRTC:269:pipe C]
<7> [122.846949] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disabling TC PLL 2
<7> [122.847655] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:507:DDI TC1/PHY F]
<7> [122.848215] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:509:DP-MST A]
<7> [122.848693] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:510:DP-MST B]
<7> [122.848704] xe 0000:03:00.0: [drm:intel_audio_component_get_power [xe]] restored AUD_FREQ_CNTRL to 0x8010
<7> [122.849155] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:511:DP-MST C]
<7> [122.849574] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:512:DP-MST D]
<7> [122.850032] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:526:DDI TC2/PHY G]
<7> [122.850627] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:528:DP-MST A]
<7> [122.851021] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:529:DP-MST B]
<7> [122.851370] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:530:DP-MST C]
<7> [122.851747] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:531:DP-MST D]
<7> [122.852073] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:538:DDI TC3/PHY H]
<7> [122.852389] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:542:DDI TC4/PHY I]
<7> [122.852700] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:544:DP-MST A]
<7> [122.852992] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:545:DP-MST B]
<7> [122.853276] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:546:DP-MST C]
<7> [122.853546] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:547:DP-MST D]
<7> [122.853849] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:527:DP-2]
<7> [122.855857] xe 0000:03:00.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xf
<7> [122.856270] xe 0000:03:00.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: no -> yes (pipe: B)
<7> [122.856552] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: yes)
<7> [122.872015] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling PW_C
<7> [122.872765] xe 0000:03:00.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating disabled for pipe_mask=0x4 (CLKGATE_DIS_DSSDSC=0xa2000000)
<7> [122.873490] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:269:pipe C]
<7> [122.874807] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:387:pipe B]
<7> [122.875096] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.enable (expected yes, found no)
<7> [122.875624] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.active (expected yes, found no)
<7> [122.876237] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in cpu_transcoder (expected 1, found -1)
<7> [122.876784] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in lane_count (expected 4, found 0)
<7> [122.877252] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [122.877739] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [122.878145] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [122.878565] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [122.879000] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [122.879372] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [122.879750] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [122.880088] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [122.880391] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [122.880711] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [122.881008] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [122.881289] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [122.881568] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [122.881892] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [122.882146] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [122.882398] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [122.882642] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [122.882907] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [122.883134] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [122.883351] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [122.883570] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [122.883805] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [122.884014] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [122.884217] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [122.884414] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [122.884605] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [122.884806] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [122.884982] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [122.885156] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [122.885319] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [122.885482] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [122.885645] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in pipe_bpp (expected 36, found 0)
<7> [122.885822] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [122.885976] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [122.886126] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in port_clock (expected 222750, found 0)
<7> [122.886271] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [122.886417] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in infoframes.gcp (expected 0x00000006, found 0x00000000)
<7> [122.886562] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in avi infoframe
<7> [122.886707] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [122.886841] xe 0000:03:00.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [122.886844] xe 0000:03:00.0: colorspace: RGB
<7> [122.886849] xe 0000:03:00.0: scan mode: Underscan
<7> [122.886850] xe 0000:03:00.0: colorimetry: No Data
<7> [122.886852] xe 0000:03:00.0: picture aspect: 16:9
<7> [122.886854] xe 0000:03:00.0: active aspect: Same as Picture
<7> [122.886855] xe 0000:03:00.0: itc: No Data
<7> [122.886857] xe 0000:03:00.0: extended colorimetry: xvYCC 601
<7> [122.886859] xe 0000:03:00.0: quantization range: Default
<7> [122.886860] xe 0000:03:00.0: nups: Unknown Non-uniform Scaling
<7> [122.886862] xe 0000:03:00.0: video code: 16
<7> [122.886864] xe 0000:03:00.0: ycc quantization range: Full
<7> [122.886865] xe 0000:03:00.0: hdmi content type: Graphics
<7> [122.886867] xe 0000:03:00.0: pixel repeat: 0
<7> [122.886869] xe 0000:03:00.0: bar top 0, bottom 0, left 0, right 0
<7> [122.886871] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [122.887000] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in spd infoframe
<7> [122.887128] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [122.887248] xe 0000:03:00.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [122.887250] xe 0000:03:00.0: vendor: Intel
<7> [122.887252] xe 0000:03:00.0: product: Discrete gfx
<7> [122.887253] xe 0000:03:00.0: source device information: PC General (0x9)
<7> [122.887255] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [122.887374] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hdmi infoframe
<7> [122.887491] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [122.887612] xe 0000:03:00.0: HDMI infoframe: Vendor, version 1, length 4
<7> [122.887614] xe 0000:03:00.0: empty frame
<7> [122.887615] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [122.887753] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:387:pipe B] fastset requirement not met, forcing full modeset
<7> [122.887870] xe 0000:03:00.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:387:pipe B] releasing TC PLL 3
<7> [122.888016] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xf -> 0x1 (total dbuf slices 0xf), mbus joined? yes->no
<7> [122.888117] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:387:pipe B] dbuf slices 0xf -> 0x0, ddb (0 - 4096) -> (0 - 0), active pipes 0x2 -> 0x0
<7> [122.888229] xe 0000:03:00.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:381:cursor B] ddb (4037 - 4096) -> ( 0 - 0), size 59 -> 0
<7> [122.888321] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:387:pipe B] min cdclk: 192000 kHz -> 0 kHz
<7> [122.888455] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:387:pipe B] enable: no [modeset]
<7> [122.888565] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:271:plane 1B] fb: [NOFB], visible: no
<7> [122.888673] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:301:plane 2B] fb: [NOFB], visible: no
<7> [122.888798] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:331:plane 3B] fb: [NOFB], visible: no
<7> [122.888899] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:361:plane 4B] fb: [NOFB], visible: no
<7> [122.888996] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:371:plane 5B] fb: [NOFB], visible: no
<7> [122.889092] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:381:cursor B] fb: [NOFB], visible: no
<7> [122.889762] 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 B]
<7> [122.921960] xe 0000:03:00.0: [drm:intel_audio_component_get_eld [xe]] Not valid for port F
<7> [122.923022] xe 0000:03:00.0: [drm:intel_disable_transcoder [xe]] disabling pipe B
<7> [122.935357] xe 0000:03:00.0: [drm:intel_audio_component_get_power [xe]] restored AUD_FREQ_CNTRL to 0x8010
<7> [122.938950] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disable TC PLL 3 (active 0x2, on? 1) for [CRTC:387:pipe B]
<7> [122.941461] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disabling TC PLL 3
<7> [122.942357] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:507:DDI TC1/PHY F]
<7> [122.942917] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:509:DP-MST A]
<7> [122.943408] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:510:DP-MST B]
<7> [122.943930] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:511:DP-MST C]
<7> [122.944406] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:512:DP-MST D]
<7> [122.944938] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:526:DDI TC2/PHY G]
<7> [122.945028] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling AUX_TC2
<7> [122.945404] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:528:DP-MST A]
<7> [122.945907] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:529:DP-MST B]
<7> [122.946364] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:530:DP-MST C]
<7> [122.946870] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:531:DP-MST D]
<7> [122.947327] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:538:DDI TC3/PHY H]
<7> [122.947994] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:542:DDI TC4/PHY I]
<7> [122.948385] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:544:DP-MST A]
<7> [122.948791] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:545:DP-MST B]
<7> [122.949153] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:546:DP-MST C]
<7> [122.949580] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:547:DP-MST D]
<7> [122.949980] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:539:HDMI-A-3]
<7> [122.950546] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 2 (mbus joined: no)
<7> [122.951084] xe 0000:03:00.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: yes -> no (pipe: *)
<7> [122.951569] xe 0000:03:00.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0x1
<7> [122.952210] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling PW_B
<7> [122.952742] xe 0000:03:00.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating disabled for pipe_mask=0x2 (CLKGATE_DIS_DSSDSC=0xaa000000)
<7> [122.953255] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling PW_2
<7> [122.953753] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:387:pipe B]
<7> [122.954403] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:505:pipe D]
<7> [122.955199] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:505:pipe D]
<7> [122.955234] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CONNECTOR:539:HDMI-A-3]
<7> [122.955335] xe 0000:03:00.0: [drm:intel_hdmi_compute_output_format [xe]] picking 12 bpc for HDMI output (pipe bpp: 36)
<7> [122.955528] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:505:pipe D] hw max bpp: 36, pipe bpp: 36, dithering: 0
<7> [122.955739] xe 0000:03:00.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:538:DDI TC3/PHY H] [CRTC:505:pipe D]
<7> [122.955937] 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> [122.956120] 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> [122.956291] 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> [122.956458] 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> [122.956620] 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> [122.956796] 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> [122.956950] 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> [122.957099] 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> [122.957242] 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> [122.957379] 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> [122.957513] 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> [122.957647] 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> [122.957801] 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> [122.957929] 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> [122.958054] 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 1084)
<7> [122.958176] 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 1089)
<7> [122.958296] 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> [122.958412] 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> [122.958528] 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> [122.958643] 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> [122.958773] 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> [122.958884] 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> [122.958990] 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> [122.959093] 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> [122.959193] 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> [122.959293] 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> [122.959391] 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 1084)
<7> [122.959488] 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 1089)
<7> [122.959586] 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> [122.959690] 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> [122.959787] 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> [122.959882] 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> [122.959977] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [122.960074] 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> [122.960171] 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> [122.960267] 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> [122.960363] 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> [122.960459] 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> [122.960554] 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> [122.960650] 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> [122.960759] 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> [122.960855] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in avi infoframe
<7> [122.960952] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [122.961048] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [122.961145] xe 0000:03:00.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [122.961147] xe 0000:03:00.0: colorspace: RGB
<7> [122.961148] xe 0000:03:00.0: scan mode: Underscan
<7> [122.961149] xe 0000:03:00.0: colorimetry: No Data
<7> [122.961151] xe 0000:03:00.0: picture aspect: 16:9
<7> [122.961152] xe 0000:03:00.0: active aspect: Same as Picture
<7> [122.961153] xe 0000:03:00.0: itc: No Data
<7> [122.961154] xe 0000:03:00.0: extended colorimetry: xvYCC 601
<7> [122.961155] xe 0000:03:00.0: quantization range: Default
<7> [122.961157] xe 0000:03:00.0: nups: Unknown Non-uniform Scaling
<7> [122.961158] xe 0000:03:00.0: video code: 16
<7> [122.961159] xe 0000:03:00.0: ycc quantization range: Full
<7> [122.961160] xe 0000:03:00.0: hdmi content type: Graphics
<7> [122.961162] xe 0000:03:00.0: pixel repeat: 0
<7> [122.961163] xe 0000:03:00.0: bar top 0, bottom 0, left 0, right 0
<7> [122.961165] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in spd infoframe
<7> [122.961261] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [122.961357] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [122.961452] xe 0000:03:00.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [122.961454] xe 0000:03:00.0: vendor: Intel
<7> [122.961455] xe 0000:03:00.0: product: Discrete gfx
<7> [122.961457] xe 0000:03:00.0: source device information: PC General (0x9)
<7> [122.961458] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hdmi infoframe
<7> [122.961553] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [122.961648] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [122.961756] xe 0000:03:00.0: HDMI infoframe: Vendor, version 1, length 4
<7> [122.961758] xe 0000:03:00.0: empty frame
<7> [122.961760] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:505:pipe D] fastset requirement not met, forcing full modeset
<7> [122.961876] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0xf), mbus joined? no->no
<7> [122.961980] 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> [122.962083] 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> [122.962166] 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> [122.962246] 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> [122.962325] 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> [122.962400] 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> [122.962476] 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> [122.962562] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] [CRTC:505:pipe D] data rate 594000 num active planes 1
<7> [122.962664] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 33600 required 624 qgv_peak_bw: 48000
<7> [122.962784] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 53000 required 624 qgv_peak_bw: 48000
<7> [122.962882] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 48000 for required data rate: 624
<7> [122.962991] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:505:pipe D] min cdclk: 0 kHz -> 192000 kHz
<7> [122.963116] xe 0000:03:00.0: [drm:intel_find_dpll [xe]] [CRTC:505:pipe D] allocated TC PLL 3
<7> [122.963217] xe 0000:03:00.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:505:pipe D] reserving TC PLL 3
<7> [122.963314] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:505:pipe D] enable: yes [modeset]
<7> [122.963420] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [122.963520] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: D, pipe bpp: 36, dithering: 0
<7> [122.963619] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [122.963729] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [122.963827] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [122.963924] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [122.964020] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [122.964115] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] GCP: 0x6
<7> [122.964211] xe 0000:03:00.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [122.964212] xe 0000:03:00.0: colorspace: RGB
<7> [122.964214] xe 0000:03:00.0: scan mode: Underscan
<7> [122.964215] xe 0000:03:00.0: colorimetry: No Data
<7> [122.964216] xe 0000:03:00.0: picture aspect: 16:9
<7> [122.964217] xe 0000:03:00.0: active aspect: Same as Picture
<7> [122.964218] xe 0000:03:00.0: itc: No Data
<7> [122.964219] xe 0000:03:00.0: extended colorimetry: xvYCC 601
<7> [122.964221] xe 0000:03:00.0: quantization range: Default
<7> [122.964222] xe 0000:03:00.0: nups: Unknown Non-uniform Scaling
<7> [122.964223] xe 0000:03:00.0: video code: 16
<7> [122.964224] xe 0000:03:00.0: ycc quantization range: Full
<7> [122.964225] xe 0000:03:00.0: hdmi content type: Graphics
<7> [122.964227] xe 0000:03:00.0: pixel repeat: 0
<7> [122.964228] xe 0000:03:00.0: bar top 0, bottom 0, left 0, right 0
<7> [122.964229] xe 0000:03:00.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [122.964231] xe 0000:03:00.0: vendor: Intel
<7> [122.964232] xe 0000:03:00.0: product: Discrete gfx
<7> [122.964233] xe 0000:03:00.0: source device information: PC General (0x9)
<7> [122.964235] xe 0000:03:00.0: HDMI infoframe: Vendor, version 1, length 4
<7> [122.964236] xe 0000:03:00.0: empty frame
<7> [122.964237] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 10 00 08 00 65 32 00 01 00 00 00 00 00 00 00 00
<7> [122.964332] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 61 a4 9a 00 4d 69 20 54 56 09 07 07 11 17 50 51
<7> [122.964426] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 07 00 00 00
<7> [122.964518] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [122.964611] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [122.964733] 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: 41, vsync end: 36
<7> [122.964829] 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> [122.964924] 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> [122.965018] 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> [122.965111] 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> [122.965204] 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> [122.965298] 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> [122.965393] 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> [122.965488] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port clock: 222750, pixel rate 148500, min cdclk 192000, min voltage level 0
<7> [122.965581] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] linetime: 119, ips linetime: 0
<7> [122.965674] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0
<7> [122.965794] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe src: 1920x1080+0+0
<7> [122.965891] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [122.965986] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [122.966080] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [122.966175] 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> [122.966269] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] c20pll_hw_state:
<7> [122.966371] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] tx[0] = 0xbe88, tx[1] = 0x5800, tx[2] = 0x0000
<7> [122.966470] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cmn[0] = 0x0500, cmn[1] = 0x0005, cmn[2] = 0x0000, cmn[3] = 0x0000
<7> [122.966566] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[0] = 0x40e8
<7> [122.966663] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[1] = 0x7d20
<7> [122.966777] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[2] = 0x0a06
<7> [122.966874] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[3] = 0x8f1c
<7> [122.966968] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[4] = 0x0000
<7> [122.967067] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[5] = 0x0000
<7> [122.967163] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[6] = 0x2200
<7> [122.967261] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[7] = 0xffff
<7> [122.967356] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[8] = 0x0400
<7> [122.967451] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[9] = 0x0000
<7> [122.967544] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[10] = 0x0001
<7> [122.967637] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vdr: custom width: 0x00, serdes rate: 0x00, hdmi rate: 0x00
<7> [122.967744] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [122.967838] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [122.967932] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [122.968026] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [122.968120] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [122.968214] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [122.968306] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [122.968400] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [122.968492] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [122.968586] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [122.968681] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [122.968786] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [122.968878] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:389:plane 1D] fb: [FB:556] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [122.968972] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [122.969065] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [122.969158] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:419:plane 2D] fb: [NOFB], visible: no
<7> [122.969252] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:449:plane 3D] fb: [NOFB], visible: no
<7> [122.969342] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:479:plane 4D] fb: [NOFB], visible: no
<7> [122.969435] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:489:plane 5D] fb: [NOFB], visible: no
<7> [122.969531] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:499:cursor D] fb: [NOFB], visible: no
<7> [122.970157] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling PW_2
<7> [122.970268] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling PW_D
<7> [122.970390] 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> [122.970508] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:507:DDI TC1/PHY F]
<7> [122.970603] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:509:DP-MST A]
<7> [122.970704] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:510:DP-MST B]
<7> [122.970794] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:511:DP-MST C]
<7> [122.970883] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:512:DP-MST D]
<7> [122.970971] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:526:DDI TC2/PHY G]
<7> [122.971061] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:528:DP-MST A]
<7> [122.971149] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:529:DP-MST B]
<7> [122.971238] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:530:DP-MST C]
<7> [122.971330] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:531:DP-MST D]
<7> [122.971420] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:538:DDI TC3/PHY H]
<7> [122.971508] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:542:DDI TC4/PHY I]
<7> [122.971598] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:544:DP-MST A]
<7> [122.971693] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:545:DP-MST B]
<7> [122.971781] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:546:DP-MST C]
<7> [122.971866] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:547:DP-MST D]
<7> [122.971960] xe 0000:03:00.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0x3
<7> [122.972129] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 2 (mbus joined: no)
<7> [122.972234] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enable TC PLL 3 (active 0x8, on? 0) for [CRTC:505:pipe D]
<7> [122.972334] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enabling TC PLL 3
<7> [122.974493] xe 0000:03:00.0: [drm:intel_enable_transcoder [xe]] enabling pipe D
<7> [122.992894] 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> [123.009758] xe 0000:03:00.0: [drm:audio_config_hdmi_pixel_clock [xe]] Configuring HDMI audio for pixel clock 148500 (0x00090000)
<7> [123.010028] xe 0000:03:00.0: [drm:hsw_audio_config_update [xe]] using automatic N
<7> [123.010638] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:539:HDMI-A-3]
<7> [123.011144] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:505:pipe D]
<7> [123.015470] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:269:pipe C]
<7> [123.015520] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CONNECTOR:527:DP-2]
<7> [123.015708] xe 0000:03:00.0: [drm:intel_dp_compute_config_limits [xe]] [ENCODER:526:DDI TC2/PHY G][CRTC:269:pipe C] 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> [123.015990] xe 0000:03:00.0: [drm:intel_dp_compute_output_format [xe]] DP lane count 4 clock 270000 bpp input 36 compressed 0.0000 HDR no link rate required 668250 available 1080000
<7> [123.016259] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:269:pipe C] hw max bpp: 36, pipe bpp: 36, dithering: 0
<7> [123.016527] xe 0000:03:00.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:526:DDI TC2/PHY G] [CRTC:269:pipe C]
<7> [123.016800] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.enable (expected no, found yes)
<7> [123.017045] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.active (expected no, found yes)
<7> [123.017273] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in cpu_transcoder (expected -1, found 2)
<7> [123.017497] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in lane_count (expected 0, found 4)
<7> [123.017738] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] 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> [123.017967] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in output_types (expected 0x00000000, found 0x00000080)
<7> [123.018187] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [123.018139] xe 0000:03:00.0: [drm:intel_audio_component_get_power [xe]] restored AUD_FREQ_CNTRL to 0x8010
<7> [123.018387] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [123.018584] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [123.018805] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [123.018990] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [123.019175] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [123.019353] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [123.019531] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [123.019734] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [123.019914] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [123.020084] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [123.020250] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [123.020415] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [123.020576] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [123.020744] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [123.020900] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [123.021050] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [123.021198] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [123.021342] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [123.021486] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [123.021627] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [123.021782] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [123.021922] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [123.022055] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [123.022188] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [123.022318] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [123.022443] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [123.022569] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [123.022704] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in enhanced_framing (expected no, found yes)
<7> [123.022828] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in pipe_bpp (expected 0, found 36)
<7> [123.022945] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [123.023063] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [123.023176] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in port_clock (expected 0, found 270000)
<7> [123.023292] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:269:pipe C] fastset requirement not met, forcing full modeset
<7> [123.023425] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0x3 -> 0xf (total dbuf slices 0xf), mbus joined? no->no
<7> [123.023523] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:269:pipe C] dbuf slices 0x0 -> 0xc, ddb (0 - 0) -> (0 - 2048), active pipes 0x8 -> 0xc
<7> [123.023644] xe 0000:03:00.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:153:plane 1C] ddb ( 0 - 0) -> ( 0 - 1989), size 0 -> 1989
<7> [123.023782] xe 0000:03:00.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:263:cursor C] ddb ( 0 - 0) -> (1989 - 2048), size 0 -> 59
<7> [123.023875] xe 0000:03:00.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:153:plane 1C] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm
<7> [123.023963] xe 0000:03:00.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:153:plane 1C] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 4, 4, 9, 9, 17, 17, 17, 17, 0, 15, 0
<7> [123.024049] xe 0000:03:00.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:153:plane 1C] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 62, 62, 139, 139, 261, 261, 261, 261, 137, 230, 244
<7> [123.024129] xe 0000:03:00.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:153:plane 1C] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 123, 123, 245, 245, 367, 367, 0, 0, 138, 306, 306
<7> [123.024220] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] [CRTC:269:pipe C] data rate 594000 num active planes 1
<7> [123.024333] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 33600 required 1248 qgv_peak_bw: 48000
<7> [123.024438] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 53000 required 1248 qgv_peak_bw: 48000
<7> [123.024540] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 48000 for required data rate: 1248
<7> [123.024651] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:269:pipe C] min cdclk: 0 kHz -> 192000 kHz
<7> [123.024772] xe 0000:03:00.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [xe]] dbuf bandwidth min cdclk: 9282 kHz -> 18563 kHz
<7> [123.024882] xe 0000:03:00.0: [drm:intel_find_dpll [xe]] [CRTC:269:pipe C] allocated TC PLL 2
<7> [123.024983] xe 0000:03:00.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:269:pipe C] reserving TC PLL 2
<7> [123.025081] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:269:pipe C] enable: yes [modeset]
<7> [123.025184] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: DP (0x80), output format: RGB, sink format: RGB
<7> [123.025284] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: C, pipe bpp: 36, dithering: 0
<7> [123.025382] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [123.025480] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [123.025577] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [123.025680] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [123.025780] 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> [123.025876] 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> [123.025971] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: enabled
<7> [123.026065] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled
<7> [123.026160] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] psr: disabled, selective update: disabled, panel replay: disabled, selective fetch: disabled
<7> [123.026256] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] minimum hblank: 0
<7> [123.026356] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] audio: 1, infoframes: 0, infoframes enabled: 0x0
<7> [123.026454] 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> [123.026552] 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> [123.026646] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 17 50 51 07 00 00 00 00
<7> [123.026754] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [123.026849] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [123.026944] 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> [123.027039] 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> [123.027135] 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> [123.027234] 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> [123.027332] 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> [123.027428] 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> [123.027524] 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> [123.027620] 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> [123.027726] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port clock: 270000, pixel rate 148500, min cdclk 192000, min voltage level 0
<7> [123.027821] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] linetime: 119, ips linetime: 0
<7> [123.027919] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0
<7> [123.028016] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe src: 1920x1080+0+0
<7> [123.028114] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [123.028211] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [123.028305] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [123.028405] 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> [123.028499] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] c20pll_hw_state:
<7> [123.028594] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] tx[0] = 0xbe88, tx[1] = 0x4800, tx[2] = 0x0000
<7> [123.028693] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cmn[0] = 0x0500, cmn[1] = 0x0005, cmn[2] = 0x0000, cmn[3] = 0x0000
<7> [123.028788] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[0] = 0x308c
<7> [123.028882] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[1] = 0x2110
<7> [123.028980] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[2] = 0xcc9c
<7> [123.029078] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[3] = 0xbfc1
<7> [123.029173] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[4] = 0x4b9a
<7> [123.029268] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[5] = 0x3f81
<7> [123.029366] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[6] = 0x2000
<7> [123.029464] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[7] = 0x0001
<7> [123.029560] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[8] = 0x5000
<7> [123.029656] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[9] = 0x0000
<7> [123.029766] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[10] = 0x0000
<7> [123.029861] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vdr: custom width: 0x00, serdes rate: 0x42, hdmi rate: 0x00
<7> [123.029955] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [123.030049] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [123.030144] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [123.030237] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [123.030332] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [123.030425] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [123.030518] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [123.030614] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [123.030713] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [123.030806] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [123.030900] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [123.030993] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [123.031086] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:153:plane 1C] fb: [FB:556] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [123.031180] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [123.031274] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [123.031369] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:183:plane 2C] fb: [NOFB], visible: no
<7> [123.031462] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:213:plane 3C] fb: [NOFB], visible: no
<7> [123.031555] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:243:plane 4C] fb: [NOFB], visible: no
<7> [123.031650] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:253:plane 5C] fb: [NOFB], visible: no
<7> [123.031754] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:263:cursor C] fb: [NOFB], visible: no
<7> [123.032153] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling PW_C
<7> [123.032279] xe 0000:03:00.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating enabled for pipe_mask=0x4 (CLKGATE_DIS_DSSDSC=0x0a000000)
<7> [123.032602] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:507:DDI TC1/PHY F]
<7> [123.032703] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:509:DP-MST A]
<7> [123.032796] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:510:DP-MST B]
<7> [123.032891] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:511:DP-MST C]
<7> [123.032979] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:512:DP-MST D]
<7> [123.033069] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:526:DDI TC2/PHY G]
<7> [123.033159] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:528:DP-MST A]
<7> [123.033248] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:529:DP-MST B]
<7> [123.033334] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:530:DP-MST C]
<7> [123.033421] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:531:DP-MST D]
<7> [123.033506] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:538:DDI TC3/PHY H]
<7> [123.033592] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:542:DDI TC4/PHY I]
<7> [123.033683] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:544:DP-MST A]
<7> [123.033771] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:545:DP-MST B]
<7> [123.033855] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:546:DP-MST C]
<7> [123.033939] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:547:DP-MST D]
<7> [123.035742] xe 0000:03:00.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xf
<7> [123.036113] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 2 (mbus joined: no)
<7> [123.036217] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enable TC PLL 2 (active 0x4, on? 0) for [CRTC:269:pipe C]
<7> [123.036316] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enabling TC PLL 2
<7> [123.038794] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling AUX_TC2
<7> [123.042426] 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 02 01 04 01 0f 00 01
<7> [123.047131] 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> [123.048147] 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 02 01 04 01 0f 00 01
<7> [123.048588] 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> [123.050105] 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> [123.050669] 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> [123.056586] 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> [123.056779] 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> [123.064564] 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> [123.064762] 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> [123.066127] xe 0000:03:00.0: [drm:intel_enable_transcoder [xe]] enabling pipe C
<7> [123.084074] 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 C], 40 bytes ELD
<7> [123.097816] xe 0000:03:00.0: [drm:intel_audio_component_get_power [xe]] restored AUD_FREQ_CNTRL to 0x8010
<7> [123.100519] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:527:DP-2]
<7> [123.101432] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:269:pipe C]
<7> [123.169705] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling AUX_TC2
<7> [125.089922] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling AUX_TC2
<7> [125.193708] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling AUX_TC2
<6> [125.265484] [IGT] kms_flip: finished subtest BD-DP2-HDMI-A3, FAIL
Created at 2026-04-24 02:39:20