Results for igt@kms_flip@2x-flip-vs-expired-vblank-interruptible@ab-edp1-dp1

Result: Fail

boot-20250925-0317 dmesg-20250925-0317

DetailValue
Duration 10.84 seconds
Igt-Version
IGT-Version: 2.1-g162a06a82 (x86_64) (Linux: 6.17.0-rc7-lgci-xe-xe-3827-690ed0728a0e31e6b+ x86_64)
Out
Using IGT_SRANDOM=1758770462 for randomisation
Opened device: /dev/dri/card0
Using monotonic timestamps
Starting subtest: 2x-flip-vs-expired-vblank-interruptible
Starting dynamic subtest: AB-eDP1-DP1
  1024x768: 60 65000 1024 1048 1184 1344 768 771 777 806 0x48 0xa 
  1024x768: 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 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:1969 run_pair()
  #3 ../tests/kms_flip.c:2160 __igt_unique____real_main2040()
  #4 ../tests/kms_flip.c:2040 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest AB-eDP1-DP1: FAIL (10.836s)
Err
Starting subtest: 2x-flip-vs-expired-vblank-interruptible
Starting dynamic subtest: AB-eDP1-DP1
(kms_flip:2542) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:928:
(kms_flip:2542) CRITICAL: Failed assertion: end - start < 500
(kms_flip:2542) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest AB-eDP1-DP1 failed.
**** DEBUG ****
(kms_flip:2542) DEBUG: Vblank took 87us
(kms_flip:2542) DEBUG: name = flip
last_ts = 299.026550
last_received_ts = 299.025940
last_seq = 6963
current_ts = 299.043243
current_received_ts = 299.042572
current_seq = 6964
count = 454
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 51us
(kms_flip:2542) DEBUG: name = flip
last_ts = 299.043243
last_received_ts = 299.042572
last_seq = 6964
current_ts = 299.059906
current_received_ts = 299.059387
current_seq = 6965
count = 455
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 79us
(kms_flip:2542) DEBUG: name = flip
last_ts = 299.059906
last_received_ts = 299.059387
last_seq = 6965
current_ts = 299.076569
current_received_ts = 299.076050
current_seq = 6966
count = 456
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 49us
(kms_flip:2542) DEBUG: name = flip
last_ts = 299.076569
last_received_ts = 299.076050
last_seq = 6966
current_ts = 299.093231
current_received_ts = 299.092682
current_seq = 6967
count = 457
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 53us
(kms_flip:2542) DEBUG: name = flip
last_ts = 299.093231
last_received_ts = 299.092682
last_seq = 6967
current_ts = 299.109894
current_received_ts = 299.109314
current_seq = 6968
count = 458
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 49us
(kms_flip:2542) DEBUG: name = flip
last_ts = 299.109894
last_received_ts = 299.109314
last_seq = 6968
current_ts = 299.126556
current_received_ts = 299.126038
current_seq = 6969
count = 459
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 47us
(kms_flip:2542) DEBUG: name = flip
last_ts = 299.126556
last_received_ts = 299.126038
last_seq = 6969
current_ts = 299.143219
current_received_ts = 299.142700
current_seq = 6970
count = 460
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 51us
(kms_flip:2542) DEBUG: name = flip
last_ts = 299.143219
last_received_ts = 299.142700
last_seq = 6970
current_ts = 299.159912
current_received_ts = 299.159363
current_seq = 6971
count = 461
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 61us
(kms_flip:2542) DEBUG: name = flip
last_ts = 299.159912
last_received_ts = 299.159363
last_seq = 6971
current_ts = 299.176544
current_received_ts = 299.175995
current_seq = 6972
count = 462
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 42us
(kms_flip:2542) DEBUG: name = flip
last_ts = 299.176544
last_received_ts = 299.175995
last_seq = 6972
current_ts = 299.193237
current_received_ts = 299.192688
current_seq = 6973
count = 463
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 46us
(kms_flip:2542) DEBUG: name = flip
last_ts = 299.193237
last_received_ts = 299.192688
last_seq = 6973
current_ts = 299.209900
current_received_ts = 299.209320
current_seq = 6974
count = 464
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 43us
(kms_flip:2542) DEBUG: name = flip
last_ts = 299.209900
last_received_ts = 299.209320
last_seq = 6974
current_ts = 299.226562
current_received_ts = 299.226044
current_seq = 6975
count = 465
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 47us
(kms_flip:2542) DEBUG: name = flip
last_ts = 299.226562
last_received_ts = 299.226044
last_seq = 6975
current_ts = 299.243225
current_received_ts = 299.242676
current_seq = 6976
count = 466
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 56us
(kms_flip:2542) DEBUG: name = flip
last_ts = 299.243225
last_received_ts = 299.242676
last_seq = 6976
current_ts = 299.259918
current_received_ts = 299.259277
current_seq = 6977
count = 467
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 46us
(kms_flip:2542) DEBUG: name = flip
last_ts = 299.259918
last_received_ts = 299.259277
last_seq = 6977
current_ts = 299.276550
current_received_ts = 299.275940
current_seq = 6978
count = 468
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 71us
(kms_flip:2542) DEBUG: name = flip
last_ts = 299.276550
last_received_ts = 299.275940
last_seq = 6978
current_ts = 299.293243
current_received_ts = 299.292572
current_seq = 6979
count = 469
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 56us
(kms_flip:2542) DEBUG: name = flip
last_ts = 299.293243
last_received_ts = 299.292572
last_seq = 6979
current_ts = 299.309906
current_received_ts = 299.309235
current_seq = 6980
count = 470
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 55us
(kms_flip:2542) DEBUG: name = flip
last_ts = 299.309906
last_received_ts = 299.309235
last_seq = 6980
current_ts = 299.326569
current_received_ts = 299.325928
current_seq = 6981
count = 471
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 49us
(kms_flip:2542) DEBUG: name = flip
last_ts = 299.326569
last_received_ts = 299.325928
last_seq = 6981
current_ts = 299.343231
current_received_ts = 299.342682
current_seq = 6982
count = 472
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 47us
(kms_flip:2542) DEBUG: name = flip
last_ts = 299.343231
last_received_ts = 299.342682
last_seq = 6982
current_ts = 299.359894
current_received_ts = 299.359344
current_seq = 6983
count = 473
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 50us
(kms_flip:2542) DEBUG: name = flip
last_ts = 299.359894
last_received_ts = 299.359344
last_seq = 6983
current_ts = 299.376556
current_received_ts = 299.375916
current_seq = 6984
count = 474
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 53us
(kms_flip:2542) DEBUG: name = flip
last_ts = 299.376556
last_received_ts = 299.375916
last_seq = 6984
current_ts = 299.393219
current_received_ts = 299.392670
current_seq = 6985
count = 475
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 35us
(kms_flip:2542) DEBUG: name = flip
last_ts = 299.393219
last_received_ts = 299.392670
last_seq = 6985
current_ts = 299.409882
current_received_ts = 299.409363
current_seq = 6986
count = 476
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 54us
(kms_flip:2542) DEBUG: name = flip
last_ts = 299.409882
last_received_ts = 299.409363
last_seq = 6986
current_ts = 299.426575
current_received_ts = 299.426056
current_seq = 6987
count = 477
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 53us
(kms_flip:2542) DEBUG: name = flip
last_ts = 299.426575
last_received_ts = 299.426056
last_seq = 6987
current_ts = 299.443237
current_received_ts = 299.442566
current_seq = 6988
count = 478
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 47us
(kms_flip:2542) DEBUG: name = flip
last_ts = 299.443237
last_received_ts = 299.442566
last_seq = 6988
current_ts = 299.459900
current_received_ts = 299.459229
current_seq = 6989
count = 479
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 54us
(kms_flip:2542) DEBUG: name = flip
last_ts = 299.459900
last_received_ts = 299.459229
last_seq = 6989
current_ts = 299.476562
current_received_ts = 299.476013
current_seq = 6990
count = 480
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 51us
(kms_flip:2542) DEBUG: name = flip
last_ts = 299.476562
last_received_ts = 299.476013
last_seq = 6990
current_ts = 299.493225
current_received_ts = 299.492676
current_seq = 6991
count = 481
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 54us
(kms_flip:2542) DEBUG: name = flip
last_ts = 299.493225
last_received_ts = 299.492676
last_seq = 6991
current_ts = 299.509888
current_received_ts = 299.509247
current_seq = 6992
count = 482
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 39us
(kms_flip:2542) DEBUG: name = flip
last_ts = 299.509888
last_received_ts = 299.509247
last_seq = 6992
current_ts = 299.526550
current_received_ts = 299.526001
current_seq = 6993
count = 483
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 58us
(kms_flip:2542) DEBUG: name = flip
last_ts = 299.526550
last_received_ts = 299.526001
last_seq = 6993
current_ts = 299.543213
current_received_ts = 299.542603
current_seq = 6994
count = 484
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 54us
(kms_flip:2542) DEBUG: name = flip
last_ts = 299.543213
last_received_ts = 299.542603
last_seq = 6994
current_ts = 299.559906
current_received_ts = 299.559235
current_seq = 6995
count = 485
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 53us
(kms_flip:2542) DEBUG: name = flip
last_ts = 299.559906
last_received_ts = 299.559235
last_seq = 6995
current_ts = 299.576569
current_received_ts = 299.576019
current_seq = 6996
count = 486
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 56us
(kms_flip:2542) DEBUG: name = flip
last_ts = 299.576569
last_received_ts = 299.576019
last_seq = 6996
current_ts = 299.593201
current_received_ts = 299.592682
current_seq = 6997
count = 487
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 62us
(kms_flip:2542) DEBUG: name = flip
last_ts = 299.593201
last_received_ts = 299.592682
last_seq = 6997
current_ts = 299.609894
current_received_ts = 299.609375
current_seq = 6998
count = 488
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 51us
(kms_flip:2542) DEBUG: name = flip
last_ts = 299.609894
last_received_ts = 299.609375
last_seq = 6998
current_ts = 299.626556
current_received_ts = 299.626038
current_seq = 6999
count = 489
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 68us
(kms_flip:2542) DEBUG: name = flip
last_ts = 299.626556
last_received_ts = 299.626038
last_seq = 6999
current_ts = 299.643219
current_received_ts = 299.642700
current_seq = 7000
count = 490
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 49us
(kms_flip:2542) DEBUG: name = flip
last_ts = 299.643219
last_received_ts = 299.642700
last_seq = 7000
current_ts = 299.659882
current_received_ts = 299.659302
current_seq = 7001
count = 491
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 44us
(kms_flip:2542) DEBUG: name = flip
last_ts = 299.659882
last_received_ts = 299.659302
last_seq = 7001
current_ts = 299.676544
current_received_ts = 299.675903
current_seq = 7002
count = 492
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 45us
(kms_flip:2542) DEBUG: name = flip
last_ts = 299.676544
last_received_ts = 299.675903
last_seq = 7002
current_ts = 299.693207
current_received_ts = 299.692657
current_seq = 7003
count = 493
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 55us
(kms_flip:2542) DEBUG: name = flip
last_ts = 299.693207
last_received_ts = 299.692657
last_seq = 7003
current_ts = 299.709869
current_received_ts = 299.709351
current_seq = 7004
count = 494
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 56us
(kms_flip:2542) DEBUG: name = flip
last_ts = 299.709869
last_received_ts = 299.709351
last_seq = 7004
current_ts = 299.726562
current_received_ts = 299.725922
current_seq = 7005
count = 495
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 49us
(kms_flip:2542) DEBUG: name = flip
last_ts = 299.726562
last_received_ts = 299.725922
last_seq = 7005
current_ts = 299.743195
current_received_ts = 299.742676
current_seq = 7006
count = 496
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 52us
(kms_flip:2542) DEBUG: name = flip
last_ts = 299.743195
last_received_ts = 299.742676
last_seq = 7006
current_ts = 299.759857
current_received_ts = 299.759369
current_seq = 7007
count = 497
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 40us
(kms_flip:2542) DEBUG: name = flip
last_ts = 299.759857
last_received_ts = 299.759369
last_seq = 7007
current_ts = 299.776550
current_received_ts = 299.775909
current_seq = 7008
count = 498
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 56us
(kms_flip:2542) DEBUG: name = flip
last_ts = 299.776550
last_received_ts = 299.775909
last_seq = 7008
current_ts = 299.793213
current_received_ts = 299.792664
current_seq = 7009
count = 499
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 48us
(kms_flip:2542) DEBUG: name = flip
last_ts = 299.793213
last_received_ts = 299.792664
last_seq = 7009
current_ts = 299.809875
current_received_ts = 299.809326
current_seq = 7010
count = 500
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 51us
(kms_flip:2542) DEBUG: name = flip
last_ts = 299.809875
last_received_ts = 299.809326
last_seq = 7010
current_ts = 299.826538
current_received_ts = 299.826019
current_seq = 7011
count = 501
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 50us
(kms_flip:2542) DEBUG: name = flip
last_ts = 299.826538
last_received_ts = 299.826019
last_seq = 7011
current_ts = 299.843201
current_received_ts = 299.842651
current_seq = 7012
count = 502
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 43us
(kms_flip:2542) DEBUG: name = flip
last_ts = 299.843201
last_received_ts = 299.842651
last_seq = 7012
current_ts = 299.859894
current_received_ts = 299.859344
current_seq = 7013
count = 503
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 45us
(kms_flip:2542) DEBUG: name = flip
last_ts = 299.859894
last_received_ts = 299.859344
last_seq = 7013
current_ts = 299.876526
current_received_ts = 299.875977
current_seq = 7014
count = 504
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 49us
(kms_flip:2542) DEBUG: name = flip
last_ts = 299.876526
last_received_ts = 299.875977
last_seq = 7014
current_ts = 299.893219
current_received_ts = 299.892670
current_seq = 7015
count = 505
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 59us
(kms_flip:2542) DEBUG: name = flip
last_ts = 299.893219
last_received_ts = 299.892670
last_seq = 7015
current_ts = 299.909882
current_received_ts = 299.909332
current_seq = 7016
count = 506
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 50us
(kms_flip:2542) DEBUG: name = flip
last_ts = 299.909882
last_received_ts = 299.909332
last_seq = 7016
current_ts = 299.926544
current_received_ts = 299.925995
current_seq = 7017
count = 507
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 56us
(kms_flip:2542) DEBUG: name = flip
last_ts = 299.926544
last_received_ts = 299.925995
last_seq = 7017
current_ts = 299.943207
current_received_ts = 299.942719
current_seq = 7018
count = 508
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 114us
(kms_flip:2542) DEBUG: name = flip
last_ts = 299.943207
last_received_ts = 299.942719
last_seq = 7018
current_ts = 299.959869
current_received_ts = 299.959259
current_seq = 7019
count = 509
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 93us
(kms_flip:2542) DEBUG: name = flip
last_ts = 299.959869
last_received_ts = 299.959259
last_seq = 7019
current_ts = 299.976532
current_received_ts = 299.976044
current_seq = 7020
count = 510
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 99us
(kms_flip:2542) DEBUG: name = flip
last_ts = 299.976532
last_received_ts = 299.976044
last_seq = 7020
current_ts = 299.993225
current_received_ts = 299.992706
current_seq = 7021
count = 511
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 83us
(kms_flip:2542) DEBUG: name = flip
last_ts = 299.993225
last_received_ts = 299.992706
last_seq = 7021
current_ts = 300.009888
current_received_ts = 300.009247
current_seq = 7022
count = 512
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 71us
(kms_flip:2542) DEBUG: name = flip
last_ts = 300.009888
last_received_ts = 300.009247
last_seq = 7022
current_ts = 300.026520
current_received_ts = 300.025909
current_seq = 7023
count = 513
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 97us
(kms_flip:2542) DEBUG: name = flip
last_ts = 300.026520
last_received_ts = 300.025909
last_seq = 7023
current_ts = 300.043213
current_received_ts = 300.042572
current_seq = 7024
count = 514
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 74us
(kms_flip:2542) DEBUG: name = flip
last_ts = 300.043213
last_received_ts = 300.042572
last_seq = 7024
current_ts = 300.059875
current_received_ts = 300.059235
current_seq = 7025
count = 515
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 70us
(kms_flip:2542) DEBUG: name = flip
last_ts = 300.059875
last_received_ts = 300.059235
last_seq = 7025
current_ts = 300.076538
current_received_ts = 300.076019
current_seq = 7026
count = 516
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 64us
(kms_flip:2542) DEBUG: name = flip
last_ts = 300.076538
last_received_ts = 300.076019
last_seq = 7026
current_ts = 300.093201
current_received_ts = 300.092560
current_seq = 7027
count = 517
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 67us
(kms_flip:2542) DEBUG: name = flip
last_ts = 300.093201
last_received_ts = 300.092560
last_seq = 7027
current_ts = 300.109894
current_received_ts = 300.109253
current_seq = 7028
count = 518
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 63us
(kms_flip:2542) DEBUG: name = flip
last_ts = 300.109894
last_received_ts = 300.109253
last_seq = 7028
current_ts = 300.126526
current_received_ts = 300.126099
current_seq = 7029
count = 519
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 63us
(kms_flip:2542) DEBUG: name = flip
last_ts = 300.126526
last_received_ts = 300.126099
last_seq = 7029
current_ts = 300.143188
current_received_ts = 300.142639
current_seq = 7030
count = 520
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 66us
(kms_flip:2542) DEBUG: name = flip
last_ts = 300.143188
last_received_ts = 300.142639
last_seq = 7030
current_ts = 300.159851
current_received_ts = 300.159271
current_seq = 7031
count = 521
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 65us
(kms_flip:2542) DEBUG: name = flip
last_ts = 300.159851
last_received_ts = 300.159271
last_seq = 7031
current_ts = 300.176544
current_received_ts = 300.176086
current_seq = 7032
count = 522
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 67us
(kms_flip:2542) DEBUG: name = flip
last_ts = 300.176544
last_received_ts = 300.176086
last_seq = 7032
current_ts = 300.193207
current_received_ts = 300.192688
current_seq = 7033
count = 523
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 65us
(kms_flip:2542) DEBUG: name = flip
last_ts = 300.193207
last_received_ts = 300.192688
last_seq = 7033
current_ts = 300.209869
current_received_ts = 300.209229
current_seq = 7034
count = 524
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 64us
(kms_flip:2542) DEBUG: name = flip
last_ts = 300.209869
last_received_ts = 300.209229
last_seq = 7034
current_ts = 300.226532
current_received_ts = 300.225922
current_seq = 7035
count = 525
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 64us
(kms_flip:2542) DEBUG: name = flip
last_ts = 300.226532
last_received_ts = 300.225922
last_seq = 7035
current_ts = 300.243195
current_received_ts = 300.242645
current_seq = 7036
count = 526
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 65us
(kms_flip:2542) DEBUG: name = flip
last_ts = 300.243195
last_received_ts = 300.242645
last_seq = 7036
current_ts = 300.259857
current_received_ts = 300.259247
current_seq = 7037
count = 527
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 63us
(kms_flip:2542) DEBUG: name = flip
last_ts = 300.259857
last_received_ts = 300.259247
last_seq = 7037
current_ts = 300.276520
current_received_ts = 300.275970
current_seq = 7038
count = 528
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 63us
(kms_flip:2542) DEBUG: name = flip
last_ts = 300.276520
last_received_ts = 300.275970
last_seq = 7038
current_ts = 300.293182
current_received_ts = 300.292664
current_seq = 7039
count = 529
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 65us
(kms_flip:2542) DEBUG: name = flip
last_ts = 300.293182
last_received_ts = 300.292664
last_seq = 7039
current_ts = 300.309875
current_received_ts = 300.309296
current_seq = 7040
count = 530
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 60us
(kms_flip:2542) DEBUG: name = flip
last_ts = 300.309875
last_received_ts = 300.309296
last_seq = 7040
current_ts = 300.326538
current_received_ts = 300.325897
current_seq = 7041
count = 531
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 65us
(kms_flip:2542) DEBUG: name = flip
last_ts = 300.326538
last_received_ts = 300.325897
last_seq = 7041
current_ts = 300.343201
current_received_ts = 300.342560
current_seq = 7042
count = 532
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 66us
(kms_flip:2542) DEBUG: name = flip
last_ts = 300.343201
last_received_ts = 300.342560
last_seq = 7042
current_ts = 300.359863
current_received_ts = 300.359222
current_seq = 7043
count = 533
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 65us
(kms_flip:2542) DEBUG: name = flip
last_ts = 300.359863
last_received_ts = 300.359222
last_seq = 7043
current_ts = 300.376526
current_received_ts = 300.375916
current_seq = 7044
count = 534
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 62us
(kms_flip:2542) DEBUG: name = flip
last_ts = 300.376526
last_received_ts = 300.375916
last_seq = 7044
current_ts = 300.393188
current_received_ts = 300.392761
current_seq = 7045
count = 535
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 63us
(kms_flip:2542) DEBUG: name = flip
last_ts = 300.393188
last_received_ts = 300.392761
last_seq = 7045
current_ts = 300.409851
current_received_ts = 300.409454
current_seq = 7046
count = 536
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 64us
(kms_flip:2542) DEBUG: name = flip
last_ts = 300.409851
last_received_ts = 300.409454
last_seq = 7046
current_ts = 300.426514
current_received_ts = 300.426117
current_seq = 7047
count = 537
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 64us
(kms_flip:2542) DEBUG: name = flip
last_ts = 300.426514
last_received_ts = 300.426117
last_seq = 7047
current_ts = 300.443176
current_received_ts = 300.442749
current_seq = 7048
count = 538
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 63us
(kms_flip:2542) DEBUG: name = flip
last_ts = 300.443176
last_received_ts = 300.442749
last_seq = 7048
current_ts = 300.459869
current_received_ts = 300.459442
current_seq = 7049
count = 539
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 64us
(kms_flip:2542) DEBUG: name = flip
last_ts = 300.459869
last_received_ts = 300.459442
last_seq = 7049
current_ts = 300.476532
current_received_ts = 300.476105
current_seq = 7050
count = 540
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 63us
(kms_flip:2542) DEBUG: name = flip
last_ts = 300.476532
last_received_ts = 300.476105
last_seq = 7050
current_ts = 300.493195
current_received_ts = 300.492645
current_seq = 7051
count = 541
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 62us
(kms_flip:2542) DEBUG: name = flip
last_ts = 300.493195
last_received_ts = 300.492645
last_seq = 7051
current_ts = 300.509857
current_received_ts = 300.509216
current_seq = 7052
count = 542
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 59us
(kms_flip:2542) DEBUG: name = flip
last_ts = 300.509857
last_received_ts = 300.509216
last_seq = 7052
current_ts = 300.526520
current_received_ts = 300.526031
current_seq = 7053
count = 543
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 63us
(kms_flip:2542) DEBUG: name = flip
last_ts = 300.526520
last_received_ts = 300.526031
last_seq = 7053
current_ts = 300.543182
current_received_ts = 300.542633
current_seq = 7054
count = 544
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 63us
(kms_flip:2542) DEBUG: name = flip
last_ts = 300.543182
last_received_ts = 300.542633
last_seq = 7054
current_ts = 300.559875
current_received_ts = 300.559204
current_seq = 7055
count = 545
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 64us
(kms_flip:2542) DEBUG: name = flip
last_ts = 300.559875
last_received_ts = 300.559204
last_seq = 7055
current_ts = 300.576508
current_received_ts = 300.575897
current_seq = 7056
count = 546
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 67us
(kms_flip:2542) DEBUG: name = flip
last_ts = 300.576508
last_received_ts = 300.575897
last_seq = 7056
current_ts = 300.593201
current_received_ts = 300.592651
current_seq = 7057
count = 547
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 65us
(kms_flip:2542) DEBUG: name = flip
last_ts = 300.593201
last_received_ts = 300.592651
last_seq = 7057
current_ts = 300.609863
current_received_ts = 300.609192
current_seq = 7058
count = 548
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 70us
(kms_flip:2542) DEBUG: name = flip
last_ts = 300.609863
last_received_ts = 300.609192
last_seq = 7058
current_ts = 300.626526
current_received_ts = 300.625885
current_seq = 7059
count = 549
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 102us
(kms_flip:2542) DEBUG: name = flip
last_ts = 300.626526
last_received_ts = 300.625885
last_seq = 7059
current_ts = 300.643188
current_received_ts = 300.642517
current_seq = 7060
count = 550
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 73us
(kms_flip:2542) DEBUG: name = flip
last_ts = 300.643188
last_received_ts = 300.642517
last_seq = 7060
current_ts = 300.659851
current_received_ts = 300.659149
current_seq = 7061
count = 551
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 78us
(kms_flip:2542) DEBUG: name = flip
last_ts = 300.659851
last_received_ts = 300.659149
last_seq = 7061
current_ts = 300.676544
current_received_ts = 300.675873
current_seq = 7062
count = 552
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 100us
(kms_flip:2542) DEBUG: name = flip
last_ts = 300.676544
last_received_ts = 300.675873
last_seq = 7062
current_ts = 300.693207
current_received_ts = 300.692535
current_seq = 7063
count = 553
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 67us
(kms_flip:2542) DEBUG: name = flip
last_ts = 300.693207
last_received_ts = 300.692535
last_seq = 7063
current_ts = 300.709839
current_received_ts = 300.709229
current_seq = 7064
count = 554
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 60us
(kms_flip:2542) DEBUG: name = flip
last_ts = 300.709839
last_received_ts = 300.709229
last_seq = 7064
current_ts = 300.726501
current_received_ts = 300.725952
current_seq = 7065
count = 555
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 59us
(kms_flip:2542) DEBUG: name = flip
last_ts = 300.726501
last_received_ts = 300.725952
last_seq = 7065
current_ts = 300.743195
current_received_ts = 300.742767
current_seq = 7066
count = 556
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 58us
(kms_flip:2542) DEBUG: name = flip
last_ts = 300.743195
last_received_ts = 300.742767
last_seq = 7066
current_ts = 300.759857
current_received_ts = 300.759399
current_seq = 7067
count = 557
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 59us
(kms_flip:2542) DEBUG: name = flip
last_ts = 300.759857
last_received_ts = 300.759399
last_seq = 7067
current_ts = 300.776520
current_received_ts = 300.776062
current_seq = 7068
count = 558
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 60us
(kms_flip:2542) DEBUG: name = flip
last_ts = 300.776520
last_received_ts = 300.776062
last_seq = 7068
current_ts = 300.793182
current_received_ts = 300.792633
current_seq = 7069
count = 559
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 47us
(kms_flip:2542) DEBUG: name = flip
last_ts = 300.793182
last_received_ts = 300.792633
last_seq = 7069
current_ts = 300.809845
current_received_ts = 300.809235
current_seq = 7070
count = 560
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 64us
(kms_flip:2542) DEBUG: name = flip
last_ts = 300.809845
last_received_ts = 300.809235
last_seq = 7070
current_ts = 300.826508
current_received_ts = 300.826080
current_seq = 7071
count = 561
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 58us
(kms_flip:2542) DEBUG: name = flip
last_ts = 300.826508
last_received_ts = 300.826080
last_seq = 7071
current_ts = 300.843170
current_received_ts = 300.842743
current_seq = 7072
count = 562
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 60us
(kms_flip:2542) DEBUG: name = flip
last_ts = 300.843170
last_received_ts = 300.842743
last_seq = 7072
current_ts = 300.859863
current_received_ts = 300.859436
current_seq = 7073
count = 563
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 59us
(kms_flip:2542) DEBUG: name = flip
last_ts = 300.859863
last_received_ts = 300.859436
last_seq = 7073
current_ts = 300.876526
current_received_ts = 300.876068
current_seq = 7074
count = 564
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 73us
(kms_flip:2542) DEBUG: name = flip
last_ts = 300.876526
last_received_ts = 300.876068
last_seq = 7074
current_ts = 300.893158
current_received_ts = 300.892731
current_seq = 7075
count = 565
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 60us
(kms_flip:2542) DEBUG: name = flip
last_ts = 300.893158
last_received_ts = 300.892731
last_seq = 7075
current_ts = 300.909851
current_received_ts = 300.909424
current_seq = 7076
count = 566
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 59us
(kms_flip:2542) DEBUG: name = flip
last_ts = 300.909851
last_received_ts = 300.909424
last_seq = 7076
current_ts = 300.926514
current_received_ts = 300.926086
current_seq = 7077
count = 567
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 59us
(kms_flip:2542) DEBUG: name = flip
last_ts = 300.926514
last_received_ts = 300.926086
last_seq = 7077
current_ts = 300.943176
current_received_ts = 300.942627
current_seq = 7078
count = 568
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 56us
(kms_flip:2542) DEBUG: name = flip
last_ts = 300.943176
last_received_ts = 300.942627
last_seq = 7078
current_ts = 300.959869
current_received_ts = 300.959198
current_seq = 7079
count = 569
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 56us
(kms_flip:2542) DEBUG: name = flip
last_ts = 300.959869
last_received_ts = 300.959198
last_seq = 7079
current_ts = 300.976501
current_received_ts = 300.975922
current_seq = 7080
count = 570
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 67us
(kms_flip:2542) DEBUG: name = flip
last_ts = 300.976501
last_received_ts = 300.975922
last_seq = 7080
current_ts = 300.993164
current_received_ts = 300.992554
current_seq = 7081
count = 571
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 80us
(kms_flip:2542) DEBUG: name = flip
last_ts = 300.993164
last_received_ts = 300.992554
last_seq = 7081
current_ts = 301.009827
current_received_ts = 301.009216
current_seq = 7082
count = 572
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 66us
(kms_flip:2542) DEBUG: name = flip
last_ts = 301.009827
last_received_ts = 301.009216
last_seq = 7082
current_ts = 301.026520
current_received_ts = 301.025818
current_seq = 7083
count = 573
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 29us
(kms_flip:2542) DEBUG: name = flip
last_ts = 301.026520
last_received_ts = 301.025818
last_seq = 7083
current_ts = 301.043182
current_received_ts = 301.042511
current_seq = 7084
count = 574
seq_step = 1
(kms_flip:2542) DEBUG: Vblank took 685us
(kms_flip:2542) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:928:
(kms_flip:2542) CRITICAL: Failed assertion: end - start < 500
(kms_flip:2542) CRITICAL: Last errno: 4, Interrupted system call
(kms_flip:2542) igt_core-INFO: Stack trace:
(kms_flip:2542) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:2542) igt_core-INFO:   #1 ../tests/kms_flip.c:923 run_test_on_crtc_set.constprop.0()
(kms_flip:2542) igt_core-INFO:   #2 ../tests/kms_flip.c:1969 run_pair()
(kms_flip:2542) igt_core-INFO:   #3 ../tests/kms_flip.c:2160 __igt_unique____real_main2040()
(kms_flip:2542) igt_core-INFO:   #4 ../tests/kms_flip.c:2040 main()
(kms_flip:2542) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:2542) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:2542) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest AB-eDP1-DP1: FAIL (10.836s)
Dmesg
<6> [290.192286] Console: switching to colour dummy device 80x25
<6> [290.192737] [IGT] kms_flip: executing
<7> [290.200839] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:225:DP-2]
<7> [290.200859] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:225:DP-2]
<7> [290.201050] 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> [290.201167] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:225:DP-2] disconnected
<7> [290.201971] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:233:DP-3]
<7> [290.201977] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:233:DP-3]
<7> [290.202116] 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> [290.202217] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:233:DP-3] disconnected
<7> [290.202614] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:205:eDP-1]
<7> [290.202620] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:205:eDP-1]
<7> [290.203139] xe 0000:00:02.0: [drm:intel_dp_read_dsc_dpcd [xe]] DSC DPCD: 01 11 00 76 0b 01 01 80 00 01 06 66 08 00 00 00
<7> [290.203456] xe 0000:00:02.0: [drm:intel_dp_print_rates [xe]] source rates: 162000, 216000, 243000, 270000, 324000, 432000, 540000, 675000, 810000
<7> [290.203554] xe 0000:00:02.0: [drm:intel_dp_print_rates [xe]] sink rates: 162000, 216000, 243000, 270000, 324000, 432000, 540000, 810000
<7> [290.203647] xe 0000:00:02.0: [drm:intel_dp_print_rates [xe]] common rates: 162000, 216000, 243000, 270000, 324000, 432000, 540000, 810000
<7> [290.203741] xe 0000:00:02.0: [drm:intel_dp_set_edid [xe]] [CONNECTOR:205:eDP-1] VRR capable: no
<7> [290.203832] xe 0000:00:02.0: [drm:intel_dp_set_edid [xe]] [CONNECTOR:205:eDP-1] DFP max bpc 0, max dotclock 0, TMDS clock 0-0, PCON Max FRL BW 0Gbps
<7> [290.203923] xe 0000:00:02.0: [drm:intel_dp_set_edid [xe]] [CONNECTOR:205:eDP-1] RGB->YcbCr conversion? no, YCbCr 4:2:0 allowed? yes, YCbCr 4:4:4->4:2:0 conversion? no
<7> [290.204484] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:205:eDP-1] probed modes:
<7> [290.204489] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x48 0xa
<7> [290.204506] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:213:DP-1]
<7> [290.204512] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:213:DP-1]
<7> [290.204651] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling AUX_TC1
<7> [290.207795] xe 0000:00:02.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX USBC1/DDI TC1/PHY TC1: Base DPCD: 12 14 c4 81 01 00 01 81 02 02 06 00 00 00 80
<7> [290.207815] xe 0000:00:02.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX USBC1/DDI TC1/PHY TC1: DPCD: 14 1e c4 81 01 00 01 81 02 02 06 00 00 00 81
<7> [290.208559] xe 0000:00:02.0: [drm:intel_dp_init_lttpr_and_dprx_caps [xe]] [CONNECTOR:213:DP-1][ENCODER:212:DDI TC1/PHY TC1][DPRX] LTTPR common capabilities: 20 1e 80 aa 04 00 01 03
<7> [290.209382] xe 0000:00:02.0: [drm:intel_dp_init_lttpr_and_dprx_caps [xe]] [CONNECTOR:213:DP-1][ENCODER:212:DDI TC1/PHY TC1][LTTPR 1] PHY capabilities: 04 03 00
<7> [290.210234] xe 0000:00:02.0: [drm:drm_dp_dump_desc [drm_display_helper]] AUX USBC1/DDI TC1/PHY TC1: LTTPR 1: OUI 98-4f-ee dev-ID HBR HW-rev 10.2 SW-rev 2.11 quirks 0x0000
<7> [290.211711] xe 0000:00:02.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX USBC1/DDI TC1/PHY TC1: Base DPCD: 12 14 c4 81 01 00 01 81 02 02 06 00 00 00 80
<7> [290.211725] xe 0000:00:02.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX USBC1/DDI TC1/PHY TC1: DPCD: 14 1e c4 81 01 00 01 81 02 02 06 00 00 00 81
<7> [290.212383] xe 0000:00:02.0: [drm:drm_dp_dump_desc [drm_display_helper]] AUX USBC1/DDI TC1/PHY TC1: DP sink: OUI 00-0c-e7 dev-ID 27UP85 HW-rev 1.0 SW-rev 3.1 quirks 0x0020
<7> [290.213204] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [ENCODER:212:DDI TC1/PHY TC1] MST support: port: yes, sink: SST, modparam: yes -> enable: SST
<7> [290.218573] xe 0000:00:02.0: [drm:intel_dp_read_dsc_dpcd [xe]] DSC DPCD: 00 12 00 00 00 00 00 00 00 00 00 00 00 00 00 00
<7> [290.219083] xe 0000:00:02.0: [drm:intel_dp_get_dsc_sink_cap [xe]] FEC CAPABILITY: 0
<7> [290.219573] xe 0000:00:02.0: [drm:intel_dp_print_rates [xe]] source rates: 162000, 216000, 243000, 270000, 324000, 432000, 540000, 675000, 810000, 1000000, 2000000
<7> [290.219671] xe 0000:00:02.0: [drm:intel_dp_print_rates [xe]] sink rates: 162000, 270000, 540000, 810000
<7> [290.219764] xe 0000:00:02.0: [drm:intel_dp_print_rates [xe]] common rates: 162000, 270000, 540000, 810000
<7> [290.238051] xe 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:213:DP-1] CEA VCDB 0x6a
<7> [290.238061] xe 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:213:DP-1] Assigning EDID-1.4 digital sink color depth as 10 bpc.
<7> [290.238066] xe 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:213:DP-1] ELD monitor LG ULTRAFINE
<7> [290.238069] xe 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:213:DP-1] ELD size 36, SAD count 1
<7> [290.238098] xe 0000:00:02.0: [drm:intel_dp_set_edid [xe]] [CONNECTOR:213:DP-1] VRR capable: no
<7> [290.238212] xe 0000:00:02.0: [drm:intel_dp_set_edid [xe]] [CONNECTOR:213:DP-1] DFP max bpc 0, max dotclock 0, TMDS clock 0-0, PCON Max FRL BW 0Gbps
<7> [290.238305] xe 0000:00:02.0: [drm:intel_dp_set_edid [xe]] [CONNECTOR:213:DP-1] RGB->YcbCr conversion? no, YCbCr 4:2:0 allowed? yes, YCbCr 4:4:4->4:2:0 conversion? no
<7> [290.239555] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:213:DP-1] probed modes:
<7> [290.239563] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "3840x2160": 60 537600 3840 3888 3920 4000 2160 2163 2168 2240 0x48 0x9
<7> [290.239567] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "3840x2160": 30 262750 3840 3888 3920 4000 2160 2163 2168 2191 0x40 0x9
<7> [290.239571] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2560x1440": 60 241500 2560 2608 2640 2720 1440 1443 1448 1481 0x40 0x9
<7> [290.239574] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x9
<7> [290.239578] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [290.239581] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 60 148352 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [290.239585] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x720": 60 74250 1280 1390 1430 1650 720 725 730 750 0x40 0x5
<7> [290.239589] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x720": 60 74176 1280 1390 1430 1650 720 725 730 750 0x40 0x5
<7> [290.239592] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [290.239596] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "800x600": 60 40000 800 840 968 1056 600 601 605 628 0x40 0x5
<7> [290.239600] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "720x480": 60 27027 720 736 798 858 480 489 495 525 0x40 0xa
<7> [290.239603] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "720x480": 60 27000 720 736 798 858 480 489 495 525 0x40 0xa
<7> [290.239606] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "640x480": 60 25200 640 656 752 800 480 490 492 525 0x40 0xa
<7> [290.239609] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "640x480": 60 25175 640 656 752 800 480 490 492 525 0x40 0xa
<7> [290.239612] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "640x480": 60 25175 640 656 752 800 480 490 492 525 0x40 0xa
<7> [290.239649] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:225:DP-2]
<7> [290.239654] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:225:DP-2]
<7> [290.239807] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:225:DP-2] disconnected
<7> [290.239822] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:233:DP-3]
<7> [290.239826] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:233:DP-3]
<7> [290.239950] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:233:DP-3] disconnected
<6> [290.245472] [IGT] kms_flip: starting subtest 2x-flip-vs-expired-vblank-interruptible
<6> [290.249567] [IGT] kms_flip: starting dynamic subtest AB-eDP1-DP1
<7> [290.250356] xe 0000:00:02.0: [drm:drm_mode_addfb2] [FB:243]
<7> [290.250596] xe 0000:00:02.0: [drm:drm_mode_addfb2] [FB:244]
<7> [290.289113] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:88:pipe A]
<7> [290.289223] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [290.289495] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [290.289610] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [290.289717] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in lane_count (expected 2, found 0)
<7> [290.289820] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in dp_m_n (expected tu 64 data 5048699/8388608 link 210362/524288, found tu 0, data 0/0 link 0/0)
<7> [290.289922] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in output_types (expected 0x00000100, found 0x00000000)
<7> [290.290021] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [290.290119] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1024, found 0)
<7> [290.290216] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 1344, found 0)
<7> [290.290314] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1024, found 0)
<7> [290.290430] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 1344, found 0)
<7> [290.290534] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 1048, found 0)
<7> [290.290636] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 1184, found 0)
<7> [290.290735] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 768, found 0)
<7> [290.290834] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 768, found 0)
<7> [290.290934] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 771, found 0)
<7> [290.291030] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 777, found 0)
<7> [290.291129] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 806, found 0)
<7> [290.291227] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 806, found 0)
<7> [290.291324] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1024, found 0)
<7> [290.291447] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 1344, found 0)
<7> [290.291549] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1024, found 0)
<7> [290.291648] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 1344, found 0)
<7> [290.291747] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 1048, found 0)
<7> [290.291844] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 1184, found 0)
<7> [290.291943] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 768, found 0)
<7> [290.292039] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 768, found 0)
<7> [290.292136] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 771, found 0)
<7> [290.292232] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 777, found 0)
<7> [290.292329] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 806, found 0)
<7> [290.292469] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 806, found 0)
<7> [290.292570] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [290.292669] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)
<7> [290.292767] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [290.292866] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [290.292963] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 65000, found 0)
<7> [290.293059] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 65000, found 0)
<7> [290.293157] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in port_clock (expected 162000, found 0)
<7> [290.293258] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:88:pipe A] fastset requirement not met, forcing full modeset
<7> [290.294018] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xf -> 0xf (total dbuf slices 0xf), mbus joined? no->yes
<7> [290.294166] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:88:pipe A] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x3 -> 0x2
<7> [290.294257] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:145:pipe B] dbuf slices 0xc -> 0xf, ddb (0 - 2048) -> (0 - 4096), active pipes 0x3 -> 0x2
<7> [290.294443] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:33:plane 1A] ddb ( 0 - 2026) -> ( 0 - 0), size 2026 -> 0
<7> [290.294542] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:83:cursor A] ddb (2026 - 2048) -> ( 0 - 0), size 22 -> 0
<7> [290.294636] 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> [290.294726] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:33:plane 1A] lines 1, 6, 6, 6, 6, 6, 0, 0, 0, 2, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [290.294793] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:33:plane 1A] blocks 9, 55, 55, 55, 55, 55, 0, 0, 23, 12, 26 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [290.294852] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:33:plane 1A] min_ddb 11, 62, 62, 62, 62, 62, 0, 0, 24, 15, 27 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [290.294912] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:90:plane 1B] ddb ( 0 - 1996) -> ( 0 - 4044), size 1996 -> 4044
<7> [290.294970] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:140:cursor B] ddb (1996 - 2048) -> (4044 - 4096), size 52 -> 52
<7> [290.295050] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:88:pipe A] data rate 0 num active planes 0
<7> [290.295134] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 2258 qgv_peak_bw: 38400
<7> [290.295215] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 2258 qgv_peak_bw: 38400
<7> [290.295302] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 2258 qgv_peak_bw: 38400
<7> [290.295408] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 2258 qgv_peak_bw: 38400
<7> [290.295516] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 2258
<7> [290.295625] xe 0000:00:02.0: [drm:intel_modeset_calc_cdclk [xe]] New cdclk calculated to be logical 268800 kHz, actual 268800 kHz
<7> [290.295728] xe 0000:00:02.0: [drm:intel_modeset_calc_cdclk [xe]] New voltage level calculated to be logical 0, actual 0
<7> [290.295835] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:88:pipe A] enable: no [modeset]
<7> [290.295913] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:33:plane 1A] fb: [NOFB], visible: no
<7> [290.295990] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:43:plane 2A] fb: [NOFB], visible: no
<7> [290.296065] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:53:plane 3A] fb: [NOFB], visible: no
<7> [290.296139] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:63:plane 4A] fb: [NOFB], visible: no
<7> [290.296212] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:73:plane 5A] fb: [NOFB], visible: no
<7> [290.296284] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:83:cursor A] fb: [NOFB], visible: no
<7> [290.296585] xe 0000:00:02.0: [drm:intel_psr_disable_locked [xe]] Disabling PSR1
<7> [290.312435] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling AUX_TC1
<7> [290.319005] xe 0000:00:02.0: [drm:intel_alpm_disable [xe]] Disabling ALPM
<7> [290.319271] xe 0000:00:02.0: [drm:intel_edp_backlight_off [xe]]
<7> [290.528365] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:205:eDP-1] set backlight PWM = 0
<7> [290.528870] xe 0000:00:02.0: [drm:intel_disable_transcoder [xe]] disabling pipe A
<7> [290.538757] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:204:DDI A/PHY A] PPS 0 turn panel power off
<7> [290.539194] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:204:DDI A/PHY A] PPS 0 wait for panel power off time
<7> [290.539602] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:204:DDI A/PHY A] PPS 0 mask: 0xb0000000 value: 0x00000000 PP_STATUS: 0xa0000002 PP_CONTROL: 0x00000060
<7> [290.592785] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [290.593186] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:204:DDI A/PHY A] - short
<7> [290.599366] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [290.599755] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:204:DDI A/PHY A]
<7> [290.599817] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling AUX_A
<7> [290.600274] xe 0000:00:02.0: [drm:__intel_fbc_disable [xe]] Disabling FBC on [PLANE:33:plane 1A]
<7> [290.600947] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:204:DDI A/PHY A]
<7> [290.601247] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:212:DDI TC1/PHY TC1]
<7> [290.601561] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:214:DP-MST A]
<7> [290.601830] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:215:DP-MST B]
<7> [290.602146] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:216:DP-MST C]
<7> [290.602444] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:224:DDI TC2/PHY TC2]
<7> [290.602700] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:226:DP-MST A]
<7> [290.602952] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:227:DP-MST B]
<7> [290.603198] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:228:DP-MST C]
<7> [290.603485] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:232:DDI TC3/PHY TC3]
<7> [290.603728] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:234:DP-MST A]
<7> [290.604005] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:235:DP-MST B]
<7> [290.604239] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:236:DP-MST C]
<7> [290.604515] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:205:eDP-1]
<7> [290.604819] xe 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: no -> yes (pipe: B)
<7> [290.605090] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 6 (mbus joined: yes)
<7> [290.617768] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_A
<7> [290.618106] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:88:pipe A]
<7> [290.618490] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:145:pipe B]
<7> [290.618739] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.enable (expected yes, found no)
<7> [290.619064] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.active (expected yes, found no)
<7> [290.619364] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in cpu_transcoder (expected 1, found -1)
<7> [290.619671] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in lane_count (expected 4, found 0)
<7> [290.619967] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in dp_m_n (expected tu 64 data 7829367/8388608 link 521957/524288, found tu 0, data 0/0 link 0/0)
<7> [290.620267] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in output_types (expected 0x00000080, found 0x00000000)
<7> [290.620606] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [290.620980] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 3840, found 0)
<7> [290.621304] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 4000, found 0)
<7> [290.621683] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 3840, found 0)
<7> [290.622024] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 4000, found 0)
<7> [290.622394] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 3888, found 0)
<7> [290.622769] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 3920, found 0)
<7> [290.623112] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 2160, found 0)
<7> [290.623552] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 2160, found 0)
<7> [290.623917] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 2163, found 0)
<7> [290.624261] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 2168, found 0)
<7> [290.624617] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 2240, found 0)
<7> [290.624966] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [290.625245] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:204:DDI A/PHY A] - long
<7> [290.625526] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 10
<7> [290.625867] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:204:DDI A/PHY A]
<7> [290.625863] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 2240, found 0)
<7> [290.626231] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 3840, found 0)
<7> [290.626609] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 4000, found 0)
<7> [290.626918] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 3840, found 0)
<7> [290.627249] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 4000, found 0)
<7> [290.627554] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 3888, found 0)
<7> [290.627819] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 3920, found 0)
<7> [290.628081] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 2160, found 0)
<7> [290.628403] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 2160, found 0)
<7> [290.628663] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 2163, found 0)
<7> [290.628899] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 2168, found 0)
<7> [290.629123] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 2240, found 0)
<7> [290.629360] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 2240, found 0)
<7> [290.629593] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [290.629819] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [290.630074] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [290.630280] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in enhanced_framing (expected yes, found no)
<7> [290.630516] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in pipe_bpp (expected 30, found 0)
<7> [290.630754] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 537600, found 0)
<7> [290.630953] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 537600, found 0)
<7> [290.631133] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in port_clock (expected 540000, found 0)
<7> [290.631310] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:145:pipe B] fastset requirement not met, forcing full modeset
<7> [290.631641] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xf -> 0x1 (total dbuf slices 0xf), mbus joined? yes->no
<7> [290.631837] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:145:pipe B] dbuf slices 0xf -> 0x0, ddb (0 - 4096) -> (0 - 0), active pipes 0x2 -> 0x0
<7> [290.632056] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:90:plane 1B] ddb ( 0 - 4044) -> ( 0 - 0), size 4044 -> 0
<7> [290.632242] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:140:cursor B] ddb (4044 - 4096) -> ( 0 - 0), size 52 -> 0
<7> [290.632454] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:90: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> [290.632610] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:90:plane 1B] lines 1, 15, 15, 15, 15, 15, 0, 0, 0, 3, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [290.632761] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:90:plane 1B] blocks 31, 466, 466, 466, 466, 466, 0, 0, 45, 94, 108 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [290.632940] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:90:plane 1B] min_ddb 36, 514, 514, 514, 514, 514, 0, 0, 46, 105, 109 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [290.633133] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:145:pipe B] data rate 0 num active planes 0
<7> [290.633382] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 0 qgv_peak_bw: 38400
<7> [290.633625] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 0 qgv_peak_bw: 38400
<7> [290.633853] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [290.634075] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [290.634348] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 0
<7> [290.634583] xe 0000:00:02.0: [drm:intel_modeset_calc_cdclk [xe]] Can change cdclk via squashing
<7> [290.634832] xe 0000:00:02.0: [drm:intel_modeset_calc_cdclk [xe]] New cdclk calculated to be logical 153600 kHz, actual 153600 kHz
<7> [290.635083] xe 0000:00:02.0: [drm:intel_modeset_calc_cdclk [xe]] New voltage level calculated to be logical 0, actual 0
<7> [290.635390] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:145:pipe B] enable: no [modeset]
<7> [290.635707] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:90:plane 1B] fb: [NOFB], visible: no
<7> [290.635964] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:100:plane 2B] fb: [NOFB], visible: no
<7> [290.636300] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:110:plane 3B] fb: [NOFB], visible: no
<7> [290.636641] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:120:plane 4B] fb: [NOFB], visible: no
<7> [290.636979] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:130:plane 5B] fb: [NOFB], visible: no
<7> [290.637278] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:140:cursor B] fb: [NOFB], visible: no
<7> [290.637760] xe 0000:00:02.0: [drm:intel_audio_codec_disable [xe]] [CONNECTOR:213:DP-1][ENCODER:212:DDI TC1/PHY TC1] Disable audio codec on [CRTC:145:pipe B]
<7> [290.667765] xe 0000:00:02.0: [drm:intel_audio_component_get_eld [xe]] Not valid for port D
<7> [290.683866] xe 0000:00:02.0: [drm:intel_audio_component_get_power [xe]] restored AUD_FREQ_CNTRL to 0x8010
<7> [290.684662] xe 0000:00:02.0: [drm:intel_disable_transcoder [xe]] disabling pipe B
<7> [290.704443] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling AUX_TC1
<7> [290.706880] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling AUX_TC1
<7> [290.707454] xe 0000:00:02.0: [drm:intel_tc_port_reset_mode [xe]] Port D/TC#1: TC port mode reset (dp-alt -> disconnected) pin assignment: C max lanes: 4
<7> [290.707841] xe 0000:00:02.0: [drm:__intel_fbc_disable [xe]] Disabling FBC on [PLANE:90:plane 1B]
<7> [290.708601] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:204:DDI A/PHY A]
<7> [290.708937] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:212:DDI TC1/PHY TC1]
<7> [290.709265] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:214:DP-MST A]
<7> [290.709650] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:215:DP-MST B]
<7> [290.709962] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:216:DP-MST C]
<7> [290.710337] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:224:DDI TC2/PHY TC2]
<7> [290.710646] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:226:DP-MST A]
<7> [290.710932] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:227:DP-MST B]
<7> [290.711217] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:228:DP-MST C]
<7> [290.711545] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:232:DDI TC3/PHY TC3]
<7> [290.711842] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:234:DP-MST A]
<7> [290.712127] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:235:DP-MST B]
<7> [290.712433] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:236:DP-MST C]
<7> [290.712762] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:213:DP-1]
<7> [290.713118] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 3 (mbus joined: no)
<7> [290.713426] xe 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: yes -> no (pipe: *)
<7> [290.713713] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0x1
<7> [290.714196] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_B
<7> [290.714644] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PICA_TC
<7> [290.715007] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_2
<7> [290.715475] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:145:pipe B]
<7> [290.715819] 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> [290.716211] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [290.716563] xe 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=384
<7> [290.717387] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:202:pipe C]
<7> [290.717758] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:145:pipe B]
<7> [290.717834] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:213:DP-1]
<7> [290.718039] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CONNECTOR:213:DP-1] Limiting display bpp to 30 (EDID bpp 30, max requested bpp 36, max platform bpp 36)
<7> [290.718501] xe 0000:00:02.0: [drm:intel_dp_compute_config_limits [xe]] [ENCODER:212:DDI TC1/PHY TC1][CRTC:145:pipe B] DP link limits: pixel clock 65000 kHz DSC off max lanes 4 max rate 810000 max pipe_bpp 30 max link_bpp 30.0000
<7> [290.718839] xe 0000:00:02.0: [drm:intel_dp_compute_link_config [xe]] DP lane count 2 clock 162000 bpp input 30 compressed 0.0000 link rate required 243750 available 324000
<7> [290.719176] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:145:pipe B] hw max bpp: 30, pipe bpp: 30, dithering: 0
<7> [290.719539] xe 0000:00:02.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:212:DDI TC1/PHY TC1] [CRTC:145:pipe B]
<7> [290.719868] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.enable (expected no, found yes)
<7> [290.720173] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.active (expected no, found yes)
<7> [290.720499] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in cpu_transcoder (expected -1, found 1)
<7> [290.720830] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in lane_count (expected 0, found 2)
<7> [290.721094] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in dp_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 6310874/8388608 link 210362/524288)
<7> [290.721365] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in output_types (expected 0x00000000, found 0x00000080)
<7> [290.721648] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [290.721890] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1024)
<7> [290.722124] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 1344)
<7> [290.722369] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1024)
<7> [290.722581] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 1344)
<7> [290.722784] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 1048)
<7> [290.722984] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 1184)
<7> [290.723212] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 768)
<7> [290.723440] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 768)
<7> [290.723652] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 771)
<7> [290.723833] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 777)
<7> [290.724000] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 806)
<7> [290.724175] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 806)
<7> [290.724356] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1024)
<7> [290.724512] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 1344)
<7> [290.724666] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1024)
<7> [290.724814] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 1344)
<7> [290.724956] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 1048)
<7> [290.725091] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 1184)
<7> [290.725225] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 768)
<7> [290.725387] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 768)
<7> [290.725544] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 771)
<7> [290.725686] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 777)
<7> [290.725823] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 806)
<7> [290.725950] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 806)
<7> [290.726073] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [290.726191] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [290.726306] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [290.726437] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in enhanced_framing (expected no, found yes)
<7> [290.726558] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in dpll_hw_state.cx0pll C20
<7> [290.726673] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [290.726785] xe 0000:00:02.0: [drm:intel_cx0pll_dump_hw_state [xe]] c20pll_hw_state:
<7> [290.726898] xe 0000:00:02.0: [drm:intel_cx0pll_dump_hw_state [xe]] tx[0] = 0xbe88, tx[1] = 0x4800, tx[2] = 0x0000
<7> [290.727010] xe 0000:00:02.0: [drm:intel_cx0pll_dump_hw_state [xe]] cmn[0] = 0x0500, cmn[1] = 0x0005, cmn[2] = 0x0000, cmn[3] = 0x0000
<7> [290.727122] xe 0000:00:02.0: [drm:intel_cx0pll_dump_hw_state [xe]] mpllb[0] = 0x108c
<7> [290.727226] xe 0000:00:02.0: [drm:intel_cx0pll_dump_hw_state [xe]] mpllb[1] = 0x2108
<7> [290.727383] xe 0000:00:02.0: [drm:intel_cx0pll_dump_hw_state [xe]] mpllb[2] = 0xcc9c
<7> [290.727494] xe 0000:00:02.0: [drm:intel_cx0pll_dump_hw_state [xe]] mpllb[3] = 0xbfc1
<7> [290.727594] xe 0000:00:02.0: [drm:intel_cx0pll_dump_hw_state [xe]] mpllb[4] = 0x4b9a
<7> [290.727689] xe 0000:00:02.0: [drm:intel_cx0pll_dump_hw_state [xe]] mpllb[5] = 0x3f81
<7> [290.727785] xe 0000:00:02.0: [drm:intel_cx0pll_dump_hw_state [xe]] mpllb[6] = 0x2000
<7> [290.727894] xe 0000:00:02.0: [drm:intel_cx0pll_dump_hw_state [xe]] mpllb[7] = 0x0001
<7> [290.727993] xe 0000:00:02.0: [drm:intel_cx0pll_dump_hw_state [xe]] mpllb[8] = 0x5000
<7> [290.728082] xe 0000:00:02.0: [drm:intel_cx0pll_dump_hw_state [xe]] mpllb[9] = 0x0000
<7> [290.728170] xe 0000:00:02.0: [drm:intel_cx0pll_dump_hw_state [xe]] mpllb[10] = 0x0000
<7> [290.728257] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [290.728351] xe 0000:00:02.0: [drm:intel_cx0pll_dump_hw_state [xe]] c20pll_hw_state:
<7> [290.728446] xe 0000:00:02.0: [drm:intel_cx0pll_dump_hw_state [xe]] tx[0] = 0xbe88, tx[1] = 0x5800, tx[2] = 0x0000
<7> [290.728537] xe 0000:00:02.0: [drm:intel_cx0pll_dump_hw_state [xe]] cmn[0] = 0x0500, cmn[1] = 0x0005, cmn[2] = 0x0000, cmn[3] = 0x0000
<7> [290.728626] xe 0000:00:02.0: [drm:intel_cx0pll_dump_hw_state [xe]] mpllb[0] = 0x50a8
<7> [290.728718] xe 0000:00:02.0: [drm:intel_cx0pll_dump_hw_state [xe]] mpllb[1] = 0x2120
<7> [290.728807] xe 0000:00:02.0: [drm:intel_cx0pll_dump_hw_state [xe]] mpllb[2] = 0xcd9a
<7> [290.728894] xe 0000:00:02.0: [drm:intel_cx0pll_dump_hw_state [xe]] mpllb[3] = 0xbfc1
<7> [290.728971] xe 0000:00:02.0: [drm:intel_cx0pll_dump_hw_state [xe]] mpllb[4] = 0x5ab8
<7> [290.729045] xe 0000:00:02.0: [drm:intel_cx0pll_dump_hw_state [xe]] mpllb[5] = 0x4c34
<7> [290.729115] xe 0000:00:02.0: [drm:intel_cx0pll_dump_hw_state [xe]] mpllb[6] = 0x2000
<7> [290.729186] xe 0000:00:02.0: [drm:intel_cx0pll_dump_hw_state [xe]] mpllb[7] = 0x0001
<7> [290.729255] xe 0000:00:02.0: [drm:intel_cx0pll_dump_hw_state [xe]] mpllb[8] = 0x6000
<7> [290.729333] xe 0000:00:02.0: [drm:intel_cx0pll_dump_hw_state [xe]] mpllb[9] = 0x0000
<7> [290.729414] xe 0000:00:02.0: [drm:intel_cx0pll_dump_hw_state [xe]] mpllb[10] = 0x0000
<7> [290.729500] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in pipe_bpp (expected 0, found 30)
<7> [290.729589] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 65000)
<7> [290.729669] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 65000)
<7> [290.729742] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in port_clock (expected 0, found 162000)
<7> [290.729817] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:145:pipe B] fastset requirement not met, forcing full modeset
<7> [290.729923] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0x1 -> 0xf (total dbuf slices 0xf), mbus joined? no->yes
<7> [290.729993] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:145:pipe B] dbuf slices 0x0 -> 0xf, ddb (0 - 0) -> (0 - 4096), active pipes 0x0 -> 0x2
<7> [290.730087] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:90:plane 1B] ddb ( 0 - 0) -> ( 0 - 4064), size 0 -> 4064
<7> [290.730161] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:140:cursor B] ddb ( 0 - 0) -> (4064 - 4096), size 0 -> 32
<7> [290.730228] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:90: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> [290.730287] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:90:plane 1B] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 4, 6, 6, 6, 6, 6, 0, 0, 0, 4, 0
<7> [290.730363] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:90:plane 1B] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 34, 51, 51, 51, 51, 51, 0, 0, 81, 34, 81
<7> [290.730434] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:90:plane 1B] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 67, 100, 100, 100, 100, 100, 0, 0, 82, 67, 82
<7> [290.730523] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:145:pipe B] data rate 260000 num active planes 1
<7> [290.730637] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 273 qgv_peak_bw: 38400
<7> [290.730728] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 273 qgv_peak_bw: 38400
<7> [290.730814] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 273 qgv_peak_bw: 38400
<7> [290.730906] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 273 qgv_peak_bw: 38400
<7> [290.730988] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 273
<7> [290.731062] xe 0000:00:02.0: [drm:intel_plane_calc_min_cdclk [xe]] [PLANE:90:plane 1B] min cdclk (32500 kHz) > [CRTC:145:pipe B] min cdclk (0 kHz)
<7> [290.731126] xe 0000:00:02.0: [drm:intel_bw_calc_min_cdclk [xe]] new bandwidth min cdclk (5079 kHz) > old min cdclk (0 kHz)
<7> [290.731208] xe 0000:00:02.0: [drm:intel_modeset_calc_cdclk [xe]] Can change cdclk via squashing
<7> [290.731290] xe 0000:00:02.0: [drm:intel_modeset_calc_cdclk [xe]] New cdclk calculated to be logical 192000 kHz, actual 192000 kHz
<7> [290.731394] xe 0000:00:02.0: [drm:intel_modeset_calc_cdclk [xe]] New voltage level calculated to be logical 0, actual 0
<7> [290.731519] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:145:pipe B] enable: yes [modeset]
<7> [290.731635] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: DP (0x80), output format: RGB, sink format: RGB
<7> [290.731747] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: B, pipe bpp: 30, dithering: 0
<7> [290.731840] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [290.731915] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [290.731986] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [290.732075] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [290.732163] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dp m_n: lanes: 2; data_m: 6310874, data_n: 8388608, link_m: 210362, link_n: 524288, tu: 64
<7> [290.732235] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dp m2_n2: lanes: 2; data_m: 0, data_n: 0, link_m: 0, link_n: 0, tu: 0
<7> [290.732305] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: enabled
<7> [290.732400] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled
<7> [290.732490] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] psr: disabled, selective update: disabled, panel replay: disabled, selective fetch: disabled
<7> [290.732579] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] minimum HBlank: 0
<7> [290.732659] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] audio: 1, infoframes: 0, infoframes enabled: 0x0
<7> [290.732731] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] ELD: 10 00 08 00 6c 14 00 01 00 00 00 00 00 00 00 00
<7> [290.732804] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] ELD: 1e 6d c2 5b 4c 47 20 55 4c 54 52 41 46 49 4e 45
<7> [290.732874] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] ELD: 09 07 07 00
<7> [290.732940] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [290.733015] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vblank delay: 0, framestart delay: 1, MSA timing delay: 0
<7> [290.733092] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: no, fixed rr: yes, vmin: 806, vmax: 806, flipline: 806, pipeline full: 0, guardband: 38 vsync start: 35, vsync end: 29
<7> [290.733160] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: vmin vblank: 768, vmax vblank: 768, vmin vtotal: 806, vmax vtotal: 806
<7> [290.733227] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] requested mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [290.733296] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] adjusted mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [290.733393] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=65000, hd=1024 hb=1024-1344 hs=1048-1184 ht=1344, vd=768 vb=768-806 vs=771-777 vt=806, flags=0xa
<7> [290.733483] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [290.733570] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=65000, hd=1024 hb=1024-1344 hs=1048-1184 ht=1344, vd=768 vb=768-806 vs=771-777 vt=806, flags=0xa
<7> [290.733655] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port clock: 162000, pipe src: 1024x768+0+0, pixel rate 65000
<7> [290.733730] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] linetime: 166, ips linetime: 0
<7> [290.733795] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [290.733862] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [290.733931] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [290.734009] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dpll_hw_state: dpll: 0x278d0, dpll_md: 0x5800be88, fp0: 0x5000000, fp1: 0x5
<7> [290.734096] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [290.734168] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [290.734235] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [290.734301] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [290.734393] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [290.734482] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [290.734567] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [290.734651] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [290.734722] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [290.734793] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [290.734869] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [290.734948] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [290.735021] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:90:plane 1B] fb: [FB:243] 1024x768 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [290.735092] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [290.735157] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] src: 1024.000000x768.000000+0.000000+0.000000 dst: 1024x768+0+0
<7> [290.735226] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:100:plane 2B] fb: [NOFB], visible: no
<7> [290.735298] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:110:plane 3B] fb: [NOFB], visible: no
<7> [290.735394] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:120:plane 4B] fb: [NOFB], visible: no
<7> [290.735404] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [290.735489] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:130:plane 5B] fb: [NOFB], visible: no
<7> [290.735573] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:140:cursor B] fb: [NOFB], visible: no
<7> [290.735561] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [290.735687] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
<7> [290.735939] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [290.736075] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [290.736209] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_2
<7> [290.736341] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_B
<7> [290.736491] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PICA_TC
<7> [290.736640] 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> [290.736759] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [290.736857] xe 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=480
<7> [290.736955] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:204:DDI A/PHY A]
<7> [290.737032] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:212:DDI TC1/PHY TC1]
<7> [290.737099] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:214:DP-MST A]
<7> [290.737161] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:215:DP-MST B]
<7> [290.737219] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:216:DP-MST C]
<7> [290.737276] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:224:DDI TC2/PHY TC2]
<7> [290.737349] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:226:DP-MST A]
<7> [290.737428] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:227:DP-MST B]
<7> [290.737506] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:228:DP-MST C]
<7> [290.737582] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:232:DDI TC3/PHY TC3]
<7> [290.737657] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:234:DP-MST A]
<7> [290.737722] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:235:DP-MST B]
<7> [290.737788] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:236:DP-MST C]
<7> [290.737855] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xf
<7> [290.738008] xe 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: no -> yes (pipe: *)
<7> [290.738078] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 8 (mbus joined: yes)
<7> [290.738270] xe 0000:00:02.0: [drm:intel_tc_port_reset_mode [xe]] Port D/TC#1: TC port mode reset (disconnected -> dp-alt) pin assignment: C max lanes: 4
<7> [290.742269] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling AUX_TC1
<7> [290.745973] xe 0000:00:02.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX USBC1/DDI TC1/PHY TC1: Base DPCD: 12 14 c4 81 01 00 01 81 02 02 06 00 00 00 80
<7> [290.745999] xe 0000:00:02.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX USBC1/DDI TC1/PHY TC1: DPCD: 14 1e c4 81 01 00 01 81 02 02 06 00 00 00 81
<7> [290.746725] xe 0000:00:02.0: [drm:intel_dp_init_lttpr_and_dprx_caps [xe]] [CONNECTOR:213:DP-1][ENCODER:212:DDI TC1/PHY TC1][DPRX] LTTPR common capabilities: 20 1e 80 aa 04 00 01 03
<7> [290.748519] xe 0000:00:02.0: [drm:intel_dp_init_lttpr_and_dprx_caps [xe]] [CONNECTOR:213:DP-1][ENCODER:212:DDI TC1/PHY TC1][LTTPR 1] PHY capabilities: 04 03 00
<7> [290.749366] xe 0000:00:02.0: [drm:drm_dp_dump_desc [drm_display_helper]] AUX USBC1/DDI TC1/PHY TC1: LTTPR 1: OUI 98-4f-ee dev-ID HBR HW-rev 10.2 SW-rev 2.11 quirks 0x0000
<7> [290.750882] xe 0000:00:02.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX USBC1/DDI TC1/PHY TC1: Base DPCD: 12 14 c4 81 01 00 01 81 02 02 06 00 00 00 80
<7> [290.750898] xe 0000:00:02.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX USBC1/DDI TC1/PHY TC1: DPCD: 14 1e c4 81 01 00 01 81 02 02 06 00 00 00 81
<7> [290.751393] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:213:DP-1][ENCODER:212:DDI TC1/PHY TC1][DPRX] Using LINK_BW_SET value 06
<7> [290.752658] xe 0000:00:02.0: [drm:intel_dp_set_signal_levels [xe]] [CONNECTOR:213:DP-1][ENCODER:212:DDI TC1/PHY TC1][LTTPR 1] 8b/10b, lanes: 2, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0
<7> [290.753018] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:213:DP-1][ENCODER:212:DDI TC1/PHY TC1][LTTPR 1] Using DP training pattern TPS1
<7> [290.754677] xe 0000:00:02.0: [drm:intel_dp_get_adjust_train [xe]] [CONNECTOR:213:DP-1][ENCODER:212:DDI TC1/PHY TC1][LTTPR 1] 8b/10b, lanes: 2, vswing request: 2/2/2/2, pre-emphasis request: 0/0/0/0
<7> [290.754780] xe 0000:00:02.0: [drm:intel_dp_set_signal_levels [xe]] [CONNECTOR:213:DP-1][ENCODER:212:DDI TC1/PHY TC1][LTTPR 1] 8b/10b, lanes: 2, vswing levels: 2/2/2/2, pre-emphasis levels: 0/0/0/0
<7> [290.756349] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:213:DP-1][ENCODER:212:DDI TC1/PHY TC1][LTTPR 1] Clock recovery OK
<7> [290.756851] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:213:DP-1][ENCODER:212:DDI TC1/PHY TC1][LTTPR 1] Using DP training pattern TPS4
<7> [290.775178] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:213:DP-1][ENCODER:212:DDI TC1/PHY TC1][LTTPR 1] Channel EQ done. DP Training successful
<7> [290.775301] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:213:DP-1][ENCODER:212:DDI TC1/PHY TC1][LTTPR 1] Link Training passed at link rate = 162000, lane count = 2
<7> [290.775875] xe 0000:00:02.0: [drm:intel_dp_set_signal_levels [xe]] [CONNECTOR:213:DP-1][ENCODER:212:DDI TC1/PHY TC1][DPRX] 8b/10b, lanes: 2, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0
<7> [290.776263] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:213:DP-1][ENCODER:212:DDI TC1/PHY TC1][DPRX] Using DP training pattern TPS1
<7> [290.778287] xe 0000:00:02.0: [drm:intel_dp_get_adjust_train [xe]] [CONNECTOR:213:DP-1][ENCODER:212:DDI TC1/PHY TC1][DPRX] 8b/10b, lanes: 2, vswing request: 1/1/0/0, pre-emphasis request: 0/0/0/0
<7> [290.778688] xe 0000:00:02.0: [drm:intel_dp_set_signal_levels [xe]] [CONNECTOR:213:DP-1][ENCODER:212:DDI TC1/PHY TC1][DPRX] 8b/10b, lanes: 2, vswing levels: 1/1/1/1, pre-emphasis levels: 0/0/0/0
<7> [290.780350] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:213:DP-1][ENCODER:212:DDI TC1/PHY TC1][DPRX] Clock recovery OK
<7> [290.780473] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:213:DP-1][ENCODER:212:DDI TC1/PHY TC1][DPRX] Using DP training pattern TPS4
<7> [290.788167] xe 0000:00:02.0: [drm:intel_dp_get_adjust_train [xe]] [CONNECTOR:213:DP-1][ENCODER:212:DDI TC1/PHY TC1][DPRX] 8b/10b, lanes: 2, vswing request: 1/1/0/0, pre-emphasis request: 0/0/0/0
<7> [290.788564] xe 0000:00:02.0: [drm:intel_dp_set_signal_levels [xe]] [CONNECTOR:213:DP-1][ENCODER:212:DDI TC1/PHY TC1][DPRX] 8b/10b, lanes: 2, vswing levels: 1/1/1/1, pre-emphasis levels: 0/0/0/0
<7> [290.796148] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:213:DP-1][ENCODER:212:DDI TC1/PHY TC1][DPRX] Channel EQ done. DP Training successful
<7> [290.796378] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:213:DP-1][ENCODER:212:DDI TC1/PHY TC1][DPRX] Link Training passed at link rate = 162000, lane count = 2
<7> [290.797511] xe 0000:00:02.0: [drm:intel_enable_transcoder [xe]] enabling pipe B
<7> [290.798806] xe 0000:00:02.0: [drm:intel_fbc_update [xe]] reserved 7077888 bytes of contiguous stolen space for FBC, limit: 1
<7> [290.799047] xe 0000:00:02.0: [drm:intel_fbc_update [xe]] Enabling FBC on [PLANE:90:plane 1B]
<7> [290.814881] xe 0000:00:02.0: [drm:intel_audio_codec_enable [xe]] [CONNECTOR:213:DP-1][ENCODER:212:DDI TC1/PHY TC1] Enable audio codec on [CRTC:145:pipe B], 36 bytes ELD
<7> [290.831847] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:213:DP-1]
<7> [290.832299] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:145:pipe B]
<7> [290.832837] xe 0000:00:02.0: [drm:intel_audio_component_get_power [xe]] restored AUD_FREQ_CNTRL to 0x8010
<7> [290.839603] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:88:pipe A]
<7> [290.839711] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:205:eDP-1]
<7> [290.839986] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CONNECTOR:205:eDP-1] Limiting display bpp to 24 (EDID bpp 0, max requested bpp 36, max platform bpp 36)
<7> [290.840525] xe 0000:00:02.0: [drm:intel_dp_compute_config_limits [xe]] [ENCODER:204:DDI A/PHY A][CRTC:88:pipe A] DP link limits: pixel clock 65000 kHz DSC off max lanes 4 max rate 810000 max pipe_bpp 24 max link_bpp 24.0000
<7> [290.840913] xe 0000:00:02.0: [drm:intel_dp_compute_link_config [xe]] DP lane count 2 clock 162000 bpp input 24 compressed 0.0000 link rate required 195000 available 324000
<7> [290.841350] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:88:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [290.841755] xe 0000:00:02.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:204:DDI A/PHY A] [CRTC:88:pipe A]
<7> [290.842169] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [290.842563] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [290.842922] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [290.843267] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in lane_count (expected 0, found 2)
<7> [290.843611] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in dp_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 5048699/8388608 link 210362/524288)
<7> [290.843935] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000100)
<7> [290.844227] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [290.844540] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1024)
<7> [290.844826] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 1344)
<7> [290.845093] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1024)
<7> [290.845381] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 1344)
<7> [290.845631] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 1048)
<7> [290.845875] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 1184)
<7> [290.846104] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 768)
<7> [290.846358] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 768)
<7> [290.846590] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 771)
<7> [290.846808] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 777)
<7> [290.847013] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 806)
<7> [290.847214] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 806)
<7> [290.847458] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1024)
<7> [290.847656] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 1344)
<7> [290.847842] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1024)
<7> [290.848023] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 1344)
<7> [290.848199] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 1048)
<7> [290.848408] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 1184)
<7> [290.848584] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 768)
<7> [290.848752] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 768)
<7> [290.848914] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 771)
<7> [290.849072] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 777)
<7> [290.849224] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 806)
<7> [290.849411] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 806)
<7> [290.849364] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling AUX_TC1
<7> [290.849572] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [290.849722] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [290.849859] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [290.849996] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [290.850130] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 65000)
<7> [290.850261] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 65000)
<7> [290.850418] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in port_clock (expected 0, found 162000)
<7> [290.850553] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:88:pipe A] fastset requirement not met, forcing full modeset
<7> [290.850778] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xf -> 0xf (total dbuf slices 0xf), mbus joined? yes->no
<7> [290.850885] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:88:pipe A] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x2 -> 0x3
<7> [290.850989] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:145:pipe B] dbuf slices 0xf -> 0xc, ddb (0 - 4096) -> (0 - 2048), active pipes 0x2 -> 0x3
<7> [290.851123] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:33:plane 1A] ddb ( 0 - 0) -> ( 0 - 2026), size 0 -> 2026
<7> [290.851206] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:83:cursor A] ddb ( 0 - 0) -> (2026 - 2048), size 0 -> 22
<7> [290.851284] 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> [290.851371] 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, 6, 6, 6, 6, 6, 0, 0, 0, 4, 0
<7> [290.851452] 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 -> 34, 51, 51, 51, 51, 51, 0, 0, 81, 34, 81
<7> [290.851526] 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 -> 67, 100, 100, 100, 100, 100, 0, 0, 82, 67, 82
<7> [290.851599] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:90:plane 1B] ddb ( 0 - 4064) -> ( 0 - 2026), size 4064 -> 2026
<7> [290.851671] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:140:cursor B] ddb (4064 - 4096) -> (2026 - 2048), size 32 -> 22
<7> [290.851768] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:88:pipe A] data rate 260000 num active planes 1
<7> [290.851885] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 546 qgv_peak_bw: 38400
<7> [290.851992] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 546 qgv_peak_bw: 38400
<7> [290.852099] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 546 qgv_peak_bw: 38400
<7> [290.852208] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 546 qgv_peak_bw: 38400
<7> [290.852327] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 546
<7> [290.852441] xe 0000:00:02.0: [drm:intel_plane_calc_min_cdclk [xe]] [PLANE:33:plane 1A] min cdclk (32500 kHz) > [CRTC:88:pipe A] min cdclk (0 kHz)
<7> [290.852526] xe 0000:00:02.0: [drm:intel_bw_calc_min_cdclk [xe]] new bandwidth min cdclk (8125 kHz) > old min cdclk (5079 kHz)
<7> [290.852637] xe 0000:00:02.0: [drm:intel_modeset_calc_cdclk [xe]] New cdclk calculated to be logical 192000 kHz, actual 192000 kHz
<7> [290.852746] xe 0000:00:02.0: [drm:intel_modeset_calc_cdclk [xe]] New voltage level calculated to be logical 0, actual 0
<7> [290.852886] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:88:pipe A] enable: yes [modeset]
<7> [290.852999] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: EDP (0x100), output format: RGB, sink format: RGB
<7> [290.853104] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [290.853211] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [290.853328] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [290.853437] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [290.853543] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [290.853648] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dp m_n: lanes: 2; data_m: 5048699, data_n: 8388608, link_m: 210362, link_n: 524288, tu: 64
<7> [290.853752] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dp m2_n2: lanes: 2; data_m: 0, data_n: 0, link_m: 0, link_n: 0, tu: 0
<7> [290.853856] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: disabled
<7> [290.853959] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled
<7> [290.854065] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] psr: enabled, selective update: disabled, panel replay: disabled, selective fetch: disabled
<7> [290.854171] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] minimum HBlank: 0
<7> [290.854276] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] audio: 0, infoframes: 0, infoframes enabled: 0x4
<7> [290.854392] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] DP SDP: VSC, revision 2, length 8
<7> [290.854502] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pixelformat: RGB
<7> [290.854606] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] colorimetry: sRGB
<7> [290.854708] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] bpc: 0
<7> [290.854812] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dynamic range: VESA range
<7> [290.854917] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] content type: Not defined
<7> [290.855022] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [290.855126] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vblank delay: 0, framestart delay: 1, MSA timing delay: 0
<7> [290.855229] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: no, fixed rr: yes, vmin: 806, vmax: 806, flipline: 806, pipeline full: 0, guardband: 38 vsync start: 35, vsync end: 29
<7> [290.855345] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: vmin vblank: 768, vmax vblank: 768, vmin vtotal: 806, vmax vtotal: 806
<7> [290.855452] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] requested mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x48 0xa
<7> [290.855555] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] adjusted mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x48 0xa
<7> [290.855659] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=65000, hd=1024 hb=1024-1344 hs=1048-1184 ht=1344, vd=768 vb=768-806 vs=771-777 vt=806, flags=0xa
<7> [290.855766] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [290.855871] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=65000, hd=1024 hb=1024-1344 hs=1048-1184 ht=1344, vd=768 vb=768-806 vs=771-777 vt=806, flags=0xa
<7> [290.855978] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port clock: 162000, pipe src: 1024x768+0+0, pixel rate 65000
<7> [290.856082] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] linetime: 166, ips linetime: 0
<7> [290.856184] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [290.856287] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [290.856397] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [290.856503] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dpll_hw_state: dpll: 0x278d0, dpll_md: 0xb42110, fp0: 0x130, fp1: 0x1000000
<7> [290.856609] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [290.856712] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [290.856815] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [290.856916] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [290.857017] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [290.857118] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [290.857217] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [290.857328] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [290.857435] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [290.857536] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [290.857636] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [290.857736] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [290.857835] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:33:plane 1A] fb: [FB:243] 1024x768 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [290.857937] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [290.858035] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] src: 1024.000000x768.000000+0.000000+0.000000 dst: 1024x768+0+0
<7> [290.858134] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:43:plane 2A] fb: [NOFB], visible: no
<7> [290.858233] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:53:plane 3A] fb: [NOFB], visible: no
<7> [290.858351] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:63:plane 4A] fb: [NOFB], visible: no
<7> [290.858456] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:73:plane 5A] fb: [NOFB], visible: no
<7> [290.858557] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:83:cursor A] fb: [NOFB], visible: no
<7> [290.859272] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_A
<7> [290.859502] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:204:DDI A/PHY A]
<7> [290.859606] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:212:DDI TC1/PHY TC1]
<7> [290.859691] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:214:DP-MST A]
<7> [290.859771] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:215:DP-MST B]
<7> [290.859850] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:216:DP-MST C]
<7> [290.859929] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:224:DDI TC2/PHY TC2]
<7> [290.860007] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:226:DP-MST A]
<7> [290.860086] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:227:DP-MST B]
<7> [290.860163] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:228:DP-MST C]
<7> [290.860240] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:232:DDI TC3/PHY TC3]
<7> [290.860332] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:234:DP-MST A]
<7> [290.860416] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:235:DP-MST B]
<7> [290.860493] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:236:DP-MST C]
<7> [290.864864] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [290.864967] xe 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: yes -> no (pipe: B)
<7> [290.881554] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling AUX_A
<7> [290.883140] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:204:DDI A/PHY A] PPS 0 turn panel power on
<7> [290.883423] xe 0000:00:02.0: [drm:wait_panel_power_cycle [xe]] [ENCODER:204:DDI A/PHY A] PPS 0 wait for panel power cycle (217 ms remaining)
<7> [291.105393] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:204:DDI A/PHY A] PPS 0 mask: 0xb800000f value: 0x00000000 PP_STATUS: 0x08000001 PP_CONTROL: 0x00000060
<7> [291.206227] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [291.206487] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:204:DDI A/PHY A] PPS 0 wait for panel power on
<7> [291.206720] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:204:DDI A/PHY A] PPS 0 mask: 0xb000000f value: 0x80000008 PP_STATUS: 0x9000000a PP_CONTROL: 0x00000063
<7> [291.242786] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [291.243131] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:204:DDI A/PHY A] - long
<7> [291.243424] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 20
<7> [291.243742] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:204:DDI A/PHY A]
<7> [291.248375] 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> [291.248383] 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> [291.417733] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [291.419915] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:204:DDI A/PHY A] PPS 0 turning VDD on
<7> [291.420202] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:204:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006b
<7> [291.422354] xe 0000:00:02.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX A/DDI A/PHY A: DPCD: 14 1e 44 41 00 00 01 c0 02 00 02 00 00 0b 80
<7> [291.423380] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:205:eDP-1][ENCODER:204:DDI A/PHY A][DPRX] Reloading eDP link rates
<7> [291.424134] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:205:eDP-1][ENCODER:204:DDI A/PHY A][DPRX] Using LINK_RATE_SET value 00
<7> [291.425214] xe 0000:00:02.0: [drm:intel_dp_set_signal_levels [xe]] [CONNECTOR:205:eDP-1][ENCODER:204:DDI A/PHY A][DPRX] 8b/10b, lanes: 2, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0
<7> [291.426086] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:205:eDP-1][ENCODER:204:DDI A/PHY A][DPRX] Using DP training pattern TPS1
<7> [291.427043] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:205:eDP-1][ENCODER:204:DDI A/PHY A][DPRX] Clock recovery OK
<7> [291.427323] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:205:eDP-1][ENCODER:204:DDI A/PHY A][DPRX] Using DP training pattern TPS3
<7> [291.428619] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:205:eDP-1][ENCODER:204:DDI A/PHY A][DPRX] Channel EQ done. DP Training successful
<7> [291.428881] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:205:eDP-1][ENCODER:204:DDI A/PHY A][DPRX] Link Training passed at link rate = 162000, lane count = 2
<7> [291.429828] xe 0000:00:02.0: [drm:intel_enable_transcoder [xe]] enabling pipe A
<7> [291.430256] xe 0000:00:02.0: [drm:intel_edp_backlight_on [xe]]
<7> [291.430568] xe 0000:00:02.0: [drm:intel_backlight_enable [xe]] pipe A
<7> [291.430931] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:205:eDP-1] set backlight PWM = 13892
<7> [291.431850] xe 0000:00:02.0: [drm:intel_fbc_update [xe]] reserved 7077888 bytes of contiguous stolen space for FBC, limit: 1
<7> [291.432142] xe 0000:00:02.0: [drm:intel_fbc_update [xe]] Enabling FBC on [PLANE:33:plane 1A]
<7> [291.447090] xe 0000:00:02.0: [drm:intel_psr_post_plane_update [xe]] Enabling PSR1
<7> [291.448660] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:205:eDP-1]
<7> [291.449047] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:88:pipe A]
<7> [292.848471] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling AUX_TC1
<7> [292.865321] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling AUX_TC1
<7> [294.001284] xe 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [xe]] [ENCODER:204:DDI A/PHY A] PPS 0 turning VDD off
<7> [294.001776] xe 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [xe]] [ENCODER:204:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x00000067
<6> [301.086460] [IGT] kms_flip: finished subtest AB-eDP1-DP1, FAIL
Created at 2025-09-25 06:25:41