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

Result: Fail

i915_display_info4 igt_runner4 results4.json results4-xe-load.json guc_logs4.tar i915_display_info_post_exec4 serial_data4 boot4 dmesg4

DetailValue
Duration 5.03 seconds
Hostname 
shard-bmg-8
Igt-Version 
IGT-Version: 2.4-g98b65acc4 (x86_64) (Linux: 7.1.0-rc4-lgci-xe-xe-pw-167105v2-debug+ x86_64)
Out 
Starting dynamic subtest: BC-DP2-HDMI-A3
  1920x1080: 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5 
  1920x1080: 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9 
Stack trace:
  #0 ../lib/igt_core.c:2106 __igt_fail_assert()
  #1 ../tests/kms_flip.c:926 run_test_on_crtc_set()
  #2 ../tests/kms_flip.c:2029 run_pair()
  #3 ../tests/kms_flip.c:2193 __igt_unique____real_main2105()
  #4 ../tests/kms_flip.c:2105 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest BC-DP2-HDMI-A3: FAIL (5.025s)
Err 
Starting dynamic subtest: BC-DP2-HDMI-A3
[463.821915] (kms_flip:9676) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931:
[463.822362] (kms_flip:9676) CRITICAL: Failed assertion: end - start < 500
Dynamic subtest BC-DP2-HDMI-A3 failed.
**** DEBUG ****
[461.804372] (kms_flip:9676) DEBUG: name = flip
last_ts = 463.233856
last_received_ts = 463.233246
last_seq = 1348
current_ts = 463.250519
current_received_ts = 463.249908
current_seq = 1349
count = 85
seq_step = 1
[461.804403] (kms_flip:9676) DEBUG: Vblank took 26us
[461.821042] (kms_flip:9676) DEBUG: name = flip
last_ts = 463.250519
last_received_ts = 463.249908
last_seq = 1349
current_ts = 463.267181
current_received_ts = 463.266571
current_seq = 1350
count = 86
seq_step = 1
[461.821072] (kms_flip:9676) DEBUG: Vblank took 25us
[461.837708] (kms_flip:9676) DEBUG: name = flip
last_ts = 463.267181
last_received_ts = 463.266571
last_seq = 1350
current_ts = 463.283844
current_received_ts = 463.283264
current_seq = 1351
count = 87
seq_step = 1
[461.837738] (kms_flip:9676) DEBUG: Vblank took 26us
[461.854377] (kms_flip:9676) DEBUG: name = flip
last_ts = 463.283844
last_received_ts = 463.283264
last_seq = 1351
current_ts = 463.300507
current_received_ts = 463.299927
current_seq = 1352
count = 88
seq_step = 1
[461.854408] (kms_flip:9676) DEBUG: Vblank took 25us
[461.871046] (kms_flip:9676) DEBUG: name = flip
last_ts = 463.300507
last_received_ts = 463.299927
last_seq = 1352
current_ts = 463.317169
current_received_ts = 463.316589
current_seq = 1353
count = 89
seq_step = 1
[461.871076] (kms_flip:9676) DEBUG: Vblank took 25us
[461.887903] (kms_flip:9676) DEBUG: name = flip
last_ts = 463.317169
last_received_ts = 463.316589
last_seq = 1353
current_ts = 463.333862
current_received_ts = 463.333435
current_seq = 1354
count = 90
seq_step = 1
[461.887933] (kms_flip:9676) DEBUG: Vblank took 26us
[461.904543] (kms_flip:9676) DEBUG: name = flip
last_ts = 463.333862
last_received_ts = 463.333435
last_seq = 1354
current_ts = 463.350525
current_received_ts = 463.350098
current_seq = 1355
count = 91
seq_step = 1
[461.904573] (kms_flip:9676) DEBUG: Vblank took 26us
[461.921210] (kms_flip:9676) DEBUG: name = flip
last_ts = 463.350525
last_received_ts = 463.350098
last_seq = 1355
current_ts = 463.367188
current_received_ts = 463.366760
current_seq = 1356
count = 92
seq_step = 1
[461.921241] (kms_flip:9676) DEBUG: Vblank took 26us
[461.937878] (kms_flip:9676) DEBUG: name = flip
last_ts = 463.367188
last_received_ts = 463.366760
last_seq = 1356
current_ts = 463.383850
current_received_ts = 463.383423
current_seq = 1357
count = 93
seq_step = 1
[461.937907] (kms_flip:9676) DEBUG: Vblank took 25us
[461.954552] (kms_flip:9676) DEBUG: name = flip
last_ts = 463.383850
last_received_ts = 463.383423
last_seq = 1357
current_ts = 463.400513
current_received_ts = 463.400085
current_seq = 1358
count = 94
seq_step = 1
[461.954582] (kms_flip:9676) DEBUG: Vblank took 26us
[461.971115] (kms_flip:9676) DEBUG: name = flip
last_ts = 463.400513
last_received_ts = 463.400085
last_seq = 1358
current_ts = 463.417206
current_received_ts = 463.416656
current_seq = 1359
count = 95
seq_step = 1
[461.971144] (kms_flip:9676) DEBUG: Vblank took 25us
[461.987780] (kms_flip:9676) DEBUG: name = flip
last_ts = 463.417206
last_received_ts = 463.416656
last_seq = 1359
current_ts = 463.433868
current_received_ts = 463.433319
current_seq = 1360
count = 96
seq_step = 1
[461.987809] (kms_flip:9676) DEBUG: Vblank took 25us
[462.004474] (kms_flip:9676) DEBUG: name = flip
last_ts = 463.433868
last_received_ts = 463.433319
last_seq = 1360
current_ts = 463.450531
current_received_ts = 463.449982
current_seq = 1361
count = 97
seq_step = 1
[462.004504] (kms_flip:9676) DEBUG: Vblank took 25us
[462.021118] (kms_flip:9676) DEBUG: name = flip
last_ts = 463.450531
last_received_ts = 463.449982
last_seq = 1361
current_ts = 463.467194
current_received_ts = 463.466675
current_seq = 1362
count = 98
seq_step = 1
[462.021148] (kms_flip:9676) DEBUG: Vblank took 25us
[462.037786] (kms_flip:9676) DEBUG: name = flip
last_ts = 463.467194
last_received_ts = 463.466675
last_seq = 1362
current_ts = 463.483856
current_received_ts = 463.483337
current_seq = 1363
count = 99
seq_step = 1
[462.037815] (kms_flip:9676) DEBUG: Vblank took 25us
[462.054473] (kms_flip:9676) DEBUG: name = flip
last_ts = 463.483856
last_received_ts = 463.483337
last_seq = 1363
current_ts = 463.500549
current_received_ts = 463.500000
current_seq = 1364
count = 100
seq_step = 1
[462.054504] (kms_flip:9676) DEBUG: Vblank took 25us
[462.071120] (kms_flip:9676) DEBUG: name = flip
last_ts = 463.500549
last_received_ts = 463.500000
last_seq = 1364
current_ts = 463.517212
current_received_ts = 463.516663
current_seq = 1365
count = 101
seq_step = 1
[462.071149] (kms_flip:9676) DEBUG: Vblank took 25us
[462.087790] (kms_flip:9676) DEBUG: name = flip
last_ts = 463.517212
last_received_ts = 463.516663
last_seq = 1365
current_ts = 463.533875
current_received_ts = 463.533325
current_seq = 1366
count = 102
seq_step = 1
[462.087820] (kms_flip:9676) DEBUG: Vblank took 25us
[462.104474] (kms_flip:9676) DEBUG: name = flip
last_ts = 463.533875
last_received_ts = 463.533325
last_seq = 1366
current_ts = 463.550537
current_received_ts = 463.549988
current_seq = 1367
count = 103
seq_step = 1
[462.104505] (kms_flip:9676) DEBUG: Vblank took 26us
[462.121123] (kms_flip:9676) DEBUG: name = flip
last_ts = 463.550537
last_received_ts = 463.549988
last_seq = 1367
current_ts = 463.567200
current_received_ts = 463.566681
current_seq = 1368
count = 104
seq_step = 1
[462.121153] (kms_flip:9676) DEBUG: Vblank took 25us
[462.137792] (kms_flip:9676) DEBUG: name = flip
last_ts = 463.567200
last_received_ts = 463.566681
last_seq = 1368
current_ts = 463.583862
current_received_ts = 463.583344
current_seq = 1369
count = 105
seq_step = 1
[462.137821] (kms_flip:9676) DEBUG: Vblank took 25us
[462.154479] (kms_flip:9676) DEBUG: name = flip
last_ts = 463.583862
last_received_ts = 463.583344
last_seq = 1369
current_ts = 463.600555
current_received_ts = 463.600037
current_seq = 1370
count = 106
seq_step = 1
[462.154509] (kms_flip:9676) DEBUG: Vblank took 25us
[462.171126] (kms_flip:9676) DEBUG: name = flip
last_ts = 463.600555
last_received_ts = 463.600037
last_seq = 1370
current_ts = 463.617188
current_received_ts = 463.616669
current_seq = 1371
count = 107
seq_step = 1
[462.171155] (kms_flip:9676) DEBUG: Vblank took 25us
[462.187884] (kms_flip:9676) DEBUG: name = flip
last_ts = 463.617188
last_received_ts = 463.616669
last_seq = 1371
current_ts = 463.633881
current_received_ts = 463.633423
current_seq = 1372
count = 108
seq_step = 1
[462.187914] (kms_flip:9676) DEBUG: Vblank took 25us
[462.204556] (kms_flip:9676) DEBUG: name = flip
last_ts = 463.633881
last_received_ts = 463.633423
last_seq = 1372
current_ts = 463.650543
current_received_ts = 463.650116
current_seq = 1373
count = 109
seq_step = 1
[462.204586] (kms_flip:9676) DEBUG: Vblank took 25us
[462.221234] (kms_flip:9676) DEBUG: name = flip
last_ts = 463.650543
last_received_ts = 463.650116
last_seq = 1373
current_ts = 463.667206
current_received_ts = 463.666779
current_seq = 1374
count = 110
seq_step = 1
[462.221263] (kms_flip:9676) DEBUG: Vblank took 26us
[462.237889] (kms_flip:9676) DEBUG: name = flip
last_ts = 463.667206
last_received_ts = 463.666779
last_seq = 1374
current_ts = 463.683868
current_received_ts = 463.683441
current_seq = 1375
count = 111
seq_step = 1
[462.237919] (kms_flip:9676) DEBUG: Vblank took 25us
[462.254583] (kms_flip:9676) DEBUG: name = flip
last_ts = 463.683868
last_received_ts = 463.683441
last_seq = 1375
current_ts = 463.700531
current_received_ts = 463.700134
current_seq = 1376
count = 112
seq_step = 1
[462.254612] (kms_flip:9676) DEBUG: Vblank took 25us
[462.271225] (kms_flip:9676) DEBUG: name = flip
last_ts = 463.700531
last_received_ts = 463.700134
last_seq = 1376
current_ts = 463.717194
current_received_ts = 463.716766
current_seq = 1377
count = 113
seq_step = 1
[462.271256] (kms_flip:9676) DEBUG: Vblank took 26us
[462.287830] (kms_flip:9676) DEBUG: name = flip
last_ts = 463.717194
last_received_ts = 463.716766
last_seq = 1377
current_ts = 463.733887
current_received_ts = 463.733368
current_seq = 1378
count = 114
seq_step = 1
[462.287861] (kms_flip:9676) DEBUG: Vblank took 26us
[462.304589] (kms_flip:9676) DEBUG: name = flip
last_ts = 463.733887
last_received_ts = 463.733368
last_seq = 1378
current_ts = 463.750549
current_received_ts = 463.750122
current_seq = 1379
count = 115
seq_step = 1
[462.304619] (kms_flip:9676) DEBUG: Vblank took 26us
[462.321236] (kms_flip:9676) DEBUG: name = flip
last_ts = 463.750549
last_received_ts = 463.750122
last_seq = 1379
current_ts = 463.767212
current_received_ts = 463.766785
current_seq = 1380
count = 116
seq_step = 1
[462.321266] (kms_flip:9676) DEBUG: Vblank took 25us
[462.337970] (kms_flip:9676) DEBUG: name = flip
last_ts = 463.767212
last_received_ts = 463.766785
last_seq = 1380
current_ts = 463.783875
current_received_ts = 463.783508
current_seq = 1381
count = 117
seq_step = 1
[462.338017] (kms_flip:9676) DEBUG: Vblank took 34us
[462.354697] (kms_flip:9676) DEBUG: name = flip
last_ts = 463.783875
last_received_ts = 463.783508
last_seq = 1381
current_ts = 463.800537
current_received_ts = 463.800232
current_seq = 1382
count = 118
seq_step = 1
[462.354744] (kms_flip:9676) DEBUG: Vblank took 34us
[462.371317] (kms_flip:9676) DEBUG: name = flip
last_ts = 463.800537
last_received_ts = 463.800232
last_seq = 1382
current_ts = 463.817230
current_received_ts = 463.816864
current_seq = 1383
count = 119
seq_step = 1
[462.371364] (kms_flip:9676) DEBUG: Vblank took 33us
[462.387869] (kms_flip:9676) DEBUG: name = flip
last_ts = 463.817230
last_received_ts = 463.816864
last_seq = 1383
current_ts = 463.833893
current_received_ts = 463.833405
current_seq = 1384
count = 120
seq_step = 1
[462.387917] (kms_flip:9676) DEBUG: Vblank took 34us
[462.404549] (kms_flip:9676) DEBUG: name = flip
last_ts = 463.833893
last_received_ts = 463.833405
last_seq = 1384
current_ts = 463.850555
current_received_ts = 463.850098
current_seq = 1385
count = 121
seq_step = 1
[462.404582] (kms_flip:9676) DEBUG: Vblank took 27us
[462.421178] (kms_flip:9676) DEBUG: name = flip
last_ts = 463.850555
last_received_ts = 463.850098
last_seq = 1385
current_ts = 463.867218
current_received_ts = 463.866730
current_seq = 1386
count = 122
seq_step = 1
[462.421209] (kms_flip:9676) DEBUG: Vblank took 26us
[462.437833] (kms_flip:9676) DEBUG: name = flip
last_ts = 463.867218
last_received_ts = 463.866730
last_seq = 1386
current_ts = 463.883881
current_received_ts = 463.883392
current_seq = 1387
count = 123
seq_step = 1
[462.437863] (kms_flip:9676) DEBUG: Vblank took 25us
[462.454531] (kms_flip:9676) DEBUG: name = flip
last_ts = 463.883881
last_received_ts = 463.883392
last_seq = 1387
current_ts = 463.900543
current_received_ts = 463.900085
current_seq = 1388
count = 124
seq_step = 1
[462.454567] (kms_flip:9676) DEBUG: Vblank took 28us
[462.471181] (kms_flip:9676) DEBUG: name = flip
last_ts = 463.900543
last_received_ts = 463.900085
last_seq = 1388
current_ts = 463.917206
current_received_ts = 463.916718
current_seq = 1389
count = 125
seq_step = 1
[462.471213] (kms_flip:9676) DEBUG: Vblank took 26us
[462.487834] (kms_flip:9676) DEBUG: name = flip
last_ts = 463.917206
last_received_ts = 463.916718
last_seq = 1389
current_ts = 463.933899
current_received_ts = 463.933380
current_seq = 1390
count = 126
seq_step = 1
[462.487864] (kms_flip:9676) DEBUG: Vblank took 25us
[462.504529] (kms_flip:9676) DEBUG: name = flip
last_ts = 463.933899
last_received_ts = 463.933380
last_seq = 1390
current_ts = 463.950562
current_received_ts = 463.950073
current_seq = 1391
count = 127
seq_step = 1
[462.504558] (kms_flip:9676) DEBUG: Vblank took 24us
[462.521169] (kms_flip:9676) DEBUG: name = flip
last_ts = 463.950562
last_received_ts = 463.950073
last_seq = 1391
current_ts = 463.967224
current_received_ts = 463.966705
current_seq = 1392
count = 128
seq_step = 1
[462.521199] (kms_flip:9676) DEBUG: Vblank took 25us
[462.537837] (kms_flip:9676) DEBUG: name = flip
last_ts = 463.967224
last_received_ts = 463.966705
last_seq = 1392
current_ts = 463.983887
current_received_ts = 463.983398
current_seq = 1393
count = 129
seq_step = 1
[462.537866] (kms_flip:9676) DEBUG: Vblank took 25us
[462.554517] (kms_flip:9676) DEBUG: name = flip
last_ts = 463.983887
last_received_ts = 463.983398
last_seq = 1393
current_ts = 464.000549
current_received_ts = 464.000061
current_seq = 1394
count = 130
seq_step = 1
[462.554548] (kms_flip:9676) DEBUG: Vblank took 24us
[462.571179] (kms_flip:9676) DEBUG: name = flip
last_ts = 464.000549
last_received_ts = 464.000061
last_seq = 1394
current_ts = 464.017212
current_received_ts = 464.016724
current_seq = 1395
count = 131
seq_step = 1
[462.571214] (kms_flip:9676) DEBUG: Vblank took 27us
[462.587841] (kms_flip:9676) DEBUG: name = flip
last_ts = 464.017212
last_received_ts = 464.016724
last_seq = 1395
current_ts = 464.033905
current_received_ts = 464.033386
current_seq = 1396
count = 132
seq_step = 1
[462.587872] (kms_flip:9676) DEBUG: Vblank took 26us
[462.604540] (kms_flip:9676) DEBUG: name = flip
last_ts = 464.033905
last_received_ts = 464.033386
last_seq = 1396
current_ts = 464.050568
current_received_ts = 464.050079
current_seq = 1397
count = 133
seq_step = 1
[462.604569] (kms_flip:9676) DEBUG: Vblank took 25us
[462.621186] (kms_flip:9676) DEBUG: name = flip
last_ts = 464.050568
last_received_ts = 464.050079
last_seq = 1397
current_ts = 464.067230
current_received_ts = 464.066742
current_seq = 1398
count = 134
seq_step = 1
[462.621216] (kms_flip:9676) DEBUG: Vblank took 25us
[462.637839] (kms_flip:9676) DEBUG: name = flip
last_ts = 464.067230
last_received_ts = 464.066742
last_seq = 1398
current_ts = 464.083893
current_received_ts = 464.083374
current_seq = 1399
count = 135
seq_step = 1
[462.637869] (kms_flip:9676) DEBUG: Vblank took 26us
[462.654529] (kms_flip:9676) DEBUG: name = flip
last_ts = 464.083893
last_received_ts = 464.083374
last_seq = 1399
current_ts = 464.100555
current_received_ts = 464.100067
current_seq = 1400
count = 136
seq_step = 1
[462.654558] (kms_flip:9676) DEBUG: Vblank took 25us
[462.671189] (kms_flip:9676) DEBUG: name = flip
last_ts = 464.100555
last_received_ts = 464.100067
last_seq = 1400
current_ts = 464.117218
current_received_ts = 464.116730
current_seq = 1401
count = 137
seq_step = 1
[462.671219] (kms_flip:9676) DEBUG: Vblank took 26us
[462.687848] (kms_flip:9676) DEBUG: name = flip
last_ts = 464.117218
last_received_ts = 464.116730
last_seq = 1401
current_ts = 464.133911
current_received_ts = 464.133392
current_seq = 1402
count = 138
seq_step = 1
[462.687879] (kms_flip:9676) DEBUG: Vblank took 25us
[462.704650] (kms_flip:9676) DEBUG: name = flip
last_ts = 464.133911
last_received_ts = 464.133392
last_seq = 1402
current_ts = 464.150574
current_received_ts = 464.150208
current_seq = 1403
count = 139
seq_step = 1
[462.704682] (kms_flip:9676) DEBUG: Vblank took 27us
[462.721306] (kms_flip:9676) DEBUG: name = flip
last_ts = 464.150574
last_received_ts = 464.150208
last_seq = 1403
current_ts = 464.167236
current_received_ts = 464.166870
current_seq = 1404
count = 140
seq_step = 1
[462.721336] (kms_flip:9676) DEBUG: Vblank took 25us
[462.737977] (kms_flip:9676) DEBUG: name = flip
last_ts = 464.167236
last_received_ts = 464.166870
last_seq = 1404
current_ts = 464.183899
current_received_ts = 464.183533
current_seq = 1405
count = 141
seq_step = 1
[462.738007] (kms_flip:9676) DEBUG: Vblank took 25us
[462.754643] (kms_flip:9676) DEBUG: name = flip
last_ts = 464.183899
last_received_ts = 464.183533
last_seq = 1405
current_ts = 464.200562
current_received_ts = 464.200195
current_seq = 1406
count = 142
seq_step = 1
[462.754673] (kms_flip:9676) DEBUG: Vblank took 25us
[462.771183] (kms_flip:9676) DEBUG: name = flip
last_ts = 464.200562
last_received_ts = 464.200195
last_seq = 1406
current_ts = 464.217255
current_received_ts = 464.216736
current_seq = 1407
count = 143
seq_step = 1
[462.771212] (kms_flip:9676) DEBUG: Vblank took 25us
[462.787846] (kms_flip:9676) DEBUG: name = flip
last_ts = 464.217255
last_received_ts = 464.216736
last_seq = 1407
current_ts = 464.233917
current_received_ts = 464.233398
current_seq = 1408
count = 144
seq_step = 1
[462.787876] (kms_flip:9676) DEBUG: Vblank took 25us
[462.804539] (kms_flip:9676) DEBUG: name = flip
last_ts = 464.233917
last_received_ts = 464.233398
last_seq = 1408
current_ts = 464.250580
current_received_ts = 464.250092
current_seq = 1409
count = 145
seq_step = 1
[462.804568] (kms_flip:9676) DEBUG: Vblank took 25us
[462.821182] (kms_flip:9676) DEBUG: name = flip
last_ts = 464.250580
last_received_ts = 464.250092
last_seq = 1409
current_ts = 464.267242
current_received_ts = 464.266724
current_seq = 1410
count = 146
seq_step = 1
[462.821211] (kms_flip:9676) DEBUG: Vblank took 25us
[462.837851] (kms_flip:9676) DEBUG: name = flip
last_ts = 464.267242
last_received_ts = 464.266724
last_seq = 1410
current_ts = 464.283905
current_received_ts = 464.283417
current_seq = 1411
count = 147
seq_step = 1
[462.837881] (kms_flip:9676) DEBUG: Vblank took 26us
[462.854539] (kms_flip:9676) DEBUG: name = flip
last_ts = 464.283905
last_received_ts = 464.283417
last_seq = 1411
current_ts = 464.300568
current_received_ts = 464.300079
current_seq = 1412
count = 148
seq_step = 1
[462.854567] (kms_flip:9676) DEBUG: Vblank took 24us
[462.871197] (kms_flip:9676) DEBUG: name = flip
last_ts = 464.300568
last_received_ts = 464.300079
last_seq = 1412
current_ts = 464.317230
current_received_ts = 464.316742
current_seq = 1413
count = 149
seq_step = 1
[462.871226] (kms_flip:9676) DEBUG: Vblank took 25us
[462.887854] (kms_flip:9676) DEBUG: name = flip
last_ts = 464.317230
last_received_ts = 464.316742
last_seq = 1413
current_ts = 464.333923
current_received_ts = 464.333405
current_seq = 1414
count = 150
seq_step = 1
[462.887883] (kms_flip:9676) DEBUG: Vblank took 25us
[462.904534] (kms_flip:9676) DEBUG: name = flip
last_ts = 464.333923
last_received_ts = 464.333405
last_seq = 1414
current_ts = 464.350586
current_received_ts = 464.350098
current_seq = 1415
count = 151
seq_step = 1
[462.904564] (kms_flip:9676) DEBUG: Vblank took 25us
[462.921186] (kms_flip:9676) DEBUG: name = flip
last_ts = 464.350586
last_received_ts = 464.350098
last_seq = 1415
current_ts = 464.367249
current_received_ts = 464.366730
current_seq = 1416
count = 152
seq_step = 1
[462.921215] (kms_flip:9676) DEBUG: Vblank took 25us
[462.937853] (kms_flip:9676) DEBUG: name = flip
last_ts = 464.367249
last_received_ts = 464.366730
last_seq = 1416
current_ts = 464.383911
current_received_ts = 464.383392
current_seq = 1417
count = 153
seq_step = 1
[462.937883] (kms_flip:9676) DEBUG: Vblank took 25us
[462.954535] (kms_flip:9676) DEBUG: name = flip
last_ts = 464.383911
last_received_ts = 464.383392
last_seq = 1417
current_ts = 464.400574
current_received_ts = 464.400085
current_seq = 1418
count = 154
seq_step = 1
[462.954564] (kms_flip:9676) DEBUG: Vblank took 25us
[462.971189] (kms_flip:9676) DEBUG: name = flip
last_ts = 464.400574
last_received_ts = 464.400085
last_seq = 1418
current_ts = 464.417236
current_received_ts = 464.416748
current_seq = 1419
count = 155
seq_step = 1
[462.971219] (kms_flip:9676) DEBUG: Vblank took 25us
[462.987857] (kms_flip:9676) DEBUG: name = flip
last_ts = 464.417236
last_received_ts = 464.416748
last_seq = 1419
current_ts = 464.433929
current_received_ts = 464.433411
current_seq = 1420
count = 156
seq_step = 1
[462.987887] (kms_flip:9676) DEBUG: Vblank took 25us
[463.004539] (kms_flip:9676) DEBUG: name = flip
last_ts = 464.433929
last_received_ts = 464.433411
last_seq = 1420
current_ts = 464.450592
current_received_ts = 464.450104
current_seq = 1421
count = 157
seq_step = 1
[463.004569] (kms_flip:9676) DEBUG: Vblank took 25us
[463.021190] (kms_flip:9676) DEBUG: name = flip
last_ts = 464.450592
last_received_ts = 464.450104
last_seq = 1421
current_ts = 464.467255
current_received_ts = 464.466736
current_seq = 1422
count = 158
seq_step = 1
[463.021220] (kms_flip:9676) DEBUG: Vblank took 24us
[463.037857] (kms_flip:9676) DEBUG: name = flip
last_ts = 464.467255
last_received_ts = 464.466736
last_seq = 1422
current_ts = 464.483917
current_received_ts = 464.483398
current_seq = 1423
count = 159
seq_step = 1
[463.037887] (kms_flip:9676) DEBUG: Vblank took 25us
[463.054535] (kms_flip:9676) DEBUG: name = flip
last_ts = 464.483917
last_received_ts = 464.483398
last_seq = 1423
current_ts = 464.500580
current_received_ts = 464.500092
current_seq = 1424
count = 160
seq_step = 1
[463.054565] (kms_flip:9676) DEBUG: Vblank took 25us
[463.071207] (kms_flip:9676) DEBUG: name = flip
last_ts = 464.500580
last_received_ts = 464.500092
last_seq = 1424
current_ts = 464.517242
current_received_ts = 464.516754
current_seq = 1425
count = 161
seq_step = 1
[463.071236] (kms_flip:9676) DEBUG: Vblank took 24us
[463.087860] (kms_flip:9676) DEBUG: name = flip
last_ts = 464.517242
last_received_ts = 464.516754
last_seq = 1425
current_ts = 464.533936
current_received_ts = 464.533417
current_seq = 1426
count = 162
seq_step = 1
[463.087889] (kms_flip:9676) DEBUG: Vblank took 26us
[463.104541] (kms_flip:9676) DEBUG: name = flip
last_ts = 464.533936
last_received_ts = 464.533417
last_seq = 1426
current_ts = 464.550598
current_received_ts = 464.550079
current_seq = 1427
count = 163
seq_step = 1
[463.104570] (kms_flip:9676) DEBUG: Vblank took 24us
[463.121195] (kms_flip:9676) DEBUG: name = flip
last_ts = 464.550598
last_received_ts = 464.550079
last_seq = 1427
current_ts = 464.567261
current_received_ts = 464.566742
current_seq = 1428
count = 164
seq_step = 1
[463.121224] (kms_flip:9676) DEBUG: Vblank took 25us
[463.137862] (kms_flip:9676) DEBUG: name = flip
last_ts = 464.567261
last_received_ts = 464.566742
last_seq = 1428
current_ts = 464.583923
current_received_ts = 464.583405
current_seq = 1429
count = 165
seq_step = 1
[463.137892] (kms_flip:9676) DEBUG: Vblank took 25us
[463.154531] (kms_flip:9676) DEBUG: name = flip
last_ts = 464.583923
last_received_ts = 464.583405
last_seq = 1429
current_ts = 464.600586
current_received_ts = 464.600098
current_seq = 1430
count = 166
seq_step = 1
[463.154560] (kms_flip:9676) DEBUG: Vblank took 24us
[463.171198] (kms_flip:9676) DEBUG: name = flip
last_ts = 464.600586
last_received_ts = 464.600098
last_seq = 1430
current_ts = 464.617249
current_received_ts = 464.616760
current_seq = 1431
count = 167
seq_step = 1
[463.171227] (kms_flip:9676) DEBUG: Vblank took 24us
[463.187864] (kms_flip:9676) DEBUG: name = flip
last_ts = 464.617249
last_received_ts = 464.616760
last_seq = 1431
current_ts = 464.633942
current_received_ts = 464.633423
current_seq = 1432
count = 168
seq_step = 1
[463.187894] (kms_flip:9676) DEBUG: Vblank took 25us
[463.204547] (kms_flip:9676) DEBUG: name = flip
last_ts = 464.633942
last_received_ts = 464.633423
last_seq = 1432
current_ts = 464.650604
current_received_ts = 464.650116
current_seq = 1433
count = 169
seq_step = 1
[463.204576] (kms_flip:9676) DEBUG: Vblank took 25us
[463.221200] (kms_flip:9676) DEBUG: name = flip
last_ts = 464.650604
last_received_ts = 464.650116
last_seq = 1433
current_ts = 464.667267
current_received_ts = 464.666748
current_seq = 1434
count = 170
seq_step = 1
[463.221229] (kms_flip:9676) DEBUG: Vblank took 25us
[463.237867] (kms_flip:9676) DEBUG: name = flip
last_ts = 464.667267
last_received_ts = 464.666748
last_seq = 1434
current_ts = 464.683929
current_received_ts = 464.683411
current_seq = 1435
count = 171
seq_step = 1
[463.237896] (kms_flip:9676) DEBUG: Vblank took 24us
[463.254549] (kms_flip:9676) DEBUG: name = flip
last_ts = 464.683929
last_received_ts = 464.683411
last_seq = 1435
current_ts = 464.700592
current_received_ts = 464.700104
current_seq = 1436
count = 172
seq_step = 1
[463.254579] (kms_flip:9676) DEBUG: Vblank took 25us
[463.271202] (kms_flip:9676) DEBUG: name = flip
last_ts = 464.700592
last_received_ts = 464.700104
last_seq = 1436
current_ts = 464.717255
current_received_ts = 464.716766
current_seq = 1437
count = 173
seq_step = 1
[463.271232] (kms_flip:9676) DEBUG: Vblank took 25us
[463.287869] (kms_flip:9676) DEBUG: name = flip
last_ts = 464.717255
last_received_ts = 464.716766
last_seq = 1437
current_ts = 464.733948
current_received_ts = 464.733429
current_seq = 1438
count = 174
seq_step = 1
[463.287898] (kms_flip:9676) DEBUG: Vblank took 24us
[463.304611] (kms_flip:9676) DEBUG: name = flip
last_ts = 464.733948
last_received_ts = 464.733429
last_seq = 1438
current_ts = 464.750610
current_received_ts = 464.750153
current_seq = 1439
count = 175
seq_step = 1
[463.304640] (kms_flip:9676) DEBUG: Vblank took 24us
[463.321214] (kms_flip:9676) DEBUG: name = flip
last_ts = 464.750610
last_received_ts = 464.750153
last_seq = 1439
current_ts = 464.767273
current_received_ts = 464.766754
current_seq = 1440
count = 176
seq_step = 1
[463.321243] (kms_flip:9676) DEBUG: Vblank took 23us
[463.337887] (kms_flip:9676) DEBUG: name = flip
last_ts = 464.767273
last_received_ts = 464.766754
last_seq = 1440
current_ts = 464.783936
current_received_ts = 464.783447
current_seq = 1441
count = 177
seq_step = 1
[463.337917] (kms_flip:9676) DEBUG: Vblank took 24us
[463.354542] (kms_flip:9676) DEBUG: name = flip
last_ts = 464.783936
last_received_ts = 464.783447
last_seq = 1441
current_ts = 464.800598
current_received_ts = 464.800110
current_seq = 1442
count = 178
seq_step = 1
[463.354572] (kms_flip:9676) DEBUG: Vblank took 26us
[463.371221] (kms_flip:9676) DEBUG: name = flip
last_ts = 464.800598
last_received_ts = 464.800110
last_seq = 1442
current_ts = 464.817261
current_received_ts = 464.816772
current_seq = 1443
count = 179
seq_step = 1
[463.371251] (kms_flip:9676) DEBUG: Vblank took 25us
[463.387876] (kms_flip:9676) DEBUG: name = flip
last_ts = 464.817261
last_received_ts = 464.816772
last_seq = 1443
current_ts = 464.833954
current_received_ts = 464.833435
current_seq = 1444
count = 180
seq_step = 1
[463.387905] (kms_flip:9676) DEBUG: Vblank took 25us
[463.404556] (kms_flip:9676) DEBUG: name = flip
last_ts = 464.833954
last_received_ts = 464.833435
last_seq = 1444
current_ts = 464.850616
current_received_ts = 464.850098
current_seq = 1445
count = 181
seq_step = 1
[463.404586] (kms_flip:9676) DEBUG: Vblank took 25us
[463.421209] (kms_flip:9676) DEBUG: name = flip
last_ts = 464.850616
last_received_ts = 464.850098
last_seq = 1445
current_ts = 464.867279
current_received_ts = 464.866760
current_seq = 1446
count = 182
seq_step = 1
[463.421240] (kms_flip:9676) DEBUG: Vblank took 26us
[463.437878] (kms_flip:9676) DEBUG: name = flip
last_ts = 464.867279
last_received_ts = 464.866760
last_seq = 1446
current_ts = 464.883942
current_received_ts = 464.883423
current_seq = 1447
count = 183
seq_step = 1
[463.437907] (kms_flip:9676) DEBUG: Vblank took 24us
[463.454544] (kms_flip:9676) DEBUG: name = flip
last_ts = 464.883942
last_received_ts = 464.883423
last_seq = 1447
current_ts = 464.900604
current_received_ts = 464.900085
current_seq = 1448
count = 184
seq_step = 1
[463.454573] (kms_flip:9676) DEBUG: Vblank took 26us
[463.471225] (kms_flip:9676) DEBUG: name = flip
last_ts = 464.900604
last_received_ts = 464.900085
last_seq = 1448
current_ts = 464.917267
current_received_ts = 464.916779
current_seq = 1449
count = 185
seq_step = 1
[463.471255] (kms_flip:9676) DEBUG: Vblank took 25us
[463.487879] (kms_flip:9676) DEBUG: name = flip
last_ts = 464.917267
last_received_ts = 464.916779
last_seq = 1449
current_ts = 464.933960
current_received_ts = 464.933441
current_seq = 1450
count = 186
seq_step = 1
[463.487909] (kms_flip:9676) DEBUG: Vblank took 25us
[463.504561] (kms_flip:9676) DEBUG: name = flip
last_ts = 464.933960
last_received_ts = 464.933441
last_seq = 1450
current_ts = 464.950623
current_received_ts = 464.950104
current_seq = 1451
count = 187
seq_step = 1
[463.504591] (kms_flip:9676) DEBUG: Vblank took 26us
[463.521228] (kms_flip:9676) DEBUG: name = flip
last_ts = 464.950623
last_received_ts = 464.950104
last_seq = 1451
current_ts = 464.967285
current_received_ts = 464.966797
current_seq = 1452
count = 188
seq_step = 1
[463.521258] (kms_flip:9676) DEBUG: Vblank took 25us
[463.537881] (kms_flip:9676) DEBUG: name = flip
last_ts = 464.967285
last_received_ts = 464.966797
last_seq = 1452
current_ts = 464.983948
current_received_ts = 464.983429
current_seq = 1453
count = 189
seq_step = 1
[463.537911] (kms_flip:9676) DEBUG: Vblank took 25us
[463.554550] (kms_flip:9676) DEBUG: name = flip
last_ts = 464.983948
last_received_ts = 464.983429
last_seq = 1453
current_ts = 465.000610
current_received_ts = 465.000092
current_seq = 1454
count = 190
seq_step = 1
[463.554579] (kms_flip:9676) DEBUG: Vblank took 25us
[463.571230] (kms_flip:9676) DEBUG: name = flip
last_ts = 465.000610
last_received_ts = 465.000092
last_seq = 1454
current_ts = 465.017273
current_received_ts = 465.016785
current_seq = 1455
count = 191
seq_step = 1
[463.571260] (kms_flip:9676) DEBUG: Vblank took 25us
[463.587884] (kms_flip:9676) DEBUG: name = flip
last_ts = 465.017273
last_received_ts = 465.016785
last_seq = 1455
current_ts = 465.033966
current_received_ts = 465.033447
current_seq = 1456
count = 192
seq_step = 1
[463.587914] (kms_flip:9676) DEBUG: Vblank took 25us
[463.604555] (kms_flip:9676) DEBUG: name = flip
last_ts = 465.033966
last_received_ts = 465.033447
last_seq = 1456
current_ts = 465.050629
current_received_ts = 465.050110
current_seq = 1457
count = 193
seq_step = 1
[463.604585] (kms_flip:9676) DEBUG: Vblank took 25us
[463.621219] (kms_flip:9676) DEBUG: name = flip
last_ts = 465.050629
last_received_ts = 465.050110
last_seq = 1457
current_ts = 465.067291
current_received_ts = 465.066772
current_seq = 1458
count = 194
seq_step = 1
[463.621249] (kms_flip:9676) DEBUG: Vblank took 25us
[463.637886] (kms_flip:9676) DEBUG: name = flip
last_ts = 465.067291
last_received_ts = 465.066772
last_seq = 1458
current_ts = 465.083954
current_received_ts = 465.083435
current_seq = 1459
count = 195
seq_step = 1
[463.637916] (kms_flip:9676) DEBUG: Vblank took 25us
[463.654555] (kms_flip:9676) DEBUG: name = flip
last_ts = 465.083954
last_received_ts = 465.083435
last_seq = 1459
current_ts = 465.100616
current_received_ts = 465.100128
current_seq = 1460
count = 196
seq_step = 1
[463.654585] (kms_flip:9676) DEBUG: Vblank took 25us
[463.671236] (kms_flip:9676) DEBUG: name = flip
last_ts = 465.100616
last_received_ts = 465.100128
last_seq = 1460
current_ts = 465.117279
current_received_ts = 465.116791
current_seq = 1461
count = 197
seq_step = 1
[463.671266] (kms_flip:9676) DEBUG: Vblank took 25us
[463.687891] (kms_flip:9676) DEBUG: name = flip
last_ts = 465.117279
last_received_ts = 465.116791
last_seq = 1461
current_ts = 465.133972
current_received_ts = 465.133453
current_seq = 1462
count = 198
seq_step = 1
[463.687925] (kms_flip:9676) DEBUG: Vblank took 28us
[463.704571] (kms_flip:9676) DEBUG: name = flip
last_ts = 465.133972
last_received_ts = 465.133453
last_seq = 1462
current_ts = 465.150635
current_received_ts = 465.150116
current_seq = 1463
count = 199
seq_step = 1
[463.704600] (kms_flip:9676) DEBUG: Vblank took 25us
[463.721224] (kms_flip:9676) DEBUG: name = flip
last_ts = 465.150635
last_received_ts = 465.150116
last_seq = 1463
current_ts = 465.167297
current_received_ts = 465.166779
current_seq = 1464
count = 200
seq_step = 1
[463.721254] (kms_flip:9676) DEBUG: Vblank took 25us
[463.737891] (kms_flip:9676) DEBUG: name = flip
last_ts = 465.167297
last_received_ts = 465.166779
last_seq = 1464
current_ts = 465.183960
current_received_ts = 465.183441
current_seq = 1465
count = 201
seq_step = 1
[463.737920] (kms_flip:9676) DEBUG: Vblank took 25us
[463.754558] (kms_flip:9676) DEBUG: name = flip
last_ts = 465.183960
last_received_ts = 465.183441
last_seq = 1465
current_ts = 465.200623
current_received_ts = 465.200104
current_seq = 1466
count = 202
seq_step = 1
[463.754587] (kms_flip:9676) DEBUG: Vblank took 25us
[463.771226] (kms_flip:9676) DEBUG: name = flip
last_ts = 465.200623
last_received_ts = 465.200104
last_seq = 1466
current_ts = 465.217285
current_received_ts = 465.216797
current_seq = 1467
count = 203
seq_step = 1
[463.771256] (kms_flip:9676) DEBUG: Vblank took 25us
[463.787895] (kms_flip:9676) DEBUG: name = flip
last_ts = 465.217285
last_received_ts = 465.216797
last_seq = 1467
current_ts = 465.233948
current_received_ts = 465.233459
current_seq = 1468
count = 204
seq_step = 1
[463.787929] (kms_flip:9676) DEBUG: Vblank took 28us
[463.804774] (kms_flip:9676) DEBUG: name = flip
last_ts = 465.233948
last_received_ts = 465.233459
last_seq = 1468
current_ts = 465.250641
current_received_ts = 465.250305
current_seq = 1469
count = 205
seq_step = 1
[463.804868] (kms_flip:9676) DEBUG: Vblank took 73us
[463.821251] (kms_flip:9676) DEBUG: name = flip
last_ts = 465.250641
last_received_ts = 465.250305
last_seq = 1469
current_ts = 465.267303
current_received_ts = 465.266785
current_seq = 1470
count = 206
seq_step = 1
[463.821909] (kms_flip:9676) DEBUG: Vblank took 642us
[463.821915] (kms_flip:9676) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931:
[463.822362] (kms_flip:9676) CRITICAL: Failed assertion: end - start < 500
[463.837029] (kms_flip:9676) igt_core-INFO: Stack trace:
[463.852657] (kms_flip:9676) igt_core-INFO:   #0 ../lib/igt_core.c:2106 __igt_fail_assert()
[463.853941] (kms_flip:9676) igt_core-INFO:   #1 ../tests/kms_flip.c:926 run_test_on_crtc_set()
[463.854018] (kms_flip:9676) igt_core-INFO:   #2 ../tests/kms_flip.c:2029 run_pair()
[463.854052] (kms_flip:9676) igt_core-INFO:   #3 ../tests/kms_flip.c:2193 __igt_unique____real_main2105()
[463.854088] (kms_flip:9676) igt_core-INFO:   #4 ../tests/kms_flip.c:2105 main()
[463.858164] (kms_flip:9676) igt_core-INFO:   #5 [__libc_init_first+0x8a]
[463.858979] (kms_flip:9676) igt_core-INFO:   #6 [__libc_start_main+0x8b]
[463.859166] (kms_flip:9676) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest BC-DP2-HDMI-A3: FAIL (5.025s)
Dmesg
<6> [461.515556] [IGT] kms_flip: starting dynamic subtest BC-DP2-HDMI-A3
<7> [461.516072] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:559]
<7> [461.516363] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:560]
<7> [461.575189] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:151:pipe A]
<7> [461.575492] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [461.575749] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [461.576007] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [461.576231] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [461.576432] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in dp_m_n (expected tu 64 data 5190451/8388608 link 288358/524288, found tu 0, data 0/0 link 0/0)
<7> [461.576635] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in output_types (expected 0x00000080, found 0x00000000)
<7> [461.576841] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [461.577034] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [461.577220] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [461.577398] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [461.577568] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [461.577736] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [461.577914] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [461.578073] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [461.578230] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [461.578386] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [461.578540] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [461.578694] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [461.578862] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [461.579013] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [461.579160] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [461.579300] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [461.579440] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [461.579580] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [461.579723] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [461.579887] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [461.580025] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [461.580159] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [461.580289] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [461.580417] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [461.580544] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [461.580670] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [461.580797] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [461.580938] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [461.581063] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in enhanced_framing (expected yes, found no)
<7> [461.581182] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in pipe_bpp (expected 36, found 0)
<7> [461.581300] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [461.581416] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [461.581531] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in port_clock (expected 270000, found 0)
<7> [461.581647] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:151:pipe A] fastset requirement not met, forcing full modeset
<7> [461.581765] xe 0000:03:00.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:151:pipe A] releasing TC PLL 2
<7> [461.581922] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:151:pipe A] dbuf slices 0x3 -> 0x0, ddb (0 - 1024) -> (0 - 0), active pipes 0x9 -> 0x8
<7> [461.582027] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:505:pipe D] dbuf slices 0x3 -> 0x3, ddb (1024 - 2048) -> (0 - 2048), active pipes 0x9 -> 0x8
<7> [461.582145] xe 0000:03:00.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:145:cursor A] ddb ( 965 - 1024) -> ( 0 - 0), size 59 -> 0
<7> [461.582253] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:151:pipe A] min cdclk: 192000 kHz -> 0 kHz
<7> [461.582402] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:151:pipe A] enable: no [modeset]
<7> [461.582516] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:35:plane 1A] fb: [NOFB], visible: no
<7> [461.582626] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [461.582738] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [461.582856] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [461.582967] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [461.583070] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:145:cursor A] fb: [NOFB], visible: no
<7> [461.583843] xe 0000:03:00.0: [drm:intel_audio_codec_disable [xe]] [CONNECTOR:527:DP-2][ENCODER:526:DDI TC2/PHY G] Disable audio codec on [CRTC:151:pipe A]
<7> [461.605776] xe 0000:03:00.0: [drm:intel_disable_transcoder [xe]] disabling pipe A
<7> [461.622969] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling AUX_TC2
<7> [461.625615] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disable TC PLL 2 (active 0x1, on? 1) for [CRTC:151:pipe A]
<7> [461.628083] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disabling TC PLL 2
<7> [461.628790] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:507:DDI TC1/PHY F]
<7> [461.629323] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:509:DP-MST A]
<7> [461.629780] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:510:DP-MST B]
<7> [461.630279] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:511:DP-MST C]
<7> [461.630719] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:512:DP-MST D]
<7> [461.631201] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:526:DDI TC2/PHY G]
<7> [461.631731] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:528:DP-MST A]
<7> [461.632221] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:529:DP-MST B]
<7> [461.632667] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:530:DP-MST C]
<7> [461.633138] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:531:DP-MST D]
<7> [461.633567] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:538:DDI TC3/PHY H]
<7> [461.634049] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:542:DDI TC4/PHY I]
<7> [461.634606] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:544:DP-MST A]
<7> [461.635148] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:545:DP-MST B]
<7> [461.635555] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:546:DP-MST C]
<7> [461.636001] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:547:DP-MST D]
<7> [461.636450] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:527:DP-2]
<7> [461.638946] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 2 (mbus joined: no)
<7> [461.641707] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling PW_A
<7> [461.642469] xe 0000:03:00.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating disabled for pipe_mask=0x1 (CLKGATE_DIS_DSSDSC=0x2a000000)
<7> [461.643122] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:151:pipe A]
<7> [461.644148] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:269:pipe C]
<7> [461.644473] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:387:pipe B]
<7> [461.644736] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:505:pipe D]
<7> [461.644908] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.enable (expected yes, found no)
<7> [461.645218] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.active (expected yes, found no)
<7> [461.645521] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in cpu_transcoder (expected 3, found -1)
<7> [461.645795] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in lane_count (expected 4, found 0)
<7> [461.646119] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [461.646362] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [461.646592] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [461.646832] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [461.647048] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [461.647251] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [461.647443] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [461.647650] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [461.647848] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [461.648029] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [461.648199] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1083, found 0)
<7> [461.648364] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1088, found 0)
<7> [461.648523] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [461.648681] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [461.648849] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [461.649005] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [461.649153] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [461.649294] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [461.649435] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [461.649569] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [461.649719] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [461.649873] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [461.650001] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1083, found 0)
<7> [461.650127] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1088, found 0)
<7> [461.650249] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [461.650370] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [461.650487] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [461.650679] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [461.650823] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [461.650932] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [461.651041] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [461.651147] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in pipe_bpp (expected 36, found 0)
<7> [461.651253] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [461.651355] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [461.651455] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in port_clock (expected 222750, found 0)
<7> [461.651553] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [461.651651] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in infoframes.gcp (expected 0x00000006, found 0x00000000)
<7> [461.651748] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in avi infoframe
<7> [461.651856] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [461.651953] xe 0000:03:00.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [461.651955] xe 0000:03:00.0: colorspace: RGB
<7> [461.651957] xe 0000:03:00.0: scan mode: Underscan
<7> [461.651958] xe 0000:03:00.0: colorimetry: No Data
<7> [461.651960] xe 0000:03:00.0: picture aspect: No Data
<7> [461.651961] xe 0000:03:00.0: active aspect: Same as Picture
<7> [461.651962] xe 0000:03:00.0: itc: No Data
<7> [461.651964] xe 0000:03:00.0: extended colorimetry: xvYCC 601
<7> [461.651965] xe 0000:03:00.0: quantization range: Full
<7> [461.651966] xe 0000:03:00.0: nups: Unknown Non-uniform Scaling
<7> [461.651968] xe 0000:03:00.0: video code: 0
<7> [461.651969] xe 0000:03:00.0: ycc quantization range: Full
<7> [461.651970] xe 0000:03:00.0: hdmi content type: Graphics
<7> [461.651972] xe 0000:03:00.0: pixel repeat: 0
<7> [461.651973] xe 0000:03:00.0: bar top 0, bottom 0, left 0, right 0
<7> [461.651975] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [461.652072] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in spd infoframe
<7> [461.652165] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [461.652257] xe 0000:03:00.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [461.652258] xe 0000:03:00.0: vendor: Intel
<7> [461.652260] xe 0000:03:00.0: product: Discrete gfx
<7> [461.652261] xe 0000:03:00.0: source device information: PC General (0x9)
<7> [461.652262] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [461.652362] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hdmi infoframe
<7> [461.652457] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [461.652552] xe 0000:03:00.0: HDMI infoframe: Vendor, version 1, length 4
<7> [461.652553] xe 0000:03:00.0: empty frame
<7> [461.652554] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [461.652647] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:505:pipe D] fastset requirement not met, forcing full modeset
<7> [461.652739] xe 0000:03:00.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:505:pipe D] releasing TC PLL 3
<7> [461.652859] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0xf), mbus joined? no->no
<7> [461.652940] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:505:pipe D] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x8 -> 0x0
<7> [461.653037] xe 0000:03:00.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:499:cursor D] ddb (1989 - 2048) -> ( 0 - 0), size 59 -> 0
<7> [461.653123] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:505:pipe D] min cdclk: 192000 kHz -> 0 kHz
<7> [461.653244] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:505:pipe D] enable: no [modeset]
<7> [461.653344] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:389:plane 1D] fb: [NOFB], visible: no
<7> [461.653442] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:419:plane 2D] fb: [NOFB], visible: no
<7> [461.653541] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:449:plane 3D] fb: [NOFB], visible: no
<7> [461.653636] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:479:plane 4D] fb: [NOFB], visible: no
<7> [461.653730] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:489:plane 5D] fb: [NOFB], visible: no
<7> [461.653833] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:499:cursor D] fb: [NOFB], visible: no
<7> [461.654495] xe 0000:03:00.0: [drm:intel_audio_codec_disable [xe]] [CONNECTOR:539:HDMI-A-3][ENCODER:538:DDI TC3/PHY H] Disable audio codec on [CRTC:505:pipe D]
<7> [461.675295] xe 0000:03:00.0: [drm:intel_disable_transcoder [xe]] disabling pipe D
<7> [461.693063] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disable TC PLL 3 (active 0x8, on? 1) for [CRTC:505:pipe D]
<7> [461.695540] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disabling TC PLL 3
<7> [461.696583] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:507:DDI TC1/PHY F]
<7> [461.697106] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:509:DP-MST A]
<7> [461.697568] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:510:DP-MST B]
<7> [461.698082] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:511:DP-MST C]
<7> [461.698507] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:512:DP-MST D]
<7> [461.698946] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:526:DDI TC2/PHY G]
<7> [461.699342] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:528:DP-MST A]
<7> [461.699693] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:529:DP-MST B]
<7> [461.700091] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:530:DP-MST C]
<7> [461.700441] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:531:DP-MST D]
<7> [461.700752] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:538:DDI TC3/PHY H]
<7> [461.701323] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:542:DDI TC4/PHY I]
<7> [461.701642] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:544:DP-MST A]
<7> [461.701968] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:545:DP-MST B]
<7> [461.702291] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:546:DP-MST C]
<7> [461.702563] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:547:DP-MST D]
<7> [461.702857] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:539:HDMI-A-3]
<7> [461.703385] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 2 (mbus joined: no)
<7> [461.703858] xe 0000:03:00.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0x1
<7> [461.704590] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling PW_D
<7> [461.705089] xe 0000:03:00.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating disabled for pipe_mask=0x8 (CLKGATE_DIS_DSSDSC=0xaa000000)
<7> [461.705571] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling PW_2
<7> [461.706078] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:505:pipe D]
<7> [461.706686] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:387:pipe B]
<7> [461.706743] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CONNECTOR:539:HDMI-A-3]
<7> [461.706910] xe 0000:03:00.0: [drm:intel_hdmi_compute_output_format [xe]] picking 12 bpc for HDMI output (pipe bpp: 36)
<7> [461.707113] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:387:pipe B] hw max bpp: 36, pipe bpp: 36, dithering: 0
<7> [461.707320] xe 0000:03:00.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:538:DDI TC3/PHY H] [CRTC:387:pipe B]
<7> [461.707516] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.enable (expected no, found yes)
<7> [461.707696] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.active (expected no, found yes)
<7> [461.707892] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in cpu_transcoder (expected -1, found 1)
<7> [461.708070] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in lane_count (expected 0, found 4)
<7> [461.708237] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [461.708395] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [461.708549] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [461.708700] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [461.708865] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [461.709017] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [461.709169] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [461.709317] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [461.709472] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [461.709606] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [461.709742] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1083)
<7> [461.709890] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1088)
<7> [461.710016] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [461.710140] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [461.710257] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [461.710374] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [461.710505] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [461.710619] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [461.710732] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [461.710854] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [461.710962] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [461.711067] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [461.711214] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1083)
<7> [461.711374] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1088)
<7> [461.711474] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [461.711572] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [461.711669] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [461.711766] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [461.711870] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [461.711961] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in has_hdmi_sink (expected no, found yes)
<7> [461.712052] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in has_infoframe (expected no, found yes)
<7> [461.712142] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in pipe_bpp (expected 0, found 36)
<7> [461.712233] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [461.712323] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [461.712413] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in port_clock (expected 0, found 222750)
<7> [461.712504] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071)
<7> [461.712593] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in infoframes.gcp (expected 0x00000000, found 0x00000006)
<7> [461.712683] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in avi infoframe
<7> [461.712773] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [461.712880] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [461.712984] xe 0000:03:00.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [461.712986] xe 0000:03:00.0: colorspace: RGB
<7> [461.712987] xe 0000:03:00.0: scan mode: Underscan
<7> [461.712988] xe 0000:03:00.0: colorimetry: No Data
<7> [461.712990] xe 0000:03:00.0: picture aspect: No Data
<7> [461.712991] xe 0000:03:00.0: active aspect: Same as Picture
<7> [461.712992] xe 0000:03:00.0: itc: No Data
<7> [461.712993] xe 0000:03:00.0: extended colorimetry: xvYCC 601
<7> [461.712994] xe 0000:03:00.0: quantization range: Full
<7> [461.712996] xe 0000:03:00.0: nups: Unknown Non-uniform Scaling
<7> [461.712997] xe 0000:03:00.0: video code: 0
<7> [461.712998] xe 0000:03:00.0: ycc quantization range: Full
<7> [461.712999] xe 0000:03:00.0: hdmi content type: Graphics
<7> [461.713001] xe 0000:03:00.0: pixel repeat: 0
<7> [461.713002] xe 0000:03:00.0: bar top 0, bottom 0, left 0, right 0
<7> [461.713004] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in spd infoframe
<7> [461.713097] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [461.713188] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [461.713280] xe 0000:03:00.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [461.713281] xe 0000:03:00.0: vendor: Intel
<7> [461.713283] xe 0000:03:00.0: product: Discrete gfx
<7> [461.713284] xe 0000:03:00.0: source device information: PC General (0x9)
<7> [461.713285] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hdmi infoframe
<7> [461.713376] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [461.713466] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [461.713556] xe 0000:03:00.0: HDMI infoframe: Vendor, version 1, length 4
<7> [461.713558] xe 0000:03:00.0: empty frame
<7> [461.713559] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:387:pipe B] fastset requirement not met, forcing full modeset
<7> [461.713681] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0x1 -> 0xf (total dbuf slices 0xf), mbus joined? no->yes
<7> [461.713764] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:387:pipe B] dbuf slices 0x0 -> 0xf, ddb (0 - 0) -> (0 - 4096), active pipes 0x0 -> 0x2
<7> [461.713875] xe 0000:03:00.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:271:plane 1B] ddb ( 0 - 0) -> ( 0 - 4037), size 0 -> 4037
<7> [461.713950] xe 0000:03:00.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:381:cursor B] ddb ( 0 - 0) -> (4037 - 4096), size 0 -> 59
<7> [461.714022] xe 0000:03:00.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:271:plane 1B] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm
<7> [461.714093] xe 0000:03:00.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:271:plane 1B] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 4, 4, 9, 9, 17, 17, 17, 17, 0, 15, 0
<7> [461.714165] xe 0000:03:00.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:271:plane 1B] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 62, 62, 139, 139, 261, 261, 261, 261, 137, 230, 244
<7> [461.714237] xe 0000:03:00.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:271:plane 1B] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 123, 123, 245, 245, 367, 367, 0, 0, 138, 306, 306
<7> [461.714319] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] [CRTC:387:pipe B] data rate 594000 num active planes 1
<7> [461.714420] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 33600 required 624 qgv_peak_bw: 48000
<7> [461.714517] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 53000 required 624 qgv_peak_bw: 48000
<7> [461.714614] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 48000 for required data rate: 624
<7> [461.714711] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:387:pipe B] min cdclk: 0 kHz -> 192000 kHz
<7> [461.714837] xe 0000:03:00.0: [drm:intel_find_dpll [xe]] [CRTC:387:pipe B] allocated TC PLL 3
<7> [461.714930] xe 0000:03:00.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:387:pipe B] reserving TC PLL 3
<7> [461.715020] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:387:pipe B] enable: yes [modeset]
<7> [461.715119] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [461.715215] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: B, pipe bpp: 36, dithering: 0
<7> [461.715310] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [461.715405] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [461.715498] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [461.715591] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [461.715683] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [461.715775] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] GCP: 0x6
<7> [461.715877] xe 0000:03:00.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [461.715878] xe 0000:03:00.0: colorspace: RGB
<7> [461.715880] xe 0000:03:00.0: scan mode: Underscan
<7> [461.715881] xe 0000:03:00.0: colorimetry: No Data
<7> [461.715883] xe 0000:03:00.0: picture aspect: No Data
<7> [461.715884] xe 0000:03:00.0: active aspect: Same as Picture
<7> [461.715885] xe 0000:03:00.0: itc: No Data
<7> [461.715886] xe 0000:03:00.0: extended colorimetry: xvYCC 601
<7> [461.715888] xe 0000:03:00.0: quantization range: Full
<7> [461.715889] xe 0000:03:00.0: nups: Unknown Non-uniform Scaling
<7> [461.715890] xe 0000:03:00.0: video code: 0
<7> [461.715891] xe 0000:03:00.0: ycc quantization range: Full
<7> [461.715893] xe 0000:03:00.0: hdmi content type: Graphics
<7> [461.715894] xe 0000:03:00.0: pixel repeat: 0
<7> [461.715895] xe 0000:03:00.0: bar top 0, bottom 0, left 0, right 0
<7> [461.715897] xe 0000:03:00.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [461.715898] xe 0000:03:00.0: vendor: Intel
<7> [461.715899] xe 0000:03:00.0: product: Discrete gfx
<7> [461.715901] xe 0000:03:00.0: source device information: PC General (0x9)
<7> [461.715902] xe 0000:03:00.0: HDMI infoframe: Vendor, version 1, length 4
<7> [461.715904] xe 0000:03:00.0: empty frame
<7> [461.715905] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 10 00 07 00 66 10 00 01 00 00 00 00 00 00 00 00
<7> [461.715997] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 05 e3 cd 0c 32 38 45 38 35 30 09 07 07 00 00 00
<7> [461.716089] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [461.716181] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [461.716272] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vrr: no, fixed rr: yes, vmin: 1125, vmax: 1125, flipline: 1125, pipeline full: 0, guardband: 45 vsync start: 42, vsync end: 37
<7> [461.716363] 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> [461.716454] 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> [461.716545] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] requested mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9
<7> [461.716636] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] adjusted mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9
<7> [461.716727] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1083-1088 vt=1125, flags=0x9
<7> [461.716823] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x40 0x9
<7> [461.716919] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1083-1088 vt=1125, flags=0x9
<7> [461.717015] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port clock: 222750, pixel rate 148500, min cdclk 192000, min voltage level 0
<7> [461.717107] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] linetime: 119, ips linetime: 0
<7> [461.717207] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0
<7> [461.717301] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe src: 1920x1080+0+0
<7> [461.717393] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [461.717485] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [461.717576] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [461.717668] 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> [461.717760] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] c20pll_hw_state:
<7> [461.717863] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] tx[0] = 0xbe88, tx[1] = 0x5800, tx[2] = 0x0000
<7> [461.717954] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cmn[0] = 0x0500, cmn[1] = 0x0005, cmn[2] = 0x0000, cmn[3] = 0x0000
<7> [461.718084] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[0] = 0x40e8
<7> [461.718222] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[1] = 0x7d20
<7> [461.718317] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[2] = 0x0a06
<7> [461.718406] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[3] = 0x8f1c
<7> [461.718494] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[4] = 0x0000
<7> [461.718583] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[5] = 0x0000
<7> [461.718671] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[6] = 0x2200
<7> [461.718760] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[7] = 0xffff
<7> [461.718860] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[8] = 0x0400
<7> [461.718950] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[9] = 0x0000
<7> [461.719040] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[10] = 0x0001
<7> [461.719130] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vdr: custom width: 0x00, serdes rate: 0x00, hdmi rate: 0x00
<7> [461.719219] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [461.719308] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [461.719398] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [461.719487] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [461.719577] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [461.719665] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [461.719754] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [461.719862] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [461.719951] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [461.720040] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [461.720130] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [461.720220] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [461.720309] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:271:plane 1B] fb: [FB:559] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [461.720407] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [461.720503] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [461.720596] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:301:plane 2B] fb: [NOFB], visible: no
<7> [461.720687] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:331:plane 3B] fb: [NOFB], visible: no
<7> [461.720779] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:361:plane 4B] fb: [NOFB], visible: no
<7> [461.720882] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:371:plane 5B] fb: [NOFB], visible: no
<7> [461.720973] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:381:cursor B] fb: [NOFB], visible: no
<7> [461.721578] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling PW_2
<7> [461.721686] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling PW_B
<7> [461.721801] xe 0000:03:00.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating enabled for pipe_mask=0x2 (CLKGATE_DIS_DSSDSC=0xa2000000)
<7> [461.721922] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:507:DDI TC1/PHY F]
<7> [461.722011] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:509:DP-MST A]
<7> [461.722097] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:510:DP-MST B]
<7> [461.722180] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:511:DP-MST C]
<7> [461.722260] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:512:DP-MST D]
<7> [461.722339] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:526:DDI TC2/PHY G]
<7> [461.722419] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:528:DP-MST A]
<7> [461.722499] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:529:DP-MST B]
<7> [461.722578] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:530:DP-MST C]
<7> [461.722657] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:531:DP-MST D]
<7> [461.722732] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:538:DDI TC3/PHY H]
<7> [461.722816] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:542:DDI TC4/PHY I]
<7> [461.722892] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:544:DP-MST A]
<7> [461.722968] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:545:DP-MST B]
<7> [461.723044] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:546:DP-MST C]
<7> [461.723119] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:547:DP-MST D]
<7> [461.723209] xe 0000:03:00.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xf
<7> [461.723373] xe 0000:03:00.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: no -> yes (pipe: *)
<7> [461.723451] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: yes)
<7> [461.723550] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enable TC PLL 3 (active 0x2, on? 0) for [CRTC:387:pipe B]
<7> [461.723641] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enabling TC PLL 3
<7> [461.725797] xe 0000:03:00.0: [drm:intel_enable_transcoder [xe]] enabling pipe B
<7> [461.725922] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling AUX_TC2
<7> [461.761109] xe 0000:03:00.0: [drm:intel_audio_codec_enable [xe]] [CONNECTOR:539:HDMI-A-3][ENCODER:538:DDI TC3/PHY H] Enable audio codec on [CRTC:387:pipe B], 32 bytes ELD
<7> [461.777760] xe 0000:03:00.0: [drm:audio_config_hdmi_pixel_clock [xe]] Configuring HDMI audio for pixel clock 148500 (0x00090000)
<7> [461.778420] xe 0000:03:00.0: [drm:hsw_audio_config_update [xe]] using automatic N
<7> [461.779422] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:539:HDMI-A-3]
<7> [461.780204] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:387:pipe B]
<7> [461.786196] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:269:pipe C]
<7> [461.786299] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CONNECTOR:527:DP-2]
<7> [461.786606] xe 0000:03:00.0: [drm:intel_dp_compute_config_limits [xe]] [ENCODER:526:DDI TC2/PHY G][CRTC:269:pipe C] DP link limits: pixel clock 148500 kHz DSC off max lanes 4 max rate 540000 max pipe_bpp 36 min link_bpp 18.0000 max link_bpp 36.0000
<7> [461.787154] xe 0000:03:00.0: [drm:intel_dp_compute_output_format [xe]] DP lane count 4 clock 270000 bpp input 36 compressed 0.0000 HDR no link rate required 668250 available 1080000
<7> [461.787658] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:269:pipe C] hw max bpp: 36, pipe bpp: 36, dithering: 0
<7> [461.788158] xe 0000:03:00.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:526:DDI TC2/PHY G] [CRTC:269:pipe C]
<7> [461.788632] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.enable (expected no, found yes)
<7> [461.789062] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.active (expected no, found yes)
<7> [461.789579] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in cpu_transcoder (expected -1, found 2)
<7> [461.789970] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in lane_count (expected 0, found 4)
<7> [461.790315] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in dp_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 5190451/8388608 link 288358/524288)
<7> [461.790651] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in output_types (expected 0x00000000, found 0x00000080)
<7> [461.791011] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [461.791325] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [461.791598] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [461.791890] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [461.792147] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [461.792406] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [461.792645] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [461.792907] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [461.793143] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [461.793369] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [461.793587] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [461.793800] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [461.794040] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [461.794239] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [461.794428] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [461.794614] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [461.794815] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [461.795046] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [461.795221] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [461.795389] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [461.795569] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [461.795734] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [461.795915] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [461.796109] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [461.796253] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [461.796397] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [461.796562] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [461.796753] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [461.796918] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in enhanced_framing (expected no, found yes)
<7> [461.797077] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in dpll_hw_state
<7> [461.797210] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [461.797336] 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> [461.797460] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] c20pll_hw_state:
<7> [461.797576] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] tx[0] = 0xbe88, tx[1] = 0x5800, tx[2] = 0x0000
<7> [461.797692] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] cmn[0] = 0x0500, cmn[1] = 0x0005, cmn[2] = 0x0000, cmn[3] = 0x0000
<7> [461.797816] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[0] = 0x40e8
<7> [461.797953] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[1] = 0x7d20
<7> [461.798066] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[2] = 0x0a06
<7> [461.798178] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[3] = 0x8f1c
<7> [461.798287] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[4] = 0x0000
<7> [461.798405] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[5] = 0x0000
<7> [461.798508] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[6] = 0x2200
<7> [461.798609] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[7] = 0xffff
<7> [461.798709] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[8] = 0x0400
<7> [461.798817] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[9] = 0x0000
<7> [461.798937] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[10] = 0x0001
<7> [461.799029] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] vdr: custom width: 0x00, serdes rate: 0x00, hdmi rate: 0x00
<7> [461.799122] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [461.799212] 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> [461.799301] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] c20pll_hw_state:
<7> [461.799390] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] tx[0] = 0xbe88, tx[1] = 0x4800, tx[2] = 0x0000
<7> [461.799479] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] cmn[0] = 0x0500, cmn[1] = 0x0005, cmn[2] = 0x0000, cmn[3] = 0x0000
<7> [461.799568] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[0] = 0x308c
<7> [461.799658] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[1] = 0x2110
<7> [461.799746] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[2] = 0xcc9c
<7> [461.799885] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[3] = 0xbfc1
<7> [461.799989] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[4] = 0x4b9a
<7> [461.800084] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[5] = 0x3f81
<7> [461.800175] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[6] = 0x2000
<7> [461.800265] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[7] = 0x0001
<7> [461.800355] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[8] = 0x5000
<7> [461.800444] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[9] = 0x0000
<7> [461.800534] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[10] = 0x0000
<7> [461.800624] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] vdr: custom width: 0x00, serdes rate: 0x42, hdmi rate: 0x00
<7> [461.800714] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in pipe_bpp (expected 0, found 36)
<7> [461.800811] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [461.800919] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [461.801008] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in port_clock (expected 0, found 270000)
<7> [461.801097] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:269:pipe C] fastset requirement not met, forcing full modeset
<7> [461.801216] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xf -> 0xd (total dbuf slices 0xf), mbus joined? yes->no
<7> [461.801303] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:387:pipe B] dbuf slices 0xf -> 0xc, ddb (0 - 4096) -> (0 - 1024), active pipes 0x2 -> 0x6
<7> [461.801381] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:269:pipe C] dbuf slices 0x0 -> 0xc, ddb (0 - 0) -> (1024 - 2048), active pipes 0x2 -> 0x6
<7> [461.801484] xe 0000:03:00.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:271:plane 1B] ddb ( 0 - 4037) -> ( 0 - 965), size 4037 -> 965
<7> [461.801556] xe 0000:03:00.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:381:cursor B] ddb (4037 - 4096) -> ( 965 - 1024), size 59 -> 59
<7> [461.801626] xe 0000:03:00.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:153:plane 1C] ddb ( 0 - 0) -> (1024 - 1989), size 0 -> 965
<7> [461.801694] xe 0000:03:00.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:263:cursor C] ddb ( 0 - 0) -> (1989 - 2048), size 0 -> 59
<7> [461.801761] xe 0000:03:00.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:153:plane 1C] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm
<7> [461.801841] xe 0000:03:00.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:153:plane 1C] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 4, 4, 9, 9, 17, 17, 17, 17, 0, 15, 0
<7> [461.801937] xe 0000:03:00.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:153:plane 1C] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 62, 62, 139, 139, 261, 261, 261, 261, 137, 230, 244
<7> [461.802006] xe 0000:03:00.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:153:plane 1C] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 123, 123, 245, 245, 367, 367, 0, 0, 138, 306, 306
<7> [461.802084] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] [CRTC:269:pipe C] data rate 594000 num active planes 1
<7> [461.802185] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 33600 required 1248 qgv_peak_bw: 48000
<7> [461.802284] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 53000 required 1248 qgv_peak_bw: 48000
<7> [461.802381] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 48000 for required data rate: 1248
<7> [461.802478] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:269:pipe C] min cdclk: 0 kHz -> 192000 kHz
<7> [461.802597] xe 0000:03:00.0: [drm:intel_find_dpll [xe]] [CRTC:269:pipe C] allocated TC PLL 2
<7> [461.802700] xe 0000:03:00.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:269:pipe C] reserving TC PLL 2
<7> [461.802792] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:269:pipe C] enable: yes [modeset]
<7> [461.802913] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: DP (0x80), output format: RGB, sink format: RGB
<7> [461.803030] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: C, pipe bpp: 36, dithering: 0
<7> [461.803129] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [461.803244] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [461.803369] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [461.803465] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [461.803558] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] dp m_n: lanes: 4; data_m: 5190451, data_n: 8388608, link_m: 288358, link_n: 524288, tu: 64
<7> [461.803651] 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> [461.803742] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: enabled
<7> [461.803846] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled
<7> [461.803967] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] psr: disabled, selective update: disabled, panel replay: disabled, selective fetch: disabled
<7> [461.804060] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] minimum hblank: 0
<7> [461.804152] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] audio: 1, infoframes: 0, infoframes enabled: 0x0
<7> [461.804245] 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> [461.804338] 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> [461.804430] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 17 50 51 07 00 00 00 00
<7> [461.804521] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [461.804610] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [461.804700] 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> [461.804791] 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> [461.804904] 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> [461.805020] 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> [461.805113] 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> [461.805205] 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> [461.805296] 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> [461.805387] 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> [461.805477] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port clock: 270000, pixel rate 148500, min cdclk 192000, min voltage level 0
<7> [461.805568] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] linetime: 119, ips linetime: 0
<7> [461.805659] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0
<7> [461.805749] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe src: 1920x1080+0+0
<7> [461.805851] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [461.805967] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [461.806060] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [461.806152] 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> [461.806243] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] c20pll_hw_state:
<7> [461.806333] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] tx[0] = 0xbe88, tx[1] = 0x4800, tx[2] = 0x0000
<7> [461.806425] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cmn[0] = 0x0500, cmn[1] = 0x0005, cmn[2] = 0x0000, cmn[3] = 0x0000
<7> [461.806516] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[0] = 0x308c
<7> [461.806606] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[1] = 0x2110
<7> [461.806697] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[2] = 0xcc9c
<7> [461.806788] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[3] = 0xbfc1
<7> [461.806900] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[4] = 0x4b9a
<7> [461.807006] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[5] = 0x3f81
<7> [461.807097] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[6] = 0x2000
<7> [461.807189] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[7] = 0x0001
<7> [461.807279] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[8] = 0x5000
<7> [461.807369] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[9] = 0x0000
<7> [461.807463] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[10] = 0x0000
<7> [461.807558] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vdr: custom width: 0x00, serdes rate: 0x42, hdmi rate: 0x00
<7> [461.807652] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [461.807744] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [461.807846] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [461.807954] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [461.808044] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [461.808136] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [461.808227] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [461.808317] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [461.808406] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [461.808498] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [461.808589] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [461.808678] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [461.808769] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:153:plane 1C] fb: [FB:559] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [461.808871] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [461.808986] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [461.809083] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:183:plane 2C] fb: [NOFB], visible: no
<7> [461.809181] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:213:plane 3C] fb: [NOFB], visible: no
<7> [461.809281] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:243:plane 4C] fb: [NOFB], visible: no
<7> [461.809375] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:253:plane 5C] fb: [NOFB], visible: no
<7> [461.809466] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:263:cursor C] fb: [NOFB], visible: no
<7> [461.810338] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling PW_C
<7> [461.810460] xe 0000:03:00.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating enabled for pipe_mask=0x4 (CLKGATE_DIS_DSSDSC=0x82000000)
<7> [461.810569] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:507:DDI TC1/PHY F]
<7> [461.810659] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:509:DP-MST A]
<7> [461.810744] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:510:DP-MST B]
<7> [461.810839] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:511:DP-MST C]
<7> [461.810925] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:512:DP-MST D]
<7> [461.811007] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:526:DDI TC2/PHY G]
<7> [461.811087] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:528:DP-MST A]
<7> [461.811163] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:529:DP-MST B]
<7> [461.811239] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:530:DP-MST C]
<7> [461.811315] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:531:DP-MST D]
<7> [461.811390] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:538:DDI TC3/PHY H]
<7> [461.811466] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:542:DDI TC4/PHY I]
<7> [461.811541] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:544:DP-MST A]
<7> [461.811614] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:545:DP-MST B]
<7> [461.811689] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:546:DP-MST C]
<7> [461.811772] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:547:DP-MST D]
<7> [461.827670] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 2 (mbus joined: no)
<7> [461.827996] xe 0000:03:00.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: yes -> no (pipe: B)
<7> [461.844346] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enable TC PLL 2 (active 0x4, on? 0) for [CRTC:269:pipe C]
<7> [461.844517] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enabling TC PLL 2
<7> [461.846912] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling AUX_TC2
<7> [461.852359] 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> [461.859471] xe 0000:03:00.0: [drm:intel_dp_init_lttpr_and_dprx_caps [xe]] [CONNECTOR:527:DP-2][ENCODER:526:DDI TC2/PHY G][DPRX] LTTPR common capabilities: 00 00 00 00 00 00 00 00
<7> [461.860953] 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> [461.861778] xe 0000:03:00.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:527:DP-2][ENCODER:526:DDI TC2/PHY G][DPRX] Using LINK_BW_SET value 0a
<7> [461.863780] xe 0000:03:00.0: [drm:intel_dp_set_signal_levels [xe]] [CONNECTOR:527:DP-2][ENCODER:526:DDI TC2/PHY G][DPRX] 8b/10b, lanes: 4, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0
<7> [461.864759] xe 0000:03:00.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:527:DP-2][ENCODER:526:DDI TC2/PHY G][DPRX] Using DP training pattern TPS1
<7> [461.872787] xe 0000:03:00.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:527:DP-2][ENCODER:526:DDI TC2/PHY G][DPRX] Clock recovery OK
<7> [461.873284] xe 0000:03:00.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:527:DP-2][ENCODER:526:DDI TC2/PHY G][DPRX] Using DP training pattern TPS3
<7> [461.879644] xe 0000:03:00.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:527:DP-2][ENCODER:526:DDI TC2/PHY G][DPRX] Channel EQ done. DP Training successful
<7> [461.880243] xe 0000:03:00.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:527:DP-2][ENCODER:526:DDI TC2/PHY G][DPRX] Link Training passed at link rate = 270000, lane count = 4
<7> [461.881978] xe 0000:03:00.0: [drm:intel_enable_transcoder [xe]] enabling pipe C
<7> [461.916045] xe 0000:03:00.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xd
<7> [461.917106] xe 0000:03:00.0: [drm:intel_audio_codec_enable [xe]] [CONNECTOR:527:DP-2][ENCODER:526:DDI TC2/PHY G] Enable audio codec on [CRTC:269:pipe C], 40 bytes ELD
<7> [461.933254] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:527:DP-2]
<7> [461.934121] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:269:pipe C]
<7> [461.981859] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling AUX_TC2
<7> [463.910917] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling AUX_TC2
<7> [464.022827] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling AUX_TC2
<6> [466.542529] [IGT] kms_flip: finished subtest BC-DP2-HDMI-A3, FAIL
Created at 2026-05-23 12:06:29