Results for igt@kms_flip@flip-vs-expired-vblank@c-edp1

Result: Fail

i915_display_info1 igt_runner1 results1.json results1-xe-load.json i915_display_info_post_exec1 boot1 dmesg1

DetailValue
Duration 8.05 seconds
Hostname 
shard-lnl-3
Igt-Version 
IGT-Version: 2.2-gb542242f5 (x86_64) (Linux: 6.18.0-rc4-lgci-xe-xe-4071-3cabae5a2677e3629+ x86_64)
Out 
Starting dynamic subtest: C-eDP1
  2880x1800: 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa 
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../tests/kms_flip.c:923 run_test_on_crtc_set.constprop.0()
  #2 ../tests/kms_flip.c:1889 run_test()
  #3 ../tests/kms_flip.c:2122 __igt_unique____real_main2045()
  #4 ../tests/kms_flip.c:2045 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest C-eDP1: FAIL (8.051s)
Subtest flip-vs-expired-vblank: FAIL (30.464s)
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../lib/igt_kms.c:2267 kmstest_set_connector_dpms()
  #2 ../lib/igt_core.c:2961 igt_atexit_handler()
  #3 [erand48_r+0x1d6]
  #4 [exit+0x1e]
  #5 ../lib/igt_core.c:2416 igt_exit()
  #6 [main+0x3f]
  #7 [__libc_init_first+0x8a]
  #8 [__libc_start_main+0x8b]
  #9 [_start+0x25]
