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

Result: Fail

integration-manifest git-log-oneline i915_display_info18 igt_runner18 runtimes18 results18.json results18-i915-load.json guc_logs18.tar i915_display_info_post_exec18 boot18 dmesg18

DetailValue
Duration 8.91 seconds
Hostname 
shard-glk8
Igt-Version 
IGT-Version: 2.4-g75c0acd70 (x86_64) (Linux: 7.0.0-Patchwork_164675v2-gb96553291acb+ x86_64)
Out 
Starting dynamic subtest: AC-HDMI-A1-HDMI-A2
  1920x1080: 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5 
  1920x1080: 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5 
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../tests/kms_flip.c:926 run_test_on_crtc_set()
  #2 ../tests/kms_flip.c:2029 run_pair()
  #3 ../tests/kms_flip.c: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 (8.909s)
Err 
Starting dynamic subtest: AC-HDMI-A1-HDMI-A2
(kms_flip:4787) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931:
(kms_flip:4787) CRITICAL: Failed assertion: end - start < 500
Dynamic subtest AC-HDMI-A1-HDMI-A2 failed.
**** DEBUG ****
(kms_flip:4787) DEBUG: name = flip
last_ts = 403.814972
last_received_ts = 403.814484
last_seq = 11671
current_ts = 403.831665
current_received_ts = 403.831451
current_seq = 11672
count = 325
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 20us
(kms_flip:4787) DEBUG: name = flip
last_ts = 403.831665
last_received_ts = 403.831451
last_seq = 11672
current_ts = 403.848328
current_received_ts = 403.848297
current_seq = 11673
count = 326
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 22us
(kms_flip:4787) DEBUG: name = flip
last_ts = 403.848328
last_received_ts = 403.848297
last_seq = 11673
current_ts = 403.864990
current_received_ts = 403.864594
current_seq = 11674
count = 327
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 26us
(kms_flip:4787) DEBUG: name = flip
last_ts = 403.864990
last_received_ts = 403.864594
last_seq = 11674
current_ts = 403.881653
current_received_ts = 403.881531
current_seq = 11675
count = 328
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 23us
(kms_flip:4787) DEBUG: name = flip
last_ts = 403.881653
last_received_ts = 403.881531
last_seq = 11675
current_ts = 403.898315
current_received_ts = 403.898193
current_seq = 11676
count = 329
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 24us
(kms_flip:4787) DEBUG: name = flip
last_ts = 403.898315
last_received_ts = 403.898193
last_seq = 11676
current_ts = 403.914978
current_received_ts = 403.914581
current_seq = 11677
count = 330
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 21us
(kms_flip:4787) DEBUG: name = flip
last_ts = 403.914978
last_received_ts = 403.914581
last_seq = 11677
current_ts = 403.931641
current_received_ts = 403.931427
current_seq = 11678
count = 331
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 23us
(kms_flip:4787) DEBUG: name = flip
last_ts = 403.931641
last_received_ts = 403.931427
last_seq = 11678
current_ts = 403.948334
current_received_ts = 403.948212
current_seq = 11679
count = 332
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 24us
(kms_flip:4787) DEBUG: name = flip
last_ts = 403.948334
last_received_ts = 403.948212
last_seq = 11679
current_ts = 403.964996
current_received_ts = 403.964600
current_seq = 11680
count = 333
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 24us
(kms_flip:4787) DEBUG: name = flip
last_ts = 403.964996
last_received_ts = 403.964600
last_seq = 11680
current_ts = 403.981659
current_received_ts = 403.981506
current_seq = 11681
count = 334
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 23us
(kms_flip:4787) DEBUG: name = flip
last_ts = 403.981659
last_received_ts = 403.981506
last_seq = 11681
current_ts = 403.998322
current_received_ts = 403.998108
current_seq = 11682
count = 335
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 21us
(kms_flip:4787) DEBUG: name = flip
last_ts = 403.998322
last_received_ts = 403.998108
last_seq = 11682
current_ts = 404.014984
current_received_ts = 404.014557
current_seq = 11683
count = 336
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 22us
(kms_flip:4787) DEBUG: name = flip
last_ts = 404.014984
last_received_ts = 404.014557
last_seq = 11683
current_ts = 404.031647
current_received_ts = 404.031464
current_seq = 11684
count = 337
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 20us
(kms_flip:4787) DEBUG: name = flip
last_ts = 404.031647
last_received_ts = 404.031464
last_seq = 11684
current_ts = 404.048309
current_received_ts = 404.048279
current_seq = 11685
count = 338
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 24us
(kms_flip:4787) DEBUG: name = flip
last_ts = 404.048309
last_received_ts = 404.048279
last_seq = 11685
current_ts = 404.065002
current_received_ts = 404.064575
current_seq = 11686
count = 339
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 23us
(kms_flip:4787) DEBUG: name = flip
last_ts = 404.065002
last_received_ts = 404.064575
last_seq = 11686
current_ts = 404.081665
current_received_ts = 404.081482
current_seq = 11687
count = 340
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 24us
(kms_flip:4787) DEBUG: name = flip
last_ts = 404.081665
last_received_ts = 404.081482
last_seq = 11687
current_ts = 404.098328
current_received_ts = 404.098206
current_seq = 11688
count = 341
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 24us
(kms_flip:4787) DEBUG: name = flip
last_ts = 404.098328
last_received_ts = 404.098206
last_seq = 11688
current_ts = 404.114990
current_received_ts = 404.114594
current_seq = 11689
count = 342
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 23us
(kms_flip:4787) DEBUG: name = flip
last_ts = 404.114990
last_received_ts = 404.114594
last_seq = 11689
current_ts = 404.131653
current_received_ts = 404.131531
current_seq = 11690
count = 343
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 25us
(kms_flip:4787) DEBUG: name = flip
last_ts = 404.131653
last_received_ts = 404.131531
last_seq = 11690
current_ts = 404.148315
current_received_ts = 404.148132
current_seq = 11691
count = 344
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 22us
(kms_flip:4787) DEBUG: name = flip
last_ts = 404.148315
last_received_ts = 404.148132
last_seq = 11691
current_ts = 404.164978
current_received_ts = 404.164612
current_seq = 11692
count = 345
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 24us
(kms_flip:4787) DEBUG: name = flip
last_ts = 404.164978
last_received_ts = 404.164612
last_seq = 11692
current_ts = 404.181641
current_received_ts = 404.181488
current_seq = 11693
count = 346
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 23us
(kms_flip:4787) DEBUG: name = flip
last_ts = 404.181641
last_received_ts = 404.181488
last_seq = 11693
current_ts = 404.198334
current_received_ts = 404.198242
current_seq = 11694
count = 347
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 24us
(kms_flip:4787) DEBUG: name = flip
last_ts = 404.198334
last_received_ts = 404.198242
last_seq = 11694
current_ts = 404.214996
current_received_ts = 404.214966
current_seq = 11695
count = 348
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 24us
(kms_flip:4787) DEBUG: name = flip
last_ts = 404.214996
last_received_ts = 404.214966
last_seq = 11695
current_ts = 404.231659
current_received_ts = 404.231537
current_seq = 11696
count = 349
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 26us
(kms_flip:4787) DEBUG: name = flip
last_ts = 404.231659
last_received_ts = 404.231537
last_seq = 11696
current_ts = 404.248322
current_received_ts = 404.248291
current_seq = 11697
count = 350
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 23us
(kms_flip:4787) DEBUG: name = flip
last_ts = 404.248322
last_received_ts = 404.248291
last_seq = 11697
current_ts = 404.264984
current_received_ts = 404.264587
current_seq = 11698
count = 351
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 22us
(kms_flip:4787) DEBUG: name = flip
last_ts = 404.264984
last_received_ts = 404.264587
last_seq = 11698
current_ts = 404.281647
current_received_ts = 404.281525
current_seq = 11699
count = 352
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 24us
(kms_flip:4787) DEBUG: name = flip
last_ts = 404.281647
last_received_ts = 404.281525
last_seq = 11699
current_ts = 404.298309
current_received_ts = 404.298187
current_seq = 11700
count = 353
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 20us
(kms_flip:4787) DEBUG: name = flip
last_ts = 404.298309
last_received_ts = 404.298187
last_seq = 11700
current_ts = 404.315002
current_received_ts = 404.314636
current_seq = 11701
count = 354
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 24us
(kms_flip:4787) DEBUG: name = flip
last_ts = 404.315002
last_received_ts = 404.314636
last_seq = 11701
current_ts = 404.331665
current_received_ts = 404.331512
current_seq = 11702
count = 355
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 21us
(kms_flip:4787) DEBUG: name = flip
last_ts = 404.331665
last_received_ts = 404.331512
last_seq = 11702
current_ts = 404.348328
current_received_ts = 404.348114
current_seq = 11703
count = 356
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 21us
(kms_flip:4787) DEBUG: name = flip
last_ts = 404.348328
last_received_ts = 404.348114
last_seq = 11703
current_ts = 404.364990
current_received_ts = 404.364594
current_seq = 11704
count = 357
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 22us
(kms_flip:4787) DEBUG: name = flip
last_ts = 404.364990
last_received_ts = 404.364594
last_seq = 11704
current_ts = 404.381653
current_received_ts = 404.381500
current_seq = 11705
count = 358
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 20us
(kms_flip:4787) DEBUG: name = flip
last_ts = 404.381653
last_received_ts = 404.381500
last_seq = 11705
current_ts = 404.398346
current_received_ts = 404.398193
current_seq = 11706
count = 359
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 20us
(kms_flip:4787) DEBUG: name = flip
last_ts = 404.398346
last_received_ts = 404.398193
last_seq = 11706
current_ts = 404.415009
current_received_ts = 404.414581
current_seq = 11707
count = 360
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 22us
(kms_flip:4787) DEBUG: name = flip
last_ts = 404.415009
last_received_ts = 404.414581
last_seq = 11707
current_ts = 404.431671
current_received_ts = 404.431488
current_seq = 11708
count = 361
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 20us
(kms_flip:4787) DEBUG: name = flip
last_ts = 404.431671
last_received_ts = 404.431488
last_seq = 11708
current_ts = 404.448334
current_received_ts = 404.448334
current_seq = 11709
count = 362
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 23us
(kms_flip:4787) DEBUG: name = flip
last_ts = 404.448334
last_received_ts = 404.448334
last_seq = 11709
current_ts = 404.464996
current_received_ts = 404.464600
current_seq = 11710
count = 363
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 21us
(kms_flip:4787) DEBUG: name = flip
last_ts = 404.464996
last_received_ts = 404.464600
last_seq = 11710
current_ts = 404.481659
current_received_ts = 404.481537
current_seq = 11711
count = 364
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 23us
(kms_flip:4787) DEBUG: name = flip
last_ts = 404.481659
last_received_ts = 404.481537
last_seq = 11711
current_ts = 404.498352
current_received_ts = 404.498199
current_seq = 11712
count = 365
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 21us
(kms_flip:4787) DEBUG: name = flip
last_ts = 404.498352
last_received_ts = 404.498199
last_seq = 11712
current_ts = 404.514984
current_received_ts = 404.514618
current_seq = 11713
count = 366
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 24us
(kms_flip:4787) DEBUG: name = flip
last_ts = 404.514984
last_received_ts = 404.514618
last_seq = 11713
current_ts = 404.531677
current_received_ts = 404.531525
current_seq = 11714
count = 367
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 24us
(kms_flip:4787) DEBUG: name = flip
last_ts = 404.531677
last_received_ts = 404.531525
last_seq = 11714
current_ts = 404.548340
current_received_ts = 404.548187
current_seq = 11715
count = 368
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 22us
(kms_flip:4787) DEBUG: name = flip
last_ts = 404.548340
last_received_ts = 404.548187
last_seq = 11715
current_ts = 404.565002
current_received_ts = 404.564606
current_seq = 11716
count = 369
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 23us
(kms_flip:4787) DEBUG: name = flip
last_ts = 404.565002
last_received_ts = 404.564606
last_seq = 11716
current_ts = 404.581665
current_received_ts = 404.581512
current_seq = 11717
count = 370
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 23us
(kms_flip:4787) DEBUG: name = flip
last_ts = 404.581665
last_received_ts = 404.581512
last_seq = 11717
current_ts = 404.598328
current_received_ts = 404.598236
current_seq = 11718
count = 371
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 25us
(kms_flip:4787) DEBUG: name = flip
last_ts = 404.598328
last_received_ts = 404.598236
last_seq = 11718
current_ts = 404.615021
current_received_ts = 404.614563
current_seq = 11719
count = 372
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 21us
(kms_flip:4787) DEBUG: name = flip
last_ts = 404.615021
last_received_ts = 404.614563
last_seq = 11719
current_ts = 404.631683
current_received_ts = 404.631500
current_seq = 11720
count = 373
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 22us
(kms_flip:4787) DEBUG: name = flip
last_ts = 404.631683
last_received_ts = 404.631500
last_seq = 11720
current_ts = 404.648346
current_received_ts = 404.648285
current_seq = 11721
count = 374
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 22us
(kms_flip:4787) DEBUG: name = flip
last_ts = 404.648346
last_received_ts = 404.648285
last_seq = 11721
current_ts = 404.665009
current_received_ts = 404.664581
current_seq = 11722
count = 375
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 21us
(kms_flip:4787) DEBUG: name = flip
last_ts = 404.665009
last_received_ts = 404.664581
last_seq = 11722
current_ts = 404.681671
current_received_ts = 404.681488
current_seq = 11723
count = 376
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 21us
(kms_flip:4787) DEBUG: name = flip
last_ts = 404.681671
last_received_ts = 404.681488
last_seq = 11723
current_ts = 404.698334
current_received_ts = 404.698212
current_seq = 11724
count = 377
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 24us
(kms_flip:4787) DEBUG: name = flip
last_ts = 404.698334
last_received_ts = 404.698212
last_seq = 11724
current_ts = 404.714996
current_received_ts = 404.714630
current_seq = 11725
count = 378
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 23us
(kms_flip:4787) DEBUG: name = flip
last_ts = 404.714996
last_received_ts = 404.714630
last_seq = 11725
current_ts = 404.731689
current_received_ts = 404.731964
current_seq = 11726
count = 379
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 25us
(kms_flip:4787) DEBUG: name = flip
last_ts = 404.731689
last_received_ts = 404.731964
last_seq = 11726
current_ts = 404.748352
current_received_ts = 404.748138
current_seq = 11727
count = 380
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 24us
(kms_flip:4787) DEBUG: name = flip
last_ts = 404.748352
last_received_ts = 404.748138
last_seq = 11727
current_ts = 404.765015
current_received_ts = 404.764648
current_seq = 11728
count = 381
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 25us
(kms_flip:4787) DEBUG: name = flip
last_ts = 404.765015
last_received_ts = 404.764648
last_seq = 11728
current_ts = 404.781677
current_received_ts = 404.781525
current_seq = 11729
count = 382
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 24us
(kms_flip:4787) DEBUG: name = flip
last_ts = 404.781677
last_received_ts = 404.781525
last_seq = 11729
current_ts = 404.798340
current_received_ts = 404.798157
current_seq = 11730
count = 383
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 21us
(kms_flip:4787) DEBUG: name = flip
last_ts = 404.798340
last_received_ts = 404.798157
last_seq = 11730
current_ts = 404.815033
current_received_ts = 404.814972
current_seq = 11731
count = 384
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 22us
(kms_flip:4787) DEBUG: name = flip
last_ts = 404.815033
last_received_ts = 404.814972
last_seq = 11731
current_ts = 404.831696
current_received_ts = 404.831451
current_seq = 11732
count = 385
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 21us
(kms_flip:4787) DEBUG: name = flip
last_ts = 404.831696
last_received_ts = 404.831451
last_seq = 11732
current_ts = 404.848358
current_received_ts = 404.848145
current_seq = 11733
count = 386
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 22us
(kms_flip:4787) DEBUG: name = flip
last_ts = 404.848358
last_received_ts = 404.848145
last_seq = 11733
current_ts = 404.865021
current_received_ts = 404.864624
current_seq = 11734
count = 387
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 22us
(kms_flip:4787) DEBUG: name = flip
last_ts = 404.865021
last_received_ts = 404.864624
last_seq = 11734
current_ts = 404.881683
current_received_ts = 404.881470
current_seq = 11735
count = 388
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 23us
(kms_flip:4787) DEBUG: name = flip
last_ts = 404.881683
last_received_ts = 404.881470
last_seq = 11735
current_ts = 404.898346
current_received_ts = 404.898163
current_seq = 11736
count = 389
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 20us
(kms_flip:4787) DEBUG: name = flip
last_ts = 404.898346
last_received_ts = 404.898163
last_seq = 11736
current_ts = 404.915009
current_received_ts = 404.914581
current_seq = 11737
count = 390
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 24us
(kms_flip:4787) DEBUG: name = flip
last_ts = 404.915009
last_received_ts = 404.914581
last_seq = 11737
current_ts = 404.931671
current_received_ts = 404.931519
current_seq = 11738
count = 391
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 23us
(kms_flip:4787) DEBUG: name = flip
last_ts = 404.931671
last_received_ts = 404.931519
last_seq = 11738
current_ts = 404.948364
current_received_ts = 404.948151
current_seq = 11739
count = 392
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 22us
(kms_flip:4787) DEBUG: name = flip
last_ts = 404.948364
last_received_ts = 404.948151
last_seq = 11739
current_ts = 404.965027
current_received_ts = 404.964600
current_seq = 11740
count = 393
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 25us
(kms_flip:4787) DEBUG: name = flip
last_ts = 404.965027
last_received_ts = 404.964600
last_seq = 11740
current_ts = 404.981689
current_received_ts = 404.981537
current_seq = 11741
count = 394
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 23us
(kms_flip:4787) DEBUG: name = flip
last_ts = 404.981689
last_received_ts = 404.981537
last_seq = 11741
current_ts = 404.998352
current_received_ts = 404.998199
current_seq = 11742
count = 395
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 21us
(kms_flip:4787) DEBUG: name = flip
last_ts = 404.998352
last_received_ts = 404.998199
last_seq = 11742
current_ts = 405.015015
current_received_ts = 405.014648
current_seq = 11743
count = 396
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 24us
(kms_flip:4787) DEBUG: name = flip
last_ts = 405.015015
last_received_ts = 405.014648
last_seq = 11743
current_ts = 405.031677
current_received_ts = 405.031525
current_seq = 11744
count = 397
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 23us
(kms_flip:4787) DEBUG: name = flip
last_ts = 405.031677
last_received_ts = 405.031525
last_seq = 11744
current_ts = 405.048340
current_received_ts = 405.048187
current_seq = 11745
count = 398
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 22us
(kms_flip:4787) DEBUG: name = flip
last_ts = 405.048340
last_received_ts = 405.048187
last_seq = 11745
current_ts = 405.065033
current_received_ts = 405.064606
current_seq = 11746
count = 399
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 26us
(kms_flip:4787) DEBUG: name = flip
last_ts = 405.065033
last_received_ts = 405.064606
last_seq = 11746
current_ts = 405.081696
current_received_ts = 405.081512
current_seq = 11747
count = 400
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 23us
(kms_flip:4787) DEBUG: name = flip
last_ts = 405.081696
last_received_ts = 405.081512
last_seq = 11747
current_ts = 405.098358
current_received_ts = 405.098175
current_seq = 11748
count = 401
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 20us
(kms_flip:4787) DEBUG: name = flip
last_ts = 405.098358
last_received_ts = 405.098175
last_seq = 11748
current_ts = 405.115021
current_received_ts = 405.114655
current_seq = 11749
count = 402
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 24us
(kms_flip:4787) DEBUG: name = flip
last_ts = 405.115021
last_received_ts = 405.114655
last_seq = 11749
current_ts = 405.131683
current_received_ts = 405.131531
current_seq = 11750
count = 403
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 23us
(kms_flip:4787) DEBUG: name = flip
last_ts = 405.131683
last_received_ts = 405.131531
last_seq = 11750
current_ts = 405.148346
current_received_ts = 405.148193
current_seq = 11751
count = 404
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 21us
(kms_flip:4787) DEBUG: name = flip
last_ts = 405.148346
last_received_ts = 405.148193
last_seq = 11751
current_ts = 405.165009
current_received_ts = 405.164581
current_seq = 11752
count = 405
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 24us
(kms_flip:4787) DEBUG: name = flip
last_ts = 405.165009
last_received_ts = 405.164581
last_seq = 11752
current_ts = 405.181702
current_received_ts = 405.181519
current_seq = 11753
count = 406
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 23us
(kms_flip:4787) DEBUG: name = flip
last_ts = 405.181702
last_received_ts = 405.181519
last_seq = 11753
current_ts = 405.198364
current_received_ts = 405.198151
current_seq = 11754
count = 407
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 22us
(kms_flip:4787) DEBUG: name = flip
last_ts = 405.198364
last_received_ts = 405.198151
last_seq = 11754
current_ts = 405.215027
current_received_ts = 405.214661
current_seq = 11755
count = 408
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 25us
(kms_flip:4787) DEBUG: name = flip
last_ts = 405.215027
last_received_ts = 405.214661
last_seq = 11755
current_ts = 405.231689
current_received_ts = 405.231537
current_seq = 11756
count = 409
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 22us
(kms_flip:4787) DEBUG: name = flip
last_ts = 405.231689
last_received_ts = 405.231537
last_seq = 11756
current_ts = 405.248352
current_received_ts = 405.248199
current_seq = 11757
count = 410
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 21us
(kms_flip:4787) DEBUG: name = flip
last_ts = 405.248352
last_received_ts = 405.248199
last_seq = 11757
current_ts = 405.265015
current_received_ts = 405.264648
current_seq = 11758
count = 411
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 23us
(kms_flip:4787) DEBUG: name = flip
last_ts = 405.265015
last_received_ts = 405.264648
last_seq = 11758
current_ts = 405.281677
current_received_ts = 405.281555
current_seq = 11759
count = 412
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 22us
(kms_flip:4787) DEBUG: name = flip
last_ts = 405.281677
last_received_ts = 405.281555
last_seq = 11759
current_ts = 405.298370
current_received_ts = 405.298187
current_seq = 11760
count = 413
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 21us
(kms_flip:4787) DEBUG: name = flip
last_ts = 405.298370
last_received_ts = 405.298187
last_seq = 11760
current_ts = 405.315033
current_received_ts = 405.314636
current_seq = 11761
count = 414
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 23us
(kms_flip:4787) DEBUG: name = flip
last_ts = 405.315033
last_received_ts = 405.314636
last_seq = 11761
current_ts = 405.331696
current_received_ts = 405.331512
current_seq = 11762
count = 415
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 22us
(kms_flip:4787) DEBUG: name = flip
last_ts = 405.331696
last_received_ts = 405.331512
last_seq = 11762
current_ts = 405.348358
current_received_ts = 405.348175
current_seq = 11763
count = 416
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 21us
(kms_flip:4787) DEBUG: name = flip
last_ts = 405.348358
last_received_ts = 405.348175
last_seq = 11763
current_ts = 405.365021
current_received_ts = 405.364594
current_seq = 11764
count = 417
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 24us
(kms_flip:4787) DEBUG: name = flip
last_ts = 405.365021
last_received_ts = 405.364594
last_seq = 11764
current_ts = 405.381683
current_received_ts = 405.381470
current_seq = 11765
count = 418
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 23us
(kms_flip:4787) DEBUG: name = flip
last_ts = 405.381683
last_received_ts = 405.381470
last_seq = 11765
current_ts = 405.398376
current_received_ts = 405.398224
current_seq = 11766
count = 419
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 23us
(kms_flip:4787) DEBUG: name = flip
last_ts = 405.398376
last_received_ts = 405.398224
last_seq = 11766
current_ts = 405.415039
current_received_ts = 405.414581
current_seq = 11767
count = 420
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 25us
(kms_flip:4787) DEBUG: name = flip
last_ts = 405.415039
last_received_ts = 405.414581
last_seq = 11767
current_ts = 405.431702
current_received_ts = 405.431915
current_seq = 11768
count = 421
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 23us
(kms_flip:4787) DEBUG: name = flip
last_ts = 405.431702
last_received_ts = 405.431915
last_seq = 11768
current_ts = 405.448364
current_received_ts = 405.448181
current_seq = 11769
count = 422
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 23us
(kms_flip:4787) DEBUG: name = flip
last_ts = 405.448364
last_received_ts = 405.448181
last_seq = 11769
current_ts = 405.465027
current_received_ts = 405.465088
current_seq = 11770
count = 423
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 25us
(kms_flip:4787) DEBUG: name = flip
last_ts = 405.465027
last_received_ts = 405.465088
last_seq = 11770
current_ts = 405.481689
current_received_ts = 405.481506
current_seq = 11771
count = 424
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 20us
(kms_flip:4787) DEBUG: name = flip
last_ts = 405.481689
last_received_ts = 405.481506
last_seq = 11771
current_ts = 405.498383
current_received_ts = 405.498230
current_seq = 11772
count = 425
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 23us
(kms_flip:4787) DEBUG: name = flip
last_ts = 405.498383
last_received_ts = 405.498230
last_seq = 11772
current_ts = 405.515045
current_received_ts = 405.514679
current_seq = 11773
count = 426
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 25us
(kms_flip:4787) DEBUG: name = flip
last_ts = 405.515045
last_received_ts = 405.514679
last_seq = 11773
current_ts = 405.531708
current_received_ts = 405.531769
current_seq = 11774
count = 427
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 25us
(kms_flip:4787) DEBUG: name = flip
last_ts = 405.531708
last_received_ts = 405.531769
last_seq = 11774
current_ts = 405.548370
current_received_ts = 405.548248
current_seq = 11775
count = 428
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 24us
(kms_flip:4787) DEBUG: name = flip
last_ts = 405.548370
last_received_ts = 405.548248
last_seq = 11775
current_ts = 405.565033
current_received_ts = 405.564636
current_seq = 11776
count = 429
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 21us
(kms_flip:4787) DEBUG: name = flip
last_ts = 405.565033
last_received_ts = 405.564636
last_seq = 11776
current_ts = 405.581696
current_received_ts = 405.581573
current_seq = 11777
count = 430
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 25us
(kms_flip:4787) DEBUG: name = flip
last_ts = 405.581696
last_received_ts = 405.581573
last_seq = 11777
current_ts = 405.598358
current_received_ts = 405.598236
current_seq = 11778
count = 431
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 25us
(kms_flip:4787) DEBUG: name = flip
last_ts = 405.598358
last_received_ts = 405.598236
last_seq = 11778
current_ts = 405.615051
current_received_ts = 405.614685
current_seq = 11779
count = 432
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 26us
(kms_flip:4787) DEBUG: name = flip
last_ts = 405.615051
last_received_ts = 405.614685
last_seq = 11779
current_ts = 405.631714
current_received_ts = 405.631561
current_seq = 11780
count = 433
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 23us
(kms_flip:4787) DEBUG: name = flip
last_ts = 405.631714
last_received_ts = 405.631561
last_seq = 11780
current_ts = 405.648376
current_received_ts = 405.648224
current_seq = 11781
count = 434
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 20us
(kms_flip:4787) DEBUG: name = flip
last_ts = 405.648376
last_received_ts = 405.648224
last_seq = 11781
current_ts = 405.665039
current_received_ts = 405.664703
current_seq = 11782
count = 435
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 25us
(kms_flip:4787) DEBUG: name = flip
last_ts = 405.665039
last_received_ts = 405.664703
last_seq = 11782
current_ts = 405.681702
current_received_ts = 405.681580
current_seq = 11783
count = 436
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 24us
(kms_flip:4787) DEBUG: name = flip
last_ts = 405.681702
last_received_ts = 405.681580
last_seq = 11783
current_ts = 405.698364
current_received_ts = 405.698273
current_seq = 11784
count = 437
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 23us
(kms_flip:4787) DEBUG: name = flip
last_ts = 405.698364
last_received_ts = 405.698273
last_seq = 11784
current_ts = 405.715057
current_received_ts = 405.714630
current_seq = 11785
count = 438
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 19us
(kms_flip:4787) DEBUG: name = flip
last_ts = 405.715057
last_received_ts = 405.714630
last_seq = 11785
current_ts = 405.731720
current_received_ts = 405.731506
current_seq = 11786
count = 439
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 21us
(kms_flip:4787) DEBUG: name = flip
last_ts = 405.731720
last_received_ts = 405.731506
last_seq = 11786
current_ts = 405.748383
current_received_ts = 405.748230
current_seq = 11787
count = 440
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 20us
(kms_flip:4787) DEBUG: name = flip
last_ts = 405.748383
last_received_ts = 405.748230
last_seq = 11787
current_ts = 405.765045
current_received_ts = 405.764709
current_seq = 11788
count = 441
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 25us
(kms_flip:4787) DEBUG: name = flip
last_ts = 405.765045
last_received_ts = 405.764709
last_seq = 11788
current_ts = 405.781708
current_received_ts = 405.781525
current_seq = 11789
count = 442
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 21us
(kms_flip:4787) DEBUG: name = flip
last_ts = 405.781708
last_received_ts = 405.781525
last_seq = 11789
current_ts = 405.798401
current_received_ts = 405.798248
current_seq = 11790
count = 443
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 21us
(kms_flip:4787) DEBUG: name = flip
last_ts = 405.798401
last_received_ts = 405.798248
last_seq = 11790
current_ts = 405.815033
current_received_ts = 405.814697
current_seq = 11791
count = 444
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 26us
(kms_flip:4787) DEBUG: name = flip
last_ts = 405.815033
last_received_ts = 405.814697
last_seq = 11791
current_ts = 405.831696
current_received_ts = 405.831482
current_seq = 11792
count = 445
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 21us
(kms_flip:4787) DEBUG: name = flip
last_ts = 405.831696
last_received_ts = 405.831482
last_seq = 11792
current_ts = 405.848389
current_received_ts = 405.848328
current_seq = 11793
count = 446
seq_step = 1
(kms_flip:4787) DEBUG: Vblank took 722us
(kms_flip:4787) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931:
(kms_flip:4787) CRITICAL: Failed assertion: end - start < 500
(kms_flip:4787) igt_core-INFO: Stack trace:
(kms_flip:4787) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:4787) igt_core-INFO:   #1 ../tests/kms_flip.c:926 run_test_on_crtc_set()
(kms_flip:4787) igt_core-INFO:   #2 ../tests/kms_flip.c:2029 run_pair()
(kms_flip:4787) igt_core-INFO:   #3 ../tests/kms_flip.c:2193 __igt_unique____real_main2105()
(kms_flip:4787) igt_core-INFO:   #4 ../tests/kms_flip.c:2105 main()
(kms_flip:4787) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:4787) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:4787) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest AC-HDMI-A1-HDMI-A2: FAIL (8.909s)
Dmesg
<6> [397.287924] [IGT] kms_flip: starting dynamic subtest AC-HDMI-A1-HDMI-A2
<7> [397.289732] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:183]
<7> [397.290087] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:185]
<7> [397.345961] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:78:pipe A]
<7> [397.346450] 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> [397.349569] 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> [397.350654] 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> [397.355510] 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> [397.355963] 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> [397.356330] 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> [397.356786] 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> [397.357166] 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> [397.359210] 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> [397.360090] 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> [397.360661] 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> [397.361048] 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> [397.361458] 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> [397.362039] 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> [397.362445] 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> [397.363059] 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 1084, found 0)
<7> [397.363443] 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 1089, found 0)
<7> [397.364031] 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> [397.364443] 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> [397.365023] 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> [397.365404] 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> [397.367256] 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> [397.368586] 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> [397.369001] 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> [397.369379] 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> [397.370923] 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> [397.371401] 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> [397.372112] 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 1084, found 0)
<7> [397.372700] 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 1089, found 0)
<7> [397.373087] 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> [397.373740] 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> [397.374123] 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> [397.374695] 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> [397.375109] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [397.377632] 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> [397.378202] 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> [397.378861] 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> [397.379251] 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> [397.380031] 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> [397.380736] 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> [397.381123] 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> [397.381761] 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> [397.382165] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in avi infoframe
<7> [397.382828] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [397.383220] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [397.383225] i915 0000:00:02.0: colorspace: RGB
<7> [397.383229] i915 0000:00:02.0: scan mode: Underscan
<7> [397.383232] i915 0000:00:02.0: colorimetry: No Data
<7> [397.383235] i915 0000:00:02.0: picture aspect: 16:9
<7> [397.383239] i915 0000:00:02.0: active aspect: Same as Picture
<7> [397.383242] i915 0000:00:02.0: itc: No Data
<7> [397.383246] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [397.383249] i915 0000:00:02.0: quantization range: Default
<7> [397.383253] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [397.383256] i915 0000:00:02.0: video code: 16
<7> [397.383259] i915 0000:00:02.0: ycc quantization range: Full
<7> [397.383263] i915 0000:00:02.0: hdmi content type: Graphics
<7> [397.383266] i915 0000:00:02.0: pixel repeat: 0
<7> [397.383269] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [397.383272] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [397.385555] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in spd infoframe
<7> [397.386053] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [397.386429] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [397.386433] i915 0000:00:02.0: vendor: Intel
<7> [397.386437] i915 0000:00:02.0: product: Integrated gfx
<7> [397.386441] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [397.386444] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [397.387107] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hdmi infoframe
<7> [397.388425] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [397.389161] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [397.389167] i915 0000:00:02.0: empty frame
<7> [397.389170] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [397.389956] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:78:pipe A] fastset requirement not met, forcing full modeset
<7> [397.390412] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:78:pipe A] releasing PORT PLL B
<7> [397.391125] 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> [397.391721] 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> [397.392193] 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> [397.392861] 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> [397.393314] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:78:pipe A] min cdclk: 192000 kHz -> 0 kHz
<7> [397.393953] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:78:pipe A] enable: no [modeset]
<7> [397.394341] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:36:plane 1A] fb: [NOFB], visible: no
<7> [397.396533] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:45:plane 2A] fb: [NOFB], visible: no
<7> [397.397032] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:54:plane 3A] fb: [NOFB], visible: no
<7> [397.397411] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:63:plane 4A] fb: [NOFB], visible: no
<7> [397.398861] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:72:cursor A] fb: [NOFB], visible: no
<7> [397.399818] 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> [397.429239] i915 0000:00:02.0: [drm:intel_audio_component_get_eld [i915]] Not valid for port B
<7> [397.430025] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [397.442072] i915 0000:00:02.0: [drm:intel_audio_component_get_power [i915]] restored AUD_FREQ_CNTRL to 0x10
<7> [397.447536] i915 0000:00:02.0: [drm:intel_ddi_disable_transcoder_func [i915]] Quirk Increase DDI disabled time
<7> [397.548970] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_B
<7> [397.550132] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable PORT PLL B (active 0x1, on? 1) for [CRTC:78:pipe A]
<7> [397.553629] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling PORT PLL B
<7> [397.554154] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:165:DDI B/PHY B]
<7> [397.554654] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:175:DDI C/PHY C]
<7> [397.555032] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:166:HDMI-A-1]
<7> [397.558033] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling dpio-common-b
<7> [397.558530] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:78:pipe A]
<7> [397.560544] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:121:pipe B]
<7> [397.560716] 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> [397.561197] 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> [397.561645] 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> [397.562030] 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> [397.562408] 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> [397.562937] 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> [397.563322] 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> [397.563735] 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> [397.564113] 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> [397.564554] azx_init_pci: snd_hda_intel 0000:00:0e.0: Clearing TCSEL
<7> [397.564572] azx_init_pci: snd_hda_intel 0000:00:0e.0: SCH snoop: Enabled
<7> [397.564553] 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> [397.564935] 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> [397.565311] 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> [397.565716] 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> [397.566094] 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> [397.566501] 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> [397.566880] 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 1084, found 0)
<7> [397.567256] 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 1089, found 0)
<7> [397.567658] 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> [397.568039] 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> [397.568418] 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> [397.568851] 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> [397.569234] 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> [397.569636] 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> [397.570017] 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> [397.570394] 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> [397.570794] 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> [397.571175] 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> [397.571589] 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 1084, found 0)
<7> [397.571969] 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 1089, found 0)
<7> [397.572348] 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> [397.572834] 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> [397.573223] 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> [397.573627] 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> [397.574006] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [397.574384] 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> [397.574829] 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> [397.575215] 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> [397.575622] 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> [397.576003] 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> [397.576379] 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> [397.576788] 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> [397.577169] 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> [397.577588] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in avi infoframe
<7> [397.577973] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [397.578355] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [397.578359] i915 0000:00:02.0: colorspace: RGB
<7> [397.578363] i915 0000:00:02.0: scan mode: Underscan
<7> [397.578366] i915 0000:00:02.0: colorimetry: No Data
<7> [397.578370] i915 0000:00:02.0: picture aspect: 16:9
<7> [397.578374] i915 0000:00:02.0: active aspect: Same as Picture
<7> [397.578377] i915 0000:00:02.0: itc: No Data
<7> [397.578380] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [397.578383] i915 0000:00:02.0: quantization range: Default
<7> [397.578387] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [397.578390] i915 0000:00:02.0: video code: 16
<7> [397.578393] i915 0000:00:02.0: ycc quantization range: Full
<7> [397.578396] i915 0000:00:02.0: hdmi content type: Graphics
<7> [397.578400] i915 0000:00:02.0: pixel repeat: 0
<7> [397.578403] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [397.578406] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [397.578808] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in spd infoframe
<7> [397.579187] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [397.579603] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [397.579607] i915 0000:00:02.0: vendor: Intel
<7> [397.579611] i915 0000:00:02.0: product: Integrated gfx
<7> [397.579614] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [397.579618] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [397.579995] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hdmi infoframe
<7> [397.580373] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [397.580765] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [397.580769] i915 0000:00:02.0: empty frame
<7> [397.580773] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [397.581151] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:121:pipe B] fastset requirement not met, forcing full modeset
<7> [397.581563] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:121:pipe B] releasing PORT PLL C
<7> [397.582004] 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> [397.582447] 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> [397.582987] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:121:pipe B] min cdclk: 192000 kHz -> 0 kHz
<7> [397.583382] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:121:pipe B] enable: no [modeset]
<7> [397.583794] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:79:plane 1B] fb: [NOFB], visible: no
<7> [397.584172] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:88:plane 2B] fb: [NOFB], visible: no
<7> [397.584579] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:97:plane 3B] fb: [NOFB], visible: no
<7> [397.584954] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:106:plane 4B] fb: [NOFB], visible: no
<7> [397.585329] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:115:cursor B] fb: [NOFB], visible: no
<7> [397.585845] 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> [397.607982] i915 0000:00:02.0: [drm:intel_audio_component_get_eld [i915]] Not valid for port C
<7> [397.608617] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe B
<7> [397.625548] i915 0000:00:02.0: [drm:intel_ddi_disable_transcoder_func [i915]] Quirk Increase DDI disabled time
<7> [397.732950] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_C
<7> [397.734169] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable PORT PLL C (active 0x2, on? 1) for [CRTC:121:pipe B]
<7> [397.736063] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling PORT PLL C
<7> [397.738575] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:165:DDI B/PHY B]
<7> [397.739071] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:175:DDI C/PHY C]
<7> [397.739552] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:176:HDMI-A-2]
<7> [397.740164] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling dpio-common-c
<7> [397.741116] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:121:pipe B]
<7> [397.742663] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:164:pipe C]
<7> [397.743058] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:164:pipe C]
<7> [397.743120] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:176:HDMI-A-2]
<7> [397.743290] i915 0000:00:02.0: [drm:intel_hdmi_compute_output_format [i915]] picking 12 bpc for HDMI output (pipe bpp: 36)
<7> [397.743991] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:164:pipe C] hw max bpp: 36, pipe bpp: 36, dithering: 0
<7> [397.744389] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:175:DDI C/PHY C] [CRTC:164:pipe C]
<7> [397.744954] 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> [397.745342] 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> [397.748061] 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> [397.749112] 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> [397.749787] 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> [397.750180] 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> [397.750772] 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> [397.751157] 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> [397.751764] 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> [397.752162] 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> [397.754091] 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> [397.754891] 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> [397.755291] 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> [397.756700] 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> [397.757107] 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> [397.757972] 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 1084)
<7> [397.758369] 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 1089)
<7> [397.759039] 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> [397.759424] 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> [397.760003] 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> [397.760385] 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> [397.760973] 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> [397.761356] 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> [397.763312] 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> [397.764116] 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> [397.765467] 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> [397.765870] 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> [397.766247] 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 1084)
<7> [397.767014] 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 1089)
<7> [397.767404] 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> [397.768058] 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> [397.768443] 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> [397.769020] 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> [397.769403] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [397.769991] 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> [397.770378] 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> [397.772510] 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> [397.773008] 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> [397.773388] 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> [397.774661] 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> [397.775061] 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> [397.775444] 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> [397.776372] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in avi infoframe
<7> [397.777065] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [397.777596] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [397.777979] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [397.777984] i915 0000:00:02.0: colorspace: RGB
<7> [397.777988] i915 0000:00:02.0: scan mode: Underscan
<7> [397.777991] i915 0000:00:02.0: colorimetry: No Data
<7> [397.777995] i915 0000:00:02.0: picture aspect: 16:9
<7> [397.777999] i915 0000:00:02.0: active aspect: Same as Picture
<7> [397.778002] i915 0000:00:02.0: itc: No Data
<7> [397.778005] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [397.778009] i915 0000:00:02.0: quantization range: Default
<7> [397.778012] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [397.778016] i915 0000:00:02.0: video code: 16
<7> [397.778019] i915 0000:00:02.0: ycc quantization range: Full
<7> [397.778022] i915 0000:00:02.0: hdmi content type: Graphics
<7> [397.778025] i915 0000:00:02.0: pixel repeat: 0
<7> [397.778028] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [397.778032] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in spd infoframe
<7> [397.778410] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [397.779084] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [397.781535] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [397.781541] i915 0000:00:02.0: vendor: Intel
<7> [397.781545] i915 0000:00:02.0: product: Integrated gfx
<7> [397.781549] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [397.781554] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hdmi infoframe
<7> [397.782052] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [397.782432] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [397.783751] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [397.783756] i915 0000:00:02.0: empty frame
<7> [397.783761] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:164:pipe C] fastset requirement not met, forcing full modeset
<7> [397.784234] 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> [397.785224] 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> [397.785902] 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> [397.786283] 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> [397.786900] 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> [397.787287] 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> [397.787868] 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> [397.788246] 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> [397.790234] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:164:pipe C] min cdclk: 0 kHz -> 192000 kHz
<7> [397.791560] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 18563 kHz -> 9282 kHz
<7> [397.791959] i915 0000:00:02.0: [drm:bxt_get_dpll [i915]] [CRTC:164:pipe C] using pre-allocated PORT PLL C
<7> [397.792346] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:164:pipe C] reserving PORT PLL C
<7> [397.793584] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:164:pipe C] enable: yes [modeset]
<7> [397.793983] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [397.794363] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: C, pipe bpp: 36, dithering: 0
<7> [397.795227] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [397.795920] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [397.796314] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [397.796853] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [397.797236] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [397.797794] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] GCP: 0x6
<7> [397.798175] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [397.798180] i915 0000:00:02.0: colorspace: RGB
<7> [397.798184] i915 0000:00:02.0: scan mode: Underscan
<7> [397.798187] i915 0000:00:02.0: colorimetry: No Data
<7> [397.798191] i915 0000:00:02.0: picture aspect: 16:9
<7> [397.798195] i915 0000:00:02.0: active aspect: Same as Picture
<7> [397.798199] i915 0000:00:02.0: itc: No Data
<7> [397.798202] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [397.798206] i915 0000:00:02.0: quantization range: Default
<7> [397.798209] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [397.798212] i915 0000:00:02.0: video code: 16
<7> [397.798215] i915 0000:00:02.0: ycc quantization range: Full
<7> [397.798218] i915 0000:00:02.0: hdmi content type: Graphics
<7> [397.798222] i915 0000:00:02.0: pixel repeat: 0
<7> [397.798225] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [397.798229] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [397.798232] i915 0000:00:02.0: vendor: Intel
<7> [397.798235] i915 0000:00:02.0: product: Integrated gfx
<7> [397.798239] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [397.798243] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [397.798246] i915 0000:00:02.0: empty frame
<7> [397.798250] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 10 00 08 00 65 32 00 01 00 00 00 00 00 00 00 00
<7> [397.800507] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 61 a4 9a 00 4d 69 20 54 56 09 07 07 11 17 50 51
<7> [397.801003] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 07 00 00 00
<7> [397.801383] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [397.803381] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [397.804334] 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> [397.805005] 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> [397.805397] 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> [397.805971] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
<7> [397.806357] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
<7> [397.806932] 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=1084-1089 vt=1125, flags=0x5
<7> [397.807317] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [397.809231] 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=1084-1089 vt=1125, flags=0x5
<7> [397.810025] 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> [397.810414] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 59, ips linetime: 0
<7> [397.812050] 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> [397.812445] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1920x1080+0+0
<7> [397.813296] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [397.813982] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [397.814382] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [397.814945] 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> [397.815330] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [397.815933] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [397.816315] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [397.818511] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [397.819007] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [397.819384] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [397.820417] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [397.821200] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:122:plane 1C] fb: [FB:183] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [397.821859] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [397.822240] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [397.822824] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:131:plane 2C] fb: [NOFB], visible: no
<7> [397.823209] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:140:plane 3C] fb: [NOFB], visible: no
<7> [397.823769] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:149:plane 4C] fb: [NOFB], visible: no
<7> [397.824152] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:158:cursor C] fb: [NOFB], visible: no
<7> [397.826789] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling dpio-common-c
<7> [397.827928] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:165:DDI B/PHY B]
<7> [397.828324] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:175:DDI C/PHY C]
<7> [397.829644] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable PORT PLL C (active 0x4, on? 0) for [CRTC:164:pipe C]
<7> [397.830046] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling PORT PLL C
<7> [397.831056] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_C
<7> [397.831613] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe C
<7> [397.849181] 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], 36 bytes ELD
<7> [397.865821] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] Configuring HDMI audio for pixel clock 148500 (0x00090000)
<7> [397.866315] i915 0000:00:02.0: [drm:hsw_audio_config_update [i915]] using automatic N
<7> [397.868643] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:176:HDMI-A-2]
<7> [397.869341] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:164:pipe C]
<7> [397.871939] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:78:pipe A]
<7> [397.871998] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:166:HDMI-A-1]
<7> [397.872174] i915 0000:00:02.0: [drm:intel_hdmi_compute_output_format [i915]] picking 12 bpc for HDMI output (pipe bpp: 36)
<7> [397.872877] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:78:pipe A] hw max bpp: 36, pipe bpp: 36, dithering: 0
<7> [397.873267] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:165:DDI B/PHY B] [CRTC:78:pipe A]
<7> [397.875523] 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> [397.876021] 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> [397.876399] 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> [397.877239] 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> [397.877818] 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> [397.878202] 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> [397.878798] 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> [397.879181] 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> [397.879733] 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> [397.880116] 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> [397.880666] 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> [397.881049] 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> [397.881427] 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> [397.883502] 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> [397.883995] 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> [397.884374] 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 1084)
<7> [397.885950] 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 1089)
<7> [397.886356] 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> [397.886938] 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> [397.887324] 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> [397.887878] 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> [397.888262] 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> [397.888858] 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> [397.889241] 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> [397.889790] 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> [397.890173] 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> [397.892489] 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> [397.892978] 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 1084)
<7> [397.893356] 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 1089)
<7> [397.894295] 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> [397.894872] 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> [397.895254] 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> [397.895861] 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> [397.896243] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [397.896812] 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> [397.897194] 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> [397.899056] 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> [397.900503] 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> [397.900896] 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> [397.901274] 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> [397.901859] 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> [397.902242] 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> [397.902816] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in avi infoframe
<7> [397.903197] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [397.903744] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [397.904124] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [397.904129] i915 0000:00:02.0: colorspace: RGB
<7> [397.904133] i915 0000:00:02.0: scan mode: Underscan
<7> [397.904136] i915 0000:00:02.0: colorimetry: No Data
<7> [397.904139] i915 0000:00:02.0: picture aspect: 16:9
<7> [397.904143] i915 0000:00:02.0: active aspect: Same as Picture
<7> [397.904146] i915 0000:00:02.0: itc: No Data
<7> [397.904149] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [397.904153] i915 0000:00:02.0: quantization range: Default
<7> [397.904156] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [397.904159] i915 0000:00:02.0: video code: 16
<7> [397.904162] i915 0000:00:02.0: ycc quantization range: Full
<7> [397.904165] i915 0000:00:02.0: hdmi content type: Graphics
<7> [397.904168] i915 0000:00:02.0: pixel repeat: 0
<7> [397.904172] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [397.904175] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in spd infoframe
<7> [397.906112] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [397.906895] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [397.907278] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [397.907282] i915 0000:00:02.0: vendor: Intel
<7> [397.907286] i915 0000:00:02.0: product: Integrated gfx
<7> [397.907289] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [397.907294] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hdmi infoframe
<7> [397.908532] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [397.908915] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [397.909291] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [397.909295] i915 0000:00:02.0: empty frame
<7> [397.909301] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:78:pipe A] fastset requirement not met, forcing full modeset
<7> [397.910627] 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> [397.911042] 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> [397.911718] 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> [397.912099] 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> [397.912655] 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> [397.913035] 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> [397.913409] 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> [397.913999] 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> [397.914376] 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> [397.914942] 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> [397.915321] 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> [397.918216] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:78:pipe A] min cdclk: 0 kHz -> 192000 kHz
<7> [397.919026] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 9282 kHz -> 18563 kHz
<7> [397.919414] i915 0000:00:02.0: [drm:bxt_get_dpll [i915]] [CRTC:78:pipe A] using pre-allocated PORT PLL B
<7> [397.919998] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:78:pipe A] reserving PORT PLL B
<7> [397.920394] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:78:pipe A] enable: yes [modeset]
<7> [397.921017] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [397.921399] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 36, dithering: 0
<7> [397.921962] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [397.922342] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [397.924187] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [397.924935] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [397.925317] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [397.926560] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] GCP: 0x6
<7> [397.926944] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [397.926949] i915 0000:00:02.0: colorspace: RGB
<7> [397.926953] i915 0000:00:02.0: scan mode: Underscan
<7> [397.926956] i915 0000:00:02.0: colorimetry: No Data
<7> [397.926960] i915 0000:00:02.0: picture aspect: 16:9
<7> [397.926963] i915 0000:00:02.0: active aspect: Same as Picture
<7> [397.926966] i915 0000:00:02.0: itc: No Data
<7> [397.926970] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [397.926973] i915 0000:00:02.0: quantization range: Default
<7> [397.926976] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [397.926979] i915 0000:00:02.0: video code: 16
<7> [397.926983] i915 0000:00:02.0: ycc quantization range: Full
<7> [397.926986] i915 0000:00:02.0: hdmi content type: Graphics
<7> [397.926989] i915 0000:00:02.0: pixel repeat: 0
<7> [397.926992] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [397.926996] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [397.927000] i915 0000:00:02.0: vendor: Intel
<7> [397.927003] i915 0000:00:02.0: product: Integrated gfx
<7> [397.927007] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [397.927010] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [397.927014] i915 0000:00:02.0: empty frame
<7> [397.927018] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 10 00 08 00 65 32 00 01 00 00 00 00 00 00 00 00
<7> [397.927394] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 61 a4 9a 00 4d 69 20 54 56 09 07 07 11 17 50 51
<7> [397.928072] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 07 00 00 00
<7> [397.928639] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [397.929018] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [397.929392] 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> [397.929970] 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> [397.930351] 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> [397.932204] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
<7> [397.933507] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
<7> [397.933893] 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=1084-1089 vt=1125, flags=0x5
<7> [397.934272] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [397.935563] 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=1084-1089 vt=1125, flags=0x5
<7> [397.935949] 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> [397.936325] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 59, ips linetime: 0
<7> [397.936919] 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> [397.937299] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1920x1080+0+0
<7> [397.937853] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [397.938235] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [397.938805] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [397.939185] 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> [397.939734] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [397.940116] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [397.942765] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [397.943255] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [397.943922] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [397.944302] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [397.944762] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [397.945143] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:36:plane 1A] fb: [FB:183] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [397.945759] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [397.946141] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [397.946691] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:45:plane 2A] fb: [NOFB], visible: no
<7> [397.947071] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:54:plane 3A] fb: [NOFB], visible: no
<7> [397.948926] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:63:plane 4A] fb: [NOFB], visible: no
<7> [397.949442] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:72:cursor A] fb: [NOFB], visible: no
<7> [397.951106] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling dpio-common-b
<7> [397.952008] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:165:DDI B/PHY B]
<7> [397.952398] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:175:DDI C/PHY C]
<7> [397.965889] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable PORT PLL B (active 0x1, on? 0) for [CRTC:78:pipe A]
<7> [397.966387] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling PORT PLL B
<7> [397.967536] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_B
<7> [397.968034] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [397.970106] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] reserved 16588800 bytes of contiguous stolen space for FBC, limit: 1
<7> [397.970622] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:36:plane 1A]
<7> [397.986685] 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], 36 bytes ELD
<7> [398.000037] i915 0000:00:02.0: [drm:intel_audio_component_get_power [i915]] restored AUD_FREQ_CNTRL to 0x10
<7> [398.003351] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] Configuring HDMI audio for pixel clock 148500 (0x00090000)
<7> [398.003902] i915 0000:00:02.0: [drm:hsw_audio_config_update [i915]] using automatic N
<7> [398.004709] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:166:HDMI-A-1]
<7> [398.005282] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:78:pipe A]
<7> [398.013139] azx_init_pci: snd_hda_intel 0000:00:0e.0: Clearing TCSEL
<7> [398.013159] azx_init_pci: snd_hda_intel 0000:00:0e.0: SCH snoop: Enabled
<6> [406.198062] [IGT] kms_flip: finished subtest AC-HDMI-A1-HDMI-A2, FAIL
Created at 2026-04-14 22:58:24