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

Result: Fail

git-log-oneline i915_display_info17 igt_runner17 runtimes17 results17.json results17-i915-load.json guc_logs17.tar i915_display_info_post_exec17 boot17 dmesg17

DetailValue
Duration 8.27 seconds
Hostname 
shard-rkl-3
Igt-Version 
IGT-Version: 2.4-g0f0afe46c (x86_64) (Linux: 7.1.0-rc1-CI_DRM_18378-g1db870ffb55b+ x86_64)
Out 
Starting dynamic subtest: C-HDMI-A2
  1920x1080: 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5 
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../tests/kms_flip.c:926 run_test_on_crtc_set()
  #2 ../tests/kms_flip.c:1920 run_test()
  #3 ../tests/kms_flip.c: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-HDMI-A2: FAIL (8.265s)
Subtest flip-vs-expired-vblank-interruptible: FAIL (28.586s)
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../lib/igt_kms.c:2352 kmstest_set_connector_dpms()
  #2 ../lib/igt_core.c:2961 igt_atexit_handler()
  #3 [erand48_r+0x1d6]
  #4 [exit+0x1e]
  #5 ../lib/igt_core.c:2416 igt_exit()
  #6 [main+0x3f]
  #7 [__libc_init_first+0x8a]
  #8 [__libc_start_main+0x8b]
  #9 [_start+0x25]
