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

Result: Fail

i915_display_info4 igt_runner4 results4.json results4-xe-load.json guc_logs4.tar i915_display_info_post_exec4 serial_data4 boot4 dmesg4

DetailValue
Duration 9.10 seconds
Hostname 
shard-lnl-7
Igt-Version 
IGT-Version: 2.4-g1ba8cce0f (x86_64) (Linux: 7.1.0-rc6-lgci-xe-xe-5185-8147d1b5e856716db-debug+ x86_64)
Out 
Starting dynamic subtest: C-eDP1
  2880x1800: 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa 
Stack trace:
  #0 ../lib/igt_core.c:2106 __igt_fail_assert()
  #1 ../tests/kms_flip.c:926 run_test_on_crtc_set()
  #2 ../tests/kms_flip.c:1920 run_test()
  #3 ../tests/kms_flip.c:2184 __igt_unique____real_main2105()
  #4 ../tests/kms_flip.c:2105 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest C-eDP1: FAIL (9.095s)
Subtest flip-vs-expired-vblank: FAIL (25.151s)
Stack trace:
  #0 ../lib/igt_core.c:2106 __igt_fail_assert()
  #1 ../lib/igt_kms.c:2354 kmstest_set_connector_dpms()
  #2 ../lib/igt_core.c:2992 igt_atexit_handler()
  #3 [erand48_r+0x1d6]
  #4 [exit+0x1e]
  #5 ../lib/igt_core.c:2447 igt_exit()
  #6 [main+0x3f]
  #7 [__libc_init_first+0x8a]
  #8 [__libc_start_main+0x8b]
  #9 [_start+0x25]
