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

Result: Fail

i915_display_info16 igt_runner16 results16.json results16-xe-load.json guc_logs16.tar i915_display_info_post_exec16 serial_data16 boot16 dmesg16

DetailValue
Duration 9.15 seconds
Hostname 
shard-lnl-8
Igt-Version 
IGT-Version: 2.4-g98b65acc4 (x86_64) (Linux: 7.1.0-rc5-lgci-xe-xe-5121-a79fb6644b307c0ce-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:2220 __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.153s)
Subtest flip-vs-expired-vblank-interruptible: FAIL (23.579s)
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
[401.836325] (kms_flip:6227) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931:
[401.836392] (kms_flip:6227) CRITICAL: Failed assertion: end - start < 500
[401.836415] (kms_flip:6227) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest C-eDP1 failed.
**** DEBUG ****
[399.818166] (kms_flip:6227) DEBUG: Vblank took 129us
[399.834653] (kms_flip:6227) DEBUG: name = flip
last_ts = 400.075806
last_received_ts = 400.075165
last_seq = 441
current_ts = 400.092468
current_received_ts = 400.091797
current_seq = 442
count = 354
seq_step = 1
[399.834746] (kms_flip:6227) DEBUG: Vblank took 85us
[399.851388] (kms_flip:6227) DEBUG: name = flip
last_ts = 400.092468
last_received_ts = 400.091797
last_seq = 442
current_ts = 400.109131
current_received_ts = 400.108521
current_seq = 443
count = 355
seq_step = 1
[399.851483] (kms_flip:6227) DEBUG: Vblank took 76us
[399.867893] (kms_flip:6227) DEBUG: name = flip
last_ts = 400.109131
last_received_ts = 400.108521
last_seq = 443
current_ts = 400.125793
current_received_ts = 400.125031
current_seq = 444
count = 356
seq_step = 1
[399.868063] (kms_flip:6227) DEBUG: Vblank took 152us
[399.884652] (kms_flip:6227) DEBUG: name = flip
last_ts = 400.125793
last_received_ts = 400.125031
last_seq = 444
current_ts = 400.142456
current_received_ts = 400.141785
current_seq = 445
count = 357
seq_step = 1
[399.884738] (kms_flip:6227) DEBUG: Vblank took 77us
[399.901290] (kms_flip:6227) DEBUG: name = flip
last_ts = 400.142456
last_received_ts = 400.141785
last_seq = 445
current_ts = 400.159119
current_received_ts = 400.158447
current_seq = 446
count = 358
seq_step = 1
[399.901338] (kms_flip:6227) DEBUG: Vblank took 44us
[399.917907] (kms_flip:6227) DEBUG: name = flip
last_ts = 400.159119
last_received_ts = 400.158447
last_seq = 446
current_ts = 400.175781
current_received_ts = 400.175049
current_seq = 447
count = 359
seq_step = 1
[399.917982] (kms_flip:6227) DEBUG: Vblank took 71us
[399.934592] (kms_flip:6227) DEBUG: name = flip
last_ts = 400.175781
last_received_ts = 400.175049
last_seq = 447
current_ts = 400.192444
current_received_ts = 400.191742
current_seq = 448
count = 360
seq_step = 1
[399.934636] (kms_flip:6227) DEBUG: Vblank took 41us
[399.951278] (kms_flip:6227) DEBUG: name = flip
last_ts = 400.192444
last_received_ts = 400.191742
last_seq = 448
current_ts = 400.209106
current_received_ts = 400.208405
current_seq = 449
count = 361
seq_step = 1
[399.951325] (kms_flip:6227) DEBUG: Vblank took 45us
[399.967925] (kms_flip:6227) DEBUG: name = flip
last_ts = 400.209106
last_received_ts = 400.208405
last_seq = 449
current_ts = 400.225769
current_received_ts = 400.225067
current_seq = 450
count = 362
seq_step = 1
[399.967973] (kms_flip:6227) DEBUG: Vblank took 45us
[399.984486] (kms_flip:6227) DEBUG: name = flip
last_ts = 400.225769
last_received_ts = 400.225067
last_seq = 450
current_ts = 400.242432
current_received_ts = 400.241608
current_seq = 451
count = 363
seq_step = 1
[399.984533] (kms_flip:6227) DEBUG: Vblank took 44us
[400.001277] (kms_flip:6227) DEBUG: name = flip
last_ts = 400.242432
last_received_ts = 400.241608
last_seq = 451
current_ts = 400.259064
current_received_ts = 400.258392
current_seq = 452
count = 364
seq_step = 1
[400.001335] (kms_flip:6227) DEBUG: Vblank took 53us
[400.017926] (kms_flip:6227) DEBUG: name = flip
last_ts = 400.259064
last_received_ts = 400.258392
last_seq = 452
current_ts = 400.275726
current_received_ts = 400.275055
current_seq = 453
count = 365
seq_step = 1
[400.017970] (kms_flip:6227) DEBUG: Vblank took 41us
[400.034497] (kms_flip:6227) DEBUG: name = flip
last_ts = 400.275726
last_received_ts = 400.275055
last_seq = 453
current_ts = 400.292389
current_received_ts = 400.291626
current_seq = 454
count = 366
seq_step = 1
[400.034536] (kms_flip:6227) DEBUG: Vblank took 35us
[400.051117] (kms_flip:6227) DEBUG: name = flip
last_ts = 400.292389
last_received_ts = 400.291626
last_seq = 454
current_ts = 400.309052
current_received_ts = 400.308228
current_seq = 455
count = 367
seq_step = 1
[400.051165] (kms_flip:6227) DEBUG: Vblank took 45us
[400.067832] (kms_flip:6227) DEBUG: name = flip
last_ts = 400.309052
last_received_ts = 400.308228
last_seq = 455
current_ts = 400.325714
current_received_ts = 400.324951
current_seq = 456
count = 368
seq_step = 1
[400.067887] (kms_flip:6227) DEBUG: Vblank took 53us
[400.084478] (kms_flip:6227) DEBUG: name = flip
last_ts = 400.325714
last_received_ts = 400.324951
last_seq = 456
current_ts = 400.342377
current_received_ts = 400.341583
current_seq = 457
count = 369
seq_step = 1
[400.084532] (kms_flip:6227) DEBUG: Vblank took 50us
[400.101130] (kms_flip:6227) DEBUG: name = flip
last_ts = 400.342377
last_received_ts = 400.341583
last_seq = 457
current_ts = 400.359039
current_received_ts = 400.358246
current_seq = 458
count = 370
seq_step = 1
[400.101182] (kms_flip:6227) DEBUG: Vblank took 49us
[400.117800] (kms_flip:6227) DEBUG: name = flip
last_ts = 400.359039
last_received_ts = 400.358246
last_seq = 458
current_ts = 400.375702
current_received_ts = 400.374908
current_seq = 459
count = 371
seq_step = 1
[400.117847] (kms_flip:6227) DEBUG: Vblank took 45us
[400.134455] (kms_flip:6227) DEBUG: name = flip
last_ts = 400.375702
last_received_ts = 400.374908
last_seq = 459
current_ts = 400.392365
current_received_ts = 400.391571
current_seq = 460
count = 372
seq_step = 1
[400.134500] (kms_flip:6227) DEBUG: Vblank took 42us
[400.151116] (kms_flip:6227) DEBUG: name = flip
last_ts = 400.392365
last_received_ts = 400.391571
last_seq = 460
current_ts = 400.409027
current_received_ts = 400.408234
current_seq = 461
count = 373
seq_step = 1
[400.151159] (kms_flip:6227) DEBUG: Vblank took 41us
[400.167790] (kms_flip:6227) DEBUG: name = flip
last_ts = 400.409027
last_received_ts = 400.408234
last_seq = 461
current_ts = 400.425690
current_received_ts = 400.424896
current_seq = 462
count = 374
seq_step = 1
[400.167833] (kms_flip:6227) DEBUG: Vblank took 40us
[400.184447] (kms_flip:6227) DEBUG: name = flip
last_ts = 400.425690
last_received_ts = 400.424896
last_seq = 462
current_ts = 400.442352
current_received_ts = 400.441528
current_seq = 463
count = 375
seq_step = 1
[400.184493] (kms_flip:6227) DEBUG: Vblank took 44us
[400.201079] (kms_flip:6227) DEBUG: name = flip
last_ts = 400.442352
last_received_ts = 400.441528
last_seq = 463
current_ts = 400.459015
current_received_ts = 400.458160
current_seq = 464
count = 376
seq_step = 1
[400.201122] (kms_flip:6227) DEBUG: Vblank took 40us
[400.217773] (kms_flip:6227) DEBUG: name = flip
last_ts = 400.459015
last_received_ts = 400.458160
last_seq = 464
current_ts = 400.475677
current_received_ts = 400.474854
current_seq = 465
count = 377
seq_step = 1
[400.217815] (kms_flip:6227) DEBUG: Vblank took 39us
[400.234459] (kms_flip:6227) DEBUG: name = flip
last_ts = 400.475677
last_received_ts = 400.474854
last_seq = 465
current_ts = 400.492340
current_received_ts = 400.491547
current_seq = 466
count = 378
seq_step = 1
[400.234505] (kms_flip:6227) DEBUG: Vblank took 43us
[400.251071] (kms_flip:6227) DEBUG: name = flip
last_ts = 400.492340
last_received_ts = 400.491547
last_seq = 466
current_ts = 400.509003
current_received_ts = 400.508148
current_seq = 467
count = 379
seq_step = 1
[400.251113] (kms_flip:6227) DEBUG: Vblank took 40us
[400.267775] (kms_flip:6227) DEBUG: name = flip
last_ts = 400.509003
last_received_ts = 400.508148
last_seq = 467
current_ts = 400.525635
current_received_ts = 400.524841
current_seq = 468
count = 380
seq_step = 1
[400.267892] (kms_flip:6227) DEBUG: Vblank took 115us
[400.284447] (kms_flip:6227) DEBUG: name = flip
last_ts = 400.525635
last_received_ts = 400.524841
last_seq = 468
current_ts = 400.542297
current_received_ts = 400.541534
current_seq = 469
count = 381
seq_step = 1
[400.284562] (kms_flip:6227) DEBUG: Vblank took 113us
[400.301069] (kms_flip:6227) DEBUG: name = flip
last_ts = 400.542297
last_received_ts = 400.541534
last_seq = 469
current_ts = 400.558960
current_received_ts = 400.558136
current_seq = 470
count = 382
seq_step = 1
[400.301111] (kms_flip:6227) DEBUG: Vblank took 39us
[400.317767] (kms_flip:6227) DEBUG: name = flip
last_ts = 400.558960
last_received_ts = 400.558136
last_seq = 470
current_ts = 400.575653
current_received_ts = 400.574829
current_seq = 471
count = 383
seq_step = 1
[400.317812] (kms_flip:6227) DEBUG: Vblank took 43us
[400.334425] (kms_flip:6227) DEBUG: name = flip
last_ts = 400.575653
last_received_ts = 400.574829
last_seq = 471
current_ts = 400.592285
current_received_ts = 400.591492
current_seq = 472
count = 384
seq_step = 1
[400.334468] (kms_flip:6227) DEBUG: Vblank took 42us
[400.351065] (kms_flip:6227) DEBUG: name = flip
last_ts = 400.592285
last_received_ts = 400.591492
last_seq = 472
current_ts = 400.608948
current_received_ts = 400.608124
current_seq = 473
count = 385
seq_step = 1
[400.351108] (kms_flip:6227) DEBUG: Vblank took 41us
[400.367751] (kms_flip:6227) DEBUG: name = flip
last_ts = 400.608948
last_received_ts = 400.608124
last_seq = 473
current_ts = 400.625610
current_received_ts = 400.624817
current_seq = 474
count = 386
seq_step = 1
[400.367793] (kms_flip:6227) DEBUG: Vblank took 39us
[400.384424] (kms_flip:6227) DEBUG: name = flip
last_ts = 400.625610
last_received_ts = 400.624817
last_seq = 474
current_ts = 400.642273
current_received_ts = 400.641479
current_seq = 475
count = 387
seq_step = 1
[400.384545] (kms_flip:6227) DEBUG: Vblank took 118us
[400.401063] (kms_flip:6227) DEBUG: name = flip
last_ts = 400.642273
last_received_ts = 400.641479
last_seq = 475
current_ts = 400.658936
current_received_ts = 400.658112
current_seq = 476
count = 388
seq_step = 1
[400.401105] (kms_flip:6227) DEBUG: Vblank took 40us
[400.417745] (kms_flip:6227) DEBUG: name = flip
last_ts = 400.658936
last_received_ts = 400.658112
last_seq = 476
current_ts = 400.675598
current_received_ts = 400.674805
current_seq = 477
count = 389
seq_step = 1
[400.417870] (kms_flip:6227) DEBUG: Vblank took 122us
[400.434419] (kms_flip:6227) DEBUG: name = flip
last_ts = 400.675598
last_received_ts = 400.674805
last_seq = 477
current_ts = 400.692261
current_received_ts = 400.691467
current_seq = 478
count = 390
seq_step = 1
[400.434467] (kms_flip:6227) DEBUG: Vblank took 45us
[400.451070] (kms_flip:6227) DEBUG: name = flip
last_ts = 400.692261
last_received_ts = 400.691467
last_seq = 478
current_ts = 400.708923
current_received_ts = 400.708099
current_seq = 479
count = 391
seq_step = 1
[400.451116] (kms_flip:6227) DEBUG: Vblank took 44us
[400.467738] (kms_flip:6227) DEBUG: name = flip
last_ts = 400.708923
last_received_ts = 400.708099
last_seq = 479
current_ts = 400.725586
current_received_ts = 400.724792
current_seq = 480
count = 392
seq_step = 1
[400.467785] (kms_flip:6227) DEBUG: Vblank took 46us
[400.484397] (kms_flip:6227) DEBUG: name = flip
last_ts = 400.725586
last_received_ts = 400.724792
last_seq = 480
current_ts = 400.742249
current_received_ts = 400.741425
current_seq = 481
count = 393
seq_step = 1
[400.484434] (kms_flip:6227) DEBUG: Vblank took 35us
[400.501050] (kms_flip:6227) DEBUG: name = flip
last_ts = 400.742249
last_received_ts = 400.741425
last_seq = 481
current_ts = 400.758911
current_received_ts = 400.758087
current_seq = 482
count = 394
seq_step = 1
[400.501092] (kms_flip:6227) DEBUG: Vblank took 39us
[400.517749] (kms_flip:6227) DEBUG: name = flip
last_ts = 400.758911
last_received_ts = 400.758087
last_seq = 482
current_ts = 400.775574
current_received_ts = 400.774780
current_seq = 483
count = 395
seq_step = 1
[400.517885] (kms_flip:6227) DEBUG: Vblank took 134us
[400.534400] (kms_flip:6227) DEBUG: name = flip
last_ts = 400.775574
last_received_ts = 400.774780
last_seq = 483
current_ts = 400.792236
current_received_ts = 400.791443
current_seq = 484
count = 396
seq_step = 1
[400.534445] (kms_flip:6227) DEBUG: Vblank took 42us
[400.551059] (kms_flip:6227) DEBUG: name = flip
last_ts = 400.792236
last_received_ts = 400.791443
last_seq = 484
current_ts = 400.808899
current_received_ts = 400.808075
current_seq = 485
count = 397
seq_step = 1
[400.551103] (kms_flip:6227) DEBUG: Vblank took 42us
[400.567724] (kms_flip:6227) DEBUG: name = flip
last_ts = 400.808899
last_received_ts = 400.808075
last_seq = 485
current_ts = 400.825562
current_received_ts = 400.824738
current_seq = 486
count = 398
seq_step = 1
[400.567777] (kms_flip:6227) DEBUG: Vblank took 50us
[400.584414] (kms_flip:6227) DEBUG: name = flip
last_ts = 400.825562
last_received_ts = 400.824738
last_seq = 486
current_ts = 400.842224
current_received_ts = 400.841431
current_seq = 487
count = 399
seq_step = 1
[400.584462] (kms_flip:6227) DEBUG: Vblank took 45us
[400.601045] (kms_flip:6227) DEBUG: name = flip
last_ts = 400.842224
last_received_ts = 400.841431
last_seq = 487
current_ts = 400.858856
current_received_ts = 400.858063
current_seq = 488
count = 400
seq_step = 1
[400.601089] (kms_flip:6227) DEBUG: Vblank took 41us
[400.617708] (kms_flip:6227) DEBUG: name = flip
last_ts = 400.858856
last_received_ts = 400.858063
last_seq = 488
current_ts = 400.875549
current_received_ts = 400.874725
current_seq = 489
count = 401
seq_step = 1
[400.617752] (kms_flip:6227) DEBUG: Vblank took 41us
[400.634397] (kms_flip:6227) DEBUG: name = flip
last_ts = 400.875549
last_received_ts = 400.874725
last_seq = 489
current_ts = 400.892181
current_received_ts = 400.891418
current_seq = 490
count = 402
seq_step = 1
[400.634444] (kms_flip:6227) DEBUG: Vblank took 44us
[400.651047] (kms_flip:6227) DEBUG: name = flip
last_ts = 400.892181
last_received_ts = 400.891418
last_seq = 490
current_ts = 400.908844
current_received_ts = 400.908051
current_seq = 491
count = 403
seq_step = 1
[400.651088] (kms_flip:6227) DEBUG: Vblank took 39us
[400.667692] (kms_flip:6227) DEBUG: name = flip
last_ts = 400.908844
last_received_ts = 400.908051
last_seq = 491
current_ts = 400.925507
current_received_ts = 400.924683
current_seq = 492
count = 404
seq_step = 1
[400.667734] (kms_flip:6227) DEBUG: Vblank took 39us
[400.684383] (kms_flip:6227) DEBUG: name = flip
last_ts = 400.925507
last_received_ts = 400.924683
last_seq = 492
current_ts = 400.942169
current_received_ts = 400.941376
current_seq = 493
count = 405
seq_step = 1
[400.684430] (kms_flip:6227) DEBUG: Vblank took 45us
[400.701054] (kms_flip:6227) DEBUG: name = flip
last_ts = 400.942169
last_received_ts = 400.941376
last_seq = 493
current_ts = 400.958832
current_received_ts = 400.958038
current_seq = 494
count = 406
seq_step = 1
[400.701095] (kms_flip:6227) DEBUG: Vblank took 39us
[400.717697] (kms_flip:6227) DEBUG: name = flip
last_ts = 400.958832
last_received_ts = 400.958038
last_seq = 494
current_ts = 400.975494
current_received_ts = 400.974701
current_seq = 495
count = 407
seq_step = 1
[400.717739] (kms_flip:6227) DEBUG: Vblank took 40us
[400.734363] (kms_flip:6227) DEBUG: name = flip
last_ts = 400.975494
last_received_ts = 400.974701
last_seq = 495
current_ts = 400.992157
current_received_ts = 400.991364
current_seq = 496
count = 408
seq_step = 1
[400.734407] (kms_flip:6227) DEBUG: Vblank took 41us
[400.751079] (kms_flip:6227) DEBUG: name = flip
last_ts = 400.992157
last_received_ts = 400.991364
last_seq = 496
current_ts = 401.008820
current_received_ts = 401.008057
current_seq = 497
count = 409
seq_step = 1
[400.751136] (kms_flip:6227) DEBUG: Vblank took 53us
[400.767701] (kms_flip:6227) DEBUG: name = flip
last_ts = 401.008820
last_received_ts = 401.008057
last_seq = 497
current_ts = 401.025482
current_received_ts = 401.024689
current_seq = 498
count = 410
seq_step = 1
[400.767752] (kms_flip:6227) DEBUG: Vblank took 48us
[400.784357] (kms_flip:6227) DEBUG: name = flip
last_ts = 401.025482
last_received_ts = 401.024689
last_seq = 498
current_ts = 401.042145
current_received_ts = 401.041321
current_seq = 499
count = 411
seq_step = 1
[400.784408] (kms_flip:6227) DEBUG: Vblank took 49us
[400.800990] (kms_flip:6227) DEBUG: name = flip
last_ts = 401.042145
last_received_ts = 401.041321
last_seq = 499
current_ts = 401.058807
current_received_ts = 401.057953
current_seq = 500
count = 412
seq_step = 1
[400.801048] (kms_flip:6227) DEBUG: Vblank took 56us
[400.817645] (kms_flip:6227) DEBUG: name = flip
last_ts = 401.058807
last_received_ts = 401.057953
last_seq = 500
current_ts = 401.075470
current_received_ts = 401.074615
current_seq = 501
count = 413
seq_step = 1
[400.817688] (kms_flip:6227) DEBUG: Vblank took 42us
[400.834324] (kms_flip:6227) DEBUG: name = flip
last_ts = 401.075470
last_received_ts = 401.074615
last_seq = 501
current_ts = 401.092133
current_received_ts = 401.091309
current_seq = 502
count = 414
seq_step = 1
[400.834373] (kms_flip:6227) DEBUG: Vblank took 46us
[400.850979] (kms_flip:6227) DEBUG: name = flip
last_ts = 401.092133
last_received_ts = 401.091309
last_seq = 502
current_ts = 401.108765
current_received_ts = 401.107941
current_seq = 503
count = 415
seq_step = 1
[400.851045] (kms_flip:6227) DEBUG: Vblank took 64us
[400.867638] (kms_flip:6227) DEBUG: name = flip
last_ts = 401.108765
last_received_ts = 401.107941
last_seq = 503
current_ts = 401.125427
current_received_ts = 401.124603
current_seq = 504
count = 416
seq_step = 1
[400.867681] (kms_flip:6227) DEBUG: Vblank took 41us
[400.884307] (kms_flip:6227) DEBUG: name = flip
last_ts = 401.125427
last_received_ts = 401.124603
last_seq = 504
current_ts = 401.142090
current_received_ts = 401.141266
current_seq = 505
count = 417
seq_step = 1
[400.884354] (kms_flip:6227) DEBUG: Vblank took 44us
[400.900977] (kms_flip:6227) DEBUG: name = flip
last_ts = 401.142090
last_received_ts = 401.141266
last_seq = 505
current_ts = 401.158752
current_received_ts = 401.157928
current_seq = 506
count = 418
seq_step = 1
[400.901022] (kms_flip:6227) DEBUG: Vblank took 43us
[400.917638] (kms_flip:6227) DEBUG: name = flip
last_ts = 401.158752
last_received_ts = 401.157928
last_seq = 506
current_ts = 401.175415
current_received_ts = 401.174591
current_seq = 507
count = 419
seq_step = 1
[400.917690] (kms_flip:6227) DEBUG: Vblank took 50us
[400.934296] (kms_flip:6227) DEBUG: name = flip
last_ts = 401.175415
last_received_ts = 401.174591
last_seq = 507
current_ts = 401.192078
current_received_ts = 401.191254
current_seq = 508
count = 420
seq_step = 1
[400.934336] (kms_flip:6227) DEBUG: Vblank took 38us
[400.950997] (kms_flip:6227) DEBUG: name = flip
last_ts = 401.192078
last_received_ts = 401.191254
last_seq = 508
current_ts = 401.208740
current_received_ts = 401.207916
current_seq = 509
count = 421
seq_step = 1
[400.951134] (kms_flip:6227) DEBUG: Vblank took 122us
[400.967631] (kms_flip:6227) DEBUG: name = flip
last_ts = 401.208740
last_received_ts = 401.207916
last_seq = 509
current_ts = 401.225403
current_received_ts = 401.224579
current_seq = 510
count = 422
seq_step = 1
[400.967674] (kms_flip:6227) DEBUG: Vblank took 41us
[400.984284] (kms_flip:6227) DEBUG: name = flip
last_ts = 401.225403
last_received_ts = 401.224579
last_seq = 510
current_ts = 401.242065
current_received_ts = 401.241211
current_seq = 511
count = 423
seq_step = 1
[400.984325] (kms_flip:6227) DEBUG: Vblank took 39us
[401.000965] (kms_flip:6227) DEBUG: name = flip
last_ts = 401.242065
last_received_ts = 401.241211
last_seq = 511
current_ts = 401.258728
current_received_ts = 401.257904
current_seq = 512
count = 424
seq_step = 1
[401.001004] (kms_flip:6227) DEBUG: Vblank took 34us
[401.017628] (kms_flip:6227) DEBUG: name = flip
last_ts = 401.258728
last_received_ts = 401.257904
last_seq = 512
current_ts = 401.275391
current_received_ts = 401.274567
current_seq = 513
count = 425
seq_step = 1
[401.017673] (kms_flip:6227) DEBUG: Vblank took 42us
[401.034302] (kms_flip:6227) DEBUG: name = flip
last_ts = 401.275391
last_received_ts = 401.274567
last_seq = 513
current_ts = 401.292053
current_received_ts = 401.291229
current_seq = 514
count = 426
seq_step = 1
[401.034352] (kms_flip:6227) DEBUG: Vblank took 47us
[401.050976] (kms_flip:6227) DEBUG: name = flip
last_ts = 401.292053
last_received_ts = 401.291229
last_seq = 514
current_ts = 401.308716
current_received_ts = 401.307892
current_seq = 515
count = 427
seq_step = 1
[401.051021] (kms_flip:6227) DEBUG: Vblank took 43us
[401.067618] (kms_flip:6227) DEBUG: name = flip
last_ts = 401.308716
last_received_ts = 401.307892
last_seq = 515
current_ts = 401.325378
current_received_ts = 401.324554
current_seq = 516
count = 428
seq_step = 1
[401.067663] (kms_flip:6227) DEBUG: Vblank took 42us
[401.084282] (kms_flip:6227) DEBUG: name = flip
last_ts = 401.325378
last_received_ts = 401.324554
last_seq = 516
current_ts = 401.342041
current_received_ts = 401.341217
current_seq = 517
count = 429
seq_step = 1
[401.084325] (kms_flip:6227) DEBUG: Vblank took 40us
[401.100956] (kms_flip:6227) DEBUG: name = flip
last_ts = 401.342041
last_received_ts = 401.341217
last_seq = 517
current_ts = 401.358704
current_received_ts = 401.357880
current_seq = 518
count = 430
seq_step = 1
[401.100999] (kms_flip:6227) DEBUG: Vblank took 40us
[401.117624] (kms_flip:6227) DEBUG: name = flip
last_ts = 401.358704
last_received_ts = 401.357880
last_seq = 518
current_ts = 401.375336
current_received_ts = 401.374542
current_seq = 519
count = 431
seq_step = 1
[401.117672] (kms_flip:6227) DEBUG: Vblank took 45us
[401.134268] (kms_flip:6227) DEBUG: name = flip
last_ts = 401.375336
last_received_ts = 401.374542
last_seq = 519
current_ts = 401.391998
current_received_ts = 401.391174
current_seq = 520
count = 432
seq_step = 1
[401.134308] (kms_flip:6227) DEBUG: Vblank took 38us
[401.150955] (kms_flip:6227) DEBUG: name = flip
last_ts = 401.391998
last_received_ts = 401.391174
last_seq = 520
current_ts = 401.408661
current_received_ts = 401.407867
current_seq = 521
count = 433
seq_step = 1
[401.151001] (kms_flip:6227) DEBUG: Vblank took 44us
[401.167620] (kms_flip:6227) DEBUG: name = flip
last_ts = 401.408661
last_received_ts = 401.407867
last_seq = 521
current_ts = 401.425323
current_received_ts = 401.424530
current_seq = 522
count = 434
seq_step = 1
[401.167660] (kms_flip:6227) DEBUG: Vblank took 38us
[401.184427] (kms_flip:6227) DEBUG: name = flip
last_ts = 401.425323
last_received_ts = 401.424530
last_seq = 522
current_ts = 401.441986
current_received_ts = 401.441315
current_seq = 523
count = 435
seq_step = 1
[401.184477] (kms_flip:6227) DEBUG: Vblank took 41us
[401.200979] (kms_flip:6227) DEBUG: name = flip
last_ts = 401.441986
last_received_ts = 401.441315
last_seq = 523
current_ts = 401.458649
current_received_ts = 401.457886
current_seq = 524
count = 436
seq_step = 1
[401.201128] (kms_flip:6227) DEBUG: Vblank took 144us
[401.217704] (kms_flip:6227) DEBUG: name = flip
last_ts = 401.458649
last_received_ts = 401.457886
last_seq = 524
current_ts = 401.475311
current_received_ts = 401.474609
current_seq = 525
count = 437
seq_step = 1
[401.217757] (kms_flip:6227) DEBUG: Vblank took 49us
[401.234375] (kms_flip:6227) DEBUG: name = flip
last_ts = 401.475311
last_received_ts = 401.474609
last_seq = 525
current_ts = 401.491974
current_received_ts = 401.491272
current_seq = 526
count = 438
seq_step = 1
[401.234419] (kms_flip:6227) DEBUG: Vblank took 42us
[401.251027] (kms_flip:6227) DEBUG: name = flip
last_ts = 401.491974
last_received_ts = 401.491272
last_seq = 526
current_ts = 401.508636
current_received_ts = 401.507904
current_seq = 527
count = 439
seq_step = 1
[401.251076] (kms_flip:6227) DEBUG: Vblank took 46us
[401.267688] (kms_flip:6227) DEBUG: name = flip
last_ts = 401.508636
last_received_ts = 401.507904
last_seq = 527
current_ts = 401.525299
current_received_ts = 401.524567
current_seq = 528
count = 440
seq_step = 1
[401.267735] (kms_flip:6227) DEBUG: Vblank took 44us
[401.284353] (kms_flip:6227) DEBUG: name = flip
last_ts = 401.525299
last_received_ts = 401.524567
last_seq = 528
current_ts = 401.541962
current_received_ts = 401.541229
current_seq = 529
count = 441
seq_step = 1
[401.284389] (kms_flip:6227) DEBUG: Vblank took 34us
[401.301042] (kms_flip:6227) DEBUG: name = flip
last_ts = 401.541962
last_received_ts = 401.541229
last_seq = 529
current_ts = 401.558624
current_received_ts = 401.557922
current_seq = 530
count = 442
seq_step = 1
[401.301099] (kms_flip:6227) DEBUG: Vblank took 53us
[401.317684] (kms_flip:6227) DEBUG: name = flip
last_ts = 401.558624
last_received_ts = 401.557922
last_seq = 530
current_ts = 401.575287
current_received_ts = 401.574554
current_seq = 531
count = 443
seq_step = 1
[401.317725] (kms_flip:6227) DEBUG: Vblank took 39us
[401.334264] (kms_flip:6227) DEBUG: name = flip
last_ts = 401.575287
last_received_ts = 401.574554
last_seq = 531
current_ts = 401.591949
current_received_ts = 401.591125
current_seq = 532
count = 444
seq_step = 1
[401.334305] (kms_flip:6227) DEBUG: Vblank took 39us
[401.350935] (kms_flip:6227) DEBUG: name = flip
last_ts = 401.591949
last_received_ts = 401.591125
last_seq = 532
current_ts = 401.608612
current_received_ts = 401.607788
current_seq = 533
count = 445
seq_step = 1
[401.350975] (kms_flip:6227) DEBUG: Vblank took 38us
[401.367685] (kms_flip:6227) DEBUG: name = flip
last_ts = 401.608612
last_received_ts = 401.607788
last_seq = 533
current_ts = 401.625275
current_received_ts = 401.624542
current_seq = 534
count = 446
seq_step = 1
[401.367734] (kms_flip:6227) DEBUG: Vblank took 47us
[401.384336] (kms_flip:6227) DEBUG: name = flip
last_ts = 401.625275
last_received_ts = 401.624542
last_seq = 534
current_ts = 401.641907
current_received_ts = 401.641205
current_seq = 535
count = 447
seq_step = 1
[401.384389] (kms_flip:6227) DEBUG: Vblank took 51us
[401.401014] (kms_flip:6227) DEBUG: name = flip
last_ts = 401.641907
last_received_ts = 401.641205
last_seq = 535
current_ts = 401.658569
current_received_ts = 401.657867
current_seq = 536
count = 448
seq_step = 1
[401.401163] (kms_flip:6227) DEBUG: Vblank took 146us
[401.417694] (kms_flip:6227) DEBUG: name = flip
last_ts = 401.658569
last_received_ts = 401.657867
last_seq = 536
current_ts = 401.675232
current_received_ts = 401.674561
current_seq = 537
count = 449
seq_step = 1
[401.417747] (kms_flip:6227) DEBUG: Vblank took 50us
[401.434318] (kms_flip:6227) DEBUG: name = flip
last_ts = 401.675232
last_received_ts = 401.674561
last_seq = 537
current_ts = 401.691895
current_received_ts = 401.691162
current_seq = 538
count = 450
seq_step = 1
[401.434358] (kms_flip:6227) DEBUG: Vblank took 38us
[401.451015] (kms_flip:6227) DEBUG: name = flip
last_ts = 401.691895
last_received_ts = 401.691162
last_seq = 538
current_ts = 401.708557
current_received_ts = 401.707855
current_seq = 539
count = 451
seq_step = 1
[401.451144] (kms_flip:6227) DEBUG: Vblank took 127us
[401.467682] (kms_flip:6227) DEBUG: name = flip
last_ts = 401.708557
last_received_ts = 401.707855
last_seq = 539
current_ts = 401.725220
current_received_ts = 401.724518
current_seq = 540
count = 452
seq_step = 1
[401.467828] (kms_flip:6227) DEBUG: Vblank took 144us
[401.484324] (kms_flip:6227) DEBUG: name = flip
last_ts = 401.725220
last_received_ts = 401.724518
last_seq = 540
current_ts = 401.741882
current_received_ts = 401.741150
current_seq = 541
count = 453
seq_step = 1
[401.484370] (kms_flip:6227) DEBUG: Vblank took 43us
[401.501009] (kms_flip:6227) DEBUG: name = flip
last_ts = 401.741882
last_received_ts = 401.741150
last_seq = 541
current_ts = 401.758545
current_received_ts = 401.757843
current_seq = 542
count = 454
seq_step = 1
[401.501053] (kms_flip:6227) DEBUG: Vblank took 42us
[401.517686] (kms_flip:6227) DEBUG: name = flip
last_ts = 401.758545
last_received_ts = 401.757843
last_seq = 542
current_ts = 401.775208
current_received_ts = 401.774506
current_seq = 543
count = 455
seq_step = 1
[401.517734] (kms_flip:6227) DEBUG: Vblank took 45us
[401.534315] (kms_flip:6227) DEBUG: name = flip
last_ts = 401.775208
last_received_ts = 401.774506
last_seq = 543
current_ts = 401.791870
current_received_ts = 401.791138
current_seq = 544
count = 456
seq_step = 1
[401.534358] (kms_flip:6227) DEBUG: Vblank took 41us
[401.550960] (kms_flip:6227) DEBUG: name = flip
last_ts = 401.791870
last_received_ts = 401.791138
last_seq = 544
current_ts = 401.808533
current_received_ts = 401.807800
current_seq = 545
count = 457
seq_step = 1
[401.551000] (kms_flip:6227) DEBUG: Vblank took 39us
[401.567672] (kms_flip:6227) DEBUG: name = flip
last_ts = 401.808533
last_received_ts = 401.807800
last_seq = 545
current_ts = 401.825195
current_received_ts = 401.824493
current_seq = 546
count = 458
seq_step = 1
[401.567778] (kms_flip:6227) DEBUG: Vblank took 104us
[401.584317] (kms_flip:6227) DEBUG: name = flip
last_ts = 401.825195
last_received_ts = 401.824493
last_seq = 546
current_ts = 401.841858
current_received_ts = 401.841125
current_seq = 547
count = 459
seq_step = 1
[401.584357] (kms_flip:6227) DEBUG: Vblank took 38us
[401.600986] (kms_flip:6227) DEBUG: name = flip
last_ts = 401.841858
last_received_ts = 401.841125
last_seq = 547
current_ts = 401.858521
current_received_ts = 401.857788
current_seq = 548
count = 460
seq_step = 1
[401.601093] (kms_flip:6227) DEBUG: Vblank took 105us
[401.617659] (kms_flip:6227) DEBUG: name = flip
last_ts = 401.858521
last_received_ts = 401.857788
last_seq = 548
current_ts = 401.875183
current_received_ts = 401.874481
current_seq = 549
count = 461
seq_step = 1
[401.617700] (kms_flip:6227) DEBUG: Vblank took 39us
[401.634296] (kms_flip:6227) DEBUG: name = flip
last_ts = 401.875183
last_received_ts = 401.874481
last_seq = 549
current_ts = 401.891815
current_received_ts = 401.891113
current_seq = 550
count = 462
seq_step = 1
[401.634337] (kms_flip:6227) DEBUG: Vblank took 40us
[401.650977] (kms_flip:6227) DEBUG: name = flip
last_ts = 401.891815
last_received_ts = 401.891113
last_seq = 550
current_ts = 401.908478
current_received_ts = 401.907776
current_seq = 551
count = 463
seq_step = 1
[401.651085] (kms_flip:6227) DEBUG: Vblank took 105us
[401.667647] (kms_flip:6227) DEBUG: name = flip
last_ts = 401.908478
last_received_ts = 401.907776
last_seq = 551
current_ts = 401.925140
current_received_ts = 401.924438
current_seq = 552
count = 464
seq_step = 1
[401.667771] (kms_flip:6227) DEBUG: Vblank took 121us
[401.684290] (kms_flip:6227) DEBUG: name = flip
last_ts = 401.925140
last_received_ts = 401.924438
last_seq = 552
current_ts = 401.941803
current_received_ts = 401.941101
current_seq = 553
count = 465
seq_step = 1
[401.684331] (kms_flip:6227) DEBUG: Vblank took 40us
[401.701018] (kms_flip:6227) DEBUG: name = flip
last_ts = 401.941803
last_received_ts = 401.941101
last_seq = 553
current_ts = 401.958466
current_received_ts = 401.957825
current_seq = 554
count = 466
seq_step = 1
[401.701139] (kms_flip:6227) DEBUG: Vblank took 119us
[401.717625] (kms_flip:6227) DEBUG: name = flip
last_ts = 401.958466
last_received_ts = 401.957825
last_seq = 554
current_ts = 401.975128
current_received_ts = 401.974426
current_seq = 555
count = 467
seq_step = 1
[401.717666] (kms_flip:6227) DEBUG: Vblank took 38us
[401.734284] (kms_flip:6227) DEBUG: name = flip
last_ts = 401.975128
last_received_ts = 401.974426
last_seq = 555
current_ts = 401.991791
current_received_ts = 401.991089
current_seq = 556
count = 468
seq_step = 1
[401.734325] (kms_flip:6227) DEBUG: Vblank took 39us
[401.750962] (kms_flip:6227) DEBUG: name = flip
last_ts = 401.991791
last_received_ts = 401.991089
last_seq = 556
current_ts = 402.008453
current_received_ts = 402.007751
current_seq = 557
count = 469
seq_step = 1
[401.751006] (kms_flip:6227) DEBUG: Vblank took 42us
[401.767621] (kms_flip:6227) DEBUG: name = flip
last_ts = 402.008453
last_received_ts = 402.007751
last_seq = 557
current_ts = 402.025116
current_received_ts = 402.024414
current_seq = 558
count = 470
seq_step = 1
[401.767745] (kms_flip:6227) DEBUG: Vblank took 122us
[401.784278] (kms_flip:6227) DEBUG: name = flip
last_ts = 402.025116
last_received_ts = 402.024414
last_seq = 558
current_ts = 402.041779
current_received_ts = 402.041046
current_seq = 559
count = 471
seq_step = 1
[401.784319] (kms_flip:6227) DEBUG: Vblank took 40us
[401.800962] (kms_flip:6227) DEBUG: name = flip
last_ts = 402.041779
last_received_ts = 402.041046
last_seq = 559
current_ts = 402.058441
current_received_ts = 402.057739
current_seq = 560
count = 472
seq_step = 1
[401.801002] (kms_flip:6227) DEBUG: Vblank took 39us
[401.817622] (kms_flip:6227) DEBUG: name = flip
last_ts = 402.058441
last_received_ts = 402.057739
last_seq = 560
current_ts = 402.075104
current_received_ts = 402.074402
current_seq = 561
count = 473
seq_step = 1
[401.817672] (kms_flip:6227) DEBUG: Vblank took 47us
[401.834985] (kms_flip:6227) DEBUG: name = flip
last_ts = 402.075104
last_received_ts = 402.074402
last_seq = 561
current_ts = 402.091766
current_received_ts = 402.091705
current_seq = 562
count = 474
seq_step = 1
[401.836318] (kms_flip:6227) DEBUG: Vblank took 1288us
[401.836325] (kms_flip:6227) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931:
[401.836392] (kms_flip:6227) CRITICAL: Failed assertion: end - start < 500
[401.836415] (kms_flip:6227) CRITICAL: Last errno: 4, Interrupted system call
[401.838167] (kms_flip:6227) igt_core-INFO: Stack trace:
[401.840328] (kms_flip:6227) igt_core-INFO:   #0 ../lib/igt_core.c:2106 __igt_fail_assert()
[401.841416] (kms_flip:6227) igt_core-INFO:   #1 ../tests/kms_flip.c:926 run_test_on_crtc_set()
[401.841450] (kms_flip:6227) igt_core-INFO:   #2 ../tests/kms_flip.c:1920 run_test()
[401.841482] (kms_flip:6227) igt_core-INFO:   #3 ../tests/kms_flip.c:2220 __igt_unique____real_main2105()
[401.841506] (kms_flip:6227) igt_core-INFO:   #4 ../tests/kms_flip.c:2105 main()
[401.843709] (kms_flip:6227) igt_core-INFO:   #5 [__libc_init_first+0x8a]
[401.845001] (kms_flip:6227) igt_core-INFO:   #6 [__libc_start_main+0x8b]
[401.845552] (kms_flip:6227) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest C-eDP1: FAIL (9.153s)
Subtest flip-vs-expired-vblank-interruptible: FAIL (23.579s)
[403.075677] (kms_flip:6227) igt_kms-CRITICAL: Test assertion failure function kmstest_set_connector_dpms, file ../lib/igt_kms.c:2354:
[403.075750] (kms_flip:6227) igt_kms-CRITICAL: Failed assertion: found_it
[403.075783] (kms_flip:6227) igt_kms-CRITICAL: Last errno: 9, Bad file descriptor
[403.075811] (kms_flip:6227) igt_kms-CRITICAL: DPMS property not found on 393
Dmesg
<6> [484.984881] [IGT] kms_flip: starting dynamic subtest C-eDP1
<7> [484.986781] xe 0000:00:02.0: [drm:drm_mode_addfb2] [FB:433]
<7> [484.988083] xe 0000:00:02.0: [drm:drm_mode_addfb2] [FB:434]
<7> [484.998497] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [484.998861] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [484.999163] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
<7> [485.045661] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:151:pipe A]
<7> [485.045763] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [485.046017] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [485.046173] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:270:pipe B]
<7> [485.046235] 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> [485.046344] 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> [485.046475] 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> [485.046600] 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> [485.046722] 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> [485.046830] 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> [485.046927] 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> [485.047014] 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> [485.047103] 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> [485.047196] 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> [485.047287] 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> [485.047377] 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> [485.047481] 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> [485.047589] 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> [485.047696] 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> [485.047800] 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> [485.047880] 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> [485.047958] 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> [485.048035] 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> [485.048113] 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> [485.048190] 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> [485.048276] 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> [485.048361] 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> [485.048452] 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> [485.048550] 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> [485.048648] 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> [485.048747] 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> [485.048832] 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> [485.048914] 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> [485.049026] 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> [485.049103] 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> [485.049181] 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> [485.049256] 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> [485.049333] 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> [485.049417] 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> [485.049568] 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> [485.049666] 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> [485.049762] 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> [485.049854] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:270:pipe B] fastset requirement not met, forcing full modeset
<7> [485.049941] xe 0000:00:02.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:270:pipe B] releasing DPLL 0
<7> [485.050051] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xf -> 0x1 (total dbuf slices 0xf), mbus joined? yes->no
<7> [485.050125] 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> [485.050213] 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> [485.050284] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:270:pipe B] min cdclk: 173855 kHz -> 0 kHz
<7> [485.050379] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] Can change cdclk via squashing
<7> [485.050469] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New cdclk calculated to be logical 153600 kHz, actual 153600 kHz
<7> [485.050561] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New voltage level calculated to be logical 0, actual 0
<7> [485.050679] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:270:pipe B] enable: no [modeset]
<7> [485.050779] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:154:plane 1B] fb: [NOFB], visible: no
<7> [485.050870] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:184:plane 2B] fb: [NOFB], visible: no
<7> [485.050959] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:214:plane 3B] fb: [NOFB], visible: no
<7> [485.051048] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:244:plane 4B] fb: [NOFB], visible: no
<7> [485.051137] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:254:plane 5B] fb: [NOFB], visible: no
<7> [485.051228] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:264:cursor B] fb: [NOFB], visible: no
<7> [485.051450] xe 0000:00:02.0: [drm:intel_alpm_disable [xe]] Disabling ALPM
<7> [485.051548] xe 0000:00:02.0: [drm:intel_edp_backlight_off [xe]]
<7> [485.253907] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:393:eDP-1] set backlight PWM = 0
<7> [485.254638] xe 0000:00:02.0: [drm:intel_disable_transcoder [xe]] disabling pipe B
<7> [485.265875] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turn panel power off
<7> [485.266296] 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> [485.266311] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [485.266724] 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> [485.266763] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:392:DDI A/PHY A] - short
<7> [485.317793] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [485.318224] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disable DPLL 0 (active 0x2, on? 1) for [CRTC:270:pipe B]
<7> [485.318258] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:392:DDI A/PHY A]
<7> [485.319003] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [485.319450] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:392:DDI A/PHY A] - short
<7> [485.319834] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disabling DPLL 0
<7> [485.319916] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:392:DDI A/PHY A]
<7> [485.320332] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling AUX_A
<7> [485.320858] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:392:DDI A/PHY A]
<7> [485.321227] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:401:DDI TC1/PHY TC1]
<7> [485.321594] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:403:DP-MST A]
<7> [485.321931] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:404:DP-MST B]
<7> [485.322309] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:405:DP-MST C]
<7> [485.322695] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:413:DDI TC2/PHY TC2]
<7> [485.323017] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:415:DP-MST A]
<7> [485.323331] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:416:DP-MST B]
<7> [485.323681] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:417:DP-MST C]
<7> [485.323991] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:421:DDI TC3/PHY TC3]
<7> [485.324350] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:423:DP-MST A]
<7> [485.324719] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:424:DP-MST B]
<7> [485.325058] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:425:DP-MST C]
<7> [485.325365] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:393:eDP-1]
<7> [485.325733] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [485.325976] xe 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: yes -> no (pipe: *)
<7> [485.326245] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0x1
<7> [485.326811] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_B
<7> [485.327180] 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> [485.327571] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_2
<7> [485.327981] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:270:pipe B]
<7> [485.328302] 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> [485.328702] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [485.329005] xe 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=384
<7> [485.329767] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:389:pipe C]
<7> [485.330140] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:389:pipe C]
<7> [485.330200] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:393:eDP-1]
<7> [485.330358] 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> [485.330699] 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> [485.331021] 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> [485.331295] xe 0000:00:02.0: [drm:intel_psr_compute_config [xe]] PSR2 not supported in transcoder C
<7> [485.331541] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:389:pipe C] hw max bpp: 30, pipe bpp: 30, dithering: 0
<7> [485.331820] xe 0000:00:02.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:392:DDI A/PHY A] [CRTC:389:pipe C]
<7> [485.332101] 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> [485.332403] 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> [485.332698] 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> [485.333040] 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> [485.333307] 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> [485.333560] 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> [485.333788] 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> [485.334022] 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> [485.334273] 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> [485.334487] 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> [485.334688] 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> [485.334878] 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> [485.335088] 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> [485.335284] 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> [485.335477] 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> [485.335651] 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> [485.335818] 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> [485.335982] 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> [485.336143] 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> [485.336304] 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> [485.336454] 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> [485.336628] 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> [485.336779] 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> [485.336938] 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> [485.337080] 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> [485.337221] 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> [485.337361] 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> [485.337515] 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> [485.337643] 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> [485.337788] 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> [485.337934] 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> [485.338064] 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> [485.338180] 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> [485.338295] 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> [485.338407] 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> [485.338528] 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> [485.338634] 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> [485.338737] 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> [485.338851] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:389:pipe C] fastset requirement not met, forcing full modeset
<7> [485.338990] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0x1 -> 0xd (total dbuf slices 0xf), mbus joined? no->no
<7> [485.339076] 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> [485.339181] 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> [485.339266] 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> [485.339352] 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> [485.339433] 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> [485.339523] 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> [485.339597] 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> [485.339687] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:389:pipe C] data rate 1390840 num active planes 1
<7> [485.339808] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 1461 qgv_peak_bw: 38400
<7> [485.339919] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 1461 qgv_peak_bw: 38400
<7> [485.340020] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [485.340117] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [485.340215] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 1461
<7> [485.340329] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:389:pipe C] min cdclk: 0 kHz -> 173855 kHz
<7> [485.340439] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] Can change cdclk via squashing
<7> [485.340556] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New cdclk calculated to be logical 192000 kHz, actual 192000 kHz
<7> [485.340678] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New voltage level calculated to be logical 0, actual 0
<7> [485.340804] xe 0000:00:02.0: [drm:intel_find_dpll [xe]] [CRTC:389:pipe C] allocated DPLL 0
<7> [485.340916] xe 0000:00:02.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:389:pipe C] reserving DPLL 0
<7> [485.341026] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:389:pipe C] enable: yes [modeset]
<7> [485.341118] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: EDP (0x100), output format: RGB, sink format: RGB
<7> [485.341211] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: C, pipe bpp: 30, dithering: 0
<7> [485.341302] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [485.341391] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [485.341478] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [485.341580] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [485.341686] 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> [485.341790] 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> [485.341877] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: disabled
<7> [485.341962] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled
<7> [485.342048] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] psr: enabled, selective update: disabled, panel replay: disabled, selective fetch: disabled
<7> [485.342126] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] minimum hblank: 0
<7> [485.342204] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] audio: 0, infoframes: 0, infoframes enabled: 0x4
<7> [485.342287] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] DP SDP: VSC, revision 2, length 8
<7> [485.342370] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pixelformat: RGB
<7> [485.342453] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] colorimetry: sRGB
<7> [485.342580] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] bpc: 0
<7> [485.342678] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dynamic range: VESA range
<7> [485.342777] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] content type: Not defined
<7> [485.342877] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [485.342965] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [485.343040] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: no, fixed rr: yes, vmin: 1906, vmax: 1906, flipline: 1906, pipeline full: 0, guardband: 104 vsync start: 103, vsync end: 97
<7> [485.343117] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: vmin vblank: 1802, vmax vblank: 1802, vmin vtotal: 1906, vmax vtotal: 1906
<7> [485.343193] 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> [485.343269] 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> [485.343344] 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> [485.343417] 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> [485.343496] 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> [485.343594] 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> [485.343692] 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> [485.343788] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] linetime: 70, ips linetime: 0
<7> [485.343879] 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> [485.343959] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe src: 2880x1800+0+0
<7> [485.344044] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [485.344145] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [485.344231] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [485.344315] 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> [485.344400] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_hw_state: fracen: no,
<7> [485.344488] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] multiplier: 225, tx_clk_div: 1.
<7> [485.344598] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_rawhw_state:
<7> [485.344709] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] tx: 0x10, cmn: 0x21
<7> [485.344818] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[0] = 0x4, pll[1] = 0x0, pll[2] = 0xa2, pll[3] = 0x1
<7> [485.344906] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[4] = 0x0, pll[5] = 0x0, pll[6] = 0x0, pll[7] = 0x0
<7> [485.344984] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[8] = 0x0, pll[9] = 0x1, pll[10] = 0x0, pll[11] = 0x0
<7> [485.345059] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[12] = 0x0, pll[13] = 0x0, pll[14] = 0x0, pll[15] = 0x1
<7> [485.345135] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[16] = 0x85, pll[17] = 0xf, pll[18] = 0xe6, pll[19] = 0x23
<7> [485.345212] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [485.345288] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [485.345363] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [485.345439] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [485.345534] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [485.345639] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [485.345748] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [485.345847] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [485.345928] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [485.346024] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [485.346120] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [485.346215] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [485.346304] 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> [485.346385] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [485.346465] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] src: 2880.000000x1800.000000+0.000000+0.000000 dst: 2880x1800+0+0
<7> [485.346579] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:303:plane 2C] fb: [NOFB], visible: no
<7> [485.346675] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:333:plane 3C] fb: [NOFB], visible: no
<7> [485.346769] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:363:plane 4C] fb: [NOFB], visible: no
<7> [485.346847] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:373:plane 5C] fb: [NOFB], visible: no
<7> [485.346925] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:383:cursor C] fb: [NOFB], visible: no
<7> [485.347209] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_2
<7> [485.347339] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_C
<7> [485.347488] 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> [485.347619] 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> [485.347742] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [485.347850] xe 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=480
<7> [485.347941] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:392:DDI A/PHY A]
<7> [485.348020] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:401:DDI TC1/PHY TC1]
<7> [485.348110] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:403:DP-MST A]
<7> [485.348199] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:404:DP-MST B]
<7> [485.348285] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:405:DP-MST C]
<7> [485.348365] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:413:DDI TC2/PHY TC2]
<7> [485.348437] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:415:DP-MST A]
<7> [485.348525] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:416:DP-MST B]
<7> [485.348611] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:417:DP-MST C]
<7> [485.348696] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:421:DDI TC3/PHY TC3]
<7> [485.348779] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:423:DP-MST A]
<7> [485.348857] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:424:DP-MST B]
<7> [485.348922] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:425:DP-MST C]
<7> [485.349005] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xd
<7> [485.349167] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [485.349278] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling AUX_A
<7> [485.350520] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enable DPLL 0 (active 0x4, on? 0) for [CRTC:389:pipe C]
<7> [485.350638] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enabling DPLL 0
<7> [485.352627] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turn panel power on
<7> [485.352727] xe 0000:00:02.0: [drm:wait_panel_power_cycle [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 wait for panel power cycle (466 ms remaining)
<7> [485.354348] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [485.354436] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:392:DDI A/PHY A] - long
<7> [485.354528] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 10
<7> [485.354649] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:392:DDI A/PHY A]
<7> [485.821724] 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> [485.872716] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [485.873022] 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> [485.873304] 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> [485.909936] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [485.910233] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:392:DDI A/PHY A] - long
<7> [485.910545] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 20
<7> [485.910873] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:392:DDI A/PHY A]
<7> [486.079412] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [486.079755] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turning VDD on
<7> [486.080032] 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> [486.081939] 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> [486.083400] 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> [486.084131] 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> [486.085143] 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> [486.086348] 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> [486.087331] 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> [486.087641] 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> [486.089001] 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> [486.089276] 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> [486.089959] xe 0000:00:02.0: [drm:intel_enable_transcoder [xe]] enabling pipe C
<7> [486.090327] xe 0000:00:02.0: [drm:intel_edp_backlight_on [xe]]
<7> [486.090605] xe 0000:00:02.0: [drm:intel_backlight_enable [xe]] pipe C
<7> [486.090938] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:393:eDP-1] set backlight PWM = 4518
<7> [486.108183] xe 0000:00:02.0: [drm:intel_fbc_update [xe]] reserved 29491200 bytes of contiguous stolen space for FBC, limit: 1
<7> [486.108525] xe 0000:00:02.0: [drm:intel_fbc_update [xe]] Enabling FBC on [PLANE:273:plane 1C]
<7> [486.123744] xe 0000:00:02.0: [drm:intel_psr_post_plane_update [xe]] Enabling PSR1
<7> [486.125131] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:393:eDP-1]
<7> [486.125455] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:389:pipe C]
<7> [488.661976] 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> [488.662504] 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> [494.139169] [IGT] kms_flip: finished subtest C-eDP1, FAIL
<6> [494.143255] [IGT] kms_flip: finished subtest flip-vs-expired-vblank-interruptible, FAIL
<7> [494.152053] xe 0000:00:02.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:433] from all active usage due to RMFB ioctl
<7> [494.152179] xe 0000:00:02.0: [drm:atomic_remove_fb] Disabling [PLANE:273:plane 1C] because [FB:433] is removed
<7> [494.152320] 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> [494.152764] 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> [494.153223] 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> [494.153591] 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> [494.154012] 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> [494.156296] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:389:pipe C] data rate 0 num active planes 0
<7> [494.156797] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 0 qgv_peak_bw: 38400
<7> [494.157316] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 0 qgv_peak_bw: 38400
<7> [494.157746] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [494.158462] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [494.158891] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 0
<7> [494.160712] xe 0000:00:02.0: [drm:intel_psr_disable_locked [xe]] Disabling PSR1
<7> [494.161548] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turning VDD on
<7> [494.161863] 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> [494.172456] xe 0000:00:02.0: [drm:__intel_fbc_disable [xe]] Disabling FBC on [PLANE:273:plane 1C]
<7> [494.198863] 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> [494.199449] 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> [494.199936] 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> [494.200405] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:151:pipe A] hw max bpp: 30, pipe bpp: 30, dithering: 0
<7> [494.200878] xe 0000:00:02.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:392:DDI A/PHY A] [CRTC:151:pipe A]
<7> [494.201399] 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> [494.201833] 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> [494.202295] 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> [494.202686] 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> [494.203090] 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> [494.203508] 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> [494.203844] 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> [494.204193] 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> [494.204499] 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> [494.204794] 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> [494.205144] 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> [494.205420] 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> [494.205690] 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> [494.205944] 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> [494.206228] 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> [494.206470] 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> [494.206699] 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> [494.206963] 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> [494.207175] 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> [494.207386] 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> [494.207577] 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> [494.207759] 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> [494.207944] 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> [494.208147] 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> [494.208340] 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> [494.208518] 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> [494.208675] 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> [494.208832] 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> [494.209049] 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> [494.209200] 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> [494.209348] 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> [494.209487] 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> [494.209624] 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> [494.209755] 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> [494.209883] 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> [494.210026] 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> [494.210150] 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> [494.210271] 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> [494.210410] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:151:pipe A] fastset requirement not met, forcing full modeset
<7> [494.210547] 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> [494.210671] 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> [494.210786] 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> [494.210898] 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> [494.211027] 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> [494.211139] 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> [494.211247] 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> [494.211352] 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> [494.211453] 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> [494.211552] 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> [494.211649] 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> [494.211751] 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> [494.211855] 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> [494.211968] 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> [494.212066] 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> [494.212164] 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> [494.212258] 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> [494.212346] 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> [494.212429] 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> [494.212510] 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> [494.212590] 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> [494.212668] 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> [494.212747] 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> [494.212823] 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> [494.212898] 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> [494.213002] 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> [494.213099] 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> [494.213194] 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> [494.213278] 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> [494.213357] 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> [494.213429] 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> [494.213502] 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> [494.213579] 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> [494.213656] 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> [494.213733] 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> [494.213808] 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> [494.213883] 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> [494.213977] 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> [494.214077] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:389:pipe C] fastset requirement not met, forcing full modeset
<7> [494.214176] xe 0000:00:02.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:389:pipe C] releasing DPLL 0
<7> [494.214324] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xd -> 0xf (total dbuf slices 0xf), mbus joined? no->yes
<7> [494.214393] 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> [494.214459] 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> [494.214527] 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> [494.214591] 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> [494.214651] 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> [494.214707] 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> [494.214764] 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> [494.214822] 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> [494.214878] 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> [494.214968] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:151:pipe A] data rate 1390840 num active planes 1
<7> [494.215084] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 1461 qgv_peak_bw: 38400
<7> [494.215199] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 1461 qgv_peak_bw: 38400
<7> [494.215295] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [494.215381] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [494.215464] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 1461
<7> [494.215546] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:151:pipe A] min cdclk: 0 kHz -> 173855 kHz
<7> [494.215630] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:389:pipe C] min cdclk: 173855 kHz -> 0 kHz
<7> [494.215753] xe 0000:00:02.0: [drm:intel_find_dpll [xe]] [CRTC:151:pipe A] allocated DPLL 0
<7> [494.215840] xe 0000:00:02.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:151:pipe A] reserving DPLL 0
<7> [494.215940] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:151:pipe A] enable: yes [modeset]
<7> [494.216053] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: EDP (0x100), output format: RGB, sink format: RGB
<7> [494.216164] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: A, pipe bpp: 30, dithering: 0
<7> [494.216267] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [494.216350] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [494.216434] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [494.216518] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [494.216600] 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> [494.216683] 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> [494.216764] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: disabled
<7> [494.216844] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled
<7> [494.216928] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] psr: enabled, selective update: enabled, panel replay: disabled, selective fetch: enabled
<7> [494.217028] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] minimum hblank: 0
<7> [494.217130] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] audio: 0, infoframes: 0, infoframes enabled: 0x4
<7> [494.217231] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] DP SDP: VSC, revision 4, length 14
<7> [494.217314] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pixelformat: RGB
<7> [494.217393] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] colorimetry: sRGB
<7> [494.217470] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] bpc: 0
<7> [494.217547] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dynamic range: VESA range
<7> [494.217623] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] content type: Not defined
<7> [494.217698] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [494.217773] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [494.217848] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: no, fixed rr: yes, vmin: 1906, vmax: 1906, flipline: 1906, pipeline full: 0, guardband: 104 vsync start: 103, vsync end: 97
<7> [494.217930] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: vmin vblank: 1802, vmax vblank: 1802, vmin vtotal: 1906, vmax vtotal: 1906
<7> [494.218031] 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> [494.218128] 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> [494.218227] 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> [494.218310] 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> [494.218385] 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> [494.218460] 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> [494.218535] 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> [494.218609] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] linetime: 70, ips linetime: 0
<7> [494.218684] 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> [494.218759] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe src: 2880x1800+0+0
<7> [494.218834] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [494.218909] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [494.219061] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [494.219160] 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> [494.219253] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_hw_state: fracen: no,
<7> [494.219333] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] multiplier: 225, tx_clk_div: 1.
<7> [494.219411] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_rawhw_state:
<7> [494.219484] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] tx: 0x10, cmn: 0x21
<7> [494.219557] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[0] = 0x4, pll[1] = 0x0, pll[2] = 0xa2, pll[3] = 0x1
<7> [494.219632] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[4] = 0x0, pll[5] = 0x0, pll[6] = 0x0, pll[7] = 0x0
<7> [494.219709] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[8] = 0x0, pll[9] = 0x1, pll[10] = 0x0, pll[11] = 0x0
<7> [494.219787] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[12] = 0x0, pll[13] = 0x0, pll[14] = 0x0, pll[15] = 0x1
<7> [494.219864] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[16] = 0x85, pll[17] = 0xf, pll[18] = 0xe6, pll[19] = 0x23
<7> [494.219954] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [494.220064] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [494.220176] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [494.220270] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [494.220351] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [494.220426] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [494.220501] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [494.220577] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [494.220651] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [494.220726] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [494.220801] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [494.220876] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [494.220970] 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> [494.221071] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [494.221169] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] src: 2880.000000x1800.000000+0.000000+0.000000 dst: 2880x1800+0+0
<7> [494.221259] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [494.221339] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [494.221414] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [494.221488] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [494.221561] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:145:cursor A] fb: [NOFB], visible: no
<7> [494.221636] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:389:pipe C] enable: no [modeset]
<7> [494.221709] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:273:plane 1C] fb: [NOFB], visible: no
<7> [494.221783] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:303:plane 2C] fb: [NOFB], visible: no
<7> [494.221856] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:333:plane 3C] fb: [NOFB], visible: no
<7> [494.221934] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:363:plane 4C] fb: [NOFB], visible: no
<7> [494.222031] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:373:plane 5C] fb: [NOFB], visible: no
<7> [494.222139] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:383:cursor C] fb: [NOFB], visible: no
<7> [494.222337] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_A
<7> [494.222490] 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> [494.222683] xe 0000:00:02.0: [drm:intel_alpm_disable [xe]] Disabling ALPM
<7> [494.222779] xe 0000:00:02.0: [drm:intel_edp_backlight_off [xe]]
<7> [494.431500] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:393:eDP-1] set backlight PWM = 0
<7> [494.432026] xe 0000:00:02.0: [drm:intel_disable_transcoder [xe]] disabling pipe C
<7> [494.439365] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turn panel power off
<7> [494.439744] 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> [494.439788] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [494.440173] 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> [494.440276] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:392:DDI A/PHY A] - short
<7> [494.492671] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [494.493015] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:392:DDI A/PHY A] - short
<7> [494.497345] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [494.497653] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disable DPLL 0 (active 0x4, on? 1) for [CRTC:389:pipe C]
<7> [494.497705] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:392:DDI A/PHY A]
<7> [494.498057] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:392:DDI A/PHY A]
<7> [494.499030] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disabling DPLL 0
<7> [494.499448] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling AUX_A
<7> [494.499794] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:392:DDI A/PHY A]
<7> [494.500101] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:401:DDI TC1/PHY TC1]
<7> [494.500327] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:403:DP-MST A]
<7> [494.500552] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:404:DP-MST B]
<7> [494.500771] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:405:DP-MST C]
<7> [494.500996] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:413:DDI TC2/PHY TC2]
<7> [494.501209] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:415:DP-MST A]
<7> [494.501402] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:416:DP-MST B]
<7> [494.501597] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:417:DP-MST C]
<7> [494.501825] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:421:DDI TC3/PHY TC3]
<7> [494.502044] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:423:DP-MST A]
<7> [494.502264] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:424:DP-MST B]
<7> [494.502461] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:425:DP-MST C]
<7> [494.502718] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xf
<7> [494.503111] xe 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: no -> yes (pipe: *)
<7> [494.503317] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 8 (mbus joined: yes)
<7> [494.503574] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling AUX_A
<7> [494.505014] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:151:pipe A]
<7> [494.505313] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enabling DPLL 0
<7> [494.507688] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turn panel power on
<7> [494.508031] xe 0000:00:02.0: [drm:wait_panel_power_cycle [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 wait for panel power cycle (490 ms remaining)
<7> [494.527735] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [494.528181] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:392:DDI A/PHY A] - long
<7> [494.528658] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 10
<7> [494.529258] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:392:DDI A/PHY A]
<7> [495.007289] 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> [495.027888] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [495.028367] 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> [495.028753] 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> [495.065265] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [495.065607] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:392:DDI A/PHY A] - long
<7> [495.065898] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 20
<7> [495.066309] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:392:DDI A/PHY A]
<7> [495.243648] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [495.244008] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turning VDD on
<7> [495.244361] 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> [495.246300] 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> [495.247774] 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> [495.248456] 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> [495.249520] 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> [495.250821] 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> [495.251918] 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> [495.252264] 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> [495.253654] 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> [495.253927] 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> [495.254600] xe 0000:00:02.0: [drm:intel_enable_transcoder [xe]] enabling pipe A
<7> [495.254959] xe 0000:00:02.0: [drm:intel_edp_backlight_on [xe]]
<7> [495.255252] xe 0000:00:02.0: [drm:intel_backlight_enable [xe]] pipe A
<7> [495.255527] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:393:eDP-1] set backlight PWM = 4518
<7> [495.288751] xe 0000:00:02.0: [drm:intel_psr_post_plane_update [xe]] Enabling PSR2
<7> [495.290332] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:393:eDP-1]
<7> [495.290625] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:151:pipe A]
<7> [495.293419] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_C
<7> [495.293771] 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> [495.294164] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_2
<7> [495.294562] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:389:pipe C]
<7> [495.295029] xe 0000:00:02.0: [drm:drm_client_dev_restore] fbdev: ret=0
<6> [495.296791] [IGT] kms_flip: exiting, ret=98
Created at 2026-05-25 11:49:11