Results for igt@kms_flip@2x-flip-vs-expired-vblank@ac-hdmi-a1-hdmi-a2

Result: Fail

integration-manifest git-log-oneline i915_display_info6 igt_runner6 runtimes6 results6.json results6-i915-load.json guc_logs6.tar i915_display_info_post_exec6 boot6 dmesg6

DetailValue
Duration 10.92 seconds
Hostname 
shard-glk2
Igt-Version 
IGT-Version: 2.4-g98b65acc4 (x86_64) (Linux: 7.1.0-rc5-Patchwork_167195v1-g04d475020800+ x86_64)
Out 
Starting dynamic subtest: AC-HDMI-A1-HDMI-A2
  1920x1080: 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9 
  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 AC-HDMI-A1-HDMI-A2: FAIL (10.917s)
Err 
Starting dynamic subtest: AC-HDMI-A1-HDMI-A2
[230.744283] (kms_flip:2580) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931:
[230.744855] (kms_flip:2580) CRITICAL: Failed assertion: end - start < 500
Dynamic subtest AC-HDMI-A1-HDMI-A2 failed.
**** DEBUG ****
[228.726163] (kms_flip:2580) DEBUG: name = flip
last_ts = 228.799500
last_received_ts = 228.799362
last_seq = 6040
current_ts = 228.816162
current_received_ts = 228.816101
current_seq = 6041
count = 464
seq_step = 1
[228.726212] (kms_flip:2580) DEBUG: Vblank took 22us
[228.742462] (kms_flip:2580) DEBUG: name = flip
last_ts = 228.816162
last_received_ts = 228.816101
last_seq = 6041
current_ts = 228.832825
current_received_ts = 228.832397
current_seq = 6042
count = 465
seq_step = 1
[228.742509] (kms_flip:2580) DEBUG: Vblank took 22us
[228.759578] (kms_flip:2580) DEBUG: name = flip
last_ts = 228.832825
last_received_ts = 228.832397
last_seq = 6042
current_ts = 228.849503
current_received_ts = 228.849518
current_seq = 6043
count = 466
seq_step = 1
[228.759627] (kms_flip:2580) DEBUG: Vblank took 23us
[228.776131] (kms_flip:2580) DEBUG: name = flip
last_ts = 228.849503
last_received_ts = 228.849518
last_seq = 6043
current_ts = 228.866150
current_received_ts = 228.866074
current_seq = 6044
count = 467
seq_step = 1
[228.776182] (kms_flip:2580) DEBUG: Vblank took 22us
[228.792595] (kms_flip:2580) DEBUG: name = flip
last_ts = 228.866150
last_received_ts = 228.866074
last_seq = 6044
current_ts = 228.882828
current_received_ts = 228.882538
current_seq = 6045
count = 468
seq_step = 1
[228.792644] (kms_flip:2580) DEBUG: Vblank took 21us
[228.809475] (kms_flip:2580) DEBUG: name = flip
last_ts = 228.882828
last_received_ts = 228.882538
last_seq = 6045
current_ts = 228.899506
current_received_ts = 228.899414
current_seq = 6046
count = 469
seq_step = 1
[228.809526] (kms_flip:2580) DEBUG: Vblank took 22us
[228.826114] (kms_flip:2580) DEBUG: name = flip
last_ts = 228.899506
last_received_ts = 228.899414
last_seq = 6046
current_ts = 228.916153
current_received_ts = 228.916000
current_seq = 6047
count = 470
seq_step = 1
[228.826163] (kms_flip:2580) DEBUG: Vblank took 22us
[228.842601] (kms_flip:2580) DEBUG: name = flip
last_ts = 228.916153
last_received_ts = 228.916000
last_seq = 6047
current_ts = 228.932816
current_received_ts = 228.932541
current_seq = 6048
count = 471
seq_step = 1
[228.842651] (kms_flip:2580) DEBUG: Vblank took 22us
[228.859465] (kms_flip:2580) DEBUG: name = flip
last_ts = 228.932816
last_received_ts = 228.932541
last_seq = 6048
current_ts = 228.949493
current_received_ts = 228.949402
current_seq = 6049
count = 472
seq_step = 1
[228.859514] (kms_flip:2580) DEBUG: Vblank took 22us
[228.876191] (kms_flip:2580) DEBUG: name = flip
last_ts = 228.949493
last_received_ts = 228.949402
last_seq = 6049
current_ts = 228.966156
current_received_ts = 228.966125
current_seq = 6050
count = 473
seq_step = 1
[228.876238] (kms_flip:2580) DEBUG: Vblank took 22us
[228.892611] (kms_flip:2580) DEBUG: name = flip
last_ts = 228.966156
last_received_ts = 228.966125
last_seq = 6050
current_ts = 228.982834
current_received_ts = 228.982544
current_seq = 6051
count = 474
seq_step = 1
[228.892660] (kms_flip:2580) DEBUG: Vblank took 22us
[228.909436] (kms_flip:2580) DEBUG: name = flip
last_ts = 228.982834
last_received_ts = 228.982544
last_seq = 6051
current_ts = 228.999496
current_received_ts = 228.999374
current_seq = 6052
count = 475
seq_step = 1
[228.909485] (kms_flip:2580) DEBUG: Vblank took 22us
[228.925829] (kms_flip:2580) DEBUG: name = flip
last_ts = 228.999496
last_received_ts = 228.999374
last_seq = 6052
current_ts = 229.016159
current_received_ts = 229.015778
current_seq = 6053
count = 476
seq_step = 1
[228.925874] (kms_flip:2580) DEBUG: Vblank took 20us
[228.942383] (kms_flip:2580) DEBUG: name = flip
last_ts = 229.016159
last_received_ts = 229.015778
last_seq = 6053
current_ts = 229.032822
current_received_ts = 229.032333
current_seq = 6054
count = 477
seq_step = 1
[228.942422] (kms_flip:2580) DEBUG: Vblank took 17us
[228.959380] (kms_flip:2580) DEBUG: name = flip
last_ts = 229.032822
last_received_ts = 229.032333
last_seq = 6054
current_ts = 229.049484
current_received_ts = 229.049316
current_seq = 6055
count = 478
seq_step = 1
[228.959424] (kms_flip:2580) DEBUG: Vblank took 19us
[228.976122] (kms_flip:2580) DEBUG: name = flip
last_ts = 229.049484
last_received_ts = 229.049316
last_seq = 6055
current_ts = 229.066162
current_received_ts = 229.066055
current_seq = 6056
count = 479
seq_step = 1
[228.976174] (kms_flip:2580) DEBUG: Vblank took 22us
[228.992601] (kms_flip:2580) DEBUG: name = flip
last_ts = 229.066162
last_received_ts = 229.066055
last_seq = 6056
current_ts = 229.082825
current_received_ts = 229.082535
current_seq = 6057
count = 480
seq_step = 1
[228.992653] (kms_flip:2580) DEBUG: Vblank took 23us
[229.009411] (kms_flip:2580) DEBUG: name = flip
last_ts = 229.082825
last_received_ts = 229.082535
last_seq = 6057
current_ts = 229.099487
current_received_ts = 229.099350
current_seq = 6058
count = 481
seq_step = 1
[229.009465] (kms_flip:2580) DEBUG: Vblank took 24us
[229.026141] (kms_flip:2580) DEBUG: name = flip
last_ts = 229.099487
last_received_ts = 229.099350
last_seq = 6058
current_ts = 229.116165
current_received_ts = 229.116074
current_seq = 6059
count = 482
seq_step = 1
[229.026197] (kms_flip:2580) DEBUG: Vblank took 24us
[229.042604] (kms_flip:2580) DEBUG: name = flip
last_ts = 229.116165
last_received_ts = 229.116074
last_seq = 6059
current_ts = 229.132828
current_received_ts = 229.132523
current_seq = 6060
count = 483
seq_step = 1
[229.042653] (kms_flip:2580) DEBUG: Vblank took 22us
[229.059417] (kms_flip:2580) DEBUG: name = flip
last_ts = 229.132828
last_received_ts = 229.132523
last_seq = 6060
current_ts = 229.149490
current_received_ts = 229.149353
current_seq = 6061
count = 484
seq_step = 1
[229.059468] (kms_flip:2580) DEBUG: Vblank took 23us
[229.076152] (kms_flip:2580) DEBUG: name = flip
last_ts = 229.149490
last_received_ts = 229.149353
last_seq = 6061
current_ts = 229.166153
current_received_ts = 229.166092
current_seq = 6062
count = 485
seq_step = 1
[229.076204] (kms_flip:2580) DEBUG: Vblank took 23us
[229.092587] (kms_flip:2580) DEBUG: name = flip
last_ts = 229.166153
last_received_ts = 229.166092
last_seq = 6062
current_ts = 229.182831
current_received_ts = 229.182526
current_seq = 6063
count = 486
seq_step = 1
[229.092635] (kms_flip:2580) DEBUG: Vblank took 21us
[229.109388] (kms_flip:2580) DEBUG: name = flip
last_ts = 229.182831
last_received_ts = 229.182526
last_seq = 6063
current_ts = 229.199493
current_received_ts = 229.199326
current_seq = 6064
count = 487
seq_step = 1
[229.109440] (kms_flip:2580) DEBUG: Vblank took 23us
[229.126172] (kms_flip:2580) DEBUG: name = flip
last_ts = 229.199493
last_received_ts = 229.199326
last_seq = 6064
current_ts = 229.216171
current_received_ts = 229.216110
current_seq = 6065
count = 488
seq_step = 1
[229.126223] (kms_flip:2580) DEBUG: Vblank took 23us
[229.142399] (kms_flip:2580) DEBUG: name = flip
last_ts = 229.216171
last_received_ts = 229.216110
last_seq = 6065
current_ts = 229.232819
current_received_ts = 229.232346
current_seq = 6066
count = 489
seq_step = 1
[229.142435] (kms_flip:2580) DEBUG: Vblank took 16us
[229.159415] (kms_flip:2580) DEBUG: name = flip
last_ts = 229.232819
last_received_ts = 229.232346
last_seq = 6066
current_ts = 229.249496
current_received_ts = 229.249344
current_seq = 6067
count = 490
seq_step = 1
[229.159467] (kms_flip:2580) DEBUG: Vblank took 23us
[229.176106] (kms_flip:2580) DEBUG: name = flip
last_ts = 229.249496
last_received_ts = 229.249344
last_seq = 6067
current_ts = 229.266159
current_received_ts = 229.266037
current_seq = 6068
count = 491
seq_step = 1
[229.176158] (kms_flip:2580) DEBUG: Vblank took 23us
[229.192549] (kms_flip:2580) DEBUG: name = flip
last_ts = 229.266159
last_received_ts = 229.266037
last_seq = 6068
current_ts = 229.282822
current_received_ts = 229.282486
current_seq = 6069
count = 492
seq_step = 1
[229.192604] (kms_flip:2580) DEBUG: Vblank took 25us
[229.209425] (kms_flip:2580) DEBUG: name = flip
last_ts = 229.282822
last_received_ts = 229.282486
last_seq = 6069
current_ts = 229.299500
current_received_ts = 229.299362
current_seq = 6070
count = 493
seq_step = 1
[229.209472] (kms_flip:2580) DEBUG: Vblank took 21us
[229.226168] (kms_flip:2580) DEBUG: name = flip
last_ts = 229.299500
last_received_ts = 229.299362
last_seq = 6070
current_ts = 229.316162
current_received_ts = 229.316101
current_seq = 6071
count = 494
seq_step = 1
[229.226219] (kms_flip:2580) DEBUG: Vblank took 23us
[229.242591] (kms_flip:2580) DEBUG: name = flip
last_ts = 229.316162
last_received_ts = 229.316101
last_seq = 6071
current_ts = 229.332825
current_received_ts = 229.332520
current_seq = 6072
count = 495
seq_step = 1
[229.242642] (kms_flip:2580) DEBUG: Vblank took 23us
[229.259351] (kms_flip:2580) DEBUG: name = flip
last_ts = 229.332825
last_received_ts = 229.332520
last_seq = 6072
current_ts = 229.349487
current_received_ts = 229.349289
current_seq = 6073
count = 496
seq_step = 1
[229.259403] (kms_flip:2580) DEBUG: Vblank took 23us
[229.276133] (kms_flip:2580) DEBUG: name = flip
last_ts = 229.349487
last_received_ts = 229.349289
last_seq = 6073
current_ts = 229.366150
current_received_ts = 229.365997
current_seq = 6074
count = 497
seq_step = 1
[229.276190] (kms_flip:2580) DEBUG: Vblank took 25us
[229.292617] (kms_flip:2580) DEBUG: name = flip
last_ts = 229.366150
last_received_ts = 229.365997
last_seq = 6074
current_ts = 229.382812
current_received_ts = 229.382538
current_seq = 6075
count = 498
seq_step = 1
[229.292662] (kms_flip:2580) DEBUG: Vblank took 20us
[229.309442] (kms_flip:2580) DEBUG: name = flip
last_ts = 229.382812
last_received_ts = 229.382538
last_seq = 6075
current_ts = 229.399490
current_received_ts = 229.399384
current_seq = 6076
count = 499
seq_step = 1
[229.309490] (kms_flip:2580) DEBUG: Vblank took 21us
[229.326121] (kms_flip:2580) DEBUG: name = flip
last_ts = 229.399490
last_received_ts = 229.399384
last_seq = 6076
current_ts = 229.416153
current_received_ts = 229.415970
current_seq = 6077
count = 500
seq_step = 1
[229.326181] (kms_flip:2580) DEBUG: Vblank took 26us
[229.342480] (kms_flip:2580) DEBUG: name = flip
last_ts = 229.416153
last_received_ts = 229.415970
last_seq = 6077
current_ts = 229.432816
current_received_ts = 229.432419
current_seq = 6078
count = 501
seq_step = 1
[229.342536] (kms_flip:2580) DEBUG: Vblank took 17us
[229.359321] (kms_flip:2580) DEBUG: name = flip
last_ts = 229.432816
last_received_ts = 229.432419
last_seq = 6078
current_ts = 229.449478
current_received_ts = 229.449249
current_seq = 6079
count = 502
seq_step = 1
[229.359375] (kms_flip:2580) DEBUG: Vblank took 24us
[229.376078] (kms_flip:2580) DEBUG: name = flip
last_ts = 229.449478
last_received_ts = 229.449249
last_seq = 6079
current_ts = 229.466156
current_received_ts = 229.465973
current_seq = 6080
count = 503
seq_step = 1
[229.376129] (kms_flip:2580) DEBUG: Vblank took 24us
[229.392602] (kms_flip:2580) DEBUG: name = flip
last_ts = 229.466156
last_received_ts = 229.465973
last_seq = 6080
current_ts = 229.482819
current_received_ts = 229.482529
current_seq = 6081
count = 504
seq_step = 1
[229.392653] (kms_flip:2580) DEBUG: Vblank took 23us
[229.409316] (kms_flip:2580) DEBUG: name = flip
last_ts = 229.482819
last_received_ts = 229.482529
last_seq = 6081
current_ts = 229.499481
current_received_ts = 229.499252
current_seq = 6082
count = 505
seq_step = 1
[229.409371] (kms_flip:2580) DEBUG: Vblank took 25us
[229.425751] (kms_flip:2580) DEBUG: name = flip
last_ts = 229.499481
last_received_ts = 229.499252
last_seq = 6082
current_ts = 229.516159
current_received_ts = 229.515686
current_seq = 6083
count = 506
seq_step = 1
[229.425799] (kms_flip:2580) DEBUG: Vblank took 22us
[229.442588] (kms_flip:2580) DEBUG: name = flip
last_ts = 229.516159
last_received_ts = 229.515686
last_seq = 6083
current_ts = 229.532822
current_received_ts = 229.532516
current_seq = 6084
count = 507
seq_step = 1
[229.442636] (kms_flip:2580) DEBUG: Vblank took 21us
[229.459433] (kms_flip:2580) DEBUG: name = flip
last_ts = 229.532822
last_received_ts = 229.532516
last_seq = 6084
current_ts = 229.549500
current_received_ts = 229.549362
current_seq = 6085
count = 508
seq_step = 1
[229.459483] (kms_flip:2580) DEBUG: Vblank took 22us
[229.476181] (kms_flip:2580) DEBUG: name = flip
last_ts = 229.549500
last_received_ts = 229.549362
last_seq = 6085
current_ts = 229.566162
current_received_ts = 229.566116
current_seq = 6086
count = 509
seq_step = 1
[229.476230] (kms_flip:2580) DEBUG: Vblank took 22us
[229.492623] (kms_flip:2580) DEBUG: name = flip
last_ts = 229.566162
last_received_ts = 229.566116
last_seq = 6086
current_ts = 229.582825
current_received_ts = 229.582550
current_seq = 6087
count = 510
seq_step = 1
[229.492671] (kms_flip:2580) DEBUG: Vblank took 22us
[229.509397] (kms_flip:2580) DEBUG: name = flip
last_ts = 229.582825
last_received_ts = 229.582550
last_seq = 6087
current_ts = 229.599487
current_received_ts = 229.599335
current_seq = 6088
count = 511
seq_step = 1
[229.509445] (kms_flip:2580) DEBUG: Vblank took 23us
[229.526138] (kms_flip:2580) DEBUG: name = flip
last_ts = 229.599487
last_received_ts = 229.599335
last_seq = 6088
current_ts = 229.616150
current_received_ts = 229.616074
current_seq = 6089
count = 512
seq_step = 1
[229.526186] (kms_flip:2580) DEBUG: Vblank took 21us
[229.542465] (kms_flip:2580) DEBUG: name = flip
last_ts = 229.616150
last_received_ts = 229.616074
last_seq = 6089
current_ts = 229.632812
current_received_ts = 229.632401
current_seq = 6090
count = 513
seq_step = 1
[229.542519] (kms_flip:2580) DEBUG: Vblank took 16us
[229.559381] (kms_flip:2580) DEBUG: name = flip
last_ts = 229.632812
last_received_ts = 229.632401
last_seq = 6090
current_ts = 229.649490
current_received_ts = 229.649307
current_seq = 6091
count = 514
seq_step = 1
[229.559431] (kms_flip:2580) DEBUG: Vblank took 21us
[229.576142] (kms_flip:2580) DEBUG: name = flip
last_ts = 229.649490
last_received_ts = 229.649307
last_seq = 6091
current_ts = 229.666153
current_received_ts = 229.666077
current_seq = 6092
count = 515
seq_step = 1
[229.576190] (kms_flip:2580) DEBUG: Vblank took 21us
[229.592585] (kms_flip:2580) DEBUG: name = flip
last_ts = 229.666153
last_received_ts = 229.666077
last_seq = 6092
current_ts = 229.682831
current_received_ts = 229.682510
current_seq = 6093
count = 516
seq_step = 1
[229.592634] (kms_flip:2580) DEBUG: Vblank took 21us
[229.609476] (kms_flip:2580) DEBUG: name = flip
last_ts = 229.682831
last_received_ts = 229.682510
last_seq = 6093
current_ts = 229.699493
current_received_ts = 229.699402
current_seq = 6094
count = 517
seq_step = 1
[229.609524] (kms_flip:2580) DEBUG: Vblank took 21us
[229.626138] (kms_flip:2580) DEBUG: name = flip
last_ts = 229.699493
last_received_ts = 229.699402
last_seq = 6094
current_ts = 229.716156
current_received_ts = 229.716064
current_seq = 6095
count = 518
seq_step = 1
[229.626188] (kms_flip:2580) DEBUG: Vblank took 23us
[229.642622] (kms_flip:2580) DEBUG: name = flip
last_ts = 229.716156
last_received_ts = 229.716064
last_seq = 6095
current_ts = 229.732819
current_received_ts = 229.732559
current_seq = 6096
count = 519
seq_step = 1
[229.642669] (kms_flip:2580) DEBUG: Vblank took 22us
[229.659386] (kms_flip:2580) DEBUG: name = flip
last_ts = 229.732819
last_received_ts = 229.732559
last_seq = 6096
current_ts = 229.749481
current_received_ts = 229.749313
current_seq = 6097
count = 520
seq_step = 1
[229.659436] (kms_flip:2580) DEBUG: Vblank took 22us
[229.676173] (kms_flip:2580) DEBUG: name = flip
last_ts = 229.749481
last_received_ts = 229.749313
last_seq = 6097
current_ts = 229.766159
current_received_ts = 229.766098
current_seq = 6098
count = 521
seq_step = 1
[229.676221] (kms_flip:2580) DEBUG: Vblank took 22us
[229.692605] (kms_flip:2580) DEBUG: name = flip
last_ts = 229.766159
last_received_ts = 229.766098
last_seq = 6098
current_ts = 229.782806
current_received_ts = 229.782532
current_seq = 6099
count = 522
seq_step = 1
[229.692654] (kms_flip:2580) DEBUG: Vblank took 21us
[229.709416] (kms_flip:2580) DEBUG: name = flip
last_ts = 229.782806
last_received_ts = 229.782532
last_seq = 6099
current_ts = 229.799484
current_received_ts = 229.799347
current_seq = 6100
count = 523
seq_step = 1
[229.709465] (kms_flip:2580) DEBUG: Vblank took 22us
[229.726206] (kms_flip:2580) DEBUG: name = flip
last_ts = 229.799484
last_received_ts = 229.799347
last_seq = 6100
current_ts = 229.816162
current_received_ts = 229.816132
current_seq = 6101
count = 524
seq_step = 1
[229.726255] (kms_flip:2580) DEBUG: Vblank took 21us
[229.742446] (kms_flip:2580) DEBUG: name = flip
last_ts = 229.816162
last_received_ts = 229.816132
last_seq = 6101
current_ts = 229.832825
current_received_ts = 229.832306
current_seq = 6102
count = 525
seq_step = 1
[229.742483] (kms_flip:2580) DEBUG: Vblank took 17us
[229.759427] (kms_flip:2580) DEBUG: name = flip
last_ts = 229.832825
last_received_ts = 229.832306
last_seq = 6102
current_ts = 229.849487
current_received_ts = 229.849350
current_seq = 6103
count = 526
seq_step = 1
[229.759475] (kms_flip:2580) DEBUG: Vblank took 22us
[229.776144] (kms_flip:2580) DEBUG: name = flip
last_ts = 229.849487
last_received_ts = 229.849350
last_seq = 6103
current_ts = 229.866150
current_received_ts = 229.866074
current_seq = 6104
count = 527
seq_step = 1
[229.776196] (kms_flip:2580) DEBUG: Vblank took 23us
[229.792622] (kms_flip:2580) DEBUG: name = flip
last_ts = 229.866150
last_received_ts = 229.866074
last_seq = 6104
current_ts = 229.882812
current_received_ts = 229.882553
current_seq = 6105
count = 528
seq_step = 1
[229.792670] (kms_flip:2580) DEBUG: Vblank took 22us
[229.809385] (kms_flip:2580) DEBUG: name = flip
last_ts = 229.882812
last_received_ts = 229.882553
last_seq = 6105
current_ts = 229.899490
current_received_ts = 229.899307
current_seq = 6106
count = 529
seq_step = 1
[229.809433] (kms_flip:2580) DEBUG: Vblank took 21us
[229.826190] (kms_flip:2580) DEBUG: name = flip
last_ts = 229.899490
last_received_ts = 229.899307
last_seq = 6106
current_ts = 229.916153
current_received_ts = 229.916122
current_seq = 6107
count = 530
seq_step = 1
[229.826241] (kms_flip:2580) DEBUG: Vblank took 23us
[229.842629] (kms_flip:2580) DEBUG: name = flip
last_ts = 229.916153
last_received_ts = 229.916122
last_seq = 6107
current_ts = 229.932816
current_received_ts = 229.932556
current_seq = 6108
count = 531
seq_step = 1
[229.842681] (kms_flip:2580) DEBUG: Vblank took 23us
[229.859418] (kms_flip:2580) DEBUG: name = flip
last_ts = 229.932816
last_received_ts = 229.932556
last_seq = 6108
current_ts = 229.949493
current_received_ts = 229.949341
current_seq = 6109
count = 532
seq_step = 1
[229.859467] (kms_flip:2580) DEBUG: Vblank took 22us
[229.876112] (kms_flip:2580) DEBUG: name = flip
last_ts = 229.949493
last_received_ts = 229.949341
last_seq = 6109
current_ts = 229.966156
current_received_ts = 229.966034
current_seq = 6110
count = 533
seq_step = 1
[229.876164] (kms_flip:2580) DEBUG: Vblank took 23us
[229.892580] (kms_flip:2580) DEBUG: name = flip
last_ts = 229.966156
last_received_ts = 229.966034
last_seq = 6110
current_ts = 229.982819
current_received_ts = 229.982513
current_seq = 6111
count = 534
seq_step = 1
[229.892626] (kms_flip:2580) DEBUG: Vblank took 20us
[229.909383] (kms_flip:2580) DEBUG: name = flip
last_ts = 229.982819
last_received_ts = 229.982513
last_seq = 6111
current_ts = 229.999481
current_received_ts = 229.999313
current_seq = 6112
count = 535
seq_step = 1
[229.909432] (kms_flip:2580) DEBUG: Vblank took 22us
[229.926191] (kms_flip:2580) DEBUG: name = flip
last_ts = 229.999481
last_received_ts = 229.999313
last_seq = 6112
current_ts = 230.016144
current_received_ts = 230.016113
current_seq = 6113
count = 536
seq_step = 1
[229.926239] (kms_flip:2580) DEBUG: Vblank took 21us
[229.942465] (kms_flip:2580) DEBUG: name = flip
last_ts = 230.016144
last_received_ts = 230.016113
last_seq = 6113
current_ts = 230.032806
current_received_ts = 230.032394
current_seq = 6114
count = 537
seq_step = 1
[229.942521] (kms_flip:2580) DEBUG: Vblank took 33us
[229.959382] (kms_flip:2580) DEBUG: name = flip
last_ts = 230.032806
last_received_ts = 230.032394
last_seq = 6114
current_ts = 230.049484
current_received_ts = 230.049301
current_seq = 6115
count = 538
seq_step = 1
[229.959433] (kms_flip:2580) DEBUG: Vblank took 24us
[229.976210] (kms_flip:2580) DEBUG: name = flip
last_ts = 230.049484
last_received_ts = 230.049301
last_seq = 6115
current_ts = 230.066147
current_received_ts = 230.066132
current_seq = 6116
count = 539
seq_step = 1
[229.976261] (kms_flip:2580) DEBUG: Vblank took 23us
[229.992573] (kms_flip:2580) DEBUG: name = flip
last_ts = 230.066147
last_received_ts = 230.066132
last_seq = 6116
current_ts = 230.082825
current_received_ts = 230.082504
current_seq = 6117
count = 540
seq_step = 1
[229.992618] (kms_flip:2580) DEBUG: Vblank took 20us
[230.009391] (kms_flip:2580) DEBUG: name = flip
last_ts = 230.082825
last_received_ts = 230.082504
last_seq = 6117
current_ts = 230.099487
current_received_ts = 230.099304
current_seq = 6118
count = 541
seq_step = 1
[230.009435] (kms_flip:2580) DEBUG: Vblank took 20us
[230.025842] (kms_flip:2580) DEBUG: name = flip
last_ts = 230.099487
last_received_ts = 230.099304
last_seq = 6118
current_ts = 230.116150
current_received_ts = 230.115768
current_seq = 6119
count = 542
seq_step = 1
[230.025891] (kms_flip:2580) DEBUG: Vblank took 22us
[230.042650] (kms_flip:2580) DEBUG: name = flip
last_ts = 230.116150
last_received_ts = 230.115768
last_seq = 6119
current_ts = 230.132812
current_received_ts = 230.132568
current_seq = 6120
count = 543
seq_step = 1
[230.042702] (kms_flip:2580) DEBUG: Vblank took 23us
[230.059369] (kms_flip:2580) DEBUG: name = flip
last_ts = 230.132812
last_received_ts = 230.132568
last_seq = 6120
current_ts = 230.149475
current_received_ts = 230.149292
current_seq = 6121
count = 544
seq_step = 1
[230.059418] (kms_flip:2580) DEBUG: Vblank took 22us
[230.076155] (kms_flip:2580) DEBUG: name = flip
last_ts = 230.149475
last_received_ts = 230.149292
last_seq = 6121
current_ts = 230.166138
current_received_ts = 230.166077
current_seq = 6122
count = 545
seq_step = 1
[230.076350] (kms_flip:2580) DEBUG: Vblank took 24us
[230.092554] (kms_flip:2580) DEBUG: name = flip
last_ts = 230.166138
last_received_ts = 230.166077
last_seq = 6122
current_ts = 230.182816
current_received_ts = 230.182480
current_seq = 6123
count = 546
seq_step = 1
[230.092603] (kms_flip:2580) DEBUG: Vblank took 22us
[230.109380] (kms_flip:2580) DEBUG: name = flip
last_ts = 230.182816
last_received_ts = 230.182480
last_seq = 6123
current_ts = 230.199478
current_received_ts = 230.199310
current_seq = 6124
count = 547
seq_step = 1
[230.109429] (kms_flip:2580) DEBUG: Vblank took 22us
[230.126217] (kms_flip:2580) DEBUG: name = flip
last_ts = 230.199478
last_received_ts = 230.199310
last_seq = 6124
current_ts = 230.216141
current_received_ts = 230.216141
current_seq = 6125
count = 548
seq_step = 1
[230.126266] (kms_flip:2580) DEBUG: Vblank took 22us
[230.142418] (kms_flip:2580) DEBUG: name = flip
last_ts = 230.216141
last_received_ts = 230.216141
last_seq = 6125
current_ts = 230.232819
current_received_ts = 230.232346
current_seq = 6126
count = 549
seq_step = 1
[230.142463] (kms_flip:2580) DEBUG: Vblank took 20us
[230.159422] (kms_flip:2580) DEBUG: name = flip
last_ts = 230.232819
last_received_ts = 230.232346
last_seq = 6126
current_ts = 230.249481
current_received_ts = 230.249344
current_seq = 6127
count = 550
seq_step = 1
[230.159472] (kms_flip:2580) DEBUG: Vblank took 23us
[230.176224] (kms_flip:2580) DEBUG: name = flip
last_ts = 230.249481
last_received_ts = 230.249344
last_seq = 6127
current_ts = 230.266144
current_received_ts = 230.266144
current_seq = 6128
count = 551
seq_step = 1
[230.176272] (kms_flip:2580) DEBUG: Vblank took 21us
[230.192659] (kms_flip:2580) DEBUG: name = flip
last_ts = 230.266144
last_received_ts = 230.266144
last_seq = 6128
current_ts = 230.282822
current_received_ts = 230.282578
current_seq = 6129
count = 552
seq_step = 1
[230.192706] (kms_flip:2580) DEBUG: Vblank took 21us
[230.209383] (kms_flip:2580) DEBUG: name = flip
last_ts = 230.282822
last_received_ts = 230.282578
last_seq = 6129
current_ts = 230.299484
current_received_ts = 230.299301
current_seq = 6130
count = 553
seq_step = 1
[230.209438] (kms_flip:2580) DEBUG: Vblank took 23us
[230.226207] (kms_flip:2580) DEBUG: name = flip
last_ts = 230.299484
last_received_ts = 230.299301
last_seq = 6130
current_ts = 230.316147
current_received_ts = 230.316132
current_seq = 6131
count = 554
seq_step = 1
[230.226258] (kms_flip:2580) DEBUG: Vblank took 24us
[230.242632] (kms_flip:2580) DEBUG: name = flip
last_ts = 230.316147
last_received_ts = 230.316132
last_seq = 6131
current_ts = 230.332825
current_received_ts = 230.332550
current_seq = 6132
count = 555
seq_step = 1
[230.242680] (kms_flip:2580) DEBUG: Vblank took 22us
[230.259323] (kms_flip:2580) DEBUG: name = flip
last_ts = 230.332825
last_received_ts = 230.332550
last_seq = 6132
current_ts = 230.349472
current_received_ts = 230.349243
current_seq = 6133
count = 556
seq_step = 1
[230.259379] (kms_flip:2580) DEBUG: Vblank took 25us
[230.276158] (kms_flip:2580) DEBUG: name = flip
last_ts = 230.349472
last_received_ts = 230.349243
last_seq = 6133
current_ts = 230.366150
current_received_ts = 230.366089
current_seq = 6134
count = 557
seq_step = 1
[230.276204] (kms_flip:2580) DEBUG: Vblank took 20us
[230.292578] (kms_flip:2580) DEBUG: name = flip
last_ts = 230.366150
last_received_ts = 230.366089
last_seq = 6134
current_ts = 230.382812
current_received_ts = 230.382492
current_seq = 6135
count = 558
seq_step = 1
[230.292633] (kms_flip:2580) DEBUG: Vblank took 25us
[230.309317] (kms_flip:2580) DEBUG: name = flip
last_ts = 230.382812
last_received_ts = 230.382492
last_seq = 6135
current_ts = 230.399475
current_received_ts = 230.399246
current_seq = 6136
count = 559
seq_step = 1
[230.309363] (kms_flip:2580) DEBUG: Vblank took 20us
[230.326016] (kms_flip:2580) DEBUG: name = flip
last_ts = 230.399475
last_received_ts = 230.399246
last_seq = 6136
current_ts = 230.416138
current_received_ts = 230.415939
current_seq = 6137
count = 560
seq_step = 1
[230.326136] (kms_flip:2580) DEBUG: Vblank took 88us
[230.342477] (kms_flip:2580) DEBUG: name = flip
last_ts = 230.416138
last_received_ts = 230.415939
last_seq = 6137
current_ts = 230.432800
current_received_ts = 230.432404
current_seq = 6138
count = 561
seq_step = 1
[230.342538] (kms_flip:2580) DEBUG: Vblank took 16us
[230.359329] (kms_flip:2580) DEBUG: name = flip
last_ts = 230.432800
last_received_ts = 230.432404
last_seq = 6138
current_ts = 230.449478
current_received_ts = 230.449249
current_seq = 6139
count = 562
seq_step = 1
[230.359385] (kms_flip:2580) DEBUG: Vblank took 25us
[230.376112] (kms_flip:2580) DEBUG: name = flip
last_ts = 230.449478
last_received_ts = 230.449249
last_seq = 6139
current_ts = 230.466141
current_received_ts = 230.466003
current_seq = 6140
count = 563
seq_step = 1
[230.376163] (kms_flip:2580) DEBUG: Vblank took 23us
[230.392623] (kms_flip:2580) DEBUG: name = flip
last_ts = 230.466141
last_received_ts = 230.466003
last_seq = 6140
current_ts = 230.482819
current_received_ts = 230.482544
current_seq = 6141
count = 564
seq_step = 1
[230.392672] (kms_flip:2580) DEBUG: Vblank took 21us
[230.409396] (kms_flip:2580) DEBUG: name = flip
last_ts = 230.482819
last_received_ts = 230.482544
last_seq = 6141
current_ts = 230.499481
current_received_ts = 230.499313
current_seq = 6142
count = 565
seq_step = 1
[230.409448] (kms_flip:2580) DEBUG: Vblank took 23us
[230.425930] (kms_flip:2580) DEBUG: name = flip
last_ts = 230.499481
last_received_ts = 230.499313
last_seq = 6142
current_ts = 230.516144
current_received_ts = 230.515854
current_seq = 6143
count = 566
seq_step = 1
[230.425985] (kms_flip:2580) DEBUG: Vblank took 24us
[230.442635] (kms_flip:2580) DEBUG: name = flip
last_ts = 230.516144
last_received_ts = 230.515854
last_seq = 6143
current_ts = 230.532806
current_received_ts = 230.532562
current_seq = 6144
count = 567
seq_step = 1
[230.442684] (kms_flip:2580) DEBUG: Vblank took 22us
[230.459388] (kms_flip:2580) DEBUG: name = flip
last_ts = 230.532806
last_received_ts = 230.532562
last_seq = 6144
current_ts = 230.549469
current_received_ts = 230.549301
current_seq = 6145
count = 568
seq_step = 1
[230.459440] (kms_flip:2580) DEBUG: Vblank took 23us
[230.476176] (kms_flip:2580) DEBUG: name = flip
last_ts = 230.549469
last_received_ts = 230.549301
last_seq = 6145
current_ts = 230.566132
current_received_ts = 230.566101
current_seq = 6146
count = 569
seq_step = 1
[230.476231] (kms_flip:2580) DEBUG: Vblank took 23us
[230.492634] (kms_flip:2580) DEBUG: name = flip
last_ts = 230.566132
last_received_ts = 230.566101
last_seq = 6146
current_ts = 230.582809
current_received_ts = 230.582535
current_seq = 6147
count = 570
seq_step = 1
[230.492680] (kms_flip:2580) DEBUG: Vblank took 20us
[230.509386] (kms_flip:2580) DEBUG: name = flip
last_ts = 230.582809
last_received_ts = 230.582535
last_seq = 6147
current_ts = 230.599472
current_received_ts = 230.599304
current_seq = 6148
count = 571
seq_step = 1
[230.509438] (kms_flip:2580) DEBUG: Vblank took 23us
[230.526172] (kms_flip:2580) DEBUG: name = flip
last_ts = 230.599472
last_received_ts = 230.599304
last_seq = 6148
current_ts = 230.616135
current_received_ts = 230.616089
current_seq = 6149
count = 572
seq_step = 1
[230.526219] (kms_flip:2580) DEBUG: Vblank took 21us
[230.542414] (kms_flip:2580) DEBUG: name = flip
last_ts = 230.616135
last_received_ts = 230.616089
last_seq = 6149
current_ts = 230.632812
current_received_ts = 230.632324
current_seq = 6150
count = 573
seq_step = 1
[230.542462] (kms_flip:2580) DEBUG: Vblank took 22us
[230.559313] (kms_flip:2580) DEBUG: name = flip
last_ts = 230.632812
last_received_ts = 230.632324
last_seq = 6150
current_ts = 230.649475
current_received_ts = 230.649231
current_seq = 6151
count = 574
seq_step = 1
[230.559368] (kms_flip:2580) DEBUG: Vblank took 25us
[230.576230] (kms_flip:2580) DEBUG: name = flip
last_ts = 230.649475
last_received_ts = 230.649231
last_seq = 6151
current_ts = 230.666138
current_received_ts = 230.666153
current_seq = 6152
count = 575
seq_step = 1
[230.576277] (kms_flip:2580) DEBUG: Vblank took 22us
[230.592649] (kms_flip:2580) DEBUG: name = flip
last_ts = 230.666138
last_received_ts = 230.666153
last_seq = 6152
current_ts = 230.682816
current_received_ts = 230.682556
current_seq = 6153
count = 576
seq_step = 1
[230.592694] (kms_flip:2580) DEBUG: Vblank took 20us
[230.609388] (kms_flip:2580) DEBUG: name = flip
last_ts = 230.682816
last_received_ts = 230.682556
last_seq = 6153
current_ts = 230.699478
current_received_ts = 230.699310
current_seq = 6154
count = 577
seq_step = 1
[230.609438] (kms_flip:2580) DEBUG: Vblank took 21us
[230.626116] (kms_flip:2580) DEBUG: name = flip
last_ts = 230.699478
last_received_ts = 230.699310
last_seq = 6154
current_ts = 230.716141
current_received_ts = 230.716003
current_seq = 6155
count = 578
seq_step = 1
[230.626226] (kms_flip:2580) DEBUG: Vblank took 24us
[230.642498] (kms_flip:2580) DEBUG: name = flip
last_ts = 230.716141
last_received_ts = 230.716003
last_seq = 6155
current_ts = 230.732803
current_received_ts = 230.732407
current_seq = 6156
count = 579
seq_step = 1
[230.642546] (kms_flip:2580) DEBUG: Vblank took 22us
[230.659386] (kms_flip:2580) DEBUG: name = flip
last_ts = 230.732803
last_received_ts = 230.732407
last_seq = 6156
current_ts = 230.749466
current_received_ts = 230.749298
current_seq = 6157
count = 580
seq_step = 1
[230.659438] (kms_flip:2580) DEBUG: Vblank took 24us
[230.676186] (kms_flip:2580) DEBUG: name = flip
last_ts = 230.749466
last_received_ts = 230.749298
last_seq = 6157
current_ts = 230.766144
current_received_ts = 230.766098
current_seq = 6158
count = 581
seq_step = 1
[230.676238] (kms_flip:2580) DEBUG: Vblank took 23us
[230.692630] (kms_flip:2580) DEBUG: name = flip
last_ts = 230.766144
last_received_ts = 230.766098
last_seq = 6158
current_ts = 230.782806
current_received_ts = 230.782547
current_seq = 6159
count = 582
seq_step = 1
[230.692681] (kms_flip:2580) DEBUG: Vblank took 23us
[230.709405] (kms_flip:2580) DEBUG: name = flip
last_ts = 230.782806
last_received_ts = 230.782547
last_seq = 6159
current_ts = 230.799469
current_received_ts = 230.799316
current_seq = 6160
count = 583
seq_step = 1
[230.709456] (kms_flip:2580) DEBUG: Vblank took 23us
[230.726127] (kms_flip:2580) DEBUG: name = flip
last_ts = 230.799469
last_received_ts = 230.799316
last_seq = 6160
current_ts = 230.816132
current_received_ts = 230.815964
current_seq = 6161
count = 584
seq_step = 1
[230.726183] (kms_flip:2580) DEBUG: Vblank took 25us
[230.742474] (kms_flip:2580) DEBUG: name = flip
last_ts = 230.816132
last_received_ts = 230.815964
last_seq = 6161
current_ts = 230.832809
current_received_ts = 230.832397
current_seq = 6162
count = 585
seq_step = 1
[230.744269] (kms_flip:2580) DEBUG: Vblank took 1763us
[230.744283] (kms_flip:2580) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931:
[230.744855] (kms_flip:2580) CRITICAL: Failed assertion: end - start < 500
[230.750619] (kms_flip:2580) igt_core-INFO: Stack trace:
[230.779472] (kms_flip:2580) igt_core-INFO:   #0 ../lib/igt_core.c:2106 __igt_fail_assert()
[230.781447] (kms_flip:2580) igt_core-INFO:   #1 ../tests/kms_flip.c:926 run_test_on_crtc_set()
[230.781621] (kms_flip:2580) igt_core-INFO:   #2 ../tests/kms_flip.c:2029 run_pair()
[230.781722] (kms_flip:2580) igt_core-INFO:   #3 ../tests/kms_flip.c:2193 __igt_unique____real_main2105()
[230.781789] (kms_flip:2580) igt_core-INFO:   #4 ../tests/kms_flip.c:2105 main()
[230.793668] (kms_flip:2580) igt_core-INFO:   #5 [__libc_init_first+0x8a]
[230.795813] (kms_flip:2580) igt_core-INFO:   #6 [__libc_start_main+0x8b]
[230.796433] (kms_flip:2580) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest AC-HDMI-A1-HDMI-A2: FAIL (10.917s)
Dmesg
<6> [220.448575] [IGT] kms_flip: starting dynamic subtest AC-HDMI-A1-HDMI-A2
<7> [220.451409] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:179]
<7> [220.452123] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:185]
<7> [220.509861] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:78:pipe A]
<7> [220.510371] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [220.511819] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [220.513039] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [220.514280] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [220.515130] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in lane_lat_optim_mask (expected 0x0000000d, found 0x00000000)
<7> [220.515601] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [220.515988] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [220.516369] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [220.516859] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [220.517249] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [220.517729] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [220.518218] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [220.518697] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [220.519083] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [220.519647] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [220.520060] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1083, found 0)
<7> [220.520441] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1088, found 0)
<7> [220.520935] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [220.521348] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [220.521871] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [220.522253] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [220.522761] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [220.523148] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [220.523667] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [220.524063] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [220.524447] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [220.524950] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [220.525335] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1083, found 0)
<7> [220.525799] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1088, found 0)
<7> [220.526183] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [220.526748] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [220.527137] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [220.527726] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [220.528140] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [220.528648] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [220.529062] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [220.529448] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in pipe_bpp (expected 36, found 0)
<7> [220.529951] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [220.530367] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [220.530841] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in port_clock (expected 222750, found 0)
<7> [220.531225] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [220.531772] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in infoframes.gcp (expected 0x00000006, found 0x00000000)
<7> [220.532185] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in avi infoframe
<7> [220.532684] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [220.533066] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [220.533071] i915 0000:00:02.0: colorspace: RGB
<7> [220.533075] i915 0000:00:02.0: scan mode: Underscan
<7> [220.533078] i915 0000:00:02.0: colorimetry: No Data
<7> [220.533081] i915 0000:00:02.0: picture aspect: No Data
<7> [220.533085] i915 0000:00:02.0: active aspect: Same as Picture
<7> [220.533092] i915 0000:00:02.0: itc: No Data
<7> [220.533095] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [220.533099] i915 0000:00:02.0: quantization range: Full
<7> [220.533102] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [220.533105] i915 0000:00:02.0: video code: 0
<7> [220.533109] i915 0000:00:02.0: ycc quantization range: Full
<7> [220.533112] i915 0000:00:02.0: hdmi content type: Graphics
<7> [220.533115] i915 0000:00:02.0: pixel repeat: 0
<7> [220.533118] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [220.533122] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [220.533632] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in spd infoframe
<7> [220.534014] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [220.534397] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [220.534401] i915 0000:00:02.0: vendor: Intel
<7> [220.534404] i915 0000:00:02.0: product: Integrated gfx
<7> [220.534407] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [220.534411] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [220.534907] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hdmi infoframe
<7> [220.535378] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [220.535934] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [220.535939] i915 0000:00:02.0: empty frame
<7> [220.535942] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [220.536346] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:78:pipe A] fastset requirement not met, forcing full modeset
<7> [220.536827] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:78:pipe A] releasing PORT PLL B
<7> [220.537304] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:78:pipe A] dbuf slices 0x1 -> 0x0, ddb (0 - 510) -> (0 - 0), active pipes 0x3 -> 0x2
<7> [220.537823] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:121:pipe B] dbuf slices 0x1 -> 0x1, ddb (510 - 1020) -> (0 - 1020), active pipes 0x3 -> 0x2
<7> [220.538323] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:72:cursor A] ddb ( 497 - 510) -> ( 0 - 0), size 13 -> 0
<7> [220.538842] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:115:cursor B] ddb (1007 - 1020) -> ( 988 - 1020), size 13 -> 32
<7> [220.539271] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:78:pipe A] min cdclk: 192000 kHz -> 0 kHz
<7> [220.539824] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:78:pipe A] enable: no [modeset]
<7> [220.540238] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:36:plane 1A] fb: [NOFB], visible: no
<7> [220.540743] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:45:plane 2A] fb: [NOFB], visible: no
<7> [220.541127] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:54:plane 3A] fb: [NOFB], visible: no
<7> [220.541592] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:63:plane 4A] fb: [NOFB], visible: no
<7> [220.541975] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:72:cursor A] fb: [NOFB], visible: no
<7> [220.542613] i915 0000:00:02.0: [drm:intel_audio_codec_disable [i915]] [CONNECTOR:166:HDMI-A-1][ENCODER:165:DDI B/PHY B] Disable audio codec on [CRTC:78:pipe A]
<7> [220.575779] i915 0000:00:02.0: [drm:intel_audio_component_get_eld [i915]] Not valid for port B
<7> [220.577974] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [220.589061] i915 0000:00:02.0: [drm:intel_audio_component_get_power [i915]] restored AUD_FREQ_CNTRL to 0x10
<7> [220.592559] i915 0000:00:02.0: [drm:intel_ddi_disable_transcoder_func [i915]] Quirk Increase DDI disabled time
<7> [220.698975] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_B
<7> [220.702714] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable PORT PLL B (active 0x1, on? 1) for [CRTC:78:pipe A]
<7> [220.703713] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling PORT PLL B
<7> [220.704153] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:165:DDI B/PHY B]
<7> [220.705300] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:175:DDI C/PHY C]
<7> [220.706159] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:166:HDMI-A-1]
<7> [220.721113] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling dpio-common-b
<7> [220.722414] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:78:pipe A]
<7> [220.725312] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:121:pipe B]
<7> [220.725718] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.enable (expected yes, found no)
<7> [220.726204] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.active (expected yes, found no)
<7> [220.726724] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in cpu_transcoder (expected 1, found -1)
<7> [220.727123] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in lane_count (expected 4, found 0)
<7> [220.727656] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in lane_lat_optim_mask (expected 0x0000000d, found 0x00000000)
<7> [220.728049] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [220.728439] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [220.729123] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [220.729748] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [220.730147] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [220.730803] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [220.731190] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [220.731824] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [220.732285] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [220.732882] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [220.733268] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1083, found 0)
<7> [220.733826] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1088, found 0)
<7> [220.734217] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [220.734843] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [220.735246] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [220.735872] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [220.736263] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [220.736848] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [220.737232] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [220.737886] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [220.738304] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [220.738929] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [220.739315] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1083, found 0)
<7> [220.739941] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1088, found 0)
<7> [220.740336] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [220.741020] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [220.741422] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [220.742020] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [220.742416] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [220.742990] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [220.743379] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [220.743897] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in pipe_bpp (expected 36, found 0)
<7> [220.744283] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [220.744903] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [220.745302] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in port_clock (expected 222750, found 0)
<7> [220.745899] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [220.746289] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in infoframes.gcp (expected 0x00000006, found 0x00000000)
<7> [220.746850] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in avi infoframe
<7> [220.747246] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [220.747828] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [220.747834] i915 0000:00:02.0: colorspace: RGB
<7> [220.747838] i915 0000:00:02.0: scan mode: Underscan
<7> [220.747841] i915 0000:00:02.0: colorimetry: No Data
<7> [220.747845] i915 0000:00:02.0: picture aspect: No Data
<7> [220.747848] i915 0000:00:02.0: active aspect: Same as Picture
<7> [220.747852] i915 0000:00:02.0: itc: No Data
<7> [220.747854] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [220.747858] i915 0000:00:02.0: quantization range: Full
<7> [220.747861] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [220.747864] i915 0000:00:02.0: video code: 0
<7> [220.747867] i915 0000:00:02.0: ycc quantization range: Full
<7> [220.747870] i915 0000:00:02.0: hdmi content type: Graphics
<7> [220.747873] i915 0000:00:02.0: pixel repeat: 0
<7> [220.747876] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [220.747880] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [220.748278] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in spd infoframe
<7> [220.749396] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [220.749970] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [220.749975] i915 0000:00:02.0: vendor: Intel
<7> [220.749979] i915 0000:00:02.0: product: Integrated gfx
<7> [220.749982] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [220.749986] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [220.750386] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hdmi infoframe
<7> [220.751011] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [220.751410] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [220.751415] i915 0000:00:02.0: empty frame
<7> [220.751418] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [220.751994] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:121:pipe B] fastset requirement not met, forcing full modeset
<7> [220.752385] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:121:pipe B] releasing PORT PLL C
<7> [220.752965] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:121:pipe B] dbuf slices 0x1 -> 0x0, ddb (0 - 1020) -> (0 - 0), active pipes 0x2 -> 0x0
<7> [220.753404] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:115:cursor B] ddb ( 988 - 1020) -> ( 0 - 0), size 32 -> 0
<7> [220.754020] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:121:pipe B] min cdclk: 192000 kHz -> 0 kHz
<7> [220.754434] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:121:pipe B] enable: no [modeset]
<7> [220.755011] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:79:plane 1B] fb: [NOFB], visible: no
<7> [220.755411] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:88:plane 2B] fb: [NOFB], visible: no
<7> [220.755974] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:97:plane 3B] fb: [NOFB], visible: no
<7> [220.756364] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:106:plane 4B] fb: [NOFB], visible: no
<7> [220.756874] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:115:cursor B] fb: [NOFB], visible: no
<7> [220.757371] i915 0000:00:02.0: [drm:intel_audio_codec_disable [i915]] [CONNECTOR:176:HDMI-A-2][ENCODER:175:DDI C/PHY C] Disable audio codec on [CRTC:121:pipe B]
<7> [220.787584] i915 0000:00:02.0: [drm:intel_audio_component_get_eld [i915]] Not valid for port C
<7> [220.789284] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe B
<7> [220.805578] i915 0000:00:02.0: [drm:intel_ddi_disable_transcoder_func [i915]] Quirk Increase DDI disabled time
<7> [220.906997] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_C
<7> [220.908231] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable PORT PLL C (active 0x2, on? 1) for [CRTC:121:pipe B]
<7> [220.911252] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling PORT PLL C
<7> [220.912007] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:165:DDI B/PHY B]
<7> [220.912399] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:175:DDI C/PHY C]
<7> [220.913259] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:176:HDMI-A-2]
<7> [220.914105] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling dpio-common-c
<7> [220.914782] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:121:pipe B]
<7> [220.917399] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [220.917958] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 79200 kHz, actual 79200 kHz
<7> [220.918346] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 4, actual 4
<7> [220.919246] i915 0000:00:02.0: [drm:intel_set_cdclk [i915]] Pre changing CDCLK to 79200 kHz, VCO 633600 kHz, ref 19200 kHz, bypass 19200 kHz, voltage level 4
<7> [220.920133] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:164:pipe C]
<7> [220.920632] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:164:pipe C]
<7> [220.920693] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:176:HDMI-A-2]
<7> [220.920881] i915 0000:00:02.0: [drm:intel_hdmi_compute_output_format [i915]] picking 12 bpc for HDMI output (pipe bpp: 36)
<7> [220.922267] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:164:pipe C] hw max bpp: 36, pipe bpp: 36, dithering: 0
<7> [220.922805] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:175:DDI C/PHY C] [CRTC:164:pipe C]
<7> [220.923189] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.enable (expected no, found yes)
<7> [220.923784] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.active (expected no, found yes)
<7> [220.924171] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in cpu_transcoder (expected -1, found 2)
<7> [220.924613] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in lane_count (expected 0, found 4)
<7> [220.925003] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in lane_lat_optim_mask (expected 0x00000000, found 0x0000000d)
<7> [220.925391] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [220.926086] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [220.926528] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [220.926910] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [220.927290] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [220.927867] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [220.928261] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [220.928702] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [220.929096] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [220.929659] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [220.930044] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1083)
<7> [220.930428] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1088)
<7> [220.930852] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [220.931239] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [220.931723] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [220.932112] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [220.932597] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [220.932982] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [220.933365] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [220.933909] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [220.934305] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [220.935171] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [220.935631] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1083)
<7> [220.936013] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1088)
<7> [220.936619] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [220.937035] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [220.937419] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [220.937998] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [220.938394] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [220.938854] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in has_hdmi_sink (expected no, found yes)
<7> [220.939242] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in has_infoframe (expected no, found yes)
<7> [220.939732] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in pipe_bpp (expected 0, found 36)
<7> [220.940119] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [220.940556] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [220.940948] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in port_clock (expected 0, found 222750)
<7> [220.941327] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071)
<7> [220.941916] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in infoframes.gcp (expected 0x00000000, found 0x00000006)
<7> [220.942319] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in avi infoframe
<7> [220.942738] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [220.943118] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [220.943742] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [220.943748] i915 0000:00:02.0: colorspace: RGB
<7> [220.943752] i915 0000:00:02.0: scan mode: Underscan
<7> [220.943755] i915 0000:00:02.0: colorimetry: No Data
<7> [220.943758] i915 0000:00:02.0: picture aspect: No Data
<7> [220.943762] i915 0000:00:02.0: active aspect: Same as Picture
<7> [220.943765] i915 0000:00:02.0: itc: No Data
<7> [220.943768] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [220.943771] i915 0000:00:02.0: quantization range: Full
<7> [220.943774] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [220.943777] i915 0000:00:02.0: video code: 0
<7> [220.943780] i915 0000:00:02.0: ycc quantization range: Full
<7> [220.943783] i915 0000:00:02.0: hdmi content type: Graphics
<7> [220.943786] i915 0000:00:02.0: pixel repeat: 0
<7> [220.943789] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [220.943794] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in spd infoframe
<7> [220.944187] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [220.944608] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [220.944988] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [220.944992] i915 0000:00:02.0: vendor: Intel
<7> [220.944995] i915 0000:00:02.0: product: Integrated gfx
<7> [220.944998] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [220.945002] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hdmi infoframe
<7> [220.945387] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [220.946079] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [220.946505] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [220.946509] i915 0000:00:02.0: empty frame
<7> [220.946515] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:164:pipe C] fastset requirement not met, forcing full modeset
<7> [220.946983] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:164:pipe C] dbuf slices 0x0 -> 0x1, ddb (0 - 0) -> (0 - 1020), active pipes 0x0 -> 0x4
<7> [220.947433] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:122:plane 1C] ddb ( 0 - 0) -> ( 0 - 988), size 0 -> 988
<7> [220.947967] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:158:cursor C] ddb ( 0 - 0) -> ( 988 - 1020), size 0 -> 32
<7> [220.948356] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:122: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> [220.949441] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:122:plane 1C] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 1, 1, 2, 2, 2, 2, 2, 0, 0, 0
<7> [220.950091] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:122:plane 1C] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 16, 16, 33, 33, 33, 33, 33, 30, 0, 0
<7> [220.950509] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:122:plane 1C] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 17, 17, 17, 34, 34, 34, 34, 34, 31, 0, 0
<7> [220.950889] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:122:plane 1C] min_ddb_uv 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [220.951406] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:164:pipe C] min cdclk: 0 kHz -> 192000 kHz
<7> [220.951886] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 18563 kHz -> 9282 kHz
<7> [220.952325] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [220.952922] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 316800 kHz, actual 316800 kHz
<7> [220.953308] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 13, actual 13
<7> [220.954053] i915 0000:00:02.0: [drm:bxt_get_dpll [i915]] [CRTC:164:pipe C] using pre-allocated PORT PLL C
<7> [220.954618] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:164:pipe C] reserving PORT PLL C
<7> [220.955018] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:164:pipe C] enable: yes [modeset]
<7> [220.955405] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [220.955819] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: C, pipe bpp: 36, dithering: 0
<7> [220.956314] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [220.956927] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [220.957317] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [220.957863] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [220.958256] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [220.958695] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] GCP: 0x6
<7> [220.959077] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [220.959081] i915 0000:00:02.0: colorspace: RGB
<7> [220.959085] i915 0000:00:02.0: scan mode: Underscan
<7> [220.959088] i915 0000:00:02.0: colorimetry: No Data
<7> [220.959092] i915 0000:00:02.0: picture aspect: No Data
<7> [220.959095] i915 0000:00:02.0: active aspect: Same as Picture
<7> [220.959098] i915 0000:00:02.0: itc: No Data
<7> [220.959101] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [220.959105] i915 0000:00:02.0: quantization range: Full
<7> [220.959108] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [220.959111] i915 0000:00:02.0: video code: 0
<7> [220.959115] i915 0000:00:02.0: ycc quantization range: Full
<7> [220.959118] i915 0000:00:02.0: hdmi content type: Graphics
<7> [220.959121] i915 0000:00:02.0: pixel repeat: 0
<7> [220.959124] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [220.959128] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [220.959135] i915 0000:00:02.0: vendor: Intel
<7> [220.959139] i915 0000:00:02.0: product: Integrated gfx
<7> [220.959142] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [220.959146] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [220.959149] i915 0000:00:02.0: empty frame
<7> [220.959153] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 10 00 07 00 66 10 00 01 00 00 00 00 00 00 00 00
<7> [220.959826] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 05 e3 cd 0c 32 38 45 38 35 30 09 07 07 00 00 00
<7> [220.960245] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [220.960671] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [220.961052] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: no, vmin: 0, vmax: 0, flipline: 0, pipeline full: 0, guardband: 0 vsync start: 0, vsync end: 0
<7> [220.961434] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 0, vmax vblank: 0, vmin vtotal: 0, vmax vtotal: 0
<7> [220.961986] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [220.962373] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9
<7> [220.962799] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9
<7> [220.963183] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] 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> [220.963836] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x40 0x9
<7> [220.964224] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] 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> [220.964646] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 222750, pixel rate 148500, min cdclk 192000, min voltage level 0
<7> [220.965027] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 59, ips linetime: 0
<7> [220.965411] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 1, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0
<7> [220.966047] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1920x1080+0+0
<7> [220.966447] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [220.966880] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [220.967264] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [220.967822] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: ebb0: 0x6200, ebb4: 0x2000,pll0: 0x21, pll1: 0x100, pll2: 0x1a6666, pll3: 0x10000, pll6: 0x30904, pll8: 0x8, pll9: 0xa, pll10: 0x8003c00, pcsdw12: 0x4d
<7> [220.968211] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [220.968633] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [220.969018] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [220.969398] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [220.970028] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [220.970418] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [220.970872] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [220.971258] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:122:plane 1C] fb: [FB:179] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [220.971670] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [220.972053] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [220.972657] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:131:plane 2C] fb: [NOFB], visible: no
<7> [220.973043] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:140:plane 3C] fb: [NOFB], visible: no
<7> [220.973421] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:149:plane 4C] fb: [NOFB], visible: no
<7> [220.973843] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:158:cursor C] fb: [NOFB], visible: no
<7> [220.975053] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling dpio-common-c
<7> [220.976014] i915 0000:00:02.0: [drm:intel_set_cdclk [i915]] Pre changing CDCLK to 316800 kHz, VCO 633600 kHz, ref 19200 kHz, bypass 19200 kHz, voltage level 13
<7> [220.976454] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:165:DDI B/PHY B]
<7> [220.976887] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:175:DDI C/PHY C]
<7> [220.977461] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable PORT PLL C (active 0x4, on? 0) for [CRTC:164:pipe C]
<7> [220.977969] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling PORT PLL C
<7> [220.978703] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_C
<7> [220.979215] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe C
<7> [221.015917] i915 0000:00:02.0: [drm:intel_audio_codec_enable [i915]] [CONNECTOR:176:HDMI-A-2][ENCODER:175:DDI C/PHY C] Enable audio codec on [CRTC:164:pipe C], 32 bytes ELD
<7> [221.032636] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] Configuring HDMI audio for pixel clock 148500 (0x00090000)
<7> [221.033135] i915 0000:00:02.0: [drm:hsw_audio_config_update [i915]] using automatic N
<7> [221.033728] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:176:HDMI-A-2]
<7> [221.034303] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:164:pipe C]
<7> [221.037909] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:78:pipe A]
<7> [221.037996] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:166:HDMI-A-1]
<7> [221.038189] i915 0000:00:02.0: [drm:intel_hdmi_compute_output_format [i915]] picking 12 bpc for HDMI output (pipe bpp: 36)
<7> [221.038886] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:78:pipe A] hw max bpp: 36, pipe bpp: 36, dithering: 0
<7> [221.039280] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:165:DDI B/PHY B] [CRTC:78:pipe A]
<7> [221.040598] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [221.041071] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [221.042162] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [221.042928] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [221.043367] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in lane_lat_optim_mask (expected 0x00000000, found 0x0000000d)
<7> [221.043928] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [221.044334] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [221.044826] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [221.045231] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [221.045775] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [221.046160] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [221.048116] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [221.048752] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [221.049139] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [221.049612] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [221.050141] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1083)
<7> [221.051011] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1088)
<7> [221.051464] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [221.052056] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [221.052462] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [221.052952] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [221.053367] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [221.053872] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [221.054283] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [221.055585] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [221.056055] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [221.056436] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [221.057790] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1083)
<7> [221.058429] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1088)
<7> [221.059051] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [221.059451] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [221.059897] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [221.060277] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [221.060754] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [221.061135] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in has_hdmi_sink (expected no, found yes)
<7> [221.061610] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in has_infoframe (expected no, found yes)
<7> [221.061992] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 36)
<7> [221.063554] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [221.064053] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [221.064452] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in port_clock (expected 0, found 222750)
<7> [221.066587] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071)
<7> [221.067109] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in infoframes.gcp (expected 0x00000000, found 0x00000006)
<7> [221.067753] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in avi infoframe
<7> [221.068146] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [221.068720] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [221.069103] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [221.069108] i915 0000:00:02.0: colorspace: RGB
<7> [221.069112] i915 0000:00:02.0: scan mode: Underscan
<7> [221.069116] i915 0000:00:02.0: colorimetry: No Data
<7> [221.069119] i915 0000:00:02.0: picture aspect: No Data
<7> [221.069122] i915 0000:00:02.0: active aspect: Same as Picture
<7> [221.069126] i915 0000:00:02.0: itc: No Data
<7> [221.069129] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [221.069135] i915 0000:00:02.0: quantization range: Full
<7> [221.069138] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [221.069141] i915 0000:00:02.0: video code: 0
<7> [221.069144] i915 0000:00:02.0: ycc quantization range: Full
<7> [221.069147] i915 0000:00:02.0: hdmi content type: Graphics
<7> [221.069150] i915 0000:00:02.0: pixel repeat: 0
<7> [221.069153] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [221.069157] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in spd infoframe
<7> [221.069819] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [221.070223] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [221.070872] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [221.070877] i915 0000:00:02.0: vendor: Intel
<7> [221.070881] i915 0000:00:02.0: product: Integrated gfx
<7> [221.070884] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [221.070889] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hdmi infoframe
<7> [221.071286] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [221.071863] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [221.072249] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [221.072254] i915 0000:00:02.0: empty frame
<7> [221.072259] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:78:pipe A] fastset requirement not met, forcing full modeset
<7> [221.072913] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:78:pipe A] dbuf slices 0x0 -> 0x1, ddb (0 - 0) -> (0 - 510), active pipes 0x4 -> 0x5
<7> [221.073329] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:164:pipe C] dbuf slices 0x1 -> 0x1, ddb (0 - 1020) -> (510 - 1020), active pipes 0x4 -> 0x5
<7> [221.073953] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:36:plane 1A] ddb ( 0 - 0) -> ( 0 - 497), size 0 -> 497
<7> [221.074345] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:72:cursor A] ddb ( 0 - 0) -> ( 497 - 510), size 0 -> 13
<7> [221.074952] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:36:plane 1A] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm
<7> [221.075333] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:36:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 1, 1, 2, 2, 2, 2, 2, 0, 0, 0
<7> [221.075887] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:36:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 16, 16, 33, 33, 33, 33, 33, 30, 0, 0
<7> [221.076267] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:36:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 17, 17, 17, 34, 34, 34, 34, 34, 31, 0, 0
<7> [221.076844] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:36:plane 1A] min_ddb_uv 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [221.077253] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:122:plane 1C] ddb ( 0 - 988) -> ( 510 - 1007), size 988 -> 497
<7> [221.077841] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:158:cursor C] ddb ( 988 - 1020) -> (1007 - 1020), size 32 -> 13
<7> [221.078277] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:78:pipe A] min cdclk: 0 kHz -> 192000 kHz
<7> [221.078916] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 9282 kHz -> 18563 kHz
<7> [221.079312] i915 0000:00:02.0: [drm:bxt_get_dpll [i915]] [CRTC:78:pipe A] using pre-allocated PORT PLL B
<7> [221.079856] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:78:pipe A] reserving PORT PLL B
<7> [221.080262] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:78:pipe A] enable: yes [modeset]
<7> [221.080907] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [221.081314] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 36, dithering: 0
<7> [221.081915] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [221.082313] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [221.082932] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [221.083328] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [221.083886] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [221.084276] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] GCP: 0x6
<7> [221.084833] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [221.084838] i915 0000:00:02.0: colorspace: RGB
<7> [221.084842] i915 0000:00:02.0: scan mode: Underscan
<7> [221.084845] i915 0000:00:02.0: colorimetry: No Data
<7> [221.084849] i915 0000:00:02.0: picture aspect: No Data
<7> [221.084852] i915 0000:00:02.0: active aspect: Same as Picture
<7> [221.084855] i915 0000:00:02.0: itc: No Data
<7> [221.084858] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [221.084861] i915 0000:00:02.0: quantization range: Full
<7> [221.084864] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [221.084867] i915 0000:00:02.0: video code: 0
<7> [221.084870] i915 0000:00:02.0: ycc quantization range: Full
<7> [221.084873] i915 0000:00:02.0: hdmi content type: Graphics
<7> [221.084877] i915 0000:00:02.0: pixel repeat: 0
<7> [221.084880] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [221.084883] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [221.084887] i915 0000:00:02.0: vendor: Intel
<7> [221.084890] i915 0000:00:02.0: product: Integrated gfx
<7> [221.084893] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [221.084897] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [221.084900] i915 0000:00:02.0: empty frame
<7> [221.084904] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 10 00 07 00 66 10 00 01 00 00 00 00 00 00 00 00
<7> [221.085289] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 05 e3 cd 0c 32 38 45 38 35 30 09 07 07 00 00 00
<7> [221.085950] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [221.086370] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [221.087004] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: no, vmin: 0, vmax: 0, flipline: 0, pipeline full: 0, guardband: 0 vsync start: 0, vsync end: 0
<7> [221.087392] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 0, vmax vblank: 0, vmin vtotal: 0, vmax vtotal: 0
<7> [221.087968] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [221.088362] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9
<7> [221.088960] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9
<7> [221.089353] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] 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> [221.089919] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x40 0x9
<7> [221.090307] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] 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> [221.090882] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 222750, pixel rate 148500, min cdclk 192000, min voltage level 0
<7> [221.091269] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 59, ips linetime: 0
<7> [221.091821] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0
<7> [221.092209] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1920x1080+0+0
<7> [221.092809] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [221.093202] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [221.093778] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [221.094164] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: ebb0: 0x6200, ebb4: 0x2000,pll0: 0x21, pll1: 0x100, pll2: 0x1a6666, pll3: 0x10000, pll6: 0x30904, pll8: 0x8, pll9: 0xa, pll10: 0x8003c00, pcsdw12: 0x4d
<7> [221.094737] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [221.095125] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [221.095691] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [221.096076] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [221.096456] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [221.097067] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [221.097606] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [221.097992] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:36:plane 1A] fb: [FB:179] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [221.098381] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [221.099017] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [221.099410] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:45:plane 2A] fb: [NOFB], visible: no
<7> [221.099987] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:54:plane 3A] fb: [NOFB], visible: no
<7> [221.100370] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:63:plane 4A] fb: [NOFB], visible: no
<7> [221.100953] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:72:cursor A] fb: [NOFB], visible: no
<7> [221.101873] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling dpio-common-b
<7> [221.102716] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:165:DDI B/PHY B]
<7> [221.103108] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:175:DDI C/PHY C]
<7> [221.116023] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable PORT PLL B (active 0x1, on? 0) for [CRTC:78:pipe A]
<7> [221.116559] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling PORT PLL B
<7> [221.117138] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_B
<7> [221.117652] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [221.135172] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] reserved 16588800 bytes of contiguous stolen space for FBC, limit: 1
<7> [221.135681] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:36:plane 1A]
<7> [221.151868] i915 0000:00:02.0: [drm:intel_audio_codec_enable [i915]] [CONNECTOR:166:HDMI-A-1][ENCODER:165:DDI B/PHY B] Enable audio codec on [CRTC:78:pipe A], 32 bytes ELD
<7> [221.168506] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] Configuring HDMI audio for pixel clock 148500 (0x00090000)
<7> [221.168998] i915 0000:00:02.0: [drm:hsw_audio_config_update [i915]] using automatic N
<7> [221.170694] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:166:HDMI-A-1]
<7> [221.171375] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:78:pipe A]
<6> [231.366857] [IGT] kms_flip: finished subtest AC-HDMI-A1-HDMI-A2, FAIL
Created at 2026-05-25 19:06:25