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

Result: Fail

i915_display_info9 igt_runner9 results9.json results9-xe-load.json guc_logs9.tar i915_display_info_post_exec9 boot9 dmesg9

DetailValue
Duration 4.12 seconds
Hostname 
shard-bmg-6
Igt-Version 
IGT-Version: 2.3-g911c75d8e (x86_64) (Linux: 7.0.0-rc2-lgci-xe-xe-4661-1242b36e0da950a66-debug+ x86_64)
Out 
Starting dynamic subtest: AC-DP2-HDMI-A3
  1920x1080: 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5 
  1920x1080: 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5 
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../tests/kms_flip.c:921 run_test_on_crtc_set.constprop.0()
  #2 ../tests/kms_flip.c:1972 run_pair()
  #3 ../tests/kms_flip.c:2129 __igt_unique____real_main2043()
  #4 ../tests/kms_flip.c:2043 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest AC-DP2-HDMI-A3: FAIL (4.124s)
Err 
Starting dynamic subtest: AC-DP2-HDMI-A3
(kms_flip:10487) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:926:
(kms_flip:10487) CRITICAL: Failed assertion: end - start < 500
Dynamic subtest AC-DP2-HDMI-A3 failed.
**** DEBUG ****
(kms_flip:10487) intel_pat-DEBUG: Parsed PAT entry 11: 0x0000061c
(kms_flip:10487) intel_pat-DEBUG: Parsed PAT entry 12: 0x0000023c
(kms_flip:10487) intel_pat-DEBUG: Parsed PAT entry 13: 0x00000000
(kms_flip:10487) intel_pat-DEBUG: Parsed PAT entry 14: 0x00000200
(kms_flip:10487) intel_pat-DEBUG: Parsed PAT entry 15: 0x00000614
(kms_flip:10487) intel_pat-DEBUG: Parsed PAT entry 16: 0x00000000 (reserved)
(kms_flip:10487) intel_pat-DEBUG: Parsed PAT entry 17: 0x00000000 (reserved)
(kms_flip:10487) intel_pat-DEBUG: Parsed PAT entry 18: 0x00000000 (reserved)
(kms_flip:10487) intel_pat-DEBUG: Parsed PAT entry 19: 0x00000000 (reserved)
(kms_flip:10487) intel_pat-DEBUG: Parsed PAT entry 20: 0x0000004c
(kms_flip:10487) intel_pat-DEBUG: Parsed PAT entry 21: 0x0000024c
(kms_flip:10487) intel_pat-DEBUG: Parsed PAT entry 22: 0x0000004e
(kms_flip:10487) intel_pat-DEBUG: Parsed PAT entry 23: 0x0000004f
(kms_flip:10487) intel_pat-DEBUG: Parsed PAT entry 24: 0x0000008c
(kms_flip:10487) intel_pat-DEBUG: Parsed PAT entry 25: 0x0000028c
(kms_flip:10487) intel_pat-DEBUG: Parsed PAT entry 26: 0x0000008e
(kms_flip:10487) intel_pat-DEBUG: Parsed PAT entry 27: 0x0000008f
(kms_flip:10487) intel_allocator_simple-DEBUG: <ial: 0x5f8998f03310, fd: 5> objects: 3, reserved_areas: 0
(kms_flip:10487) intel_allocator_simple-DEBUG: <ial: 0x5f8998f03310, fd: 5> objects: 3, reserved_areas: 0
(kms_flip:10487) igt_kms-INFO:   1920x1080: 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5 
(kms_flip:10487) igt_kms-INFO:   1920x1080: 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5 
(kms_flip:10487) DEBUG: No stale events found
(kms_flip:10487) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0
(kms_flip:10487) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0
(kms_flip:10487) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0
(kms_flip:10487) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0
(kms_flip:10487) DEBUG: name = flip
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 433.323456
current_received_ts = 433.322937
current_seq = 14236
count = 0
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 38us
(kms_flip:10487) DEBUG: name = flip
last_ts = 433.323456
last_received_ts = 433.322937
last_seq = 14236
current_ts = 433.340118
current_received_ts = 433.339539
current_seq = 14237
count = 1
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 27us
(kms_flip:10487) DEBUG: name = flip
last_ts = 433.340118
last_received_ts = 433.339539
last_seq = 14237
current_ts = 433.356781
current_received_ts = 433.356201
current_seq = 14238
count = 2
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 26us
(kms_flip:10487) DEBUG: name = flip
last_ts = 433.356781
last_received_ts = 433.356201
last_seq = 14238
current_ts = 433.373444
current_received_ts = 433.372864
current_seq = 14239
count = 3
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 27us
(kms_flip:10487) DEBUG: name = flip
last_ts = 433.373444
last_received_ts = 433.372864
last_seq = 14239
current_ts = 433.390106
current_received_ts = 433.389526
current_seq = 14240
count = 4
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 26us
(kms_flip:10487) DEBUG: name = flip
last_ts = 433.390106
last_received_ts = 433.389526
last_seq = 14240
current_ts = 433.406769
current_received_ts = 433.406189
current_seq = 14241
count = 5
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 25us
(kms_flip:10487) DEBUG: name = flip
last_ts = 433.406769
last_received_ts = 433.406189
last_seq = 14241
current_ts = 433.423462
current_received_ts = 433.422882
current_seq = 14242
count = 6
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 25us
(kms_flip:10487) DEBUG: name = flip
last_ts = 433.423462
last_received_ts = 433.422882
last_seq = 14242
current_ts = 433.440125
current_received_ts = 433.439545
current_seq = 14243
count = 7
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 25us
(kms_flip:10487) DEBUG: name = flip
last_ts = 433.440125
last_received_ts = 433.439545
last_seq = 14243
current_ts = 433.456787
current_received_ts = 433.456207
current_seq = 14244
count = 8
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 25us
(kms_flip:10487) DEBUG: name = flip
last_ts = 433.456787
last_received_ts = 433.456207
last_seq = 14244
current_ts = 433.473450
current_received_ts = 433.472870
current_seq = 14245
count = 9
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 25us
(kms_flip:10487) DEBUG: name = flip
last_ts = 433.473450
last_received_ts = 433.472870
last_seq = 14245
current_ts = 433.490112
current_received_ts = 433.489532
current_seq = 14246
count = 10
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 26us
(kms_flip:10487) DEBUG: name = flip
last_ts = 433.490112
last_received_ts = 433.489532
last_seq = 14246
current_ts = 433.506805
current_received_ts = 433.506226
current_seq = 14247
count = 11
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 25us
(kms_flip:10487) DEBUG: name = flip
last_ts = 433.506805
last_received_ts = 433.506226
last_seq = 14247
current_ts = 433.523468
current_received_ts = 433.522888
current_seq = 14248
count = 12
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 25us
(kms_flip:10487) DEBUG: name = flip
last_ts = 433.523468
last_received_ts = 433.522888
last_seq = 14248
current_ts = 433.540131
current_received_ts = 433.539551
current_seq = 14249
count = 13
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 26us
(kms_flip:10487) DEBUG: name = flip
last_ts = 433.540131
last_received_ts = 433.539551
last_seq = 14249
current_ts = 433.556793
current_received_ts = 433.556213
current_seq = 14250
count = 14
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 25us
(kms_flip:10487) DEBUG: name = flip
last_ts = 433.556793
last_received_ts = 433.556213
last_seq = 14250
current_ts = 433.573456
current_received_ts = 433.572876
current_seq = 14251
count = 15
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 25us
(kms_flip:10487) DEBUG: name = flip
last_ts = 433.573456
last_received_ts = 433.572876
last_seq = 14251
current_ts = 433.590118
current_received_ts = 433.589539
current_seq = 14252
count = 16
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 26us
(kms_flip:10487) DEBUG: name = flip
last_ts = 433.590118
last_received_ts = 433.589539
last_seq = 14252
current_ts = 433.606812
current_received_ts = 433.606201
current_seq = 14253
count = 17
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 25us
(kms_flip:10487) DEBUG: name = flip
last_ts = 433.606812
last_received_ts = 433.606201
last_seq = 14253
current_ts = 433.623474
current_received_ts = 433.622864
current_seq = 14254
count = 18
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 26us
(kms_flip:10487) DEBUG: name = flip
last_ts = 433.623474
last_received_ts = 433.622864
last_seq = 14254
current_ts = 433.640137
current_received_ts = 433.639557
current_seq = 14255
count = 19
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 26us
(kms_flip:10487) DEBUG: name = flip
last_ts = 433.640137
last_received_ts = 433.639557
last_seq = 14255
current_ts = 433.656799
current_received_ts = 433.656219
current_seq = 14256
count = 20
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 26us
(kms_flip:10487) DEBUG: name = flip
last_ts = 433.656799
last_received_ts = 433.656219
last_seq = 14256
current_ts = 433.673462
current_received_ts = 433.672882
current_seq = 14257
count = 21
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 26us
(kms_flip:10487) DEBUG: name = flip
last_ts = 433.673462
last_received_ts = 433.672882
last_seq = 14257
current_ts = 433.690125
current_received_ts = 433.689545
current_seq = 14258
count = 22
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 26us
(kms_flip:10487) DEBUG: name = flip
last_ts = 433.690125
last_received_ts = 433.689545
last_seq = 14258
current_ts = 433.706818
current_received_ts = 433.706207
current_seq = 14259
count = 23
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 25us
(kms_flip:10487) DEBUG: name = flip
last_ts = 433.706818
last_received_ts = 433.706207
last_seq = 14259
current_ts = 433.723480
current_received_ts = 433.722900
current_seq = 14260
count = 24
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 26us
(kms_flip:10487) DEBUG: name = flip
last_ts = 433.723480
last_received_ts = 433.722900
last_seq = 14260
current_ts = 433.740143
current_received_ts = 433.739563
current_seq = 14261
count = 25
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 26us
(kms_flip:10487) DEBUG: name = flip
last_ts = 433.740143
last_received_ts = 433.739563
last_seq = 14261
current_ts = 433.756805
current_received_ts = 433.756226
current_seq = 14262
count = 26
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 25us
(kms_flip:10487) DEBUG: name = flip
last_ts = 433.756805
last_received_ts = 433.756226
last_seq = 14262
current_ts = 433.773468
current_received_ts = 433.772888
current_seq = 14263
count = 27
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 25us
(kms_flip:10487) DEBUG: name = flip
last_ts = 433.773468
last_received_ts = 433.772888
last_seq = 14263
current_ts = 433.790131
current_received_ts = 433.789551
current_seq = 14264
count = 28
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 25us
(kms_flip:10487) DEBUG: name = flip
last_ts = 433.790131
last_received_ts = 433.789551
last_seq = 14264
current_ts = 433.806824
current_received_ts = 433.806244
current_seq = 14265
count = 29
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 25us
(kms_flip:10487) DEBUG: name = flip
last_ts = 433.806824
last_received_ts = 433.806244
last_seq = 14265
current_ts = 433.823486
current_received_ts = 433.822906
current_seq = 14266
count = 30
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 26us
(kms_flip:10487) DEBUG: name = flip
last_ts = 433.823486
last_received_ts = 433.822906
last_seq = 14266
current_ts = 433.840149
current_received_ts = 433.839569
current_seq = 14267
count = 31
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 25us
(kms_flip:10487) DEBUG: name = flip
last_ts = 433.840149
last_received_ts = 433.839569
last_seq = 14267
current_ts = 433.856812
current_received_ts = 433.856232
current_seq = 14268
count = 32
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 24us
(kms_flip:10487) DEBUG: name = flip
last_ts = 433.856812
last_received_ts = 433.856232
last_seq = 14268
current_ts = 433.873474
current_received_ts = 433.872894
current_seq = 14269
count = 33
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 25us
(kms_flip:10487) DEBUG: name = flip
last_ts = 433.873474
last_received_ts = 433.872894
last_seq = 14269
current_ts = 433.890167
current_received_ts = 433.889557
current_seq = 14270
count = 34
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 26us
(kms_flip:10487) DEBUG: name = flip
last_ts = 433.890167
last_received_ts = 433.889557
last_seq = 14270
current_ts = 433.906830
current_received_ts = 433.906250
current_seq = 14271
count = 35
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 26us
(kms_flip:10487) DEBUG: name = flip
last_ts = 433.906830
last_received_ts = 433.906250
last_seq = 14271
current_ts = 433.923492
current_received_ts = 433.922913
current_seq = 14272
count = 36
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 26us
(kms_flip:10487) DEBUG: name = flip
last_ts = 433.923492
last_received_ts = 433.922913
last_seq = 14272
current_ts = 433.940155
current_received_ts = 433.939575
current_seq = 14273
count = 37
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 26us
(kms_flip:10487) DEBUG: name = flip
last_ts = 433.940155
last_received_ts = 433.939575
last_seq = 14273
current_ts = 433.956818
current_received_ts = 433.956238
current_seq = 14274
count = 38
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 27us
(kms_flip:10487) DEBUG: name = flip
last_ts = 433.956818
last_received_ts = 433.956238
last_seq = 14274
current_ts = 433.973480
current_received_ts = 433.972900
current_seq = 14275
count = 39
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 26us
(kms_flip:10487) DEBUG: name = flip
last_ts = 433.973480
last_received_ts = 433.972900
last_seq = 14275
current_ts = 433.990143
current_received_ts = 433.989563
current_seq = 14276
count = 40
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 25us
(kms_flip:10487) DEBUG: name = flip
last_ts = 433.990143
last_received_ts = 433.989563
last_seq = 14276
current_ts = 434.006836
current_received_ts = 434.006256
current_seq = 14277
count = 41
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 25us
(kms_flip:10487) DEBUG: name = flip
last_ts = 434.006836
last_received_ts = 434.006256
last_seq = 14277
current_ts = 434.023499
current_received_ts = 434.022919
current_seq = 14278
count = 42
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 25us
(kms_flip:10487) DEBUG: name = flip
last_ts = 434.023499
last_received_ts = 434.022919
last_seq = 14278
current_ts = 434.040161
current_received_ts = 434.039581
current_seq = 14279
count = 43
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 26us
(kms_flip:10487) DEBUG: name = flip
last_ts = 434.040161
last_received_ts = 434.039581
last_seq = 14279
current_ts = 434.056824
current_received_ts = 434.056244
current_seq = 14280
count = 44
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 25us
(kms_flip:10487) DEBUG: name = flip
last_ts = 434.056824
last_received_ts = 434.056244
last_seq = 14280
current_ts = 434.073517
current_received_ts = 434.072906
current_seq = 14281
count = 45
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 25us
(kms_flip:10487) DEBUG: name = flip
last_ts = 434.073517
last_received_ts = 434.072906
last_seq = 14281
current_ts = 434.090179
current_received_ts = 434.089569
current_seq = 14282
count = 46
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 25us
(kms_flip:10487) DEBUG: name = flip
last_ts = 434.090179
last_received_ts = 434.089569
last_seq = 14282
current_ts = 434.106842
current_received_ts = 434.106262
current_seq = 14283
count = 47
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 25us
(kms_flip:10487) DEBUG: name = flip
last_ts = 434.106842
last_received_ts = 434.106262
last_seq = 14283
current_ts = 434.123505
current_received_ts = 434.122925
current_seq = 14284
count = 48
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 25us
(kms_flip:10487) DEBUG: name = flip
last_ts = 434.123505
last_received_ts = 434.122925
last_seq = 14284
current_ts = 434.140167
current_received_ts = 434.139587
current_seq = 14285
count = 49
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 26us
(kms_flip:10487) DEBUG: name = flip
last_ts = 434.140167
last_received_ts = 434.139587
last_seq = 14285
current_ts = 434.156830
current_received_ts = 434.156250
current_seq = 14286
count = 50
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 24us
(kms_flip:10487) DEBUG: name = flip
last_ts = 434.156830
last_received_ts = 434.156250
last_seq = 14286
current_ts = 434.173492
current_received_ts = 434.172913
current_seq = 14287
count = 51
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 26us
(kms_flip:10487) DEBUG: name = flip
last_ts = 434.173492
last_received_ts = 434.172913
last_seq = 14287
current_ts = 434.190186
current_received_ts = 434.189575
current_seq = 14288
count = 52
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 25us
(kms_flip:10487) DEBUG: name = flip
last_ts = 434.190186
last_received_ts = 434.189575
last_seq = 14288
current_ts = 434.206848
current_received_ts = 434.206268
current_seq = 14289
count = 53
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 26us
(kms_flip:10487) DEBUG: name = flip
last_ts = 434.206848
last_received_ts = 434.206268
last_seq = 14289
current_ts = 434.223511
current_received_ts = 434.222931
current_seq = 14290
count = 54
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 26us
(kms_flip:10487) DEBUG: name = flip
last_ts = 434.223511
last_received_ts = 434.222931
last_seq = 14290
current_ts = 434.240173
current_received_ts = 434.239594
current_seq = 14291
count = 55
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 25us
(kms_flip:10487) DEBUG: name = flip
last_ts = 434.240173
last_received_ts = 434.239594
last_seq = 14291
current_ts = 434.256836
current_received_ts = 434.256256
current_seq = 14292
count = 56
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 26us
(kms_flip:10487) DEBUG: name = flip
last_ts = 434.256836
last_received_ts = 434.256256
last_seq = 14292
current_ts = 434.273499
current_received_ts = 434.272919
current_seq = 14293
count = 57
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 26us
(kms_flip:10487) DEBUG: name = flip
last_ts = 434.273499
last_received_ts = 434.272919
last_seq = 14293
current_ts = 434.290192
current_received_ts = 434.289581
current_seq = 14294
count = 58
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 26us
(kms_flip:10487) DEBUG: name = flip
last_ts = 434.290192
last_received_ts = 434.289581
last_seq = 14294
current_ts = 434.306854
current_received_ts = 434.306274
current_seq = 14295
count = 59
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 26us
(kms_flip:10487) DEBUG: name = flip
last_ts = 434.306854
last_received_ts = 434.306274
last_seq = 14295
current_ts = 434.323517
current_received_ts = 434.322937
current_seq = 14296
count = 60
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 25us
(kms_flip:10487) DEBUG: name = flip
last_ts = 434.323517
last_received_ts = 434.322937
last_seq = 14296
current_ts = 434.340179
current_received_ts = 434.339600
current_seq = 14297
count = 61
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 25us
(kms_flip:10487) DEBUG: name = flip
last_ts = 434.340179
last_received_ts = 434.339600
last_seq = 14297
current_ts = 434.356842
current_received_ts = 434.356262
current_seq = 14298
count = 62
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 25us
(kms_flip:10487) DEBUG: name = flip
last_ts = 434.356842
last_received_ts = 434.356262
last_seq = 14298
current_ts = 434.373535
current_received_ts = 434.372925
current_seq = 14299
count = 63
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 26us
(kms_flip:10487) DEBUG: name = flip
last_ts = 434.373535
last_received_ts = 434.372925
last_seq = 14299
current_ts = 434.390198
current_received_ts = 434.389587
current_seq = 14300
count = 64
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 25us
(kms_flip:10487) DEBUG: name = flip
last_ts = 434.390198
last_received_ts = 434.389587
last_seq = 14300
current_ts = 434.406860
current_received_ts = 434.406281
current_seq = 14301
count = 65
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 26us
(kms_flip:10487) DEBUG: name = flip
last_ts = 434.406860
last_received_ts = 434.406281
last_seq = 14301
current_ts = 434.423523
current_received_ts = 434.422943
current_seq = 14302
count = 66
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 29us
(kms_flip:10487) DEBUG: name = flip
last_ts = 434.423523
last_received_ts = 434.422943
last_seq = 14302
current_ts = 434.440216
current_received_ts = 434.439697
current_seq = 14303
count = 67
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 25us
(kms_flip:10487) DEBUG: name = flip
last_ts = 434.440216
last_received_ts = 434.439697
last_seq = 14303
current_ts = 434.456848
current_received_ts = 434.456360
current_seq = 14304
count = 68
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 27us
(kms_flip:10487) DEBUG: name = flip
last_ts = 434.456848
last_received_ts = 434.456360
last_seq = 14304
current_ts = 434.473541
current_received_ts = 434.473053
current_seq = 14305
count = 69
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 25us
(kms_flip:10487) DEBUG: name = flip
last_ts = 434.473541
last_received_ts = 434.473053
last_seq = 14305
current_ts = 434.490204
current_received_ts = 434.489685
current_seq = 14306
count = 70
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 24us
(kms_flip:10487) DEBUG: name = flip
last_ts = 434.490204
last_received_ts = 434.489685
last_seq = 14306
current_ts = 434.506866
current_received_ts = 434.506348
current_seq = 14307
count = 71
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 24us
(kms_flip:10487) DEBUG: name = flip
last_ts = 434.506866
last_received_ts = 434.506348
last_seq = 14307
current_ts = 434.523529
current_received_ts = 434.523041
current_seq = 14308
count = 72
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 25us
(kms_flip:10487) DEBUG: name = flip
last_ts = 434.523529
last_received_ts = 434.523041
last_seq = 14308
current_ts = 434.540192
current_received_ts = 434.539673
current_seq = 14309
count = 73
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 25us
(kms_flip:10487) DEBUG: name = flip
last_ts = 434.540192
last_received_ts = 434.539673
last_seq = 14309
current_ts = 434.556885
current_received_ts = 434.556366
current_seq = 14310
count = 74
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 25us
(kms_flip:10487) DEBUG: name = flip
last_ts = 434.556885
last_received_ts = 434.556366
last_seq = 14310
current_ts = 434.573547
current_received_ts = 434.573059
current_seq = 14311
count = 75
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 25us
(kms_flip:10487) DEBUG: name = flip
last_ts = 434.573547
last_received_ts = 434.573059
last_seq = 14311
current_ts = 434.590210
current_received_ts = 434.589691
current_seq = 14312
count = 76
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 24us
(kms_flip:10487) DEBUG: name = flip
last_ts = 434.590210
last_received_ts = 434.589691
last_seq = 14312
current_ts = 434.606873
current_received_ts = 434.606384
current_seq = 14313
count = 77
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 25us
(kms_flip:10487) DEBUG: name = flip
last_ts = 434.606873
last_received_ts = 434.606384
last_seq = 14313
current_ts = 434.623535
current_received_ts = 434.623047
current_seq = 14314
count = 78
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 24us
(kms_flip:10487) DEBUG: name = flip
last_ts = 434.623535
last_received_ts = 434.623047
last_seq = 14314
current_ts = 434.640228
current_received_ts = 434.639679
current_seq = 14315
count = 79
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 25us
(kms_flip:10487) DEBUG: name = flip
last_ts = 434.640228
last_received_ts = 434.639679
last_seq = 14315
current_ts = 434.656891
current_received_ts = 434.656372
current_seq = 14316
count = 80
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 25us
(kms_flip:10487) DEBUG: name = flip
last_ts = 434.656891
last_received_ts = 434.656372
last_seq = 14316
current_ts = 434.673553
current_received_ts = 434.673065
current_seq = 14317
count = 81
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 24us
(kms_flip:10487) DEBUG: name = flip
last_ts = 434.673553
last_received_ts = 434.673065
last_seq = 14317
current_ts = 434.690216
current_received_ts = 434.689697
current_seq = 14318
count = 82
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 25us
(kms_flip:10487) DEBUG: name = flip
last_ts = 434.690216
last_received_ts = 434.689697
last_seq = 14318
current_ts = 434.706879
current_received_ts = 434.706360
current_seq = 14319
count = 83
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 25us
(kms_flip:10487) DEBUG: name = flip
last_ts = 434.706879
last_received_ts = 434.706360
last_seq = 14319
current_ts = 434.723541
current_received_ts = 434.723053
current_seq = 14320
count = 84
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 24us
(kms_flip:10487) DEBUG: name = flip
last_ts = 434.723541
last_received_ts = 434.723053
last_seq = 14320
current_ts = 434.740234
current_received_ts = 434.739716
current_seq = 14321
count = 85
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 25us
(kms_flip:10487) DEBUG: name = flip
last_ts = 434.740234
last_received_ts = 434.739716
last_seq = 14321
current_ts = 434.756897
current_received_ts = 434.756378
current_seq = 14322
count = 86
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 24us
(kms_flip:10487) DEBUG: name = flip
last_ts = 434.756897
last_received_ts = 434.756378
last_seq = 14322
current_ts = 434.773560
current_received_ts = 434.773041
current_seq = 14323
count = 87
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 24us
(kms_flip:10487) DEBUG: name = flip
last_ts = 434.773560
last_received_ts = 434.773041
last_seq = 14323
current_ts = 434.790222
current_received_ts = 434.789703
current_seq = 14324
count = 88
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 25us
(kms_flip:10487) DEBUG: name = flip
last_ts = 434.790222
last_received_ts = 434.789703
last_seq = 14324
current_ts = 434.806885
current_received_ts = 434.806366
current_seq = 14325
count = 89
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 25us
(kms_flip:10487) DEBUG: name = flip
last_ts = 434.806885
last_received_ts = 434.806366
last_seq = 14325
current_ts = 434.823578
current_received_ts = 434.822998
current_seq = 14326
count = 90
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 26us
(kms_flip:10487) DEBUG: name = flip
last_ts = 434.823578
last_received_ts = 434.822998
last_seq = 14326
current_ts = 434.840210
current_received_ts = 434.839630
current_seq = 14327
count = 91
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 25us
(kms_flip:10487) DEBUG: name = flip
last_ts = 434.840210
last_received_ts = 434.839630
last_seq = 14327
current_ts = 434.856903
current_received_ts = 434.856384
current_seq = 14328
count = 92
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 25us
(kms_flip:10487) DEBUG: name = flip
last_ts = 434.856903
last_received_ts = 434.856384
last_seq = 14328
current_ts = 434.873566
current_received_ts = 434.873047
current_seq = 14329
count = 93
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 50us
(kms_flip:10487) DEBUG: name = flip
last_ts = 434.873566
last_received_ts = 434.873047
last_seq = 14329
current_ts = 434.890228
current_received_ts = 434.889709
current_seq = 14330
count = 94
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 27us
(kms_flip:10487) DEBUG: name = flip
last_ts = 434.890228
last_received_ts = 434.889709
last_seq = 14330
current_ts = 434.906891
current_received_ts = 434.906342
current_seq = 14331
count = 95
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 27us
(kms_flip:10487) DEBUG: name = flip
last_ts = 434.906891
last_received_ts = 434.906342
last_seq = 14331
current_ts = 434.923553
current_received_ts = 434.922974
current_seq = 14332
count = 96
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 25us
(kms_flip:10487) DEBUG: name = flip
last_ts = 434.923553
last_received_ts = 434.922974
last_seq = 14332
current_ts = 434.940216
current_received_ts = 434.939636
current_seq = 14333
count = 97
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 26us
(kms_flip:10487) DEBUG: name = flip
last_ts = 434.940216
last_received_ts = 434.939636
last_seq = 14333
current_ts = 434.956909
current_received_ts = 434.956329
current_seq = 14334
count = 98
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 26us
(kms_flip:10487) DEBUG: name = flip
last_ts = 434.956909
last_received_ts = 434.956329
last_seq = 14334
current_ts = 434.973572
current_received_ts = 434.972992
current_seq = 14335
count = 99
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 26us
(kms_flip:10487) DEBUG: name = flip
last_ts = 434.973572
last_received_ts = 434.972992
last_seq = 14335
current_ts = 434.990234
current_received_ts = 434.989655
current_seq = 14336
count = 100
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 26us
(kms_flip:10487) DEBUG: name = flip
last_ts = 434.990234
last_received_ts = 434.989655
last_seq = 14336
current_ts = 435.006897
current_received_ts = 435.006317
current_seq = 14337
count = 101
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 25us
(kms_flip:10487) DEBUG: name = flip
last_ts = 435.006897
last_received_ts = 435.006317
last_seq = 14337
current_ts = 435.023560
current_received_ts = 435.022980
current_seq = 14338
count = 102
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 26us
(kms_flip:10487) DEBUG: name = flip
last_ts = 435.023560
last_received_ts = 435.022980
last_seq = 14338
current_ts = 435.040253
current_received_ts = 435.039642
current_seq = 14339
count = 103
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 24us
(kms_flip:10487) DEBUG: name = flip
last_ts = 435.040253
last_received_ts = 435.039642
last_seq = 14339
current_ts = 435.056915
current_received_ts = 435.056335
current_seq = 14340
count = 104
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 25us
(kms_flip:10487) DEBUG: name = flip
last_ts = 435.056915
last_received_ts = 435.056335
last_seq = 14340
current_ts = 435.073578
current_received_ts = 435.072998
current_seq = 14341
count = 105
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 25us
(kms_flip:10487) DEBUG: name = flip
last_ts = 435.073578
last_received_ts = 435.072998
last_seq = 14341
current_ts = 435.090240
current_received_ts = 435.089661
current_seq = 14342
count = 106
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 25us
(kms_flip:10487) DEBUG: name = flip
last_ts = 435.090240
last_received_ts = 435.089661
last_seq = 14342
current_ts = 435.106903
current_received_ts = 435.106323
current_seq = 14343
count = 107
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 26us
(kms_flip:10487) DEBUG: name = flip
last_ts = 435.106903
last_received_ts = 435.106323
last_seq = 14343
current_ts = 435.123566
current_received_ts = 435.123077
current_seq = 14344
count = 108
seq_step = 1
(kms_flip:10487) DEBUG: Vblank took 683us
(kms_flip:10487) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:926:
(kms_flip:10487) CRITICAL: Failed assertion: end - start < 500
(kms_flip:10487) igt_core-INFO: Stack trace:
(kms_flip:10487) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:10487) igt_core-INFO:   #1 ../tests/kms_flip.c:921 run_test_on_crtc_set.constprop.0()
(kms_flip:10487) igt_core-INFO:   #2 ../tests/kms_flip.c:1972 run_pair()
(kms_flip:10487) igt_core-INFO:   #3 ../tests/kms_flip.c:2129 __igt_unique____real_main2043()
(kms_flip:10487) igt_core-INFO:   #4 ../tests/kms_flip.c:2043 main()
(kms_flip:10487) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:10487) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:10487) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest AC-DP2-HDMI-A3: FAIL (4.124s)
Dmesg
<6> [433.162990] [IGT] kms_flip: starting dynamic subtest AC-DP2-HDMI-A3
<7> [433.163794] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:557]
<7> [433.164113] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:583]
<7> [433.227872] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:150:pipe A]
<7> [433.228070] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [433.228362] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [433.228620] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [433.228901] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [433.229143] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in dp_m_n (expected tu 64 data 7208960/8388608 link 480597/524288, found tu 0, data 0/0 link 0/0)
<7> [433.229378] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in output_types (expected 0x00000080, found 0x00000000)
<7> [433.229594] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [433.229825] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [433.230036] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [433.230238] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [433.230434] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [433.230631] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [433.230839] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [433.231024] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [433.231206] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [433.231382] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [433.231561] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [433.231748] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [433.231920] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [433.232090] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [433.232253] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [433.232415] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [433.232578] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [433.232744] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [433.232903] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [433.233056] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [433.233205] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [433.233355] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [433.233497] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [433.233640] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [433.233798] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [433.233948] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [433.234093] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [433.234232] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [433.234365] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in enhanced_framing (expected yes, found no)
<7> [433.234499] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in pipe_bpp (expected 30, found 0)
<7> [433.234628] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [433.234767] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [433.234892] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in port_clock (expected 162000, found 0)
<7> [433.235018] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:150:pipe A] fastset requirement not met, forcing full modeset
<7> [433.235143] xe 0000:03:00.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:150:pipe A] releasing TC PLL 2
<7> [433.235305] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xf -> 0xf (total dbuf slices 0xf), mbus joined? no->yes
<7> [433.235410] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:150:pipe A] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x3 -> 0x2
<7> [433.235519] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:268:pipe B] dbuf slices 0xc -> 0xf, ddb (0 - 2048) -> (0 - 4096), active pipes 0x3 -> 0x2
<7> [433.235653] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:144:cursor A] ddb (1989 - 2048) -> ( 0 - 0), size 59 -> 0
<7> [433.235773] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:262:cursor B] ddb (1989 - 2048) -> (4037 - 4096), size 59 -> 59
<7> [433.235880] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:150:pipe A] min cdclk: 192000 kHz -> 0 kHz
<7> [433.236022] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:150:pipe A] enable: no [modeset]
<7> [433.236139] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:34:plane 1A] fb: [NOFB], visible: no
<7> [433.236254] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:64:plane 2A] fb: [NOFB], visible: no
<7> [433.236363] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:94:plane 3A] fb: [NOFB], visible: no
<7> [433.236470] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:124:plane 4A] fb: [NOFB], visible: no
<7> [433.236575] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:134:plane 5A] fb: [NOFB], visible: no
<7> [433.236679] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:144:cursor A] fb: [NOFB], visible: no
<7> [433.237261] xe 0000:03:00.0: [drm:intel_audio_codec_disable [xe]] [CONNECTOR:526:DP-2][ENCODER:525:DDI TC2/PHY G] Disable audio codec on [CRTC:150:pipe A]
<7> [433.269597] xe 0000:03:00.0: [drm:intel_disable_transcoder [xe]] disabling pipe A
<7> [433.290168] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling AUX_TC2
<7> [433.292273] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disable TC PLL 2 (active 0x1, on? 1) for [CRTC:150:pipe A]
<7> [433.294489] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disabling TC PLL 2
<7> [433.295154] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:506:DDI TC1/PHY F]
<7> [433.295638] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:508:DP-MST A]
<7> [433.296155] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:509:DP-MST B]
<7> [433.296602] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:510:DP-MST C]
<7> [433.297079] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:511:DP-MST D]
<7> [433.297509] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:525:DDI TC2/PHY G]
<7> [433.297975] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:527:DP-MST A]
<7> [433.298401] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:528:DP-MST B]
<7> [433.298848] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:529:DP-MST C]
<7> [433.299278] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:530:DP-MST D]
<7> [433.299715] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:537:DDI TC3/PHY H]
<7> [433.300140] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:541:DDI TC4/PHY I]
<7> [433.300557] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:543:DP-MST A]
<7> [433.301010] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:544:DP-MST B]
<7> [433.301434] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:545:DP-MST C]
<7> [433.301888] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:546:DP-MST D]
<7> [433.302266] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:526:DP-2]
<7> [433.304349] xe 0000:03:00.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: no -> yes (pipe: B)
<7> [433.304655] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: yes)
<7> [433.306946] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling PW_A
<7> [433.307296] xe 0000:03:00.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating disabled for pipe_mask=0x1 (CLKGATE_DIS_DSSDSC=0xa2000000)
<7> [433.307651] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:150:pipe A]
<7> [433.308013] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:268:pipe B]
<7> [433.308155] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.enable (expected yes, found no)
<7> [433.308428] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.active (expected yes, found no)
<7> [433.308675] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in cpu_transcoder (expected 1, found -1)
<7> [433.308941] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in lane_count (expected 4, found 0)
<7> [433.309169] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [433.309387] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [433.309598] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [433.309830] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [433.310039] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [433.310238] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [433.310424] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [433.310602] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [433.310977] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [433.311144] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [433.311301] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [433.311455] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [433.311604] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [433.311764] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [433.311916] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [433.312054] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [433.312194] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [433.312330] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [433.312465] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [433.312594] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [433.312728] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [433.312855] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [433.312976] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [433.313097] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [433.313219] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [433.313333] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [433.313446] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [433.313553] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [433.313661] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [433.313776] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [433.313883] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [433.313987] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in pipe_bpp (expected 30, found 0)
<7> [433.314089] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [433.314196] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [433.314304] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in port_clock (expected 185625, found 0)
<7> [433.314407] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [433.314510] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in infoframes.gcp (expected 0x00000006, found 0x00000000)
<7> [433.314612] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in avi infoframe
<7> [433.314719] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [433.314821] xe 0000:03:00.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [433.314823] xe 0000:03:00.0: colorspace: RGB
<7> [433.314824] xe 0000:03:00.0: scan mode: Underscan
<7> [433.314825] xe 0000:03:00.0: colorimetry: No Data
<7> [433.314826] xe 0000:03:00.0: picture aspect: 16:9
<7> [433.314828] xe 0000:03:00.0: active aspect: Same as Picture
<7> [433.314829] xe 0000:03:00.0: itc: No Data
<7> [433.314830] xe 0000:03:00.0: extended colorimetry: xvYCC 601
<7> [433.314831] xe 0000:03:00.0: quantization range: Default
<7> [433.314832] xe 0000:03:00.0: nups: Unknown Non-uniform Scaling
<7> [433.314833] xe 0000:03:00.0: video code: 16
<7> [433.314834] xe 0000:03:00.0: ycc quantization range: Full
<7> [433.314835] xe 0000:03:00.0: hdmi content type: Graphics
<7> [433.314836] xe 0000:03:00.0: pixel repeat: 0
<7> [433.314837] xe 0000:03:00.0: bar top 0, bottom 0, left 0, right 0
<7> [433.314839] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [433.314940] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in spd infoframe
<7> [433.315041] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [433.315142] xe 0000:03:00.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [433.315143] xe 0000:03:00.0: vendor: Intel
<7> [433.315144] xe 0000:03:00.0: product: Discrete gfx
<7> [433.315145] xe 0000:03:00.0: source device information: PC General (0x9)
<7> [433.315147] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [433.315248] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hdmi infoframe
<7> [433.315350] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [433.315449] xe 0000:03:00.0: HDMI infoframe: Vendor, version 1, length 4
<7> [433.315451] xe 0000:03:00.0: empty frame
<7> [433.315452] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [433.315557] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:268:pipe B] fastset requirement not met, forcing full modeset
<7> [433.315660] xe 0000:03:00.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:268:pipe B] releasing TC PLL 3
<7> [433.315797] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xf -> 0x1 (total dbuf slices 0xf), mbus joined? yes->no
<7> [433.315887] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:268:pipe B] dbuf slices 0xf -> 0x0, ddb (0 - 4096) -> (0 - 0), active pipes 0x2 -> 0x0
<7> [433.315994] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:262:cursor B] ddb (4037 - 4096) -> ( 0 - 0), size 59 -> 0
<7> [433.316088] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:268:pipe B] min cdclk: 192000 kHz -> 0 kHz
<7> [433.316210] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:268:pipe B] enable: no [modeset]
<7> [433.316321] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:152:plane 1B] fb: [NOFB], visible: no
<7> [433.316426] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:182:plane 2B] fb: [NOFB], visible: no
<7> [433.316531] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:212:plane 3B] fb: [NOFB], visible: no
<7> [433.316634] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:242:plane 4B] fb: [NOFB], visible: no
<7> [433.316746] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:252:plane 5B] fb: [NOFB], visible: no
<7> [433.316853] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:262:cursor B] fb: [NOFB], visible: no
<7> [433.317166] xe 0000:03:00.0: [drm:intel_audio_codec_disable [xe]] [CONNECTOR:538:HDMI-A-3][ENCODER:537:DDI TC3/PHY H] Disable audio codec on [CRTC:268:pipe B]
<7> [433.340664] xe 0000:03:00.0: [drm:intel_disable_transcoder [xe]] disabling pipe B
<7> [433.361307] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disable TC PLL 3 (active 0x2, on? 1) for [CRTC:268:pipe B]
<7> [433.363672] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disabling TC PLL 3
<7> [433.364542] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:506:DDI TC1/PHY F]
<7> [433.365206] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:508:DP-MST A]
<7> [433.365752] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:509:DP-MST B]
<7> [433.366247] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:510:DP-MST C]
<7> [433.366741] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:511:DP-MST D]
<7> [433.367216] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:525:DDI TC2/PHY G]
<7> [433.367720] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:527:DP-MST A]
<7> [433.368226] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:528:DP-MST B]
<7> [433.368669] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:529:DP-MST C]
<7> [433.369152] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:530:DP-MST D]
<7> [433.369578] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:537:DDI TC3/PHY H]
<7> [433.369873] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:541:DDI TC4/PHY I]
<7> [433.369966] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:543:DP-MST A]
<7> [433.370058] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:544:DP-MST B]
<7> [433.370153] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:545:DP-MST C]
<7> [433.370245] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:546:DP-MST D]
<7> [433.370335] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:538:HDMI-A-3]
<7> [433.370448] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 2 (mbus joined: no)
<7> [433.370545] xe 0000:03:00.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: yes -> no (pipe: *)
<7> [433.370632] xe 0000:03:00.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0x1
<7> [433.370814] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling PW_B
<7> [433.370927] 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> [433.371055] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling PW_2
<7> [433.371181] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:268:pipe B]
<7> [433.371378] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:386:pipe C]
<7> [433.371829] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:504:pipe D]
<7> [433.372089] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:386:pipe C]
<7> [433.372107] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CONNECTOR:538:HDMI-A-3]
<7> [433.372160] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CONNECTOR:538:HDMI-A-3] Limiting target display pipe bpp to 30 (EDID bpp 48, max requested bpp 30, max platform bpp 36)
<7> [433.372277] xe 0000:03:00.0: [drm:intel_hdmi_compute_clock [xe]] picking 10 bpc for HDMI output (pipe bpp: 30)
<7> [433.372385] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:386:pipe C] hw max bpp: 30, pipe bpp: 30, dithering: 0
<7> [433.372495] xe 0000:03:00.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:537:DDI TC3/PHY H] [CRTC:386:pipe C]
<7> [433.372604] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.enable (expected no, found yes)
<7> [433.372716] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.active (expected no, found yes)
<7> [433.372826] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in cpu_transcoder (expected -1, found 2)
<7> [433.372935] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in lane_count (expected 0, found 4)
<7> [433.373041] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [433.373145] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [433.373249] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [433.373352] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [433.373456] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [433.373561] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [433.373671] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [433.373786] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [433.373891] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [433.373994] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [433.374098] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [433.374201] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [433.374305] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [433.374406] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [433.374509] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [433.374612] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [433.374719] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [433.374822] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [433.374928] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [433.375035] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [433.375143] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [433.375247] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [433.375351] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [433.375453] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [433.375555] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [433.375657] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [433.375765] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [433.375868] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [433.375972] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [433.376077] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in has_hdmi_sink (expected no, found yes)
<7> [433.376182] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in has_infoframe (expected no, found yes)
<7> [433.376286] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in dpll_hw_state
<7> [433.376388] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [433.376491] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] cx0pll_hw_state: lane_count: 4, ssc_enabled: no, use_c10: no, tbt_mode: no
<7> [433.376593] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] c20pll_hw_state:
<7> [433.376699] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] tx[0] = 0xbe88, tx[1] = 0x5800, tx[2] = 0x0000
<7> [433.376801] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] cmn[0] = 0x0500, cmn[1] = 0x0005, cmn[2] = 0x0000, cmn[3] = 0x0000
<7> [433.376904] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[0] = 0x50a8
<7> [433.377005] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[1] = 0x2120
<7> [433.377104] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[2] = 0xcd9a
<7> [433.377205] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[3] = 0xbfc1
<7> [433.377304] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[4] = 0x5ab8
<7> [433.377406] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[5] = 0x4c34
<7> [433.377507] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[6] = 0x2000
<7> [433.377608] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[7] = 0x0001
<7> [433.377712] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[8] = 0x6000
<7> [433.377811] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[9] = 0x0000
<7> [433.377911] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[10] = 0x0000
<7> [433.378014] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] vdr: custom width: 0x00, serdes rate: 0x40, hdmi rate: 0x00
<7> [433.378120] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [433.378225] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] cx0pll_hw_state: lane_count: 4, ssc_enabled: no, use_c10: no, tbt_mode: no
<7> [433.378328] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] c20pll_hw_state:
<7> [433.378432] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] tx[0] = 0xbe88, tx[1] = 0x5800, tx[2] = 0x0000
<7> [433.378532] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] cmn[0] = 0x0500, cmn[1] = 0x0005, cmn[2] = 0x0000, cmn[3] = 0x0000
<7> [433.378634] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[0] = 0x40c0
<7> [433.378748] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[1] = 0x7d20
<7> [433.378850] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[2] = 0x4a06
<7> [433.378952] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[3] = 0x8f1c
<7> [433.379054] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[4] = 0x0000
<7> [433.379155] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[5] = 0x0000
<7> [433.379258] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[6] = 0x2200
<7> [433.379364] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[7] = 0xffff
<7> [433.379469] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[8] = 0xae00
<7> [433.379574] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[9] = 0x0000
<7> [433.379677] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[10] = 0x0001
<7> [433.379788] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] vdr: custom width: 0x00, serdes rate: 0x00, hdmi rate: 0x00
<7> [433.379899] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in pipe_bpp (expected 0, found 30)
<7> [433.380008] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [433.380113] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [433.380219] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in port_clock (expected 0, found 185625)
<7> [433.380328] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071)
<7> [433.380434] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in infoframes.gcp (expected 0x00000000, found 0x00000006)
<7> [433.380538] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in avi infoframe
<7> [433.380641] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [433.380750] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [433.380853] xe 0000:03:00.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [433.380854] xe 0000:03:00.0: colorspace: RGB
<7> [433.380855] xe 0000:03:00.0: scan mode: Underscan
<7> [433.380857] xe 0000:03:00.0: colorimetry: No Data
<7> [433.380858] xe 0000:03:00.0: picture aspect: 16:9
<7> [433.380859] xe 0000:03:00.0: active aspect: Same as Picture
<7> [433.380860] xe 0000:03:00.0: itc: No Data
<7> [433.380861] xe 0000:03:00.0: extended colorimetry: xvYCC 601
<7> [433.380862] xe 0000:03:00.0: quantization range: Default
<7> [433.380863] xe 0000:03:00.0: nups: Unknown Non-uniform Scaling
<7> [433.380864] xe 0000:03:00.0: video code: 16
<7> [433.380866] xe 0000:03:00.0: ycc quantization range: Full
<7> [433.380867] xe 0000:03:00.0: hdmi content type: Graphics
<7> [433.380868] xe 0000:03:00.0: pixel repeat: 0
<7> [433.380869] xe 0000:03:00.0: bar top 0, bottom 0, left 0, right 0
<7> [433.380870] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in spd infoframe
<7> [433.380972] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [433.381074] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [433.381175] xe 0000:03:00.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [433.381177] xe 0000:03:00.0: vendor: Intel
<7> [433.381178] xe 0000:03:00.0: product: Discrete gfx
<7> [433.381179] xe 0000:03:00.0: source device information: PC General (0x9)
<7> [433.381180] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hdmi infoframe
<7> [433.381282] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [433.381388] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [433.381489] xe 0000:03:00.0: HDMI infoframe: Vendor, version 1, length 4
<7> [433.381490] xe 0000:03:00.0: empty frame
<7> [433.381492] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:386:pipe C] fastset requirement not met, forcing full modeset
<7> [433.381612] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0x1 -> 0xd (total dbuf slices 0xf), mbus joined? no->no
<7> [433.381707] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:386:pipe C] dbuf slices 0x0 -> 0xc, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x4
<7> [433.381819] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:270:plane 1C] ddb ( 0 - 0) -> ( 0 - 1989), size 0 -> 1989
<7> [433.381907] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:380:cursor C] ddb ( 0 - 0) -> (1989 - 2048), size 0 -> 59
<7> [433.381993] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:270: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> [433.382078] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:270: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> [433.382164] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:270: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> [433.382247] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:270: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> [433.382338] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] [CRTC:386:pipe C] data rate 594000 num active planes 1
<7> [433.382448] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 33600 required 624 qgv_peak_bw: 48000
<7> [433.382553] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 53000 required 624 qgv_peak_bw: 48000
<7> [433.382659] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 48000 for required data rate: 624
<7> [433.382812] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:386:pipe C] min cdclk: 0 kHz -> 192000 kHz
<7> [433.382931] xe 0000:03:00.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [xe]] dbuf bandwidth min cdclk: 18563 kHz -> 9282 kHz
<7> [433.383048] xe 0000:03:00.0: [drm:intel_find_dpll [xe]] [CRTC:386:pipe C] allocated TC PLL 3
<7> [433.383156] xe 0000:03:00.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:386:pipe C] reserving TC PLL 3
<7> [433.383264] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:386:pipe C] enable: yes [modeset]
<7> [433.383373] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [433.383480] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: C, pipe bpp: 30, dithering: 0
<7> [433.383586] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [433.383689] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [433.383805] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [433.383912] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [433.384015] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [433.384119] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] GCP: 0x6
<7> [433.384221] xe 0000:03:00.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [433.384223] xe 0000:03:00.0: colorspace: RGB
<7> [433.384224] xe 0000:03:00.0: scan mode: Underscan
<7> [433.384225] xe 0000:03:00.0: colorimetry: No Data
<7> [433.384226] xe 0000:03:00.0: picture aspect: 16:9
<7> [433.384227] xe 0000:03:00.0: active aspect: Same as Picture
<7> [433.384228] xe 0000:03:00.0: itc: No Data
<7> [433.384229] xe 0000:03:00.0: extended colorimetry: xvYCC 601
<7> [433.384230] xe 0000:03:00.0: quantization range: Default
<7> [433.384231] xe 0000:03:00.0: nups: Unknown Non-uniform Scaling
<7> [433.384233] xe 0000:03:00.0: video code: 16
<7> [433.384234] xe 0000:03:00.0: ycc quantization range: Full
<7> [433.384235] xe 0000:03:00.0: hdmi content type: Graphics
<7> [433.384236] xe 0000:03:00.0: pixel repeat: 0
<7> [433.384237] xe 0000:03:00.0: bar top 0, bottom 0, left 0, right 0
<7> [433.384238] xe 0000:03:00.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [433.384239] xe 0000:03:00.0: vendor: Intel
<7> [433.384241] xe 0000:03:00.0: product: Discrete gfx
<7> [433.384242] xe 0000:03:00.0: source device information: PC General (0x9)
<7> [433.384243] xe 0000:03:00.0: HDMI infoframe: Vendor, version 1, length 4
<7> [433.384244] xe 0000:03:00.0: empty frame
<7> [433.384245] 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> [433.384349] 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> [433.384450] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 07 00 00 00
<7> [433.384551] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [433.384652] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [433.384759] 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> [433.384860] 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> [433.384961] 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> [433.385061] 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> [433.385161] 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> [433.385261] 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> [433.385363] 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> [433.385465] 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> [433.385566] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port clock: 185625, pipe src: 1920x1080+0+0, pixel rate 148500, min cdclk 192000
<7> [433.385667] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] linetime: 119, ips linetime: 0
<7> [433.385777] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [433.385882] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [433.385985] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [433.386086] 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> [433.386186] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] c20pll_hw_state:
<7> [433.386287] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] tx[0] = 0xbe88, tx[1] = 0x5800, tx[2] = 0x0000
<7> [433.386389] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cmn[0] = 0x0500, cmn[1] = 0x0005, cmn[2] = 0x0000, cmn[3] = 0x0000
<7> [433.386492] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[0] = 0x40c0
<7> [433.386593] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[1] = 0x7d20
<7> [433.386694] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[2] = 0x4a06
<7> [433.386805] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[3] = 0x8f1c
<7> [433.386905] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[4] = 0x0000
<7> [433.387004] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[5] = 0x0000
<7> [433.387102] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[6] = 0x2200
<7> [433.387200] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[7] = 0xffff
<7> [433.387299] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[8] = 0xae00
<7> [433.387397] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[9] = 0x0000
<7> [433.387496] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[10] = 0x0001
<7> [433.387596] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vdr: custom width: 0x00, serdes rate: 0x00, hdmi rate: 0x00
<7> [433.387700] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [433.387799] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [433.387898] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [433.387997] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [433.388096] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [433.388195] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [433.388293] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [433.388392] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [433.388490] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [433.388589] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [433.388693] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [433.388806] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [433.388908] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [433.389008] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:270:plane 1C] fb: [FB:557] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [433.389110] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [433.389211] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [433.389312] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:300:plane 2C] fb: [NOFB], visible: no
<7> [433.389412] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:330:plane 3C] fb: [NOFB], visible: no
<7> [433.389511] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:360:plane 4C] fb: [NOFB], visible: no
<7> [433.389611] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:370:plane 5C] fb: [NOFB], visible: no
<7> [433.389713] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:380:cursor C] fb: [NOFB], visible: no
<7> [433.390115] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling PW_2
<7> [433.390263] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling PW_C
<7> [433.390416] xe 0000:03:00.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating enabled for pipe_mask=0x4 (CLKGATE_DIS_DSSDSC=0x8a000000)
<7> [433.390534] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:506:DDI TC1/PHY F]
<7> [433.390635] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:508:DP-MST A]
<7> [433.390747] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:509:DP-MST B]
<7> [433.390844] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:510:DP-MST C]
<7> [433.390938] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:511:DP-MST D]
<7> [433.391030] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:525:DDI TC2/PHY G]
<7> [433.391121] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:527:DP-MST A]
<7> [433.391211] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:528:DP-MST B]
<7> [433.391301] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:529:DP-MST C]
<7> [433.391393] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:530:DP-MST D]
<7> [433.391488] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:537:DDI TC3/PHY H]
<7> [433.391583] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:541:DDI TC4/PHY I]
<7> [433.391676] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:543:DP-MST A]
<7> [433.391776] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:544:DP-MST B]
<7> [433.391865] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:545:DP-MST C]
<7> [433.391955] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:546:DP-MST D]
<7> [433.392056] xe 0000:03:00.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xd
<7> [433.392231] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 2 (mbus joined: no)
<7> [433.392338] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enable TC PLL 3 (active 0x4, on? 0) for [CRTC:386:pipe C]
<7> [433.392442] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enabling TC PLL 3
<7> [433.394952] xe 0000:03:00.0: [drm:intel_enable_transcoder [xe]] enabling pipe C
<7> [433.395813] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling AUX_TC2
<7> [433.413331] xe 0000:03:00.0: [drm:intel_audio_codec_enable [xe]] [CONNECTOR:538:HDMI-A-3][ENCODER:537:DDI TC3/PHY H] Enable audio codec on [CRTC:386:pipe C], 36 bytes ELD
<7> [433.429880] xe 0000:03:00.0: [drm:audio_config_hdmi_pixel_clock [xe]] Configuring HDMI audio for pixel clock 148500 (0x00090000)
<7> [433.430111] xe 0000:03:00.0: [drm:hsw_audio_config_update [xe]] using automatic N
<7> [433.430465] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:538:HDMI-A-3]
<7> [433.430693] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:386:pipe C]
<7> [433.434620] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:150:pipe A]
<7> [433.434662] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CONNECTOR:526:DP-2]
<7> [433.434805] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CONNECTOR:526:DP-2] Limiting target display pipe bpp to 30 (EDID bpp 48, max requested bpp 30, max platform bpp 36)
<7> [433.435066] xe 0000:03:00.0: [drm:intel_dp_compute_config_limits [xe]] [ENCODER:525:DDI TC2/PHY G][CRTC:150:pipe A] DP link limits: pixel clock 148500 kHz DSC off max lanes 4 max rate 540000 max pipe_bpp 30 min link_bpp 18.0000 max link_bpp 30.0000
<7> [433.435318] xe 0000:03:00.0: [drm:intel_dp_compute_link_config [xe]] DP lane count 4 clock 162000 bpp input 30 compressed 0.0000 HDR no link rate required 556875 available 648000
<7> [433.435555] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:150:pipe A] hw max bpp: 30, pipe bpp: 30, dithering: 0
<7> [433.435818] xe 0000:03:00.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:525:DDI TC2/PHY G] [CRTC:150:pipe A]
<7> [433.436049] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [433.436276] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [433.436497] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [433.436715] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [433.436919] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in dp_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 7208960/8388608 link 480597/524288)
<7> [433.437124] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000080)
<7> [433.437314] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [433.437505] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [433.437702] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [433.437888] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [433.438065] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [433.438242] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [433.438417] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [433.438588] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [433.438766] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [433.438930] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [433.439091] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [433.439246] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [433.439400] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [433.439555] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [433.439714] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [433.439863] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [433.440012] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [433.440155] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [433.440297] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [433.440434] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [433.440572] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [433.440717] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [433.440847] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [433.440978] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [433.441104] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [433.441231] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [433.441353] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [433.441476] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [433.441598] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in enhanced_framing (expected no, found yes)
<7> [433.441726] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 30)
<7> [433.441859] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [433.441986] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [433.442106] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:150:pipe A] fastset requirement not met in port_clock (expected 0, found 162000)
<7> [433.442224] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:150:pipe A] fastset requirement not met, forcing full modeset
<7> [433.442363] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xd -> 0xf (total dbuf slices 0xf), mbus joined? no->no
<7> [433.442466] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:150:pipe A] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x4 -> 0x5
<7> [433.442583] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:34:plane 1A] ddb ( 0 - 0) -> ( 0 - 1989), size 0 -> 1989
<7> [433.442674] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:144:cursor A] ddb ( 0 - 0) -> (1989 - 2048), size 0 -> 59
<7> [433.442773] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:34:plane 1A] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm
<7> [433.442861] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:34:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 4, 4, 9, 9, 17, 17, 17, 17, 0, 15, 0
<7> [433.442949] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:34:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 62, 62, 139, 139, 261, 261, 261, 261, 137, 230, 244
<7> [433.443034] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:34:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 123, 123, 245, 245, 367, 367, 0, 0, 138, 306, 306
<7> [433.443131] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] [CRTC:150:pipe A] data rate 594000 num active planes 1
<7> [433.443240] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 33600 required 1248 qgv_peak_bw: 48000
<7> [433.443346] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 53000 required 1248 qgv_peak_bw: 48000
<7> [433.443452] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 48000 for required data rate: 1248
<7> [433.443567] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:150:pipe A] min cdclk: 0 kHz -> 192000 kHz
<7> [433.443681] xe 0000:03:00.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [xe]] dbuf bandwidth min cdclk: 9282 kHz -> 18563 kHz
<7> [433.443809] xe 0000:03:00.0: [drm:intel_find_dpll [xe]] [CRTC:150:pipe A] allocated TC PLL 2
<7> [433.443914] xe 0000:03:00.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:150:pipe A] reserving TC PLL 2
<7> [433.444015] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:150:pipe A] enable: yes [modeset]
<7> [433.444124] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: DP (0x80), output format: RGB, sink format: RGB
<7> [433.444230] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: A, pipe bpp: 30, dithering: 0
<7> [433.444334] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [433.444437] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [433.444540] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [433.444643] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [433.444756] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] dp m_n: lanes: 4; data_m: 7208960, data_n: 8388608, link_m: 480597, link_n: 524288, tu: 64
<7> [433.444863] 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> [433.444966] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: enabled
<7> [433.445068] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled
<7> [433.445170] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] psr: disabled, selective update: disabled, panel replay: disabled, selective fetch: disabled
<7> [433.445271] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] minimum HBlank: 0
<7> [433.445372] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] audio: 1, infoframes: 0, infoframes enabled: 0x0
<7> [433.445473] 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> [433.445575] 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> [433.445675] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 17 50 51 07 00 00 00 00
<7> [433.445810] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [433.445911] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [433.446010] 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> [433.446110] 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> [433.446211] 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> [433.446311] 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> [433.446412] 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> [433.446512] 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> [433.446612] 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> [433.446737] 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> [433.446844] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port clock: 162000, pipe src: 1920x1080+0+0, pixel rate 148500, min cdclk 192000
<7> [433.446948] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] linetime: 119, ips linetime: 0
<7> [433.447053] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [433.447160] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [433.447264] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [433.447366] 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> [433.447466] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] c20pll_hw_state:
<7> [433.447565] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] tx[0] = 0xbe88, tx[1] = 0x5800, tx[2] = 0x0000
<7> [433.447667] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cmn[0] = 0x0500, cmn[1] = 0x0005, cmn[2] = 0x0000, cmn[3] = 0x0000
<7> [433.447775] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[0] = 0x50a8
<7> [433.447875] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[1] = 0x2120
<7> [433.447975] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[2] = 0xcd9a
<7> [433.448075] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[3] = 0xbfc1
<7> [433.448173] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[4] = 0x5ab8
<7> [433.448273] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[5] = 0x4c34
<7> [433.448373] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[6] = 0x2000
<7> [433.448472] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[7] = 0x0001
<7> [433.448576] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[8] = 0x6000
<7> [433.448679] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[9] = 0x0000
<7> [433.448791] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[10] = 0x0000
<7> [433.448893] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vdr: custom width: 0x00, serdes rate: 0x40, hdmi rate: 0x00
<7> [433.448993] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [433.449094] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [433.449192] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [433.449292] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [433.449391] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [433.449491] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [433.449591] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [433.449689] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [433.449800] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [433.449899] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [433.450004] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [433.450106] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [433.450206] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [433.450306] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:34:plane 1A] fb: [FB:557] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [433.450407] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [433.450513] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [433.450619] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:64:plane 2A] fb: [NOFB], visible: no
<7> [433.450739] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:94:plane 3A] fb: [NOFB], visible: no
<7> [433.450841] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:124:plane 4A] fb: [NOFB], visible: no
<7> [433.450942] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:134:plane 5A] fb: [NOFB], visible: no
<7> [433.451041] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:144:cursor A] fb: [NOFB], visible: no
<7> [433.451449] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling PW_A
<7> [433.451606] xe 0000:03:00.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating enabled for pipe_mask=0x1 (CLKGATE_DIS_DSSDSC=0x88000000)
<7> [433.451757] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:506:DDI TC1/PHY F]
<7> [433.451858] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:508:DP-MST A]
<7> [433.451956] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:509:DP-MST B]
<7> [433.452051] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:510:DP-MST C]
<7> [433.452144] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:511:DP-MST D]
<7> [433.452236] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:525:DDI TC2/PHY G]
<7> [433.452328] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:527:DP-MST A]
<7> [433.452422] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:528:DP-MST B]
<7> [433.452516] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:529:DP-MST C]
<7> [433.452608] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:530:DP-MST D]
<7> [433.452704] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:537:DDI TC3/PHY H]
<7> [433.452796] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:541:DDI TC4/PHY I]
<7> [433.452885] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:543:DP-MST A]
<7> [433.452974] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:544:DP-MST B]
<7> [433.453063] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:545:DP-MST C]
<7> [433.453153] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:546:DP-MST D]
<7> [433.454758] xe 0000:03:00.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xf
<7> [433.454934] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 2 (mbus joined: no)
<7> [433.455050] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enable TC PLL 2 (active 0x1, on? 0) for [CRTC:150:pipe A]
<7> [433.455156] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enabling TC PLL 2
<7> [433.457567] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling AUX_TC2
<7> [433.460338] xe 0000:03:00.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX USBC2/DDI TC2/PHY G: DPCD: 12 14 c4 01 01 15 01 81 00 01 04 01 0f 00 01
<7> [433.463297] xe 0000:03:00.0: [drm:intel_dp_init_lttpr_and_dprx_caps [xe]] [CONNECTOR:526:DP-2][ENCODER:525:DDI TC2/PHY G][DPRX] LTTPR common capabilities: 00 00 00 00 00 00 00 00
<7> [433.463882] xe 0000:03:00.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX USBC2/DDI TC2/PHY G: DPCD: 12 14 c4 01 01 15 01 81 00 01 04 01 0f 00 01
<7> [433.464316] xe 0000:03:00.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:526:DP-2][ENCODER:525:DDI TC2/PHY G][DPRX] Using LINK_BW_SET value 06
<7> [433.465172] xe 0000:03:00.0: [drm:intel_dp_set_signal_levels [xe]] [CONNECTOR:526:DP-2][ENCODER:525:DDI TC2/PHY G][DPRX] 8b/10b, lanes: 4, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0
<7> [433.465527] xe 0000:03:00.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:526:DP-2][ENCODER:525:DDI TC2/PHY G][DPRX] Using DP training pattern TPS1
<7> [433.471253] xe 0000:03:00.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:526:DP-2][ENCODER:525:DDI TC2/PHY G][DPRX] Clock recovery OK
<7> [433.471348] xe 0000:03:00.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:526:DP-2][ENCODER:525:DDI TC2/PHY G][DPRX] Using DP training pattern TPS3
<7> [433.477362] xe 0000:03:00.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:526:DP-2][ENCODER:525:DDI TC2/PHY G][DPRX] Channel EQ done. DP Training successful
<7> [433.477578] xe 0000:03:00.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:526:DP-2][ENCODER:525:DDI TC2/PHY G][DPRX] Link Training passed at link rate = 162000, lane count = 4
<7> [433.478546] xe 0000:03:00.0: [drm:intel_enable_transcoder [xe]] enabling pipe A
<7> [433.495801] xe 0000:03:00.0: [drm:intel_audio_codec_enable [xe]] [CONNECTOR:526:DP-2][ENCODER:525:DDI TC2/PHY G] Enable audio codec on [CRTC:150:pipe A], 40 bytes ELD
<7> [433.512461] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:526:DP-2]
<7> [433.513173] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:150:pipe A]
<7> [433.579837] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling AUX_TC2
<7> [435.407225] xe 0000:03:00.0: [drm:xe_hwmon_read [xe]] thermal data for group 0 val 0x2f2f2b2c
<7> [435.407380] xe 0000:03:00.0: [drm:xe_hwmon_read [xe]] thermal data for group 1 val 0x2f302f31
<7> [435.507729] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling AUX_TC2
<7> [435.612749] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling AUX_TC2
<6> [437.289119] [IGT] kms_flip: finished subtest AC-DP2-HDMI-A3, FAIL
Created at 2026-03-06 07:33:35