Results for igt@kms_flip@flip-vs-expired-vblank@d-hdmi-a3

Result: Fail

integration-manifest git-log-oneline i915_display_info11 igt_runner11 runtimes11 results11.json results11-i915-load.json guc_logs11.tar i915_display_info_post_exec11 boot11 dmesg11

DetailValue
Duration 3.76 seconds
Hostname 
shard-dg2-7
Igt-Version 
IGT-Version: 2.3-gdc0445d49 (x86_64) (Linux: 7.0.0-rc6-CI_DRM_18246-g24a8a6cff08b+ x86_64)
Out 
Starting dynamic subtest: D-HDMI-A3
  1920x1080: 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5 
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../tests/kms_flip.c:925 run_test_on_crtc_set.constprop.0()
  #2 ../tests/kms_flip.c:1891 run_test()
  #3 ../tests/kms_flip.c:2126 __igt_unique____real_main2047()
  #4 ../tests/kms_flip.c:2047 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest D-HDMI-A3: FAIL (3.757s)
Subtest flip-vs-expired-vblank: FAIL (26.961s)
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: D-HDMI-A3
(kms_flip:3297) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:930:
(kms_flip:3297) CRITICAL: Failed assertion: end - start < 500
Dynamic subtest D-HDMI-A3 failed.
**** DEBUG ****
(kms_flip:3297) DEBUG: name = flip
last_ts = 582.417358
last_received_ts = 582.417053
last_seq = 581
current_ts = 582.434021
current_received_ts = 582.433655
current_seq = 582
count = 78
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 64us
(kms_flip:3297) DEBUG: name = flip
last_ts = 582.434021
last_received_ts = 582.433655
last_seq = 582
current_ts = 582.450684
current_received_ts = 582.450378
current_seq = 583
count = 79
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 64us
(kms_flip:3297) DEBUG: name = flip
last_ts = 582.450684
last_received_ts = 582.450378
last_seq = 583
current_ts = 582.467346
current_received_ts = 582.467041
current_seq = 584
count = 80
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 61us
(kms_flip:3297) DEBUG: name = flip
last_ts = 582.467346
last_received_ts = 582.467041
last_seq = 584
current_ts = 582.484009
current_received_ts = 582.483704
current_seq = 585
count = 81
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 79us
(kms_flip:3297) DEBUG: name = flip
last_ts = 582.484009
last_received_ts = 582.483704
last_seq = 585
current_ts = 582.500732
current_received_ts = 582.500427
current_seq = 586
count = 82
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 61us
(kms_flip:3297) DEBUG: name = flip
last_ts = 582.500732
last_received_ts = 582.500427
last_seq = 586
current_ts = 582.517395
current_received_ts = 582.517151
current_seq = 587
count = 83
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 61us
(kms_flip:3297) DEBUG: name = flip
last_ts = 582.517395
last_received_ts = 582.517151
last_seq = 587
current_ts = 582.534058
current_received_ts = 582.533752
current_seq = 588
count = 84
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 61us
(kms_flip:3297) DEBUG: name = flip
last_ts = 582.534058
last_received_ts = 582.533752
last_seq = 588
current_ts = 582.550720
current_received_ts = 582.550476
current_seq = 589
count = 85
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 62us
(kms_flip:3297) DEBUG: name = flip
last_ts = 582.550720
last_received_ts = 582.550476
last_seq = 589
current_ts = 582.567383
current_received_ts = 582.567078
current_seq = 590
count = 86
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 57us
(kms_flip:3297) DEBUG: name = flip
last_ts = 582.567383
last_received_ts = 582.567078
last_seq = 590
current_ts = 582.584045
current_received_ts = 582.583740
current_seq = 591
count = 87
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 61us
(kms_flip:3297) DEBUG: name = flip
last_ts = 582.584045
last_received_ts = 582.583740
last_seq = 591
current_ts = 582.600708
current_received_ts = 582.600464
current_seq = 592
count = 88
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 62us
(kms_flip:3297) DEBUG: name = flip
last_ts = 582.600708
last_received_ts = 582.600464
last_seq = 592
current_ts = 582.617432
current_received_ts = 582.617126
current_seq = 593
count = 89
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 62us
(kms_flip:3297) DEBUG: name = flip
last_ts = 582.617432
last_received_ts = 582.617126
last_seq = 593
current_ts = 582.634094
current_received_ts = 582.633850
current_seq = 594
count = 90
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 61us
(kms_flip:3297) DEBUG: name = flip
last_ts = 582.634094
last_received_ts = 582.633850
last_seq = 594
current_ts = 582.650757
current_received_ts = 582.650330
current_seq = 595
count = 91
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 61us
(kms_flip:3297) DEBUG: name = flip
last_ts = 582.650757
last_received_ts = 582.650330
last_seq = 595
current_ts = 582.667419
current_received_ts = 582.667175
current_seq = 596
count = 92
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 61us
(kms_flip:3297) DEBUG: name = flip
last_ts = 582.667419
last_received_ts = 582.667175
last_seq = 596
current_ts = 582.684082
current_received_ts = 582.683838
current_seq = 597
count = 93
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 60us
(kms_flip:3297) DEBUG: name = flip
last_ts = 582.684082
last_received_ts = 582.683838
last_seq = 597
current_ts = 582.700745
current_received_ts = 582.700439
current_seq = 598
count = 94
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 65us
(kms_flip:3297) DEBUG: name = flip
last_ts = 582.700745
last_received_ts = 582.700439
last_seq = 598
current_ts = 582.717468
current_received_ts = 582.717163
current_seq = 599
count = 95
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 64us
(kms_flip:3297) DEBUG: name = flip
last_ts = 582.717468
last_received_ts = 582.717163
last_seq = 599
current_ts = 582.734131
current_received_ts = 582.733765
current_seq = 600
count = 96
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 64us
(kms_flip:3297) DEBUG: name = flip
last_ts = 582.734131
last_received_ts = 582.733765
last_seq = 600
current_ts = 582.750793
current_received_ts = 582.750305
current_seq = 601
count = 97
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 56us
(kms_flip:3297) DEBUG: name = flip
last_ts = 582.750793
last_received_ts = 582.750305
last_seq = 601
current_ts = 582.767456
current_received_ts = 582.767090
current_seq = 602
count = 98
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 57us
(kms_flip:3297) DEBUG: name = flip
last_ts = 582.767456
last_received_ts = 582.767090
last_seq = 602
current_ts = 582.784119
current_received_ts = 582.783752
current_seq = 603
count = 99
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 57us
(kms_flip:3297) DEBUG: name = flip
last_ts = 582.784119
last_received_ts = 582.783752
last_seq = 603
current_ts = 582.800781
current_received_ts = 582.800354
current_seq = 604
count = 100
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 55us
(kms_flip:3297) DEBUG: name = flip
last_ts = 582.800781
last_received_ts = 582.800354
last_seq = 604
current_ts = 582.817444
current_received_ts = 582.817078
current_seq = 605
count = 101
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 58us
(kms_flip:3297) DEBUG: name = flip
last_ts = 582.817444
last_received_ts = 582.817078
last_seq = 605
current_ts = 582.834167
current_received_ts = 582.833801
current_seq = 606
count = 102
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 60us
(kms_flip:3297) DEBUG: name = flip
last_ts = 582.834167
last_received_ts = 582.833801
last_seq = 606
current_ts = 582.850830
current_received_ts = 582.850403
current_seq = 607
count = 103
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 60us
(kms_flip:3297) DEBUG: name = flip
last_ts = 582.850830
last_received_ts = 582.850403
last_seq = 607
current_ts = 582.867493
current_received_ts = 582.867188
current_seq = 608
count = 104
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 60us
(kms_flip:3297) DEBUG: name = flip
last_ts = 582.867493
last_received_ts = 582.867188
last_seq = 608
current_ts = 582.884155
current_received_ts = 582.883911
current_seq = 609
count = 105
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 61us
(kms_flip:3297) DEBUG: name = flip
last_ts = 582.884155
last_received_ts = 582.883911
last_seq = 609
current_ts = 582.900818
current_received_ts = 582.900452
current_seq = 610
count = 106
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 60us
(kms_flip:3297) DEBUG: name = flip
last_ts = 582.900818
last_received_ts = 582.900452
last_seq = 610
current_ts = 582.917480
current_received_ts = 582.917175
current_seq = 611
count = 107
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 61us
(kms_flip:3297) DEBUG: name = flip
last_ts = 582.917480
last_received_ts = 582.917175
last_seq = 611
current_ts = 582.934143
current_received_ts = 582.933899
current_seq = 612
count = 108
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 60us
(kms_flip:3297) DEBUG: name = flip
last_ts = 582.934143
last_received_ts = 582.933899
last_seq = 612
current_ts = 582.950867
current_received_ts = 582.950500
current_seq = 613
count = 109
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 61us
(kms_flip:3297) DEBUG: name = flip
last_ts = 582.950867
last_received_ts = 582.950500
last_seq = 613
current_ts = 582.967529
current_received_ts = 582.967163
current_seq = 614
count = 110
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 61us
(kms_flip:3297) DEBUG: name = flip
last_ts = 582.967529
last_received_ts = 582.967163
last_seq = 614
current_ts = 582.984192
current_received_ts = 582.983887
current_seq = 615
count = 111
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 107us
(kms_flip:3297) DEBUG: name = flip
last_ts = 582.984192
last_received_ts = 582.983887
last_seq = 615
current_ts = 583.000854
current_received_ts = 583.000488
current_seq = 616
count = 112
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 57us
(kms_flip:3297) DEBUG: name = flip
last_ts = 583.000854
last_received_ts = 583.000488
last_seq = 616
current_ts = 583.017517
current_received_ts = 583.017212
current_seq = 617
count = 113
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 60us
(kms_flip:3297) DEBUG: name = flip
last_ts = 583.017517
last_received_ts = 583.017212
last_seq = 617
current_ts = 583.034180
current_received_ts = 583.033936
current_seq = 618
count = 114
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 97us
(kms_flip:3297) DEBUG: name = flip
last_ts = 583.034180
last_received_ts = 583.033936
last_seq = 618
current_ts = 583.050903
current_received_ts = 583.050476
current_seq = 619
count = 115
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 61us
(kms_flip:3297) DEBUG: name = flip
last_ts = 583.050903
last_received_ts = 583.050476
last_seq = 619
current_ts = 583.067566
current_received_ts = 583.067322
current_seq = 620
count = 116
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 60us
(kms_flip:3297) DEBUG: name = flip
last_ts = 583.067566
last_received_ts = 583.067322
last_seq = 620
current_ts = 583.084229
current_received_ts = 583.083923
current_seq = 621
count = 117
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 101us
(kms_flip:3297) DEBUG: name = flip
last_ts = 583.084229
last_received_ts = 583.083923
last_seq = 621
current_ts = 583.100891
current_received_ts = 583.100586
current_seq = 622
count = 118
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 61us
(kms_flip:3297) DEBUG: name = flip
last_ts = 583.100891
last_received_ts = 583.100586
last_seq = 622
current_ts = 583.117554
current_received_ts = 583.117310
current_seq = 623
count = 119
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 58us
(kms_flip:3297) DEBUG: name = flip
last_ts = 583.117554
last_received_ts = 583.117310
last_seq = 623
current_ts = 583.134216
current_received_ts = 583.133911
current_seq = 624
count = 120
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 105us
(kms_flip:3297) DEBUG: name = flip
last_ts = 583.134216
last_received_ts = 583.133911
last_seq = 624
current_ts = 583.150879
current_received_ts = 583.150635
current_seq = 625
count = 121
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 61us
(kms_flip:3297) DEBUG: name = flip
last_ts = 583.150879
last_received_ts = 583.150635
last_seq = 625
current_ts = 583.167603
current_received_ts = 583.167236
current_seq = 626
count = 122
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 60us
(kms_flip:3297) DEBUG: name = flip
last_ts = 583.167603
last_received_ts = 583.167236
last_seq = 626
current_ts = 583.184265
current_received_ts = 583.183960
current_seq = 627
count = 123
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 63us
(kms_flip:3297) DEBUG: name = flip
last_ts = 583.184265
last_received_ts = 583.183960
last_seq = 627
current_ts = 583.200928
current_received_ts = 583.200500
current_seq = 628
count = 124
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 60us
(kms_flip:3297) DEBUG: name = flip
last_ts = 583.200928
last_received_ts = 583.200500
last_seq = 628
current_ts = 583.217590
current_received_ts = 583.217346
current_seq = 629
count = 125
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 60us
(kms_flip:3297) DEBUG: name = flip
last_ts = 583.217590
last_received_ts = 583.217346
last_seq = 629
current_ts = 583.234253
current_received_ts = 583.234009
current_seq = 630
count = 126
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 61us
(kms_flip:3297) DEBUG: name = flip
last_ts = 583.234253
last_received_ts = 583.234009
last_seq = 630
current_ts = 583.250916
current_received_ts = 583.250610
current_seq = 631
count = 127
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 60us
(kms_flip:3297) DEBUG: name = flip
last_ts = 583.250916
last_received_ts = 583.250610
last_seq = 631
current_ts = 583.267639
current_received_ts = 583.267273
current_seq = 632
count = 128
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 61us
(kms_flip:3297) DEBUG: name = flip
last_ts = 583.267639
last_received_ts = 583.267273
last_seq = 632
current_ts = 583.284302
current_received_ts = 583.283936
current_seq = 633
count = 129
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 97us
(kms_flip:3297) DEBUG: name = flip
last_ts = 583.284302
last_received_ts = 583.283936
last_seq = 633
current_ts = 583.300964
current_received_ts = 583.300659
current_seq = 634
count = 130
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 61us
(kms_flip:3297) DEBUG: name = flip
last_ts = 583.300964
last_received_ts = 583.300659
last_seq = 634
current_ts = 583.317627
current_received_ts = 583.317383
current_seq = 635
count = 131
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 60us
(kms_flip:3297) DEBUG: name = flip
last_ts = 583.317627
last_received_ts = 583.317383
last_seq = 635
current_ts = 583.334290
current_received_ts = 583.334045
current_seq = 636
count = 132
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 61us
(kms_flip:3297) DEBUG: name = flip
last_ts = 583.334290
last_received_ts = 583.334045
last_seq = 636
current_ts = 583.350952
current_received_ts = 583.350647
current_seq = 637
count = 133
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 60us
(kms_flip:3297) DEBUG: name = flip
last_ts = 583.350952
last_received_ts = 583.350647
last_seq = 637
current_ts = 583.367615
current_received_ts = 583.367371
current_seq = 638
count = 134
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 57us
(kms_flip:3297) DEBUG: name = flip
last_ts = 583.367615
last_received_ts = 583.367371
last_seq = 638
current_ts = 583.384338
current_received_ts = 583.384094
current_seq = 639
count = 135
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 61us
(kms_flip:3297) DEBUG: name = flip
last_ts = 583.384338
last_received_ts = 583.384094
last_seq = 639
current_ts = 583.401001
current_received_ts = 583.400696
current_seq = 640
count = 136
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 60us
(kms_flip:3297) DEBUG: name = flip
last_ts = 583.401001
last_received_ts = 583.400696
last_seq = 640
current_ts = 583.417664
current_received_ts = 583.417419
current_seq = 641
count = 137
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 58us
(kms_flip:3297) DEBUG: name = flip
last_ts = 583.417664
last_received_ts = 583.417419
last_seq = 641
current_ts = 583.434326
current_received_ts = 583.434082
current_seq = 642
count = 138
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 60us
(kms_flip:3297) DEBUG: name = flip
last_ts = 583.434326
last_received_ts = 583.434082
last_seq = 642
current_ts = 583.450989
current_received_ts = 583.450684
current_seq = 643
count = 139
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 60us
(kms_flip:3297) DEBUG: name = flip
last_ts = 583.450989
last_received_ts = 583.450684
last_seq = 643
current_ts = 583.467651
current_received_ts = 583.467407
current_seq = 644
count = 140
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 60us
(kms_flip:3297) DEBUG: name = flip
last_ts = 583.467651
last_received_ts = 583.467407
last_seq = 644
current_ts = 583.484314
current_received_ts = 583.484070
current_seq = 645
count = 141
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 57us
(kms_flip:3297) DEBUG: name = flip
last_ts = 583.484314
last_received_ts = 583.484070
last_seq = 645
current_ts = 583.500977
current_received_ts = 583.500671
current_seq = 646
count = 142
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 58us
(kms_flip:3297) DEBUG: name = flip
last_ts = 583.500977
last_received_ts = 583.500671
last_seq = 646
current_ts = 583.517700
current_received_ts = 583.517334
current_seq = 647
count = 143
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 57us
(kms_flip:3297) DEBUG: name = flip
last_ts = 583.517700
last_received_ts = 583.517334
last_seq = 647
current_ts = 583.534363
current_received_ts = 583.534119
current_seq = 648
count = 144
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 61us
(kms_flip:3297) DEBUG: name = flip
last_ts = 583.534363
last_received_ts = 583.534119
last_seq = 648
current_ts = 583.551025
current_received_ts = 583.550720
current_seq = 649
count = 145
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 60us
(kms_flip:3297) DEBUG: name = flip
last_ts = 583.551025
last_received_ts = 583.550720
last_seq = 649
current_ts = 583.567688
current_received_ts = 583.567383
current_seq = 650
count = 146
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 57us
(kms_flip:3297) DEBUG: name = flip
last_ts = 583.567688
last_received_ts = 583.567383
last_seq = 650
current_ts = 583.584351
current_received_ts = 583.584106
current_seq = 651
count = 147
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 61us
(kms_flip:3297) DEBUG: name = flip
last_ts = 583.584351
last_received_ts = 583.584106
last_seq = 651
current_ts = 583.601013
current_received_ts = 583.600769
current_seq = 652
count = 148
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 60us
(kms_flip:3297) DEBUG: name = flip
last_ts = 583.601013
last_received_ts = 583.600769
last_seq = 652
current_ts = 583.617737
current_received_ts = 583.617310
current_seq = 653
count = 149
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 60us
(kms_flip:3297) DEBUG: name = flip
last_ts = 583.617737
last_received_ts = 583.617310
last_seq = 653
current_ts = 583.634399
current_received_ts = 583.634094
current_seq = 654
count = 150
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 59us
(kms_flip:3297) DEBUG: name = flip
last_ts = 583.634399
last_received_ts = 583.634094
last_seq = 654
current_ts = 583.651062
current_received_ts = 583.650757
current_seq = 655
count = 151
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 60us
(kms_flip:3297) DEBUG: name = flip
last_ts = 583.651062
last_received_ts = 583.650757
last_seq = 655
current_ts = 583.667725
current_received_ts = 583.667358
current_seq = 656
count = 152
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 60us
(kms_flip:3297) DEBUG: name = flip
last_ts = 583.667725
last_received_ts = 583.667358
last_seq = 656
current_ts = 583.684387
current_received_ts = 583.684143
current_seq = 657
count = 153
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 60us
(kms_flip:3297) DEBUG: name = flip
last_ts = 583.684387
last_received_ts = 583.684143
last_seq = 657
current_ts = 583.701050
current_received_ts = 583.700806
current_seq = 658
count = 154
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 60us
(kms_flip:3297) DEBUG: name = flip
last_ts = 583.701050
last_received_ts = 583.700806
last_seq = 658
current_ts = 583.717773
current_received_ts = 583.717407
current_seq = 659
count = 155
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 57us
(kms_flip:3297) DEBUG: name = flip
last_ts = 583.717773
last_received_ts = 583.717407
last_seq = 659
current_ts = 583.734436
current_received_ts = 583.734131
current_seq = 660
count = 156
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 60us
(kms_flip:3297) DEBUG: name = flip
last_ts = 583.734436
last_received_ts = 583.734131
last_seq = 660
current_ts = 583.751099
current_received_ts = 583.750793
current_seq = 661
count = 157
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 59us
(kms_flip:3297) DEBUG: name = flip
last_ts = 583.751099
last_received_ts = 583.750793
last_seq = 661
current_ts = 583.767761
current_received_ts = 583.767334
current_seq = 662
count = 158
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 61us
(kms_flip:3297) DEBUG: name = flip
last_ts = 583.767761
last_received_ts = 583.767334
last_seq = 662
current_ts = 583.784424
current_received_ts = 583.784119
current_seq = 663
count = 159
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 61us
(kms_flip:3297) DEBUG: name = flip
last_ts = 583.784424
last_received_ts = 583.784119
last_seq = 663
current_ts = 583.801086
current_received_ts = 583.800842
current_seq = 664
count = 160
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 59us
(kms_flip:3297) DEBUG: name = flip
last_ts = 583.801086
last_received_ts = 583.800842
last_seq = 664
current_ts = 583.817810
current_received_ts = 583.817383
current_seq = 665
count = 161
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 60us
(kms_flip:3297) DEBUG: name = flip
last_ts = 583.817810
last_received_ts = 583.817383
last_seq = 665
current_ts = 583.834412
current_received_ts = 583.834167
current_seq = 666
count = 162
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 60us
(kms_flip:3297) DEBUG: name = flip
last_ts = 583.834412
last_received_ts = 583.834167
last_seq = 666
current_ts = 583.851135
current_received_ts = 583.850830
current_seq = 667
count = 163
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 60us
(kms_flip:3297) DEBUG: name = flip
last_ts = 583.851135
last_received_ts = 583.850830
last_seq = 667
current_ts = 583.867798
current_received_ts = 583.867371
current_seq = 668
count = 164
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 60us
(kms_flip:3297) DEBUG: name = flip
last_ts = 583.867798
last_received_ts = 583.867371
last_seq = 668
current_ts = 583.884460
current_received_ts = 583.884216
current_seq = 669
count = 165
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 57us
(kms_flip:3297) DEBUG: name = flip
last_ts = 583.884460
last_received_ts = 583.884216
last_seq = 669
current_ts = 583.901123
current_received_ts = 583.900818
current_seq = 670
count = 166
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 61us
(kms_flip:3297) DEBUG: name = flip
last_ts = 583.901123
last_received_ts = 583.900818
last_seq = 670
current_ts = 583.917786
current_received_ts = 583.917419
current_seq = 671
count = 167
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 57us
(kms_flip:3297) DEBUG: name = flip
last_ts = 583.917786
last_received_ts = 583.917419
last_seq = 671
current_ts = 583.934509
current_received_ts = 583.934204
current_seq = 672
count = 168
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 59us
(kms_flip:3297) DEBUG: name = flip
last_ts = 583.934509
last_received_ts = 583.934204
last_seq = 672
current_ts = 583.951172
current_received_ts = 583.950867
current_seq = 673
count = 169
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 57us
(kms_flip:3297) DEBUG: name = flip
last_ts = 583.951172
last_received_ts = 583.950867
last_seq = 673
current_ts = 583.967834
current_received_ts = 583.967407
current_seq = 674
count = 170
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 57us
(kms_flip:3297) DEBUG: name = flip
last_ts = 583.967834
last_received_ts = 583.967407
last_seq = 674
current_ts = 583.984497
current_received_ts = 583.984253
current_seq = 675
count = 171
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 60us
(kms_flip:3297) DEBUG: name = flip
last_ts = 583.984497
last_received_ts = 583.984253
last_seq = 675
current_ts = 584.001160
current_received_ts = 584.000793
current_seq = 676
count = 172
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 60us
(kms_flip:3297) DEBUG: name = flip
last_ts = 584.001160
last_received_ts = 584.000793
last_seq = 676
current_ts = 584.017822
current_received_ts = 584.017456
current_seq = 677
count = 173
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 58us
(kms_flip:3297) DEBUG: name = flip
last_ts = 584.017822
last_received_ts = 584.017456
last_seq = 677
current_ts = 584.034485
current_received_ts = 584.034241
current_seq = 678
count = 174
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 58us
(kms_flip:3297) DEBUG: name = flip
last_ts = 584.034485
last_received_ts = 584.034241
last_seq = 678
current_ts = 584.051147
current_received_ts = 584.050903
current_seq = 679
count = 175
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 60us
(kms_flip:3297) DEBUG: name = flip
last_ts = 584.051147
last_received_ts = 584.050903
last_seq = 679
current_ts = 584.067871
current_received_ts = 584.067505
current_seq = 680
count = 176
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 60us
(kms_flip:3297) DEBUG: name = flip
last_ts = 584.067871
last_received_ts = 584.067505
last_seq = 680
current_ts = 584.084534
current_received_ts = 584.084290
current_seq = 681
count = 177
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 61us
(kms_flip:3297) DEBUG: name = flip
last_ts = 584.084534
last_received_ts = 584.084290
last_seq = 681
current_ts = 584.101196
current_received_ts = 584.100891
current_seq = 682
count = 178
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 89us
(kms_flip:3297) DEBUG: name = flip
last_ts = 584.101196
last_received_ts = 584.100891
last_seq = 682
current_ts = 584.117859
current_received_ts = 584.117432
current_seq = 683
count = 179
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 61us
(kms_flip:3297) DEBUG: name = flip
last_ts = 584.117859
last_received_ts = 584.117432
last_seq = 683
current_ts = 584.134521
current_received_ts = 584.134155
current_seq = 684
count = 180
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 58us
(kms_flip:3297) DEBUG: name = flip
last_ts = 584.134521
last_received_ts = 584.134155
last_seq = 684
current_ts = 584.151184
current_received_ts = 584.150940
current_seq = 685
count = 181
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 95us
(kms_flip:3297) DEBUG: name = flip
last_ts = 584.151184
last_received_ts = 584.150940
last_seq = 685
current_ts = 584.167847
current_received_ts = 584.167480
current_seq = 686
count = 182
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 61us
(kms_flip:3297) DEBUG: name = flip
last_ts = 584.167847
last_received_ts = 584.167480
last_seq = 686
current_ts = 584.184570
current_received_ts = 584.184204
current_seq = 687
count = 183
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 60us
(kms_flip:3297) DEBUG: name = flip
last_ts = 584.184570
last_received_ts = 584.184204
last_seq = 687
current_ts = 584.201233
current_received_ts = 584.200928
current_seq = 688
count = 184
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 104us
(kms_flip:3297) DEBUG: name = flip
last_ts = 584.201233
last_received_ts = 584.200928
last_seq = 688
current_ts = 584.217896
current_received_ts = 584.217529
current_seq = 689
count = 185
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 60us
(kms_flip:3297) DEBUG: name = flip
last_ts = 584.217896
last_received_ts = 584.217529
last_seq = 689
current_ts = 584.234558
current_received_ts = 584.234192
current_seq = 690
count = 186
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 60us
(kms_flip:3297) DEBUG: name = flip
last_ts = 584.234558
last_received_ts = 584.234192
last_seq = 690
current_ts = 584.251221
current_received_ts = 584.250977
current_seq = 691
count = 187
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 62us
(kms_flip:3297) DEBUG: name = flip
last_ts = 584.251221
last_received_ts = 584.250977
last_seq = 691
current_ts = 584.267883
current_received_ts = 584.267578
current_seq = 692
count = 188
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 60us
(kms_flip:3297) DEBUG: name = flip
last_ts = 584.267883
last_received_ts = 584.267578
last_seq = 692
current_ts = 584.284607
current_received_ts = 584.284363
current_seq = 693
count = 189
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 60us
(kms_flip:3297) DEBUG: name = flip
last_ts = 584.284607
last_received_ts = 584.284363
last_seq = 693
current_ts = 584.301270
current_received_ts = 584.300903
current_seq = 694
count = 190
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 94us
(kms_flip:3297) DEBUG: name = flip
last_ts = 584.301270
last_received_ts = 584.300903
last_seq = 694
current_ts = 584.317932
current_received_ts = 584.317627
current_seq = 695
count = 191
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 60us
(kms_flip:3297) DEBUG: name = flip
last_ts = 584.317932
last_received_ts = 584.317627
last_seq = 695
current_ts = 584.334595
current_received_ts = 584.334351
current_seq = 696
count = 192
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 61us
(kms_flip:3297) DEBUG: name = flip
last_ts = 584.334595
last_received_ts = 584.334351
last_seq = 696
current_ts = 584.351257
current_received_ts = 584.351013
current_seq = 697
count = 193
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 62us
(kms_flip:3297) DEBUG: name = flip
last_ts = 584.351257
last_received_ts = 584.351013
last_seq = 697
current_ts = 584.367920
current_received_ts = 584.367554
current_seq = 698
count = 194
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 59us
(kms_flip:3297) DEBUG: name = flip
last_ts = 584.367920
last_received_ts = 584.367554
last_seq = 698
current_ts = 584.384644
current_received_ts = 584.384277
current_seq = 699
count = 195
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 58us
(kms_flip:3297) DEBUG: name = flip
last_ts = 584.384644
last_received_ts = 584.384277
last_seq = 699
current_ts = 584.401306
current_received_ts = 584.401062
current_seq = 700
count = 196
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 62us
(kms_flip:3297) DEBUG: name = flip
last_ts = 584.401306
last_received_ts = 584.401062
last_seq = 700
current_ts = 584.417969
current_received_ts = 584.417603
current_seq = 701
count = 197
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 61us
(kms_flip:3297) DEBUG: name = flip
last_ts = 584.417969
last_received_ts = 584.417603
last_seq = 701
current_ts = 584.434631
current_received_ts = 584.434204
current_seq = 702
count = 198
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 60us
(kms_flip:3297) DEBUG: name = flip
last_ts = 584.434631
last_received_ts = 584.434204
last_seq = 702
current_ts = 584.451294
current_received_ts = 584.450867
current_seq = 703
count = 199
seq_step = 1
(kms_flip:3297) DEBUG: Vblank took 1433us
(kms_flip:3297) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:930:
(kms_flip:3297) CRITICAL: Failed assertion: end - start < 500
(kms_flip:3297) igt_core-INFO: Stack trace:
(kms_flip:3297) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:3297) igt_core-INFO:   #1 ../tests/kms_flip.c:925 run_test_on_crtc_set.constprop.0()
(kms_flip:3297) igt_core-INFO:   #2 ../tests/kms_flip.c:1891 run_test()
(kms_flip:3297) igt_core-INFO:   #3 ../tests/kms_flip.c:2126 __igt_unique____real_main2047()
(kms_flip:3297) igt_core-INFO:   #4 ../tests/kms_flip.c:2047 main()
(kms_flip:3297) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:3297) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:3297) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest D-HDMI-A3: FAIL (3.757s)
Subtest flip-vs-expired-vblank: FAIL (26.961s)
(kms_flip:3297) igt_kms-CRITICAL: Test assertion failure function kmstest_set_connector_dpms, file ../lib/igt_kms.c:2352:
(kms_flip:3297) igt_kms-CRITICAL: Failed assertion: found_it
(kms_flip:3297) igt_kms-CRITICAL: Last errno: 9, Bad file descriptor
(kms_flip:3297) igt_kms-CRITICAL: DPMS property not found on 556
Dmesg
<6> [580.401613] [IGT] kms_flip: starting dynamic subtest D-HDMI-A3
<7> [580.402019] i915 0000:03:00.0: [drm:drm_mode_addfb2] [FB:562]
<7> [580.402172] i915 0000:03:00.0: [drm:drm_mode_addfb2] [FB:563]
<7> [580.402583] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [580.403152] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [580.424838] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [580.425302] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [580.431729] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [580.432158] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [580.452530] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [580.452885] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [580.479087] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:151:pipe A]
<7> [580.479238] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:269:pipe B]
<7> [580.479354] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:387:pipe C]
<7> [580.479435] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.enable (expected yes, found no)
<7> [580.479669] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.active (expected yes, found no)
<7> [580.479877] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in cpu_transcoder (expected 2, found -1)
<7> [580.480094] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in lane_count (expected 4, found 0)
<7> [580.480335] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [580.480541] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [580.480746] i915 0000:03:00.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> [580.480989] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [580.481196] i915 0000:03:00.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> [580.481402] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [580.481606] i915 0000:03:00.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> [580.481811] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [580.482060] i915 0000:03:00.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> [580.482270] i915 0000:03:00.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> [580.482475] i915 0000:03:00.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> [580.482680] i915 0000:03:00.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> [580.482886] i915 0000:03:00.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> [580.483197] i915 0000:03:00.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> [580.483404] i915 0000:03:00.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> [580.483612] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [580.483819] i915 0000:03:00.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> [580.484030] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [580.484236] i915 0000:03:00.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> [580.484442] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [580.484647] i915 0000:03:00.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> [580.484853] i915 0000:03:00.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> [580.485098] i915 0000:03:00.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> [580.485303] i915 0000:03:00.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> [580.485509] i915 0000:03:00.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> [580.485715] i915 0000:03:00.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> [580.485945] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [580.486165] i915 0000:03:00.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> [580.486371] i915 0000:03:00.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> [580.486578] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [580.486783] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [580.487027] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [580.487233] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in port_clock (expected 148500, found 0)
<7> [580.487440] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:387:pipe C] fastset requirement not met, forcing full modeset
<7> [580.487666] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0xd -> 0x1 (total dbuf slices 0xf), mbus joined? no->no
<7> [580.487864] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [CRTC:387:pipe C] dbuf slices 0xc -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x4 -> 0x0
<7> [580.488123] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:381:cursor C] ddb (1963 - 2048) -> ( 0 - 0), size 85 -> 0
<7> [580.488351] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:387:pipe C] min cdclk: 74250 kHz -> 0 kHz
<7> [580.488558] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 163200 kHz, actual 163200 kHz
<7> [580.488762] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [580.489014] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [CRTC:387:pipe C] enable: no [modeset]
<7> [580.489219] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:271:plane 1C] fb: [NOFB], visible: no
<7> [580.489425] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:301:plane 2C] fb: [NOFB], visible: no
<7> [580.489630] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:331:plane 3C] fb: [NOFB], visible: no
<7> [580.489834] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:361:plane 4C] fb: [NOFB], visible: no
<7> [580.490075] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:371:plane 5C] fb: [NOFB], visible: no
<7> [580.490279] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:381:cursor C] fb: [NOFB], visible: no
<7> [580.490601] i915 0000:03:00.0: [drm:intel_disable_transcoder [i915]] disabling pipe C
<7> [580.500143] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_D
<7> [580.500420] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:507:DDI A/PHY A]
<7> [580.500622] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:509:DP-MST A]
<7> [580.500822] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:510:DP-MST B]
<7> [580.501070] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:511:DP-MST C]
<7> [580.501270] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:512:DP-MST D]
<7> [580.501470] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:520:DDI B/PHY B]
<7> [580.501668] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:522:DP-MST A]
<7> [580.501868] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:523:DP-MST B]
<7> [580.502113] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:524:DP-MST C]
<7> [580.502312] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:525:DP-MST D]
<7> [580.502512] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:535:DDI C/PHY C]
<7> [580.502711] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:537:DP-MST A]
<7> [580.502938] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:538:DP-MST B]
<7> [580.503152] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:539:DP-MST C]
<7> [580.503353] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:540:DP-MST D]
<7> [580.503551] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:547:DDI D/PHY D]
<7> [580.503769] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:549:DP-MST A]
<7> [580.504012] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:550:DP-MST B]
<7> [580.504212] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:551:DP-MST C]
<7> [580.504411] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:552:DP-MST D]
<7> [580.504610] i915 0000:03:00.0: [drm:verify_connector_state [i915]] [CONNECTOR:556:HDMI-A-3]
<7> [580.504848] i915 0000:03:00.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [580.505201] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling PW_C
<7> [580.505468] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling PW_2
<7> [580.505718] i915 0000:03:00.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:387:pipe C]
<7> [580.506169] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:505:pipe D]
<7> [580.506335] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:505:pipe D]
<7> [580.506366] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CONNECTOR:556:HDMI-A-3]
<7> [580.506440] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CONNECTOR:556:HDMI-A-3] Limiting target display pipe bpp to 24 (EDID bpp 0, max requested bpp 36, max platform bpp 36)
<7> [580.506656] i915 0000:03:00.0: [drm:intel_hdmi_compute_clock [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [580.506843] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:505:pipe D] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [580.507094] i915 0000:03:00.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:547:DDI D/PHY D] [CRTC:505:pipe D]
<7> [580.507286] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.enable (expected no, found yes)
<7> [580.507495] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.active (expected no, found yes)
<7> [580.507701] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in cpu_transcoder (expected -1, found 3)
<7> [580.507913] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in lane_count (expected 0, found 4)
<7> [580.508155] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [580.508361] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [580.508568] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [580.508774] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [580.509027] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [580.509234] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [580.509438] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [580.509643] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [580.509850] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [580.510131] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [580.510336] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [580.510542] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [580.510747] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [580.510991] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [580.511196] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [580.511402] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [580.511608] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [580.511814] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [580.512056] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [580.512262] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [580.512467] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [580.512672] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [580.512877] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [580.513159] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [580.513366] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [580.513572] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [580.513776] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [580.514020] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [580.514226] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [580.514432] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [580.514636] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [580.514842] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [580.515085] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in port_clock (expected 0, found 148500)
<7> [580.515291] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:505:pipe D] fastset requirement not met, forcing full modeset
<7> [580.515523] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0xd (total dbuf slices 0xf), mbus joined? no->no
<7> [580.515718] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [CRTC:505:pipe D] dbuf slices 0x0 -> 0xc, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x8
<7> [580.515980] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:389:plane 1D] ddb ( 0 - 0) -> ( 0 - 1963), size 0 -> 1963
<7> [580.516176] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:499:cursor D] ddb ( 0 - 0) -> (1963 - 2048), size 0 -> 85
<7> [580.516371] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:389:plane 1D] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm
<7> [580.516566] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:389:plane 1D] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 4, 4, 4, 5, 9, 11, 17, 25, 0, 0, 0
<7> [580.516761] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:389:plane 1D] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 62, 62, 62, 78, 139, 169, 261, 383, 137, 0, 0
<7> [580.516995] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:389:plane 1D] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 123, 123, 123, 184, 245, 245, 367, 489, 138, 0, 0
<7> [580.517207] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] [CRTC:505:pipe D] data rate 594000 num active planes 1
<7> [580.517412] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 50000 required 624
<7> [580.517616] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [580.517835] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:505:pipe D] min cdclk: 0 kHz -> 74250 kHz
<7> [580.518094] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 163200 kHz, actual 163200 kHz
<7> [580.518300] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [580.518506] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [CRTC:505:pipe D] enable: yes [modeset]
<7> [580.518710] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [580.518954] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: D, pipe bpp: 24, dithering: 0
<7> [580.519159] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [580.519361] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [580.519566] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [580.519770] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [580.520012] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [580.520217] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [580.520420] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [580.520625] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: yes, vmin: 1125, vmax: 1125, flipline: 1125, pipeline full: 0, guardband: 45 vsync start: 41, vsync end: 36
<7> [580.520829] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 1080, vmax vblank: 1080, vmin vtotal: 1125, vmax vtotal: 1125
<7> [580.521071] i915 0000:03:00.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> [580.521276] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
<7> [580.521482] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
<7> [580.521688] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [580.521900] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [580.522137] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [580.522342] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] port clock: 148500, pipe src: 1920x1080+0+0, pixel rate 148500, min cdclk 74250
<7> [580.522546] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] linetime: 119, ips linetime: 0
<7> [580.522751] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [580.522997] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [580.523202] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [580.523408] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: dpll: 0x24414, dpll_md: 0x18000000, fp0: 0x8801ef8, fp1: 0x2b000440
<7> [580.523613] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [580.523817] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [580.524064] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [580.524092] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [580.524269] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [580.524349] i915 0000:03:00.0: [drm:gen9_enable_dc5 [i915]] Enabling DC5
<7> [580.524474] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [580.524561] i915 0000:03:00.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 01
<7> [580.524678] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [580.524882] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [580.525204] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [580.525407] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [580.525610] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [580.525814] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [580.526052] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [580.526256] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [580.526460] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:389:plane 1D] fb: [FB:562] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [580.526666] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [580.526871] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [580.527115] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:419:plane 2D] fb: [NOFB], visible: no
<7> [580.527320] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:449:plane 3D] fb: [NOFB], visible: no
<7> [580.527525] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:479:plane 4D] fb: [NOFB], visible: no
<7> [580.527728] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:489:plane 5D] fb: [NOFB], visible: no
<7> [580.527971] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:499:cursor D] fb: [NOFB], visible: no
<7> [580.528589] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [580.528945] i915 0000:03:00.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 01 to 00
<7> [580.529382] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling PW_2
<7> [580.529612] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling PW_D
<7> [580.529869] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:507:DDI A/PHY A]
<7> [580.530139] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:509:DP-MST A]
<7> [580.530339] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:510:DP-MST B]
<7> [580.530540] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:511:DP-MST C]
<7> [580.530739] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:512:DP-MST D]
<7> [580.530982] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:520:DDI B/PHY B]
<7> [580.531183] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:522:DP-MST A]
<7> [580.531382] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:523:DP-MST B]
<7> [580.531581] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:524:DP-MST C]
<7> [580.531782] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:525:DP-MST D]
<7> [580.532017] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:535:DDI C/PHY C]
<7> [580.532217] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:537:DP-MST A]
<7> [580.532416] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:538:DP-MST B]
<7> [580.532616] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:539:DP-MST C]
<7> [580.532815] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:540:DP-MST D]
<7> [580.533053] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:547:DDI D/PHY D]
<7> [580.533252] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:549:DP-MST A]
<7> [580.533451] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:550:DP-MST B]
<7> [580.533651] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:551:DP-MST C]
<7> [580.533849] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:552:DP-MST D]
<7> [580.534098] i915 0000:03:00.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0xd
<7> [580.534503] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_D
<7> [580.534837] i915 0000:03:00.0: [drm:intel_enable_transcoder [i915]] enabling pipe D
<7> [580.554140] i915 0000:03:00.0: [drm:verify_connector_state [i915]] [CONNECTOR:556:HDMI-A-3]
<7> [580.554481] i915 0000:03:00.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:505:pipe D]
<6> [584.160410] [IGT] kms_flip: finished subtest D-HDMI-A3, FAIL
<6> [584.163249] [IGT] kms_flip: finished subtest flip-vs-expired-vblank, FAIL
<7> [584.190169] i915 0000:03:00.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:563] from all active usage due to RMFB ioctl
<7> [584.190519] i915 0000:03:00.0: [drm:atomic_remove_fb] Disabling [PLANE:389:plane 1D] because [FB:563] is removed
<7> [584.190567] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:389:plane 1D] ddb ( 0 - 1963) -> ( 0 - 0), size 1963 -> 0
<7> [584.190800] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:389:plane 1D] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [584.191049] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:389:plane 1D] lines 4, 4, 4, 5, 9, 11, 17, 25, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [584.191325] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:389:plane 1D] blocks 62, 62, 62, 78, 139, 169, 261, 383, 137, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [584.191517] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:389:plane 1D] min_ddb 123, 123, 123, 184, 245, 245, 367, 489, 138, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [584.191736] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] [CRTC:505:pipe D] data rate 0 num active planes 0
<7> [584.192013] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 50000 required 0
<6> [584.204917] [IGT] kms_flip: exiting, ret=98
<7> [584.217605] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CONNECTOR:556:HDMI-A-3] Limiting target display pipe bpp to 24 (EDID bpp 0, max requested bpp 36, max platform bpp 36)
<7> [584.217854] i915 0000:03:00.0: [drm:intel_hdmi_compute_clock [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [584.218098] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:151:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [584.218348] i915 0000:03:00.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:547:DDI D/PHY D] [CRTC:151:pipe A]
<7> [584.218537] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [584.218758] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [584.219014] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [584.219273] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [584.219492] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [584.219735] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [584.220140] i915 0000:03:00.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> [584.220361] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [584.220568] i915 0000:03:00.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> [584.220774] i915 0000:03:00.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 2200)
<7> [584.221088] i915 0000:03:00.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> [584.221296] i915 0000:03:00.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 2052)
<7> [584.221501] i915 0000:03:00.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> [584.221707] i915 0000:03:00.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> [584.221956] i915 0000:03:00.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> [584.222164] i915 0000:03:00.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> [584.222370] i915 0000:03:00.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> [584.222576] i915 0000:03:00.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> [584.222782] i915 0000:03:00.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> [584.223114] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [584.223321] i915 0000:03:00.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> [584.223529] i915 0000:03:00.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 2200)
<7> [584.223735] i915 0000:03:00.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> [584.224676] i915 0000:03:00.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 2052)
<7> [584.224925] i915 0000:03:00.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> [584.225162] i915 0000:03:00.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> [584.225367] i915 0000:03:00.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> [584.225573] i915 0000:03:00.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> [584.225779] i915 0000:03:00.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> [584.226057] i915 0000:03:00.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> [584.226263] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [584.226470] i915 0000:03:00.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> [584.226676] i915 0000:03:00.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> [584.226906] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [584.227155] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [584.227361] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [584.227567] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in port_clock (expected 0, found 148500)
<7> [584.227773] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:151:pipe A] fastset requirement not met, forcing full modeset
<7> [584.228013] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.enable (expected yes, found no)
<7> [584.228219] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.active (expected yes, found no)
<7> [584.228425] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in cpu_transcoder (expected 3, found -1)
<7> [584.228631] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in lane_count (expected 4, found 0)
<7> [584.228836] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [584.229078] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [584.229283] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [584.229490] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [584.229696] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [584.229937] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [584.230143] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [584.230349] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [584.230554] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [584.230760] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [584.231044] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [584.231250] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [584.231455] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [584.231661] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [584.231873] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [584.232111] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [584.232317] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [584.232524] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [584.232730] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [584.232971] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [584.233177] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [584.233383] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [584.233589] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [584.233794] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [584.234116] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [584.234323] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [584.234530] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [584.234735] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [584.235014] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [584.235221] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [584.235428] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [584.235633] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [584.235840] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in port_clock (expected 148500, found 0)
<7> [584.236086] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:505:pipe D] fastset requirement not met, forcing full modeset
<7> [584.236328] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0xd -> 0x3 (total dbuf slices 0xf), mbus joined? no->no
<7> [584.236527] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [CRTC:151:pipe A] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x8 -> 0x1
<7> [584.236724] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [CRTC:505:pipe D] dbuf slices 0xc -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x8 -> 0x1
<7> [584.236962] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:35:plane 1A] ddb ( 0 - 0) -> ( 0 - 1963), size 0 -> 1963
<7> [584.237158] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:145:cursor A] ddb ( 0 - 0) -> (1963 - 2048), size 0 -> 85
<7> [584.237354] i915 0000:03:00.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> [584.237549] i915 0000:03:00.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, 3, 4, 5, 9, 11, 17, 25, 0, 0, 0
<7> [584.237744] i915 0000:03:00.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, 49, 65, 81, 145, 177, 273, 401, 30, 0, 0
<7> [584.237975] i915 0000:03:00.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, 55, 73, 91, 161, 196, 302, 443, 31, 0, 0
<7> [584.238172] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:499:cursor D] ddb (1963 - 2048) -> ( 0 - 0), size 85 -> 0
<7> [584.238386] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] [CRTC:151:pipe A] data rate 594000 num active planes 1
<7> [584.238593] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 50000 required 624
<7> [584.238796] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [584.239051] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:151:pipe A] min cdclk: 0 kHz -> 74250 kHz
<7> [584.239257] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:505:pipe D] min cdclk: 74250 kHz -> 0 kHz
<7> [584.239482] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [CRTC:151:pipe A] enable: yes [modeset]
<7> [584.239688] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [584.239929] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [584.240133] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [584.240338] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [584.240543] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [584.240748] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [584.240990] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [584.241195] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [584.241399] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [584.241604] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: yes, vmin: 1125, vmax: 1125, flipline: 1125, pipeline full: 0, guardband: 45 vsync start: 41, vsync end: 36
<7> [584.241810] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 1080, vmax vblank: 1080, vmin vtotal: 1125, vmax vtotal: 1125
<7> [584.242059] i915 0000:03:00.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> [584.242264] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
<7> [584.242470] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
<7> [584.242675] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [584.242918] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [584.243124] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [584.243330] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] port clock: 148500, pipe src: 1920x1080+0+0, pixel rate 148500, min cdclk 74250
<7> [584.243535] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] linetime: 119, ips linetime: 0
<7> [584.243740] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [584.243983] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [584.244189] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [584.244394] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: dpll: 0x24414, dpll_md: 0x18000000, fp0: 0x8801ef8, fp1: 0x2b000440
<7> [584.244599] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [584.244805] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [584.245050] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [584.245255] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [584.245460] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [584.245666] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [584.245892] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [584.246110] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [584.246315] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [584.246520] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [584.246723] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [584.246961] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [584.247166] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [584.247371] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:plane 1A] fb: [FB:559] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [584.247577] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [584.247782] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [584.248026] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [584.248231] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [584.248436] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [584.248641] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [584.248845] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:145:cursor A] fb: [NOFB], visible: no
<7> [584.249085] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [CRTC:505:pipe D] enable: no [modeset]
<7> [584.249289] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:389:plane 1D] fb: [NOFB], visible: no
<7> [584.249495] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:419:plane 2D] fb: [NOFB], visible: no
<7> [584.249699] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:449:plane 3D] fb: [NOFB], visible: no
<7> [584.249937] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:479:plane 4D] fb: [NOFB], visible: no
<7> [584.250141] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:489:plane 5D] fb: [NOFB], visible: no
<7> [584.250345] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:499:cursor D] fb: [NOFB], visible: no
<7> [584.250666] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling PW_A
<7> [584.251087] i915 0000:03:00.0: [drm:intel_disable_transcoder [i915]] disabling pipe D
<7> [584.253976] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_D
<7> [584.254254] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:507:DDI A/PHY A]
<7> [584.254458] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:509:DP-MST A]
<7> [584.254658] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:510:DP-MST B]
<7> [584.254868] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:511:DP-MST C]
<7> [584.255101] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:512:DP-MST D]
<7> [584.255302] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:520:DDI B/PHY B]
<7> [584.255502] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:522:DP-MST A]
<7> [584.255702] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:523:DP-MST B]
<7> [584.255940] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:524:DP-MST C]
<7> [584.256139] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:525:DP-MST D]
<7> [584.256339] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:535:DDI C/PHY C]
<7> [584.256538] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:537:DP-MST A]
<7> [584.256738] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:538:DP-MST B]
<7> [584.257006] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:539:DP-MST C]
<7> [584.257206] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:540:DP-MST D]
<7> [584.257406] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:547:DDI D/PHY D]
<7> [584.257605] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:549:DP-MST A]
<7> [584.257805] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:550:DP-MST B]
<7> [584.258042] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:551:DP-MST C]
<7> [584.258242] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:552:DP-MST D]
<7> [584.258447] i915 0000:03:00.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0xf
<7> [584.258887] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_D
<7> [584.259239] i915 0000:03:00.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [584.261761] i915 0000:03:00.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1
<7> [584.262018] i915 0000:03:00.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:35:plane 1A]
<7> [584.277975] i915 0000:03:00.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [584.278613] i915 0000:03:00.0: [drm:verify_connector_state [i915]] [CONNECTOR:556:HDMI-A-3]
<7> [584.278979] i915 0000:03:00.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:151:pipe A]
<7> [584.279987] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling PW_D
<7> [584.280280] i915 0000:03:00.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:505:pipe D]
<7> [584.280844] i915 0000:03:00.0: [drm:drm_client_dev_restore] fbdev: ret=0
Created at 2026-03-30 04:52:53