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

Result: Fail

i915_display_info8 igt_runner8 results8.json results8-xe-load.json guc_logs8.tar i915_display_info_post_exec8 serial_data8 boot8 dmesg8

DetailValue
Duration 10.71 seconds
Hostname 
shard-lnl-8
Igt-Version 
IGT-Version: 2.4-g3e5747e53 (x86_64) (Linux: 7.1.0-rc2-lgci-xe-xe-pw-165688v4-debug+ 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:926 run_test_on_crtc_set()
  #2 ../tests/kms_flip.c:1920 run_test()
  #3 ../tests/kms_flip.c:2184 __igt_unique____real_main2105()
  #4 ../tests/kms_flip.c:2105 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest C-eDP1: FAIL (10.705s)
Subtest flip-vs-expired-vblank: FAIL (33.172s)
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../lib/igt_kms.c:2352 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:2378) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931:
(kms_flip:2378) CRITICAL: Failed assertion: end - start < 500
Dynamic subtest C-eDP1 failed.
**** DEBUG ****
(kms_flip:2378) DEBUG: name = flip
last_ts = 105.744553
last_received_ts = 105.743973
last_seq = 444
current_ts = 105.761223
current_received_ts = 105.760658
current_seq = 445
count = 440
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 61us
(kms_flip:2378) DEBUG: name = flip
last_ts = 105.761223
last_received_ts = 105.760658
last_seq = 445
current_ts = 105.777885
current_received_ts = 105.777313
current_seq = 446
count = 441
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 103us
(kms_flip:2378) DEBUG: name = flip
last_ts = 105.777885
last_received_ts = 105.777313
last_seq = 446
current_ts = 105.794548
current_received_ts = 105.793983
current_seq = 447
count = 442
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 82us
(kms_flip:2378) DEBUG: name = flip
last_ts = 105.794548
last_received_ts = 105.793983
last_seq = 447
current_ts = 105.811211
current_received_ts = 105.810623
current_seq = 448
count = 443
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 59us
(kms_flip:2378) DEBUG: name = flip
last_ts = 105.811211
last_received_ts = 105.810623
last_seq = 448
current_ts = 105.827881
current_received_ts = 105.827286
current_seq = 449
count = 444
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 52us
(kms_flip:2378) DEBUG: name = flip
last_ts = 105.827881
last_received_ts = 105.827286
last_seq = 449
current_ts = 105.844543
current_received_ts = 105.843971
current_seq = 450
count = 445
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 82us
(kms_flip:2378) DEBUG: name = flip
last_ts = 105.844543
last_received_ts = 105.843971
last_seq = 450
current_ts = 105.861206
current_received_ts = 105.860634
current_seq = 451
count = 446
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 58us
(kms_flip:2378) DEBUG: name = flip
last_ts = 105.861206
last_received_ts = 105.860634
last_seq = 451
current_ts = 105.877876
current_received_ts = 105.877266
current_seq = 452
count = 447
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 98us
(kms_flip:2378) DEBUG: name = flip
last_ts = 105.877876
last_received_ts = 105.877266
last_seq = 452
current_ts = 105.894531
current_received_ts = 105.893944
current_seq = 453
count = 448
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 82us
(kms_flip:2378) DEBUG: name = flip
last_ts = 105.894531
last_received_ts = 105.893944
last_seq = 453
current_ts = 105.911201
current_received_ts = 105.910637
current_seq = 454
count = 449
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 61us
(kms_flip:2378) DEBUG: name = flip
last_ts = 105.911201
last_received_ts = 105.910637
last_seq = 454
current_ts = 105.927864
current_received_ts = 105.927269
current_seq = 455
count = 450
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 60us
(kms_flip:2378) DEBUG: name = flip
last_ts = 105.927864
last_received_ts = 105.927269
last_seq = 455
current_ts = 105.944527
current_received_ts = 105.943947
current_seq = 456
count = 451
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 77us
(kms_flip:2378) DEBUG: name = flip
last_ts = 105.944527
last_received_ts = 105.943947
last_seq = 456
current_ts = 105.961189
current_received_ts = 105.960625
current_seq = 457
count = 452
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 61us
(kms_flip:2378) DEBUG: name = flip
last_ts = 105.961189
last_received_ts = 105.960625
last_seq = 457
current_ts = 105.977859
current_received_ts = 105.977272
current_seq = 458
count = 453
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 82us
(kms_flip:2378) DEBUG: name = flip
last_ts = 105.977859
last_received_ts = 105.977272
last_seq = 458
current_ts = 105.994522
current_received_ts = 105.993950
current_seq = 459
count = 454
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 71us
(kms_flip:2378) DEBUG: name = flip
last_ts = 105.994522
last_received_ts = 105.993950
last_seq = 459
current_ts = 106.011185
current_received_ts = 106.010567
current_seq = 460
count = 455
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 57us
(kms_flip:2378) DEBUG: name = flip
last_ts = 106.011185
last_received_ts = 106.010567
last_seq = 460
current_ts = 106.027855
current_received_ts = 106.027260
current_seq = 461
count = 456
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 81us
(kms_flip:2378) DEBUG: name = flip
last_ts = 106.027855
last_received_ts = 106.027260
last_seq = 461
current_ts = 106.044510
current_received_ts = 106.043945
current_seq = 462
count = 457
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 167us
(kms_flip:2378) DEBUG: name = flip
last_ts = 106.044510
last_received_ts = 106.043945
last_seq = 462
current_ts = 106.061180
current_received_ts = 106.060616
current_seq = 463
count = 458
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 62us
(kms_flip:2378) DEBUG: name = flip
last_ts = 106.061180
last_received_ts = 106.060616
last_seq = 463
current_ts = 106.077843
current_received_ts = 106.077271
current_seq = 464
count = 459
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 78us
(kms_flip:2378) DEBUG: name = flip
last_ts = 106.077843
last_received_ts = 106.077271
last_seq = 464
current_ts = 106.094505
current_received_ts = 106.093910
current_seq = 465
count = 460
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 60us
(kms_flip:2378) DEBUG: name = flip
last_ts = 106.094505
last_received_ts = 106.093910
last_seq = 465
current_ts = 106.111176
current_received_ts = 106.110619
current_seq = 466
count = 461
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 114us
(kms_flip:2378) DEBUG: name = flip
last_ts = 106.111176
last_received_ts = 106.110619
last_seq = 466
current_ts = 106.127838
current_received_ts = 106.127274
current_seq = 467
count = 462
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 54us
(kms_flip:2378) DEBUG: name = flip
last_ts = 106.127838
last_received_ts = 106.127274
last_seq = 467
current_ts = 106.144501
current_received_ts = 106.143929
current_seq = 468
count = 463
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 88us
(kms_flip:2378) DEBUG: name = flip
last_ts = 106.144501
last_received_ts = 106.143929
last_seq = 468
current_ts = 106.161171
current_received_ts = 106.160614
current_seq = 469
count = 464
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 119us
(kms_flip:2378) DEBUG: name = flip
last_ts = 106.161171
last_received_ts = 106.160614
last_seq = 469
current_ts = 106.177834
current_received_ts = 106.177261
current_seq = 470
count = 465
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 75us
(kms_flip:2378) DEBUG: name = flip
last_ts = 106.177834
last_received_ts = 106.177261
last_seq = 470
current_ts = 106.194496
current_received_ts = 106.193932
current_seq = 471
count = 466
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 141us
(kms_flip:2378) DEBUG: name = flip
last_ts = 106.194496
last_received_ts = 106.193932
last_seq = 471
current_ts = 106.211159
current_received_ts = 106.210594
current_seq = 472
count = 467
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 58us
(kms_flip:2378) DEBUG: name = flip
last_ts = 106.211159
last_received_ts = 106.210594
last_seq = 472
current_ts = 106.227821
current_received_ts = 106.227257
current_seq = 473
count = 468
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 89us
(kms_flip:2378) DEBUG: name = flip
last_ts = 106.227821
last_received_ts = 106.227257
last_seq = 473
current_ts = 106.244492
current_received_ts = 106.243919
current_seq = 474
count = 469
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 150us
(kms_flip:2378) DEBUG: name = flip
last_ts = 106.244492
last_received_ts = 106.243919
last_seq = 474
current_ts = 106.261154
current_received_ts = 106.260605
current_seq = 475
count = 470
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 61us
(kms_flip:2378) DEBUG: name = flip
last_ts = 106.261154
last_received_ts = 106.260605
last_seq = 475
current_ts = 106.277824
current_received_ts = 106.277260
current_seq = 476
count = 471
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 93us
(kms_flip:2378) DEBUG: name = flip
last_ts = 106.277824
last_received_ts = 106.277260
last_seq = 476
current_ts = 106.294487
current_received_ts = 106.293907
current_seq = 477
count = 472
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 59us
(kms_flip:2378) DEBUG: name = flip
last_ts = 106.294487
last_received_ts = 106.293907
last_seq = 477
current_ts = 106.311150
current_received_ts = 106.310570
current_seq = 478
count = 473
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 59us
(kms_flip:2378) DEBUG: name = flip
last_ts = 106.311150
last_received_ts = 106.310570
last_seq = 478
current_ts = 106.327820
current_received_ts = 106.327240
current_seq = 479
count = 474
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 104us
(kms_flip:2378) DEBUG: name = flip
last_ts = 106.327820
last_received_ts = 106.327240
last_seq = 479
current_ts = 106.344475
current_received_ts = 106.343910
current_seq = 480
count = 475
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 59us
(kms_flip:2378) DEBUG: name = flip
last_ts = 106.344475
last_received_ts = 106.343910
last_seq = 480
current_ts = 106.361145
current_received_ts = 106.360580
current_seq = 481
count = 476
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 58us
(kms_flip:2378) DEBUG: name = flip
last_ts = 106.361145
last_received_ts = 106.360580
last_seq = 481
current_ts = 106.377808
current_received_ts = 106.377205
current_seq = 482
count = 477
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 104us
(kms_flip:2378) DEBUG: name = flip
last_ts = 106.377808
last_received_ts = 106.377205
last_seq = 482
current_ts = 106.394470
current_received_ts = 106.393913
current_seq = 483
count = 478
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 133us
(kms_flip:2378) DEBUG: name = flip
last_ts = 106.394470
last_received_ts = 106.393913
last_seq = 483
current_ts = 106.411133
current_received_ts = 106.410583
current_seq = 484
count = 479
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 61us
(kms_flip:2378) DEBUG: name = flip
last_ts = 106.411133
last_received_ts = 106.410583
last_seq = 484
current_ts = 106.427795
current_received_ts = 106.427216
current_seq = 485
count = 480
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 142us
(kms_flip:2378) DEBUG: name = flip
last_ts = 106.427795
last_received_ts = 106.427216
last_seq = 485
current_ts = 106.444458
current_received_ts = 106.443886
current_seq = 486
count = 481
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 151us
(kms_flip:2378) DEBUG: name = flip
last_ts = 106.444458
last_received_ts = 106.443886
last_seq = 486
current_ts = 106.461128
current_received_ts = 106.460564
current_seq = 487
count = 482
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 115us
(kms_flip:2378) DEBUG: name = flip
last_ts = 106.461128
last_received_ts = 106.460564
last_seq = 487
current_ts = 106.477791
current_received_ts = 106.477203
current_seq = 488
count = 483
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 108us
(kms_flip:2378) DEBUG: name = flip
last_ts = 106.477791
last_received_ts = 106.477203
last_seq = 488
current_ts = 106.494461
current_received_ts = 106.493904
current_seq = 489
count = 484
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 61us
(kms_flip:2378) DEBUG: name = flip
last_ts = 106.494461
last_received_ts = 106.493904
last_seq = 489
current_ts = 106.511116
current_received_ts = 106.510536
current_seq = 490
count = 485
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 115us
(kms_flip:2378) DEBUG: name = flip
last_ts = 106.511116
last_received_ts = 106.510536
last_seq = 490
current_ts = 106.527786
current_received_ts = 106.527191
current_seq = 491
count = 486
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 117us
(kms_flip:2378) DEBUG: name = flip
last_ts = 106.527786
last_received_ts = 106.527191
last_seq = 491
current_ts = 106.544449
current_received_ts = 106.543884
current_seq = 492
count = 487
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 64us
(kms_flip:2378) DEBUG: name = flip
last_ts = 106.544449
last_received_ts = 106.543884
last_seq = 492
current_ts = 106.561111
current_received_ts = 106.560555
current_seq = 493
count = 488
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 59us
(kms_flip:2378) DEBUG: name = flip
last_ts = 106.561111
last_received_ts = 106.560555
last_seq = 493
current_ts = 106.577782
current_received_ts = 106.577126
current_seq = 494
count = 489
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 65us
(kms_flip:2378) DEBUG: name = flip
last_ts = 106.577782
last_received_ts = 106.577126
last_seq = 494
current_ts = 106.594444
current_received_ts = 106.593719
current_seq = 495
count = 490
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 47us
(kms_flip:2378) DEBUG: name = flip
last_ts = 106.594444
last_received_ts = 106.593719
last_seq = 495
current_ts = 106.611107
current_received_ts = 106.610489
current_seq = 496
count = 491
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 80us
(kms_flip:2378) DEBUG: name = flip
last_ts = 106.611107
last_received_ts = 106.610489
last_seq = 496
current_ts = 106.627769
current_received_ts = 106.627136
current_seq = 497
count = 492
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 42us
(kms_flip:2378) DEBUG: name = flip
last_ts = 106.627769
last_received_ts = 106.627136
last_seq = 497
current_ts = 106.644440
current_received_ts = 106.643837
current_seq = 498
count = 493
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 102us
(kms_flip:2378) DEBUG: name = flip
last_ts = 106.644440
last_received_ts = 106.643837
last_seq = 498
current_ts = 106.661102
current_received_ts = 106.660538
current_seq = 499
count = 494
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 69us
(kms_flip:2378) DEBUG: name = flip
last_ts = 106.661102
last_received_ts = 106.660538
last_seq = 499
current_ts = 106.677765
current_received_ts = 106.677177
current_seq = 500
count = 495
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 63us
(kms_flip:2378) DEBUG: name = flip
last_ts = 106.677765
last_received_ts = 106.677177
last_seq = 500
current_ts = 106.694427
current_received_ts = 106.693848
current_seq = 501
count = 496
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 62us
(kms_flip:2378) DEBUG: name = flip
last_ts = 106.694427
last_received_ts = 106.693848
last_seq = 501
current_ts = 106.711098
current_received_ts = 106.710518
current_seq = 502
count = 497
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 112us
(kms_flip:2378) DEBUG: name = flip
last_ts = 106.711098
last_received_ts = 106.710518
last_seq = 502
current_ts = 106.727760
current_received_ts = 106.727196
current_seq = 503
count = 498
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 102us
(kms_flip:2378) DEBUG: name = flip
last_ts = 106.727760
last_received_ts = 106.727196
last_seq = 503
current_ts = 106.744423
current_received_ts = 106.743851
current_seq = 504
count = 499
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 67us
(kms_flip:2378) DEBUG: name = flip
last_ts = 106.744423
last_received_ts = 106.743851
last_seq = 504
current_ts = 106.761086
current_received_ts = 106.760521
current_seq = 505
count = 500
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 111us
(kms_flip:2378) DEBUG: name = flip
last_ts = 106.761086
last_received_ts = 106.760521
last_seq = 505
current_ts = 106.777756
current_received_ts = 106.777176
current_seq = 506
count = 501
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 119us
(kms_flip:2378) DEBUG: name = flip
last_ts = 106.777756
last_received_ts = 106.777176
last_seq = 506
current_ts = 106.794418
current_received_ts = 106.793854
current_seq = 507
count = 502
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 66us
(kms_flip:2378) DEBUG: name = flip
last_ts = 106.794418
last_received_ts = 106.793854
last_seq = 507
current_ts = 106.811081
current_received_ts = 106.810493
current_seq = 508
count = 503
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 125us
(kms_flip:2378) DEBUG: name = flip
last_ts = 106.811081
last_received_ts = 106.810493
last_seq = 508
current_ts = 106.827751
current_received_ts = 106.827164
current_seq = 509
count = 504
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 60us
(kms_flip:2378) DEBUG: name = flip
last_ts = 106.827751
last_received_ts = 106.827164
last_seq = 509
current_ts = 106.844414
current_received_ts = 106.843842
current_seq = 510
count = 505
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 136us
(kms_flip:2378) DEBUG: name = flip
last_ts = 106.844414
last_received_ts = 106.843842
last_seq = 510
current_ts = 106.861076
current_received_ts = 106.860497
current_seq = 511
count = 506
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 59us
(kms_flip:2378) DEBUG: name = flip
last_ts = 106.861076
last_received_ts = 106.860497
last_seq = 511
current_ts = 106.877739
current_received_ts = 106.877151
current_seq = 512
count = 507
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 61us
(kms_flip:2378) DEBUG: name = flip
last_ts = 106.877739
last_received_ts = 106.877151
last_seq = 512
current_ts = 106.894402
current_received_ts = 106.893852
current_seq = 513
count = 508
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 61us
(kms_flip:2378) DEBUG: name = flip
last_ts = 106.894402
last_received_ts = 106.893852
last_seq = 513
current_ts = 106.911072
current_received_ts = 106.910454
current_seq = 514
count = 509
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 59us
(kms_flip:2378) DEBUG: name = flip
last_ts = 106.911072
last_received_ts = 106.910454
last_seq = 514
current_ts = 106.927727
current_received_ts = 106.927177
current_seq = 515
count = 510
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 67us
(kms_flip:2378) DEBUG: name = flip
last_ts = 106.927727
last_received_ts = 106.927177
last_seq = 515
current_ts = 106.944405
current_received_ts = 106.943832
current_seq = 516
count = 511
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 59us
(kms_flip:2378) DEBUG: name = flip
last_ts = 106.944405
last_received_ts = 106.943832
last_seq = 516
current_ts = 106.961060
current_received_ts = 106.960411
current_seq = 517
count = 512
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 58us
(kms_flip:2378) DEBUG: name = flip
last_ts = 106.961060
last_received_ts = 106.960411
last_seq = 517
current_ts = 106.977722
current_received_ts = 106.977165
current_seq = 518
count = 513
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 61us
(kms_flip:2378) DEBUG: name = flip
last_ts = 106.977722
last_received_ts = 106.977165
last_seq = 518
current_ts = 106.994392
current_received_ts = 106.993820
current_seq = 519
count = 514
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 61us
(kms_flip:2378) DEBUG: name = flip
last_ts = 106.994392
last_received_ts = 106.993820
last_seq = 519
current_ts = 107.011055
current_received_ts = 107.010475
current_seq = 520
count = 515
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 112us
(kms_flip:2378) DEBUG: name = flip
last_ts = 107.011055
last_received_ts = 107.010475
last_seq = 520
current_ts = 107.027718
current_received_ts = 107.027153
current_seq = 521
count = 516
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 60us
(kms_flip:2378) DEBUG: name = flip
last_ts = 107.027718
last_received_ts = 107.027153
last_seq = 521
current_ts = 107.044380
current_received_ts = 107.043823
current_seq = 522
count = 517
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 59us
(kms_flip:2378) DEBUG: name = flip
last_ts = 107.044380
last_received_ts = 107.043823
last_seq = 522
current_ts = 107.061050
current_received_ts = 107.060440
current_seq = 523
count = 518
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 105us
(kms_flip:2378) DEBUG: name = flip
last_ts = 107.061050
last_received_ts = 107.060440
last_seq = 523
current_ts = 107.077713
current_received_ts = 107.077164
current_seq = 524
count = 519
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 61us
(kms_flip:2378) DEBUG: name = flip
last_ts = 107.077713
last_received_ts = 107.077164
last_seq = 524
current_ts = 107.094376
current_received_ts = 107.093803
current_seq = 525
count = 520
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 61us
(kms_flip:2378) DEBUG: name = flip
last_ts = 107.094376
last_received_ts = 107.093803
last_seq = 525
current_ts = 107.111046
current_received_ts = 107.110405
current_seq = 526
count = 521
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 66us
(kms_flip:2378) DEBUG: name = flip
last_ts = 107.111046
last_received_ts = 107.110405
last_seq = 526
current_ts = 107.127708
current_received_ts = 107.127151
current_seq = 527
count = 522
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 59us
(kms_flip:2378) DEBUG: name = flip
last_ts = 107.127708
last_received_ts = 107.127151
last_seq = 527
current_ts = 107.144371
current_received_ts = 107.143814
current_seq = 528
count = 523
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 61us
(kms_flip:2378) DEBUG: name = flip
last_ts = 107.144371
last_received_ts = 107.143814
last_seq = 528
current_ts = 107.161034
current_received_ts = 107.160454
current_seq = 529
count = 524
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 99us
(kms_flip:2378) DEBUG: name = flip
last_ts = 107.161034
last_received_ts = 107.160454
last_seq = 529
current_ts = 107.177696
current_received_ts = 107.177116
current_seq = 530
count = 525
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 59us
(kms_flip:2378) DEBUG: name = flip
last_ts = 107.177696
last_received_ts = 107.177116
last_seq = 530
current_ts = 107.194366
current_received_ts = 107.193802
current_seq = 531
count = 526
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 62us
(kms_flip:2378) DEBUG: name = flip
last_ts = 107.194366
last_received_ts = 107.193802
last_seq = 531
current_ts = 107.211029
current_received_ts = 107.210442
current_seq = 532
count = 527
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 98us
(kms_flip:2378) DEBUG: name = flip
last_ts = 107.211029
last_received_ts = 107.210442
last_seq = 532
current_ts = 107.227692
current_received_ts = 107.227127
current_seq = 533
count = 528
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 61us
(kms_flip:2378) DEBUG: name = flip
last_ts = 107.227692
last_received_ts = 107.227127
last_seq = 533
current_ts = 107.244354
current_received_ts = 107.243782
current_seq = 534
count = 529
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 42us
(kms_flip:2378) DEBUG: name = flip
last_ts = 107.244354
last_received_ts = 107.243782
last_seq = 534
current_ts = 107.261017
current_received_ts = 107.260422
current_seq = 535
count = 530
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 105us
(kms_flip:2378) DEBUG: name = flip
last_ts = 107.261017
last_received_ts = 107.260422
last_seq = 535
current_ts = 107.277687
current_received_ts = 107.277130
current_seq = 536
count = 531
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 61us
(kms_flip:2378) DEBUG: name = flip
last_ts = 107.277687
last_received_ts = 107.277130
last_seq = 536
current_ts = 107.294350
current_received_ts = 107.293793
current_seq = 537
count = 532
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 61us
(kms_flip:2378) DEBUG: name = flip
last_ts = 107.294350
last_received_ts = 107.293793
last_seq = 537
current_ts = 107.311020
current_received_ts = 107.310387
current_seq = 538
count = 533
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 67us
(kms_flip:2378) DEBUG: name = flip
last_ts = 107.311020
last_received_ts = 107.310387
last_seq = 538
current_ts = 107.327682
current_received_ts = 107.327126
current_seq = 539
count = 534
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 62us
(kms_flip:2378) DEBUG: name = flip
last_ts = 107.327682
last_received_ts = 107.327126
last_seq = 539
current_ts = 107.344345
current_received_ts = 107.343773
current_seq = 540
count = 535
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 61us
(kms_flip:2378) DEBUG: name = flip
last_ts = 107.344345
last_received_ts = 107.343773
last_seq = 540
current_ts = 107.361008
current_received_ts = 107.360458
current_seq = 541
count = 536
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 80us
(kms_flip:2378) DEBUG: name = flip
last_ts = 107.361008
last_received_ts = 107.360458
last_seq = 541
current_ts = 107.377678
current_received_ts = 107.377106
current_seq = 542
count = 537
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 62us
(kms_flip:2378) DEBUG: name = flip
last_ts = 107.377678
last_received_ts = 107.377106
last_seq = 542
current_ts = 107.394333
current_received_ts = 107.393753
current_seq = 543
count = 538
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 63us
(kms_flip:2378) DEBUG: name = flip
last_ts = 107.394333
last_received_ts = 107.393753
last_seq = 543
current_ts = 107.410995
current_received_ts = 107.410423
current_seq = 544
count = 539
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 105us
(kms_flip:2378) DEBUG: name = flip
last_ts = 107.410995
last_received_ts = 107.410423
last_seq = 544
current_ts = 107.427666
current_received_ts = 107.427124
current_seq = 545
count = 540
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 61us
(kms_flip:2378) DEBUG: name = flip
last_ts = 107.427666
last_received_ts = 107.427124
last_seq = 545
current_ts = 107.444328
current_received_ts = 107.443748
current_seq = 546
count = 541
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 62us
(kms_flip:2378) DEBUG: name = flip
last_ts = 107.444328
last_received_ts = 107.443748
last_seq = 546
current_ts = 107.460991
current_received_ts = 107.460426
current_seq = 547
count = 542
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 112us
(kms_flip:2378) DEBUG: name = flip
last_ts = 107.460991
last_received_ts = 107.460426
last_seq = 547
current_ts = 107.477654
current_received_ts = 107.477097
current_seq = 548
count = 543
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 61us
(kms_flip:2378) DEBUG: name = flip
last_ts = 107.477654
last_received_ts = 107.477097
last_seq = 548
current_ts = 107.494324
current_received_ts = 107.493752
current_seq = 549
count = 544
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 64us
(kms_flip:2378) DEBUG: name = flip
last_ts = 107.494324
last_received_ts = 107.493752
last_seq = 549
current_ts = 107.510994
current_received_ts = 107.510414
current_seq = 550
count = 545
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 103us
(kms_flip:2378) DEBUG: name = flip
last_ts = 107.510994
last_received_ts = 107.510414
last_seq = 550
current_ts = 107.527649
current_received_ts = 107.527061
current_seq = 551
count = 546
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 62us
(kms_flip:2378) DEBUG: name = flip
last_ts = 107.527649
last_received_ts = 107.527061
last_seq = 551
current_ts = 107.544312
current_received_ts = 107.543747
current_seq = 552
count = 547
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 62us
(kms_flip:2378) DEBUG: name = flip
last_ts = 107.544312
last_received_ts = 107.543747
last_seq = 552
current_ts = 107.560982
current_received_ts = 107.560410
current_seq = 553
count = 548
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 121us
(kms_flip:2378) DEBUG: name = flip
last_ts = 107.560982
last_received_ts = 107.560410
last_seq = 553
current_ts = 107.577644
current_received_ts = 107.577095
current_seq = 554
count = 549
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 61us
(kms_flip:2378) DEBUG: name = flip
last_ts = 107.577644
last_received_ts = 107.577095
last_seq = 554
current_ts = 107.594307
current_received_ts = 107.593765
current_seq = 555
count = 550
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 61us
(kms_flip:2378) DEBUG: name = flip
last_ts = 107.594307
last_received_ts = 107.593765
last_seq = 555
current_ts = 107.610970
current_received_ts = 107.610397
current_seq = 556
count = 551
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 101us
(kms_flip:2378) DEBUG: name = flip
last_ts = 107.610970
last_received_ts = 107.610397
last_seq = 556
current_ts = 107.627640
current_received_ts = 107.627068
current_seq = 557
count = 552
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 59us
(kms_flip:2378) DEBUG: name = flip
last_ts = 107.627640
last_received_ts = 107.627068
last_seq = 557
current_ts = 107.644302
current_received_ts = 107.643761
current_seq = 558
count = 553
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 127us
(kms_flip:2378) DEBUG: name = flip
last_ts = 107.644302
last_received_ts = 107.643761
last_seq = 558
current_ts = 107.660965
current_received_ts = 107.660385
current_seq = 559
count = 554
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 106us
(kms_flip:2378) DEBUG: name = flip
last_ts = 107.660965
last_received_ts = 107.660385
last_seq = 559
current_ts = 107.677628
current_received_ts = 107.677055
current_seq = 560
count = 555
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 61us
(kms_flip:2378) DEBUG: name = flip
last_ts = 107.677628
last_received_ts = 107.677055
last_seq = 560
current_ts = 107.694298
current_received_ts = 107.693726
current_seq = 561
count = 556
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 61us
(kms_flip:2378) DEBUG: name = flip
last_ts = 107.694298
last_received_ts = 107.693726
last_seq = 561
current_ts = 107.710960
current_received_ts = 107.710335
current_seq = 562
count = 557
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 61us
(kms_flip:2378) DEBUG: name = flip
last_ts = 107.710960
last_received_ts = 107.710335
last_seq = 562
current_ts = 107.727631
current_received_ts = 107.727051
current_seq = 563
count = 558
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 62us
(kms_flip:2378) DEBUG: name = flip
last_ts = 107.727631
last_received_ts = 107.727051
last_seq = 563
current_ts = 107.744286
current_received_ts = 107.743729
current_seq = 564
count = 559
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 119us
(kms_flip:2378) DEBUG: name = flip
last_ts = 107.744286
last_received_ts = 107.743729
last_seq = 564
current_ts = 107.760956
current_received_ts = 107.760353
current_seq = 565
count = 560
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 107us
(kms_flip:2378) DEBUG: name = flip
last_ts = 107.760956
last_received_ts = 107.760353
last_seq = 565
current_ts = 107.777618
current_received_ts = 107.777046
current_seq = 566
count = 561
seq_step = 1
(kms_flip:2378) DEBUG: Vblank took 880us
(kms_flip:2378) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931:
(kms_flip:2378) CRITICAL: Failed assertion: end - start < 500
(kms_flip:2378) igt_core-INFO: Stack trace:
(kms_flip:2378) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:2378) igt_core-INFO:   #1 ../tests/kms_flip.c:926 run_test_on_crtc_set()
(kms_flip:2378) igt_core-INFO:   #2 ../tests/kms_flip.c:1920 run_test()
(kms_flip:2378) igt_core-INFO:   #3 ../tests/kms_flip.c:2184 __igt_unique____real_main2105()
(kms_flip:2378) igt_core-INFO:   #4 ../tests/kms_flip.c:2105 main()
(kms_flip:2378) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:2378) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:2378) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest C-eDP1: FAIL (10.705s)
Subtest flip-vs-expired-vblank: FAIL (33.172s)
(kms_flip:2378) igt_kms-CRITICAL: Test assertion failure function kmstest_set_connector_dpms, file ../lib/igt_kms.c:2352:
(kms_flip:2378) igt_kms-CRITICAL: Failed assertion: found_it
(kms_flip:2378) igt_kms-CRITICAL: Last errno: 9, Bad file descriptor
(kms_flip:2378) igt_kms-CRITICAL: DPMS property not found on 393
Dmesg
<6> [97.416713] [IGT] kms_flip: starting dynamic subtest C-eDP1
<7> [97.419160] xe 0000:00:02.0: [drm:drm_mode_addfb2] [FB:430]
<7> [97.420124] xe 0000:00:02.0: [drm:drm_mode_addfb2] [FB:432]
<7> [97.432707] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [97.433008] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [97.433208] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
<7> [97.503014] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:151:pipe A]
<7> [97.503271] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [97.503648] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [97.504018] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:270:pipe B]
<7> [97.504180] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.enable (expected yes, found no)
<7> [97.504458] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.active (expected yes, found no)
<7> [97.504714] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in cpu_transcoder (expected 1, found -1)
<7> [97.504939] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in lane_count (expected 4, found 0)
<7> [97.505155] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270: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> [97.505359] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in output_types (expected 0x00000100, found 0x00000000)
<7> [97.505572] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [97.505762] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 2880, found 0)
<7> [97.505948] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 3040, found 0)
<7> [97.506128] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 2880, found 0)
<7> [97.506390] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 3040, found 0)
<7> [97.506605] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2928, found 0)
<7> [97.506782] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2960, found 0)
<7> [97.506942] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1800, found 0)
<7> [97.507104] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1800, found 0)
<7> [97.507262] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1803, found 0)
<7> [97.507412] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1809, found 0)
<7> [97.507577] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1906, found 0)
<7> [97.507736] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1906, found 0)
<7> [97.507914] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 2880, found 0)
<7> [97.508090] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 3040, found 0)
<7> [97.508236] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 2880, found 0)
<7> [97.508379] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 3040, found 0)
<7> [97.508541] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2928, found 0)
<7> [97.508768] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2960, found 0)
<7> [97.508903] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1800, found 0)
<7> [97.509037] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1800, found 0)
<7> [97.509196] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1803, found 0)
<7> [97.509328] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1809, found 0)
<7> [97.509454] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1906, found 0)
<7> [97.509585] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1906, found 0)
<7> [97.509709] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [97.509826] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)
<7> [97.509943] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [97.510077] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in pipe_bpp (expected 30, found 0)
<7> [97.510212] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 347710, found 0)
<7> [97.510327] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 347710, found 0)
<7> [97.510437] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in port_clock (expected 432000, found 0)
<7> [97.510551] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:270:pipe B] fastset requirement not met, forcing full modeset
<7> [97.510690] xe 0000:00:02.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:270:pipe B] releasing DPLL 0
<7> [97.510841] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xf -> 0x1 (total dbuf slices 0xf), mbus joined? yes->no
<7> [97.510943] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:270:pipe B] dbuf slices 0xf -> 0x0, ddb (0 - 4096) -> (0 - 0), active pipes 0x2 -> 0x0
<7> [97.511080] xe 0000:00:02.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:264:cursor B] ddb (4051 - 4096) -> ( 0 - 0), size 45 -> 0
<7> [97.511175] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:270:pipe B] min cdclk: 173855 kHz -> 0 kHz
<7> [97.511295] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] Can change cdclk via squashing
<7> [97.511409] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New cdclk calculated to be logical 153600 kHz, actual 153600 kHz
<7> [97.511521] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New voltage level calculated to be logical 0, actual 0
<7> [97.511698] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:270:pipe B] enable: no [modeset]
<7> [97.511824] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:154:plane 1B] fb: [NOFB], visible: no
<7> [97.511935] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:184:plane 2B] fb: [NOFB], visible: no
<7> [97.512040] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:214:plane 3B] fb: [NOFB], visible: no
<7> [97.512144] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:244:plane 4B] fb: [NOFB], visible: no
<7> [97.512245] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:254:plane 5B] fb: [NOFB], visible: no
<7> [97.512343] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:264:cursor B] fb: [NOFB], visible: no
<7> [97.512556] xe 0000:00:02.0: [drm:intel_alpm_disable [xe]] Disabling ALPM
<7> [97.512706] xe 0000:00:02.0: [drm:intel_edp_backlight_off [xe]]
<7> [97.714878] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:393:eDP-1] set backlight PWM = 0
<7> [97.715374] xe 0000:00:02.0: [drm:intel_disable_transcoder [xe]] disabling pipe B
<7> [97.733075] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turn panel power off
<7> [97.733534] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 wait for panel power off time
<7> [97.734043] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 mask: 0xb0000000 value: 0x00000000 PP_STATUS: 0xa0000002 PP_CONTROL: 0x00000060
<7> [97.786481] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [97.786817] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:392:DDI A/PHY A] - short
<7> [97.792716] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [97.793184] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disable DPLL 0 (active 0x2, on? 1) for [CRTC:270:pipe B]
<7> [97.793341] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:392:DDI A/PHY A]
<7> [97.794814] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disabling DPLL 0
<7> [97.795393] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling AUX_A
<7> [97.796008] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:392:DDI A/PHY A]
<7> [97.796441] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:401:DDI TC1/PHY TC1]
<7> [97.796876] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:403:DP-MST A]
<7> [97.797199] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:404:DP-MST B]
<7> [97.797517] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:405:DP-MST C]
<7> [97.797933] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:413:DDI TC2/PHY TC2]
<7> [97.798301] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:415:DP-MST A]
<7> [97.798672] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:416:DP-MST B]
<7> [97.798979] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:417:DP-MST C]
<7> [97.799333] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:421:DDI TC3/PHY TC3]
<7> [97.799668] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:423:DP-MST A]
<7> [97.799958] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:424:DP-MST B]
<7> [97.800241] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:425:DP-MST C]
<7> [97.800561] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:393:eDP-1]
<7> [97.800937] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [97.801225] xe 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: yes -> no (pipe: *)
<7> [97.801500] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0x1
<7> [97.802049] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_B
<7> [97.802449] xe 0000:00:02.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating disabled for pipe_mask=0x2 (CLKGATE_DIS_DSSDSC=0xaa000000)
<7> [97.802922] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_2
<7> [97.803369] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:270:pipe B]
<7> [97.803807] 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> [97.804273] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [97.804593] xe 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=384
<7> [97.805435] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:389:pipe C]
<7> [97.805865] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:389:pipe C]
<7> [97.805936] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:393:eDP-1]
<7> [97.806113] xe 0000:00:02.0: [drm:compute_baseline_pipe_bpp [xe]] [CONNECTOR:393:eDP-1] Limiting target display pipe bpp to 30 (EDID bpp 30, max requested bpp 36, max platform bpp 36)
<7> [97.806492] xe 0000:00:02.0: [drm:intel_dp_compute_config_limits [xe]] [ENCODER:392:DDI A/PHY A][CRTC:389:pipe C] DP link limits: pixel clock 347710 kHz DSC off max lanes 4 max rate 810000 max pipe_bpp 30 min link_bpp 18.0000 max link_bpp 30.0000
<7> [97.806865] xe 0000:00:02.0: [drm:intel_dp_compute_output_format [xe]] DP lane count 4 clock 432000 bpp input 30 compressed 0.0000 HDR no link rate required 1303913 available 1728000
<7> [97.807207] xe 0000:00:02.0: [drm:intel_psr_compute_config [xe]] PSR2 not supported in transcoder C
<7> [97.807469] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:389:pipe C] hw max bpp: 30, pipe bpp: 30, dithering: 0
<7> [97.807804] xe 0000:00:02.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:392:DDI A/PHY A] [CRTC:389:pipe C]
<7> [97.808165] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.enable (expected no, found yes)
<7> [97.808484] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.active (expected no, found yes)
<7> [97.808791] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in cpu_transcoder (expected -1, found 2)
<7> [97.809047] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in lane_count (expected 0, found 4)
<7> [97.809285] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389: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> [97.809562] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in output_types (expected 0x00000000, found 0x00000100)
<7> [97.809784] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [97.810005] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 2880)
<7> [97.810211] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 3040)
<7> [97.810438] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 2880)
<7> [97.810665] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 3040)
<7> [97.810873] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2928)
<7> [97.811048] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2960)
<7> [97.811221] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1800)
<7> [97.811389] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1800)
<7> [97.811563] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1803)
<7> [97.811728] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1809)
<7> [97.811887] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1906)
<7> [97.812037] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1906)
<7> [97.812186] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 2880)
<7> [97.812330] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 3040)
<7> [97.812471] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 2880)
<7> [97.812629] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 3040)
<7> [97.812772] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2928)
<7> [97.812903] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2960)
<7> [97.813039] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1800)
<7> [97.813172] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1800)
<7> [97.813318] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1803)
<7> [97.813464] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1809)
<7> [97.813601] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1906)
<7> [97.813726] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1906)
<7> [97.813845] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [97.813956] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [97.814068] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [97.814179] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in dpll_hw_state
<7> [97.814288] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [97.814399] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] cx0pll_hw_state: lane_count: 0, ssc_enabled: no, use_c10: no, tbt_mode: no
<7> [97.814503] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] c20pll_hw_state:
<7> [97.814615] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] tx[0] = 0x0000, tx[1] = 0x0000, tx[2] = 0x0000
<7> [97.814713] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] cmn[0] = 0x0000, cmn[1] = 0x0000, cmn[2] = 0x0000, cmn[3] = 0x0000
<7> [97.814815] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] mplla[0] = 0x0000
<7> [97.814910] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] mplla[1] = 0x0000
<7> [97.815002] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] mplla[2] = 0x0000
<7> [97.815093] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] mplla[3] = 0x0000
<7> [97.815183] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] mplla[4] = 0x0000
<7> [97.815269] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] mplla[5] = 0x0000
<7> [97.815359] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] mplla[6] = 0x0000
<7> [97.815452] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] mplla[7] = 0x0000
<7> [97.815548] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] mplla[8] = 0x0000
<7> [97.815639] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] mplla[9] = 0x0000
<7> [97.815727] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] mpllb[10] = 0x0000
<7> [97.815821] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] vdr: custom width: 0x00, serdes rate: 0x00, hdmi rate: 0x00
<7> [97.815904] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [97.815986] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] cx0pll_hw_state: lane_count: 4, ssc_enabled: no, use_c10: yes, tbt_mode: no
<7> [97.816067] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] c10pll_hw_state: fracen: no,
<7> [97.816144] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] multiplier: 225, tx_clk_div: 1.
<7> [97.816221] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] c10pll_rawhw_state:
<7> [97.816295] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] tx: 0x10, cmn: 0x21
<7> [97.816367] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] pll[0] = 0x4, pll[1] = 0x0, pll[2] = 0xa2, pll[3] = 0x1
<7> [97.816441] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] pll[4] = 0x0, pll[5] = 0x0, pll[6] = 0x0, pll[7] = 0x0
<7> [97.816513] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] pll[8] = 0x0, pll[9] = 0x1, pll[10] = 0x0, pll[11] = 0x0
<7> [97.816625] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] pll[12] = 0x0, pll[13] = 0x0, pll[14] = 0x0, pll[15] = 0x1
<7> [97.816711] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] pll[16] = 0x85, pll[17] = 0xf, pll[18] = 0xe6, pll[19] = 0x23
<7> [97.816784] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in pipe_bpp (expected 0, found 30)
<7> [97.816855] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 347710)
<7> [97.816926] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 347710)
<7> [97.816997] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in port_clock (expected 0, found 432000)
<7> [97.817069] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:389:pipe C] fastset requirement not met, forcing full modeset
<7> [97.817162] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0x1 -> 0xd (total dbuf slices 0xf), mbus joined? no->no
<7> [97.817229] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:389:pipe C] dbuf slices 0x0 -> 0xc, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x4
<7> [97.817314] xe 0000:00:02.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:273:plane 1C] ddb ( 0 - 0) -> ( 0 - 2003), size 0 -> 2003
<7> [97.817374] xe 0000:00:02.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:383:cursor C] ddb ( 0 - 0) -> (2003 - 2048), size 0 -> 45
<7> [97.817431] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:273: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> [97.817486] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:273: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> [97.817553] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:273: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> [97.817609] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:273: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> [97.817697] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:389:pipe C] data rate 1390840 num active planes 1
<7> [97.817790] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 1461 qgv_peak_bw: 38400
<7> [97.817874] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 1461 qgv_peak_bw: 38400
<7> [97.817957] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [97.818039] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [97.818119] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 1461
<7> [97.818219] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:389:pipe C] min cdclk: 0 kHz -> 173855 kHz
<7> [97.818322] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] Can change cdclk via squashing
<7> [97.818404] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New cdclk calculated to be logical 192000 kHz, actual 192000 kHz
<7> [97.818486] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New voltage level calculated to be logical 0, actual 0
<7> [97.818587] xe 0000:00:02.0: [drm:intel_find_dpll [xe]] [CRTC:389:pipe C] allocated DPLL 0
<7> [97.818671] xe 0000:00:02.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:389:pipe C] reserving DPLL 0
<7> [97.818762] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:389:pipe C] enable: yes [modeset]
<7> [97.818849] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: EDP (0x100), output format: RGB, sink format: RGB
<7> [97.818939] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: C, pipe bpp: 30, dithering: 0
<7> [97.819021] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [97.819101] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [97.819179] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [97.819257] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [97.819334] 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> [97.819411] 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> [97.819488] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: disabled
<7> [97.819568] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled
<7> [97.819652] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] psr: enabled, selective update: disabled, panel replay: disabled, selective fetch: disabled
<7> [97.819751] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] minimum hblank: 0
<7> [97.819831] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] audio: 0, infoframes: 0, infoframes enabled: 0x4
<7> [97.819906] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] DP SDP: VSC, revision 2, length 8
<7> [97.819981] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pixelformat: RGB
<7> [97.820056] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] colorimetry: sRGB
<7> [97.820130] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] bpc: 0
<7> [97.820203] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dynamic range: VESA range
<7> [97.820277] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] content type: Not defined
<7> [97.820350] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [97.820423] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [97.820496] 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> [97.820579] 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> [97.820662] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [97.820737] 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> [97.820810] 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> [97.820883] 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> [97.820933] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [97.820957] 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> [97.821031] 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> [97.821036] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:392:DDI A/PHY A] - long
<7> [97.821103] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port clock: 432000, pixel rate 347710, min cdclk 173855, min voltage level 0
<7> [97.821127] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 10
<7> [97.821180] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] linetime: 70, ips linetime: 0
<7> [97.821261] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0
<7> [97.821346] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe src: 2880x1800+0+0
<7> [97.821330] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:392:DDI A/PHY A]
<7> [97.821431] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [97.821510] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [97.821604] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [97.821690] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cx0pll_hw_state: lane_count: 4, ssc_enabled: no, use_c10: yes, tbt_mode: no
<7> [97.821778] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_hw_state: fracen: no,
<7> [97.821862] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] multiplier: 225, tx_clk_div: 1.
<7> [97.821936] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_rawhw_state:
<7> [97.822009] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] tx: 0x10, cmn: 0x21
<7> [97.822083] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[0] = 0x4, pll[1] = 0x0, pll[2] = 0xa2, pll[3] = 0x1
<7> [97.822157] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[4] = 0x0, pll[5] = 0x0, pll[6] = 0x0, pll[7] = 0x0
<7> [97.822230] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[8] = 0x0, pll[9] = 0x1, pll[10] = 0x0, pll[11] = 0x0
<7> [97.822304] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[12] = 0x0, pll[13] = 0x0, pll[14] = 0x0, pll[15] = 0x1
<7> [97.822378] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[16] = 0x85, pll[17] = 0xf, pll[18] = 0xe6, pll[19] = 0x23
<7> [97.822452] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [97.822525] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [97.822614] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [97.822713] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [97.822796] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [97.822868] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [97.822943] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [97.823014] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [97.823086] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [97.823163] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [97.823243] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [97.823324] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [97.823401] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:273:plane 1C] fb: [FB:430] 2880x1800 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [97.823475] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [97.823556] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] src: 2880.000000x1800.000000+0.000000+0.000000 dst: 2880x1800+0+0
<7> [97.823643] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:303:plane 2C] fb: [NOFB], visible: no
<7> [97.823653] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [97.823739] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:333:plane 3C] fb: [NOFB], visible: no
<7> [97.823831] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:363:plane 4C] fb: [NOFB], visible: no
<7> [97.823829] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [97.823909] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:373:plane 5C] fb: [NOFB], visible: no
<7> [97.823933] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
<7> [97.823987] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:383:cursor C] fb: [NOFB], visible: no
<7> [97.824062] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling always-on
<7> [97.824350] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling always-on
<7> [97.824448] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [97.824697] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [97.824855] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_2
<7> [97.824982] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_C
<7> [97.825121] xe 0000:00:02.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating enabled for pipe_mask=0x4 (CLKGATE_DIS_DSSDSC=0x8a000000)
<7> [97.825217] 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> [97.825312] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [97.825399] xe 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=480
<7> [97.825483] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:392:DDI A/PHY A]
<7> [97.825557] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:401:DDI TC1/PHY TC1]
<7> [97.825641] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:403:DP-MST A]
<7> [97.825713] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:404:DP-MST B]
<7> [97.825783] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:405:DP-MST C]
<7> [97.825847] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:413:DDI TC2/PHY TC2]
<7> [97.825910] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:415:DP-MST A]
<7> [97.825974] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:416:DP-MST B]
<7> [97.826041] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:417:DP-MST C]
<7> [97.826108] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:421:DDI TC3/PHY TC3]
<7> [97.826172] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:423:DP-MST A]
<7> [97.826235] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:424:DP-MST B]
<7> [97.826297] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:425:DP-MST C]
<7> [97.826380] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xd
<7> [97.826545] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [97.826655] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling AUX_A
<7> [97.827687] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enable DPLL 0 (active 0x4, on? 0) for [CRTC:389:pipe C]
<7> [97.827822] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enabling DPLL 0
<7> [97.829825] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turn panel power on
<7> [97.829918] xe 0000:00:02.0: [drm:wait_panel_power_cycle [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 wait for panel power cycle (464 ms remaining)
<7> [98.298865] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 mask: 0xb800000f value: 0x00000000 PP_STATUS: 0x08000001 PP_CONTROL: 0x00000060
<7> [98.319458] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [98.319869] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 wait for panel power on
<7> [98.320191] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 mask: 0xb000000f value: 0x80000008 PP_STATUS: 0x9000000a PP_CONTROL: 0x00000063
<7> [98.356797] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [98.357142] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:392:DDI A/PHY A] - long
<7> [98.357423] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 20
<7> [98.357842] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:392:DDI A/PHY A]
<7> [98.530690] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [98.531058] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turning VDD on
<7> [98.531345] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006b
<7> [98.533353] 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> [98.534874] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Reloading eDP link rates
<7> [98.535681] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Using LINK_RATE_SET value 05
<7> [98.536877] xe 0000:00:02.0: [drm:intel_dp_set_signal_levels [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] 8b/10b, lanes: 4, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0
<7> [98.538354] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Using DP training pattern TPS1
<7> [98.539625] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Clock recovery OK
<7> [98.540088] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Using DP training pattern TPS4
<7> [98.541718] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Channel EQ done. DP Training successful
<7> [98.542173] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Link Training passed at link rate = 432000, lane count = 4
<7> [98.543090] xe 0000:00:02.0: [drm:intel_enable_transcoder [xe]] enabling pipe C
<7> [98.543722] xe 0000:00:02.0: [drm:intel_edp_backlight_on [xe]]
<7> [98.544186] xe 0000:00:02.0: [drm:intel_backlight_enable [xe]] pipe C
<7> [98.544772] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:393:eDP-1] set backlight PWM = 13892
<7> [98.561891] xe 0000:00:02.0: [drm:intel_fbc_update [xe]] reserved 14745600 bytes of contiguous stolen space for FBC, limit: 2
<7> [98.562370] xe 0000:00:02.0: [drm:intel_fbc_update [xe]] Enabling FBC on [PLANE:273:plane 1C]
<7> [98.577348] xe 0000:00:02.0: [drm:intel_psr_post_plane_update [xe]] Enabling PSR1
<7> [98.578850] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:393:eDP-1]
<7> [98.579275] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:389:pipe C]
<7> [101.139539] xe 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turning VDD off
<7> [101.139872] xe 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x00000067
<6> [108.121683] [IGT] kms_flip: finished subtest C-eDP1, FAIL
<6> [108.124090] [IGT] kms_flip: finished subtest flip-vs-expired-vblank, FAIL
<7> [108.145625] xe 0000:00:02.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:432] from all active usage due to RMFB ioctl
<7> [108.146357] xe 0000:00:02.0: [drm:atomic_remove_fb] Disabling [PLANE:273:plane 1C] because [FB:432] is removed
<7> [108.146479] xe 0000:00:02.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:273:plane 1C] ddb ( 0 - 2003) -> ( 0 - 0), size 2003 -> 0
<7> [108.146763] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:273: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> [108.146971] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:273: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> [108.147160] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:273: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> [108.147836] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:273: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> [108.148121] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:389:pipe C] data rate 0 num active planes 0
<7> [108.148442] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 0 qgv_peak_bw: 38400
<7> [108.148720] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 0 qgv_peak_bw: 38400
<7> [108.148978] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [108.149236] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [108.149481] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 0
<7> [108.151044] xe 0000:00:02.0: [drm:intel_psr_disable_locked [xe]] Disabling PSR1
<7> [108.158313] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turning VDD on
<7> [108.158666] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006f
<7> [108.173534] xe 0000:00:02.0: [drm:__intel_fbc_disable [xe]] Disabling FBC on [PLANE:273:plane 1C]
<7> [108.196023] xe 0000:00:02.0: [drm:compute_baseline_pipe_bpp [xe]] [CONNECTOR:393:eDP-1] Limiting target display pipe bpp to 30 (EDID bpp 30, max requested bpp 36, max platform bpp 36)
<7> [108.196405] xe 0000:00:02.0: [drm:intel_dp_compute_config_limits [xe]] [ENCODER:392:DDI A/PHY A][CRTC:151:pipe A] DP link limits: pixel clock 347710 kHz DSC off max lanes 4 max rate 810000 max pipe_bpp 30 min link_bpp 18.0000 max link_bpp 30.0000
<7> [108.196720] xe 0000:00:02.0: [drm:intel_dp_compute_output_format [xe]] DP lane count 4 clock 432000 bpp input 30 compressed 0.0000 HDR no link rate required 1303913 available 1728000
<7> [108.197035] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:151:pipe A] hw max bpp: 30, pipe bpp: 30, dithering: 0
<7> [108.197404] xe 0000:00:02.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:392:DDI A/PHY A] [CRTC:151:pipe A]
<7> [108.197845] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [108.198194] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [108.198508] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [108.198817] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [108.199113] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151: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> [108.199420] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000100)
<7> [108.199750] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [108.200006] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 2880)
<7> [108.200341] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 3040)
<7> [108.200594] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 2880)
<7> [108.200831] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 3040)
<7> [108.201063] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2928)
<7> [108.201309] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2960)
<7> [108.201542] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1800)
<7> [108.201744] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1800)
<7> [108.201946] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1803)
<7> [108.202359] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1809)
<7> [108.202544] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1906)
<7> [108.202722] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1906)
<7> [108.202933] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 2880)
<7> [108.203117] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 3040)
<7> [108.203307] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 2880)
<7> [108.203473] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 3040)
<7> [108.203634] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2928)
<7> [108.203812] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2960)
<7> [108.203970] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1800)
<7> [108.204128] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1800)
<7> [108.204298] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1803)
<7> [108.204448] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1809)
<7> [108.204599] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1906)
<7> [108.204738] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1906)
<7> [108.204875] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [108.205010] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [108.205143] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [108.205400] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 30)
<7> [108.205526] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 347710)
<7> [108.205646] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 347710)
<7> [108.205765] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in port_clock (expected 0, found 432000)
<7> [108.205880] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:151:pipe A] fastset requirement not met, forcing full modeset
<7> [108.205996] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.enable (expected yes, found no)
<7> [108.206111] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.active (expected yes, found no)
<7> [108.206237] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in cpu_transcoder (expected 2, found -1)
<7> [108.206346] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in lane_count (expected 4, found 0)
<7> [108.206452] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389: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> [108.206556] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in output_types (expected 0x00000100, found 0x00000000)
<7> [108.206659] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [108.206759] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 2880, found 0)
<7> [108.206859] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 3040, found 0)
<7> [108.206967] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 2880, found 0)
<7> [108.207081] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 3040, found 0)
<7> [108.207190] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2928, found 0)
<7> [108.207290] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2960, found 0)
<7> [108.207388] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1800, found 0)
<7> [108.207487] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1800, found 0)
<7> [108.207583] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1803, found 0)
<7> [108.207674] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1809, found 0)
<7> [108.207763] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1906, found 0)
<7> [108.207857] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1906, found 0)
<7> [108.207946] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 2880, found 0)
<7> [108.208034] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 3040, found 0)
<7> [108.208118] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 2880, found 0)
<7> [108.208219] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 3040, found 0)
<7> [108.208315] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2928, found 0)
<7> [108.208409] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2960, found 0)
<7> [108.208503] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1800, found 0)
<7> [108.208605] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1800, found 0)
<7> [108.208694] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1803, found 0)
<7> [108.208782] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1809, found 0)
<7> [108.208867] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1906, found 0)
<7> [108.208945] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1906, found 0)
<7> [108.209018] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [108.209094] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)
<7> [108.209178] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [108.209267] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in pipe_bpp (expected 30, found 0)
<7> [108.209360] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 347710, found 0)
<7> [108.209452] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 347710, found 0)
<7> [108.209535] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in port_clock (expected 432000, found 0)
<7> [108.209607] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:389:pipe C] fastset requirement not met, forcing full modeset
<7> [108.209692] xe 0000:00:02.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:389:pipe C] releasing DPLL 0
<7> [108.209843] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xd -> 0xf (total dbuf slices 0xf), mbus joined? no->yes
<7> [108.209910] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:151:pipe A] dbuf slices 0x0 -> 0xf, ddb (0 - 0) -> (0 - 4096), active pipes 0x4 -> 0x1
<7> [108.209976] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:389:pipe C] dbuf slices 0xc -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x4 -> 0x1
<7> [108.210042] xe 0000:00:02.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:35:plane 1A] ddb ( 0 - 0) -> ( 0 - 4051), size 0 -> 4051
<7> [108.210105] xe 0000:00:02.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:145:cursor A] ddb ( 0 - 0) -> (4051 - 4096), size 0 -> 45
<7> [108.210168] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:35: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> [108.210263] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:35: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> [108.210339] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:35: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> [108.210411] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:35: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> [108.210483] xe 0000:00:02.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:383:cursor C] ddb (2003 - 2048) -> ( 0 - 0), size 45 -> 0
<7> [108.210578] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:151:pipe A] data rate 1390840 num active planes 1
<7> [108.210695] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 1461 qgv_peak_bw: 38400
<7> [108.210789] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 1461 qgv_peak_bw: 38400
<7> [108.210875] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [108.210964] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [108.211051] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 1461
<7> [108.211135] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:151:pipe A] min cdclk: 0 kHz -> 173855 kHz
<7> [108.211240] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:389:pipe C] min cdclk: 173855 kHz -> 0 kHz
<7> [108.211389] xe 0000:00:02.0: [drm:intel_find_dpll [xe]] [CRTC:151:pipe A] allocated DPLL 0
<7> [108.211495] xe 0000:00:02.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:151:pipe A] reserving DPLL 0
<7> [108.211599] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:151:pipe A] enable: yes [modeset]
<7> [108.211690] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: EDP (0x100), output format: RGB, sink format: RGB
<7> [108.211781] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: A, pipe bpp: 30, dithering: 0
<7> [108.211869] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [108.211954] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [108.212033] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [108.212112] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [108.212204] 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> [108.212306] 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> [108.212407] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: disabled
<7> [108.212506] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled
<7> [108.212601] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] psr: enabled, selective update: enabled, panel replay: disabled, selective fetch: enabled
<7> [108.212684] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] minimum hblank: 0
<7> [108.212765] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] audio: 0, infoframes: 0, infoframes enabled: 0x4
<7> [108.212845] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] DP SDP: VSC, revision 4, length 14
<7> [108.212919] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pixelformat: RGB
<7> [108.212997] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] colorimetry: sRGB
<7> [108.213077] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] bpc: 0
<7> [108.213157] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dynamic range: VESA range
<7> [108.213263] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] content type: Not defined
<7> [108.213360] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [108.213455] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [108.213551] 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> [108.213641] 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> [108.213714] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [108.213792] 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> [108.213873] 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> [108.213953] 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> [108.214033] 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> [108.214113] 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> [108.214198] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port clock: 432000, pixel rate 347710, min cdclk 173855, min voltage level 0
<7> [108.214294] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] linetime: 70, ips linetime: 0
<7> [108.214393] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0
<7> [108.214493] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe src: 2880x1800+0+0
<7> [108.214589] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [108.214672] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [108.214750] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [108.214831] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cx0pll_hw_state: lane_count: 4, ssc_enabled: no, use_c10: yes, tbt_mode: no
<7> [108.214913] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_hw_state: fracen: no,
<7> [108.214994] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] multiplier: 225, tx_clk_div: 1.
<7> [108.215074] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_rawhw_state:
<7> [108.215149] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] tx: 0x10, cmn: 0x21
<7> [108.215251] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[0] = 0x4, pll[1] = 0x0, pll[2] = 0xa2, pll[3] = 0x1
<7> [108.215348] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[4] = 0x0, pll[5] = 0x0, pll[6] = 0x0, pll[7] = 0x0
<7> [108.215444] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[8] = 0x0, pll[9] = 0x1, pll[10] = 0x0, pll[11] = 0x0
<7> [108.215540] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[12] = 0x0, pll[13] = 0x0, pll[14] = 0x0, pll[15] = 0x1
<7> [108.215638] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[16] = 0x85, pll[17] = 0xf, pll[18] = 0xe6, pll[19] = 0x23
<7> [108.215727] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [108.215807] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [108.215881] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [108.215955] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [108.216035] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [108.216114] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [108.216198] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [108.216294] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [108.216388] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [108.216482] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [108.216574] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [108.216650] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [108.216723] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:35:plane 1A] fb: [FB:394] 2880x1800 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [108.216804] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [108.216882] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] src: 2880.000000x1800.000000+0.000000+0.000000 dst: 2880x1800+0+0
<7> [108.216960] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [108.217035] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [108.217114] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [108.217196] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [108.217293] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:145:cursor A] fb: [NOFB], visible: no
<7> [108.217390] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:389:pipe C] enable: no [modeset]
<7> [108.217488] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:273:plane 1C] fb: [NOFB], visible: no
<7> [108.217583] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:303:plane 2C] fb: [NOFB], visible: no
<7> [108.217663] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:333:plane 3C] fb: [NOFB], visible: no
<7> [108.217743] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:363:plane 4C] fb: [NOFB], visible: no
<7> [108.217823] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:373:plane 5C] fb: [NOFB], visible: no
<7> [108.217903] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:383:cursor C] fb: [NOFB], visible: no
<7> [108.218041] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_A
<7> [108.218202] xe 0000:00:02.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating enabled for pipe_mask=0x1 (CLKGATE_DIS_DSSDSC=0x88000000)
<7> [108.218792] xe 0000:00:02.0: [drm:intel_alpm_disable [xe]] Disabling ALPM
<7> [108.218882] xe 0000:00:02.0: [drm:intel_edp_backlight_off [xe]]
<7> [108.426283] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:393:eDP-1] set backlight PWM = 0
<7> [108.426816] xe 0000:00:02.0: [drm:intel_disable_transcoder [xe]] disabling pipe C
<7> [108.440590] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turn panel power off
<7> [108.440969] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 wait for panel power off time
<7> [108.442324] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 mask: 0xb0000000 value: 0x00000000 PP_STATUS: 0xa0000002 PP_CONTROL: 0x00000060
<7> [108.493516] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [108.493830] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [108.493891] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disable DPLL 0 (active 0x4, on? 1) for [CRTC:389:pipe C]
<7> [108.494120] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:392:DDI A/PHY A] - short
<7> [108.494598] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:392:DDI A/PHY A]
<7> [108.495275] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disabling DPLL 0
<7> [108.496328] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling AUX_A
<7> [108.496680] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:392:DDI A/PHY A]
<7> [108.496942] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:401:DDI TC1/PHY TC1]
<7> [108.497160] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:403:DP-MST A]
<7> [108.497478] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:404:DP-MST B]
<7> [108.497692] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:405:DP-MST C]
<7> [108.497906] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:413:DDI TC2/PHY TC2]
<7> [108.498125] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:415:DP-MST A]
<7> [108.498391] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:416:DP-MST B]
<7> [108.498607] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:417:DP-MST C]
<7> [108.498818] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:421:DDI TC3/PHY TC3]
<7> [108.499091] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:423:DP-MST A]
<7> [108.499351] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:424:DP-MST B]
<7> [108.499597] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:425:DP-MST C]
<7> [108.500012] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xf
<7> [108.500473] xe 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: no -> yes (pipe: *)
<7> [108.500715] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 8 (mbus joined: yes)
<7> [108.501060] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling AUX_A
<7> [108.502308] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:151:pipe A]
<7> [108.502732] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enabling DPLL 0
<7> [108.505197] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turn panel power on
<7> [108.505494] xe 0000:00:02.0: [drm:wait_panel_power_cycle [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 wait for panel power cycle (489 ms remaining)
<7> [108.528418] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [108.528748] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:392:DDI A/PHY A] - long
<7> [108.529061] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 10
<7> [108.529494] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:392:DDI A/PHY A]
<7> [109.002397] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 mask: 0xb800000f value: 0x00000000 PP_STATUS: 0x08000001 PP_CONTROL: 0x00000060
<7> [109.033139] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [109.033699] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 wait for panel power on
<7> [109.034122] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 mask: 0xb000000f value: 0x80000008 PP_STATUS: 0x9000000a PP_CONTROL: 0x00000063
<7> [109.070622] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [109.070973] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:392:DDI A/PHY A] - long
<7> [109.071256] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 20
<7> [109.071888] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:392:DDI A/PHY A]
<7> [109.243036] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [109.243438] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turning VDD on
<7> [109.243702] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006b
<7> [109.245662] 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> [109.247121] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Reloading eDP link rates
<7> [109.247852] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Using LINK_RATE_SET value 05
<7> [109.248898] xe 0000:00:02.0: [drm:intel_dp_set_signal_levels [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] 8b/10b, lanes: 4, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0
<7> [109.250186] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Using DP training pattern TPS1
<7> [109.251238] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Clock recovery OK
<7> [109.251557] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Using DP training pattern TPS4
<7> [109.252972] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Channel EQ done. DP Training successful
<7> [109.253288] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Link Training passed at link rate = 432000, lane count = 4
<7> [109.253950] xe 0000:00:02.0: [drm:intel_enable_transcoder [xe]] enabling pipe A
<7> [109.254412] xe 0000:00:02.0: [drm:intel_edp_backlight_on [xe]]
<7> [109.254723] xe 0000:00:02.0: [drm:intel_backlight_enable [xe]] pipe A
<7> [109.255062] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:393:eDP-1] set backlight PWM = 13892
<7> [109.288203] xe 0000:00:02.0: [drm:intel_psr_post_plane_update [xe]] Enabling PSR2
<7> [109.289768] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:393:eDP-1]
<7> [109.290072] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:151:pipe A]
<7> [109.292812] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_C
<7> [109.293103] xe 0000:00:02.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating disabled for pipe_mask=0x4 (CLKGATE_DIS_DSSDSC=0xa8000000)
<7> [109.293493] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_2
<7> [109.293803] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:389:pipe C]
<7> [109.294606] xe 0000:00:02.0: [drm:drm_client_dev_restore] fbdev: ret=0
<6> [109.295058] [IGT] kms_flip: exiting, ret=98
<7> [109.311275] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [109.311428] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [109.311525] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
Created at 2026-05-08 16:43:20