Results for igt@kms_flip@flip-vs-expired-vblank-interruptible

Result: Fail

i915_display_info19 igt_runner19 results19.json results19-xe-load.json guc_logs19.tar boot19 dmesg19

DetailValue
Duration 32.07 seconds
Hostname 
shard-lnl-7
Igt-Version 
IGT-Version: 2.3-g726612327 (x86_64) (Linux: 6.19.0-rc5-lgci-xe-xe-4370-fb2e525015f1b65b8+ x86_64)
Out 
Using IGT_SRANDOM=1768270036 for randomisation
Opened device: /dev/dri/card0
Using monotonic timestamps
Starting subtest: flip-vs-expired-vblank-interruptible
Starting dynamic subtest: A-eDP1
  2880x1800: 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa 
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../tests/kms_flip.c:923 run_test_on_crtc_set.constprop.0()
  #2 ../tests/kms_flip.c:1889 run_test()
  #3 ../tests/kms_flip.c:2158 __igt_unique____real_main2045()
  #4 ../tests/kms_flip.c:2045 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest A-eDP1: FAIL (9.664s)
Starting dynamic subtest: B-eDP1
  2880x1800: 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa 
Dynamic subtest B-eDP1: SUCCESS (11.170s)
Starting dynamic subtest: C-eDP1
  2880x1800: 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa 