Err 
Starting dynamic subtest: C-eDP1
[265.638111] (kms_flip:12185) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931:
[265.638185] (kms_flip:12185) CRITICAL: Failed assertion: end - start < 500
Dynamic subtest C-eDP1 failed.
**** DEBUG ****
[263.621218] (kms_flip:12185) DEBUG: name = flip
last_ts = 265.299652
last_received_ts = 265.299072
last_seq = 344
current_ts = 265.316315
current_received_ts = 265.315735
current_seq = 345
count = 336
seq_step = 1
[263.621297] (kms_flip:12185) DEBUG: Vblank took 73us
[263.637907] (kms_flip:12185) DEBUG: name = flip
last_ts = 265.316315
last_received_ts = 265.315735
last_seq = 345
current_ts = 265.332977
current_received_ts = 265.332428
current_seq = 346
count = 337
seq_step = 1
[263.637986] (kms_flip:12185) DEBUG: Vblank took 73us
[263.654554] (kms_flip:12185) DEBUG: name = flip
last_ts = 265.332977
last_received_ts = 265.332428
last_seq = 346
current_ts = 265.349640
current_received_ts = 265.349060
current_seq = 347
count = 338
seq_step = 1
[263.654636] (kms_flip:12185) DEBUG: Vblank took 75us
[263.671213] (kms_flip:12185) DEBUG: name = flip
last_ts = 265.349640
last_received_ts = 265.349060
last_seq = 347
current_ts = 265.366302
current_received_ts = 265.365723
current_seq = 348
count = 339
seq_step = 1
[263.671292] (kms_flip:12185) DEBUG: Vblank took 72us
[263.687911] (kms_flip:12185) DEBUG: name = flip
last_ts = 265.366302
last_received_ts = 265.365723
last_seq = 348
current_ts = 265.382965
current_received_ts = 265.382416
current_seq = 349
count = 340
seq_step = 1
[263.687990] (kms_flip:12185) DEBUG: Vblank took 73us
[263.704552] (kms_flip:12185) DEBUG: name = flip
last_ts = 265.382965
last_received_ts = 265.382416
last_seq = 349
current_ts = 265.399658
current_received_ts = 265.399078
current_seq = 350
count = 341
seq_step = 1
[263.704632] (kms_flip:12185) DEBUG: Vblank took 73us
[263.721203] (kms_flip:12185) DEBUG: name = flip
last_ts = 265.399658
last_received_ts = 265.399078
last_seq = 350
current_ts = 265.416290
current_received_ts = 265.415710
current_seq = 351
count = 342
seq_step = 1
[263.721281] (kms_flip:12185) DEBUG: Vblank took 71us
[263.737872] (kms_flip:12185) DEBUG: name = flip
last_ts = 265.416290
last_received_ts = 265.415710
last_seq = 351
current_ts = 265.432983
current_received_ts = 265.432404
current_seq = 352
count = 343
seq_step = 1
[263.737952] (kms_flip:12185) DEBUG: Vblank took 73us
[263.754546] (kms_flip:12185) DEBUG: name = flip
last_ts = 265.432983
last_received_ts = 265.432404
last_seq = 352
current_ts = 265.449646
current_received_ts = 265.449066
current_seq = 353
count = 344
seq_step = 1
[263.754627] (kms_flip:12185) DEBUG: Vblank took 74us
[263.771192] (kms_flip:12185) DEBUG: name = flip
last_ts = 265.449646
last_received_ts = 265.449066
last_seq = 353
current_ts = 265.466309
current_received_ts = 265.465698
current_seq = 354
count = 345
seq_step = 1
[263.771270] (kms_flip:12185) DEBUG: Vblank took 71us
[263.787862] (kms_flip:12185) DEBUG: name = flip
last_ts = 265.466309
last_received_ts = 265.465698
last_seq = 354
current_ts = 265.482971
current_received_ts = 265.482391
current_seq = 355
count = 346
seq_step = 1
[263.787941] (kms_flip:12185) DEBUG: Vblank took 73us
[263.804525] (kms_flip:12185) DEBUG: name = flip
last_ts = 265.482971
last_received_ts = 265.482391
last_seq = 355
current_ts = 265.499634
current_received_ts = 265.499054
current_seq = 356
count = 347
seq_step = 1
[263.804610] (kms_flip:12185) DEBUG: Vblank took 79us
[263.821198] (kms_flip:12185) DEBUG: name = flip
last_ts = 265.499634
last_received_ts = 265.499054
last_seq = 356
current_ts = 265.516296
current_received_ts = 265.515717
current_seq = 357
count = 348
seq_step = 1
[263.821276] (kms_flip:12185) DEBUG: Vblank took 72us
[263.837878] (kms_flip:12185) DEBUG: name = flip
last_ts = 265.516296
last_received_ts = 265.515717
last_seq = 357
current_ts = 265.532959
current_received_ts = 265.532410
current_seq = 358
count = 349
seq_step = 1
[263.837949] (kms_flip:12185) DEBUG: Vblank took 66us
[263.854443] (kms_flip:12185) DEBUG: name = flip
last_ts = 265.532959
last_received_ts = 265.532410
last_seq = 358
current_ts = 265.549622
current_received_ts = 265.548981
current_seq = 359
count = 350
seq_step = 1
[263.854532] (kms_flip:12185) DEBUG: Vblank took 83us
[263.871180] (kms_flip:12185) DEBUG: name = flip
last_ts = 265.549622
last_received_ts = 265.548981
last_seq = 359
current_ts = 265.566284
current_received_ts = 265.565704
current_seq = 360
count = 351
seq_step = 1
[263.871261] (kms_flip:12185) DEBUG: Vblank took 75us
[263.887865] (kms_flip:12185) DEBUG: name = flip
last_ts = 265.566284
last_received_ts = 265.565704
last_seq = 360
current_ts = 265.582947
current_received_ts = 265.582397
current_seq = 361
count = 352
seq_step = 1
[263.887943] (kms_flip:12185) DEBUG: Vblank took 71us
[263.904536] (kms_flip:12185) DEBUG: name = flip
last_ts = 265.582947
last_received_ts = 265.582397
last_seq = 361
current_ts = 265.599609
current_received_ts = 265.599060
current_seq = 362
count = 353
seq_step = 1
[263.904617] (kms_flip:12185) DEBUG: Vblank took 74us
[263.921177] (kms_flip:12185) DEBUG: name = flip
last_ts = 265.599609
last_received_ts = 265.599060
last_seq = 362
current_ts = 265.616272
current_received_ts = 265.615692
current_seq = 363
count = 354
seq_step = 1
[263.921255] (kms_flip:12185) DEBUG: Vblank took 71us
[263.937866] (kms_flip:12185) DEBUG: name = flip
last_ts = 265.616272
last_received_ts = 265.615692
last_seq = 363
current_ts = 265.632935
current_received_ts = 265.632385
current_seq = 364
count = 355
seq_step = 1
[263.937946] (kms_flip:12185) DEBUG: Vblank took 74us
[263.954524] (kms_flip:12185) DEBUG: name = flip
last_ts = 265.632935
last_received_ts = 265.632385
last_seq = 364
current_ts = 265.649597
current_received_ts = 265.649048
current_seq = 365
count = 356
seq_step = 1
[263.954603] (kms_flip:12185) DEBUG: Vblank took 72us
[263.971166] (kms_flip:12185) DEBUG: name = flip
last_ts = 265.649597
last_received_ts = 265.649048
last_seq = 365
current_ts = 265.666260
current_received_ts = 265.665680
current_seq = 366
count = 357
seq_step = 1
[263.971245] (kms_flip:12185) DEBUG: Vblank took 72us
[263.987846] (kms_flip:12185) DEBUG: name = flip
last_ts = 265.666260
last_received_ts = 265.665680
last_seq = 366
current_ts = 265.682953
current_received_ts = 265.682373
current_seq = 367
count = 358
seq_step = 1
[263.987924] (kms_flip:12185) DEBUG: Vblank took 71us
[264.004518] (kms_flip:12185) DEBUG: name = flip
last_ts = 265.682953
last_received_ts = 265.682373
last_seq = 367
current_ts = 265.699615
current_received_ts = 265.699036
current_seq = 368
count = 359
seq_step = 1
[264.004599] (kms_flip:12185) DEBUG: Vblank took 74us
[264.021160] (kms_flip:12185) DEBUG: name = flip
last_ts = 265.699615
last_received_ts = 265.699036
last_seq = 368
current_ts = 265.716278
current_received_ts = 265.715698
current_seq = 369
count = 360
seq_step = 1
[264.021238] (kms_flip:12185) DEBUG: Vblank took 72us
[264.037858] (kms_flip:12185) DEBUG: name = flip
last_ts = 265.716278
last_received_ts = 265.715698
last_seq = 369
current_ts = 265.732941
current_received_ts = 265.732391
current_seq = 370
count = 361
seq_step = 1
[264.037937] (kms_flip:12185) DEBUG: Vblank took 72us
[264.054517] (kms_flip:12185) DEBUG: name = flip
last_ts = 265.732941
last_received_ts = 265.732391
last_seq = 370
current_ts = 265.749603
current_received_ts = 265.749054
current_seq = 371
count = 362
seq_step = 1
[264.054599] (kms_flip:12185) DEBUG: Vblank took 74us
[264.071159] (kms_flip:12185) DEBUG: name = flip
last_ts = 265.749603
last_received_ts = 265.749054
last_seq = 371
current_ts = 265.766266
current_received_ts = 265.765686
current_seq = 372
count = 363
seq_step = 1
[264.071239] (kms_flip:12185) DEBUG: Vblank took 73us
[264.087801] (kms_flip:12185) DEBUG: name = flip
last_ts = 265.766266
last_received_ts = 265.765686
last_seq = 372
current_ts = 265.782928
current_received_ts = 265.782349
current_seq = 373
count = 364
seq_step = 1
[264.087882] (kms_flip:12185) DEBUG: Vblank took 74us
[264.104506] (kms_flip:12185) DEBUG: name = flip
last_ts = 265.782928
last_received_ts = 265.782349
last_seq = 373
current_ts = 265.799591
current_received_ts = 265.799042
current_seq = 374
count = 365
seq_step = 1
[264.104587] (kms_flip:12185) DEBUG: Vblank took 75us
[264.121150] (kms_flip:12185) DEBUG: name = flip
last_ts = 265.799591
last_received_ts = 265.799042
last_seq = 374
current_ts = 265.816254
current_received_ts = 265.815674
current_seq = 375
count = 366
seq_step = 1
[264.121227] (kms_flip:12185) DEBUG: Vblank took 72us
[264.137790] (kms_flip:12185) DEBUG: name = flip
last_ts = 265.816254
last_received_ts = 265.815674
last_seq = 375
current_ts = 265.832916
current_received_ts = 265.832336
current_seq = 376
count = 367
seq_step = 1
[264.137872] (kms_flip:12185) DEBUG: Vblank took 75us
[264.154500] (kms_flip:12185) DEBUG: name = flip
last_ts = 265.832916
last_received_ts = 265.832336
last_seq = 376
current_ts = 265.849579
current_received_ts = 265.849030
current_seq = 377
count = 368
seq_step = 1
[264.154586] (kms_flip:12185) DEBUG: Vblank took 79us
[264.171148] (kms_flip:12185) DEBUG: name = flip
last_ts = 265.849579
last_received_ts = 265.849030
last_seq = 377
current_ts = 265.866241
current_received_ts = 265.865692
current_seq = 378
count = 369
seq_step = 1
[264.171227] (kms_flip:12185) DEBUG: Vblank took 72us
[264.187789] (kms_flip:12185) DEBUG: name = flip
last_ts = 265.866241
last_received_ts = 265.865692
last_seq = 378
current_ts = 265.882935
current_received_ts = 265.882324
current_seq = 379
count = 370
seq_step = 1
[264.187869] (kms_flip:12185) DEBUG: Vblank took 74us
[264.204488] (kms_flip:12185) DEBUG: name = flip
last_ts = 265.882935
last_received_ts = 265.882324
last_seq = 379
current_ts = 265.899597
current_received_ts = 265.898987
current_seq = 380
count = 371
seq_step = 1
[264.204571] (kms_flip:12185) DEBUG: Vblank took 77us
[264.221017] (kms_flip:12185) DEBUG: name = flip
last_ts = 265.899597
last_received_ts = 265.898987
last_seq = 380
current_ts = 265.916260
current_received_ts = 265.915558
current_seq = 381
count = 372
seq_step = 1
[264.221099] (kms_flip:12185) DEBUG: Vblank took 75us
[264.237780] (kms_flip:12185) DEBUG: name = flip
last_ts = 265.916260
last_received_ts = 265.915558
last_seq = 381
current_ts = 265.932922
current_received_ts = 265.932312
current_seq = 382
count = 373
seq_step = 1
[264.237861] (kms_flip:12185) DEBUG: Vblank took 74us
[264.254484] (kms_flip:12185) DEBUG: name = flip
last_ts = 265.932922
last_received_ts = 265.932312
last_seq = 382
current_ts = 265.949585
current_received_ts = 265.949005
current_seq = 383
count = 374
seq_step = 1
[264.254565] (kms_flip:12185) DEBUG: Vblank took 75us
[264.271119] (kms_flip:12185) DEBUG: name = flip
last_ts = 265.949585
last_received_ts = 265.949005
last_seq = 383
current_ts = 265.966248
current_received_ts = 265.965668
current_seq = 384
count = 375
seq_step = 1
[264.271200] (kms_flip:12185) DEBUG: Vblank took 76us
[264.287773] (kms_flip:12185) DEBUG: name = flip
last_ts = 265.966248
last_received_ts = 265.965668
last_seq = 384
current_ts = 265.982910
current_received_ts = 265.982300
current_seq = 385
count = 376
seq_step = 1
[264.287854] (kms_flip:12185) DEBUG: Vblank took 74us
[264.304444] (kms_flip:12185) DEBUG: name = flip
last_ts = 265.982910
last_received_ts = 265.982300
last_seq = 385
current_ts = 265.999573
current_received_ts = 265.998993
current_seq = 386
count = 377
seq_step = 1
[264.304559] (kms_flip:12185) DEBUG: Vblank took 81us
[264.320997] (kms_flip:12185) DEBUG: name = flip
last_ts = 265.999573
last_received_ts = 265.998993
last_seq = 386
current_ts = 266.016235
current_received_ts = 266.015533
current_seq = 387
count = 378
seq_step = 1
[264.321079] (kms_flip:12185) DEBUG: Vblank took 75us
[264.337765] (kms_flip:12185) DEBUG: name = flip
last_ts = 266.016235
last_received_ts = 266.015533
last_seq = 387
current_ts = 266.032898
current_received_ts = 266.032318
current_seq = 388
count = 379
seq_step = 1
[264.337847] (kms_flip:12185) DEBUG: Vblank took 75us
[264.354429] (kms_flip:12185) DEBUG: name = flip
last_ts = 266.032898
last_received_ts = 266.032318
last_seq = 388
current_ts = 266.049561
current_received_ts = 266.048981
current_seq = 389
count = 380
seq_step = 1
[264.354547] (kms_flip:12185) DEBUG: Vblank took 111us
[264.371109] (kms_flip:12185) DEBUG: name = flip
last_ts = 266.049561
last_received_ts = 266.048981
last_seq = 389
current_ts = 266.066223
current_received_ts = 266.065643
current_seq = 390
count = 381
seq_step = 1
[264.371190] (kms_flip:12185) DEBUG: Vblank took 74us
[264.387784] (kms_flip:12185) DEBUG: name = flip
last_ts = 266.066223
last_received_ts = 266.065643
last_seq = 390
current_ts = 266.082886
current_received_ts = 266.082336
current_seq = 391
count = 382
seq_step = 1
[264.387866] (kms_flip:12185) DEBUG: Vblank took 75us
[264.404432] (kms_flip:12185) DEBUG: name = flip
last_ts = 266.082886
last_received_ts = 266.082336
last_seq = 391
current_ts = 266.099548
current_received_ts = 266.098969
current_seq = 392
count = 383
seq_step = 1
[264.404547] (kms_flip:12185) DEBUG: Vblank took 108us
[264.421098] (kms_flip:12185) DEBUG: name = flip
last_ts = 266.099548
last_received_ts = 266.098969
last_seq = 392
current_ts = 266.116211
current_received_ts = 266.115631
current_seq = 393
count = 384
seq_step = 1
[264.421178] (kms_flip:12185) DEBUG: Vblank took 74us
[264.437772] (kms_flip:12185) DEBUG: name = flip
last_ts = 266.116211
last_received_ts = 266.115631
last_seq = 393
current_ts = 266.132874
current_received_ts = 266.132324
current_seq = 394
count = 385
seq_step = 1
[264.437854] (kms_flip:12185) DEBUG: Vblank took 75us
[264.454477] (kms_flip:12185) DEBUG: name = flip
last_ts = 266.132874
last_received_ts = 266.132324
last_seq = 394
current_ts = 266.149536
current_received_ts = 266.148987
current_seq = 395
count = 386
seq_step = 1
[264.454562] (kms_flip:12185) DEBUG: Vblank took 78us
[264.471129] (kms_flip:12185) DEBUG: name = flip
last_ts = 266.149536
last_received_ts = 266.148987
last_seq = 395
current_ts = 266.166199
current_received_ts = 266.165680
current_seq = 396
count = 387
seq_step = 1
[264.471209] (kms_flip:12185) DEBUG: Vblank took 73us
[264.487753] (kms_flip:12185) DEBUG: name = flip
last_ts = 266.166199
last_received_ts = 266.165680
last_seq = 396
current_ts = 266.182892
current_received_ts = 266.182312
current_seq = 397
count = 388
seq_step = 1
[264.487833] (kms_flip:12185) DEBUG: Vblank took 73us
[264.504439] (kms_flip:12185) DEBUG: name = flip
last_ts = 266.182892
last_received_ts = 266.182312
last_seq = 397
current_ts = 266.199554
current_received_ts = 266.198975
current_seq = 398
count = 389
seq_step = 1
[264.504556] (kms_flip:12185) DEBUG: Vblank took 79us
[264.521073] (kms_flip:12185) DEBUG: name = flip
last_ts = 266.199554
last_received_ts = 266.198975
last_seq = 398
current_ts = 266.216217
current_received_ts = 266.215637
current_seq = 399
count = 390
seq_step = 1
[264.521151] (kms_flip:12185) DEBUG: Vblank took 72us
[264.537750] (kms_flip:12185) DEBUG: name = flip
last_ts = 266.216217
last_received_ts = 266.215637
last_seq = 399
current_ts = 266.232880
current_received_ts = 266.232300
current_seq = 400
count = 391
seq_step = 1
[264.537831] (kms_flip:12185) DEBUG: Vblank took 75us
[264.554411] (kms_flip:12185) DEBUG: name = flip
last_ts = 266.232880
last_received_ts = 266.232300
last_seq = 400
current_ts = 266.249542
current_received_ts = 266.248962
current_seq = 401
count = 392
seq_step = 1
[264.554515] (kms_flip:12185) DEBUG: Vblank took 98us
[264.571011] (kms_flip:12185) DEBUG: name = flip
last_ts = 266.249542
last_received_ts = 266.248962
last_seq = 401
current_ts = 266.266205
current_received_ts = 266.265564
current_seq = 402
count = 393
seq_step = 1
[264.571085] (kms_flip:12185) DEBUG: Vblank took 68us
[264.587724] (kms_flip:12185) DEBUG: name = flip
last_ts = 266.266205
last_received_ts = 266.265564
last_seq = 402
current_ts = 266.282867
current_received_ts = 266.282288
current_seq = 403
count = 394
seq_step = 1
[264.587807] (kms_flip:12185) DEBUG: Vblank took 78us
[264.604405] (kms_flip:12185) DEBUG: name = flip
last_ts = 266.282867
last_received_ts = 266.282288
last_seq = 403
current_ts = 266.299530
current_received_ts = 266.298950
current_seq = 404
count = 395
seq_step = 1
[264.604508] (kms_flip:12185) DEBUG: Vblank took 95us
[264.621059] (kms_flip:12185) DEBUG: name = flip
last_ts = 266.299530
last_received_ts = 266.298950
last_seq = 404
current_ts = 266.316193
current_received_ts = 266.315613
current_seq = 405
count = 396
seq_step = 1
[264.621138] (kms_flip:12185) DEBUG: Vblank took 73us
[264.637748] (kms_flip:12185) DEBUG: name = flip
last_ts = 266.316193
last_received_ts = 266.315613
last_seq = 405
current_ts = 266.332855
current_received_ts = 266.332306
current_seq = 406
count = 397
seq_step = 1
[264.637829] (kms_flip:12185) DEBUG: Vblank took 74us
[264.654395] (kms_flip:12185) DEBUG: name = flip
last_ts = 266.332855
last_received_ts = 266.332306
last_seq = 406
current_ts = 266.349518
current_received_ts = 266.348938
current_seq = 407
count = 398
seq_step = 1
[264.654501] (kms_flip:12185) DEBUG: Vblank took 99us
[264.671049] (kms_flip:12185) DEBUG: name = flip
last_ts = 266.349518
last_received_ts = 266.348938
last_seq = 407
current_ts = 266.366211
current_received_ts = 266.365601
current_seq = 408
count = 399
seq_step = 1
[264.671127] (kms_flip:12185) DEBUG: Vblank took 71us
[264.687719] (kms_flip:12185) DEBUG: name = flip
last_ts = 266.366211
last_received_ts = 266.365601
last_seq = 408
current_ts = 266.382843
current_received_ts = 266.382263
current_seq = 409
count = 400
seq_step = 1
[264.687799] (kms_flip:12185) DEBUG: Vblank took 74us
[264.704387] (kms_flip:12185) DEBUG: name = flip
last_ts = 266.382843
last_received_ts = 266.382263
last_seq = 409
current_ts = 266.399536
current_received_ts = 266.398956
current_seq = 410
count = 401
seq_step = 1
[264.704490] (kms_flip:12185) DEBUG: Vblank took 97us
[264.721045] (kms_flip:12185) DEBUG: name = flip
last_ts = 266.399536
last_received_ts = 266.398956
last_seq = 410
current_ts = 266.416168
current_received_ts = 266.415619
current_seq = 411
count = 402
seq_step = 1
[264.721122] (kms_flip:12185) DEBUG: Vblank took 71us
[264.737709] (kms_flip:12185) DEBUG: name = flip
last_ts = 266.416168
last_received_ts = 266.415619
last_seq = 411
current_ts = 266.432861
current_received_ts = 266.432281
current_seq = 412
count = 403
seq_step = 1
[264.737788] (kms_flip:12185) DEBUG: Vblank took 73us
[264.754391] (kms_flip:12185) DEBUG: name = flip
last_ts = 266.432861
last_received_ts = 266.432281
last_seq = 412
current_ts = 266.449524
current_received_ts = 266.448944
current_seq = 413
count = 404
seq_step = 1
[264.754490] (kms_flip:12185) DEBUG: Vblank took 93us
[264.771037] (kms_flip:12185) DEBUG: name = flip
last_ts = 266.449524
last_received_ts = 266.448944
last_seq = 413
current_ts = 266.466187
current_received_ts = 266.465607
current_seq = 414
count = 405
seq_step = 1
[264.771115] (kms_flip:12185) DEBUG: Vblank took 72us
[264.787703] (kms_flip:12185) DEBUG: name = flip
last_ts = 266.466187
last_received_ts = 266.465607
last_seq = 414
current_ts = 266.482849
current_received_ts = 266.482269
current_seq = 415
count = 406
seq_step = 1
[264.787786] (kms_flip:12185) DEBUG: Vblank took 76us
[264.804364] (kms_flip:12185) DEBUG: name = flip
last_ts = 266.482849
last_received_ts = 266.482269
last_seq = 415
current_ts = 266.499512
current_received_ts = 266.498932
current_seq = 416
count = 407
seq_step = 1
[264.804470] (kms_flip:12185) DEBUG: Vblank took 100us
[264.821032] (kms_flip:12185) DEBUG: name = flip
last_ts = 266.499512
last_received_ts = 266.498932
last_seq = 416
current_ts = 266.516174
current_received_ts = 266.515594
current_seq = 417
count = 408
seq_step = 1
[264.821111] (kms_flip:12185) DEBUG: Vblank took 72us
[264.837679] (kms_flip:12185) DEBUG: name = flip
last_ts = 266.516174
last_received_ts = 266.515594
last_seq = 417
current_ts = 266.532837
current_received_ts = 266.532257
current_seq = 418
count = 409
seq_step = 1
[264.837760] (kms_flip:12185) DEBUG: Vblank took 74us
[264.854353] (kms_flip:12185) DEBUG: name = flip
last_ts = 266.532837
last_received_ts = 266.532257
last_seq = 418
current_ts = 266.549500
current_received_ts = 266.548920
current_seq = 419
count = 410
seq_step = 1
[264.854457] (kms_flip:12185) DEBUG: Vblank took 98us
[264.871027] (kms_flip:12185) DEBUG: name = flip
last_ts = 266.549500
last_received_ts = 266.548920
last_seq = 419
current_ts = 266.566162
current_received_ts = 266.565582
current_seq = 420
count = 411
seq_step = 1
[264.871105] (kms_flip:12185) DEBUG: Vblank took 71us
[264.887689] (kms_flip:12185) DEBUG: name = flip
last_ts = 266.566162
last_received_ts = 266.565582
last_seq = 420
current_ts = 266.582825
current_received_ts = 266.582245
current_seq = 421
count = 412
seq_step = 1
[264.887770] (kms_flip:12185) DEBUG: Vblank took 74us
[264.904352] (kms_flip:12185) DEBUG: name = flip
last_ts = 266.582825
last_received_ts = 266.582245
last_seq = 421
current_ts = 266.599487
current_received_ts = 266.598907
current_seq = 422
count = 413
seq_step = 1
[264.904456] (kms_flip:12185) DEBUG: Vblank took 97us
[264.921039] (kms_flip:12185) DEBUG: name = flip
last_ts = 266.599487
last_received_ts = 266.598907
last_seq = 422
current_ts = 266.616150
current_received_ts = 266.615601
current_seq = 423
count = 414
seq_step = 1
[264.921119] (kms_flip:12185) DEBUG: Vblank took 73us
[264.937684] (kms_flip:12185) DEBUG: name = flip
last_ts = 266.616150
last_received_ts = 266.615601
last_seq = 423
current_ts = 266.632812
current_received_ts = 266.632263
current_seq = 424
count = 415
seq_step = 1
[264.937765] (kms_flip:12185) DEBUG: Vblank took 74us
[264.954339] (kms_flip:12185) DEBUG: name = flip
last_ts = 266.632812
last_received_ts = 266.632263
last_seq = 424
current_ts = 266.649475
current_received_ts = 266.648895
current_seq = 425
count = 416
seq_step = 1
[264.954417] (kms_flip:12185) DEBUG: Vblank took 71us
[264.971008] (kms_flip:12185) DEBUG: name = flip
last_ts = 266.649475
last_received_ts = 266.648895
last_seq = 425
current_ts = 266.666168
current_received_ts = 266.665588
current_seq = 426
count = 417
seq_step = 1
[264.971089] (kms_flip:12185) DEBUG: Vblank took 74us
[264.987662] (kms_flip:12185) DEBUG: name = flip
last_ts = 266.666168
last_received_ts = 266.665588
last_seq = 426
current_ts = 266.682831
current_received_ts = 266.682220
current_seq = 427
count = 418
seq_step = 1
[264.987743] (kms_flip:12185) DEBUG: Vblank took 73us
[265.004330] (kms_flip:12185) DEBUG: name = flip
last_ts = 266.682831
last_received_ts = 266.682220
last_seq = 427
current_ts = 266.699493
current_received_ts = 266.698914
current_seq = 428
count = 419
seq_step = 1
[265.004410] (kms_flip:12185) DEBUG: Vblank took 73us
[265.021008] (kms_flip:12185) DEBUG: name = flip
last_ts = 266.699493
last_received_ts = 266.698914
last_seq = 428
current_ts = 266.716156
current_received_ts = 266.715576
current_seq = 429
count = 420
seq_step = 1
[265.021093] (kms_flip:12185) DEBUG: Vblank took 76us
[265.037671] (kms_flip:12185) DEBUG: name = flip
last_ts = 266.716156
last_received_ts = 266.715576
last_seq = 429
current_ts = 266.732819
current_received_ts = 266.732239
current_seq = 430
count = 421
seq_step = 1
[265.037754] (kms_flip:12185) DEBUG: Vblank took 75us
[265.054320] (kms_flip:12185) DEBUG: name = flip
last_ts = 266.732819
last_received_ts = 266.732239
last_seq = 430
current_ts = 266.749481
current_received_ts = 266.748901
current_seq = 431
count = 422
seq_step = 1
[265.054398] (kms_flip:12185) DEBUG: Vblank took 72us
[265.071018] (kms_flip:12185) DEBUG: name = flip
last_ts = 266.749481
last_received_ts = 266.748901
last_seq = 431
current_ts = 266.766144
current_received_ts = 266.765594
current_seq = 432
count = 423
seq_step = 1
[265.071099] (kms_flip:12185) DEBUG: Vblank took 74us
[265.087659] (kms_flip:12185) DEBUG: name = flip
last_ts = 266.766144
last_received_ts = 266.765594
last_seq = 432
current_ts = 266.782806
current_received_ts = 266.782227
current_seq = 433
count = 424
seq_step = 1
[265.087740] (kms_flip:12185) DEBUG: Vblank took 74us
[265.104316] (kms_flip:12185) DEBUG: name = flip
last_ts = 266.782806
last_received_ts = 266.782227
last_seq = 433
current_ts = 266.799469
current_received_ts = 266.798889
current_seq = 434
count = 425
seq_step = 1
[265.104394] (kms_flip:12185) DEBUG: Vblank took 71us
[265.120986] (kms_flip:12185) DEBUG: name = flip
last_ts = 266.799469
last_received_ts = 266.798889
last_seq = 434
current_ts = 266.816132
current_received_ts = 266.815552
current_seq = 435
count = 426
seq_step = 1
[265.121064] (kms_flip:12185) DEBUG: Vblank took 71us
[265.137633] (kms_flip:12185) DEBUG: name = flip
last_ts = 266.816132
last_received_ts = 266.815552
last_seq = 435
current_ts = 266.832794
current_received_ts = 266.832214
current_seq = 436
count = 427
seq_step = 1
[265.137715] (kms_flip:12185) DEBUG: Vblank took 75us
[265.154490] (kms_flip:12185) DEBUG: name = flip
last_ts = 266.832794
last_received_ts = 266.832214
last_seq = 436
current_ts = 266.849457
current_received_ts = 266.849030
current_seq = 437
count = 428
seq_step = 1
[265.154576] (kms_flip:12185) DEBUG: Vblank took 64us
[265.171011] (kms_flip:12185) DEBUG: name = flip
last_ts = 266.849457
last_received_ts = 266.849030
last_seq = 437
current_ts = 266.866119
current_received_ts = 266.865570
current_seq = 438
count = 429
seq_step = 1
[265.171101] (kms_flip:12185) DEBUG: Vblank took 67us
[265.187641] (kms_flip:12185) DEBUG: name = flip
last_ts = 266.866119
last_received_ts = 266.865570
last_seq = 438
current_ts = 266.882812
current_received_ts = 266.882202
current_seq = 439
count = 430
seq_step = 1
[265.187733] (kms_flip:12185) DEBUG: Vblank took 80us
[265.204346] (kms_flip:12185) DEBUG: name = flip
last_ts = 266.882812
last_received_ts = 266.882202
last_seq = 439
current_ts = 266.899445
current_received_ts = 266.898926
current_seq = 440
count = 431
seq_step = 1
[265.204472] (kms_flip:12185) DEBUG: Vblank took 115us
[265.220944] (kms_flip:12185) DEBUG: name = flip
last_ts = 266.899445
last_received_ts = 266.898926
last_seq = 440
current_ts = 266.916138
current_received_ts = 266.915527
current_seq = 441
count = 432
seq_step = 1
[265.221016] (kms_flip:12185) DEBUG: Vblank took 64us
[265.237611] (kms_flip:12185) DEBUG: name = flip
last_ts = 266.916138
last_received_ts = 266.915527
last_seq = 441
current_ts = 266.932800
current_received_ts = 266.932190
current_seq = 442
count = 433
seq_step = 1
[265.237755] (kms_flip:12185) DEBUG: Vblank took 136us
[265.254298] (kms_flip:12185) DEBUG: name = flip
last_ts = 266.932800
last_received_ts = 266.932190
last_seq = 442
current_ts = 266.949463
current_received_ts = 266.948883
current_seq = 443
count = 434
seq_step = 1
[265.254367] (kms_flip:12185) DEBUG: Vblank took 60us
[265.270965] (kms_flip:12185) DEBUG: name = flip
last_ts = 266.949463
last_received_ts = 266.948883
last_seq = 443
current_ts = 266.966125
current_received_ts = 266.965546
current_seq = 444
count = 435
seq_step = 1
[265.271095] (kms_flip:12185) DEBUG: Vblank took 122us
[265.287655] (kms_flip:12185) DEBUG: name = flip
last_ts = 266.966125
last_received_ts = 266.965546
last_seq = 444
current_ts = 266.982788
current_received_ts = 266.982239
current_seq = 445
count = 436
seq_step = 1
[265.287770] (kms_flip:12185) DEBUG: Vblank took 106us
[265.304283] (kms_flip:12185) DEBUG: name = flip
last_ts = 266.982788
last_received_ts = 266.982239
last_seq = 445
current_ts = 266.999451
current_received_ts = 266.998871
current_seq = 446
count = 437
seq_step = 1
[265.304352] (kms_flip:12185) DEBUG: Vblank took 60us
[265.320952] (kms_flip:12185) DEBUG: name = flip
last_ts = 266.999451
last_received_ts = 266.998871
last_seq = 446
current_ts = 267.016113
current_received_ts = 267.015533
current_seq = 447
count = 438
seq_step = 1
[265.321023] (kms_flip:12185) DEBUG: Vblank took 63us
[265.337583] (kms_flip:12185) DEBUG: name = flip
last_ts = 267.016113
last_received_ts = 267.015533
last_seq = 447
current_ts = 267.032776
current_received_ts = 267.032166
current_seq = 448
count = 439
seq_step = 1
[265.337652] (kms_flip:12185) DEBUG: Vblank took 60us
[265.354272] (kms_flip:12185) DEBUG: name = flip
last_ts = 267.032776
last_received_ts = 267.032166
last_seq = 448
current_ts = 267.049438
current_received_ts = 267.048859
current_seq = 449
count = 440
seq_step = 1
[265.354340] (kms_flip:12185) DEBUG: Vblank took 61us
[265.370945] (kms_flip:12185) DEBUG: name = flip
last_ts = 267.049438
last_received_ts = 267.048859
last_seq = 449
current_ts = 267.066101
current_received_ts = 267.065521
current_seq = 450
count = 441
seq_step = 1
[265.371076] (kms_flip:12185) DEBUG: Vblank took 122us
[265.387589] (kms_flip:12185) DEBUG: name = flip
last_ts = 267.066101
last_received_ts = 267.065521
last_seq = 450
current_ts = 267.082764
current_received_ts = 267.082184
current_seq = 451
count = 442
seq_step = 1
[265.387657] (kms_flip:12185) DEBUG: Vblank took 60us
[265.404270] (kms_flip:12185) DEBUG: name = flip
last_ts = 267.082764
last_received_ts = 267.082184
last_seq = 451
current_ts = 267.099426
current_received_ts = 267.098846
current_seq = 452
count = 443
seq_step = 1
[265.404338] (kms_flip:12185) DEBUG: Vblank took 60us
[265.420932] (kms_flip:12185) DEBUG: name = flip
last_ts = 267.099426
last_received_ts = 267.098846
last_seq = 452
current_ts = 267.116119
current_received_ts = 267.115509
current_seq = 453
count = 444
seq_step = 1
[265.421001] (kms_flip:12185) DEBUG: Vblank took 60us
[265.437588] (kms_flip:12185) DEBUG: name = flip
last_ts = 267.116119
last_received_ts = 267.115509
last_seq = 453
current_ts = 267.132782
current_received_ts = 267.132172
current_seq = 454
count = 445
seq_step = 1
[265.437660] (kms_flip:12185) DEBUG: Vblank took 63us
[265.454250] (kms_flip:12185) DEBUG: name = flip
last_ts = 267.132782
last_received_ts = 267.132172
last_seq = 454
current_ts = 267.149445
current_received_ts = 267.148834
current_seq = 455
count = 446
seq_step = 1
[265.454318] (kms_flip:12185) DEBUG: Vblank took 60us
[265.470919] (kms_flip:12185) DEBUG: name = flip
last_ts = 267.149445
last_received_ts = 267.148834
last_seq = 455
current_ts = 267.166107
current_received_ts = 267.165497
current_seq = 456
count = 447
seq_step = 1
[265.471043] (kms_flip:12185) DEBUG: Vblank took 116us
[265.487572] (kms_flip:12185) DEBUG: name = flip
last_ts = 267.166107
last_received_ts = 267.165497
last_seq = 456
current_ts = 267.182770
current_received_ts = 267.182159
current_seq = 457
count = 448
seq_step = 1
[265.487641] (kms_flip:12185) DEBUG: Vblank took 60us
[265.504253] (kms_flip:12185) DEBUG: name = flip
last_ts = 267.182770
last_received_ts = 267.182159
last_seq = 457
current_ts = 267.199432
current_received_ts = 267.198853
current_seq = 458
count = 449
seq_step = 1
[265.504321] (kms_flip:12185) DEBUG: Vblank took 60us
[265.520944] (kms_flip:12185) DEBUG: name = flip
last_ts = 267.199432
last_received_ts = 267.198853
last_seq = 458
current_ts = 267.216095
current_received_ts = 267.215546
current_seq = 459
count = 450
seq_step = 1
[265.521065] (kms_flip:12185) DEBUG: Vblank took 114us
[265.537572] (kms_flip:12185) DEBUG: name = flip
last_ts = 267.216095
last_received_ts = 267.215546
last_seq = 459
current_ts = 267.232758
current_received_ts = 267.232178
current_seq = 460
count = 451
seq_step = 1
[265.537643] (kms_flip:12185) DEBUG: Vblank took 62us
[265.554247] (kms_flip:12185) DEBUG: name = flip
last_ts = 267.232758
last_received_ts = 267.232178
last_seq = 460
current_ts = 267.249420
current_received_ts = 267.248840
current_seq = 461
count = 452
seq_step = 1
[265.554315] (kms_flip:12185) DEBUG: Vblank took 60us
[265.570892] (kms_flip:12185) DEBUG: name = flip
last_ts = 267.249420
last_received_ts = 267.248840
last_seq = 461
current_ts = 267.266083
current_received_ts = 267.265472
current_seq = 462
count = 453
seq_step = 1
[265.570969] (kms_flip:12185) DEBUG: Vblank took 68us
[265.587579] (kms_flip:12185) DEBUG: name = flip
last_ts = 267.266083
last_received_ts = 267.265472
last_seq = 462
current_ts = 267.282745
current_received_ts = 267.282166
current_seq = 463
count = 454
seq_step = 1
[265.587661] (kms_flip:12185) DEBUG: Vblank took 72us
[265.604254] (kms_flip:12185) DEBUG: name = flip
last_ts = 267.282745
last_received_ts = 267.282166
last_seq = 463
current_ts = 267.299408
current_received_ts = 267.298859
current_seq = 464
count = 455
seq_step = 1
[265.604323] (kms_flip:12185) DEBUG: Vblank took 60us
[265.620830] (kms_flip:12185) DEBUG: name = flip
last_ts = 267.299408
last_received_ts = 267.298859
last_seq = 464
current_ts = 267.316071
current_received_ts = 267.315430
current_seq = 465
count = 456
seq_step = 1
[265.620898] (kms_flip:12185) DEBUG: Vblank took 60us
[265.637557] (kms_flip:12185) DEBUG: name = flip
last_ts = 267.316071
last_received_ts = 267.315430
last_seq = 465
current_ts = 267.332733
current_received_ts = 267.332153
current_seq = 466
count = 457
seq_step = 1
[265.638106] (kms_flip:12185) DEBUG: Vblank took 541us
[265.638111] (kms_flip:12185) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931:
[265.638185] (kms_flip:12185) CRITICAL: Failed assertion: end - start < 500
[265.639936] (kms_flip:12185) igt_core-INFO: Stack trace:
[265.642136] (kms_flip:12185) igt_core-INFO:   #0 ../lib/igt_core.c:2106 __igt_fail_assert()
[265.643184] (kms_flip:12185) igt_core-INFO:   #1 ../tests/kms_flip.c:926 run_test_on_crtc_set()
[265.643219] (kms_flip:12185) igt_core-INFO:   #2 ../tests/kms_flip.c:1920 run_test()
[265.643246] (kms_flip:12185) igt_core-INFO:   #3 ../tests/kms_flip.c:2184 __igt_unique____real_main2105()
[265.643270] (kms_flip:12185) igt_core-INFO:   #4 ../tests/kms_flip.c:2105 main()
[265.646201] (kms_flip:12185) igt_core-INFO:   #5 [__libc_init_first+0x8a]
[265.647692] (kms_flip:12185) igt_core-INFO:   #6 [__libc_start_main+0x8b]
[265.648099] (kms_flip:12185) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest C-eDP1: FAIL (9.095s)
Subtest flip-vs-expired-vblank: FAIL (25.151s)
[267.042706] (kms_flip:12185) igt_kms-CRITICAL: Test assertion failure function kmstest_set_connector_dpms, file ../lib/igt_kms.c:2354:
[267.042774] (kms_flip:12185) igt_kms-CRITICAL: Failed assertion: found_it
[267.042807] (kms_flip:12185) igt_kms-CRITICAL: Last errno: 9, Bad file descriptor
[267.042829] (kms_flip:12185) igt_kms-CRITICAL: DPMS property not found on 393
Dmesg
<6> [258.451849] [IGT] kms_flip: starting dynamic subtest C-eDP1
<7> [258.453666] xe 0000:00:02.0: [drm:drm_mode_addfb2] [FB:433]
<7> [258.455176] xe 0000:00:02.0: [drm:drm_mode_addfb2] [FB:434]
<7> [258.466954] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [258.467225] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [258.467439] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
<7> [258.556896] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:151:pipe A]
<7> [258.557286] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [258.557675] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [258.558137] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:270:pipe B]
<7> [258.558354] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.enable (expected yes, found no)
<7> [258.558588] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.active (expected yes, found no)
<7> [258.558795] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in cpu_transcoder (expected 1, found -1)
<7> [258.559011] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in lane_count (expected 4, found 0)
<7> [258.559206] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in dp_m_n (expected tu 64 data 6329869/8388608 link 421991/524288, found tu 0, data 0/0 link 0/0)
<7> [258.559396] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in output_types (expected 0x00000100, found 0x00000000)
<7> [258.559581] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [258.559762] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 2880, found 0)
<7> [258.560066] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 3040, found 0)
<7> [258.560250] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 2880, found 0)
<7> [258.560413] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 3040, found 0)
<7> [258.560570] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2928, found 0)
<7> [258.560725] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2960, found 0)
<7> [258.560877] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1800, found 0)
<7> [258.561045] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1800, found 0)
<7> [258.561200] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1803, found 0)
<7> [258.561350] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1809, found 0)
<7> [258.561497] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1906, found 0)
<7> [258.561639] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1906, found 0)
<7> [258.561782] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 2880, found 0)
<7> [258.561934] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 3040, found 0)
<7> [258.562074] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 2880, found 0)
<7> [258.562209] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 3040, found 0)
<7> [258.562342] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2928, found 0)
<7> [258.562472] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2960, found 0)
<7> [258.562605] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1800, found 0)
<7> [258.562736] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1800, found 0)
<7> [258.562865] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1803, found 0)
<7> [258.562997] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1809, found 0)
<7> [258.563125] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1906, found 0)
<7> [258.563249] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1906, found 0)
<7> [258.563375] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [258.563488] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)
<7> [258.563603] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [258.563722] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in pipe_bpp (expected 30, found 0)
<7> [258.563832] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 347710, found 0)
<7> [258.563948] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 347710, found 0)
<7> [258.564058] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in port_clock (expected 432000, found 0)
<7> [258.564170] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:270:pipe B] fastset requirement not met, forcing full modeset
<7> [258.564284] xe 0000:00:02.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:270:pipe B] releasing DPLL 0
<7> [258.564465] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xf -> 0x1 (total dbuf slices 0xf), mbus joined? yes->no
<7> [258.564565] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:270:pipe B] dbuf slices 0xf -> 0x0, ddb (0 - 4096) -> (0 - 0), active pipes 0x2 -> 0x0
<7> [258.564682] xe 0000:00:02.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:264:cursor B] ddb (4051 - 4096) -> ( 0 - 0), size 45 -> 0
<7> [258.564771] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:270:pipe B] min cdclk: 173855 kHz -> 0 kHz
<7> [258.564897] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] Can change cdclk via squashing
<7> [258.565030] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New cdclk calculated to be logical 153600 kHz, actual 153600 kHz
<7> [258.565151] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New voltage level calculated to be logical 0, actual 0
<7> [258.565338] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:270:pipe B] enable: no [modeset]
<7> [258.565465] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:154:plane 1B] fb: [NOFB], visible: no
<7> [258.565587] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:184:plane 2B] fb: [NOFB], visible: no
<7> [258.565702] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:214:plane 3B] fb: [NOFB], visible: no
<7> [258.565815] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:244:plane 4B] fb: [NOFB], visible: no
<7> [258.565937] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:254:plane 5B] fb: [NOFB], visible: no
<7> [258.566060] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:264:cursor B] fb: [NOFB], visible: no
<7> [258.566378] xe 0000:00:02.0: [drm:intel_alpm_disable [xe]] Disabling ALPM
<7> [258.566519] xe 0000:00:02.0: [drm:intel_edp_backlight_off [xe]]
<7> [258.770976] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:393:eDP-1] set backlight PWM = 0
<7> [258.771981] xe 0000:00:02.0: [drm:intel_disable_transcoder [xe]] disabling pipe B
<7> [258.783352] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turn panel power off
<7> [258.784050] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 wait for panel power off time
<7> [258.784570] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 mask: 0xb0000000 value: 0x00000000 PP_STATUS: 0xa0000002 PP_CONTROL: 0x00000060
<7> [258.836717] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [258.837057] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:392:DDI A/PHY A] - short
<7> [258.844996] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [258.845401] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disable DPLL 0 (active 0x2, on? 1) for [CRTC:270:pipe B]
<7> [258.845547] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:392:DDI A/PHY A]
<7> [258.847058] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disabling DPLL 0
<7> [258.847637] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling AUX_A
<7> [258.848265] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:392:DDI A/PHY A]
<7> [258.848682] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:401:DDI TC1/PHY TC1]
<7> [258.849093] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:403:DP-MST A]
<7> [258.849465] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:404:DP-MST B]
<7> [258.849828] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:405:DP-MST C]
<7> [258.850211] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:413:DDI TC2/PHY TC2]
<7> [258.850574] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:415:DP-MST A]
<7> [258.850953] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:416:DP-MST B]
<7> [258.851299] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:417:DP-MST C]
<7> [258.851616] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:421:DDI TC3/PHY TC3]
<7> [258.851952] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:423:DP-MST A]
<7> [258.852273] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:424:DP-MST B]
<7> [258.852590] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:425:DP-MST C]
<7> [258.852920] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:393:eDP-1]
<7> [258.853324] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [258.853629] xe 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: yes -> no (pipe: *)
<7> [258.853937] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0x1
<7> [258.854469] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_B
<7> [258.854916] xe 0000:00:02.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating disabled for pipe_mask=0x2 (CLKGATE_DIS_DSSDSC=0xaa000000)
<7> [258.855378] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_2
<7> [258.855860] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:270:pipe B]
<7> [258.856266] 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> [258.856756] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [258.857104] xe 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=384
<7> [258.858080] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:389:pipe C]
<7> [258.858582] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:389:pipe C]
<7> [258.858674] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:393:eDP-1]
<7> [258.858955] xe 0000:00:02.0: [drm:compute_baseline_pipe_bpp [xe]] [CONNECTOR:393:eDP-1] Limiting target display pipe bpp to 30 (EDID bpp 30, max requested bpp 36, max platform bpp 36)
<7> [258.859375] xe 0000:00:02.0: [drm:intel_dp_compute_config_limits [xe]] [ENCODER:392:DDI A/PHY A][CRTC:389:pipe C] DP link limits: pixel clock 347710 kHz DSC off max lanes 4 max rate 810000 max pipe_bpp 30 min link_bpp 18.0000 max link_bpp 30.0000
<7> [258.859694] xe 0000:00:02.0: [drm:intel_dp_compute_output_format [xe]] DP lane count 4 clock 432000 bpp input 30 compressed 0.0000 HDR no link rate required 1303913 available 1728000
<7> [258.860019] xe 0000:00:02.0: [drm:intel_psr_compute_config [xe]] PSR2 not supported in transcoder C
<7> [258.860295] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:389:pipe C] hw max bpp: 30, pipe bpp: 30, dithering: 0
<7> [258.860607] xe 0000:00:02.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:392:DDI A/PHY A] [CRTC:389:pipe C]
<7> [258.860917] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.enable (expected no, found yes)
<7> [258.861201] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.active (expected no, found yes)
<7> [258.861478] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in cpu_transcoder (expected -1, found 2)
<7> [258.861732] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in lane_count (expected 0, found 4)
<7> [258.861997] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in dp_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 6329869/8388608 link 421991/524288)
<7> [258.862238] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in output_types (expected 0x00000000, found 0x00000100)
<7> [258.862473] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [258.862692] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 2880)
<7> [258.862916] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 3040)
<7> [258.863126] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 2880)
<7> [258.863331] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 3040)
<7> [258.863527] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2928)
<7> [258.863719] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2960)
<7> [258.863914] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1800)
<7> [258.864103] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1800)
<7> [258.864278] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1803)
<7> [258.864448] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1809)
<7> [258.864617] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1906)
<7> [258.864779] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1906)
<7> [258.864949] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 2880)
<7> [258.865108] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 3040)
<7> [258.865263] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 2880)
<7> [258.865416] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 3040)
<7> [258.865561] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2928)
<7> [258.865704] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2960)
<7> [258.865844] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1800)
<7> [258.865988] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1800)
<7> [258.866123] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1803)
<7> [258.866256] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1809)
<7> [258.866385] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1906)
<7> [258.866509] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1906)
<7> [258.866630] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [258.866752] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [258.866873] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [258.866996] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in pipe_bpp (expected 0, found 30)
<7> [258.867112] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 347710)
<7> [258.867227] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 347710)
<7> [258.867339] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in port_clock (expected 0, found 432000)
<7> [258.867450] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:389:pipe C] fastset requirement not met, forcing full modeset
<7> [258.867603] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0x1 -> 0xd (total dbuf slices 0xf), mbus joined? no->no
<7> [258.867695] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:389:pipe C] dbuf slices 0x0 -> 0xc, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x4
<7> [258.867813] xe 0000:00:02.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:273:plane 1C] ddb ( 0 - 0) -> ( 0 - 2003), size 0 -> 2003
<7> [258.867899] xe 0000:00:02.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:383:cursor C] ddb ( 0 - 0) -> (2003 - 2048), size 0 -> 45
<7> [258.867978] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:273:plane 1C] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm
<7> [258.868049] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:273:plane 1C] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 4, 13, 13, 13, 13, 13, 0, 0, 0, 4, 0
<7> [258.868123] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:273:plane 1C] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 92, 297, 297, 297, 297, 297, 0, 0, 197, 92, 197
<7> [258.868196] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:273:plane 1C] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 183, 456, 456, 456, 456, 456, 0, 0, 198, 183, 198
<7> [258.868291] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:389:pipe C] data rate 1390840 num active planes 1
<7> [258.868416] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23529 required 1461 qgv_peak_bw: 38400
<7> [258.868536] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36259 required 1461 qgv_peak_bw: 38400
<7> [258.868654] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [258.868771] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [258.868887] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 1461
<7> [258.869093] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:389:pipe C] min cdclk: 0 kHz -> 173855 kHz
<7> [258.869243] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] Can change cdclk via squashing
<7> [258.869362] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New cdclk calculated to be logical 192000 kHz, actual 192000 kHz
<7> [258.869476] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New voltage level calculated to be logical 0, actual 0
<7> [258.869620] xe 0000:00:02.0: [drm:intel_find_dpll [xe]] [CRTC:389:pipe C] allocated DPLL 0
<7> [258.869732] xe 0000:00:02.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:389:pipe C] reserving DPLL 0
<7> [258.869842] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:389:pipe C] enable: yes [modeset]
<7> [258.869976] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: EDP (0x100), output format: RGB, sink format: RGB
<7> [258.870099] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: C, pipe bpp: 30, dithering: 0
<7> [258.870213] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [258.870327] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [258.870444] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [258.870559] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [258.870674] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dp m_n: lanes: 4; data_m: 6329869, data_n: 8388608, link_m: 421991, link_n: 524288, tu: 64
<7> [258.870792] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dp m2_n2: lanes: 4; data_m: 0, data_n: 0, link_m: 0, link_n: 0, tu: 0
<7> [258.870913] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: disabled
<7> [258.871029] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled
<7> [258.871142] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] psr: enabled, selective update: disabled, panel replay: disabled, selective fetch: disabled
<7> [258.871257] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] minimum hblank: 0
<7> [258.871371] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] audio: 0, infoframes: 0, infoframes enabled: 0x4
<7> [258.871487] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] DP SDP: VSC, revision 2, length 8
<7> [258.871600] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pixelformat: RGB
<7> [258.871717] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] colorimetry: sRGB
<7> [258.871835] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] bpc: 0
<7> [258.871957] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dynamic range: VESA range
<7> [258.872072] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] content type: Not defined
<7> [258.872185] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [258.872301] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [258.872415] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: no, fixed rr: yes, vmin: 1906, vmax: 1906, flipline: 1906, pipeline full: 0, guardband: 37 vsync start: 103, vsync end: 97
<7> [258.872532] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: vmin vblank: 1869, vmax vblank: 1869, vmin vtotal: 1906, vmax vtotal: 1906
<7> [258.872653] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [258.872769] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] requested mode: "2880x1800": 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa
<7> [258.872883] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] adjusted mode: "2880x1800": 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa
<7> [258.873001] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=347710, hd=2880 hb=2880-3040 hs=2928-2960 ht=3040, vd=1800 vb=1800-1906 vs=1803-1809 vt=1906, flags=0xa
<7> [258.873119] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe mode: "2880x1800": 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x40 0xa
<7> [258.873235] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=347710, hd=2880 hb=2880-3040 hs=2928-2960 ht=3040, vd=1800 vb=1800-1906 vs=1803-1809 vt=1906, flags=0xa
<7> [258.873350] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port clock: 432000, pixel rate 347710, min cdclk 173855, min voltage level 0
<7> [258.873468] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] linetime: 70, ips linetime: 0
<7> [258.873582] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0
<7> [258.873694] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe src: 2880x1800+0+0
<7> [258.873807] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [258.873926] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [258.874045] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [258.874160] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cx0pll_hw_state: lane_count: 4, ssc_enabled: no, use_c10: yes, tbt_mode: no
<7> [258.874275] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_hw_state: fracen: no,
<7> [258.874389] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] multiplier: 225, tx_clk_div: 1.
<7> [258.874502] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_rawhw_state:
<7> [258.874617] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] tx: 0x10, cmn: 0x21
<7> [258.874729] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[0] = 0x4, pll[1] = 0x0, pll[2] = 0xa2, pll[3] = 0x1
<7> [258.874842] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[4] = 0x0, pll[5] = 0x0, pll[6] = 0x0, pll[7] = 0x0
<7> [258.874963] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[8] = 0x0, pll[9] = 0x1, pll[10] = 0x0, pll[11] = 0x0
<7> [258.875084] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[12] = 0x0, pll[13] = 0x0, pll[14] = 0x0, pll[15] = 0x1
<7> [258.875199] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[16] = 0x85, pll[17] = 0xf, pll[18] = 0xe6, pll[19] = 0x23
<7> [258.875315] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [258.875437] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [258.875553] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [258.875666] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [258.875779] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [258.875908] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [258.875932] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [258.876031] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [258.876108] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [258.876148] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [258.876237] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
<7> [258.876262] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [258.876376] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [258.876399] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling always-on
<7> [258.876499] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [258.876613] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [258.876729] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:273:plane 1C] fb: [FB:433] 2880x1800 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [258.876776] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [258.876846] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [258.876902] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:392:DDI A/PHY A] - long
<7> [258.876992] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] src: 2880.000000x1800.000000+0.000000+0.000000 dst: 2880x1800+0+0
<7> [258.877027] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 10
<7> [258.877118] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:303:plane 2C] fb: [NOFB], visible: no
<7> [258.877161] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:392:DDI A/PHY A]
<7> [258.877236] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:333:plane 3C] fb: [NOFB], visible: no
<7> [258.877352] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:363:plane 4C] fb: [NOFB], visible: no
<7> [258.877466] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:373:plane 5C] fb: [NOFB], visible: no
<7> [258.877582] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:383:cursor C] fb: [NOFB], visible: no
<7> [258.878018] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling always-on
<7> [258.878164] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [258.878425] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [258.878602] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_2
<7> [258.878762] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_C
<7> [258.878944] xe 0000:00:02.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating enabled for pipe_mask=0x4 (CLKGATE_DIS_DSSDSC=0x8a000000)
<7> [258.879082] 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> [258.879221] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [258.879340] xe 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=480
<7> [258.879462] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:392:DDI A/PHY A]
<7> [258.879554] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:401:DDI TC1/PHY TC1]
<7> [258.879642] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:403:DP-MST A]
<7> [258.879728] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:404:DP-MST B]
<7> [258.879816] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:405:DP-MST C]
<7> [258.879914] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:413:DDI TC2/PHY TC2]
<7> [258.880008] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:415:DP-MST A]
<7> [258.880094] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:416:DP-MST B]
<7> [258.880178] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:417:DP-MST C]
<7> [258.880266] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:421:DDI TC3/PHY TC3]
<7> [258.880356] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:423:DP-MST A]
<7> [258.880439] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:424:DP-MST B]
<7> [258.880524] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:425:DP-MST C]
<7> [258.880634] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xd
<7> [258.880832] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [258.880976] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling AUX_A
<7> [258.881943] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enable DPLL 0 (active 0x4, on? 0) for [CRTC:389:pipe C]
<7> [258.882066] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enabling DPLL 0
<7> [258.884147] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turn panel power on
<7> [258.884260] xe 0000:00:02.0: [drm:wait_panel_power_cycle [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 wait for panel power cycle (462 ms remaining)
<7> [259.355388] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 mask: 0xb800000f value: 0x00000000 PP_STATUS: 0x08000001 PP_CONTROL: 0x00000060
<7> [259.448266] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [259.449118] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 wait for panel power on
<7> [259.449693] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 mask: 0xb000000f value: 0x80000008 PP_STATUS: 0x9000000a PP_CONTROL: 0x00000063
<7> [259.485918] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [259.486221] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:392:DDI A/PHY A] - long
<7> [259.486506] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 20
<7> [259.486945] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:392:DDI A/PHY A]
<7> [259.661914] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [259.662298] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turning VDD on
<7> [259.662558] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006b
<7> [259.664587] xe 0000:00:02.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX A/DDI A/PHY A: DPCD: 14 1e 44 c1 00 00 01 c0 02 00 02 00 00 0b 80
<7> [259.666112] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Reloading eDP link rates
<7> [259.666804] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Using LINK_RATE_SET value 05
<7> [259.667851] xe 0000:00:02.0: [drm:intel_dp_set_signal_levels [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] 8b/10b, lanes: 4, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0
<7> [259.669092] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Using DP training pattern TPS1
<7> [259.670105] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Clock recovery OK
<7> [259.670391] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Using DP training pattern TPS4
<7> [259.671819] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Channel EQ done. DP Training successful
<7> [259.672117] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Link Training passed at link rate = 432000, lane count = 4
<7> [259.672819] xe 0000:00:02.0: [drm:intel_enable_transcoder [xe]] enabling pipe C
<7> [259.673266] xe 0000:00:02.0: [drm:intel_edp_backlight_on [xe]]
<7> [259.673588] xe 0000:00:02.0: [drm:intel_backlight_enable [xe]] pipe C
<7> [259.673994] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:393:eDP-1] set backlight PWM = 4518
<7> [259.690777] xe 0000:00:02.0: [drm:intel_fbc_update [xe]] reserved 29491200 bytes of contiguous stolen space for FBC, limit: 1
<7> [259.691284] xe 0000:00:02.0: [drm:intel_fbc_update [xe]] Enabling FBC on [PLANE:273:plane 1C]
<7> [259.706972] xe 0000:00:02.0: [drm:intel_psr_post_plane_update [xe]] Enabling PSR1
<7> [259.708580] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:393:eDP-1]
<7> [259.709080] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:389:pipe C]
<7> [262.250977] xe 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turning VDD off
<7> [262.251574] xe 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x00000067
<6> [267.547757] [IGT] kms_flip: finished subtest C-eDP1, FAIL
<6> [267.550700] [IGT] kms_flip: finished subtest flip-vs-expired-vblank, FAIL
<7> [267.568707] xe 0000:00:02.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:434] from all active usage due to RMFB ioctl
<7> [267.568815] xe 0000:00:02.0: [drm:atomic_remove_fb] Disabling [PLANE:273:plane 1C] because [FB:434] is removed
<7> [267.568920] xe 0000:00:02.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:273:plane 1C] ddb ( 0 - 2003) -> ( 0 - 0), size 2003 -> 0
<7> [267.569171] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:273:plane 1C] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [267.569363] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:273:plane 1C] lines 4, 13, 13, 13, 13, 13, 0, 0, 0, 4, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [267.569599] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:273:plane 1C] blocks 92, 297, 297, 297, 297, 297, 0, 0, 197, 92, 197 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [267.569791] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:273:plane 1C] min_ddb 183, 456, 456, 456, 456, 456, 0, 0, 198, 183, 198 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [267.570006] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:389:pipe C] data rate 0 num active planes 0
<7> [267.570292] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 34060 required 0 qgv_peak_bw: 38400
<7> [267.570589] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [267.570870] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [267.571159] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [267.571447] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 0
<7> [267.572284] xe 0000:00:02.0: [drm:intel_psr_disable_locked [xe]] Disabling PSR1
<7> [267.578747] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turning VDD on
<7> [267.579128] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006f
<7> [267.594682] xe 0000:00:02.0: [drm:__intel_fbc_disable [xe]] Disabling FBC on [PLANE:273:plane 1C]
<7> [267.618622] xe 0000:00:02.0: [drm:compute_baseline_pipe_bpp [xe]] [CONNECTOR:393:eDP-1] Limiting target display pipe bpp to 30 (EDID bpp 30, max requested bpp 36, max platform bpp 36)
<7> [267.618997] xe 0000:00:02.0: [drm:intel_dp_compute_config_limits [xe]] [ENCODER:392:DDI A/PHY A][CRTC:151:pipe A] DP link limits: pixel clock 347710 kHz DSC off max lanes 4 max rate 810000 max pipe_bpp 30 min link_bpp 18.0000 max link_bpp 30.0000
<7> [267.619318] xe 0000:00:02.0: [drm:intel_dp_compute_output_format [xe]] DP lane count 4 clock 432000 bpp input 30 compressed 0.0000 HDR no link rate required 1303913 available 1728000
<7> [267.619657] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:151:pipe A] hw max bpp: 30, pipe bpp: 30, dithering: 0
<7> [267.619988] xe 0000:00:02.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:392:DDI A/PHY A] [CRTC:151:pipe A]
<7> [267.620327] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [267.620612] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [267.620880] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [267.621174] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [267.621415] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in dp_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 6329869/8388608 link 421991/524288)
<7> [267.621677] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000100)
<7> [267.621915] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [267.622155] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 2880)
<7> [267.622399] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 3040)
<7> [267.622905] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 2880)
<7> [267.623152] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 3040)
<7> [267.623397] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2928)
<7> [267.623629] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2960)
<7> [267.623840] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1800)
<7> [267.624042] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1800)
<7> [267.624241] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1803)
<7> [267.624434] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1809)
<7> [267.624641] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1906)
<7> [267.624838] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1906)
<7> [267.625023] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 2880)
<7> [267.625209] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 3040)
<7> [267.625387] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 2880)
<7> [267.625564] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 3040)
<7> [267.625730] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2928)
<7> [267.625891] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2960)
<7> [267.626069] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1800)
<7> [267.626239] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1800)
<7> [267.626412] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1803)
<7> [267.626586] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1809)
<7> [267.626729] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1906)
<7> [267.626867] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1906)
<7> [267.626999] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [267.627132] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [267.627266] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [267.627399] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 30)
<7> [267.627523] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 347710)
<7> [267.627771] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 347710)
<7> [267.627917] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in port_clock (expected 0, found 432000)
<7> [267.628051] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:151:pipe A] fastset requirement not met, forcing full modeset
<7> [267.628172] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.enable (expected yes, found no)
<7> [267.628291] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.active (expected yes, found no)
<7> [267.628409] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in cpu_transcoder (expected 2, found -1)
<7> [267.628522] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in lane_count (expected 4, found 0)
<7> [267.628649] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in dp_m_n (expected tu 64 data 6329869/8388608 link 421991/524288, found tu 0, data 0/0 link 0/0)
<7> [267.628757] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in output_types (expected 0x00000100, found 0x00000000)
<7> [267.628860] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [267.628961] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 2880, found 0)
<7> [267.629061] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 3040, found 0)
<7> [267.629161] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 2880, found 0)
<7> [267.629260] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 3040, found 0)
<7> [267.629355] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2928, found 0)
<7> [267.629448] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2960, found 0)
<7> [267.629546] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1800, found 0)
<7> [267.629640] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1800, found 0)
<7> [267.629734] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1803, found 0)
<7> [267.629832] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1809, found 0)
<7> [267.629925] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1906, found 0)
<7> [267.630015] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1906, found 0)
<7> [267.630103] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 2880, found 0)
<7> [267.630188] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 3040, found 0)
<7> [267.630273] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 2880, found 0)
<7> [267.630357] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 3040, found 0)
<7> [267.630439] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2928, found 0)
<7> [267.630519] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2960, found 0)
<7> [267.630620] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1800, found 0)
<7> [267.630715] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1800, found 0)
<7> [267.630809] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1803, found 0)
<7> [267.630895] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1809, found 0)
<7> [267.630973] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1906, found 0)
<7> [267.631051] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1906, found 0)
<7> [267.631123] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [267.631195] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)
<7> [267.631266] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [267.631343] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in pipe_bpp (expected 30, found 0)
<7> [267.631420] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 347710, found 0)
<7> [267.631498] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 347710, found 0)
<7> [267.631606] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in port_clock (expected 432000, found 0)
<7> [267.631699] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:389:pipe C] fastset requirement not met, forcing full modeset
<7> [267.631792] xe 0000:00:02.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:389:pipe C] releasing DPLL 0
<7> [267.631944] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xd -> 0xf (total dbuf slices 0xf), mbus joined? no->yes
<7> [267.632020] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:151:pipe A] dbuf slices 0x0 -> 0xf, ddb (0 - 0) -> (0 - 4096), active pipes 0x4 -> 0x1
<7> [267.632095] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:389:pipe C] dbuf slices 0xc -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x4 -> 0x1
<7> [267.632170] xe 0000:00:02.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:35:plane 1A] ddb ( 0 - 0) -> ( 0 - 4051), size 0 -> 4051
<7> [267.632235] xe 0000:00:02.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:145:cursor A] ddb ( 0 - 0) -> (4051 - 4096), size 0 -> 45
<7> [267.632293] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:35:plane 1A] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm
<7> [267.632349] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:35:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 13, 13, 13, 13, 13, 0, 0, 0, 3, 0
<7> [267.632405] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:35:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 24, 313, 313, 313, 313, 313, 0, 0, 38, 73, 87
<7> [267.632469] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:35:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 28, 346, 346, 346, 346, 346, 0, 0, 39, 82, 88
<7> [267.632542] xe 0000:00:02.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:383:cursor C] ddb (2003 - 2048) -> ( 0 - 0), size 45 -> 0
<7> [267.632633] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:151:pipe A] data rate 1390840 num active planes 1
<7> [267.632750] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23529 required 1461 qgv_peak_bw: 38400
<7> [267.632856] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36259 required 1461 qgv_peak_bw: 38400
<7> [267.632940] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [267.633029] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [267.633118] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 1461
<7> [267.633209] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:151:pipe A] min cdclk: 0 kHz -> 173855 kHz
<7> [267.633297] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:389:pipe C] min cdclk: 173855 kHz -> 0 kHz
<7> [267.633411] xe 0000:00:02.0: [drm:intel_find_dpll [xe]] [CRTC:151:pipe A] allocated DPLL 0
<7> [267.633498] xe 0000:00:02.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:151:pipe A] reserving DPLL 0
<7> [267.633617] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:151:pipe A] enable: yes [modeset]
<7> [267.633742] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: EDP (0x100), output format: RGB, sink format: RGB
<7> [267.633848] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: A, pipe bpp: 30, dithering: 0
<7> [267.633943] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [267.634024] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [267.634106] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [267.634194] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [267.634279] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dp m_n: lanes: 4; data_m: 6329869, data_n: 8388608, link_m: 421991, link_n: 524288, tu: 64
<7> [267.634366] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dp m2_n2: lanes: 4; data_m: 0, data_n: 0, link_m: 0, link_n: 0, tu: 0
<7> [267.634450] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: disabled
<7> [267.634528] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled
<7> [267.634637] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] psr: enabled, selective update: enabled, panel replay: disabled, selective fetch: enabled
<7> [267.634738] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] minimum hblank: 0
<7> [267.634837] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] audio: 0, infoframes: 0, infoframes enabled: 0x4
<7> [267.634936] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] DP SDP: VSC, revision 4, length 14
<7> [267.635023] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pixelformat: RGB
<7> [267.635099] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] colorimetry: sRGB
<7> [267.635175] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] bpc: 0
<7> [267.635257] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dynamic range: VESA range
<7> [267.635342] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] content type: Not defined
<7> [267.635422] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [267.635502] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [267.635598] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: no, fixed rr: yes, vmin: 1906, vmax: 1906, flipline: 1906, pipeline full: 0, guardband: 37 vsync start: 103, vsync end: 97
<7> [267.635698] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: vmin vblank: 1869, vmax vblank: 1869, vmin vtotal: 1906, vmax vtotal: 1906
<7> [267.635796] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [267.635893] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] requested mode: "2880x1800": 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa
<7> [267.635977] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] adjusted mode: "2880x1800": 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa
<7> [267.636059] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=347710, hd=2880 hb=2880-3040 hs=2928-2960 ht=3040, vd=1800 vb=1800-1906 vs=1803-1809 vt=1906, flags=0xa
<7> [267.636140] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe mode: "2880x1800": 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x40 0xa
<7> [267.636216] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=347710, hd=2880 hb=2880-3040 hs=2928-2960 ht=3040, vd=1800 vb=1800-1906 vs=1803-1809 vt=1906, flags=0xa
<7> [267.636291] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port clock: 432000, pixel rate 347710, min cdclk 173855, min voltage level 0
<7> [267.636371] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] linetime: 70, ips linetime: 0
<7> [267.636452] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0
<7> [267.636540] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe src: 2880x1800+0+0
<7> [267.636640] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [267.636741] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [267.636838] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [267.636931] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cx0pll_hw_state: lane_count: 4, ssc_enabled: no, use_c10: yes, tbt_mode: no
<7> [267.637015] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_hw_state: fracen: no,
<7> [267.637098] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] multiplier: 225, tx_clk_div: 1.
<7> [267.637177] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_rawhw_state:
<7> [267.637254] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] tx: 0x10, cmn: 0x21
<7> [267.637328] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[0] = 0x4, pll[1] = 0x0, pll[2] = 0xa2, pll[3] = 0x1
<7> [267.637404] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[4] = 0x0, pll[5] = 0x0, pll[6] = 0x0, pll[7] = 0x0
<7> [267.637481] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[8] = 0x0, pll[9] = 0x1, pll[10] = 0x0, pll[11] = 0x0
<7> [267.637595] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[12] = 0x0, pll[13] = 0x0, pll[14] = 0x0, pll[15] = 0x1
<7> [267.637692] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[16] = 0x85, pll[17] = 0xf, pll[18] = 0xe6, pll[19] = 0x23
<7> [267.637786] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [267.637881] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [267.637963] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [267.638042] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [267.638118] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [267.638196] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [267.638275] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [267.638354] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [267.638433] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [267.638508] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [267.638602] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [267.638697] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [267.638792] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:35:plane 1A] fb: [FB:394] 2880x1800 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [267.638897] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [267.638991] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] src: 2880.000000x1800.000000+0.000000+0.000000 dst: 2880x1800+0+0
<7> [267.639081] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [267.639162] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [267.639233] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [267.639311] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [267.639391] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:145:cursor A] fb: [NOFB], visible: no
<7> [267.639470] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:389:pipe C] enable: no [modeset]
<7> [267.639554] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:273:plane 1C] fb: [NOFB], visible: no
<7> [267.639653] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:303:plane 2C] fb: [NOFB], visible: no
<7> [267.639751] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:333:plane 3C] fb: [NOFB], visible: no
<7> [267.639849] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:363:plane 4C] fb: [NOFB], visible: no
<7> [267.639934] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:373:plane 5C] fb: [NOFB], visible: no
<7> [267.640014] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:383:cursor C] fb: [NOFB], visible: no
<7> [267.640184] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_A
<7> [267.640340] xe 0000:00:02.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating enabled for pipe_mask=0x1 (CLKGATE_DIS_DSSDSC=0x88000000)
<7> [267.640517] xe 0000:00:02.0: [drm:intel_alpm_disable [xe]] Disabling ALPM
<7> [267.640634] xe 0000:00:02.0: [drm:intel_edp_backlight_off [xe]]
<7> [267.842832] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:393:eDP-1] set backlight PWM = 0
<7> [267.843381] xe 0000:00:02.0: [drm:intel_disable_transcoder [xe]] disabling pipe C
<7> [267.844814] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turn panel power off
<7> [267.845067] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 wait for panel power off time
<7> [267.845307] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 mask: 0xb0000000 value: 0x00000000 PP_STATUS: 0xa0000002 PP_CONTROL: 0x00000060
<7> [267.897686] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [267.898022] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:392:DDI A/PHY A] - short
<7> [267.905589] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [267.905892] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disable DPLL 0 (active 0x4, on? 1) for [CRTC:389:pipe C]
<7> [267.906035] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:392:DDI A/PHY A]
<7> [267.907302] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disabling DPLL 0
<7> [267.907770] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling AUX_A
<7> [267.908212] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:392:DDI A/PHY A]
<7> [267.908600] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:401:DDI TC1/PHY TC1]
<7> [267.908905] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:403:DP-MST A]
<7> [267.909232] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:404:DP-MST B]
<7> [267.909591] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:405:DP-MST C]
<7> [267.909903] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:413:DDI TC2/PHY TC2]
<7> [267.910222] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:415:DP-MST A]
<7> [267.910574] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:416:DP-MST B]
<7> [267.910974] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:417:DP-MST C]
<7> [267.911357] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:421:DDI TC3/PHY TC3]
<7> [267.911761] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:423:DP-MST A]
<7> [267.912091] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:424:DP-MST B]
<7> [267.912415] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:425:DP-MST C]
<7> [267.912912] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xf
<7> [267.913478] xe 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: no -> yes (pipe: *)
<7> [267.913870] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 8 (mbus joined: yes)
<7> [267.914222] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling AUX_A
<7> [267.915556] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:151:pipe A]
<7> [267.915987] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enabling DPLL 0
<7> [267.918381] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turn panel power on
<7> [267.918753] xe 0000:00:02.0: [drm:wait_panel_power_cycle [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 wait for panel power cycle (488 ms remaining)
<7> [267.937703] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [267.938148] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:392:DDI A/PHY A] - long
<7> [267.938616] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 10
<7> [267.939201] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:392:DDI A/PHY A]
<7> [268.411051] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 mask: 0xb800000f value: 0x00000000 PP_STATUS: 0x08000001 PP_CONTROL: 0x00000060
<7> [268.498697] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [268.499031] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 wait for panel power on
<7> [268.499304] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 mask: 0xb000000f value: 0x80000008 PP_STATUS: 0x9000000a PP_CONTROL: 0x00000063
<7> [268.535822] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [268.536137] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:392:DDI A/PHY A] - long
<7> [268.536419] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 20
<7> [268.536841] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:392:DDI A/PHY A]
<7> [268.706698] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [268.707051] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turning VDD on
<7> [268.707320] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006b
<7> [268.709279] xe 0000:00:02.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX A/DDI A/PHY A: DPCD: 14 1e 44 c1 00 00 01 c0 02 00 02 00 00 0b 80
<7> [268.710772] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Reloading eDP link rates
<7> [268.711473] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Using LINK_RATE_SET value 05
<7> [268.712599] xe 0000:00:02.0: [drm:intel_dp_set_signal_levels [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] 8b/10b, lanes: 4, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0
<7> [268.713940] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Using DP training pattern TPS1
<7> [268.715026] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Clock recovery OK
<7> [268.715369] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Using DP training pattern TPS4
<7> [268.716988] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Channel EQ done. DP Training successful
<7> [268.717537] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Link Training passed at link rate = 432000, lane count = 4
<7> [268.718447] xe 0000:00:02.0: [drm:intel_enable_transcoder [xe]] enabling pipe A
<7> [268.719105] xe 0000:00:02.0: [drm:intel_edp_backlight_on [xe]]
<7> [268.719572] xe 0000:00:02.0: [drm:intel_backlight_enable [xe]] pipe A
<7> [268.720069] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:393:eDP-1] set backlight PWM = 4518
<7> [268.752802] xe 0000:00:02.0: [drm:intel_psr_post_plane_update [xe]] Enabling PSR2
<7> [268.754361] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:393:eDP-1]
<7> [268.754686] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:151:pipe A]
<7> [268.757409] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_C
<7> [268.757781] xe 0000:00:02.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating disabled for pipe_mask=0x4 (CLKGATE_DIS_DSSDSC=0xa8000000)
<7> [268.758143] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_2
<7> [268.758543] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:389:pipe C]
<7> [268.759026] xe 0000:00:02.0: [drm:drm_client_dev_restore] fbdev: ret=0
<6> [268.760391] [IGT] kms_flip: exiting, ret=98
Created at 2026-06-03 08:30:45