Err 
Starting dynamic subtest: C-eDP1
(kms_flip:6824) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:928:
(kms_flip:6824) CRITICAL: Failed assertion: end - start < 500
Dynamic subtest C-eDP1 failed.
**** DEBUG ****
(kms_flip:6824) DEBUG: name = flip
last_ts = 406.032196
last_received_ts = 406.031616
last_seq = 648
current_ts = 406.048859
current_received_ts = 406.048279
current_seq = 649
count = 279
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 86us
(kms_flip:6824) DEBUG: name = flip
last_ts = 406.048859
last_received_ts = 406.048279
last_seq = 649
current_ts = 406.065552
current_received_ts = 406.065002
current_seq = 650
count = 280
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 77us
(kms_flip:6824) DEBUG: name = flip
last_ts = 406.065552
last_received_ts = 406.065002
last_seq = 650
current_ts = 406.082214
current_received_ts = 406.081635
current_seq = 651
count = 281
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 83us
(kms_flip:6824) DEBUG: name = flip
last_ts = 406.082214
last_received_ts = 406.081635
last_seq = 651
current_ts = 406.098907
current_received_ts = 406.098358
current_seq = 652
count = 282
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 138us
(kms_flip:6824) DEBUG: name = flip
last_ts = 406.098907
last_received_ts = 406.098358
last_seq = 652
current_ts = 406.115570
current_received_ts = 406.115051
current_seq = 653
count = 283
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 72us
(kms_flip:6824) DEBUG: name = flip
last_ts = 406.115570
last_received_ts = 406.115051
last_seq = 653
current_ts = 406.132263
current_received_ts = 406.131744
current_seq = 654
count = 284
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 103us
(kms_flip:6824) DEBUG: name = flip
last_ts = 406.132263
last_received_ts = 406.131744
last_seq = 654
current_ts = 406.148926
current_received_ts = 406.148407
current_seq = 655
count = 285
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 135us
(kms_flip:6824) DEBUG: name = flip
last_ts = 406.148926
last_received_ts = 406.148407
last_seq = 655
current_ts = 406.165619
current_received_ts = 406.165100
current_seq = 656
count = 286
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 52us
(kms_flip:6824) DEBUG: name = flip
last_ts = 406.165619
last_received_ts = 406.165100
last_seq = 656
current_ts = 406.182281
current_received_ts = 406.181732
current_seq = 657
count = 287
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 80us
(kms_flip:6824) DEBUG: name = flip
last_ts = 406.182281
last_received_ts = 406.181732
last_seq = 657
current_ts = 406.198975
current_received_ts = 406.198334
current_seq = 658
count = 288
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 73us
(kms_flip:6824) DEBUG: name = flip
last_ts = 406.198975
last_received_ts = 406.198334
last_seq = 658
current_ts = 406.215637
current_received_ts = 406.215057
current_seq = 659
count = 289
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 71us
(kms_flip:6824) DEBUG: name = flip
last_ts = 406.215637
last_received_ts = 406.215057
last_seq = 659
current_ts = 406.232300
current_received_ts = 406.231750
current_seq = 660
count = 290
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 73us
(kms_flip:6824) DEBUG: name = flip
last_ts = 406.232300
last_received_ts = 406.231750
last_seq = 660
current_ts = 406.248993
current_received_ts = 406.248413
current_seq = 661
count = 291
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 81us
(kms_flip:6824) DEBUG: name = flip
last_ts = 406.248993
last_received_ts = 406.248413
last_seq = 661
current_ts = 406.265656
current_received_ts = 406.265076
current_seq = 662
count = 292
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 74us
(kms_flip:6824) DEBUG: name = flip
last_ts = 406.265656
last_received_ts = 406.265076
last_seq = 662
current_ts = 406.282349
current_received_ts = 406.281769
current_seq = 663
count = 293
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 81us
(kms_flip:6824) DEBUG: name = flip
last_ts = 406.282349
last_received_ts = 406.281769
last_seq = 663
current_ts = 406.299011
current_received_ts = 406.298401
current_seq = 664
count = 294
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 74us
(kms_flip:6824) DEBUG: name = flip
last_ts = 406.299011
last_received_ts = 406.298401
last_seq = 664
current_ts = 406.315704
current_received_ts = 406.315125
current_seq = 665
count = 295
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 73us
(kms_flip:6824) DEBUG: name = flip
last_ts = 406.315704
last_received_ts = 406.315125
last_seq = 665
current_ts = 406.332367
current_received_ts = 406.331818
current_seq = 666
count = 296
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 79us
(kms_flip:6824) DEBUG: name = flip
last_ts = 406.332367
last_received_ts = 406.331818
last_seq = 666
current_ts = 406.349060
current_received_ts = 406.348480
current_seq = 667
count = 297
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 73us
(kms_flip:6824) DEBUG: name = flip
last_ts = 406.349060
last_received_ts = 406.348480
last_seq = 667
current_ts = 406.365723
current_received_ts = 406.365173
current_seq = 668
count = 298
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 72us
(kms_flip:6824) DEBUG: name = flip
last_ts = 406.365723
last_received_ts = 406.365173
last_seq = 668
current_ts = 406.382385
current_received_ts = 406.381836
current_seq = 669
count = 299
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 73us
(kms_flip:6824) DEBUG: name = flip
last_ts = 406.382385
last_received_ts = 406.381836
last_seq = 669
current_ts = 406.399078
current_received_ts = 406.398438
current_seq = 670
count = 300
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 80us
(kms_flip:6824) DEBUG: name = flip
last_ts = 406.399078
last_received_ts = 406.398438
last_seq = 670
current_ts = 406.415741
current_received_ts = 406.415161
current_seq = 671
count = 301
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 73us
(kms_flip:6824) DEBUG: name = flip
last_ts = 406.415741
last_received_ts = 406.415161
last_seq = 671
current_ts = 406.432434
current_received_ts = 406.431854
current_seq = 672
count = 302
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 73us
(kms_flip:6824) DEBUG: name = flip
last_ts = 406.432434
last_received_ts = 406.431854
last_seq = 672
current_ts = 406.449097
current_received_ts = 406.448547
current_seq = 673
count = 303
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 81us
(kms_flip:6824) DEBUG: name = flip
last_ts = 406.449097
last_received_ts = 406.448547
last_seq = 673
current_ts = 406.465790
current_received_ts = 406.465179
current_seq = 674
count = 304
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 73us
(kms_flip:6824) DEBUG: name = flip
last_ts = 406.465790
last_received_ts = 406.465179
last_seq = 674
current_ts = 406.482452
current_received_ts = 406.481903
current_seq = 675
count = 305
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 72us
(kms_flip:6824) DEBUG: name = flip
last_ts = 406.482452
last_received_ts = 406.481903
last_seq = 675
current_ts = 406.499146
current_received_ts = 406.498596
current_seq = 676
count = 306
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 51us
(kms_flip:6824) DEBUG: name = flip
last_ts = 406.499146
last_received_ts = 406.498596
last_seq = 676
current_ts = 406.515808
current_received_ts = 406.515167
current_seq = 677
count = 307
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 83us
(kms_flip:6824) DEBUG: name = flip
last_ts = 406.515808
last_received_ts = 406.515167
last_seq = 677
current_ts = 406.532501
current_received_ts = 406.531921
current_seq = 678
count = 308
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 97us
(kms_flip:6824) DEBUG: name = flip
last_ts = 406.532501
last_received_ts = 406.531921
last_seq = 678
current_ts = 406.549164
current_received_ts = 406.548584
current_seq = 679
count = 309
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 50us
(kms_flip:6824) DEBUG: name = flip
last_ts = 406.549164
last_received_ts = 406.548584
last_seq = 679
current_ts = 406.565826
current_received_ts = 406.565155
current_seq = 680
count = 310
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 80us
(kms_flip:6824) DEBUG: name = flip
last_ts = 406.565826
last_received_ts = 406.565155
last_seq = 680
current_ts = 406.582520
current_received_ts = 406.581940
current_seq = 681
count = 311
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 113us
(kms_flip:6824) DEBUG: name = flip
last_ts = 406.582520
last_received_ts = 406.581940
last_seq = 681
current_ts = 406.599182
current_received_ts = 406.598633
current_seq = 682
count = 312
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 81us
(kms_flip:6824) DEBUG: name = flip
last_ts = 406.599182
last_received_ts = 406.598633
last_seq = 682
current_ts = 406.615875
current_received_ts = 406.615265
current_seq = 683
count = 313
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 134us
(kms_flip:6824) DEBUG: name = flip
last_ts = 406.615875
last_received_ts = 406.615265
last_seq = 683
current_ts = 406.632538
current_received_ts = 406.631989
current_seq = 684
count = 314
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 71us
(kms_flip:6824) DEBUG: name = flip
last_ts = 406.632538
last_received_ts = 406.631989
last_seq = 684
current_ts = 406.649231
current_received_ts = 406.648651
current_seq = 685
count = 315
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 54us
(kms_flip:6824) DEBUG: name = flip
last_ts = 406.649231
last_received_ts = 406.648651
last_seq = 685
current_ts = 406.665894
current_received_ts = 406.665314
current_seq = 686
count = 316
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 74us
(kms_flip:6824) DEBUG: name = flip
last_ts = 406.665894
last_received_ts = 406.665314
last_seq = 686
current_ts = 406.682587
current_received_ts = 406.682068
current_seq = 687
count = 317
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 74us
(kms_flip:6824) DEBUG: name = flip
last_ts = 406.682587
last_received_ts = 406.682068
last_seq = 687
current_ts = 406.699249
current_received_ts = 406.698730
current_seq = 688
count = 318
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 72us
(kms_flip:6824) DEBUG: name = flip
last_ts = 406.699249
last_received_ts = 406.698730
last_seq = 688
current_ts = 406.715942
current_received_ts = 406.715332
current_seq = 689
count = 319
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 75us
(kms_flip:6824) DEBUG: name = flip
last_ts = 406.715942
last_received_ts = 406.715332
last_seq = 689
current_ts = 406.732605
current_received_ts = 406.732086
current_seq = 690
count = 320
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 73us
(kms_flip:6824) DEBUG: name = flip
last_ts = 406.732605
last_received_ts = 406.732086
last_seq = 690
current_ts = 406.749298
current_received_ts = 406.748749
current_seq = 691
count = 321
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 55us
(kms_flip:6824) DEBUG: name = flip
last_ts = 406.749298
last_received_ts = 406.748749
last_seq = 691
current_ts = 406.765961
current_received_ts = 406.765350
current_seq = 692
count = 322
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 72us
(kms_flip:6824) DEBUG: name = flip
last_ts = 406.765961
last_received_ts = 406.765350
last_seq = 692
current_ts = 406.782623
current_received_ts = 406.782074
current_seq = 693
count = 323
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 73us
(kms_flip:6824) DEBUG: name = flip
last_ts = 406.782623
last_received_ts = 406.782074
last_seq = 693
current_ts = 406.799316
current_received_ts = 406.798737
current_seq = 694
count = 324
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 81us
(kms_flip:6824) DEBUG: name = flip
last_ts = 406.799316
last_received_ts = 406.798737
last_seq = 694
current_ts = 406.816010
current_received_ts = 406.815460
current_seq = 695
count = 325
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 126us
(kms_flip:6824) DEBUG: name = flip
last_ts = 406.816010
last_received_ts = 406.815460
last_seq = 695
current_ts = 406.832672
current_received_ts = 406.832123
current_seq = 696
count = 326
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 81us
(kms_flip:6824) DEBUG: name = flip
last_ts = 406.832672
last_received_ts = 406.832123
last_seq = 696
current_ts = 406.849335
current_received_ts = 406.848785
current_seq = 697
count = 327
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 80us
(kms_flip:6824) DEBUG: name = flip
last_ts = 406.849335
last_received_ts = 406.848785
last_seq = 697
current_ts = 406.866028
current_received_ts = 406.865417
current_seq = 698
count = 328
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 54us
(kms_flip:6824) DEBUG: name = flip
last_ts = 406.866028
last_received_ts = 406.865417
last_seq = 698
current_ts = 406.882690
current_received_ts = 406.882111
current_seq = 699
count = 329
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 75us
(kms_flip:6824) DEBUG: name = flip
last_ts = 406.882690
last_received_ts = 406.882111
last_seq = 699
current_ts = 406.899384
current_received_ts = 406.898804
current_seq = 700
count = 330
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 72us
(kms_flip:6824) DEBUG: name = flip
last_ts = 406.899384
last_received_ts = 406.898804
last_seq = 700
current_ts = 406.916046
current_received_ts = 406.915436
current_seq = 701
count = 331
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 80us
(kms_flip:6824) DEBUG: name = flip
last_ts = 406.916046
last_received_ts = 406.915436
last_seq = 701
current_ts = 406.932709
current_received_ts = 406.932159
current_seq = 702
count = 332
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 85us
(kms_flip:6824) DEBUG: name = flip
last_ts = 406.932709
last_received_ts = 406.932159
last_seq = 702
current_ts = 406.949402
current_received_ts = 406.948853
current_seq = 703
count = 333
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 74us
(kms_flip:6824) DEBUG: name = flip
last_ts = 406.949402
last_received_ts = 406.948853
last_seq = 703
current_ts = 406.966064
current_received_ts = 406.965546
current_seq = 704
count = 334
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 89us
(kms_flip:6824) DEBUG: name = flip
last_ts = 406.966064
last_received_ts = 406.965546
last_seq = 704
current_ts = 406.982758
current_received_ts = 406.982178
current_seq = 705
count = 335
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 80us
(kms_flip:6824) DEBUG: name = flip
last_ts = 406.982758
last_received_ts = 406.982178
last_seq = 705
current_ts = 406.999420
current_received_ts = 406.998871
current_seq = 706
count = 336
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 81us
(kms_flip:6824) DEBUG: name = flip
last_ts = 406.999420
last_received_ts = 406.998871
last_seq = 706
current_ts = 407.016113
current_received_ts = 407.015503
current_seq = 707
count = 337
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 73us
(kms_flip:6824) DEBUG: name = flip
last_ts = 407.016113
last_received_ts = 407.015503
last_seq = 707
current_ts = 407.032776
current_received_ts = 407.032227
current_seq = 708
count = 338
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 81us
(kms_flip:6824) DEBUG: name = flip
last_ts = 407.032776
last_received_ts = 407.032227
last_seq = 708
current_ts = 407.049469
current_received_ts = 407.048889
current_seq = 709
count = 339
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 80us
(kms_flip:6824) DEBUG: name = flip
last_ts = 407.049469
last_received_ts = 407.048889
last_seq = 709
current_ts = 407.066132
current_received_ts = 407.065582
current_seq = 710
count = 340
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 81us
(kms_flip:6824) DEBUG: name = flip
last_ts = 407.066132
last_received_ts = 407.065582
last_seq = 710
current_ts = 407.082794
current_received_ts = 407.082153
current_seq = 711
count = 341
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 72us
(kms_flip:6824) DEBUG: name = flip
last_ts = 407.082794
last_received_ts = 407.082153
last_seq = 711
current_ts = 407.099487
current_received_ts = 407.098938
current_seq = 712
count = 342
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 92us
(kms_flip:6824) DEBUG: name = flip
last_ts = 407.099487
last_received_ts = 407.098938
last_seq = 712
current_ts = 407.116150
current_received_ts = 407.115601
current_seq = 713
count = 343
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 73us
(kms_flip:6824) DEBUG: name = flip
last_ts = 407.116150
last_received_ts = 407.115601
last_seq = 713
current_ts = 407.132843
current_received_ts = 407.132263
current_seq = 714
count = 344
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 89us
(kms_flip:6824) DEBUG: name = flip
last_ts = 407.132843
last_received_ts = 407.132263
last_seq = 714
current_ts = 407.149506
current_received_ts = 407.148956
current_seq = 715
count = 345
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 108us
(kms_flip:6824) DEBUG: name = flip
last_ts = 407.149506
last_received_ts = 407.148956
last_seq = 715
current_ts = 407.166199
current_received_ts = 407.165558
current_seq = 716
count = 346
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 73us
(kms_flip:6824) DEBUG: name = flip
last_ts = 407.166199
last_received_ts = 407.165558
last_seq = 716
current_ts = 407.182861
current_received_ts = 407.182190
current_seq = 717
count = 347
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 80us
(kms_flip:6824) DEBUG: name = flip
last_ts = 407.182861
last_received_ts = 407.182190
last_seq = 717
current_ts = 407.199554
current_received_ts = 407.199036
current_seq = 718
count = 348
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 84us
(kms_flip:6824) DEBUG: name = flip
last_ts = 407.199554
last_received_ts = 407.199036
last_seq = 718
current_ts = 407.216217
current_received_ts = 407.215668
current_seq = 719
count = 349
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 79us
(kms_flip:6824) DEBUG: name = flip
last_ts = 407.216217
last_received_ts = 407.215668
last_seq = 719
current_ts = 407.232880
current_received_ts = 407.232269
current_seq = 720
count = 350
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 72us
(kms_flip:6824) DEBUG: name = flip
last_ts = 407.232880
last_received_ts = 407.232269
last_seq = 720
current_ts = 407.249573
current_received_ts = 407.249023
current_seq = 721
count = 351
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 80us
(kms_flip:6824) DEBUG: name = flip
last_ts = 407.249573
last_received_ts = 407.249023
last_seq = 721
current_ts = 407.266235
current_received_ts = 407.265686
current_seq = 722
count = 352
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 73us
(kms_flip:6824) DEBUG: name = flip
last_ts = 407.266235
last_received_ts = 407.265686
last_seq = 722
current_ts = 407.282928
current_received_ts = 407.282318
current_seq = 723
count = 353
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 72us
(kms_flip:6824) DEBUG: name = flip
last_ts = 407.282928
last_received_ts = 407.282318
last_seq = 723
current_ts = 407.299591
current_received_ts = 407.299072
current_seq = 724
count = 354
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 84us
(kms_flip:6824) DEBUG: name = flip
last_ts = 407.299591
last_received_ts = 407.299072
last_seq = 724
current_ts = 407.316254
current_received_ts = 407.315704
current_seq = 725
count = 355
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 73us
(kms_flip:6824) DEBUG: name = flip
last_ts = 407.316254
last_received_ts = 407.315704
last_seq = 725
current_ts = 407.332947
current_received_ts = 407.332397
current_seq = 726
count = 356
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 133us
(kms_flip:6824) DEBUG: name = flip
last_ts = 407.332947
last_received_ts = 407.332397
last_seq = 726
current_ts = 407.349609
current_received_ts = 407.349091
current_seq = 727
count = 357
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 52us
(kms_flip:6824) DEBUG: name = flip
last_ts = 407.349609
last_received_ts = 407.349091
last_seq = 727
current_ts = 407.366302
current_received_ts = 407.365753
current_seq = 728
count = 358
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 75us
(kms_flip:6824) DEBUG: name = flip
last_ts = 407.366302
last_received_ts = 407.365753
last_seq = 728
current_ts = 407.382965
current_received_ts = 407.382355
current_seq = 729
count = 359
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 81us
(kms_flip:6824) DEBUG: name = flip
last_ts = 407.382965
last_received_ts = 407.382355
last_seq = 729
current_ts = 407.399658
current_received_ts = 407.399109
current_seq = 730
count = 360
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 73us
(kms_flip:6824) DEBUG: name = flip
last_ts = 407.399658
last_received_ts = 407.399109
last_seq = 730
current_ts = 407.416321
current_received_ts = 407.415771
current_seq = 731
count = 361
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 84us
(kms_flip:6824) DEBUG: name = flip
last_ts = 407.416321
last_received_ts = 407.415771
last_seq = 731
current_ts = 407.433014
current_received_ts = 407.432404
current_seq = 732
count = 362
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 54us
(kms_flip:6824) DEBUG: name = flip
last_ts = 407.433014
last_received_ts = 407.432404
last_seq = 732
current_ts = 407.449677
current_received_ts = 407.449127
current_seq = 733
count = 363
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 81us
(kms_flip:6824) DEBUG: name = flip
last_ts = 407.449677
last_received_ts = 407.449127
last_seq = 733
current_ts = 407.466339
current_received_ts = 407.465820
current_seq = 734
count = 364
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 86us
(kms_flip:6824) DEBUG: name = flip
last_ts = 407.466339
last_received_ts = 407.465820
last_seq = 734
current_ts = 407.483032
current_received_ts = 407.482483
current_seq = 735
count = 365
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 135us
(kms_flip:6824) DEBUG: name = flip
last_ts = 407.483032
last_received_ts = 407.482483
last_seq = 735
current_ts = 407.499695
current_received_ts = 407.499146
current_seq = 736
count = 366
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 72us
(kms_flip:6824) DEBUG: name = flip
last_ts = 407.499695
last_received_ts = 407.499146
last_seq = 736
current_ts = 407.516388
current_received_ts = 407.515808
current_seq = 737
count = 367
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 80us
(kms_flip:6824) DEBUG: name = flip
last_ts = 407.516388
last_received_ts = 407.515808
last_seq = 737
current_ts = 407.533051
current_received_ts = 407.532440
current_seq = 738
count = 368
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 81us
(kms_flip:6824) DEBUG: name = flip
last_ts = 407.533051
last_received_ts = 407.532440
last_seq = 738
current_ts = 407.549744
current_received_ts = 407.549164
current_seq = 739
count = 369
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 73us
(kms_flip:6824) DEBUG: name = flip
last_ts = 407.549744
last_received_ts = 407.549164
last_seq = 739
current_ts = 407.566406
current_received_ts = 407.565857
current_seq = 740
count = 370
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 79us
(kms_flip:6824) DEBUG: name = flip
last_ts = 407.566406
last_received_ts = 407.565857
last_seq = 740
current_ts = 407.583099
current_received_ts = 407.582550
current_seq = 741
count = 371
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 81us
(kms_flip:6824) DEBUG: name = flip
last_ts = 407.583099
last_received_ts = 407.582550
last_seq = 741
current_ts = 407.599762
current_received_ts = 407.599182
current_seq = 742
count = 372
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 73us
(kms_flip:6824) DEBUG: name = flip
last_ts = 407.599762
last_received_ts = 407.599182
last_seq = 742
current_ts = 407.616455
current_received_ts = 407.615875
current_seq = 743
count = 373
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 72us
(kms_flip:6824) DEBUG: name = flip
last_ts = 407.616455
last_received_ts = 407.615875
last_seq = 743
current_ts = 407.633118
current_received_ts = 407.632568
current_seq = 744
count = 374
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 73us
(kms_flip:6824) DEBUG: name = flip
last_ts = 407.633118
last_received_ts = 407.632568
last_seq = 744
current_ts = 407.649811
current_received_ts = 407.649170
current_seq = 745
count = 375
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 51us
(kms_flip:6824) DEBUG: name = flip
last_ts = 407.649811
last_received_ts = 407.649170
last_seq = 745
current_ts = 407.666473
current_received_ts = 407.665894
current_seq = 746
count = 376
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 80us
(kms_flip:6824) DEBUG: name = flip
last_ts = 407.666473
last_received_ts = 407.665894
last_seq = 746
current_ts = 407.683136
current_received_ts = 407.682556
current_seq = 747
count = 377
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 75us
(kms_flip:6824) DEBUG: name = flip
last_ts = 407.683136
last_received_ts = 407.682556
last_seq = 747
current_ts = 407.699829
current_received_ts = 407.699158
current_seq = 748
count = 378
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 72us
(kms_flip:6824) DEBUG: name = flip
last_ts = 407.699829
last_received_ts = 407.699158
last_seq = 748
current_ts = 407.716492
current_received_ts = 407.715942
current_seq = 749
count = 379
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 113us
(kms_flip:6824) DEBUG: name = flip
last_ts = 407.716492
last_received_ts = 407.715942
last_seq = 749
current_ts = 407.733185
current_received_ts = 407.732605
current_seq = 750
count = 380
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 81us
(kms_flip:6824) DEBUG: name = flip
last_ts = 407.733185
last_received_ts = 407.732605
last_seq = 750
current_ts = 407.749847
current_received_ts = 407.749207
current_seq = 751
count = 381
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 81us
(kms_flip:6824) DEBUG: name = flip
last_ts = 407.749847
last_received_ts = 407.749207
last_seq = 751
current_ts = 407.766510
current_received_ts = 407.765961
current_seq = 752
count = 382
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 103us
(kms_flip:6824) DEBUG: name = flip
last_ts = 407.766510
last_received_ts = 407.765961
last_seq = 752
current_ts = 407.783203
current_received_ts = 407.782623
current_seq = 753
count = 383
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 76us
(kms_flip:6824) DEBUG: name = flip
last_ts = 407.783203
last_received_ts = 407.782623
last_seq = 753
current_ts = 407.799866
current_received_ts = 407.799194
current_seq = 754
count = 384
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 80us
(kms_flip:6824) DEBUG: name = flip
last_ts = 407.799866
last_received_ts = 407.799194
last_seq = 754
current_ts = 407.816559
current_received_ts = 407.815979
current_seq = 755
count = 385
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 51us
(kms_flip:6824) DEBUG: name = flip
last_ts = 407.816559
last_received_ts = 407.815979
last_seq = 755
current_ts = 407.833221
current_received_ts = 407.832672
current_seq = 756
count = 386
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 84us
(kms_flip:6824) DEBUG: name = flip
last_ts = 407.833221
last_received_ts = 407.832672
last_seq = 756
current_ts = 407.849915
current_received_ts = 407.849304
current_seq = 757
count = 387
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 81us
(kms_flip:6824) DEBUG: name = flip
last_ts = 407.849915
last_received_ts = 407.849304
last_seq = 757
current_ts = 407.866577
current_received_ts = 407.866058
current_seq = 758
count = 388
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 78us
(kms_flip:6824) DEBUG: name = flip
last_ts = 407.866577
last_received_ts = 407.866058
last_seq = 758
current_ts = 407.883240
current_received_ts = 407.882721
current_seq = 759
count = 389
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 75us
(kms_flip:6824) DEBUG: name = flip
last_ts = 407.883240
last_received_ts = 407.882721
last_seq = 759
current_ts = 407.899933
current_received_ts = 407.899323
current_seq = 760
count = 390
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 81us
(kms_flip:6824) DEBUG: name = flip
last_ts = 407.899933
last_received_ts = 407.899323
last_seq = 760
current_ts = 407.916595
current_received_ts = 407.916077
current_seq = 761
count = 391
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 82us
(kms_flip:6824) DEBUG: name = flip
last_ts = 407.916595
last_received_ts = 407.916077
last_seq = 761
current_ts = 407.933289
current_received_ts = 407.932739
current_seq = 762
count = 392
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 81us
(kms_flip:6824) DEBUG: name = flip
last_ts = 407.933289
last_received_ts = 407.932739
last_seq = 762
current_ts = 407.949951
current_received_ts = 407.949402
current_seq = 763
count = 393
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 74us
(kms_flip:6824) DEBUG: name = flip
last_ts = 407.949951
last_received_ts = 407.949402
last_seq = 763
current_ts = 407.966644
current_received_ts = 407.966095
current_seq = 764
count = 394
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 79us
(kms_flip:6824) DEBUG: name = flip
last_ts = 407.966644
last_received_ts = 407.966095
last_seq = 764
current_ts = 407.983307
current_received_ts = 407.982758
current_seq = 765
count = 395
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 82us
(kms_flip:6824) DEBUG: name = flip
last_ts = 407.983307
last_received_ts = 407.982758
last_seq = 765
current_ts = 408.000000
current_received_ts = 407.999420
current_seq = 766
count = 396
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 137us
(kms_flip:6824) DEBUG: name = flip
last_ts = 408.000000
last_received_ts = 407.999420
last_seq = 766
current_ts = 408.016663
current_received_ts = 408.016113
current_seq = 767
count = 397
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 86us
(kms_flip:6824) DEBUG: name = flip
last_ts = 408.016663
last_received_ts = 408.016113
last_seq = 767
current_ts = 408.033325
current_received_ts = 408.032776
current_seq = 768
count = 398
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 54us
(kms_flip:6824) DEBUG: name = flip
last_ts = 408.033325
last_received_ts = 408.032776
last_seq = 768
current_ts = 408.050018
current_received_ts = 408.049316
current_seq = 769
count = 399
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 73us
(kms_flip:6824) DEBUG: name = flip
last_ts = 408.050018
last_received_ts = 408.049316
last_seq = 769
current_ts = 408.066681
current_received_ts = 408.066132
current_seq = 770
count = 400
seq_step = 1
(kms_flip:6824) DEBUG: Vblank took 1364us
(kms_flip:6824) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:928:
(kms_flip:6824) CRITICAL: Failed assertion: end - start < 500
(kms_flip:6824) igt_core-INFO: Stack trace:
(kms_flip:6824) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:6824) igt_core-INFO:   #1 ../tests/kms_flip.c:923 run_test_on_crtc_set.constprop.0()
(kms_flip:6824) igt_core-INFO:   #2 ../tests/kms_flip.c:1889 run_test()
(kms_flip:6824) igt_core-INFO:   #3 ../tests/kms_flip.c:2122 __igt_unique____real_main2045()
(kms_flip:6824) igt_core-INFO:   #4 ../tests/kms_flip.c:2045 main()
(kms_flip:6824) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:6824) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:6824) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest C-eDP1: FAIL (8.051s)
Subtest flip-vs-expired-vblank: FAIL (30.464s)
(kms_flip:6824) igt_kms-CRITICAL: Test assertion failure function kmstest_set_connector_dpms, file ../lib/igt_kms.c:2267:
(kms_flip:6824) igt_kms-CRITICAL: Failed assertion: found_it
(kms_flip:6824) igt_kms-CRITICAL: Last errno: 9, Bad file descriptor
(kms_flip:6824) igt_kms-CRITICAL: DPMS property not found on 208
Dmesg
<6> [414.541146] [IGT] kms_flip: starting dynamic subtest C-eDP1
<7> [414.556085] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [414.556201] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [414.556343] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
<7> [414.556543] xe 0000:00:02.0: [drm:drm_mode_addfb2] [FB:245]
<7> [414.556893] xe 0000:00:02.0: [drm:drm_mode_addfb2] [FB:247]
<7> [414.627978] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:88:pipe A]
<7> [414.628245] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [414.628562] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [414.628935] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:146:pipe B]
<7> [414.629070] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:146:pipe B] fastset requirement not met in hw.enable (expected yes, found no)
<7> [414.629391] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:146:pipe B] fastset requirement not met in hw.active (expected yes, found no)
<7> [414.629616] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:146:pipe B] fastset requirement not met in cpu_transcoder (expected 1, found -1)
<7> [414.629843] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:146:pipe B] fastset requirement not met in lane_count (expected 4, found 0)
<7> [414.630063] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:146:pipe B] fastset requirement not met in dp_m_n (expected tu 64 data 6329869/8388608 link 421991/524288, found tu 0, data 0/0 link 0/0)
<7> [414.630295] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:146:pipe B] fastset requirement not met in output_types (expected 0x00000100, found 0x00000000)
<7> [414.630498] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:146:pipe B] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [414.630690] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:146:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 2880, found 0)
<7> [414.630877] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:146:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 3040, found 0)
<7> [414.631063] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:146:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 2880, found 0)
<7> [414.631260] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:146:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 3040, found 0)
<7> [414.631436] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:146:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2928, found 0)
<7> [414.631610] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:146:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2960, found 0)
<7> [414.631781] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:146:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1800, found 0)
<7> [414.631950] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:146:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1800, found 0)
<7> [414.632113] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:146:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1803, found 0)
<7> [414.632289] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:146:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1809, found 0)
<7> [414.632441] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:146:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1906, found 0)
<7> [414.632586] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:146:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1906, found 0)
<7> [414.632729] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:146:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 2880, found 0)
<7> [414.632874] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:146:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 3040, found 0)
<7> [414.633021] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:146:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 2880, found 0)
<7> [414.633164] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:146:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 3040, found 0)
<7> [414.633294] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:146:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2928, found 0)
<7> [414.633422] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:146:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2960, found 0)
<7> [414.633549] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:146:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1800, found 0)
<7> [414.633672] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:146:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1800, found 0)
<7> [414.633794] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:146:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1803, found 0)
<7> [414.633914] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:146:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1809, found 0)
<7> [414.634033] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:146:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1906, found 0)
<7> [414.634149] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:146:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1906, found 0)
<7> [414.634286] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:146:pipe B] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [414.634398] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:146:pipe B] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)
<7> [414.634508] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:146:pipe B] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [414.634618] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:146:pipe B] fastset requirement not met in pipe_bpp (expected 30, found 0)
<7> [414.634727] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:146:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 347710, found 0)
<7> [414.634837] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:146:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 347710, found 0)
<7> [414.634949] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:146:pipe B] fastset requirement not met in port_clock (expected 432000, found 0)
<7> [414.635075] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:146:pipe B] fastset requirement not met, forcing full modeset
<7> [414.635288] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xf -> 0x1 (total dbuf slices 0xf), mbus joined? yes->no
<7> [414.635416] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:146:pipe B] dbuf slices 0xf -> 0x0, ddb (0 - 4096) -> (0 - 0), active pipes 0x2 -> 0x0
<7> [414.635554] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:141:cursor B] ddb (4051 - 4096) -> ( 0 - 0), size 45 -> 0
<7> [414.635678] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:146:pipe B] min cdclk: 173855 kHz -> 0 kHz
<7> [414.635806] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] Can change cdclk via squashing
<7> [414.635932] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New cdclk calculated to be logical 153600 kHz, actual 153600 kHz
<7> [414.636054] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New voltage level calculated to be logical 0, actual 0
<7> [414.636214] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:146:pipe B] enable: no [modeset]
<7> [414.636346] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:91:plane 1B] fb: [NOFB], visible: no
<7> [414.636480] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:101:plane 2B] fb: [NOFB], visible: no
<7> [414.636612] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:111:plane 3B] fb: [NOFB], visible: no
<7> [414.636734] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:121:plane 4B] fb: [NOFB], visible: no
<7> [414.636857] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:131:plane 5B] fb: [NOFB], visible: no
<7> [414.636989] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:141:cursor B] fb: [NOFB], visible: no
<7> [414.637276] xe 0000:00:02.0: [drm:intel_alpm_disable [xe]] Disabling ALPM
<7> [414.637420] xe 0000:00:02.0: [drm:intel_edp_backlight_off [xe]]
<7> [414.845189] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:208:eDP-1] set backlight PWM = 0
<7> [414.846000] xe 0000:00:02.0: [drm:intel_disable_transcoder [xe]] disabling pipe B
<7> [414.856177] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:207:DDI A/PHY A] PPS 0 turn panel power off
<7> [414.856634] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:207:DDI A/PHY A] PPS 0 wait for panel power off time
<7> [414.857089] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:207:DDI A/PHY A] PPS 0 mask: 0xb0000000 value: 0x00000000 PP_STATUS: 0xa0000002 PP_CONTROL: 0x00000060
<7> [414.909399] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [414.910015] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:207:DDI A/PHY A] - short
<7> [414.916917] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [414.917503] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling AUX_A
<7> [414.918142] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:207:DDI A/PHY A]
<7> [414.918126] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:207:DDI A/PHY A]
<7> [414.918589] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:216:DDI TC1/PHY TC1]
<7> [414.918986] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:218:DP-MST A]
<7> [414.919339] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:219:DP-MST B]
<7> [414.919678] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:220:DP-MST C]
<7> [414.920074] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:228:DDI TC2/PHY TC2]
<7> [414.920407] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:230:DP-MST A]
<7> [414.920761] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:231:DP-MST B]
<7> [414.921128] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:232:DP-MST C]
<7> [414.921473] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:236:DDI TC3/PHY TC3]
<7> [414.921843] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:238:DP-MST A]
<7> [414.922213] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:239:DP-MST B]
<7> [414.922519] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:240:DP-MST C]
<7> [414.922922] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:208:eDP-1]
<7> [414.923272] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [414.923547] xe 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: yes -> no (pipe: *)
<7> [414.923825] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0x1
<7> [414.924352] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_B
<7> [414.924812] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_2
<7> [414.925305] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:146:pipe B]
<7> [414.925683] xe 0000:00:02.0: [drm:intel_set_cdclk [xe]] Post changing CDCLK to 153600 kHz, VCO 614400 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0
<7> [414.926152] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [414.926460] xe 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=384
<7> [414.927303] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:204:pipe C]
<7> [414.927693] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:204:pipe C]
<7> [414.927768] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:208:eDP-1]
<7> [414.927965] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CONNECTOR:208:eDP-1] Limiting display bpp to 30 (EDID bpp 30, max requested bpp 36, max platform bpp 36)
<7> [414.928388] xe 0000:00:02.0: [drm:intel_dp_compute_config_limits [xe]] [ENCODER:207:DDI A/PHY A][CRTC:204:pipe C] DP link limits: pixel clock 347710 kHz DSC off max lanes 4 max rate 810000 max pipe_bpp 30 max link_bpp 30.0000
<7> [414.928795] xe 0000:00:02.0: [drm:intel_dp_compute_link_config [xe]] DP lane count 4 clock 432000 bpp input 30 compressed 0.0000 link rate required 1303913 available 1728000
<7> [414.929190] xe 0000:00:02.0: [drm:intel_psr_compute_config [xe]] PSR2 not supported in transcoder C
<7> [414.929468] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:204:pipe C] hw max bpp: 30, pipe bpp: 30, dithering: 0
<7> [414.929850] xe 0000:00:02.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:207:DDI A/PHY A] [CRTC:204:pipe C]
<7> [414.930163] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in hw.enable (expected no, found yes)
<7> [414.930473] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in hw.active (expected no, found yes)
<7> [414.930813] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in cpu_transcoder (expected -1, found 2)
<7> [414.931146] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in lane_count (expected 0, found 4)
<7> [414.931420] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in dp_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 6329869/8388608 link 421991/524288)
<7> [414.931688] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in output_types (expected 0x00000000, found 0x00000100)
<7> [414.931971] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [414.932219] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 2880)
<7> [414.932495] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 3040)
<7> [414.932711] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 2880)
<7> [414.932946] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 3040)
<7> [414.933152] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2928)
<7> [414.933351] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2960)
<7> [414.933540] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1800)
<7> [414.933764] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1800)
<7> [414.933975] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1803)
<7> [414.934156] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1809)
<7> [414.934340] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1906)
<7> [414.934503] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1906)
<7> [414.934662] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 2880)
<7> [414.934820] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 3040)
<7> [414.934996] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 2880)
<7> [414.935158] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 3040)
<7> [414.935303] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2928)
<7> [414.935448] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2960)
<7> [414.935588] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1800)
<7> [414.935725] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1800)
<7> [414.935865] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1803)
<7> [414.936004] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1809)
<7> [414.936133] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1906)
<7> [414.936261] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1906)
<7> [414.936385] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [414.936504] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [414.936621] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [414.936736] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in pipe_bpp (expected 0, found 30)
<7> [414.936852] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 347710)
<7> [414.936962] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 347710)
<7> [414.937072] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in port_clock (expected 0, found 432000)
<7> [414.937180] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:204:pipe C] fastset requirement not met, forcing full modeset
<7> [414.937314] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0x1 -> 0xd (total dbuf slices 0xf), mbus joined? no->no
<7> [414.937411] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:204:pipe C] dbuf slices 0x0 -> 0xc, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x4
<7> [414.937546] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:149:plane 1C] ddb ( 0 - 0) -> ( 0 - 2003), size 0 -> 2003
<7> [414.937638] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:199:cursor C] ddb ( 0 - 0) -> (2003 - 2048), size 0 -> 45
<7> [414.937720] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:149: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> [414.937798] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:149:plane 1C] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 4, 13, 13, 13, 13, 13, 0, 0, 0, 4, 0
<7> [414.937889] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:149:plane 1C] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 92, 297, 297, 297, 297, 297, 0, 0, 197, 92, 197
<7> [414.937966] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:149:plane 1C] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 183, 456, 456, 456, 456, 456, 0, 0, 198, 183, 198
<7> [414.938059] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:204:pipe C] data rate 1390840 num active planes 1
<7> [414.938187] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 1461 qgv_peak_bw: 38400
<7> [414.938305] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 1461 qgv_peak_bw: 38400
<7> [414.938415] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [414.938512] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [414.938606] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 1461
<7> [414.938713] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:204:pipe C] min cdclk: 0 kHz -> 173855 kHz
<7> [414.938817] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] Can change cdclk via squashing
<7> [414.938930] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New cdclk calculated to be logical 192000 kHz, actual 192000 kHz
<7> [414.939034] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New voltage level calculated to be logical 0, actual 0
<7> [414.939149] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:204:pipe C] enable: yes [modeset]
<7> [414.939241] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: EDP (0x100), output format: RGB, sink format: RGB
<7> [414.939326] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: C, pipe bpp: 30, dithering: 0
<7> [414.939409] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [414.939493] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [414.939578] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [414.939660] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [414.939737] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dp m_n: lanes: 4; data_m: 6329869, data_n: 8388608, link_m: 421991, link_n: 524288, tu: 64
<7> [414.939822] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dp m2_n2: lanes: 4; data_m: 0, data_n: 0, link_m: 0, link_n: 0, tu: 0
<7> [414.939935] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: disabled
<7> [414.940032] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled
<7> [414.940126] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] psr: enabled, selective update: disabled, panel replay: disabled, selective fetch: disabled
<7> [414.940220] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] minimum HBlank: 0
<7> [414.940314] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] audio: 0, infoframes: 0, infoframes enabled: 0x4
<7> [414.940405] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] DP SDP: VSC, revision 2, length 8
<7> [414.940482] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pixelformat: RGB
<7> [414.940559] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] colorimetry: sRGB
<7> [414.940628] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] bpc: 0
<7> [414.940703] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dynamic range: VESA range
<7> [414.940785] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] content type: Not defined
<7> [414.940878] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [414.940968] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [414.941058] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: no, fixed rr: yes, vmin: 1906, vmax: 1906, flipline: 1906, pipeline full: 0, guardband: 104 vsync start: 103, vsync end: 97
<7> [414.941148] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: vmin vblank: 1802, vmax vblank: 1802, vmin vtotal: 1906, vmax vtotal: 1906
<7> [414.941230] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] requested mode: "2880x1800": 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa
<7> [414.941305] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] adjusted mode: "2880x1800": 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa
<7> [414.941373] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=347710, hd=2880 hb=2880-3040 hs=2928-2960 ht=3040, vd=1800 vb=1800-1906 vs=1803-1809 vt=1906, flags=0xa
<7> [414.941442] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe mode: "2880x1800": 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x40 0xa
<7> [414.941509] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=347710, hd=2880 hb=2880-3040 hs=2928-2960 ht=3040, vd=1800 vb=1800-1906 vs=1803-1809 vt=1906, flags=0xa
<7> [414.941583] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port clock: 432000, pipe src: 2880x1800+0+0, pixel rate 347710, min cdclk 173855
<7> [414.941661] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] linetime: 70, ips linetime: 0
<7> [414.941740] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [414.941818] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [414.941918] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [414.942013] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dpll_hw_state: dpll: 0x69780, dpll_md: 0x42110, fp0: 0x1a2, fp1: 0x1000000
<7> [414.942106] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [414.942197] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [414.942274] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [414.942342] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [414.942410] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [414.942479] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [414.942547] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [414.942613] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [414.942680] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [414.942747] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [414.942813] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [414.942909] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [414.943000] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [414.943093] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:149:plane 1C] fb: [FB:245] 2880x1800 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [414.943176] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [414.943249] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] src: 2880.000000x1800.000000+0.000000+0.000000 dst: 2880x1800+0+0
<7> [414.943319] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:159:plane 2C] fb: [NOFB], visible: no
<7> [414.943386] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:169:plane 3C] fb: [NOFB], visible: no
<7> [414.943452] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:179:plane 4C] fb: [NOFB], visible: no
<7> [414.943518] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:189:plane 5C] fb: [NOFB], visible: no
<7> [414.943585] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:199:cursor C] fb: [NOFB], visible: no
<7> [414.943970] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_2
<7> [414.944147] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_C
<7> [414.944304] xe 0000:00:02.0: [drm:intel_set_cdclk [xe]] Pre changing CDCLK to 192000 kHz, VCO 614400 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0
<7> [414.944402] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [414.944497] xe 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=480
<7> [414.944585] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:207:DDI A/PHY A]
<7> [414.944653] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:216:DDI TC1/PHY TC1]
<7> [414.944718] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:218:DP-MST A]
<7> [414.944781] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:219:DP-MST B]
<7> [414.944861] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:220:DP-MST C]
<7> [414.944946] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:228:DDI TC2/PHY TC2]
<7> [414.945033] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:230:DP-MST A]
<7> [414.945114] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:231:DP-MST B]
<7> [414.945184] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:232:DP-MST C]
<7> [414.945243] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:236:DDI TC3/PHY TC3]
<7> [414.945301] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:238:DP-MST A]
<7> [414.945360] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:239:DP-MST B]
<7> [414.945422] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:240:DP-MST C]
<7> [414.945483] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xd
<7> [414.945642] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [414.945751] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling AUX_A
<7> [414.946936] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:207:DDI A/PHY A] PPS 0 turn panel power on
<7> [414.947076] xe 0000:00:02.0: [drm:wait_panel_power_cycle [xe]] [ENCODER:207:DDI A/PHY A] PPS 0 wait for panel power cycle (471 ms remaining)
<7> [414.947865] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [414.948049] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:207:DDI A/PHY A] - long
<7> [414.948152] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 10
<7> [414.948268] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:207:DDI A/PHY A]
<7> [415.420719] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:207:DDI A/PHY A] PPS 0 mask: 0xb800000f value: 0x00000000 PP_STATUS: 0x08000001 PP_CONTROL: 0x00000060
<7> [415.461660] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [415.461963] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:207:DDI A/PHY A] PPS 0 wait for panel power on
<7> [415.462215] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:207:DDI A/PHY A] PPS 0 mask: 0xb000000f value: 0x80000008 PP_STATUS: 0x9000000a PP_CONTROL: 0x00000063
<7> [415.498817] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [415.499185] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:207:DDI A/PHY A] - long
<7> [415.499468] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 20
<7> [415.499780] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:207:DDI A/PHY A]
<7> [415.672719] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [415.674865] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:207:DDI A/PHY A] PPS 0 turning VDD on
<7> [415.675228] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:207:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006b
<7> [415.677762] xe 0000:00:02.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX A/DDI A/PHY A: DPCD: 14 1e 44 c1 00 00 01 c0 02 00 02 00 00 0b 80
<7> [415.679066] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:208:eDP-1][ENCODER:207:DDI A/PHY A][DPRX] Reloading eDP link rates
<7> [415.680002] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:208:eDP-1][ENCODER:207:DDI A/PHY A][DPRX] Using LINK_RATE_SET value 05
<7> [415.681208] xe 0000:00:02.0: [drm:intel_dp_set_signal_levels [xe]] [CONNECTOR:208:eDP-1][ENCODER:207:DDI A/PHY A][DPRX] 8b/10b, lanes: 4, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0
<7> [415.682343] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:208:eDP-1][ENCODER:207:DDI A/PHY A][DPRX] Using DP training pattern TPS1
<7> [415.683349] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:208:eDP-1][ENCODER:207:DDI A/PHY A][DPRX] Clock recovery OK
<7> [415.683676] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:208:eDP-1][ENCODER:207:DDI A/PHY A][DPRX] Using DP training pattern TPS4
<7> [415.685041] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:208:eDP-1][ENCODER:207:DDI A/PHY A][DPRX] Channel EQ done. DP Training successful
<7> [415.685396] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:208:eDP-1][ENCODER:207:DDI A/PHY A][DPRX] Link Training passed at link rate = 432000, lane count = 4
<7> [415.686255] xe 0000:00:02.0: [drm:intel_enable_transcoder [xe]] enabling pipe C
<7> [415.686693] xe 0000:00:02.0: [drm:intel_edp_backlight_on [xe]]
<7> [415.687058] xe 0000:00:02.0: [drm:intel_backlight_enable [xe]] pipe C
<7> [415.687692] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:208:eDP-1] set backlight PWM = 4518
<7> [415.688949] xe 0000:00:02.0: [drm:intel_fbc_update [xe]] reserved 14745600 bytes of contiguous stolen space for FBC, limit: 2
<7> [415.689306] xe 0000:00:02.0: [drm:intel_fbc_update [xe]] Enabling FBC on [PLANE:149:plane 1C]
<7> [415.703514] xe 0000:00:02.0: [drm:intel_psr_post_plane_update [xe]] Enabling PSR1
<7> [415.704975] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:208:eDP-1]
<7> [415.705373] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:204:pipe C]
<7> [418.249780] xe 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [xe]] [ENCODER:207:DDI A/PHY A] PPS 0 turning VDD off
<7> [418.250342] xe 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [xe]] [ENCODER:207:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x00000067
<6> [422.592712] [IGT] kms_flip: finished subtest C-eDP1, FAIL
<6> [422.596356] [IGT] kms_flip: finished subtest flip-vs-expired-vblank, FAIL
<7> [422.615988] xe 0000:00:02.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:245] from all active usage due to RMFB ioctl
<7> [422.616104] xe 0000:00:02.0: [drm:atomic_remove_fb] Disabling [PLANE:149:plane 1C] because [FB:245] is removed
<7> [422.616233] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:149:plane 1C] ddb ( 0 - 2003) -> ( 0 - 0), size 2003 -> 0
<7> [422.616648] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:149: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> [422.616945] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:149:plane 1C] lines 4, 13, 13, 13, 13, 13, 0, 0, 0, 4, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [422.617249] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:149:plane 1C] blocks 92, 297, 297, 297, 297, 297, 0, 0, 197, 92, 197 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [422.617562] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:149:plane 1C] min_ddb 183, 456, 456, 456, 456, 456, 0, 0, 198, 183, 198 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [422.617913] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:204:pipe C] data rate 0 num active planes 0
<7> [422.618283] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 0 qgv_peak_bw: 38400
<7> [422.618673] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 0 qgv_peak_bw: 38400
<7> [422.619023] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [422.619387] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [422.619698] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 0
<7> [422.620844] xe 0000:00:02.0: [drm:intel_psr_disable_locked [xe]] Disabling PSR1
<7> [422.626537] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:207:DDI A/PHY A] PPS 0 turning VDD on
<7> [422.626971] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:207:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006f
<7> [422.643096] xe 0000:00:02.0: [drm:__intel_fbc_disable [xe]] Disabling FBC on [PLANE:149:plane 1C]
<7> [422.663202] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CONNECTOR:208:eDP-1] Limiting display bpp to 30 (EDID bpp 30, max requested bpp 36, max platform bpp 36)
<7> [422.663833] xe 0000:00:02.0: [drm:intel_dp_compute_config_limits [xe]] [ENCODER:207:DDI A/PHY A][CRTC:88:pipe A] DP link limits: pixel clock 347710 kHz DSC off max lanes 4 max rate 810000 max pipe_bpp 30 max link_bpp 30.0000
<7> [422.664254] xe 0000:00:02.0: [drm:intel_dp_compute_link_config [xe]] DP lane count 4 clock 432000 bpp input 30 compressed 0.0000 link rate required 1303913 available 1728000
<7> [422.664672] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:88:pipe A] hw max bpp: 30, pipe bpp: 30, dithering: 0
<7> [422.665049] xe 0000:00:02.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:207:DDI A/PHY A] [CRTC:88:pipe A]
<7> [422.665482] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [422.665848] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [422.666184] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [422.666540] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [422.666971] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in dp_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 6329869/8388608 link 421991/524288)
<7> [422.667345] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000100)
<7> [422.667738] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [422.668063] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 2880)
<7> [422.668442] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 3040)
<7> [422.668776] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 2880)
<7> [422.669078] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 3040)
<7> [422.669386] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2928)
<7> [422.669717] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2960)
<7> [422.669983] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1800)
<7> [422.670292] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1800)
<7> [422.670767] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1803)
<7> [422.670993] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1809)
<7> [422.671216] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1906)
<7> [422.671444] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1906)
<7> [422.671650] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 2880)
<7> [422.671878] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 3040)
<7> [422.672084] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 2880)
<7> [422.672299] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 3040)
<7> [422.672605] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2928)
<7> [422.672801] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2960)
<7> [422.672969] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1800)
<7> [422.673129] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1800)
<7> [422.673290] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1803)
<7> [422.673467] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1809)
<7> [422.673619] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1906)
<7> [422.673790] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1906)
<7> [422.673933] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [422.674073] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [422.674210] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [422.674355] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 30)
<7> [422.674490] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 347710)
<7> [422.674628] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 347710)
<7> [422.674764] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in port_clock (expected 0, found 432000)
<7> [422.674894] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:88:pipe A] fastset requirement not met, forcing full modeset
<7> [422.675022] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in hw.enable (expected yes, found no)
<7> [422.675145] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in hw.active (expected yes, found no)
<7> [422.675264] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in cpu_transcoder (expected 2, found -1)
<7> [422.675394] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in lane_count (expected 4, found 0)
<7> [422.675509] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in dp_m_n (expected tu 64 data 6329869/8388608 link 421991/524288, found tu 0, data 0/0 link 0/0)
<7> [422.675624] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in output_types (expected 0x00000100, found 0x00000000)
<7> [422.675733] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [422.675841] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 2880, found 0)
<7> [422.675945] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 3040, found 0)
<7> [422.676047] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 2880, found 0)
<7> [422.676150] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 3040, found 0)
<7> [422.676248] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2928, found 0)
<7> [422.676350] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2960, found 0)
<7> [422.676446] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1800, found 0)
<7> [422.676540] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1800, found 0)
<7> [422.676635] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1803, found 0)
<7> [422.676727] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1809, found 0)
<7> [422.676816] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1906, found 0)
<7> [422.676904] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1906, found 0)
<7> [422.676989] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 2880, found 0)
<7> [422.677073] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 3040, found 0)
<7> [422.677156] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 2880, found 0)
<7> [422.677236] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 3040, found 0)
<7> [422.677318] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2928, found 0)
<7> [422.677422] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2960, found 0)
<7> [422.677514] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1800, found 0)
<7> [422.677607] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1800, found 0)
<7> [422.677691] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1803, found 0)
<7> [422.677772] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1809, found 0)
<7> [422.677848] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1906, found 0)
<7> [422.677924] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1906, found 0)
<7> [422.678000] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [422.678075] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)
<7> [422.678149] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [422.678219] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in pipe_bpp (expected 30, found 0)
<7> [422.678289] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 347710, found 0)
<7> [422.678377] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 347710, found 0)
<7> [422.678468] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:204:pipe C] fastset requirement not met in port_clock (expected 432000, found 0)
<7> [422.678557] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:204:pipe C] fastset requirement not met, forcing full modeset
<7> [422.678693] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xd -> 0xf (total dbuf slices 0xf), mbus joined? no->yes
<7> [422.678762] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:88:pipe A] dbuf slices 0x0 -> 0xf, ddb (0 - 0) -> (0 - 4096), active pipes 0x4 -> 0x1
<7> [422.678833] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:204:pipe C] dbuf slices 0xc -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x4 -> 0x1
<7> [422.678901] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:33:plane 1A] ddb ( 0 - 0) -> ( 0 - 4051), size 0 -> 4051
<7> [422.678970] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:83:cursor A] ddb ( 0 - 0) -> (4051 - 4096), size 0 -> 45
<7> [422.679029] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:33: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> [422.679086] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:33:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 13, 13, 13, 13, 13, 0, 0, 0, 3, 0
<7> [422.679142] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:33:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 24, 313, 313, 313, 313, 313, 0, 0, 38, 73, 87
<7> [422.679197] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:33:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 28, 346, 346, 346, 346, 346, 0, 0, 39, 82, 88
<7> [422.679252] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:199:cursor C] ddb (2003 - 2048) -> ( 0 - 0), size 45 -> 0
<7> [422.679327] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:88:pipe A] data rate 1390840 num active planes 1
<7> [422.679427] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 1461 qgv_peak_bw: 38400
<7> [422.679532] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 1461 qgv_peak_bw: 38400
<7> [422.679634] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [422.679715] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [422.679792] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 1461
<7> [422.679870] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:88:pipe A] min cdclk: 0 kHz -> 173855 kHz
<7> [422.679955] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:204:pipe C] min cdclk: 173855 kHz -> 0 kHz
<7> [422.680071] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:88:pipe A] enable: yes [modeset]
<7> [422.680168] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: EDP (0x100), output format: RGB, sink format: RGB
<7> [422.680266] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: A, pipe bpp: 30, dithering: 0
<7> [422.680374] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [422.680478] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [422.680581] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [422.680668] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [422.680745] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dp m_n: lanes: 4; data_m: 6329869, data_n: 8388608, link_m: 421991, link_n: 524288, tu: 64
<7> [422.680819] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dp m2_n2: lanes: 4; data_m: 0, data_n: 0, link_m: 0, link_n: 0, tu: 0
<7> [422.680893] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: disabled
<7> [422.680966] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled
<7> [422.681038] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] psr: enabled, selective update: enabled, panel replay: disabled, selective fetch: enabled
<7> [422.681109] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] minimum HBlank: 0
<7> [422.681179] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] audio: 0, infoframes: 0, infoframes enabled: 0x4
<7> [422.681251] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] DP SDP: VSC, revision 4, length 14
<7> [422.681328] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pixelformat: RGB
<7> [422.681417] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] colorimetry: sRGB
<7> [422.681509] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] bpc: 0
<7> [422.681600] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dynamic range: VESA range
<7> [422.681688] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] content type: Not defined
<7> [422.681761] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [422.681829] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [422.681898] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: no, fixed rr: yes, vmin: 1906, vmax: 1906, flipline: 1906, pipeline full: 0, guardband: 104 vsync start: 103, vsync end: 97
<7> [422.681967] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: vmin vblank: 1802, vmax vblank: 1802, vmin vtotal: 1906, vmax vtotal: 1906
<7> [422.682035] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] requested mode: "2880x1800": 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa
<7> [422.682105] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] adjusted mode: "2880x1800": 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa
<7> [422.682174] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=347710, hd=2880 hb=2880-3040 hs=2928-2960 ht=3040, vd=1800 vb=1800-1906 vs=1803-1809 vt=1906, flags=0xa
<7> [422.682242] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe mode: "2880x1800": 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x40 0xa
<7> [422.682310] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=347710, hd=2880 hb=2880-3040 hs=2928-2960 ht=3040, vd=1800 vb=1800-1906 vs=1803-1809 vt=1906, flags=0xa
<7> [422.682413] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port clock: 432000, pipe src: 2880x1800+0+0, pixel rate 347710, min cdclk 173855
<7> [422.682494] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] linetime: 70, ips linetime: 0
<7> [422.682572] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [422.682655] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [422.682744] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [422.682815] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dpll_hw_state: dpll: 0x69780, dpll_md: 0x42110, fp0: 0x1a2, fp1: 0x1000000
<7> [422.682886] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [422.682955] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [422.683024] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [422.683093] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [422.683161] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [422.683229] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [422.683297] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [422.683387] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [422.683478] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [422.683565] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [422.683651] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [422.683727] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [422.683793] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [422.683860] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:33:plane 1A] fb: [FB:209] 2880x1800 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [422.683927] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [422.683994] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] src: 2880.000000x1800.000000+0.000000+0.000000 dst: 2880x1800+0+0
<7> [422.684061] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:43:plane 2A] fb: [NOFB], visible: no
<7> [422.684127] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:53:plane 3A] fb: [NOFB], visible: no
<7> [422.684194] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:63:plane 4A] fb: [NOFB], visible: no
<7> [422.684260] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:73:plane 5A] fb: [NOFB], visible: no
<7> [422.684332] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:83:cursor A] fb: [NOFB], visible: no
<7> [422.684415] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:204:pipe C] enable: no [modeset]
<7> [422.684502] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:149:plane 1C] fb: [NOFB], visible: no
<7> [422.684588] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:159:plane 2C] fb: [NOFB], visible: no
<7> [422.684672] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:169:plane 3C] fb: [NOFB], visible: no
<7> [422.684740] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:179:plane 4C] fb: [NOFB], visible: no
<7> [422.684806] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:189:plane 5C] fb: [NOFB], visible: no
<7> [422.684872] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:199:cursor C] fb: [NOFB], visible: no
<7> [422.685008] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_A
<7> [422.685236] xe 0000:00:02.0: [drm:intel_alpm_disable [xe]] Disabling ALPM
<7> [422.685330] xe 0000:00:02.0: [drm:intel_edp_backlight_off [xe]]
<7> [422.894668] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:208:eDP-1] set backlight PWM = 0
<7> [422.895131] xe 0000:00:02.0: [drm:intel_disable_transcoder [xe]] disabling pipe C
<7> [422.916192] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:207:DDI A/PHY A] PPS 0 turn panel power off
<7> [422.916653] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:207:DDI A/PHY A] PPS 0 wait for panel power off time
<7> [422.917079] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:207:DDI A/PHY A] PPS 0 mask: 0xb0000000 value: 0x00000000 PP_STATUS: 0xa0000002 PP_CONTROL: 0x00000060
<7> [422.969480] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [422.970030] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:207:DDI A/PHY A] - short
<7> [422.975398] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [422.975939] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling AUX_A
<7> [422.976523] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:207:DDI A/PHY A]
<7> [422.976537] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:207:DDI A/PHY A]
<7> [422.976877] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:216:DDI TC1/PHY TC1]
<7> [422.977244] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:218:DP-MST A]
<7> [422.977563] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:219:DP-MST B]
<7> [422.977872] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:220:DP-MST C]
<7> [422.978255] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:228:DDI TC2/PHY TC2]
<7> [422.978589] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:230:DP-MST A]
<7> [422.978883] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:231:DP-MST B]
<7> [422.979192] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:232:DP-MST C]
<7> [422.979529] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:236:DDI TC3/PHY TC3]
<7> [422.979849] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:238:DP-MST A]
<7> [422.980139] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:239:DP-MST B]
<7> [422.980415] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:240:DP-MST C]
<7> [422.980722] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xf
<7> [422.981201] xe 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: no -> yes (pipe: *)
<7> [422.981494] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 8 (mbus joined: yes)
<7> [422.981834] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling AUX_A
<7> [422.983289] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:207:DDI A/PHY A] PPS 0 turn panel power on
<7> [422.983699] xe 0000:00:02.0: [drm:wait_panel_power_cycle [xe]] [ENCODER:207:DDI A/PHY A] PPS 0 wait for panel power cycle (493 ms remaining)
<7> [423.007735] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [423.007944] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:207:DDI A/PHY A] - long
<7> [423.008086] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 10
<7> [423.008252] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:207:DDI A/PHY A]
<7> [423.478086] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:207:DDI A/PHY A] PPS 0 mask: 0xb800000f value: 0x00000000 PP_STATUS: 0x08000001 PP_CONTROL: 0x00000060
<7> [423.508084] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [423.508459] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:207:DDI A/PHY A] PPS 0 wait for panel power on
<7> [423.508848] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:207:DDI A/PHY A] PPS 0 mask: 0xb000000f value: 0x80000008 PP_STATUS: 0x9000000a PP_CONTROL: 0x00000063
<7> [423.545299] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [423.545699] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:207:DDI A/PHY A] - long
<7> [423.546010] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 20
<7> [423.546345] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:207:DDI A/PHY A]
<7> [423.718601] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [423.720896] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:207:DDI A/PHY A] PPS 0 turning VDD on
<7> [423.721290] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:207:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006b
<7> [423.723682] xe 0000:00:02.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX A/DDI A/PHY A: DPCD: 14 1e 44 c1 00 00 01 c0 02 00 02 00 00 0b 80
<7> [423.725015] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:208:eDP-1][ENCODER:207:DDI A/PHY A][DPRX] Reloading eDP link rates
<7> [423.725921] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:208:eDP-1][ENCODER:207:DDI A/PHY A][DPRX] Using LINK_RATE_SET value 05
<7> [423.727119] xe 0000:00:02.0: [drm:intel_dp_set_signal_levels [xe]] [CONNECTOR:208:eDP-1][ENCODER:207:DDI A/PHY A][DPRX] 8b/10b, lanes: 4, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0
<7> [423.728297] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:208:eDP-1][ENCODER:207:DDI A/PHY A][DPRX] Using DP training pattern TPS1
<7> [423.729368] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:208:eDP-1][ENCODER:207:DDI A/PHY A][DPRX] Clock recovery OK
<7> [423.729754] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:208:eDP-1][ENCODER:207:DDI A/PHY A][DPRX] Using DP training pattern TPS4
<7> [423.731192] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:208:eDP-1][ENCODER:207:DDI A/PHY A][DPRX] Channel EQ done. DP Training successful
<7> [423.731539] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:208:eDP-1][ENCODER:207:DDI A/PHY A][DPRX] Link Training passed at link rate = 432000, lane count = 4
<7> [423.732415] xe 0000:00:02.0: [drm:intel_enable_transcoder [xe]] enabling pipe A
<7> [423.732887] xe 0000:00:02.0: [drm:intel_edp_backlight_on [xe]]
<7> [423.733222] xe 0000:00:02.0: [drm:intel_backlight_enable [xe]] pipe A
<7> [423.733586] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:208:eDP-1] set backlight PWM = 4518
<7> [423.749780] xe 0000:00:02.0: [drm:intel_psr_post_plane_update [xe]] Enabling PSR2
<7> [423.751468] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:208:eDP-1]
<7> [423.751858] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:88:pipe A]
<7> [423.754068] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_C
<7> [423.754454] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_2
<7> [423.754858] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:204:pipe C]
<7> [423.755761] xe 0000:00:02.0: [drm:drm_client_dev_restore] fbdev: ret=0
<6> [423.756716] [IGT] kms_flip: exiting, ret=98
Created at 2025-11-09 02:19:38