Err 
Starting dynamic subtest: C-HDMI-A2
(kms_flip:6378) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931:
(kms_flip:6378) CRITICAL: Failed assertion: end - start < 500
(kms_flip:6378) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest C-HDMI-A2 failed.
**** DEBUG ****
(kms_flip:6378) DEBUG: Vblank took 34us
(kms_flip:6378) DEBUG: name = flip
last_ts = 183.835663
last_received_ts = 183.835083
last_seq = 509
current_ts = 183.854614
current_received_ts = 183.854080
current_seq = 510
count = 305
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 35us
(kms_flip:6378) DEBUG: name = flip
last_ts = 183.854614
last_received_ts = 183.854080
last_seq = 510
current_ts = 183.873596
current_received_ts = 183.872986
current_seq = 511
count = 306
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 32us
(kms_flip:6378) DEBUG: name = flip
last_ts = 183.873596
last_received_ts = 183.872986
last_seq = 511
current_ts = 183.892578
current_received_ts = 183.891983
current_seq = 512
count = 307
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 38us
(kms_flip:6378) DEBUG: name = flip
last_ts = 183.892578
last_received_ts = 183.891983
last_seq = 512
current_ts = 183.911545
current_received_ts = 183.910934
current_seq = 513
count = 308
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 31us
(kms_flip:6378) DEBUG: name = flip
last_ts = 183.911545
last_received_ts = 183.910934
last_seq = 513
current_ts = 183.930527
current_received_ts = 183.929932
current_seq = 514
count = 309
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 34us
(kms_flip:6378) DEBUG: name = flip
last_ts = 183.930527
last_received_ts = 183.929932
last_seq = 514
current_ts = 183.949493
current_received_ts = 183.948898
current_seq = 515
count = 310
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 34us
(kms_flip:6378) DEBUG: name = flip
last_ts = 183.949493
last_received_ts = 183.948898
last_seq = 515
current_ts = 183.968475
current_received_ts = 183.967850
current_seq = 516
count = 311
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 35us
(kms_flip:6378) DEBUG: name = flip
last_ts = 183.968475
last_received_ts = 183.967850
last_seq = 516
current_ts = 183.987442
current_received_ts = 183.986908
current_seq = 517
count = 312
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 34us
(kms_flip:6378) DEBUG: name = flip
last_ts = 183.987442
last_received_ts = 183.986908
last_seq = 517
current_ts = 184.006409
current_received_ts = 184.005829
current_seq = 518
count = 313
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 33us
(kms_flip:6378) DEBUG: name = flip
last_ts = 184.006409
last_received_ts = 184.005829
last_seq = 518
current_ts = 184.025391
current_received_ts = 184.024796
current_seq = 519
count = 314
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 34us
(kms_flip:6378) DEBUG: name = flip
last_ts = 184.025391
last_received_ts = 184.024796
last_seq = 519
current_ts = 184.044357
current_received_ts = 184.043747
current_seq = 520
count = 315
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 29us
(kms_flip:6378) DEBUG: name = flip
last_ts = 184.044357
last_received_ts = 184.043747
last_seq = 520
current_ts = 184.063339
current_received_ts = 184.062714
current_seq = 521
count = 316
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 34us
(kms_flip:6378) DEBUG: name = flip
last_ts = 184.063339
last_received_ts = 184.062714
last_seq = 521
current_ts = 184.082306
current_received_ts = 184.081741
current_seq = 522
count = 317
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 30us
(kms_flip:6378) DEBUG: name = flip
last_ts = 184.082306
last_received_ts = 184.081741
last_seq = 522
current_ts = 184.101288
current_received_ts = 184.100677
current_seq = 523
count = 318
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 30us
(kms_flip:6378) DEBUG: name = flip
last_ts = 184.101288
last_received_ts = 184.100677
last_seq = 523
current_ts = 184.120255
current_received_ts = 184.119659
current_seq = 524
count = 319
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 34us
(kms_flip:6378) DEBUG: name = flip
last_ts = 184.120255
last_received_ts = 184.119659
last_seq = 524
current_ts = 184.139236
current_received_ts = 184.138641
current_seq = 525
count = 320
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 34us
(kms_flip:6378) DEBUG: name = flip
last_ts = 184.139236
last_received_ts = 184.138641
last_seq = 525
current_ts = 184.158203
current_received_ts = 184.157623
current_seq = 526
count = 321
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 34us
(kms_flip:6378) DEBUG: name = flip
last_ts = 184.158203
last_received_ts = 184.157623
last_seq = 526
current_ts = 184.177170
current_received_ts = 184.176559
current_seq = 527
count = 322
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 34us
(kms_flip:6378) DEBUG: name = flip
last_ts = 184.177170
last_received_ts = 184.176559
last_seq = 527
current_ts = 184.196167
current_received_ts = 184.195572
current_seq = 528
count = 323
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 33us
(kms_flip:6378) DEBUG: name = flip
last_ts = 184.196167
last_received_ts = 184.195572
last_seq = 528
current_ts = 184.215134
current_received_ts = 184.214584
current_seq = 529
count = 324
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 34us
(kms_flip:6378) DEBUG: name = flip
last_ts = 184.215134
last_received_ts = 184.214584
last_seq = 529
current_ts = 184.234100
current_received_ts = 184.233612
current_seq = 530
count = 325
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 34us
(kms_flip:6378) DEBUG: name = flip
last_ts = 184.234100
last_received_ts = 184.233612
last_seq = 530
current_ts = 184.253082
current_received_ts = 184.252457
current_seq = 531
count = 326
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 33us
(kms_flip:6378) DEBUG: name = flip
last_ts = 184.253082
last_received_ts = 184.252457
last_seq = 531
current_ts = 184.272049
current_received_ts = 184.271545
current_seq = 532
count = 327
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 35us
(kms_flip:6378) DEBUG: name = flip
last_ts = 184.272049
last_received_ts = 184.271545
last_seq = 532
current_ts = 184.291016
current_received_ts = 184.290405
current_seq = 533
count = 328
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 34us
(kms_flip:6378) DEBUG: name = flip
last_ts = 184.291016
last_received_ts = 184.290405
last_seq = 533
current_ts = 184.309982
current_received_ts = 184.309402
current_seq = 534
count = 329
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 33us
(kms_flip:6378) DEBUG: name = flip
last_ts = 184.309982
last_received_ts = 184.309402
last_seq = 534
current_ts = 184.328964
current_received_ts = 184.328400
current_seq = 535
count = 330
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 34us
(kms_flip:6378) DEBUG: name = flip
last_ts = 184.328964
last_received_ts = 184.328400
last_seq = 535
current_ts = 184.347931
current_received_ts = 184.347412
current_seq = 536
count = 331
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 34us
(kms_flip:6378) DEBUG: name = flip
last_ts = 184.347931
last_received_ts = 184.347412
last_seq = 536
current_ts = 184.366913
current_received_ts = 184.366287
current_seq = 537
count = 332
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 35us
(kms_flip:6378) DEBUG: name = flip
last_ts = 184.366913
last_received_ts = 184.366287
last_seq = 537
current_ts = 184.385880
current_received_ts = 184.385269
current_seq = 538
count = 333
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 33us
(kms_flip:6378) DEBUG: name = flip
last_ts = 184.385880
last_received_ts = 184.385269
last_seq = 538
current_ts = 184.404861
current_received_ts = 184.404266
current_seq = 539
count = 334
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 34us
(kms_flip:6378) DEBUG: name = flip
last_ts = 184.404861
last_received_ts = 184.404266
last_seq = 539
current_ts = 184.423828
current_received_ts = 184.423218
current_seq = 540
count = 335
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 35us
(kms_flip:6378) DEBUG: name = flip
last_ts = 184.423828
last_received_ts = 184.423218
last_seq = 540
current_ts = 184.442810
current_received_ts = 184.442200
current_seq = 541
count = 336
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 34us
(kms_flip:6378) DEBUG: name = flip
last_ts = 184.442810
last_received_ts = 184.442200
last_seq = 541
current_ts = 184.461792
current_received_ts = 184.461182
current_seq = 542
count = 337
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 33us
(kms_flip:6378) DEBUG: name = flip
last_ts = 184.461792
last_received_ts = 184.461182
last_seq = 542
current_ts = 184.480759
current_received_ts = 184.480164
current_seq = 543
count = 338
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 34us
(kms_flip:6378) DEBUG: name = flip
last_ts = 184.480759
last_received_ts = 184.480164
last_seq = 543
current_ts = 184.499725
current_received_ts = 184.499115
current_seq = 544
count = 339
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 34us
(kms_flip:6378) DEBUG: name = flip
last_ts = 184.499725
last_received_ts = 184.499115
last_seq = 544
current_ts = 184.518707
current_received_ts = 184.518112
current_seq = 545
count = 340
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 33us
(kms_flip:6378) DEBUG: name = flip
last_ts = 184.518707
last_received_ts = 184.518112
last_seq = 545
current_ts = 184.537674
current_received_ts = 184.537079
current_seq = 546
count = 341
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 30us
(kms_flip:6378) DEBUG: name = flip
last_ts = 184.537674
last_received_ts = 184.537079
last_seq = 546
current_ts = 184.556656
current_received_ts = 184.556168
current_seq = 547
count = 342
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 35us
(kms_flip:6378) DEBUG: name = flip
last_ts = 184.556656
last_received_ts = 184.556168
last_seq = 547
current_ts = 184.575638
current_received_ts = 184.575058
current_seq = 548
count = 343
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 33us
(kms_flip:6378) DEBUG: name = flip
last_ts = 184.575638
last_received_ts = 184.575058
last_seq = 548
current_ts = 184.594604
current_received_ts = 184.593979
current_seq = 549
count = 344
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 35us
(kms_flip:6378) DEBUG: name = flip
last_ts = 184.594604
last_received_ts = 184.593979
last_seq = 549
current_ts = 184.613571
current_received_ts = 184.612991
current_seq = 550
count = 345
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 31us
(kms_flip:6378) DEBUG: name = flip
last_ts = 184.613571
last_received_ts = 184.612991
last_seq = 550
current_ts = 184.632538
current_received_ts = 184.631958
current_seq = 551
count = 346
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 59us
(kms_flip:6378) DEBUG: name = flip
last_ts = 184.632538
last_received_ts = 184.631958
last_seq = 551
current_ts = 184.651535
current_received_ts = 184.650925
current_seq = 552
count = 347
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 34us
(kms_flip:6378) DEBUG: name = flip
last_ts = 184.651535
last_received_ts = 184.650925
last_seq = 552
current_ts = 184.670486
current_received_ts = 184.670059
current_seq = 553
count = 348
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 35us
(kms_flip:6378) DEBUG: name = flip
last_ts = 184.670486
last_received_ts = 184.670059
last_seq = 553
current_ts = 184.689468
current_received_ts = 184.688889
current_seq = 554
count = 349
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 35us
(kms_flip:6378) DEBUG: name = flip
last_ts = 184.689468
last_received_ts = 184.688889
last_seq = 554
current_ts = 184.708450
current_received_ts = 184.707809
current_seq = 555
count = 350
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 34us
(kms_flip:6378) DEBUG: name = flip
last_ts = 184.708450
last_received_ts = 184.707809
last_seq = 555
current_ts = 184.727417
current_received_ts = 184.726837
current_seq = 556
count = 351
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 33us
(kms_flip:6378) DEBUG: name = flip
last_ts = 184.727417
last_received_ts = 184.726837
last_seq = 556
current_ts = 184.746399
current_received_ts = 184.745804
current_seq = 557
count = 352
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 34us
(kms_flip:6378) DEBUG: name = flip
last_ts = 184.746399
last_received_ts = 184.745804
last_seq = 557
current_ts = 184.765366
current_received_ts = 184.764771
current_seq = 558
count = 353
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 34us
(kms_flip:6378) DEBUG: name = flip
last_ts = 184.765366
last_received_ts = 184.764771
last_seq = 558
current_ts = 184.784348
current_received_ts = 184.783920
current_seq = 559
count = 354
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 34us
(kms_flip:6378) DEBUG: name = flip
last_ts = 184.784348
last_received_ts = 184.783920
last_seq = 559
current_ts = 184.803314
current_received_ts = 184.802704
current_seq = 560
count = 355
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 33us
(kms_flip:6378) DEBUG: name = flip
last_ts = 184.803314
last_received_ts = 184.802704
last_seq = 560
current_ts = 184.822296
current_received_ts = 184.821686
current_seq = 561
count = 356
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 33us
(kms_flip:6378) DEBUG: name = flip
last_ts = 184.822296
last_received_ts = 184.821686
last_seq = 561
current_ts = 184.841263
current_received_ts = 184.840714
current_seq = 562
count = 357
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 31us
(kms_flip:6378) DEBUG: name = flip
last_ts = 184.841263
last_received_ts = 184.840714
last_seq = 562
current_ts = 184.860229
current_received_ts = 184.859650
current_seq = 563
count = 358
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 34us
(kms_flip:6378) DEBUG: name = flip
last_ts = 184.860229
last_received_ts = 184.859650
last_seq = 563
current_ts = 184.879196
current_received_ts = 184.878616
current_seq = 564
count = 359
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 34us
(kms_flip:6378) DEBUG: name = flip
last_ts = 184.879196
last_received_ts = 184.878616
last_seq = 564
current_ts = 184.898178
current_received_ts = 184.897568
current_seq = 565
count = 360
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 30us
(kms_flip:6378) DEBUG: name = flip
last_ts = 184.898178
last_received_ts = 184.897568
last_seq = 565
current_ts = 184.917145
current_received_ts = 184.916550
current_seq = 566
count = 361
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 33us
(kms_flip:6378) DEBUG: name = flip
last_ts = 184.917145
last_received_ts = 184.916550
last_seq = 566
current_ts = 184.936127
current_received_ts = 184.935562
current_seq = 567
count = 362
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 33us
(kms_flip:6378) DEBUG: name = flip
last_ts = 184.936127
last_received_ts = 184.935562
last_seq = 567
current_ts = 184.955109
current_received_ts = 184.954544
current_seq = 568
count = 363
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 34us
(kms_flip:6378) DEBUG: name = flip
last_ts = 184.955109
last_received_ts = 184.954544
last_seq = 568
current_ts = 184.974075
current_received_ts = 184.973480
current_seq = 569
count = 364
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 33us
(kms_flip:6378) DEBUG: name = flip
last_ts = 184.974075
last_received_ts = 184.973480
last_seq = 569
current_ts = 184.993057
current_received_ts = 184.992447
current_seq = 570
count = 365
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 34us
(kms_flip:6378) DEBUG: name = flip
last_ts = 184.993057
last_received_ts = 184.992447
last_seq = 570
current_ts = 185.012024
current_received_ts = 185.011414
current_seq = 571
count = 366
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 33us
(kms_flip:6378) DEBUG: name = flip
last_ts = 185.012024
last_received_ts = 185.011414
last_seq = 571
current_ts = 185.031006
current_received_ts = 185.030365
current_seq = 572
count = 367
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 34us
(kms_flip:6378) DEBUG: name = flip
last_ts = 185.031006
last_received_ts = 185.030365
last_seq = 572
current_ts = 185.049973
current_received_ts = 185.049332
current_seq = 573
count = 368
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 30us
(kms_flip:6378) DEBUG: name = flip
last_ts = 185.049973
last_received_ts = 185.049332
last_seq = 573
current_ts = 185.068954
current_received_ts = 185.068390
current_seq = 574
count = 369
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 33us
(kms_flip:6378) DEBUG: name = flip
last_ts = 185.068954
last_received_ts = 185.068390
last_seq = 574
current_ts = 185.087921
current_received_ts = 185.087296
current_seq = 575
count = 370
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 34us
(kms_flip:6378) DEBUG: name = flip
last_ts = 185.087921
last_received_ts = 185.087296
last_seq = 575
current_ts = 185.106888
current_received_ts = 185.106277
current_seq = 576
count = 371
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 34us
(kms_flip:6378) DEBUG: name = flip
last_ts = 185.106888
last_received_ts = 185.106277
last_seq = 576
current_ts = 185.125870
current_received_ts = 185.125336
current_seq = 577
count = 372
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 34us
(kms_flip:6378) DEBUG: name = flip
last_ts = 185.125870
last_received_ts = 185.125336
last_seq = 577
current_ts = 185.144836
current_received_ts = 185.144211
current_seq = 578
count = 373
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 57us
(kms_flip:6378) DEBUG: name = flip
last_ts = 185.144836
last_received_ts = 185.144211
last_seq = 578
current_ts = 185.163818
current_received_ts = 185.163269
current_seq = 579
count = 374
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 33us
(kms_flip:6378) DEBUG: name = flip
last_ts = 185.163818
last_received_ts = 185.163269
last_seq = 579
current_ts = 185.182800
current_received_ts = 185.182236
current_seq = 580
count = 375
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 34us
(kms_flip:6378) DEBUG: name = flip
last_ts = 185.182800
last_received_ts = 185.182236
last_seq = 580
current_ts = 185.201752
current_received_ts = 185.201157
current_seq = 581
count = 376
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 34us
(kms_flip:6378) DEBUG: name = flip
last_ts = 185.201752
last_received_ts = 185.201157
last_seq = 581
current_ts = 185.220734
current_received_ts = 185.220139
current_seq = 582
count = 377
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 34us
(kms_flip:6378) DEBUG: name = flip
last_ts = 185.220734
last_received_ts = 185.220139
last_seq = 582
current_ts = 185.239716
current_received_ts = 185.239090
current_seq = 583
count = 378
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 35us
(kms_flip:6378) DEBUG: name = flip
last_ts = 185.239716
last_received_ts = 185.239090
last_seq = 583
current_ts = 185.258682
current_received_ts = 185.258270
current_seq = 584
count = 379
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 39us
(kms_flip:6378) DEBUG: name = flip
last_ts = 185.258682
last_received_ts = 185.258270
last_seq = 584
current_ts = 185.277664
current_received_ts = 185.277084
current_seq = 585
count = 380
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 34us
(kms_flip:6378) DEBUG: name = flip
last_ts = 185.277664
last_received_ts = 185.277084
last_seq = 585
current_ts = 185.296631
current_received_ts = 185.296173
current_seq = 586
count = 381
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 34us
(kms_flip:6378) DEBUG: name = flip
last_ts = 185.296631
last_received_ts = 185.296173
last_seq = 586
current_ts = 185.315613
current_received_ts = 185.315033
current_seq = 587
count = 382
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 33us
(kms_flip:6378) DEBUG: name = flip
last_ts = 185.315613
last_received_ts = 185.315033
last_seq = 587
current_ts = 185.334579
current_received_ts = 185.333984
current_seq = 588
count = 383
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 34us
(kms_flip:6378) DEBUG: name = flip
last_ts = 185.334579
last_received_ts = 185.333984
last_seq = 588
current_ts = 185.353561
current_received_ts = 185.352921
current_seq = 589
count = 384
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 34us
(kms_flip:6378) DEBUG: name = flip
last_ts = 185.353561
last_received_ts = 185.352921
last_seq = 589
current_ts = 185.372528
current_received_ts = 185.371979
current_seq = 590
count = 385
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 36us
(kms_flip:6378) DEBUG: name = flip
last_ts = 185.372528
last_received_ts = 185.371979
last_seq = 590
current_ts = 185.391495
current_received_ts = 185.390900
current_seq = 591
count = 386
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 34us
(kms_flip:6378) DEBUG: name = flip
last_ts = 185.391495
last_received_ts = 185.390900
last_seq = 591
current_ts = 185.410461
current_received_ts = 185.409882
current_seq = 592
count = 387
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 33us
(kms_flip:6378) DEBUG: name = flip
last_ts = 185.410461
last_received_ts = 185.409882
last_seq = 592
current_ts = 185.429459
current_received_ts = 185.429062
current_seq = 593
count = 388
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 43us
(kms_flip:6378) DEBUG: name = flip
last_ts = 185.429459
last_received_ts = 185.429062
last_seq = 593
current_ts = 185.448425
current_received_ts = 185.447830
current_seq = 594
count = 389
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 33us
(kms_flip:6378) DEBUG: name = flip
last_ts = 185.448425
last_received_ts = 185.447830
last_seq = 594
current_ts = 185.467407
current_received_ts = 185.466766
current_seq = 595
count = 390
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 34us
(kms_flip:6378) DEBUG: name = flip
last_ts = 185.467407
last_received_ts = 185.466766
last_seq = 595
current_ts = 185.486374
current_received_ts = 185.485855
current_seq = 596
count = 391
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 34us
(kms_flip:6378) DEBUG: name = flip
last_ts = 185.486374
last_received_ts = 185.485855
last_seq = 596
current_ts = 185.505356
current_received_ts = 185.504807
current_seq = 597
count = 392
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 33us
(kms_flip:6378) DEBUG: name = flip
last_ts = 185.505356
last_received_ts = 185.504807
last_seq = 597
current_ts = 185.524323
current_received_ts = 185.523727
current_seq = 598
count = 393
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 34us
(kms_flip:6378) DEBUG: name = flip
last_ts = 185.524323
last_received_ts = 185.523727
last_seq = 598
current_ts = 185.543289
current_received_ts = 185.542694
current_seq = 599
count = 394
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 34us
(kms_flip:6378) DEBUG: name = flip
last_ts = 185.543289
last_received_ts = 185.542694
last_seq = 599
current_ts = 185.562256
current_received_ts = 185.561783
current_seq = 600
count = 395
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 34us
(kms_flip:6378) DEBUG: name = flip
last_ts = 185.562256
last_received_ts = 185.561783
last_seq = 600
current_ts = 185.581238
current_received_ts = 185.580643
current_seq = 601
count = 396
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 33us
(kms_flip:6378) DEBUG: name = flip
last_ts = 185.581238
last_received_ts = 185.580643
last_seq = 601
current_ts = 185.600220
current_received_ts = 185.599579
current_seq = 602
count = 397
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 34us
(kms_flip:6378) DEBUG: name = flip
last_ts = 185.600220
last_received_ts = 185.599579
last_seq = 602
current_ts = 185.619186
current_received_ts = 185.618652
current_seq = 603
count = 398
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 30us
(kms_flip:6378) DEBUG: name = flip
last_ts = 185.619186
last_received_ts = 185.618652
last_seq = 603
current_ts = 185.638153
current_received_ts = 185.637604
current_seq = 604
count = 399
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 30us
(kms_flip:6378) DEBUG: name = flip
last_ts = 185.638153
last_received_ts = 185.637604
last_seq = 604
current_ts = 185.657135
current_received_ts = 185.656555
current_seq = 605
count = 400
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 35us
(kms_flip:6378) DEBUG: name = flip
last_ts = 185.657135
last_received_ts = 185.656555
last_seq = 605
current_ts = 185.676117
current_received_ts = 185.675507
current_seq = 606
count = 401
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 33us
(kms_flip:6378) DEBUG: name = flip
last_ts = 185.676117
last_received_ts = 185.675507
last_seq = 606
current_ts = 185.695084
current_received_ts = 185.694473
current_seq = 607
count = 402
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 29us
(kms_flip:6378) DEBUG: name = flip
last_ts = 185.695084
last_received_ts = 185.694473
last_seq = 607
current_ts = 185.714050
current_received_ts = 185.713562
current_seq = 608
count = 403
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 30us
(kms_flip:6378) DEBUG: name = flip
last_ts = 185.714050
last_received_ts = 185.713562
last_seq = 608
current_ts = 185.733032
current_received_ts = 185.732407
current_seq = 609
count = 404
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 33us
(kms_flip:6378) DEBUG: name = flip
last_ts = 185.733032
last_received_ts = 185.732407
last_seq = 609
current_ts = 185.751999
current_received_ts = 185.751389
current_seq = 610
count = 405
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 55us
(kms_flip:6378) DEBUG: name = flip
last_ts = 185.751999
last_received_ts = 185.751389
last_seq = 610
current_ts = 185.770981
current_received_ts = 185.770401
current_seq = 611
count = 406
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 34us
(kms_flip:6378) DEBUG: name = flip
last_ts = 185.770981
last_received_ts = 185.770401
last_seq = 611
current_ts = 185.789948
current_received_ts = 185.789383
current_seq = 612
count = 407
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 30us
(kms_flip:6378) DEBUG: name = flip
last_ts = 185.789948
last_received_ts = 185.789383
last_seq = 612
current_ts = 185.808914
current_received_ts = 185.808304
current_seq = 613
count = 408
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 33us
(kms_flip:6378) DEBUG: name = flip
last_ts = 185.808914
last_received_ts = 185.808304
last_seq = 613
current_ts = 185.827896
current_received_ts = 185.827271
current_seq = 614
count = 409
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 34us
(kms_flip:6378) DEBUG: name = flip
last_ts = 185.827896
last_received_ts = 185.827271
last_seq = 614
current_ts = 185.846878
current_received_ts = 185.846344
current_seq = 615
count = 410
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 33us
(kms_flip:6378) DEBUG: name = flip
last_ts = 185.846878
last_received_ts = 185.846344
last_seq = 615
current_ts = 185.865845
current_received_ts = 185.865173
current_seq = 616
count = 411
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 30us
(kms_flip:6378) DEBUG: name = flip
last_ts = 185.865845
last_received_ts = 185.865173
last_seq = 616
current_ts = 185.884811
current_received_ts = 185.884247
current_seq = 617
count = 412
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 34us
(kms_flip:6378) DEBUG: name = flip
last_ts = 185.884811
last_received_ts = 185.884247
last_seq = 617
current_ts = 185.903793
current_received_ts = 185.903183
current_seq = 618
count = 413
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 33us
(kms_flip:6378) DEBUG: name = flip
last_ts = 185.903793
last_received_ts = 185.903183
last_seq = 618
current_ts = 185.922775
current_received_ts = 185.922241
current_seq = 619
count = 414
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 34us
(kms_flip:6378) DEBUG: name = flip
last_ts = 185.922775
last_received_ts = 185.922241
last_seq = 619
current_ts = 185.941742
current_received_ts = 185.941223
current_seq = 620
count = 415
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 35us
(kms_flip:6378) DEBUG: name = flip
last_ts = 185.941742
last_received_ts = 185.941223
last_seq = 620
current_ts = 185.960709
current_received_ts = 185.960114
current_seq = 621
count = 416
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 35us
(kms_flip:6378) DEBUG: name = flip
last_ts = 185.960709
last_received_ts = 185.960114
last_seq = 621
current_ts = 185.979675
current_received_ts = 185.979111
current_seq = 622
count = 417
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 34us
(kms_flip:6378) DEBUG: name = flip
last_ts = 185.979675
last_received_ts = 185.979111
last_seq = 622
current_ts = 185.998672
current_received_ts = 185.998093
current_seq = 623
count = 418
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 35us
(kms_flip:6378) DEBUG: name = flip
last_ts = 185.998672
last_received_ts = 185.998093
last_seq = 623
current_ts = 186.017639
current_received_ts = 186.017059
current_seq = 624
count = 419
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 33us
(kms_flip:6378) DEBUG: name = flip
last_ts = 186.017639
last_received_ts = 186.017059
last_seq = 624
current_ts = 186.036621
current_received_ts = 186.036041
current_seq = 625
count = 420
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 34us
(kms_flip:6378) DEBUG: name = flip
last_ts = 186.036621
last_received_ts = 186.036041
last_seq = 625
current_ts = 186.055588
current_received_ts = 186.054993
current_seq = 626
count = 421
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 34us
(kms_flip:6378) DEBUG: name = flip
last_ts = 186.055588
last_received_ts = 186.054993
last_seq = 626
current_ts = 186.074554
current_received_ts = 186.074097
current_seq = 627
count = 422
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 34us
(kms_flip:6378) DEBUG: name = flip
last_ts = 186.074554
last_received_ts = 186.074097
last_seq = 627
current_ts = 186.093521
current_received_ts = 186.092941
current_seq = 628
count = 423
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 58us
(kms_flip:6378) DEBUG: name = flip
last_ts = 186.093521
last_received_ts = 186.092941
last_seq = 628
current_ts = 186.112503
current_received_ts = 186.111893
current_seq = 629
count = 424
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 34us
(kms_flip:6378) DEBUG: name = flip
last_ts = 186.112503
last_received_ts = 186.111893
last_seq = 629
current_ts = 186.131470
current_received_ts = 186.130951
current_seq = 630
count = 425
seq_step = 1
(kms_flip:6378) DEBUG: Vblank took 893us
(kms_flip:6378) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931:
(kms_flip:6378) CRITICAL: Failed assertion: end - start < 500
(kms_flip:6378) CRITICAL: Last errno: 4, Interrupted system call
(kms_flip:6378) igt_core-INFO: Stack trace:
(kms_flip:6378) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:6378) igt_core-INFO:   #1 ../tests/kms_flip.c:926 run_test_on_crtc_set()
(kms_flip:6378) igt_core-INFO:   #2 ../tests/kms_flip.c:1920 run_test()
(kms_flip:6378) igt_core-INFO:   #3 ../tests/kms_flip.c:2220 __igt_unique____real_main2105()
(kms_flip:6378) igt_core-INFO:   #4 ../tests/kms_flip.c:2105 main()
(kms_flip:6378) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:6378) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:6378) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest C-HDMI-A2: FAIL (8.265s)
Subtest flip-vs-expired-vblank-interruptible: FAIL (28.586s)
(kms_flip:6378) igt_kms-CRITICAL: Test assertion failure function kmstest_set_connector_dpms, file ../lib/igt_kms.c:2352:
(kms_flip:6378) igt_kms-CRITICAL: Failed assertion: found_it
(kms_flip:6378) igt_kms-CRITICAL: Last errno: 9, Bad file descriptor
(kms_flip:6378) igt_kms-CRITICAL: DPMS property not found on 400
Dmesg
<6> [223.713298] [IGT] kms_flip: starting dynamic subtest C-HDMI-A2
<7> [223.714343] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:417]
<7> [223.714683] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:437]
<7> [223.731088] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:151:pipe A]
<7> [223.731252] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:269:pipe B]
<7> [223.731312] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.enable (expected yes, found no)
<7> [223.731483] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.active (expected yes, found no)
<7> [223.731622] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in cpu_transcoder (expected 1, found -1)
<7> [223.731757] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in lane_count (expected 4, found 0)
<7> [223.731904] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [223.732039] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [223.732171] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [223.732304] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2360, found 0)
<7> [223.732440] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [223.732576] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2360, found 0)
<7> [223.732711] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [223.732854] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2096, found 0)
<7> [223.732987] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [223.733121] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [223.733254] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [223.733387] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [223.733518] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [223.733651] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [223.733782] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [223.733950] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2360, found 0)
<7> [223.734128] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [223.734258] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2360, found 0)
<7> [223.734389] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [223.734520] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2096, found 0)
<7> [223.734650] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [223.734783] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [223.734928] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [223.735060] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [223.735192] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [223.735324] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [223.735456] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [223.735588] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [223.735719] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [223.735864] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [223.735995] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 139929, found 0)
<7> [223.736126] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 139929, found 0)
<7> [223.736257] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in port_clock (expected 139929, found 0)
<7> [223.736389] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:269:pipe B] fastset requirement not met, forcing full modeset
<7> [223.736522] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:269:pipe B] releasing DPLL 0
<7> [223.736675] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [223.736809] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:269:pipe B] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x2 -> 0x0
<7> [223.737011] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:263:cursor B] ddb (2016 - 2048) -> ( 0 - 0), size 32 -> 0
<7> [223.737161] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:269:pipe B] min cdclk: 69965 kHz -> 0 kHz
<7> [223.737304] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:269:pipe B] enable: no [modeset]
<7> [223.737445] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:153:plane 1B] fb: [NOFB], visible: no
<7> [223.737582] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:183:plane 2B] fb: [NOFB], visible: no
<7> [223.737714] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:213:plane 3B] fb: [NOFB], visible: no
<7> [223.737867] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:243:plane 4B] fb: [NOFB], visible: no
<7> [223.738010] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:253:plane 5B] fb: [NOFB], visible: no
<7> [223.738141] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:263:cursor B] fb: [NOFB], visible: no
<7> [223.738364] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe B
<7> [223.749097] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_TC1
<7> [223.749267] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x2, on? 1) for [CRTC:269:pipe B]
<7> [223.749434] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0
<7> [223.749589] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:389:DDI B/PHY B]
<7> [223.749731] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:399:DDI TC1/PHY C]
<7> [223.749913] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:403:DDI TC2/PHY D]
<7> [223.750058] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:405:DP-MST A]
<7> [223.750197] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:406:DP-MST B]
<7> [223.750336] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:407:DP-MST C]
<7> [223.750477] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:400:HDMI-A-2]
<7> [223.750683] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [223.750884] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_3
<7> [223.751057] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:269:pipe B]
<7> [223.751377] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:387:pipe C]
<7> [223.751508] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:387:pipe C]
<7> [223.751530] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:400:HDMI-A-2]
<7> [223.751585] i915 0000:00:02.0: [drm:compute_baseline_pipe_bpp [i915]] [CONNECTOR:400:HDMI-A-2] Limiting target display pipe bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36)
<7> [223.751750] i915 0000:00:02.0: [drm:intel_hdmi_compute_output_format [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [223.751896] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:387:pipe C] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [223.752053] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:399:DDI TC1/PHY C] [CRTC:387:pipe C]
<7> [223.752186] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.enable (expected no, found yes)
<7> [223.752323] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.active (expected no, found yes)
<7> [223.752459] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in cpu_transcoder (expected -1, found 2)
<7> [223.752594] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in lane_count (expected 0, found 4)
<7> [223.752729] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [223.752877] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [223.753025] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [223.753162] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2360)
<7> [223.753298] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [223.753431] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2360)
<7> [223.753564] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [223.753698] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2096)
<7> [223.753842] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [223.753986] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [223.754117] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [223.754249] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [223.754390] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [223.754522] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [223.754656] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [223.754786] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2360)
<7> [223.754932] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [223.755075] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2360)
<7> [223.755206] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [223.755338] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2096)
<7> [223.755468] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [223.755601] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [223.755732] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [223.755875] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [223.756020] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [223.756151] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [223.756280] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [223.756411] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [223.756542] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [223.756673] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [223.756805] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 139929)
<7> [223.756962] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 139929)
<7> [223.757109] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in port_clock (expected 0, found 139929)
<7> [223.757245] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:387:pipe C] fastset requirement not met, forcing full modeset
<7> [223.757398] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [223.757531] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:387:pipe C] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x4
<7> [223.757685] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:271:plane 1C] ddb ( 0 - 0) -> ( 0 - 2016), size 0 -> 2016
<7> [223.757815] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:381:cursor C] ddb ( 0 - 0) -> (2016 - 2048), size 0 -> 32
<7> [223.757963] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:271:plane 1C] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm
<7> [223.758102] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:271:plane 1C] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 2, 2, 2, 2, 5, 8, 9, 0, 0, 0
<7> [223.758232] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:271:plane 1C] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 33, 33, 33, 33, 81, 129, 145, 30, 0, 0
<7> [223.758364] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:271:plane 1C] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 19, 38, 38, 38, 38, 91, 143, 161, 31, 0, 0
<7> [223.758509] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:387:pipe C] data rate 559716 num active planes 1
<7> [223.758647] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 560
<7> [223.758781] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [223.758950] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:387:pipe C] min cdclk: 0 kHz -> 69965 kHz
<7> [223.759106] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:387:pipe C] allocated DPLL 0
<7> [223.759244] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:387:pipe C] reserving DPLL 0
<7> [223.759383] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:387:pipe C] enable: yes [modeset]
<7> [223.759519] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [223.759655] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: C, pipe bpp: 24, dithering: 0
<7> [223.759789] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [223.759987] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [223.760124] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [223.760257] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [223.760391] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [223.760524] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [223.760656] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [223.760788] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: yes, vmin: 1125, vmax: 1125, flipline: 1125, pipeline full: 42, guardband: 44 vsync start: 0, vsync end: 0
<7> [223.760938] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 1081, vmax vblank: 1081, vmin vtotal: 1125, vmax vtotal: 1125
<7> [223.761081] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [223.761213] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5
<7> [223.761346] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5
<7> [223.761478] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=139929, hd=1920 hb=1920-2360 hs=2008-2096 ht=2360, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [223.761610] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 53 139929 1920 2008 2096 2360 1080 1084 1089 1125 0x40 0x5
<7> [223.761742] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=139929, hd=1920 hb=1920-2360 hs=2008-2096 ht=2360, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [223.761886] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 139929, pixel rate 139929, min cdclk 69965, min voltage level 0
<7> [223.762029] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 135, ips linetime: 0
<7> [223.762159] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0
<7> [223.762290] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1920x1080+0+0
<7> [223.762421] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [223.762558] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [223.762692] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [223.762833] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: cfgcr0: 0x1a6655d, cfgcr1: 0xe84, div0: 0x0, mg_refclkin_ctl: 0x0, hg_clktop2_coreclkctl1: 0x0, mg_clktop2_hsclkctl: 0x0, mg_pll_div0: 0x0, mg_pll_div2: 0x0, mg_pll_lf: 0x0, mg_pll_frac_lock: 0x0, mg_pll_ssc: 0x0, mg_pll_bias: 0x0, mg_pll_tdc_coldst_bias: 0x0
<7> [223.762978] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [223.763110] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [223.763243] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [223.763377] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [223.763512] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [223.763644] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [223.763777] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [223.763924] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [223.764069] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [223.764200] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [223.764331] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [223.764466] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [223.764599] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:271:plane 1C] fb: [FB:417] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [223.764732] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [223.764880] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [223.765024] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:301:plane 2C] fb: [NOFB], visible: no
<7> [223.765156] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:331:plane 3C] fb: [NOFB], visible: no
<7> [223.765286] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:361:plane 4C] fb: [NOFB], visible: no
<7> [223.765418] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:371:plane 5C] fb: [NOFB], visible: no
<7> [223.765548] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:381:cursor C] fb: [NOFB], visible: no
<7> [223.766141] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_3
<7> [223.766291] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_4
<7> [223.766451] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:389:DDI B/PHY B]
<7> [223.766588] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:399:DDI TC1/PHY C]
<7> [223.766724] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:403:DDI TC2/PHY D]
<7> [223.766874] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:405:DP-MST A]
<7> [223.767017] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:406:DP-MST B]
<7> [223.767151] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:407:DP-MST C]
<7> [223.767321] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [223.767490] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x4, on? 0) for [CRTC:387:pipe C]
<7> [223.767628] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0
<7> [223.767818] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_TC1
<7> [223.768017] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe C
<7> [223.787944] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:400:HDMI-A-2]
<7> [223.788147] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:387:pipe C]
<6> [223.889858] pcieport 0000:00:06.0: AER: Correctable error message received from 0000:01:00.0
<4> [223.890002] nvme 0000:01:00.0: PCIe Bus Error: severity=Correctable, type=Physical Layer, (Receiver ID)
<4> [223.890009] nvme 0000:01:00.0: device [15b7:5006] error status/mask=00000001/0000e000
<4> [223.890016] nvme 0000:01:00.0: [ 0] RxErr (First)
<6> [231.978163] [IGT] kms_flip: finished subtest C-HDMI-A2, FAIL
<6> [231.979048] [IGT] kms_flip: finished subtest flip-vs-expired-vblank-interruptible, FAIL
<7> [231.981924] i915 0000:00:02.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:437] from all active usage due to RMFB ioctl
<7> [231.981974] i915 0000:00:02.0: [drm:atomic_remove_fb] Disabling [PLANE:271:plane 1C] because [FB:437] is removed
<7> [231.982014] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:271:plane 1C] ddb ( 0 - 2016) -> ( 0 - 0), size 2016 -> 0
<7> [231.982182] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:271:plane 1C] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [231.982323] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:271:plane 1C] lines 1, 2, 2, 2, 2, 5, 8, 9, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [231.982463] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:271:plane 1C] blocks 16, 33, 33, 33, 33, 81, 129, 145, 30, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [231.982616] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:271:plane 1C] min_ddb 19, 38, 38, 38, 38, 91, 143, 161, 31, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [231.982768] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:387:pipe C] data rate 0 num active planes 0
<7> [231.982934] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 0
<7> [231.983069] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<6> [231.984991] [IGT] kms_flip: exiting, ret=98
<7> [231.994909] i915 0000:00:02.0: [drm:compute_baseline_pipe_bpp [i915]] [CONNECTOR:400:HDMI-A-2] Limiting target display pipe bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36)
<7> [231.995082] i915 0000:00:02.0: [drm:intel_hdmi_compute_output_format [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [231.995216] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:151:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [231.995369] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:399:DDI TC1/PHY C] [CRTC:151:pipe A]
<7> [231.995506] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [231.995666] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [231.995805] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [231.995994] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [231.996148] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [231.996282] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [231.996416] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [231.996551] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2360)
<7> [231.996689] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [231.996832] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2360)
<7> [231.996968] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [231.997101] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2096)
<7> [231.997233] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [231.997368] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [231.997500] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [231.997634] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [231.997767] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [231.997910] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [231.998043] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [231.998177] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2360)
<7> [231.998311] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [231.998443] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2360)
<7> [231.998576] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [231.998708] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2096)
<7> [231.998846] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [231.998979] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [231.999113] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [231.999246] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [231.999380] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [231.999513] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [231.999646] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [231.999778] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [231.999921] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [232.000054] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [232.000188] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 139929)
<7> [232.000321] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 139929)
<7> [232.000455] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in port_clock (expected 0, found 139929)
<7> [232.000589] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:151:pipe A] fastset requirement not met, forcing full modeset
<7> [232.000722] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.enable (expected yes, found no)
<7> [232.000864] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.active (expected yes, found no)
<7> [232.000996] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in cpu_transcoder (expected 2, found -1)
<7> [232.001130] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in lane_count (expected 4, found 0)
<7> [232.001263] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [232.001397] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [232.001534] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [232.001670] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2360, found 0)
<7> [232.001805] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [232.002032] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2360, found 0)
<7> [232.002168] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [232.002307] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2096, found 0)
<7> [232.002439] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [232.002572] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [232.002703] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [232.003037] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [232.003170] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [232.003303] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [232.003436] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [232.003567] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2360, found 0)
<7> [232.003699] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [232.003838] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2360, found 0)
<7> [232.003969] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [232.004101] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2096, found 0)
<7> [232.004233] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [232.004366] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [232.004498] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [232.004630] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [232.004762] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [232.004904] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [232.005037] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [232.005169] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [232.005300] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [232.005432] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [232.005563] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 139929, found 0)
<7> [232.005700] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 139929, found 0)
<7> [232.005839] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in port_clock (expected 139929, found 0)
<7> [232.005971] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:387:pipe C] fastset requirement not met, forcing full modeset
<7> [232.006105] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:387:pipe C] releasing DPLL 0
<7> [232.006274] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:151:pipe A] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x4 -> 0x1
<7> [232.006409] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:387:pipe C] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x4 -> 0x1
<7> [232.006545] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:35:plane 1A] ddb ( 0 - 0) -> ( 0 - 2016), size 0 -> 2016
<7> [232.006677] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:145:cursor A] ddb ( 0 - 0) -> (2016 - 2048), size 0 -> 32
<7> [232.006808] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [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> [232.006954] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 2, 2, 2, 2, 5, 8, 9, 0, 0, 0
<7> [232.007085] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 33, 33, 33, 33, 81, 129, 145, 30, 0, 0
<7> [232.007218] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 19, 38, 38, 38, 38, 91, 143, 161, 31, 0, 0
<7> [232.007350] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:381:cursor C] ddb (2016 - 2048) -> ( 0 - 0), size 32 -> 0
<7> [232.007497] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:151:pipe A] data rate 559716 num active planes 1
<7> [232.007634] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 560
<7> [232.007769] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [232.007929] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:151:pipe A] min cdclk: 0 kHz -> 69965 kHz
<7> [232.008065] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:387:pipe C] min cdclk: 69965 kHz -> 0 kHz
<7> [232.008215] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:151:pipe A] allocated DPLL 0
<7> [232.008354] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:151:pipe A] reserving DPLL 0
<7> [232.008494] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:151:pipe A] enable: yes [modeset]
<7> [232.008630] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [232.008767] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [232.008920] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [232.009056] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [232.009189] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [232.009322] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [232.009453] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [232.009586] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [232.009717] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [232.009968] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: yes, vmin: 1125, vmax: 1125, flipline: 1125, pipeline full: 42, guardband: 44 vsync start: 0, vsync end: 0
<7> [232.010101] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 1081, vmax vblank: 1081, vmin vtotal: 1125, vmax vtotal: 1125
<7> [232.010234] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [232.010366] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5
<7> [232.010499] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5
<7> [232.010631] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=139929, hd=1920 hb=1920-2360 hs=2008-2096 ht=2360, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [232.010763] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 53 139929 1920 2008 2096 2360 1080 1084 1089 1125 0x40 0x5
<7> [232.011403] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=139929, hd=1920 hb=1920-2360 hs=2008-2096 ht=2360, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [232.011558] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 139929, pixel rate 139929, min cdclk 69965, min voltage level 0
<7> [232.011693] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 135, ips linetime: 0
<7> [232.011839] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0
<7> [232.011994] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1920x1080+0+0
<7> [232.012130] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [232.012266] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [232.012401] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [232.012535] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: cfgcr0: 0x1a6655d, cfgcr1: 0xe84, div0: 0x0, mg_refclkin_ctl: 0x0, hg_clktop2_coreclkctl1: 0x0, mg_clktop2_hsclkctl: 0x0, mg_pll_div0: 0x0, mg_pll_div2: 0x0, mg_pll_lf: 0x0, mg_pll_frac_lock: 0x0, mg_pll_ssc: 0x0, mg_pll_bias: 0x0, mg_pll_tdc_coldst_bias: 0x0
<7> [232.012671] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [232.012805] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [232.012958] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [232.013096] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [232.013228] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [232.013362] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [232.013495] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [232.013625] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [232.013765] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [232.013911] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [232.014058] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [232.014191] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [232.014324] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:plane 1A] fb: [FB:416] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [232.014458] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [232.014590] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [232.014722] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [232.014867] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [232.014997] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [232.015128] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [232.015258] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:145:cursor A] fb: [NOFB], visible: no
<7> [232.015389] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:387:pipe C] enable: no [modeset]
<7> [232.015519] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:271:plane 1C] fb: [NOFB], visible: no
<7> [232.015649] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:301:plane 2C] fb: [NOFB], visible: no
<7> [232.015779] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:331:plane 3C] fb: [NOFB], visible: no
<7> [232.015924] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:361:plane 4C] fb: [NOFB], visible: no
<7> [232.016058] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:371:plane 5C] fb: [NOFB], visible: no
<7> [232.016192] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:381:cursor C] fb: [NOFB], visible: no
<7> [232.016853] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe C
<7> [232.023912] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_TC1
<7> [232.024084] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x4, on? 1) for [CRTC:387:pipe C]
<7> [232.024253] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0
<7> [232.024407] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:389:DDI B/PHY B]
<7> [232.024551] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:399:DDI TC1/PHY C]
<7> [232.024692] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:403:DDI TC2/PHY D]
<7> [232.024841] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:405:DP-MST A]
<7> [232.024984] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:406:DP-MST B]
<7> [232.025124] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:407:DP-MST C]
<7> [232.025368] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:151:pipe A]
<7> [232.025505] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0
<7> [232.025696] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_TC1
<7> [232.025884] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [232.026279] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1
<7> [232.026421] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:35:plane 1A]
<7> [232.045391] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:400:HDMI-A-2]
<7> [232.045609] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:151:pipe A]
<7> [232.046128] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_4
<7> [232.046302] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:387:pipe C]
<7> [232.046680] i915 0000:00:02.0: [drm:drm_client_dev_restore] fbdev: ret=0
Created at 2026-04-29 18:31:16