Dynamic subtest C-eDP1: SUCCESS (11.226s)
Subtest flip-vs-expired-vblank-interruptible: FAIL (32.070s)
Err 
Starting subtest: flip-vs-expired-vblank-interruptible
Starting dynamic subtest: A-eDP1
(kms_flip:6461) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:928:
(kms_flip:6461) CRITICAL: Failed assertion: end - start < 500
(kms_flip:6461) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest A-eDP1 failed.
**** DEBUG ****
(kms_flip:6461) DEBUG: Vblank took 31us
(kms_flip:6461) DEBUG: name = flip
last_ts = 242.327805
last_received_ts = 242.327179
last_seq = 2491
current_ts = 242.344467
current_received_ts = 242.344040
current_seq = 2492
count = 382
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 35us
(kms_flip:6461) DEBUG: name = flip
last_ts = 242.344467
last_received_ts = 242.344040
last_seq = 2492
current_ts = 242.361145
current_received_ts = 242.360703
current_seq = 2493
count = 383
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 92us
(kms_flip:6461) DEBUG: name = flip
last_ts = 242.361145
last_received_ts = 242.360703
last_seq = 2493
current_ts = 242.377808
current_received_ts = 242.377319
current_seq = 2494
count = 384
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 33us
(kms_flip:6461) DEBUG: name = flip
last_ts = 242.377808
last_received_ts = 242.377319
last_seq = 2494
current_ts = 242.394485
current_received_ts = 242.393875
current_seq = 2495
count = 385
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 128us
(kms_flip:6461) DEBUG: name = flip
last_ts = 242.394485
last_received_ts = 242.393875
last_seq = 2495
current_ts = 242.411148
current_received_ts = 242.410690
current_seq = 2496
count = 386
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 30us
(kms_flip:6461) DEBUG: name = flip
last_ts = 242.411148
last_received_ts = 242.410690
last_seq = 2496
current_ts = 242.427811
current_received_ts = 242.427185
current_seq = 2497
count = 387
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 89us
(kms_flip:6461) DEBUG: name = flip
last_ts = 242.427811
last_received_ts = 242.427185
last_seq = 2497
current_ts = 242.444473
current_received_ts = 242.444000
current_seq = 2498
count = 388
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 34us
(kms_flip:6461) DEBUG: name = flip
last_ts = 242.444473
last_received_ts = 242.444000
last_seq = 2498
current_ts = 242.461151
current_received_ts = 242.460541
current_seq = 2499
count = 389
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 92us
(kms_flip:6461) DEBUG: name = flip
last_ts = 242.461151
last_received_ts = 242.460541
last_seq = 2499
current_ts = 242.477829
current_received_ts = 242.477371
current_seq = 2500
count = 390
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 29us
(kms_flip:6461) DEBUG: name = flip
last_ts = 242.477829
last_received_ts = 242.477371
last_seq = 2500
current_ts = 242.494492
current_received_ts = 242.494049
current_seq = 2501
count = 391
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 32us
(kms_flip:6461) DEBUG: name = flip
last_ts = 242.494492
last_received_ts = 242.494049
last_seq = 2501
current_ts = 242.511154
current_received_ts = 242.510452
current_seq = 2502
count = 392
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 118us
(kms_flip:6461) DEBUG: name = flip
last_ts = 242.511154
last_received_ts = 242.510452
last_seq = 2502
current_ts = 242.527832
current_received_ts = 242.527359
current_seq = 2503
count = 393
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 37us
(kms_flip:6461) DEBUG: name = flip
last_ts = 242.527832
last_received_ts = 242.527359
last_seq = 2503
current_ts = 242.544495
current_received_ts = 242.543900
current_seq = 2504
count = 394
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 30us
(kms_flip:6461) DEBUG: name = flip
last_ts = 242.544495
last_received_ts = 242.543900
last_seq = 2504
current_ts = 242.561157
current_received_ts = 242.560699
current_seq = 2505
count = 395
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 33us
(kms_flip:6461) DEBUG: name = flip
last_ts = 242.561157
last_received_ts = 242.560699
last_seq = 2505
current_ts = 242.577835
current_received_ts = 242.577362
current_seq = 2506
count = 396
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 37us
(kms_flip:6461) DEBUG: name = flip
last_ts = 242.577835
last_received_ts = 242.577362
last_seq = 2506
current_ts = 242.594498
current_received_ts = 242.594055
current_seq = 2507
count = 397
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 32us
(kms_flip:6461) DEBUG: name = flip
last_ts = 242.594498
last_received_ts = 242.594055
last_seq = 2507
current_ts = 242.611176
current_received_ts = 242.610580
current_seq = 2508
count = 398
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 31us
(kms_flip:6461) DEBUG: name = flip
last_ts = 242.611176
last_received_ts = 242.610580
last_seq = 2508
current_ts = 242.627838
current_received_ts = 242.627396
current_seq = 2509
count = 399
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 86us
(kms_flip:6461) DEBUG: name = flip
last_ts = 242.627838
last_received_ts = 242.627396
last_seq = 2509
current_ts = 242.644516
current_received_ts = 242.643921
current_seq = 2510
count = 400
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 104us
(kms_flip:6461) DEBUG: name = flip
last_ts = 242.644516
last_received_ts = 242.643921
last_seq = 2510
current_ts = 242.661179
current_received_ts = 242.660751
current_seq = 2511
count = 401
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 90us
(kms_flip:6461) DEBUG: name = flip
last_ts = 242.661179
last_received_ts = 242.660751
last_seq = 2511
current_ts = 242.677856
current_received_ts = 242.677414
current_seq = 2512
count = 402
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 92us
(kms_flip:6461) DEBUG: name = flip
last_ts = 242.677856
last_received_ts = 242.677414
last_seq = 2512
current_ts = 242.694519
current_received_ts = 242.694077
current_seq = 2513
count = 403
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 38us
(kms_flip:6461) DEBUG: name = flip
last_ts = 242.694519
last_received_ts = 242.694077
last_seq = 2513
current_ts = 242.711182
current_received_ts = 242.710510
current_seq = 2514
count = 404
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 88us
(kms_flip:6461) DEBUG: name = flip
last_ts = 242.711182
last_received_ts = 242.710510
last_seq = 2514
current_ts = 242.727859
current_received_ts = 242.727417
current_seq = 2515
count = 405
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 29us
(kms_flip:6461) DEBUG: name = flip
last_ts = 242.727859
last_received_ts = 242.727417
last_seq = 2515
current_ts = 242.744522
current_received_ts = 242.743942
current_seq = 2516
count = 406
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 30us
(kms_flip:6461) DEBUG: name = flip
last_ts = 242.744522
last_received_ts = 242.743942
last_seq = 2516
current_ts = 242.761185
current_received_ts = 242.760788
current_seq = 2517
count = 407
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 31us
(kms_flip:6461) DEBUG: name = flip
last_ts = 242.761185
last_received_ts = 242.760788
last_seq = 2517
current_ts = 242.777863
current_received_ts = 242.777420
current_seq = 2518
count = 408
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 89us
(kms_flip:6461) DEBUG: name = flip
last_ts = 242.777863
last_received_ts = 242.777420
last_seq = 2518
current_ts = 242.794540
current_received_ts = 242.794067
current_seq = 2519
count = 409
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 29us
(kms_flip:6461) DEBUG: name = flip
last_ts = 242.794540
last_received_ts = 242.794067
last_seq = 2519
current_ts = 242.811203
current_received_ts = 242.810532
current_seq = 2520
count = 410
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 93us
(kms_flip:6461) DEBUG: name = flip
last_ts = 242.811203
last_received_ts = 242.810532
last_seq = 2520
current_ts = 242.827866
current_received_ts = 242.827469
current_seq = 2521
count = 411
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 32us
(kms_flip:6461) DEBUG: name = flip
last_ts = 242.827866
last_received_ts = 242.827469
last_seq = 2521
current_ts = 242.844543
current_received_ts = 242.844055
current_seq = 2522
count = 412
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 35us
(kms_flip:6461) DEBUG: name = flip
last_ts = 242.844543
last_received_ts = 242.844055
last_seq = 2522
current_ts = 242.861206
current_received_ts = 242.860611
current_seq = 2523
count = 413
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 32us
(kms_flip:6461) DEBUG: name = flip
last_ts = 242.861206
last_received_ts = 242.860611
last_seq = 2523
current_ts = 242.877884
current_received_ts = 242.877258
current_seq = 2524
count = 414
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 32us
(kms_flip:6461) DEBUG: name = flip
last_ts = 242.877884
last_received_ts = 242.877258
last_seq = 2524
current_ts = 242.894547
current_received_ts = 242.893936
current_seq = 2525
count = 415
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 30us
(kms_flip:6461) DEBUG: name = flip
last_ts = 242.894547
last_received_ts = 242.893936
last_seq = 2525
current_ts = 242.911224
current_received_ts = 242.910767
current_seq = 2526
count = 416
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 34us
(kms_flip:6461) DEBUG: name = flip
last_ts = 242.911224
last_received_ts = 242.910767
last_seq = 2526
current_ts = 242.927887
current_received_ts = 242.927444
current_seq = 2527
count = 417
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 31us
(kms_flip:6461) DEBUG: name = flip
last_ts = 242.927887
last_received_ts = 242.927444
last_seq = 2527
current_ts = 242.944550
current_received_ts = 242.944153
current_seq = 2528
count = 418
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 97us
(kms_flip:6461) DEBUG: name = flip
last_ts = 242.944550
last_received_ts = 242.944153
last_seq = 2528
current_ts = 242.961227
current_received_ts = 242.960709
current_seq = 2529
count = 419
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 32us
(kms_flip:6461) DEBUG: name = flip
last_ts = 242.961227
last_received_ts = 242.960709
last_seq = 2529
current_ts = 242.977890
current_received_ts = 242.977432
current_seq = 2530
count = 420
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 31us
(kms_flip:6461) DEBUG: name = flip
last_ts = 242.977890
last_received_ts = 242.977432
last_seq = 2530
current_ts = 242.994553
current_received_ts = 242.994095
current_seq = 2531
count = 421
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 95us
(kms_flip:6461) DEBUG: name = flip
last_ts = 242.994553
last_received_ts = 242.994095
last_seq = 2531
current_ts = 243.011230
current_received_ts = 243.010696
current_seq = 2532
count = 422
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 121us
(kms_flip:6461) DEBUG: name = flip
last_ts = 243.011230
last_received_ts = 243.010696
last_seq = 2532
current_ts = 243.027893
current_received_ts = 243.027466
current_seq = 2533
count = 423
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 31us
(kms_flip:6461) DEBUG: name = flip
last_ts = 243.027893
last_received_ts = 243.027466
last_seq = 2533
current_ts = 243.044571
current_received_ts = 243.044128
current_seq = 2534
count = 424
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 32us
(kms_flip:6461) DEBUG: name = flip
last_ts = 243.044571
last_received_ts = 243.044128
last_seq = 2534
current_ts = 243.061234
current_received_ts = 243.060806
current_seq = 2535
count = 425
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 91us
(kms_flip:6461) DEBUG: name = flip
last_ts = 243.061234
last_received_ts = 243.060806
last_seq = 2535
current_ts = 243.077911
current_received_ts = 243.077469
current_seq = 2536
count = 426
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 30us
(kms_flip:6461) DEBUG: name = flip
last_ts = 243.077911
last_received_ts = 243.077469
last_seq = 2536
current_ts = 243.094574
current_received_ts = 243.094086
current_seq = 2537
count = 427
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 30us
(kms_flip:6461) DEBUG: name = flip
last_ts = 243.094574
last_received_ts = 243.094086
last_seq = 2537
current_ts = 243.111237
current_received_ts = 243.110794
current_seq = 2538
count = 428
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 32us
(kms_flip:6461) DEBUG: name = flip
last_ts = 243.111237
last_received_ts = 243.110794
last_seq = 2538
current_ts = 243.127914
current_received_ts = 243.127472
current_seq = 2539
count = 429
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 31us
(kms_flip:6461) DEBUG: name = flip
last_ts = 243.127914
last_received_ts = 243.127472
last_seq = 2539
current_ts = 243.144577
current_received_ts = 243.144104
current_seq = 2540
count = 430
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 35us
(kms_flip:6461) DEBUG: name = flip
last_ts = 243.144577
last_received_ts = 243.144104
last_seq = 2540
current_ts = 243.161255
current_received_ts = 243.160645
current_seq = 2541
count = 431
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 33us
(kms_flip:6461) DEBUG: name = flip
last_ts = 243.161255
last_received_ts = 243.160645
last_seq = 2541
current_ts = 243.177917
current_received_ts = 243.177460
current_seq = 2542
count = 432
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 31us
(kms_flip:6461) DEBUG: name = flip
last_ts = 243.177917
last_received_ts = 243.177460
last_seq = 2542
current_ts = 243.194595
current_received_ts = 243.193970
current_seq = 2543
count = 433
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 41us
(kms_flip:6461) DEBUG: name = flip
last_ts = 243.194595
last_received_ts = 243.193970
last_seq = 2543
current_ts = 243.211258
current_received_ts = 243.210556
current_seq = 2544
count = 434
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 39us
(kms_flip:6461) DEBUG: name = flip
last_ts = 243.211258
last_received_ts = 243.210556
last_seq = 2544
current_ts = 243.227921
current_received_ts = 243.227417
current_seq = 2545
count = 435
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 31us
(kms_flip:6461) DEBUG: name = flip
last_ts = 243.227921
last_received_ts = 243.227417
last_seq = 2545
current_ts = 243.244598
current_received_ts = 243.244156
current_seq = 2546
count = 436
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 90us
(kms_flip:6461) DEBUG: name = flip
last_ts = 243.244598
last_received_ts = 243.244156
last_seq = 2546
current_ts = 243.261261
current_received_ts = 243.260559
current_seq = 2547
count = 437
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 109us
(kms_flip:6461) DEBUG: name = flip
last_ts = 243.261261
last_received_ts = 243.260559
last_seq = 2547
current_ts = 243.277924
current_received_ts = 243.277466
current_seq = 2548
count = 438
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 87us
(kms_flip:6461) DEBUG: name = flip
last_ts = 243.277924
last_received_ts = 243.277466
last_seq = 2548
current_ts = 243.294601
current_received_ts = 243.293991
current_seq = 2549
count = 439
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 63us
(kms_flip:6461) DEBUG: name = flip
last_ts = 243.294601
last_received_ts = 243.293991
last_seq = 2549
current_ts = 243.311264
current_received_ts = 243.310547
current_seq = 2550
count = 440
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 36us
(kms_flip:6461) DEBUG: name = flip
last_ts = 243.311264
last_received_ts = 243.310547
last_seq = 2550
current_ts = 243.327942
current_received_ts = 243.327499
current_seq = 2551
count = 441
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 30us
(kms_flip:6461) DEBUG: name = flip
last_ts = 243.327942
last_received_ts = 243.327499
last_seq = 2551
current_ts = 243.344604
current_received_ts = 243.344116
current_seq = 2552
count = 442
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 35us
(kms_flip:6461) DEBUG: name = flip
last_ts = 243.344604
last_received_ts = 243.344116
last_seq = 2552
current_ts = 243.361267
current_received_ts = 243.360565
current_seq = 2553
count = 443
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 34us
(kms_flip:6461) DEBUG: name = flip
last_ts = 243.361267
last_received_ts = 243.360565
last_seq = 2553
current_ts = 243.377945
current_received_ts = 243.377457
current_seq = 2554
count = 444
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 32us
(kms_flip:6461) DEBUG: name = flip
last_ts = 243.377945
last_received_ts = 243.377457
last_seq = 2554
current_ts = 243.394608
current_received_ts = 243.394196
current_seq = 2555
count = 445
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 33us
(kms_flip:6461) DEBUG: name = flip
last_ts = 243.394608
last_received_ts = 243.394196
last_seq = 2555
current_ts = 243.411285
current_received_ts = 243.410660
current_seq = 2556
count = 446
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 31us
(kms_flip:6461) DEBUG: name = flip
last_ts = 243.411285
last_received_ts = 243.410660
last_seq = 2556
current_ts = 243.427948
current_received_ts = 243.427490
current_seq = 2557
count = 447
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 31us
(kms_flip:6461) DEBUG: name = flip
last_ts = 243.427948
last_received_ts = 243.427490
last_seq = 2557
current_ts = 243.444611
current_received_ts = 243.444122
current_seq = 2558
count = 448
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 30us
(kms_flip:6461) DEBUG: name = flip
last_ts = 243.444611
last_received_ts = 243.444122
last_seq = 2558
current_ts = 243.461288
current_received_ts = 243.460556
current_seq = 2559
count = 449
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 34us
(kms_flip:6461) DEBUG: name = flip
last_ts = 243.461288
last_received_ts = 243.460556
last_seq = 2559
current_ts = 243.477951
current_received_ts = 243.477509
current_seq = 2560
count = 450
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 30us
(kms_flip:6461) DEBUG: name = flip
last_ts = 243.477951
last_received_ts = 243.477509
last_seq = 2560
current_ts = 243.494629
current_received_ts = 243.494156
current_seq = 2561
count = 451
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 31us
(kms_flip:6461) DEBUG: name = flip
last_ts = 243.494629
last_received_ts = 243.494156
last_seq = 2561
current_ts = 243.511292
current_received_ts = 243.510574
current_seq = 2562
count = 452
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 33us
(kms_flip:6461) DEBUG: name = flip
last_ts = 243.511292
last_received_ts = 243.510574
last_seq = 2562
current_ts = 243.527954
current_received_ts = 243.527496
current_seq = 2563
count = 453
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 32us
(kms_flip:6461) DEBUG: name = flip
last_ts = 243.527954
last_received_ts = 243.527496
last_seq = 2563
current_ts = 243.544632
current_received_ts = 243.543915
current_seq = 2564
count = 454
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 32us
(kms_flip:6461) DEBUG: name = flip
last_ts = 243.544632
last_received_ts = 243.543915
last_seq = 2564
current_ts = 243.561295
current_received_ts = 243.560913
current_seq = 2565
count = 455
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 32us
(kms_flip:6461) DEBUG: name = flip
last_ts = 243.561295
last_received_ts = 243.560913
last_seq = 2565
current_ts = 243.577972
current_received_ts = 243.577499
current_seq = 2566
count = 456
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 33us
(kms_flip:6461) DEBUG: name = flip
last_ts = 243.577972
last_received_ts = 243.577499
last_seq = 2566
current_ts = 243.594635
current_received_ts = 243.594177
current_seq = 2567
count = 457
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 30us
(kms_flip:6461) DEBUG: name = flip
last_ts = 243.594635
last_received_ts = 243.594177
last_seq = 2567
current_ts = 243.611298
current_received_ts = 243.610580
current_seq = 2568
count = 458
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 30us
(kms_flip:6461) DEBUG: name = flip
last_ts = 243.611298
last_received_ts = 243.610580
last_seq = 2568
current_ts = 243.627975
current_received_ts = 243.627487
current_seq = 2569
count = 459
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 32us
(kms_flip:6461) DEBUG: name = flip
last_ts = 243.627975
last_received_ts = 243.627487
last_seq = 2569
current_ts = 243.644638
current_received_ts = 243.644028
current_seq = 2570
count = 460
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 31us
(kms_flip:6461) DEBUG: name = flip
last_ts = 243.644638
last_received_ts = 243.644028
last_seq = 2570
current_ts = 243.661316
current_received_ts = 243.660828
current_seq = 2571
count = 461
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 87us
(kms_flip:6461) DEBUG: name = flip
last_ts = 243.661316
last_received_ts = 243.660828
last_seq = 2571
current_ts = 243.677979
current_received_ts = 243.677551
current_seq = 2572
count = 462
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 85us
(kms_flip:6461) DEBUG: name = flip
last_ts = 243.677979
last_received_ts = 243.677551
last_seq = 2572
current_ts = 243.694641
current_received_ts = 243.694183
current_seq = 2573
count = 463
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 30us
(kms_flip:6461) DEBUG: name = flip
last_ts = 243.694641
last_received_ts = 243.694183
last_seq = 2573
current_ts = 243.711319
current_received_ts = 243.710587
current_seq = 2574
count = 464
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 31us
(kms_flip:6461) DEBUG: name = flip
last_ts = 243.711319
last_received_ts = 243.710587
last_seq = 2574
current_ts = 243.727982
current_received_ts = 243.727539
current_seq = 2575
count = 465
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 30us
(kms_flip:6461) DEBUG: name = flip
last_ts = 243.727982
last_received_ts = 243.727539
last_seq = 2575
current_ts = 243.744659
current_received_ts = 243.744034
current_seq = 2576
count = 466
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 33us
(kms_flip:6461) DEBUG: name = flip
last_ts = 243.744659
last_received_ts = 243.744034
last_seq = 2576
current_ts = 243.761322
current_received_ts = 243.760605
current_seq = 2577
count = 467
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 29us
(kms_flip:6461) DEBUG: name = flip
last_ts = 243.761322
last_received_ts = 243.760605
last_seq = 2577
current_ts = 243.777985
current_received_ts = 243.777557
current_seq = 2578
count = 468
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 30us
(kms_flip:6461) DEBUG: name = flip
last_ts = 243.777985
last_received_ts = 243.777557
last_seq = 2578
current_ts = 243.794662
current_received_ts = 243.793930
current_seq = 2579
count = 469
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 32us
(kms_flip:6461) DEBUG: name = flip
last_ts = 243.794662
last_received_ts = 243.793930
last_seq = 2579
current_ts = 243.811325
current_received_ts = 243.810684
current_seq = 2580
count = 470
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 30us
(kms_flip:6461) DEBUG: name = flip
last_ts = 243.811325
last_received_ts = 243.810684
last_seq = 2580
current_ts = 243.828003
current_received_ts = 243.827560
current_seq = 2581
count = 471
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 28us
(kms_flip:6461) DEBUG: name = flip
last_ts = 243.828003
last_received_ts = 243.827560
last_seq = 2581
current_ts = 243.844666
current_received_ts = 243.844254
current_seq = 2582
count = 472
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 29us
(kms_flip:6461) DEBUG: name = flip
last_ts = 243.844666
last_received_ts = 243.844254
last_seq = 2582
current_ts = 243.861343
current_received_ts = 243.860611
current_seq = 2583
count = 473
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 28us
(kms_flip:6461) DEBUG: name = flip
last_ts = 243.861343
last_received_ts = 243.860611
last_seq = 2583
current_ts = 243.878006
current_received_ts = 243.877563
current_seq = 2584
count = 474
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 86us
(kms_flip:6461) DEBUG: name = flip
last_ts = 243.878006
last_received_ts = 243.877563
last_seq = 2584
current_ts = 243.894669
current_received_ts = 243.894211
current_seq = 2585
count = 475
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 28us
(kms_flip:6461) DEBUG: name = flip
last_ts = 243.894669
last_received_ts = 243.894211
last_seq = 2585
current_ts = 243.911346
current_received_ts = 243.910645
current_seq = 2586
count = 476
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 111us
(kms_flip:6461) DEBUG: name = flip
last_ts = 243.911346
last_received_ts = 243.910645
last_seq = 2586
current_ts = 243.928009
current_received_ts = 243.927582
current_seq = 2587
count = 477
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 87us
(kms_flip:6461) DEBUG: name = flip
last_ts = 243.928009
last_received_ts = 243.927582
last_seq = 2587
current_ts = 243.944687
current_received_ts = 243.944061
current_seq = 2588
count = 478
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 29us
(kms_flip:6461) DEBUG: name = flip
last_ts = 243.944687
last_received_ts = 243.944061
last_seq = 2588
current_ts = 243.961349
current_received_ts = 243.960739
current_seq = 2589
count = 479
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 87us
(kms_flip:6461) DEBUG: name = flip
last_ts = 243.961349
last_received_ts = 243.960739
last_seq = 2589
current_ts = 243.978012
current_received_ts = 243.977554
current_seq = 2590
count = 480
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 32us
(kms_flip:6461) DEBUG: name = flip
last_ts = 243.978012
last_received_ts = 243.977554
last_seq = 2590
current_ts = 243.994690
current_received_ts = 243.994232
current_seq = 2591
count = 481
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 29us
(kms_flip:6461) DEBUG: name = flip
last_ts = 243.994690
last_received_ts = 243.994232
last_seq = 2591
current_ts = 244.011353
current_received_ts = 244.010651
current_seq = 2592
count = 482
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 36us
(kms_flip:6461) DEBUG: name = flip
last_ts = 244.011353
last_received_ts = 244.010651
last_seq = 2592
current_ts = 244.028015
current_received_ts = 244.027252
current_seq = 2593
count = 483
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 87us
(kms_flip:6461) DEBUG: name = flip
last_ts = 244.028015
last_received_ts = 244.027252
last_seq = 2593
current_ts = 244.044693
current_received_ts = 244.043915
current_seq = 2594
count = 484
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 50us
(kms_flip:6461) DEBUG: name = flip
last_ts = 244.044693
last_received_ts = 244.043915
last_seq = 2594
current_ts = 244.061371
current_received_ts = 244.060577
current_seq = 2595
count = 485
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 41us
(kms_flip:6461) DEBUG: name = flip
last_ts = 244.061371
last_received_ts = 244.060577
last_seq = 2595
current_ts = 244.078033
current_received_ts = 244.077225
current_seq = 2596
count = 486
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 52us
(kms_flip:6461) DEBUG: name = flip
last_ts = 244.078033
last_received_ts = 244.077225
last_seq = 2596
current_ts = 244.094696
current_received_ts = 244.093933
current_seq = 2597
count = 487
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 48us
(kms_flip:6461) DEBUG: name = flip
last_ts = 244.094696
last_received_ts = 244.093933
last_seq = 2597
current_ts = 244.111374
current_received_ts = 244.110641
current_seq = 2598
count = 488
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 51us
(kms_flip:6461) DEBUG: name = flip
last_ts = 244.111374
last_received_ts = 244.110641
last_seq = 2598
current_ts = 244.128036
current_received_ts = 244.127289
current_seq = 2599
count = 489
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 123us
(kms_flip:6461) DEBUG: name = flip
last_ts = 244.128036
last_received_ts = 244.127289
last_seq = 2599
current_ts = 244.144699
current_received_ts = 244.143906
current_seq = 2600
count = 490
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 43us
(kms_flip:6461) DEBUG: name = flip
last_ts = 244.144699
last_received_ts = 244.143906
last_seq = 2600
current_ts = 244.161362
current_received_ts = 244.160599
current_seq = 2601
count = 491
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 73us
(kms_flip:6461) DEBUG: name = flip
last_ts = 244.161362
last_received_ts = 244.160599
last_seq = 2601
current_ts = 244.178040
current_received_ts = 244.177216
current_seq = 2602
count = 492
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 68us
(kms_flip:6461) DEBUG: name = flip
last_ts = 244.178040
last_received_ts = 244.177216
last_seq = 2602
current_ts = 244.194702
current_received_ts = 244.193893
current_seq = 2603
count = 493
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 64us
(kms_flip:6461) DEBUG: name = flip
last_ts = 244.194702
last_received_ts = 244.193893
last_seq = 2603
current_ts = 244.211380
current_received_ts = 244.210556
current_seq = 2604
count = 494
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 56us
(kms_flip:6461) DEBUG: name = flip
last_ts = 244.211380
last_received_ts = 244.210556
last_seq = 2604
current_ts = 244.228058
current_received_ts = 244.227219
current_seq = 2605
count = 495
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 55us
(kms_flip:6461) DEBUG: name = flip
last_ts = 244.228058
last_received_ts = 244.227219
last_seq = 2605
current_ts = 244.244705
current_received_ts = 244.243881
current_seq = 2606
count = 496
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 54us
(kms_flip:6461) DEBUG: name = flip
last_ts = 244.244705
last_received_ts = 244.243881
last_seq = 2606
current_ts = 244.261383
current_received_ts = 244.260544
current_seq = 2607
count = 497
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 53us
(kms_flip:6461) DEBUG: name = flip
last_ts = 244.261383
last_received_ts = 244.260544
last_seq = 2607
current_ts = 244.278046
current_received_ts = 244.277222
current_seq = 2608
count = 498
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 53us
(kms_flip:6461) DEBUG: name = flip
last_ts = 244.278046
last_received_ts = 244.277222
last_seq = 2608
current_ts = 244.294724
current_received_ts = 244.293900
current_seq = 2609
count = 499
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 53us
(kms_flip:6461) DEBUG: name = flip
last_ts = 244.294724
last_received_ts = 244.293900
last_seq = 2609
current_ts = 244.311386
current_received_ts = 244.310577
current_seq = 2610
count = 500
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 57us
(kms_flip:6461) DEBUG: name = flip
last_ts = 244.311386
last_received_ts = 244.310577
last_seq = 2610
current_ts = 244.328049
current_received_ts = 244.327240
current_seq = 2611
count = 501
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 55us
(kms_flip:6461) DEBUG: name = flip
last_ts = 244.328049
last_received_ts = 244.327240
last_seq = 2611
current_ts = 244.344727
current_received_ts = 244.343964
current_seq = 2612
count = 502
seq_step = 1
(kms_flip:6461) DEBUG: Vblank took 1381us
(kms_flip:6461) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:928:
(kms_flip:6461) CRITICAL: Failed assertion: end - start < 500
(kms_flip:6461) CRITICAL: Last errno: 4, Interrupted system call
(kms_flip:6461) igt_core-INFO: Stack trace:
(kms_flip:6461) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:6461) igt_core-INFO:   #1 ../tests/kms_flip.c:923 run_test_on_crtc_set.constprop.0()
(kms_flip:6461) igt_core-INFO:   #2 ../tests/kms_flip.c:1889 run_test()
(kms_flip:6461) igt_core-INFO:   #3 ../tests/kms_flip.c:2158 __igt_unique____real_main2045()
(kms_flip:6461) igt_core-INFO:   #4 ../tests/kms_flip.c:2045 main()
(kms_flip:6461) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:6461) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:6461) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest A-eDP1: FAIL (9.664s)
Starting dynamic subtest: B-eDP1
Dynamic subtest B-eDP1: SUCCESS (11.170s)
Starting dynamic subtest: C-eDP1
Dynamic subtest C-eDP1: SUCCESS (11.226s)
Subtest flip-vs-expired-vblank-interruptible: FAIL (32.070s)
Dmesg
<6> [268.517479] Console: switching to colour dummy device 80x25
<6> [268.517729] [IGT] kms_flip: executing
<7> [268.524642] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:400:DP-1]
<7> [268.524657] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:400:DP-1]
<7> [268.524841] xe 0000:00:02.0: [drm:intel_tc_port_reset_mode [xe]] Port D/TC#1: TC port mode reset (disconnected -> tbt-alt) pin assignment: - max lanes: 4
<7> [268.524956] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:400:DP-1] disconnected
<7> [268.525363] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:412:DP-2]
<7> [268.525376] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:412:DP-2]
<7> [268.525550] xe 0000:00:02.0: [drm:intel_tc_port_reset_mode [xe]] Port E/TC#2: TC port mode reset (disconnected -> tbt-alt) pin assignment: - max lanes: 4
<7> [268.525644] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:412:DP-2] disconnected
<7> [268.525922] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:420:DP-3]
<7> [268.525925] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:420:DP-3]
<7> [268.526043] xe 0000:00:02.0: [drm:intel_tc_port_reset_mode [xe]] Port F/TC#3: TC port mode reset (disconnected -> tbt-alt) pin assignment: - max lanes: 4
<7> [268.526130] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:420:DP-3] disconnected
<7> [268.526487] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:391:eDP-1]
<7> [268.526492] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:391:eDP-1]
<7> [268.526589] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 turning VDD on
<7> [268.526696] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006f
<7> [268.527120] xe 0000:00:02.0: [drm:intel_dp_read_dsc_dpcd [xe]] DSC DPCD: 00 11 00 76 0b 01 01 80 00 01 06 66 08 00 00 00
<7> [268.527459] xe 0000:00:02.0: [drm:intel_dp_print_rates [xe]] source rates: 162000, 216000, 243000, 270000, 324000, 432000, 540000, 675000, 810000
<7> [268.527564] xe 0000:00:02.0: [drm:intel_dp_print_rates [xe]] sink rates: 162000, 216000, 243000, 270000, 324000, 432000, 540000, 810000
<7> [268.527665] xe 0000:00:02.0: [drm:intel_dp_print_rates [xe]] common rates: 162000, 216000, 243000, 270000, 324000, 432000, 540000, 810000
<7> [268.527787] xe 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:391:eDP-1] Supported Monitor Refresh rate range is 40 Hz - 120 Hz
<5> [268.527792] [drm] DisplayID checksum invalid, remainder is 248 (ignoring)
<5> [268.527794] [drm] DisplayID checksum invalid, remainder is 248 (ignoring)
<7> [268.527795] xe 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:391:eDP-1] DisplayID extension version 0x13, primary use 0x00
<7> [268.527797] xe 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:391:eDP-1] Assigning EDID-1.4 digital sink color depth as 10 bpc.
<5> [268.527799] [drm] DisplayID checksum invalid, remainder is 248 (ignoring)
<7> [268.527801] xe 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:391:eDP-1] ELD monitor
<5> [268.527803] [drm] DisplayID checksum invalid, remainder is 248 (ignoring)
<7> [268.527804] xe 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:391:eDP-1] ELD size 20, SAD count 0
<5> [268.527806] [drm] DisplayID checksum invalid, remainder is 248 (ignoring)
<7> [268.527821] xe 0000:00:02.0: [drm:intel_dp_set_edid [xe]] [CONNECTOR:391:eDP-1] VRR capable: yes
<7> [268.527913] xe 0000:00:02.0: [drm:intel_dp_set_edid [xe]] [CONNECTOR:391:eDP-1] DFP max bpc 0, max dotclock 0, TMDS clock 0-0, PCON Max FRL BW 0Gbps
<7> [268.528008] xe 0000:00:02.0: [drm:intel_dp_set_edid [xe]] [CONNECTOR:391:eDP-1] RGB->YcbCr conversion? no, YCbCr 4:2:0 allowed? yes, YCbCr 4:4:4->4:2:0 conversion? no
<5> [268.528889] [drm] DisplayID checksum invalid, remainder is 248 (ignoring)
<5> [268.528892] [drm] DisplayID checksum invalid, remainder is 248 (ignoring)
<5> [268.528893] [drm] DisplayID checksum invalid, remainder is 248 (ignoring)
<7> [268.528954] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:391:eDP-1] probed modes:
<7> [268.528958] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2880x1800": 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa
<7> [268.528961] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2880x1800": 120 695310 2880 2928 2960 3040 1800 1803 1809 1906 0x40 0xa
<7> [268.528976] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:400:DP-1]
<7> [268.528979] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:400:DP-1]
<7> [268.529112] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:400:DP-1] disconnected
<7> [268.529123] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:412:DP-2]
<7> [268.529126] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:412:DP-2]
<7> [268.529251] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:412:DP-2] disconnected
<7> [268.529265] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:420:DP-3]
<7> [268.529270] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:420:DP-3]
<7> [268.529430] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:420:DP-3] disconnected
<6> [268.533277] [IGT] kms_flip: starting subtest flip-vs-expired-vblank-interruptible
<6> [268.534622] [IGT] kms_flip: starting dynamic subtest A-eDP1
<7> [268.545929] xe 0000:00:02.0: [drm:drm_mode_addfb2] [FB:427]
<7> [268.548988] xe 0000:00:02.0: [drm:drm_mode_addfb2] [FB:430]
<7> [268.602080] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:149:pipe A]
<7> [268.602179] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [268.602344] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [268.602474] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [268.602574] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [268.602669] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] 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> [268.602765] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in output_types (expected 0x00000100, found 0x00000000)
<7> [268.602859] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [268.602951] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 2880, found 0)
<7> [268.603037] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 3040, found 0)
<7> [268.603120] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 2880, found 0)
<7> [268.603279] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 3040, found 0)
<7> [268.603399] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2928, found 0)
<7> [268.603500] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2960, found 0)
<7> [268.603590] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1800, found 0)
<7> [268.603688] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1800, found 0)
<7> [268.603790] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1803, found 0)
<7> [268.603892] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1809, found 0)
<7> [268.603978] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1906, found 0)
<7> [268.604061] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1906, found 0)
<7> [268.604149] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 2880, found 0)
<7> [268.604264] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 3040, found 0)
<7> [268.604361] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 2880, found 0)
<7> [268.604457] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 3040, found 0)
<7> [268.604538] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2928, found 0)
<7> [268.604625] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2960, found 0)
<7> [268.604721] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1800, found 0)
<7> [268.604814] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1800, found 0)
<7> [268.604909] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1803, found 0)
<7> [268.604989] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1809, found 0)
<7> [268.605067] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1906, found 0)
<7> [268.605138] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1906, found 0)
<7> [268.605239] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [268.605333] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)
<7> [268.605427] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [268.605513] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in pipe_bpp (expected 30, found 0)
<7> [268.605590] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 347710, found 0)
<7> [268.605667] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 347710, found 0)
<7> [268.605743] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in port_clock (expected 432000, found 0)
<7> [268.605821] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:149:pipe A] fastset requirement not met, forcing full modeset
<7> [268.605899] xe 0000:00:02.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:149:pipe A] releasing DPLL 0
<7> [268.606034] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xf -> 0x1 (total dbuf slices 0xf), mbus joined? yes->no
<7> [268.606096] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:149:pipe A] dbuf slices 0xf -> 0x0, ddb (0 - 4096) -> (0 - 0), active pipes 0x1 -> 0x0
<7> [268.606192] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:33:plane 1A] ddb ( 0 - 4051) -> ( 0 - 0), size 4051 -> 0
<7> [268.606268] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:143:cursor A] ddb (4051 - 4096) -> ( 0 - 0), size 45 -> 0
<7> [268.606349] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:33:plane 1A] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [268.606421] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:33:plane 1A] lines 1, 13, 13, 13, 13, 13, 0, 0, 0, 3, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [268.606485] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:33:plane 1A] blocks 24, 313, 313, 313, 313, 313, 0, 0, 38, 73, 87 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [268.606541] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:33:plane 1A] min_ddb 28, 346, 346, 346, 346, 346, 0, 0, 39, 82, 88 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [268.606621] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:149:pipe A] data rate 0 num active planes 0
<7> [268.606729] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 0 qgv_peak_bw: 38400
<7> [268.606834] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 0 qgv_peak_bw: 38400
<7> [268.606932] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [268.607016] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [268.607097] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 0
<7> [268.607184] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:149:pipe A] min cdclk: 173855 kHz -> 0 kHz
<7> [268.607308] xe 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [xe]] dbuf bandwidth min cdclk: 21732 kHz -> 0 kHz
<7> [268.607423] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] Can change cdclk via squashing
<7> [268.607539] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New cdclk calculated to be logical 153600 kHz, actual 153600 kHz
<7> [268.607645] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New voltage level calculated to be logical 0, actual 0
<7> [268.607763] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:149:pipe A] enable: no [modeset]
<7> [268.607850] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:33:plane 1A] fb: [NOFB], visible: no
<7> [268.607935] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:63:plane 2A] fb: [NOFB], visible: no
<7> [268.608020] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:93:plane 3A] fb: [NOFB], visible: no
<7> [268.608100] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:123:plane 4A] fb: [NOFB], visible: no
<7> [268.608334] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:133:plane 5A] fb: [NOFB], visible: no
<7> [268.608453] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:143:cursor A] fb: [NOFB], visible: no
<7> [268.608793] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [268.608894] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [268.609042] xe 0000:00:02.0: [drm:intel_psr_disable_locked [xe]] Disabling PSR2
<7> [268.609743] xe 0000:00:02.0: [drm:intel_alpm_disable [xe]] Disabling ALPM
<7> [268.609865] xe 0000:00:02.0: [drm:intel_edp_backlight_off [xe]]
<7> [268.816260] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:391:eDP-1] set backlight PWM = 0
<7> [268.816813] xe 0000:00:02.0: [drm:intel_disable_transcoder [xe]] disabling pipe A
<7> [268.827561] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 turn panel power off
<7> [268.827790] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 wait for panel power off time
<7> [268.828019] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 mask: 0xb0000000 value: 0x00000000 PP_STATUS: 0xa0000002 PP_CONTROL: 0x00000060
<7> [268.880169] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [268.880533] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:390:DDI A/PHY A] - short
<7> [268.889081] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [268.889517] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disable DPLL 0 (active 0x1, on? 1) for [CRTC:149:pipe A]
<7> [268.889559] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:390:DDI A/PHY A]
<7> [268.890826] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disabling DPLL 0
<7> [268.891320] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling AUX_A
<7> [268.891820] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:390:DDI A/PHY A]
<7> [268.892153] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:399:DDI TC1/PHY TC1]
<7> [268.892434] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:401:DP-MST A]
<7> [268.892702] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:402:DP-MST B]
<7> [268.892961] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:403:DP-MST C]
<7> [268.893275] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:411:DDI TC2/PHY TC2]
<7> [268.893531] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:413:DP-MST A]
<7> [268.893808] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:414:DP-MST B]
<7> [268.894081] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:415:DP-MST C]
<7> [268.894336] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:419:DDI TC3/PHY TC3]
<7> [268.894586] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:421:DP-MST A]
<7> [268.894832] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:422:DP-MST B]
<7> [268.895116] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:423:DP-MST C]
<7> [268.895374] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:391:eDP-1]
<7> [268.895712] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [268.895961] xe 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: yes -> no (pipe: *)
<7> [268.896255] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0x1
<7> [268.896756] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_A
<7> [268.897195] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:149:pipe A]
<7> [268.897485] 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> [268.897888] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [268.898212] xe 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=384
<7> [268.899077] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:268:pipe B]
<7> [268.899446] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:387:pipe C]
<7> [268.899745] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:149:pipe A]
<7> [268.899818] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:391:eDP-1]
<7> [268.899999] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CONNECTOR:391:eDP-1] Limiting display bpp to 30 (EDID bpp 30, max requested bpp 36, max platform bpp 36)
<7> [268.900429] xe 0000:00:02.0: [drm:intel_dp_compute_config_limits [xe]] [ENCODER:390:DDI A/PHY A][CRTC:149: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> [268.900799] xe 0000:00:02.0: [drm:intel_dp_compute_link_config [xe]] DP lane count 4 clock 432000 bpp input 30 compressed 0.0000 link rate required 1303913 available 1728000
<7> [268.901162] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:149:pipe A] hw max bpp: 30, pipe bpp: 30, dithering: 0
<7> [268.901521] xe 0000:00:02.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:390:DDI A/PHY A] [CRTC:149:pipe A]
<7> [268.901869] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [268.902242] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [268.902549] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [268.902848] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [268.903140] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149: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> [268.903414] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000100)
<7> [268.903700] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [268.903946] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 2880)
<7> [268.904217] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 3040)
<7> [268.904448] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 2880)
<7> [268.904673] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 3040)
<7> [268.904888] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2928)
<7> [268.905126] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2960)
<7> [268.905340] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1800)
<7> [268.905535] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1800)
<7> [268.905730] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1803)
<7> [268.905916] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1809)
<7> [268.906122] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1906)
<7> [268.906307] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1906)
<7> [268.906478] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 2880)
<7> [268.906671] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 3040)
<7> [268.906856] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 2880)
<7> [268.907039] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 3040)
<7> [268.907195] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2928)
<7> [268.907347] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2960)
<7> [268.907495] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1800)
<7> [268.907645] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1800)
<7> [268.907815] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1803)
<7> [268.907983] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1809)
<7> [268.908142] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1906)
<7> [268.908305] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1906)
<7> [268.908442] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [268.908570] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [268.908695] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [268.908821] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 30)
<7> [268.908941] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 347710)
<7> [268.909073] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 347710)
<7> [268.909189] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in port_clock (expected 0, found 432000)
<7> [268.909307] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:149:pipe A] fastset requirement not met, forcing full modeset
<7> [268.909466] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0x1 -> 0xf (total dbuf slices 0xf), mbus joined? no->yes
<7> [268.909568] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:149:pipe A] dbuf slices 0x0 -> 0xf, ddb (0 - 0) -> (0 - 4096), active pipes 0x0 -> 0x1
<7> [268.909699] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:33:plane 1A] ddb ( 0 - 0) -> ( 0 - 4051), size 0 -> 4051
<7> [268.909790] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:143:cursor A] ddb ( 0 - 0) -> (4051 - 4096), size 0 -> 45
<7> [268.909874] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:33:plane 1A] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm
<7> [268.909956] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:33:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 4, 13, 13, 13, 13, 13, 0, 0, 0, 4, 0
<7> [268.910044] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:33:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 92, 297, 297, 297, 297, 297, 0, 0, 197, 92, 197
<7> [268.910123] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:33:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 183, 456, 456, 456, 456, 456, 0, 0, 198, 183, 198
<7> [268.910228] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:149:pipe A] data rate 1390840 num active planes 1
<7> [268.910363] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 1461 qgv_peak_bw: 38400
<7> [268.910490] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 1461 qgv_peak_bw: 38400
<7> [268.910601] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [268.910705] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [268.910805] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 1461
<7> [268.910903] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:149:pipe A] min cdclk: 0 kHz -> 173855 kHz
<7> [268.911021] xe 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [xe]] dbuf bandwidth min cdclk: 0 kHz -> 21732 kHz
<7> [268.911128] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] Can change cdclk via squashing
<7> [268.911236] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New cdclk calculated to be logical 192000 kHz, actual 192000 kHz
<7> [268.911339] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New voltage level calculated to be logical 0, actual 0
<7> [268.911444] xe 0000:00:02.0: [drm:intel_find_dpll [xe]] [CRTC:149:pipe A] allocated DPLL 0
<7> [268.911535] xe 0000:00:02.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:149:pipe A] reserving DPLL 0
<7> [268.911622] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:149:pipe A] enable: yes [modeset]
<7> [268.911710] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: EDP (0x100), output format: RGB, sink format: RGB
<7> [268.911796] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: A, pipe bpp: 30, dithering: 0
<7> [268.911882] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [268.911964] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [268.912057] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [268.912141] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [268.912223] 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> [268.912304] 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> [268.912379] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: disabled
<7> [268.912451] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled
<7> [268.912523] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] psr: enabled, selective update: enabled, panel replay: disabled, selective fetch: enabled
<7> [268.912598] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] minimum HBlank: 0
<7> [268.912675] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] audio: 0, infoframes: 0, infoframes enabled: 0x4
<7> [268.912754] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] DP SDP: VSC, revision 4, length 14
<7> [268.912832] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pixelformat: RGB
<7> [268.912910] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] colorimetry: sRGB
<7> [268.912987] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] bpc: 0
<7> [268.913086] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dynamic range: VESA range
<7> [268.913179] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] content type: Not defined
<7> [268.913276] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [268.913359] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [268.913428] 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> [268.913498] 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> [268.913567] 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> [268.913639] 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> [268.913716] 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> [268.913793] 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> [268.913872] 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> [268.913951] 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> [268.914034] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port clock: 432000, pipe src: 2880x1800+0+0, pixel rate 347710, min cdclk 173855
<7> [268.914113] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] linetime: 70, ips linetime: 0
<7> [268.914190] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [268.914266] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [268.914343] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [268.914419] 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> [268.914495] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_hw_state: clock: 432000, fracen: no,
<7> [268.914571] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] multiplier: 225, tx_clk_div: 1.
<7> [268.914647] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_rawhw_state:
<7> [268.914727] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] tx: 0x10, cmn: 0x21
<7> [268.914808] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[0] = 0x4, pll[1] = 0x0, pll[2] = 0xa2, pll[3] = 0x1
<7> [268.914883] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[4] = 0x0, pll[5] = 0x0, pll[6] = 0x0, pll[7] = 0x0
<7> [268.914959] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[8] = 0x0, pll[9] = 0x1, pll[10] = 0x0, pll[11] = 0x0
<7> [268.915038] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[12] = 0x0, pll[13] = 0x0, pll[14] = 0x0, pll[15] = 0x1
<7> [268.915117] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[16] = 0x85, pll[17] = 0xf, pll[18] = 0xe6, pll[19] = 0x23
<7> [268.915215] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [268.915317] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [268.915415] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [268.915498] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [268.915578] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [268.915652] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [268.915726] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [268.915801] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [268.915881] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [268.915965] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [268.916053] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [268.916128] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [268.916201] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [268.916278] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:33:plane 1A] fb: [FB:427] 2880x1800 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [268.916358] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [268.916436] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] src: 2880.000000x1800.000000+0.000000+0.000000 dst: 2880x1800+0+0
<7> [268.916515] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:63:plane 2A] fb: [NOFB], visible: no
<7> [268.916592] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:93:plane 3A] fb: [NOFB], visible: no
<7> [268.916669] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:123:plane 4A] fb: [NOFB], visible: no
<7> [268.916746] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:133:plane 5A] fb: [NOFB], visible: no
<7> [268.916822] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:143:cursor A] fb: [NOFB], visible: no
<7> [268.917243] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_A
<7> [268.917408] 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> [268.917502] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [268.917592] xe 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=480
<7> [268.917676] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:390:DDI A/PHY A]
<7> [268.917748] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:399:DDI TC1/PHY TC1]
<7> [268.917817] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:401:DP-MST A]
<7> [268.917881] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:402:DP-MST B]
<7> [268.917944] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:403:DP-MST C]
<7> [268.918006] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:411:DDI TC2/PHY TC2]
<7> [268.918085] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:413:DP-MST A]
<7> [268.918153] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:414:DP-MST B]
<7> [268.918218] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:415:DP-MST C]
<7> [268.918283] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:419:DDI TC3/PHY TC3]
<7> [268.918347] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:421:DP-MST A]
<7> [268.918410] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:422:DP-MST B]
<7> [268.918473] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:423:DP-MST C]
<7> [268.918556] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xf
<7> [268.918717] xe 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: no -> yes (pipe: *)
<7> [268.918781] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 8 (mbus joined: yes)
<7> [268.918889] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling AUX_A
<7> [268.920016] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:149:pipe A]
<7> [268.920109] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enabling DPLL 0
<7> [268.920846] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [268.920975] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:390:DDI A/PHY A] - long
<7> [268.921064] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 10
<7> [268.921156] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:390:DDI A/PHY A]
<7> [268.921793] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 turn panel power on
<7> [268.921869] xe 0000:00:02.0: [drm:wait_panel_power_cycle [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 wait for panel power cycle (468 ms remaining)
<7> [269.392127] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 mask: 0xb800000f value: 0x00000000 PP_STATUS: 0x08000001 PP_CONTROL: 0x00000060
<7> [269.493513] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [269.493871] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 wait for panel power on
<7> [269.494132] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 mask: 0xb000000f value: 0x80000008 PP_STATUS: 0x9000000a PP_CONTROL: 0x00000063
<7> [269.530525] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [269.530797] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:390:DDI A/PHY A] - long
<7> [269.531032] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 20
<7> [269.531287] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:390:DDI A/PHY A]
<7> [269.575923] xe 0000:00:02.0: [drm:intel_tc_port_reset_mode [xe]] Port E/TC#2: TC port mode reset (tbt-alt -> disconnected) pin assignment: - max lanes: 4
<7> [269.575908] xe 0000:00:02.0: [drm:intel_tc_port_reset_mode [xe]] Port F/TC#3: TC port mode reset (tbt-alt -> disconnected) pin assignment: - max lanes: 4
<7> [269.576033] xe 0000:00:02.0: [drm:intel_tc_port_reset_mode [xe]] Port D/TC#1: TC port mode reset (tbt-alt -> disconnected) pin assignment: - max lanes: 4
<7> [269.707255] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [269.707783] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 turning VDD on
<7> [269.708138] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006b
<7> [269.710219] 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> [269.711534] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Reloading eDP link rates
<7> [269.712374] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Using LINK_RATE_SET value 05
<7> [269.713484] xe 0000:00:02.0: [drm:intel_dp_set_signal_levels [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] 8b/10b, lanes: 4, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0
<7> [269.714631] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Using DP training pattern TPS1
<7> [269.715768] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Clock recovery OK
<7> [269.716100] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Using DP training pattern TPS4
<7> [269.717710] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Channel EQ done. DP Training successful
<7> [269.718101] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Link Training passed at link rate = 432000, lane count = 4
<7> [269.718850] xe 0000:00:02.0: [drm:intel_enable_transcoder [xe]] enabling pipe A
<7> [269.719292] xe 0000:00:02.0: [drm:intel_edp_backlight_on [xe]]
<7> [269.719607] xe 0000:00:02.0: [drm:intel_backlight_enable [xe]] pipe A
<7> [269.719998] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:391:eDP-1] set backlight PWM = 4518
<7> [269.736150] xe 0000:00:02.0: [drm:intel_psr_post_plane_update [xe]] Enabling PSR2
<7> [269.737787] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:391:eDP-1]
<7> [269.738136] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:149:pipe A]
<7> [272.263700] xe 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 turning VDD off
<7> [272.264028] xe 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x00000067
<7> [278.152831] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [278.153167] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [278.153367] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
<6> [278.199026] [IGT] kms_flip: finished subtest A-eDP1, FAIL
<6> [278.200675] [IGT] kms_flip: starting dynamic subtest B-eDP1
<7> [278.221582] xe 0000:00:02.0: [drm:drm_mode_addfb2] [FB:431]
<7> [278.224815] xe 0000:00:02.0: [drm:drm_mode_addfb2] [FB:432]
<7> [278.275052] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:149:pipe A]
<7> [278.275150] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [278.275289] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [278.275398] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [278.275502] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [278.275602] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] 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> [278.275700] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in output_types (expected 0x00000100, found 0x00000000)
<7> [278.275808] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [278.275903] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 2880, found 0)
<7> [278.275996] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 3040, found 0)
<7> [278.276086] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 2880, found 0)
<7> [278.276176] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 3040, found 0)
<7> [278.276266] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2928, found 0)
<7> [278.276363] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2960, found 0)
<7> [278.276453] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1800, found 0)
<7> [278.276537] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1800, found 0)
<7> [278.276619] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1803, found 0)
<7> [278.276706] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1809, found 0)
<7> [278.276799] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1906, found 0)
<7> [278.276885] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1906, found 0)
<7> [278.276968] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 2880, found 0)
<7> [278.277051] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 3040, found 0)
<7> [278.277131] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 2880, found 0)
<7> [278.277214] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 3040, found 0)
<7> [278.277310] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2928, found 0)
<7> [278.277406] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2960, found 0)
<7> [278.277486] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1800, found 0)
<7> [278.277565] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1800, found 0)
<7> [278.277643] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1803, found 0)
<7> [278.277716] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1809, found 0)
<7> [278.277795] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1906, found 0)
<7> [278.277885] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1906, found 0)
<7> [278.277977] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [278.278070] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)
<7> [278.278149] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [278.278231] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in pipe_bpp (expected 30, found 0)
<7> [278.278310] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 347710, found 0)
<7> [278.278379] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 347710, found 0)
<7> [278.278449] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in port_clock (expected 432000, found 0)
<7> [278.278520] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:149:pipe A] fastset requirement not met, forcing full modeset
<7> [278.278597] xe 0000:00:02.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:149:pipe A] releasing DPLL 0
<7> [278.278711] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xf -> 0x1 (total dbuf slices 0xf), mbus joined? yes->no
<7> [278.278785] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:149:pipe A] dbuf slices 0xf -> 0x0, ddb (0 - 4096) -> (0 - 0), active pipes 0x1 -> 0x0
<7> [278.278882] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:33:plane 1A] ddb ( 0 - 4051) -> ( 0 - 0), size 4051 -> 0
<7> [278.278963] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:143:cursor A] ddb (4051 - 4096) -> ( 0 - 0), size 45 -> 0
<7> [278.279047] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:33:plane 1A] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [278.279108] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:33:plane 1A] lines 4, 13, 13, 13, 13, 13, 0, 0, 0, 4, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [278.279166] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:33:plane 1A] blocks 92, 297, 297, 297, 297, 297, 0, 0, 197, 92, 197 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [278.279229] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:33:plane 1A] 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> [278.279314] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:149:pipe A] data rate 0 num active planes 0
<7> [278.279423] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 0 qgv_peak_bw: 38400
<7> [278.279521] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 0 qgv_peak_bw: 38400
<7> [278.279601] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [278.279681] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [278.279904] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 0
<7> [278.280056] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:149:pipe A] min cdclk: 173855 kHz -> 0 kHz
<7> [278.280161] xe 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [xe]] dbuf bandwidth min cdclk: 21732 kHz -> 0 kHz
<7> [278.280244] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] Can change cdclk via squashing
<7> [278.280323] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New cdclk calculated to be logical 153600 kHz, actual 153600 kHz
<7> [278.280406] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New voltage level calculated to be logical 0, actual 0
<7> [278.280523] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:149:pipe A] enable: no [modeset]
<7> [278.280609] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:33:plane 1A] fb: [NOFB], visible: no
<7> [278.280694] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:63:plane 2A] fb: [NOFB], visible: no
<7> [278.280776] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:93:plane 3A] fb: [NOFB], visible: no
<7> [278.280873] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:123:plane 4A] fb: [NOFB], visible: no
<7> [278.280970] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:133:plane 5A] fb: [NOFB], visible: no
<7> [278.281073] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:143:cursor A] fb: [NOFB], visible: no
<7> [278.281209] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [278.281313] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [278.281463] xe 0000:00:02.0: [drm:intel_psr_disable_locked [xe]] Disabling PSR2
<7> [278.281718] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 turning VDD on
<7> [278.281860] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006f
<7> [278.282474] xe 0000:00:02.0: [drm:intel_alpm_disable [xe]] Disabling ALPM
<7> [278.282579] xe 0000:00:02.0: [drm:intel_edp_backlight_off [xe]]
<7> [278.484871] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:391:eDP-1] set backlight PWM = 0
<7> [278.485304] xe 0000:00:02.0: [drm:intel_disable_transcoder [xe]] disabling pipe A
<7> [278.505598] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 turn panel power off
<7> [278.505908] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 wait for panel power off time
<7> [278.506167] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 mask: 0xb0000000 value: 0x00000000 PP_STATUS: 0xa0000002 PP_CONTROL: 0x00000060
<7> [278.506054] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [278.506677] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:390:DDI A/PHY A] - short
<7> [278.558415] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [278.558996] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:390:DDI A/PHY A] - short
<7> [278.565731] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [278.566036] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disable DPLL 0 (active 0x1, on? 1) for [CRTC:149:pipe A]
<7> [278.566182] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:390:DDI A/PHY A]
<7> [278.566901] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:390:DDI A/PHY A]
<7> [278.567212] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disabling DPLL 0
<7> [278.567564] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling AUX_A
<7> [278.567961] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:390:DDI A/PHY A]
<7> [278.568186] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:399:DDI TC1/PHY TC1]
<7> [278.568386] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:401:DP-MST A]
<7> [278.568581] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:402:DP-MST B]
<7> [278.568799] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:403:DP-MST C]
<7> [278.568989] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:411:DDI TC2/PHY TC2]
<7> [278.569174] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:413:DP-MST A]
<7> [278.569367] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:414:DP-MST B]
<7> [278.569559] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:415:DP-MST C]
<7> [278.569776] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:419:DDI TC3/PHY TC3]
<7> [278.570009] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:421:DP-MST A]
<7> [278.570224] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:422:DP-MST B]
<7> [278.570450] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:423:DP-MST C]
<7> [278.570655] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:391:eDP-1]
<7> [278.570966] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [278.571180] xe 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: yes -> no (pipe: *)
<7> [278.571403] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0x1
<7> [278.571849] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_A
<7> [278.572248] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:149:pipe A]
<7> [278.572495] 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> [278.572834] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [278.573064] xe 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=384
<7> [278.574071] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:268:pipe B]
<7> [278.574396] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:387:pipe C]
<7> [278.574637] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:268:pipe B]
<7> [278.574712] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:391:eDP-1]
<7> [278.574868] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CONNECTOR:391:eDP-1] Limiting display bpp to 30 (EDID bpp 30, max requested bpp 36, max platform bpp 36)
<7> [278.575173] xe 0000:00:02.0: [drm:intel_dp_compute_config_limits [xe]] [ENCODER:390:DDI A/PHY A][CRTC:268:pipe B] 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> [278.575453] xe 0000:00:02.0: [drm:intel_dp_compute_link_config [xe]] DP lane count 4 clock 432000 bpp input 30 compressed 0.0000 link rate required 1303913 available 1728000
<7> [278.575784] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:268:pipe B] hw max bpp: 30, pipe bpp: 30, dithering: 0
<7> [278.576076] xe 0000:00:02.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:390:DDI A/PHY A] [CRTC:268:pipe B]
<7> [278.576367] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.enable (expected no, found yes)
<7> [278.576695] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.active (expected no, found yes)
<7> [278.576978] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in cpu_transcoder (expected -1, found 1)
<7> [278.577255] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in lane_count (expected 0, found 4)
<7> [278.577516] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] 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> [278.577857] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in output_types (expected 0x00000000, found 0x00000100)
<7> [278.578117] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [278.578369] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 2880)
<7> [278.578603] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 3040)
<7> [278.578862] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 2880)
<7> [278.579122] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 3040)
<7> [278.579336] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2928)
<7> [278.579550] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2960)
<7> [278.579773] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1800)
<7> [278.579993] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1800)
<7> [278.580189] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1803)
<7> [278.580378] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1809)
<7> [278.580560] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1906)
<7> [278.580790] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1906)
<7> [278.580973] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 2880)
<7> [278.581144] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 3040)
<7> [278.581308] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 2880)
<7> [278.581469] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 3040)
<7> [278.581623] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2928)
<7> [278.581798] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2960)
<7> [278.581945] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1800)
<7> [278.582091] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1800)
<7> [278.582238] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1803)
<7> [278.582380] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1809)
<7> [278.582518] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1906)
<7> [278.582651] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1906)
<7> [278.582805] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [278.582937] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [278.583072] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [278.583210] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in pipe_bpp (expected 0, found 30)
<7> [278.583343] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 347710)
<7> [278.583469] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 347710)
<7> [278.583610] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in port_clock (expected 0, found 432000)
<7> [278.583845] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:268:pipe B] fastset requirement not met, forcing full modeset
<7> [278.584009] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0x1 -> 0xf (total dbuf slices 0xf), mbus joined? no->yes
<7> [278.584099] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:268:pipe B] dbuf slices 0x0 -> 0xf, ddb (0 - 0) -> (0 - 4096), active pipes 0x0 -> 0x2
<7> [278.584223] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:152:plane 1B] ddb ( 0 - 0) -> ( 0 - 4051), size 0 -> 4051
<7> [278.584309] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:262:cursor B] ddb ( 0 - 0) -> (4051 - 4096), size 0 -> 45
<7> [278.584390] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:152:plane 1B] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm
<7> [278.584469] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:152:plane 1B] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 4, 13, 13, 13, 13, 13, 0, 0, 0, 4, 0
<7> [278.584548] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:152:plane 1B] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 92, 297, 297, 297, 297, 297, 0, 0, 197, 92, 197
<7> [278.584626] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:152:plane 1B] 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> [278.584748] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:268:pipe B] data rate 1390840 num active planes 1
<7> [278.584884] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 1461 qgv_peak_bw: 38400
<7> [278.585000] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 1461 qgv_peak_bw: 38400
<7> [278.585112] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [278.585221] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [278.585329] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 1461
<7> [278.585430] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:268:pipe B] min cdclk: 0 kHz -> 173855 kHz
<7> [278.585549] xe 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [xe]] dbuf bandwidth min cdclk: 0 kHz -> 21732 kHz
<7> [278.585660] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] Can change cdclk via squashing
<7> [278.585784] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New cdclk calculated to be logical 192000 kHz, actual 192000 kHz
<7> [278.585884] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New voltage level calculated to be logical 0, actual 0
<7> [278.585994] xe 0000:00:02.0: [drm:intel_find_dpll [xe]] [CRTC:268:pipe B] allocated DPLL 0
<7> [278.586083] xe 0000:00:02.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:268:pipe B] reserving DPLL 0
<7> [278.586173] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:268:pipe B] enable: yes [modeset]
<7> [278.586267] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: EDP (0x100), output format: RGB, sink format: RGB
<7> [278.586358] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: B, pipe bpp: 30, dithering: 0
<7> [278.586444] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [278.586528] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [278.586611] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [278.586699] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [278.586797] 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> [278.586895] 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> [278.586991] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: disabled
<7> [278.587068] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled
<7> [278.587145] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] psr: enabled, selective update: enabled, panel replay: disabled, selective fetch: enabled
<7> [278.587222] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] minimum HBlank: 0
<7> [278.587302] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] audio: 0, infoframes: 0, infoframes enabled: 0x4
<7> [278.587399] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] DP SDP: VSC, revision 4, length 14
<7> [278.587497] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pixelformat: RGB
<7> [278.587595] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] colorimetry: sRGB
<7> [278.587692] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] bpc: 0
<7> [278.587790] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dynamic range: VESA range
<7> [278.587887] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] content type: Not defined
<7> [278.587983] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [278.588077] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [278.588152] 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> [278.588224] 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> [278.588296] 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> [278.588375] 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> [278.588454] 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> [278.588533] 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> [278.588610] 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> [278.588693] 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> [278.588787] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port clock: 432000, pipe src: 2880x1800+0+0, pixel rate 347710, min cdclk 173855
<7> [278.588878] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] linetime: 70, ips linetime: 0
<7> [278.588970] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [278.589046] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [278.589115] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [278.589189] 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> [278.589264] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_hw_state: clock: 432000, fracen: no,
<7> [278.589338] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] multiplier: 225, tx_clk_div: 1.
<7> [278.589414] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_rawhw_state:
<7> [278.589500] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] tx: 0x10, cmn: 0x21
<7> [278.589589] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[0] = 0x4, pll[1] = 0x0, pll[2] = 0xa2, pll[3] = 0x1
<7> [278.589686] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[4] = 0x0, pll[5] = 0x0, pll[6] = 0x0, pll[7] = 0x0
<7> [278.589777] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[8] = 0x0, pll[9] = 0x1, pll[10] = 0x0, pll[11] = 0x0
<7> [278.589866] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[12] = 0x0, pll[13] = 0x0, pll[14] = 0x0, pll[15] = 0x1
<7> [278.589954] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[16] = 0x85, pll[17] = 0xf, pll[18] = 0xe6, pll[19] = 0x23
<7> [278.590035] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [278.590112] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [278.590191] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [278.590269] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [278.590346] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [278.590424] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [278.590501] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [278.590577] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [278.590653] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [278.590752] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [278.590843] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [278.590933] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [278.591021] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [278.591106] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:152:plane 1B] fb: [FB:431] 2880x1800 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [278.591192] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [278.591273] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] src: 2880.000000x1800.000000+0.000000+0.000000 dst: 2880x1800+0+0
<7> [278.591348] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:182:plane 2B] fb: [NOFB], visible: no
<7> [278.591423] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:212:plane 3B] fb: [NOFB], visible: no
<7> [278.591497] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:242:plane 4B] fb: [NOFB], visible: no
<7> [278.591570] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:252:plane 5B] fb: [NOFB], visible: no
<7> [278.591644] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:262:cursor B] fb: [NOFB], visible: no
<7> [278.591762] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [278.591924] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [278.592088] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
<7> [278.592217] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling always-on
<7> [278.592330] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling always-on
<7> [278.592440] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [278.592553] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [278.592718] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_2
<7> [278.592873] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_B
<7> [278.593039] 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> [278.593150] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [278.593241] xe 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=480
<7> [278.593330] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:390:DDI A/PHY A]
<7> [278.593399] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:399:DDI TC1/PHY TC1]
<7> [278.593467] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:401:DP-MST A]
<7> [278.593532] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:402:DP-MST B]
<7> [278.593592] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:403:DP-MST C]
<7> [278.593656] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:411:DDI TC2/PHY TC2]
<7> [278.593742] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:413:DP-MST A]
<7> [278.593823] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:414:DP-MST B]
<7> [278.593905] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:415:DP-MST C]
<7> [278.593969] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:419:DDI TC3/PHY TC3]
<7> [278.594037] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:421:DP-MST A]
<7> [278.594102] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:422:DP-MST B]
<7> [278.594167] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:423:DP-MST C]
<7> [278.594251] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xf
<7> [278.594412] xe 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: no -> yes (pipe: *)
<7> [278.594474] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 8 (mbus joined: yes)
<7> [278.594577] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling AUX_A
<7> [278.595725] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enable DPLL 0 (active 0x2, on? 0) for [CRTC:268:pipe B]
<7> [278.595852] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enabling DPLL 0
<7> [278.597870] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 turn panel power on
<7> [278.597990] xe 0000:00:02.0: [drm:wait_panel_power_cycle [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 wait for panel power cycle (469 ms remaining)
<7> [278.598793] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [278.598965] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:390:DDI A/PHY A] - long
<7> [278.599070] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 10
<7> [278.599175] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:390:DDI A/PHY A]
<7> [279.068747] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 mask: 0xb800000f value: 0x00000000 PP_STATUS: 0x08000001 PP_CONTROL: 0x00000060
<7> [279.078591] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [279.078886] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 wait for panel power on
<7> [279.079163] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 mask: 0xb000000f value: 0x80000008 PP_STATUS: 0x9000000a PP_CONTROL: 0x00000063
<7> [279.115548] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [279.116186] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:390:DDI A/PHY A] - long
<7> [279.116667] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 20
<7> [279.117157] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:390:DDI A/PHY A]
<7> [279.290313] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [279.290688] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 turning VDD on
<7> [279.290949] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006b
<7> [279.293074] 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> [279.294344] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Reloading eDP link rates
<7> [279.295087] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Using LINK_RATE_SET value 05
<7> [279.296119] xe 0000:00:02.0: [drm:intel_dp_set_signal_levels [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] 8b/10b, lanes: 4, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0
<7> [279.297138] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Using DP training pattern TPS1
<7> [279.298110] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Clock recovery OK
<7> [279.298378] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Using DP training pattern TPS4
<7> [279.299800] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Channel EQ done. DP Training successful
<7> [279.300069] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Link Training passed at link rate = 432000, lane count = 4
<7> [279.300808] xe 0000:00:02.0: [drm:intel_enable_transcoder [xe]] enabling pipe B
<7> [279.301187] xe 0000:00:02.0: [drm:intel_edp_backlight_on [xe]]
<7> [279.301436] xe 0000:00:02.0: [drm:intel_backlight_enable [xe]] pipe B
<7> [279.301792] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:391:eDP-1] set backlight PWM = 4518
<7> [279.318064] xe 0000:00:02.0: [drm:intel_psr_post_plane_update [xe]] Enabling PSR2
<7> [279.319617] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:391:eDP-1]
<7> [279.319906] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:268:pipe B]
<7> [281.860878] xe 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 turning VDD off
<7> [281.861193] xe 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x00000067
<7> [289.333408] xe 0000:00:02.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:432] from all active usage due to RMFB ioctl
<7> [289.333498] xe 0000:00:02.0: [drm:atomic_remove_fb] Disabling [PLANE:152:plane 1B] because [FB:432] is removed
<7> [289.333551] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:152:plane 1B] ddb ( 0 - 4051) -> ( 0 - 0), size 4051 -> 0
<7> [289.333820] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:152:plane 1B] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [289.334036] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:152:plane 1B] lines 4, 13, 13, 13, 13, 13, 0, 0, 0, 4, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [289.334303] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:152:plane 1B] blocks 92, 297, 297, 297, 297, 297, 0, 0, 197, 92, 197 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [289.334516] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:152:plane 1B] 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> [289.334761] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:268:pipe B] data rate 0 num active planes 0
<7> [289.335102] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 0 qgv_peak_bw: 38400
<7> [289.335368] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 0 qgv_peak_bw: 38400
<7> [289.335625] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [289.335873] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [289.336126] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 0
<7> [289.336761] xe 0000:00:02.0: [drm:intel_psr_disable_locked [xe]] Disabling PSR2
<7> [289.358181] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 turning VDD on
<7> [289.358480] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006f
<6> [289.371969] [IGT] kms_flip: finished subtest B-eDP1, SUCCESS
<6> [289.373477] [IGT] kms_flip: starting dynamic subtest C-eDP1
<7> [289.384129] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [289.384365] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [289.384555] xe 0000:00:02.0: [drm:drm_mode_addfb2] [FB:431]
<7> [289.384519] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
<7> [289.385109] xe 0000:00:02.0: [drm:drm_mode_addfb2] [FB:432]
<7> [289.438470] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:149:pipe A]
<7> [289.438568] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [289.438688] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [289.438881] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:268:pipe B]
<7> [289.438937] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.enable (expected yes, found no)
<7> [289.439030] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.active (expected yes, found no)
<7> [289.439137] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in cpu_transcoder (expected 1, found -1)
<7> [289.439237] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in lane_count (expected 4, found 0)
<7> [289.439349] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268: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> [289.439449] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in output_types (expected 0x00000100, found 0x00000000)
<7> [289.439542] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [289.439634] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 2880, found 0)
<7> [289.439725] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 3040, found 0)
<7> [289.439814] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 2880, found 0)
<7> [289.439919] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 3040, found 0)
<7> [289.440028] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2928, found 0)
<7> [289.440158] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2960, found 0)
<7> [289.440266] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1800, found 0)
<7> [289.440371] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1800, found 0)
<7> [289.440451] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1803, found 0)
<7> [289.440546] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1809, found 0)
<7> [289.440633] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1906, found 0)
<7> [289.440718] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1906, found 0)
<7> [289.440803] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 2880, found 0)
<7> [289.440887] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 3040, found 0)
<7> [289.440971] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 2880, found 0)
<7> [289.441053] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 3040, found 0)
<7> [289.441168] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2928, found 0)
<7> [289.441264] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2960, found 0)
<7> [289.441358] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1800, found 0)
<7> [289.441444] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1800, found 0)
<7> [289.441528] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1803, found 0)
<7> [289.441607] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1809, found 0)
<7> [289.441684] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1906, found 0)
<7> [289.441773] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1906, found 0)
<7> [289.441874] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [289.441978] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)
<7> [289.442082] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [289.442180] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in pipe_bpp (expected 30, found 0)
<7> [289.442271] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 347710, found 0)
<7> [289.442362] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 347710, found 0)
<7> [289.442440] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in port_clock (expected 432000, found 0)
<7> [289.442517] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:268:pipe B] fastset requirement not met, forcing full modeset
<7> [289.442595] xe 0000:00:02.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:268:pipe B] releasing DPLL 0
<7> [289.442831] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xf -> 0x1 (total dbuf slices 0xf), mbus joined? yes->no
<7> [289.442904] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:268:pipe B] dbuf slices 0xf -> 0x0, ddb (0 - 4096) -> (0 - 0), active pipes 0x2 -> 0x0
<7> [289.442987] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:262:cursor B] ddb (4051 - 4096) -> ( 0 - 0), size 45 -> 0
<7> [289.443069] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:268:pipe B] min cdclk: 173855 kHz -> 0 kHz
<7> [289.443179] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] Can change cdclk via squashing
<7> [289.443285] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New cdclk calculated to be logical 153600 kHz, actual 153600 kHz
<7> [289.443387] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New voltage level calculated to be logical 0, actual 0
<7> [289.443490] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:268:pipe B] enable: no [modeset]
<7> [289.443606] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:152:plane 1B] fb: [NOFB], visible: no
<7> [289.443723] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:182:plane 2B] fb: [NOFB], visible: no
<7> [289.443828] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:212:plane 3B] fb: [NOFB], visible: no
<7> [289.443910] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:242:plane 4B] fb: [NOFB], visible: no
<7> [289.443985] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:252:plane 5B] fb: [NOFB], visible: no
<7> [289.444068] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:262:cursor B] fb: [NOFB], visible: no
<7> [289.444278] xe 0000:00:02.0: [drm:intel_alpm_disable [xe]] Disabling ALPM
<7> [289.444392] xe 0000:00:02.0: [drm:intel_edp_backlight_off [xe]]
<7> [289.650276] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:391:eDP-1] set backlight PWM = 0
<7> [289.650777] xe 0000:00:02.0: [drm:intel_disable_transcoder [xe]] disabling pipe B
<7> [289.671950] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 turn panel power off
<7> [289.672515] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 wait for panel power off time
<7> [289.672963] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 mask: 0xb0000000 value: 0x00000000 PP_STATUS: 0xa0000002 PP_CONTROL: 0x00000060
<7> [289.725036] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [289.725533] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:390:DDI A/PHY A] - short
<7> [289.733055] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [289.733656] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disable DPLL 0 (active 0x2, on? 1) for [CRTC:268:pipe B]
<7> [289.733700] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:390:DDI A/PHY A]
<7> [289.735158] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disabling DPLL 0
<7> [289.735742] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling AUX_A
<7> [289.736372] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:390:DDI A/PHY A]
<7> [289.736778] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:399:DDI TC1/PHY TC1]
<7> [289.737156] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:401:DP-MST A]
<7> [289.737484] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:402:DP-MST B]
<7> [289.737818] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:403:DP-MST C]
<7> [289.738235] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:411:DDI TC2/PHY TC2]
<7> [289.738549] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:413:DP-MST A]
<7> [289.738860] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:414:DP-MST B]
<7> [289.739210] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:415:DP-MST C]
<7> [289.739513] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:419:DDI TC3/PHY TC3]
<7> [289.739810] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:421:DP-MST A]
<7> [289.740139] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:422:DP-MST B]
<7> [289.740433] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:423:DP-MST C]
<7> [289.740727] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:391:eDP-1]
<7> [289.741118] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [289.741482] xe 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: yes -> no (pipe: *)
<7> [289.741770] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0x1
<7> [289.742359] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_B
<7> [289.742802] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_2
<7> [289.743287] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:268:pipe B]
<7> [289.743695] 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> [289.744184] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [289.744472] xe 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=384
<7> [289.745405] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:387:pipe C]
<7> [289.745819] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:387:pipe C]
<7> [289.745896] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:391:eDP-1]
<7> [289.746109] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CONNECTOR:391:eDP-1] Limiting display bpp to 30 (EDID bpp 30, max requested bpp 36, max platform bpp 36)
<7> [289.746522] xe 0000:00:02.0: [drm:intel_dp_compute_config_limits [xe]] [ENCODER:390:DDI A/PHY A][CRTC:387: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> [289.746871] xe 0000:00:02.0: [drm:intel_dp_compute_link_config [xe]] DP lane count 4 clock 432000 bpp input 30 compressed 0.0000 link rate required 1303913 available 1728000
<7> [289.747223] xe 0000:00:02.0: [drm:intel_psr_compute_config [xe]] PSR2 not supported in transcoder C
<7> [289.747498] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:387:pipe C] hw max bpp: 30, pipe bpp: 30, dithering: 0
<7> [289.747804] xe 0000:00:02.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:390:DDI A/PHY A] [CRTC:387:pipe C]
<7> [289.748169] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.enable (expected no, found yes)
<7> [289.748465] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.active (expected no, found yes)
<7> [289.748741] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in cpu_transcoder (expected -1, found 2)
<7> [289.749018] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in lane_count (expected 0, found 4)
<7> [289.749275] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387: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> [289.749535] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in output_types (expected 0x00000000, found 0x00000100)
<7> [289.749779] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [289.750016] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 2880)
<7> [289.750247] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 3040)
<7> [289.750462] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 2880)
<7> [289.750666] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 3040)
<7> [289.750877] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2928)
<7> [289.751124] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2960)
<7> [289.751326] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1800)
<7> [289.751503] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1800)
<7> [289.751679] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1803)
<7> [289.751845] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1809)
<7> [289.752013] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1906)
<7> [289.752172] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1906)
<7> [289.752330] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 2880)
<7> [289.752480] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 3040)
<7> [289.752625] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 2880)
<7> [289.752767] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 3040)
<7> [289.752906] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2928)
<7> [289.753058] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2960)
<7> [289.753196] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1800)
<7> [289.753345] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1800)
<7> [289.753493] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1803)
<7> [289.753633] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1809)
<7> [289.753762] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1906)
<7> [289.753878] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1906)
<7> [289.754005] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [289.754122] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [289.754237] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [289.754351] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in pipe_bpp (expected 0, found 30)
<7> [289.754459] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 347710)
<7> [289.754564] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 347710)
<7> [289.754674] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in port_clock (expected 0, found 432000)
<7> [289.754783] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:387:pipe C] fastset requirement not met, forcing full modeset
<7> [289.754913] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0x1 -> 0xd (total dbuf slices 0xf), mbus joined? no->no
<7> [289.754999] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:387:pipe C] dbuf slices 0x0 -> 0xc, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x4
<7> [289.755108] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:271:plane 1C] ddb ( 0 - 0) -> ( 0 - 2003), size 0 -> 2003
<7> [289.755192] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:381:cursor C] ddb ( 0 - 0) -> (2003 - 2048), size 0 -> 45
<7> [289.755277] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:271: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> [289.755356] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:271: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> [289.755427] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:271: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> [289.755496] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:271: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> [289.755586] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:387:pipe C] data rate 1390840 num active planes 1
<7> [289.755698] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 1461 qgv_peak_bw: 38400
<7> [289.755798] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 1461 qgv_peak_bw: 38400
<7> [289.755894] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [289.755989] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [289.756106] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 1461
<7> [289.756222] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:387:pipe C] min cdclk: 0 kHz -> 173855 kHz
<7> [289.756342] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] Can change cdclk via squashing
<7> [289.756438] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New cdclk calculated to be logical 192000 kHz, actual 192000 kHz
<7> [289.756531] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New voltage level calculated to be logical 0, actual 0
<7> [289.756633] xe 0000:00:02.0: [drm:intel_find_dpll [xe]] [CRTC:387:pipe C] allocated DPLL 0
<7> [289.756713] xe 0000:00:02.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:387:pipe C] reserving DPLL 0
<7> [289.756793] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:387:pipe C] enable: yes [modeset]
<7> [289.756874] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: EDP (0x100), output format: RGB, sink format: RGB
<7> [289.756954] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: C, pipe bpp: 30, dithering: 0
<7> [289.757049] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [289.757148] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [289.757247] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [289.757344] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [289.757435] 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> [289.757516] 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> [289.757596] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: disabled
<7> [289.757670] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled
<7> [289.757744] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] psr: enabled, selective update: disabled, panel replay: disabled, selective fetch: disabled
<7> [289.757817] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] minimum HBlank: 0
<7> [289.757891] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] audio: 0, infoframes: 0, infoframes enabled: 0x4
<7> [289.757965] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] DP SDP: VSC, revision 2, length 8
<7> [289.758062] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pixelformat: RGB
<7> [289.758169] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] colorimetry: sRGB
<7> [289.758280] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] bpc: 0
<7> [289.758371] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dynamic range: VESA range
<7> [289.758448] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] content type: Not defined
<7> [289.758520] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [289.758591] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [289.758663] 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> [289.758735] 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> [289.758805] 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> [289.758876] 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> [289.758947] 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> [289.759026] 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> [289.759128] 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> [289.759232] 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> [289.759319] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port clock: 432000, pipe src: 2880x1800+0+0, pixel rate 347710, min cdclk 173855
<7> [289.759404] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] linetime: 70, ips linetime: 0
<7> [289.759480] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [289.759556] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [289.759631] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [289.759702] 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> [289.759771] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_hw_state: clock: 432000, fracen: no,
<7> [289.759844] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] multiplier: 225, tx_clk_div: 1.
<7> [289.759918] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_rawhw_state:
<7> [289.759986] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] tx: 0x10, cmn: 0x21
<7> [289.760127] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[0] = 0x4, pll[1] = 0x0, pll[2] = 0xa2, pll[3] = 0x1
<7> [289.760217] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[4] = 0x0, pll[5] = 0x0, pll[6] = 0x0, pll[7] = 0x0
<7> [289.760309] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[8] = 0x0, pll[9] = 0x1, pll[10] = 0x0, pll[11] = 0x0
<7> [289.760387] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[12] = 0x0, pll[13] = 0x0, pll[14] = 0x0, pll[15] = 0x1
<7> [289.760463] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[16] = 0x85, pll[17] = 0xf, pll[18] = 0xe6, pll[19] = 0x23
<7> [289.760535] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [289.760607] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [289.760678] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [289.760749] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [289.760819] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [289.760889] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [289.760958] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [289.761045] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [289.761138] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [289.761229] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [289.761319] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [289.761410] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [289.761485] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [289.761560] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:271:plane 1C] fb: [FB:431] 2880x1800 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [289.761632] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [289.761700] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] src: 2880.000000x1800.000000+0.000000+0.000000 dst: 2880x1800+0+0
<7> [289.761770] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:301:plane 2C] fb: [NOFB], visible: no
<7> [289.761838] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:331:plane 3C] fb: [NOFB], visible: no
<7> [289.761905] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:361:plane 4C] fb: [NOFB], visible: no
<7> [289.761974] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:371:plane 5C] fb: [NOFB], visible: no
<7> [289.762065] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:381:cursor C] fb: [NOFB], visible: no
<7> [289.762503] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_2
<7> [289.762627] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_C
<7> [289.762767] 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> [289.762857] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [289.762941] xe 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=480
<7> [289.763035] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:390:DDI A/PHY A]
<7> [289.763122] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:399:DDI TC1/PHY TC1]
<7> [289.763203] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:401:DP-MST A]
<7> [289.763283] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:402:DP-MST B]
<7> [289.763355] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:403:DP-MST C]
<7> [289.763417] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:411:DDI TC2/PHY TC2]
<7> [289.763482] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:413:DP-MST A]
<7> [289.763545] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:414:DP-MST B]
<7> [289.763608] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:415:DP-MST C]
<7> [289.763669] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:419:DDI TC3/PHY TC3]
<7> [289.763731] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:421:DP-MST A]
<7> [289.763787] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:422:DP-MST B]
<7> [289.763842] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:423:DP-MST C]
<7> [289.763920] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xd
<7> [289.764089] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [289.764213] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling AUX_A
<7> [289.765024] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enable DPLL 0 (active 0x4, on? 0) for [CRTC:387:pipe C]
<7> [289.765117] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enabling DPLL 0
<7> [289.765256] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [289.765418] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:390:DDI A/PHY A] - long
<7> [289.765517] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 10
<7> [289.765620] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:390:DDI A/PHY A]
<7> [289.766836] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 turn panel power on
<7> [289.766903] xe 0000:00:02.0: [drm:wait_panel_power_cycle [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 wait for panel power cycle (467 ms remaining)
<7> [290.242140] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 mask: 0xb800000f value: 0x00000000 PP_STATUS: 0x08000001 PP_CONTROL: 0x00000060
<7> [290.303452] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [290.303832] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 wait for panel power on
<7> [290.304243] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 mask: 0xb000000f value: 0x80000008 PP_STATUS: 0x9000000a PP_CONTROL: 0x00000063
<7> [290.340467] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [290.340821] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:390:DDI A/PHY A] - long
<7> [290.341139] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 20
<7> [290.341470] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:390:DDI A/PHY A]
<7> [290.517875] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [290.518238] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 turning VDD on
<7> [290.518534] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006b
<7> [290.520516] 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> [290.521821] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Reloading eDP link rates
<7> [290.522651] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Using LINK_RATE_SET value 05
<7> [290.523813] xe 0000:00:02.0: [drm:intel_dp_set_signal_levels [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] 8b/10b, lanes: 4, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0
<7> [290.525027] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Using DP training pattern TPS1
<7> [290.526126] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Clock recovery OK
<7> [290.526561] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Using DP training pattern TPS4
<7> [290.528118] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Channel EQ done. DP Training successful
<7> [290.528497] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Link Training passed at link rate = 432000, lane count = 4
<7> [290.529310] xe 0000:00:02.0: [drm:intel_enable_transcoder [xe]] enabling pipe C
<7> [290.529787] xe 0000:00:02.0: [drm:intel_edp_backlight_on [xe]]
<7> [290.530182] xe 0000:00:02.0: [drm:intel_backlight_enable [xe]] pipe C
<7> [290.530598] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:391:eDP-1] set backlight PWM = 4518
<7> [290.532013] xe 0000:00:02.0: [drm:intel_fbc_update [xe]] reserved 14745600 bytes of contiguous stolen space for FBC, limit: 2
<7> [290.532329] xe 0000:00:02.0: [drm:intel_fbc_update [xe]] Enabling FBC on [PLANE:271:plane 1C]
<7> [290.546558] xe 0000:00:02.0: [drm:intel_psr_post_plane_update [xe]] Enabling PSR1
<7> [290.547994] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:391:eDP-1]
<7> [290.548304] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:387:pipe C]
<7> [293.057341] xe 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 turning VDD off
<7> [293.057694] xe 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x00000067
<7> [300.561946] xe 0000:00:02.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:432] from all active usage due to RMFB ioctl
<7> [300.562052] xe 0000:00:02.0: [drm:atomic_remove_fb] Disabling [PLANE:271:plane 1C] because [FB:432] is removed
<7> [300.562116] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:271:plane 1C] ddb ( 0 - 2003) -> ( 0 - 0), size 2003 -> 0
<7> [300.562378] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:271: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> [300.562572] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:271: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> [300.562801] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:271: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> [300.562984] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:271: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> [300.563247] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:387:pipe C] data rate 0 num active planes 0
<7> [300.563538] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 0 qgv_peak_bw: 38400
<7> [300.563833] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 0 qgv_peak_bw: 38400
<7> [300.564106] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [300.564440] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [300.564722] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 0
<7> [300.565610] xe 0000:00:02.0: [drm:intel_psr_disable_locked [xe]] Disabling PSR1
<7> [300.565882] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 turning VDD on
<7> [300.566117] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006f
<7> [300.578286] xe 0000:00:02.0: [drm:__intel_fbc_disable [xe]] Disabling FBC on [PLANE:271:plane 1C]
<6> [300.600350] [IGT] kms_flip: finished subtest C-eDP1, SUCCESS
<6> [300.603768] [IGT] kms_flip: finished subtest flip-vs-expired-vblank-interruptible, FAIL
<7> [300.622009] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CONNECTOR:391:eDP-1] Limiting display bpp to 30 (EDID bpp 30, max requested bpp 36, max platform bpp 36)
<7> [300.622528] xe 0000:00:02.0: [drm:intel_dp_compute_config_limits [xe]] [ENCODER:390:DDI A/PHY A][CRTC:149: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> [300.622977] xe 0000:00:02.0: [drm:intel_dp_compute_link_config [xe]] DP lane count 4 clock 432000 bpp input 30 compressed 0.0000 link rate required 1303913 available 1728000
<7> [300.623367] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:149:pipe A] hw max bpp: 30, pipe bpp: 30, dithering: 0
<7> [300.623772] xe 0000:00:02.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:390:DDI A/PHY A] [CRTC:149:pipe A]
<7> [300.624163] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [300.624481] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [300.624801] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [300.625144] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [300.625431] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149: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> [300.625706] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000100)
<7> [300.625961] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [300.626205] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 2880)
<7> [300.626433] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 3040)
<7> [300.626668] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 2880)
<7> [300.627193] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 3040)
<7> [300.627400] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2928)
<7> [300.627624] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2960)
<7> [300.627909] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1800)
<7> [300.628124] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1800)
<7> [300.628317] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1803)
<7> [300.628491] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1809)
<7> [300.628657] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1906)
<7> [300.628893] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1906)
<7> [300.629067] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 2880)
<7> [300.629227] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 3040)
<7> [300.629382] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 2880)
<7> [300.629552] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 3040)
<7> [300.629794] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2928)
<7> [300.629948] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2960)
<7> [300.630083] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1800)
<7> [300.630218] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1800)
<7> [300.630353] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1803)
<7> [300.630483] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1809)
<7> [300.630609] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1906)
<7> [300.630778] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1906)
<7> [300.630897] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [300.631010] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [300.631122] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [300.631236] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 30)
<7> [300.631347] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 347710)
<7> [300.631467] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 347710)
<7> [300.631589] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in port_clock (expected 0, found 432000)
<7> [300.631734] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:149:pipe A] fastset requirement not met, forcing full modeset
<7> [300.631839] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.enable (expected yes, found no)
<7> [300.631937] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.active (expected yes, found no)
<7> [300.632031] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in cpu_transcoder (expected 2, found -1)
<7> [300.632126] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in lane_count (expected 4, found 0)
<7> [300.632219] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387: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> [300.632314] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in output_types (expected 0x00000100, found 0x00000000)
<7> [300.632405] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [300.632493] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 2880, found 0)
<7> [300.632586] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 3040, found 0)
<7> [300.632686] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 2880, found 0)
<7> [300.632781] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 3040, found 0)
<7> [300.632875] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2928, found 0)
<7> [300.632968] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2960, found 0)
<7> [300.633056] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1800, found 0)
<7> [300.633138] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1800, found 0)
<7> [300.633222] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1803, found 0)
<7> [300.633304] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1809, found 0)
<7> [300.633383] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1906, found 0)
<7> [300.633461] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1906, found 0)
<7> [300.633538] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 2880, found 0)
<7> [300.633613] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 3040, found 0)
<7> [300.633696] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 2880, found 0)
<7> [300.633787] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 3040, found 0)
<7> [300.633875] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2928, found 0)
<7> [300.633972] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2960, found 0)
<7> [300.634058] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1800, found 0)
<7> [300.634125] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1800, found 0)
<7> [300.634192] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1803, found 0)
<7> [300.634258] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1809, found 0)
<7> [300.634326] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1906, found 0)
<7> [300.634395] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1906, found 0)
<7> [300.634463] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [300.634532] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)
<7> [300.634600] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [300.634671] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in pipe_bpp (expected 30, found 0)
<7> [300.634805] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 347710, found 0)
<7> [300.634900] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 347710, found 0)
<7> [300.634990] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in port_clock (expected 432000, found 0)
<7> [300.635065] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:387:pipe C] fastset requirement not met, forcing full modeset
<7> [300.635142] xe 0000:00:02.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:387:pipe C] releasing DPLL 0
<7> [300.635362] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xd -> 0xf (total dbuf slices 0xf), mbus joined? no->yes
<7> [300.635440] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:149:pipe A] dbuf slices 0x0 -> 0xf, ddb (0 - 0) -> (0 - 4096), active pipes 0x4 -> 0x1
<7> [300.635509] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:387:pipe C] dbuf slices 0xc -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x4 -> 0x1
<7> [300.635579] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:33:plane 1A] ddb ( 0 - 0) -> ( 0 - 4051), size 0 -> 4051
<7> [300.635648] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:143:cursor A] ddb ( 0 - 0) -> (4051 - 4096), size 0 -> 45
<7> [300.635730] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:33:plane 1A] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm
<7> [300.635803] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:33:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 13, 13, 13, 13, 13, 0, 0, 0, 3, 0
<7> [300.635873] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:33:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 24, 313, 313, 313, 313, 313, 0, 0, 38, 73, 87
<7> [300.635947] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:33:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 28, 346, 346, 346, 346, 346, 0, 0, 39, 82, 88
<7> [300.636013] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:381:cursor C] ddb (2003 - 2048) -> ( 0 - 0), size 45 -> 0
<7> [300.636086] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:149:pipe A] data rate 1390840 num active planes 1
<7> [300.636172] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 1461 qgv_peak_bw: 38400
<7> [300.636257] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 1461 qgv_peak_bw: 38400
<7> [300.636340] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [300.636419] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [300.636497] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 1461
<7> [300.636575] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:149:pipe A] min cdclk: 0 kHz -> 173855 kHz
<7> [300.636654] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:387:pipe C] min cdclk: 173855 kHz -> 0 kHz
<7> [300.636801] xe 0000:00:02.0: [drm:intel_find_dpll [xe]] [CRTC:149:pipe A] allocated DPLL 0
<7> [300.636911] xe 0000:00:02.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:149:pipe A] reserving DPLL 0
<7> [300.637012] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:149:pipe A] enable: yes [modeset]
<7> [300.637103] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: EDP (0x100), output format: RGB, sink format: RGB
<7> [300.637185] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: A, pipe bpp: 30, dithering: 0
<7> [300.637265] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [300.637349] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [300.637433] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [300.637516] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [300.637600] 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> [300.637686] 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> [300.637790] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: disabled
<7> [300.637888] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled
<7> [300.637987] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] psr: enabled, selective update: enabled, panel replay: disabled, selective fetch: enabled
<7> [300.638066] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] minimum HBlank: 0
<7> [300.638142] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] audio: 0, infoframes: 0, infoframes enabled: 0x4
<7> [300.638216] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] DP SDP: VSC, revision 4, length 14
<7> [300.638290] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pixelformat: RGB
<7> [300.638363] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] colorimetry: sRGB
<7> [300.638434] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] bpc: 0
<7> [300.638505] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dynamic range: VESA range
<7> [300.638577] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] content type: Not defined
<7> [300.638647] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [300.638795] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [300.638891] 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> [300.638985] 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> [300.639063] 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> [300.639139] 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> [300.639216] 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> [300.639292] 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> [300.639368] 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> [300.639443] 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> [300.639513] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port clock: 432000, pipe src: 2880x1800+0+0, pixel rate 347710, min cdclk 173855
<7> [300.639582] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] linetime: 70, ips linetime: 0
<7> [300.639651] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [300.639747] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [300.639841] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [300.639935] 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> [300.640026] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_hw_state: clock: 432000, fracen: no,
<7> [300.640103] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] multiplier: 225, tx_clk_div: 1.
<7> [300.640174] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_rawhw_state:
<7> [300.640242] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] tx: 0x10, cmn: 0x21
<7> [300.640311] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[0] = 0x4, pll[1] = 0x0, pll[2] = 0xa2, pll[3] = 0x1
<7> [300.640380] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[4] = 0x0, pll[5] = 0x0, pll[6] = 0x0, pll[7] = 0x0
<7> [300.640449] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[8] = 0x0, pll[9] = 0x1, pll[10] = 0x0, pll[11] = 0x0
<7> [300.640517] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[12] = 0x0, pll[13] = 0x0, pll[14] = 0x0, pll[15] = 0x1
<7> [300.640588] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[16] = 0x85, pll[17] = 0xf, pll[18] = 0xe6, pll[19] = 0x23
<7> [300.640660] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [300.640765] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [300.640870] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [300.640977] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [300.641066] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [300.641152] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [300.641228] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [300.641304] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [300.641380] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [300.641453] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [300.641522] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [300.641590] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [300.641659] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [300.641756] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:33:plane 1A] fb: [FB:392] 2880x1800 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [300.641852] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [300.641944] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] src: 2880.000000x1800.000000+0.000000+0.000000 dst: 2880x1800+0+0
<7> [300.642024] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:63:plane 2A] fb: [NOFB], visible: no
<7> [300.642099] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:93:plane 3A] fb: [NOFB], visible: no
<7> [300.642169] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:123:plane 4A] fb: [NOFB], visible: no
<7> [300.642238] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:133:plane 5A] fb: [NOFB], visible: no
<7> [300.642306] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:143:cursor A] fb: [NOFB], visible: no
<7> [300.642373] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:387:pipe C] enable: no [modeset]
<7> [300.642446] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:271:plane 1C] fb: [NOFB], visible: no
<7> [300.642523] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:301:plane 2C] fb: [NOFB], visible: no
<7> [300.642599] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:331:plane 3C] fb: [NOFB], visible: no
<7> [300.642679] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:361:plane 4C] fb: [NOFB], visible: no
<7> [300.642764] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:371:plane 5C] fb: [NOFB], visible: no
<7> [300.642852] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:381:cursor C] fb: [NOFB], visible: no
<7> [300.643028] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_A
<7> [300.643298] xe 0000:00:02.0: [drm:intel_alpm_disable [xe]] Disabling ALPM
<7> [300.643395] xe 0000:00:02.0: [drm:intel_edp_backlight_off [xe]]
<7> [300.847959] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:391:eDP-1] set backlight PWM = 0
<7> [300.848480] xe 0000:00:02.0: [drm:intel_disable_transcoder [xe]] disabling pipe C
<7> [300.869587] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 turn panel power off
<7> [300.870059] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 wait for panel power off time
<7> [300.869988] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [300.870469] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 mask: 0xb0000000 value: 0x00000000 PP_STATUS: 0xa0000002 PP_CONTROL: 0x00000060
<7> [300.870708] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:390:DDI A/PHY A] - short
<7> [300.921607] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [300.921989] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disable DPLL 0 (active 0x4, on? 1) for [CRTC:387:pipe C]
<7> [300.922368] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:390:DDI A/PHY A]
<7> [300.923148] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disabling DPLL 0
<7> [300.922707] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [300.923486] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling AUX_A
<7> [300.923854] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:390:DDI A/PHY A]
<7> [300.924070] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:399:DDI TC1/PHY TC1]
<7> [300.923777] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:390:DDI A/PHY A] - short
<7> [300.924269] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:401:DP-MST A]
<7> [300.924458] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:402:DP-MST B]
<7> [300.924660] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:403:DP-MST C]
<7> [300.924713] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:390:DDI A/PHY A]
<7> [300.924841] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:411:DDI TC2/PHY TC2]
<7> [300.925065] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:413:DP-MST A]
<7> [300.925252] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:414:DP-MST B]
<7> [300.925434] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:415:DP-MST C]
<7> [300.925632] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:419:DDI TC3/PHY TC3]
<7> [300.925822] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:421:DP-MST A]
<7> [300.926000] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:422:DP-MST B]
<7> [300.926166] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:423:DP-MST C]
<7> [300.926386] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xf
<7> [300.926718] xe 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: no -> yes (pipe: *)
<7> [300.926892] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 8 (mbus joined: yes)
<7> [300.927119] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling AUX_A
<7> [300.928636] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:149:pipe A]
<7> [300.928894] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enabling DPLL 0
<7> [300.930878] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 turn panel power on
<7> [300.931125] xe 0000:00:02.0: [drm:wait_panel_power_cycle [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 wait for panel power cycle (491 ms remaining)
<7> [300.962682] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [300.963144] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:390:DDI A/PHY A] - long
<7> [300.963513] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 10
<7> [300.964098] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:390:DDI A/PHY A]
<7> [301.423853] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 mask: 0xb800000f value: 0x00000000 PP_STATUS: 0x08000001 PP_CONTROL: 0x00000060
<7> [301.532015] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [301.532323] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 wait for panel power on
<7> [301.532644] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 mask: 0xb000000f value: 0x80000008 PP_STATUS: 0x9000000a PP_CONTROL: 0x00000063
<7> [301.568972] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [301.569580] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:390:DDI A/PHY A] - long
<7> [301.570060] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 20
<7> [301.570742] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:390:DDI A/PHY A]
<7> [301.742184] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [301.742591] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 turning VDD on
<7> [301.742872] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006b
<7> [301.744863] 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> [301.746136] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Reloading eDP link rates
<7> [301.746895] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Using LINK_RATE_SET value 05
<7> [301.747965] xe 0000:00:02.0: [drm:intel_dp_set_signal_levels [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] 8b/10b, lanes: 4, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0
<7> [301.748801] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Using DP training pattern TPS1
<7> [301.750064] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Clock recovery OK
<7> [301.750711] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Using DP training pattern TPS4
<7> [301.752537] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Channel EQ done. DP Training successful
<7> [301.753092] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Link Training passed at link rate = 432000, lane count = 4
<7> [301.753998] xe 0000:00:02.0: [drm:intel_enable_transcoder [xe]] enabling pipe A
<7> [301.754560] xe 0000:00:02.0: [drm:intel_edp_backlight_on [xe]]
<7> [301.754975] xe 0000:00:02.0: [drm:intel_backlight_enable [xe]] pipe A
<7> [301.755431] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:391:eDP-1] set backlight PWM = 4518
<7> [301.771498] xe 0000:00:02.0: [drm:intel_psr_post_plane_update [xe]] Enabling PSR2
<7> [301.773260] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:391:eDP-1]
<7> [301.773686] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:149:pipe A]
<7> [301.776244] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_C
<7> [301.776687] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_2
<7> [301.777080] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:387:pipe C]
<7> [301.777996] xe 0000:00:02.0: [drm:drm_client_dev_restore] fbdev: ret=0
<6> [301.778864] [IGT] kms_flip: exiting, ret=98
<6> [301.788946] Console: switching to colour frame buffer device 180x56
<7> [301.805563] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [301.805973] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [301.806302] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
<7> [301.806683] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [301.807045] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [301.839578] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [301.839931] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [301.840215] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
Created at 2026-01-13 02:24:31