Results for igt@kms_flip@2x-plain-flip-ts-check@bc-hdmi-a1-hdmi-a2

Machine description: shard-glk7

Result: Fail

integration-manifest git-log-oneline i915_display_info13 igt_runner13 run13 runtimes13 results13.json i915_display_info_post_exec13 boot13 dmesg13

DetailValue
Duration 9.12 seconds
Hostname
shard-glk7
Igt-Version
IGT-Version: 1.29-NO-GIT (x86_64) (Linux: 6.12.0-rc3-CI_DRM_15538-g01c7b2c084e5+ x86_64)
Out
Starting dynamic subtest: BC-HDMI-A1-HDMI-A2
  1920x1080: 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9 
  1920x1080: 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9 
Expected frametime: 16667us; measured 16532.8us +- 4.754us accuracy 0.09%
vblank interval differs from modeline! expected 16666.7us, measured 16533us +- 4.754us, difference 133.9us (28.2 sigma)
Stack trace:
  #0 ../lib/igt_core.c:2051 __igt_fail_assert()
  #1 ../tests/kms_flip.c:791 run_test_on_crtc_set.constprop.0()
  #2 ../tests/kms_flip.c:1930 run_pair()
  #3 ../tests/kms_flip.c:2087 __igt_unique____real_main2001()
  #4 ../tests/kms_flip.c:2001 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest BC-HDMI-A1-HDMI-A2: FAIL (9.123s)
Subtest 2x-plain-flip-ts-check: FAIL (20.221s)
Stack trace:
  #0 ../lib/igt_core.c:2051 __igt_fail_assert()
  #1 ../lib/igt_kms.c:2234 kmstest_set_connector_dpms()
  #2 ../lib/igt_core.c:2930 igt_atexit_handler()
  #3 [erand48_r+0x1d6]
  #4 [exit+0x1e]
  #5 ../lib/igt_core.c:2386 igt_exit()
  #6 [main+0x3f]
  #7 [__libc_init_first+0x8a]
  #8 [__libc_start_main+0x8b]
  #9 [_start+0x25]
Err
Starting dynamic subtest: BC-HDMI-A1-HDMI-A2
(kms_flip:1365) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1674:
(kms_flip:1365) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon)
Dynamic subtest BC-HDMI-A1-HDMI-A2 failed.
**** DEBUG ****
(kms_flip:1365) DEBUG: flip ts/seq: last 62.054264/603, current 62.070839/604: elapsed=16575.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 62.070839
last_received_ts = 62.070461
last_seq = 604
current_ts = 62.087418
current_received_ts = 62.087265
current_seq = 605
count = 145
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 62.070839/604, current 62.087418/605: elapsed=16579.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 62.087418
last_received_ts = 62.087265
last_seq = 605
current_ts = 62.103992
current_received_ts = 62.103661
current_seq = 606
count = 146
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 62.087418/605, current 62.103992/606: elapsed=16575.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 62.103992
last_received_ts = 62.103661
last_seq = 606
current_ts = 62.120560
current_received_ts = 62.120335
current_seq = 607
count = 147
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 62.103992/606, current 62.120560/607: elapsed=16567.0us expected=16532.8us +- 82.7us, error 0.2%
(kms_flip:1365) DEBUG: name = flip
last_ts = 62.120560
last_received_ts = 62.120335
last_seq = 607
current_ts = 62.137142
current_received_ts = 62.136967
current_seq = 608
count = 148
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 62.120560/607, current 62.137142/608: elapsed=16581.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 62.137142
last_received_ts = 62.136967
last_seq = 608
current_ts = 62.153717
current_received_ts = 62.153507
current_seq = 609
count = 149
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 62.137142/608, current 62.153717/609: elapsed=16576.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 62.153717
last_received_ts = 62.153507
last_seq = 609
current_ts = 62.170303
current_received_ts = 62.170082
current_seq = 610
count = 150
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 62.153717/609, current 62.170303/610: elapsed=16584.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 62.170303
last_received_ts = 62.170082
last_seq = 610
current_ts = 62.186878
current_received_ts = 62.186726
current_seq = 611
count = 151
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 62.170303/610, current 62.186878/611: elapsed=16576.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 62.186878
last_received_ts = 62.186726
last_seq = 611
current_ts = 62.203453
current_received_ts = 62.203354
current_seq = 612
count = 152
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 62.186878/611, current 62.203453/612: elapsed=16575.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 62.203453
last_received_ts = 62.203354
last_seq = 612
current_ts = 62.220032
current_received_ts = 62.219822
current_seq = 613
count = 153
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 62.203453/612, current 62.220032/613: elapsed=16579.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 62.220032
last_received_ts = 62.219822
last_seq = 613
current_ts = 62.236595
current_received_ts = 62.236496
current_seq = 614
count = 154
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 62.220032/613, current 62.236595/614: elapsed=16564.0us expected=16532.8us +- 82.7us, error 0.2%
(kms_flip:1365) DEBUG: name = flip
last_ts = 62.236595
last_received_ts = 62.236496
last_seq = 614
current_ts = 62.253197
current_received_ts = 62.253071
current_seq = 615
count = 155
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 62.236595/614, current 62.253197/615: elapsed=16600.0us expected=16532.8us +- 82.7us, error 0.4%
(kms_flip:1365) DEBUG: name = flip
last_ts = 62.253197
last_received_ts = 62.253071
last_seq = 615
current_ts = 62.269760
current_received_ts = 62.269424
current_seq = 616
count = 156
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 62.253197/615, current 62.269760/616: elapsed=16565.0us expected=16532.8us +- 82.7us, error 0.2%
(kms_flip:1365) DEBUG: name = flip
last_ts = 62.269760
last_received_ts = 62.269424
last_seq = 616
current_ts = 62.286331
current_received_ts = 62.286221
current_seq = 617
count = 157
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 62.269760/616, current 62.286331/617: elapsed=16572.0us expected=16532.8us +- 82.7us, error 0.2%
(kms_flip:1365) DEBUG: name = flip
last_ts = 62.286331
last_received_ts = 62.286221
last_seq = 617
current_ts = 62.302914
current_received_ts = 62.302830
current_seq = 618
count = 158
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 62.286331/617, current 62.302914/618: elapsed=16579.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 62.302914
last_received_ts = 62.302830
last_seq = 618
current_ts = 62.319481
current_received_ts = 62.319225
current_seq = 619
count = 159
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 62.302914/618, current 62.319481/619: elapsed=16570.0us expected=16532.8us +- 82.7us, error 0.2%
(kms_flip:1365) DEBUG: name = flip
last_ts = 62.319481
last_received_ts = 62.319225
last_seq = 619
current_ts = 62.336063
current_received_ts = 62.335987
current_seq = 620
count = 160
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 62.319481/619, current 62.336063/620: elapsed=16582.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 62.336063
last_received_ts = 62.335987
last_seq = 620
current_ts = 62.352646
current_received_ts = 62.352547
current_seq = 621
count = 161
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 62.336063/620, current 62.352646/621: elapsed=16580.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 62.352646
last_received_ts = 62.352547
last_seq = 621
current_ts = 62.369221
current_received_ts = 62.369167
current_seq = 622
count = 162
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 62.352646/621, current 62.369221/622: elapsed=16577.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 62.369221
last_received_ts = 62.369167
last_seq = 622
current_ts = 62.385799
current_received_ts = 62.385612
current_seq = 623
count = 163
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 62.369221/622, current 62.385799/623: elapsed=16577.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 62.385799
last_received_ts = 62.385612
last_seq = 623
current_ts = 62.402370
current_received_ts = 62.402210
current_seq = 624
count = 164
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 62.385799/623, current 62.402370/624: elapsed=16571.0us expected=16532.8us +- 82.7us, error 0.2%
(kms_flip:1365) DEBUG: name = flip
last_ts = 62.402370
last_received_ts = 62.402210
last_seq = 624
current_ts = 62.418941
current_received_ts = 62.418648
current_seq = 625
count = 165
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 62.402370/624, current 62.418941/625: elapsed=16572.0us expected=16532.8us +- 82.7us, error 0.2%
(kms_flip:1365) DEBUG: name = flip
last_ts = 62.418941
last_received_ts = 62.418648
last_seq = 625
current_ts = 62.435524
current_received_ts = 62.435406
current_seq = 626
count = 166
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 62.418941/625, current 62.435524/626: elapsed=16582.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 62.435524
last_received_ts = 62.435406
last_seq = 626
current_ts = 62.452103
current_received_ts = 62.451923
current_seq = 627
count = 167
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 62.435524/626, current 62.452103/627: elapsed=16579.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 62.452103
last_received_ts = 62.451923
last_seq = 627
current_ts = 62.468678
current_received_ts = 62.468510
current_seq = 628
count = 168
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 62.452103/627, current 62.468678/628: elapsed=16577.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 62.468678
last_received_ts = 62.468510
last_seq = 628
current_ts = 62.485245
current_received_ts = 62.485210
current_seq = 629
count = 169
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 62.468678/628, current 62.485245/629: elapsed=16566.0us expected=16532.8us +- 82.7us, error 0.2%
(kms_flip:1365) DEBUG: name = flip
last_ts = 62.485245
last_received_ts = 62.485210
last_seq = 629
current_ts = 62.501835
current_received_ts = 62.501663
current_seq = 630
count = 170
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 62.485245/629, current 62.501835/630: elapsed=16590.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 62.501835
last_received_ts = 62.501663
last_seq = 630
current_ts = 62.518402
current_received_ts = 62.518238
current_seq = 631
count = 171
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 62.501835/630, current 62.518402/631: elapsed=16566.0us expected=16532.8us +- 82.7us, error 0.2%
(kms_flip:1365) DEBUG: name = flip
last_ts = 62.518402
last_received_ts = 62.518238
last_seq = 631
current_ts = 62.534985
current_received_ts = 62.534882
current_seq = 632
count = 172
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 62.518402/631, current 62.534985/632: elapsed=16584.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 62.534985
last_received_ts = 62.534882
last_seq = 632
current_ts = 62.551559
current_received_ts = 62.551460
current_seq = 633
count = 173
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 62.534985/632, current 62.551559/633: elapsed=16574.0us expected=16532.8us +- 82.7us, error 0.2%
(kms_flip:1365) DEBUG: name = flip
last_ts = 62.551559
last_received_ts = 62.551460
last_seq = 633
current_ts = 62.568142
current_received_ts = 62.568138
current_seq = 634
count = 174
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 62.551559/633, current 62.568142/634: elapsed=16582.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 62.568142
last_received_ts = 62.568138
last_seq = 634
current_ts = 62.584705
current_received_ts = 62.584637
current_seq = 635
count = 175
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 62.568142/634, current 62.584705/635: elapsed=16564.0us expected=16532.8us +- 82.7us, error 0.2%
(kms_flip:1365) DEBUG: name = flip
last_ts = 62.584705
last_received_ts = 62.584637
last_seq = 635
current_ts = 62.601288
current_received_ts = 62.601219
current_seq = 636
count = 176
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 62.584705/635, current 62.601288/636: elapsed=16583.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 62.601288
last_received_ts = 62.601219
last_seq = 636
current_ts = 62.617863
current_received_ts = 62.617695
current_seq = 637
count = 177
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 62.601288/636, current 62.617863/637: elapsed=16576.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 62.617863
last_received_ts = 62.617695
last_seq = 637
current_ts = 62.634441
current_received_ts = 62.634308
current_seq = 638
count = 178
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 62.617863/637, current 62.634441/638: elapsed=16576.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 62.634441
last_received_ts = 62.634308
last_seq = 638
current_ts = 62.651020
current_received_ts = 62.650913
current_seq = 639
count = 179
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 62.634441/638, current 62.651020/639: elapsed=16581.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 62.651020
last_received_ts = 62.650913
last_seq = 639
current_ts = 62.667599
current_received_ts = 62.667492
current_seq = 640
count = 180
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 62.651020/639, current 62.667599/640: elapsed=16577.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 62.667599
last_received_ts = 62.667492
last_seq = 640
current_ts = 62.684162
current_received_ts = 62.684055
current_seq = 641
count = 181
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 62.667599/640, current 62.684162/641: elapsed=16566.0us expected=16532.8us +- 82.7us, error 0.2%
(kms_flip:1365) DEBUG: name = flip
last_ts = 62.684162
last_received_ts = 62.684055
last_seq = 641
current_ts = 62.700756
current_received_ts = 62.700520
current_seq = 642
count = 182
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 62.684162/641, current 62.700756/642: elapsed=16591.0us expected=16532.8us +- 82.7us, error 0.4%
(kms_flip:1365) DEBUG: name = flip
last_ts = 62.700756
last_received_ts = 62.700520
last_seq = 642
current_ts = 62.717323
current_received_ts = 62.717178
current_seq = 643
count = 183
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 62.700756/642, current 62.717323/643: elapsed=16568.0us expected=16532.8us +- 82.7us, error 0.2%
(kms_flip:1365) DEBUG: name = flip
last_ts = 62.717323
last_received_ts = 62.717178
last_seq = 643
current_ts = 62.733902
current_received_ts = 62.733761
current_seq = 644
count = 184
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 62.717323/643, current 62.733902/644: elapsed=16579.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 62.733902
last_received_ts = 62.733761
last_seq = 644
current_ts = 62.750481
current_received_ts = 62.750336
current_seq = 645
count = 185
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 62.733902/644, current 62.750481/645: elapsed=16580.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 62.750481
last_received_ts = 62.750336
last_seq = 645
current_ts = 62.767056
current_received_ts = 62.766884
current_seq = 646
count = 186
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 62.750481/645, current 62.767056/646: elapsed=16575.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 62.767056
last_received_ts = 62.766884
last_seq = 646
current_ts = 62.783623
current_received_ts = 62.783527
current_seq = 647
count = 187
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 62.767056/646, current 62.783623/647: elapsed=16567.0us expected=16532.8us +- 82.7us, error 0.2%
(kms_flip:1365) DEBUG: name = flip
last_ts = 62.783623
last_received_ts = 62.783527
last_seq = 647
current_ts = 62.800201
current_received_ts = 62.800171
current_seq = 648
count = 188
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 62.783623/647, current 62.800201/648: elapsed=16576.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 62.800201
last_received_ts = 62.800171
last_seq = 648
current_ts = 62.816780
current_received_ts = 62.816444
current_seq = 649
count = 189
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 62.800201/648, current 62.816780/649: elapsed=16582.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 62.816780
last_received_ts = 62.816444
last_seq = 649
current_ts = 62.833363
current_received_ts = 62.833241
current_seq = 650
count = 190
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 62.816780/649, current 62.833363/650: elapsed=16582.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 62.833363
last_received_ts = 62.833241
last_seq = 650
current_ts = 62.849937
current_received_ts = 62.849789
current_seq = 651
count = 191
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 62.833363/650, current 62.849937/651: elapsed=16575.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 62.849937
last_received_ts = 62.849789
last_seq = 651
current_ts = 62.866508
current_received_ts = 62.866261
current_seq = 652
count = 192
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 62.849937/651, current 62.866508/652: elapsed=16571.0us expected=16532.8us +- 82.7us, error 0.2%
(kms_flip:1365) DEBUG: name = flip
last_ts = 62.866508
last_received_ts = 62.866261
last_seq = 652
current_ts = 62.883091
current_received_ts = 62.882931
current_seq = 653
count = 193
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 62.866508/652, current 62.883091/653: elapsed=16582.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 62.883091
last_received_ts = 62.882931
last_seq = 653
current_ts = 62.899673
current_received_ts = 62.899506
current_seq = 654
count = 194
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 62.883091/653, current 62.899673/654: elapsed=16580.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 62.899673
last_received_ts = 62.899506
last_seq = 654
current_ts = 62.916241
current_received_ts = 62.916050
current_seq = 655
count = 195
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 62.899673/654, current 62.916241/655: elapsed=16569.0us expected=16532.8us +- 82.7us, error 0.2%
(kms_flip:1365) DEBUG: name = flip
last_ts = 62.916241
last_received_ts = 62.916050
last_seq = 655
current_ts = 62.932816
current_received_ts = 62.932400
current_seq = 656
count = 196
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 62.916241/655, current 62.932816/656: elapsed=16575.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 62.932816
last_received_ts = 62.932400
last_seq = 656
current_ts = 62.949406
current_received_ts = 62.949261
current_seq = 657
count = 197
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 62.932816/656, current 62.949406/657: elapsed=16591.0us expected=16532.8us +- 82.7us, error 0.4%
(kms_flip:1365) DEBUG: name = flip
last_ts = 62.949406
last_received_ts = 62.949261
last_seq = 657
current_ts = 62.966000
current_received_ts = 62.965855
current_seq = 658
count = 198
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 62.949406/657, current 62.966000/658: elapsed=16594.0us expected=16532.8us +- 82.7us, error 0.4%
(kms_flip:1365) DEBUG: name = flip
last_ts = 62.966000
last_received_ts = 62.965855
last_seq = 658
current_ts = 62.982590
current_received_ts = 62.982498
current_seq = 659
count = 199
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 62.966000/658, current 62.982590/659: elapsed=16589.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 62.982590
last_received_ts = 62.982498
last_seq = 659
current_ts = 62.999176
current_received_ts = 62.999123
current_seq = 660
count = 200
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 62.982590/659, current 62.999176/660: elapsed=16586.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 62.999176
last_received_ts = 62.999123
last_seq = 660
current_ts = 63.015766
current_received_ts = 63.015598
current_seq = 661
count = 201
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 62.999176/660, current 63.015766/661: elapsed=16589.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 63.015766
last_received_ts = 63.015598
last_seq = 661
current_ts = 63.032352
current_received_ts = 63.032257
current_seq = 662
count = 202
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 63.015766/661, current 63.032352/662: elapsed=16588.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 63.032352
last_received_ts = 63.032257
last_seq = 662
current_ts = 63.048943
current_received_ts = 63.048771
current_seq = 663
count = 203
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 63.032352/662, current 63.048943/663: elapsed=16590.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 63.048943
last_received_ts = 63.048771
last_seq = 663
current_ts = 63.065533
current_received_ts = 63.065395
current_seq = 664
count = 204
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 63.048943/663, current 63.065533/664: elapsed=16590.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 63.065533
last_received_ts = 63.065395
last_seq = 664
current_ts = 63.082111
current_received_ts = 63.081966
current_seq = 665
count = 205
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 63.065533/664, current 63.082111/665: elapsed=16580.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 63.082111
last_received_ts = 63.081966
last_seq = 665
current_ts = 63.098705
current_received_ts = 63.098553
current_seq = 666
count = 206
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 63.082111/665, current 63.098705/666: elapsed=16592.0us expected=16532.8us +- 82.7us, error 0.4%
(kms_flip:1365) DEBUG: name = flip
last_ts = 63.098705
last_received_ts = 63.098553
last_seq = 666
current_ts = 63.115295
current_received_ts = 63.115074
current_seq = 667
count = 207
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 63.098705/666, current 63.115295/667: elapsed=16589.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 63.115295
last_received_ts = 63.115074
last_seq = 667
current_ts = 63.131870
current_received_ts = 63.131454
current_seq = 668
count = 208
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 63.115295/667, current 63.131870/668: elapsed=16577.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 63.131870
last_received_ts = 63.131454
last_seq = 668
current_ts = 63.148460
current_received_ts = 63.148258
current_seq = 669
count = 209
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 63.131870/668, current 63.148460/669: elapsed=16590.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 63.148460
last_received_ts = 63.148258
last_seq = 669
current_ts = 63.165054
current_received_ts = 63.164860
current_seq = 670
count = 210
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 63.148460/669, current 63.165054/670: elapsed=16592.0us expected=16532.8us +- 82.7us, error 0.4%
(kms_flip:1365) DEBUG: name = flip
last_ts = 63.165054
last_received_ts = 63.164860
last_seq = 670
current_ts = 63.181637
current_received_ts = 63.181458
current_seq = 671
count = 211
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 63.165054/670, current 63.181637/671: elapsed=16584.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 63.181637
last_received_ts = 63.181458
last_seq = 671
current_ts = 63.198227
current_received_ts = 63.198124
current_seq = 672
count = 212
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 63.181637/671, current 63.198227/672: elapsed=16591.0us expected=16532.8us +- 82.7us, error 0.4%
(kms_flip:1365) DEBUG: name = flip
last_ts = 63.198227
last_received_ts = 63.198124
last_seq = 672
current_ts = 63.214813
current_received_ts = 63.214622
current_seq = 673
count = 213
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 63.198227/672, current 63.214813/673: elapsed=16587.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 63.214813
last_received_ts = 63.214622
last_seq = 673
current_ts = 63.231407
current_received_ts = 63.231243
current_seq = 674
count = 214
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 63.214813/673, current 63.231407/674: elapsed=16593.0us expected=16532.8us +- 82.7us, error 0.4%
(kms_flip:1365) DEBUG: name = flip
last_ts = 63.231407
last_received_ts = 63.231243
last_seq = 674
current_ts = 63.247997
current_received_ts = 63.247799
current_seq = 675
count = 215
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 63.231407/674, current 63.247997/675: elapsed=16588.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 63.247997
last_received_ts = 63.247799
last_seq = 675
current_ts = 63.264580
current_received_ts = 63.264462
current_seq = 676
count = 216
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 63.247997/675, current 63.264580/676: elapsed=16584.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 63.264580
last_received_ts = 63.264462
last_seq = 676
current_ts = 63.281174
current_received_ts = 63.280952
current_seq = 677
count = 217
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 63.264580/676, current 63.281174/677: elapsed=16592.0us expected=16532.8us +- 82.7us, error 0.4%
(kms_flip:1365) DEBUG: name = flip
last_ts = 63.281174
last_received_ts = 63.280952
last_seq = 677
current_ts = 63.297756
current_received_ts = 63.297565
current_seq = 678
count = 218
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 63.281174/677, current 63.297756/678: elapsed=16583.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 63.297756
last_received_ts = 63.297565
last_seq = 678
current_ts = 63.314342
current_received_ts = 63.314083
current_seq = 679
count = 219
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 63.297756/678, current 63.314342/679: elapsed=16587.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 63.314342
last_received_ts = 63.314083
last_seq = 679
current_ts = 63.330925
current_received_ts = 63.330605
current_seq = 680
count = 220
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 63.314342/679, current 63.330925/680: elapsed=16583.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 63.330925
last_received_ts = 63.330605
last_seq = 680
current_ts = 63.347523
current_received_ts = 63.347305
current_seq = 681
count = 221
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 63.330925/680, current 63.347523/681: elapsed=16599.0us expected=16532.8us +- 82.7us, error 0.4%
(kms_flip:1365) DEBUG: name = flip
last_ts = 63.347523
last_received_ts = 63.347305
last_seq = 681
current_ts = 63.364105
current_received_ts = 63.363972
current_seq = 682
count = 222
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 63.347523/681, current 63.364105/682: elapsed=16583.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 63.364105
last_received_ts = 63.363972
last_seq = 682
current_ts = 63.380692
current_received_ts = 63.380409
current_seq = 683
count = 223
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 63.364105/682, current 63.380692/683: elapsed=16583.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 63.380692
last_received_ts = 63.380409
last_seq = 683
current_ts = 63.397278
current_received_ts = 63.397163
current_seq = 684
count = 224
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 63.380692/683, current 63.397278/684: elapsed=16588.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 63.397278
last_received_ts = 63.397163
last_seq = 684
current_ts = 63.413872
current_received_ts = 63.413734
current_seq = 685
count = 225
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 63.397278/684, current 63.413872/685: elapsed=16592.0us expected=16532.8us +- 82.7us, error 0.4%
(kms_flip:1365) DEBUG: name = flip
last_ts = 63.413872
last_received_ts = 63.413734
last_seq = 685
current_ts = 63.430454
current_received_ts = 63.430202
current_seq = 686
count = 226
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 63.413872/685, current 63.430454/686: elapsed=16583.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 63.430454
last_received_ts = 63.430202
last_seq = 686
current_ts = 63.447041
current_received_ts = 63.446884
current_seq = 687
count = 227
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 63.430454/686, current 63.447041/687: elapsed=16586.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 63.447041
last_received_ts = 63.446884
last_seq = 687
current_ts = 63.463634
current_received_ts = 63.463493
current_seq = 688
count = 228
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 63.447041/687, current 63.463634/688: elapsed=16595.0us expected=16532.8us +- 82.7us, error 0.4%
(kms_flip:1365) DEBUG: name = flip
last_ts = 63.463634
last_received_ts = 63.463493
last_seq = 688
current_ts = 63.480217
current_received_ts = 63.479984
current_seq = 689
count = 229
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 63.463634/688, current 63.480217/689: elapsed=16583.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 63.480217
last_received_ts = 63.479984
last_seq = 689
current_ts = 63.496803
current_received_ts = 63.496712
current_seq = 690
count = 230
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 63.480217/689, current 63.496803/690: elapsed=16586.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 63.496803
last_received_ts = 63.496712
last_seq = 690
current_ts = 63.513393
current_received_ts = 63.513214
current_seq = 691
count = 231
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 63.496803/690, current 63.513393/691: elapsed=16590.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 63.513393
last_received_ts = 63.513214
last_seq = 691
current_ts = 63.529980
current_received_ts = 63.529633
current_seq = 692
count = 232
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 63.513393/691, current 63.529980/692: elapsed=16588.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 63.529980
last_received_ts = 63.529633
last_seq = 692
current_ts = 63.546574
current_received_ts = 63.546467
current_seq = 693
count = 233
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 63.529980/692, current 63.546574/693: elapsed=16592.0us expected=16532.8us +- 82.7us, error 0.4%
(kms_flip:1365) DEBUG: name = flip
last_ts = 63.546574
last_received_ts = 63.546467
last_seq = 693
current_ts = 63.563164
current_received_ts = 63.563118
current_seq = 694
count = 234
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 63.546574/693, current 63.563164/694: elapsed=16590.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 63.563164
last_received_ts = 63.563118
last_seq = 694
current_ts = 63.579754
current_received_ts = 63.579384
current_seq = 695
count = 235
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 63.563164/694, current 63.579754/695: elapsed=16592.0us expected=16532.8us +- 82.7us, error 0.4%
(kms_flip:1365) DEBUG: name = flip
last_ts = 63.579754
last_received_ts = 63.579384
last_seq = 695
current_ts = 63.596340
current_received_ts = 63.596195
current_seq = 696
count = 236
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 63.579754/695, current 63.596340/696: elapsed=16585.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 63.596340
last_received_ts = 63.596195
last_seq = 696
current_ts = 63.612926
current_received_ts = 63.612839
current_seq = 697
count = 237
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 63.596340/696, current 63.612926/697: elapsed=16588.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 63.612926
last_received_ts = 63.612839
last_seq = 697
current_ts = 63.629509
current_received_ts = 63.629314
current_seq = 698
count = 238
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 63.612926/697, current 63.629509/698: elapsed=16582.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 63.629509
last_received_ts = 63.629314
last_seq = 698
current_ts = 63.646103
current_received_ts = 63.645931
current_seq = 699
count = 239
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 63.629509/698, current 63.646103/699: elapsed=16593.0us expected=16532.8us +- 82.7us, error 0.4%
(kms_flip:1365) DEBUG: name = flip
last_ts = 63.646103
last_received_ts = 63.645931
last_seq = 699
current_ts = 63.662685
current_received_ts = 63.662376
current_seq = 700
count = 240
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 63.646103/699, current 63.662685/700: elapsed=16584.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 63.662685
last_received_ts = 63.662376
last_seq = 700
current_ts = 63.679276
current_received_ts = 63.679104
current_seq = 701
count = 241
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 63.662685/700, current 63.679276/701: elapsed=16588.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 63.679276
last_received_ts = 63.679104
last_seq = 701
current_ts = 63.695866
current_received_ts = 63.695751
current_seq = 702
count = 242
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 63.679276/701, current 63.695866/702: elapsed=16589.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 63.695866
last_received_ts = 63.695751
last_seq = 702
current_ts = 63.712456
current_received_ts = 63.712311
current_seq = 703
count = 243
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 63.695866/702, current 63.712456/703: elapsed=16590.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 63.712456
last_received_ts = 63.712311
last_seq = 703
current_ts = 63.729038
current_received_ts = 63.728836
current_seq = 704
count = 244
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 63.712456/703, current 63.729038/704: elapsed=16585.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 63.729038
last_received_ts = 63.728836
last_seq = 704
current_ts = 63.745632
current_received_ts = 63.745518
current_seq = 705
count = 245
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 63.729038/704, current 63.745632/705: elapsed=16593.0us expected=16532.8us +- 82.7us, error 0.4%
(kms_flip:1365) DEBUG: name = flip
last_ts = 63.745632
last_received_ts = 63.745518
last_seq = 705
current_ts = 63.762215
current_received_ts = 63.762089
current_seq = 706
count = 246
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 63.745632/705, current 63.762215/706: elapsed=16584.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 63.762215
last_received_ts = 63.762089
last_seq = 706
current_ts = 63.778812
current_received_ts = 63.778423
current_seq = 707
count = 247
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 63.762215/706, current 63.778812/707: elapsed=16598.0us expected=16532.8us +- 82.7us, error 0.4%
(kms_flip:1365) DEBUG: name = flip
last_ts = 63.778812
last_received_ts = 63.778423
last_seq = 707
current_ts = 63.795395
current_received_ts = 63.795235
current_seq = 708
count = 248
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 63.778812/707, current 63.795395/708: elapsed=16580.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 63.795395
last_received_ts = 63.795235
last_seq = 708
current_ts = 63.811974
current_received_ts = 63.811634
current_seq = 709
count = 249
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 63.795395/708, current 63.811974/709: elapsed=16581.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 63.811974
last_received_ts = 63.811634
last_seq = 709
current_ts = 63.828571
current_received_ts = 63.828423
current_seq = 710
count = 250
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 63.811974/709, current 63.828571/710: elapsed=16598.0us expected=16532.8us +- 82.7us, error 0.4%
(kms_flip:1365) DEBUG: name = flip
last_ts = 63.828571
last_received_ts = 63.828423
last_seq = 710
current_ts = 63.845154
current_received_ts = 63.844990
current_seq = 711
count = 251
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 63.828571/710, current 63.845154/711: elapsed=16582.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 63.845154
last_received_ts = 63.844990
last_seq = 711
current_ts = 63.861748
current_received_ts = 63.861649
current_seq = 712
count = 252
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 63.845154/711, current 63.861748/712: elapsed=16592.0us expected=16532.8us +- 82.7us, error 0.4%
(kms_flip:1365) DEBUG: name = flip
last_ts = 63.861748
last_received_ts = 63.861649
last_seq = 712
current_ts = 63.878326
current_received_ts = 63.878120
current_seq = 713
count = 253
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 63.861748/712, current 63.878326/713: elapsed=16580.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 63.878326
last_received_ts = 63.878120
last_seq = 713
current_ts = 63.894913
current_received_ts = 63.894535
current_seq = 714
count = 254
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 63.878326/713, current 63.894913/714: elapsed=16587.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 63.894913
last_received_ts = 63.894535
last_seq = 714
current_ts = 63.911503
current_received_ts = 63.911221
current_seq = 715
count = 255
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 63.894913/714, current 63.911503/715: elapsed=16587.0us expected=16532.8us +- 82.7us, error 0.3%
(kms_flip:1365) DEBUG: name = flip
last_ts = 63.911503
last_received_ts = 63.911221
last_seq = 715
current_ts = 63.928093
current_received_ts = 63.927898
current_seq = 716
count = 256
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 63.911503/715, current 63.928093/716: elapsed=16592.0us expected=16532.8us +- 82.7us, error 0.4%
(kms_flip:1365) DEBUG: name = flip
last_ts = 63.928093
last_received_ts = 63.927898
last_seq = 716
current_ts = 63.944691
current_received_ts = 63.944542
current_seq = 717
count = 257
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 63.928093/716, current 63.944691/717: elapsed=16599.0us expected=16532.8us +- 82.7us, error 0.4%
(kms_flip:1365) DEBUG: name = flip
last_ts = 63.944691
last_received_ts = 63.944542
last_seq = 717
current_ts = 63.961292
current_received_ts = 63.961208
current_seq = 718
count = 258
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 63.944691/717, current 63.961292/718: elapsed=16599.0us expected=16532.8us +- 82.7us, error 0.4%
(kms_flip:1365) DEBUG: name = flip
last_ts = 63.961292
last_received_ts = 63.961208
last_seq = 718
current_ts = 63.977886
current_received_ts = 63.977654
current_seq = 719
count = 259
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 63.961292/718, current 63.977886/719: elapsed=16595.0us expected=16532.8us +- 82.7us, error 0.4%
(kms_flip:1365) DEBUG: name = flip
last_ts = 63.977886
last_received_ts = 63.977654
last_seq = 719
current_ts = 63.994484
current_received_ts = 63.994312
current_seq = 720
count = 260
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 63.977886/719, current 63.994484/720: elapsed=16597.0us expected=16532.8us +- 82.7us, error 0.4%
(kms_flip:1365) DEBUG: name = flip
last_ts = 63.994484
last_received_ts = 63.994312
last_seq = 720
current_ts = 64.011086
current_received_ts = 64.010887
current_seq = 721
count = 261
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 63.994484/720, current 64.011086/721: elapsed=16600.0us expected=16532.8us +- 82.7us, error 0.4%
(kms_flip:1365) DEBUG: name = flip
last_ts = 64.011086
last_received_ts = 64.010887
last_seq = 721
current_ts = 64.027679
current_received_ts = 64.027542
current_seq = 722
count = 262
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 64.011086/721, current 64.027679/722: elapsed=16595.0us expected=16532.8us +- 82.7us, error 0.4%
(kms_flip:1365) DEBUG: name = flip
last_ts = 64.027679
last_received_ts = 64.027542
last_seq = 722
current_ts = 64.044281
current_received_ts = 64.044220
current_seq = 723
count = 263
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 64.027679/722, current 64.044281/723: elapsed=16602.0us expected=16532.8us +- 82.7us, error 0.4%
(kms_flip:1365) DEBUG: name = flip
last_ts = 64.044281
last_received_ts = 64.044220
last_seq = 723
current_ts = 64.060898
current_received_ts = 64.060707
current_seq = 724
count = 264
seq_step = 1
(kms_flip:1365) DEBUG: flip ts/seq: last 64.044281/723, current 64.060898/724: elapsed=16616.0us expected=16532.8us +- 82.7us, error 0.5%
(kms_flip:1365) DEBUG: inconsistent flip ts/seq: last 64.044281/723, current 64.060898/724: elapsed=16616.0us expected=16532.8us
(kms_flip:1365) DEBUG: expected 0, counted 264, encoder type 2
(kms_flip:1365) DEBUG: dropped frames, expected 0, counted 264, encoder type 2
(kms_flip:1365) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1674:
(kms_flip:1365) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon)
(kms_flip:1365) igt_core-INFO: Stack trace:
(kms_flip:1365) igt_core-INFO:   #0 ../lib/igt_core.c:2051 __igt_fail_assert()
(kms_flip:1365) igt_core-INFO:   #1 ../tests/kms_flip.c:791 run_test_on_crtc_set.constprop.0()
(kms_flip:1365) igt_core-INFO:   #2 ../tests/kms_flip.c:1930 run_pair()
(kms_flip:1365) igt_core-INFO:   #3 ../tests/kms_flip.c:2087 __igt_unique____real_main2001()
(kms_flip:1365) igt_core-INFO:   #4 ../tests/kms_flip.c:2001 main()
(kms_flip:1365) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:1365) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:1365) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest BC-HDMI-A1-HDMI-A2: FAIL (9.123s)
Subtest 2x-plain-flip-ts-check: FAIL (20.221s)
(kms_flip:1365) igt_kms-CRITICAL: Test assertion failure function kmstest_set_connector_dpms, file ../lib/igt_kms.c:2234:
(kms_flip:1365) igt_kms-CRITICAL: Failed assertion: found_it
(kms_flip:1365) igt_kms-CRITICAL: Last errno: 9, Bad file descriptor
(kms_flip:1365) igt_kms-CRITICAL: DPMS property not found on 148
Dmesg
<6> [58.994476] [IGT] kms_flip: starting dynamic subtest BC-HDMI-A1-HDMI-A2
<7> [58.995556] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:170]
<7> [58.995702] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:171]
<7> [59.049745] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:70:pipe A]
<7> [59.050002] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [59.050570] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [59.050942] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [59.051311] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [59.051722] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in lane_lat_optim_mask (expected 0x0000000d, found 0x00000000)
<7> [59.052092] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [59.052555] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [59.052926] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [59.053294] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [59.053679] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [59.054094] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [59.054516] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [59.054885] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [59.055252] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [59.055677] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [59.056084] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1083, found 0)
<7> [59.056513] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1088, found 0)
<7> [59.056881] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [59.057248] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [59.057632] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [59.058052] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [59.058469] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [59.058837] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [59.059204] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [59.059610] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [59.060017] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [59.060430] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [59.060833] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1083, found 0)
<7> [59.061202] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1088, found 0)
<7> [59.061596] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [59.062003] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [59.062410] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [59.062794] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [59.063163] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [59.063584] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [59.063989] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [59.064402] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in pipe_bpp (expected 36, found 0)
<7> [59.064789] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [59.065157] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [59.065538] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in port_clock (expected 222750, found 0)
<7> [59.065945] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [59.066348] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in infoframes.gcp (expected 0x00000006, found 0x00000000)
<7> [59.066752] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in avi infoframe
<7> [59.067120] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [59.067523] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [59.067528] i915 0000:00:02.0: colorspace: RGB
<7> [59.067532] i915 0000:00:02.0: scan mode: Underscan
<7> [59.067535] i915 0000:00:02.0: colorimetry: No Data
<7> [59.067538] i915 0000:00:02.0: picture aspect: No Data
<7> [59.067542] i915 0000:00:02.0: active aspect: Same as Picture
<7> [59.067545] i915 0000:00:02.0: itc: No Data
<7> [59.067549] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [59.067552] i915 0000:00:02.0: quantization range: Full
<7> [59.067556] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [59.067560] i915 0000:00:02.0: video code: 0
<7> [59.067563] i915 0000:00:02.0: ycc quantization range: Full
<7> [59.067567] i915 0000:00:02.0: hdmi content type: Graphics
<7> [59.067570] i915 0000:00:02.0: pixel repeat: 0
<7> [59.067573] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [59.067578] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [59.067982] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in spd infoframe
<7> [59.068391] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [59.068793] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [59.068798] i915 0000:00:02.0: vendor: Intel
<7> [59.068801] i915 0000:00:02.0: product: Integrated gfx
<7> [59.068804] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [59.068808] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [59.069175] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hdmi infoframe
<7> [59.069557] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [59.069964] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [59.069968] i915 0000:00:02.0: empty frame
<7> [59.069971] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [59.070374] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:70:pipe A] fastset requirement not met, forcing full modeset
<7> [59.070760] i915 0000:00:02.0: [drm:intel_unreference_shared_dpll_crtc [i915]] [CRTC:70:pipe A] releasing PORT PLL B
<7> [59.071161] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:70:pipe A] dbuf slices 0x1 -> 0x0, ddb (0 - 510) -> (0 - 0), active pipes 0x5 -> 0x4
<7> [59.071595] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:146:pipe C] dbuf slices 0x1 -> 0x1, ddb (510 - 1020) -> (0 - 1020), active pipes 0x5 -> 0x4
<7> [59.072066] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:33:plane 1A] ddb ( 0 - 502) -> ( 0 - 0), size 502 -> 0
<7> [59.072462] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:65:cursor A] ddb ( 502 - 510) -> ( 0 - 0), size 8 -> 0
<7> [59.072846] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:33:plane 1A] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [59.073201] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:33:plane 1A] lines 1, 1, 1, 2, 2, 2, 2, 2, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [59.073593] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:33:plane 1A] blocks 16, 16, 16, 33, 33, 33, 33, 33, 30, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [59.073988] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:33:plane 1A] min_ddb 17, 17, 17, 34, 34, 34, 34, 34, 31, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [59.074383] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:109:plane 1C] ddb ( 510 - 1012) -> ( 0 - 988), size 502 -> 988
<7> [59.074754] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:141:cursor C] ddb (1012 - 1020) -> ( 988 - 1020), size 8 -> 32
<7> [59.075141] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 316800 kHz, actual 316800 kHz
<7> [59.075513] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 13, actual 13
<7> [59.075945] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:70:pipe A] enable: no [modeset]
<7> [59.076353] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:33:plane 1A] fb: [NOFB], visible: no
<7> [59.076781] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:41:plane 2A] fb: [NOFB], visible: no
<7> [59.077149] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:49:plane 3A] fb: [NOFB], visible: no
<7> [59.077515] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:57:plane 4A] fb: [NOFB], visible: no
<7> [59.077894] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:65:cursor A] fb: [NOFB], visible: no
<7> [59.078813] i915 0000:00:02.0: [drm:intel_audio_codec_disable [i915]] [CONNECTOR:148:HDMI-A-1][ENCODER:147:DDI B/PHY B] Disable audio codec on [CRTC:70:pipe A]
<7> [59.118196] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [59.136169] i915 0000:00:02.0: [drm:intel_ddi_disable_transcoder_func [i915]] Quirk Increase DDI disabled time
<7> [59.243214] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_B
<7> [59.243749] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disable PORT PLL B (active 0x1, on? 1) for [CRTC:70:pipe A]
<7> [59.244377] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disabling PORT PLL B
<7> [59.244808] i915 0000:00:02.0: [drm:__intel_fbc_disable [i915]] Disabling FBC on [PLANE:33:plane 1A]
<7> [59.245465] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:147:DDI B/PHY B]
<7> [59.245902] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:157:DDI C/PHY C]
<7> [59.246423] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:148:HDMI-A-1]
<7> [59.248043] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling dpio-common-b
<7> [59.248501] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:70:pipe A]
<7> [59.250919] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:108:pipe B]
<7> [59.252203] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:146:pipe C]
<7> [59.252338] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.enable (expected yes, found no)
<7> [59.252743] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.active (expected yes, found no)
<7> [59.253102] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in cpu_transcoder (expected 2, found -1)
<7> [59.254188] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in lane_count (expected 4, found 0)
<7> [59.254573] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in lane_lat_optim_mask (expected 0x0000000d, found 0x00000000)
<7> [59.254953] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [59.256219] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [59.256602] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [59.256980] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [59.260272] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [59.260770] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [59.261165] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [59.261756] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [59.262127] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [59.262618] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [59.262989] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1083, found 0)
<7> [59.263523] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1088, found 0)
<7> [59.263898] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [59.264416] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [59.264829] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [59.265198] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [59.265730] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [59.266101] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [59.266537] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [59.266908] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [59.267453] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [59.267825] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [59.268194] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1083, found 0)
<7> [59.268752] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1088, found 0)
<7> [59.269124] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [59.269600] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [59.269971] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [59.270483] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [59.270855] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [59.271224] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [59.271773] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [59.272146] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in pipe_bpp (expected 36, found 0)
<7> [59.272623] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [59.272994] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [59.273506] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in port_clock (expected 222750, found 0)
<7> [59.273879] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [59.274248] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in infoframes.gcp (expected 0x00000006, found 0x00000000)
<7> [59.274779] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in avi infoframe
<7> [59.275150] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [59.275622] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [59.275628] i915 0000:00:02.0: colorspace: RGB
<7> [59.275632] i915 0000:00:02.0: scan mode: Underscan
<7> [59.275635] i915 0000:00:02.0: colorimetry: No Data
<7> [59.275639] i915 0000:00:02.0: picture aspect: No Data
<7> [59.275642] i915 0000:00:02.0: active aspect: Same as Picture
<7> [59.275646] i915 0000:00:02.0: itc: No Data
<7> [59.275649] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [59.275652] i915 0000:00:02.0: quantization range: Full
<7> [59.275656] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [59.275659] i915 0000:00:02.0: video code: 0
<7> [59.275662] i915 0000:00:02.0: ycc quantization range: Full
<7> [59.275666] i915 0000:00:02.0: hdmi content type: Graphics
<7> [59.275669] i915 0000:00:02.0: pixel repeat: 0
<7> [59.275672] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [59.275676] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [59.276045] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in spd infoframe
<7> [59.276617] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [59.276986] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [59.276990] i915 0000:00:02.0: vendor: Intel
<7> [59.276994] i915 0000:00:02.0: product: Integrated gfx
<7> [59.276997] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [59.277001] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [59.277536] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hdmi infoframe
<7> [59.277906] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [59.278272] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [59.278276] i915 0000:00:02.0: empty frame
<7> [59.278280] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [59.278818] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:146:pipe C] fastset requirement not met, forcing full modeset
<7> [59.279226] i915 0000:00:02.0: [drm:intel_unreference_shared_dpll_crtc [i915]] [CRTC:146:pipe C] releasing PORT PLL C
<7> [59.279783] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:146:pipe C] dbuf slices 0x1 -> 0x0, ddb (0 - 1020) -> (0 - 0), active pipes 0x4 -> 0x0
<7> [59.280204] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:109:plane 1C] ddb ( 0 - 988) -> ( 0 - 0), size 988 -> 0
<7> [59.280704] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:141:cursor C] ddb ( 988 - 1020) -> ( 0 - 0), size 32 -> 0
<7> [59.281064] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:109: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> [59.281542] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:109:plane 1C] lines 1, 1, 1, 2, 2, 2, 2, 2, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [59.281902] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:109:plane 1C] blocks 16, 16, 16, 33, 33, 33, 33, 33, 30, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [59.282258] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:109:plane 1C] min_ddb 17, 17, 17, 34, 34, 34, 34, 34, 31, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [59.282847] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] Modeset required for cdclk change
<7> [59.283222] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 79200 kHz, actual 79200 kHz
<7> [59.283762] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 4, actual 4
<7> [59.284141] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:146:pipe C] enable: no [modeset]
<7> [59.284620] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:109:plane 1C] fb: [NOFB], visible: no
<7> [59.284991] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:117:plane 2C] fb: [NOFB], visible: no
<7> [59.285359] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:125:plane 3C] fb: [NOFB], visible: no
<7> [59.285890] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:133:plane 4C] fb: [NOFB], visible: no
<7> [59.286263] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:141:cursor C] fb: [NOFB], visible: no
<7> [59.286896] i915 0000:00:02.0: [drm:intel_audio_codec_disable [i915]] [CONNECTOR:158:HDMI-A-2][ENCODER:157:DDI C/PHY C] Disable audio codec on [CRTC:146:pipe C]
<7> [59.314804] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe C
<7> [59.332884] i915 0000:00:02.0: [drm:intel_ddi_disable_transcoder_func [i915]] Quirk Increase DDI disabled time
<7> [59.437968] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_C
<7> [59.439441] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disable PORT PLL C (active 0x4, on? 1) for [CRTC:146:pipe C]
<7> [59.441811] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disabling PORT PLL C
<7> [59.442307] i915 0000:00:02.0: [drm:intel_set_cdclk [i915]] Pre changing CDCLK to 79200 kHz, VCO 633600 kHz, ref 19200 kHz, bypass 19200 kHz, voltage level 4
<7> [59.443075] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:147:DDI B/PHY B]
<7> [59.443440] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:157:DDI C/PHY C]
<7> [59.443985] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:158:HDMI-A-2]
<7> [59.444546] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling dpio-common-c
<7> [59.445111] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_2
<7> [59.445560] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:146:pipe C]
<7> [59.447076] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:146:pipe C]
<7> [59.447134] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:158:HDMI-A-2]
<7> [59.447271] i915 0000:00:02.0: [drm:intel_hdmi_compute_clock [i915]] picking 12 bpc for HDMI output (pipe bpp: 36)
<7> [59.447698] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:146:pipe C] hw max bpp: 36, pipe bpp: 36, dithering: 0
<7> [59.448281] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:157:DDI C/PHY C] [CRTC:146:pipe C]
<7> [59.448643] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.enable (expected no, found yes)
<7> [59.449164] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.active (expected no, found yes)
<7> [59.449536] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in cpu_transcoder (expected -1, found 2)
<7> [59.450521] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in lane_count (expected 0, found 4)
<7> [59.451056] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in lane_lat_optim_mask (expected 0x00000000, found 0x0000000d)
<7> [59.451431] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [59.451965] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [59.452337] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [59.452705] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [59.453240] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [59.454336] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [59.454844] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [59.455216] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [59.455583] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [59.456120] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [59.456490] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1083)
<7> [59.457006] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1088)
<7> [59.457392] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [59.457760] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [59.458843] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [59.459244] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [59.459613] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [59.460141] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [59.460511] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [59.461037] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [59.461407] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [59.461774] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [59.462309] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1083)
<7> [59.462679] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1088)
<7> [59.463873] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [59.464253] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [59.464621] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [59.465098] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [59.465485] i915 0000:00:02.0: [drm:gen9_enable_dc5 [i915]] Enabling DC5
<7> [59.465853] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 01
<7> [59.467421] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [59.467794] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [59.468819] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling always-on
<7> [59.469227] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in has_hdmi_sink (expected no, found yes)
<7> [59.469600] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in has_infoframe (expected no, found yes)
<7> [59.470181] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in pipe_bpp (expected 0, found 36)
<7> [59.471292] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [59.471697] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [59.472239] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in port_clock (expected 0, found 222750)
<7> [59.472610] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071)
<7> [59.473126] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in infoframes.gcp (expected 0x00000000, found 0x00000006)
<7> [59.473498] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in avi infoframe
<7> [59.473864] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [59.474398] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [59.474767] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [59.474774] i915 0000:00:02.0: colorspace: RGB
<7> [59.474778] i915 0000:00:02.0: scan mode: Underscan
<7> [59.474781] i915 0000:00:02.0: colorimetry: No Data
<7> [59.474785] i915 0000:00:02.0: picture aspect: No Data
<7> [59.474789] i915 0000:00:02.0: active aspect: Same as Picture
<7> [59.474792] i915 0000:00:02.0: itc: No Data
<7> [59.474795] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [59.474799] i915 0000:00:02.0: quantization range: Full
<7> [59.474803] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [59.474807] i915 0000:00:02.0: video code: 0
<7> [59.474810] i915 0000:00:02.0: ycc quantization range: Full
<7> [59.474813] i915 0000:00:02.0: hdmi content type: Graphics
<7> [59.474817] i915 0000:00:02.0: pixel repeat: 0
<7> [59.474820] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [59.474824] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in spd infoframe
<7> [59.476229] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [59.476674] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [59.477206] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [59.477211] i915 0000:00:02.0: vendor: Intel
<7> [59.477214] i915 0000:00:02.0: product: Integrated gfx
<7> [59.477218] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [59.477222] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hdmi infoframe
<7> [59.477592] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [59.477958] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [59.478517] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [59.478522] i915 0000:00:02.0: empty frame
<7> [59.478526] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:146:pipe C] fastset requirement not met, forcing full modeset
<7> [59.478942] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:146:pipe C] dbuf slices 0x0 -> 0x1, ddb (0 - 0) -> (0 - 1020), active pipes 0x0 -> 0x4
<7> [59.479444] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:109:plane 1C] ddb ( 0 - 0) -> ( 0 - 988), size 0 -> 988
<7> [59.479804] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:141:cursor C] ddb ( 0 - 0) -> ( 988 - 1020), size 0 -> 32
<7> [59.481037] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:109: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> [59.481403] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:109:plane 1C] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 1, 1, 2, 2, 2, 2, 2, 0, 0, 0
<7> [59.481759] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:109:plane 1C] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 16, 16, 33, 33, 33, 33, 33, 30, 0, 0
<7> [59.482389] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:109:plane 1C] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 17, 17, 17, 34, 34, 34, 34, 34, 31, 0, 0
<7> [59.482864] i915 0000:00:02.0: [drm:intel_plane_calc_min_cdclk [i915]] [PLANE:109:plane 1C] min cdclk (74250 kHz) > [CRTC:146:pipe C] min cdclk (0 kHz)
<7> [59.483396] i915 0000:00:02.0: [drm:intel_bw_calc_min_cdclk [i915]] new bandwidth min cdclk (9282 kHz) > old min cdclk (0 kHz)
<7> [59.483815] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] Modeset required for cdclk change
<7> [59.484352] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 316800 kHz, actual 316800 kHz
<7> [59.484725] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 13, actual 13
<7> [59.485246] i915 0000:00:02.0: [drm:bxt_get_dpll [i915]] [CRTC:146:pipe C] using pre-allocated PORT PLL C
<7> [59.486307] i915 0000:00:02.0: [drm:intel_reference_shared_dpll_crtc [i915]] [CRTC:146:pipe C] reserving PORT PLL C
<7> [59.486719] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:146:pipe C] enable: yes [modeset]
<7> [59.487262] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [59.487639] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: C, pipe bpp: 36, dithering: 0
<7> [59.488007] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [59.488528] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [59.488902] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [59.489445] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [59.489818] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0
<7> [59.490813] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [59.491342] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] GCP: 0x6
<7> [59.491715] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [59.491719] i915 0000:00:02.0: colorspace: RGB
<7> [59.491723] i915 0000:00:02.0: scan mode: Underscan
<7> [59.491726] i915 0000:00:02.0: colorimetry: No Data
<7> [59.491729] i915 0000:00:02.0: picture aspect: No Data
<7> [59.491732] i915 0000:00:02.0: active aspect: Same as Picture
<7> [59.491735] i915 0000:00:02.0: itc: No Data
<7> [59.491739] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [59.491742] i915 0000:00:02.0: quantization range: Full
<7> [59.491745] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [59.491748] i915 0000:00:02.0: video code: 0
<7> [59.491751] i915 0000:00:02.0: ycc quantization range: Full
<7> [59.491754] i915 0000:00:02.0: hdmi content type: Graphics
<7> [59.491758] i915 0000:00:02.0: pixel repeat: 0
<7> [59.491761] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [59.491765] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [59.491769] i915 0000:00:02.0: vendor: Intel
<7> [59.491772] i915 0000:00:02.0: product: Integrated gfx
<7> [59.491775] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [59.491779] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [59.491782] i915 0000:00:02.0: empty frame
<7> [59.491787] ELD: 10 00 07 00 66 10 00 01 00 00 00 00 00 00 00 00
<7> [59.491791] ELD: 05 e3 cd 0c 32 38 45 38 35 30 09 07 07 00 00 00
<7> [59.491794] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, vmin: 0, vmax: 0, pipeline full: 0, guardband: 0 flipline: 0, vmin vblank: -1, vmax vblank: -2
<7> [59.492412] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9
<7> [59.492787] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9
<7> [59.493303] i915 0000:00:02.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=1083-1088 vt=1125, flags=0x9
<7> [59.493679] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x40 0x9
<7> [59.494048] i915 0000:00:02.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=1083-1088 vt=1125, flags=0x9
<7> [59.495461] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 222750, pipe src: 1920x1080+0+0, pixel rate 148500
<7> [59.495922] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 59, ips linetime: 0
<7> [59.496445] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 1, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [59.496818] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [59.497344] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [59.497716] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: ebb0: 0x6200, ebb4: 0x2000,pll0: 0x21, pll1: 0x100, pll2: 0x1a6666, pll3: 0x10000, pll6: 0x30904, pll8: 0x8, pll9: 0xa, pll10: 0x8003c00, pcsdw12: 0x4d
<7> [59.498087] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [59.498602] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [59.499305] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [59.499676] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [59.500043] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [59.500946] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [59.501457] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [59.501831] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:109:plane 1C] fb: [FB:170] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [59.502359] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [59.502729] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [59.503098] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:117:plane 2C] fb: [NOFB], visible: no
<7> [59.503632] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:125:plane 3C] fb: [NOFB], visible: no
<7> [59.504001] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:133:plane 4C] fb: [NOFB], visible: no
<7> [59.505035] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:141:cursor C] fb: [NOFB], visible: no
<7> [59.505990] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling always-on
<7> [59.506533] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [59.507544] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 01 to 00
<7> [59.509028] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_2
<7> [59.510191] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling dpio-common-c
<7> [59.511203] i915 0000:00:02.0: [drm:intel_set_cdclk [i915]] Pre changing CDCLK to 316800 kHz, VCO 633600 kHz, ref 19200 kHz, bypass 19200 kHz, voltage level 13
<7> [59.511806] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:147:DDI B/PHY B]
<7> [59.512827] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:157:DDI C/PHY C]
<7> [59.513587] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enable PORT PLL C (active 0x4, on? 0) for [CRTC:146:pipe C]
<7> [59.513964] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enabling PORT PLL C
<7> [59.514645] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_C
<7> [59.515127] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe C
<7> [59.533679] i915 0000:00:02.0: [drm:intel_audio_codec_enable [i915]] [CONNECTOR:158:HDMI-A-2][ENCODER:157:DDI C/PHY C] Enable audio codec on [CRTC:146:pipe C], 32 bytes ELD
<7> [59.550294] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] Configuring HDMI audio for pixel clock 148500 (0x00090000)
<7> [59.550705] i915 0000:00:02.0: [drm:hsw_hdmi_audio_config_update.isra.0 [i915]] using automatic N
<7> [59.551223] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:158:HDMI-A-2]
<7> [59.552178] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:146:pipe C]
<7> [59.553941] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:108:pipe B]
<7> [59.553995] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:148:HDMI-A-1]
<7> [59.554127] i915 0000:00:02.0: [drm:intel_hdmi_compute_clock [i915]] picking 12 bpc for HDMI output (pipe bpp: 36)
<7> [59.554561] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:108:pipe B] hw max bpp: 36, pipe bpp: 36, dithering: 0
<7> [59.555044] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:147:DDI B/PHY B] [CRTC:108:pipe B]
<7> [59.555400] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe B] fastset requirement not met in hw.enable (expected no, found yes)
<7> [59.555974] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe B] fastset requirement not met in hw.active (expected no, found yes)
<7> [59.556347] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe B] fastset requirement not met in cpu_transcoder (expected -1, found 1)
<7> [59.557369] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe B] fastset requirement not met in lane_count (expected 0, found 4)
<7> [59.557958] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe B] fastset requirement not met in lane_lat_optim_mask (expected 0x00000000, found 0x0000000d)
<7> [59.558333] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe B] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [59.558848] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe B] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [59.559221] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [59.559589] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [59.560138] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [59.560510] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [59.561587] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [59.562197] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [59.562571] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [59.563082] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [59.563453] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1083)
<7> [59.563979] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1088)
<7> [59.564350] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [59.564871] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [59.565820] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [59.566191] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [59.566558] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [59.567132] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [59.567526] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [59.568040] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [59.568412] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [59.568918] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [59.569289] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1083)
<7> [59.569656] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1088)
<7> [59.570741] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [59.571117] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [59.571485] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe B] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [59.572083] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe B] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [59.572536] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe B] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [59.573073] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe B] fastset requirement not met in has_hdmi_sink (expected no, found yes)
<7> [59.573443] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe B] fastset requirement not met in has_infoframe (expected no, found yes)
<7> [59.573965] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe B] fastset requirement not met in pipe_bpp (expected 0, found 36)
<7> [59.574338] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [59.574706] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [59.575233] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe B] fastset requirement not met in port_clock (expected 0, found 222750)
<7> [59.576297] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe B] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071)
<7> [59.576671] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe B] fastset requirement not met in infoframes.gcp (expected 0x00000000, found 0x00000006)
<7> [59.577196] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe B] fastset requirement not met in avi infoframe
<7> [59.577570] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [59.578095] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [59.578465] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [59.578470] i915 0000:00:02.0: colorspace: RGB
<7> [59.578474] i915 0000:00:02.0: scan mode: Underscan
<7> [59.578477] i915 0000:00:02.0: colorimetry: No Data
<7> [59.578480] i915 0000:00:02.0: picture aspect: No Data
<7> [59.578484] i915 0000:00:02.0: active aspect: Same as Picture
<7> [59.578487] i915 0000:00:02.0: itc: No Data
<7> [59.578490] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [59.578494] i915 0000:00:02.0: quantization range: Full
<7> [59.578497] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [59.578501] i915 0000:00:02.0: video code: 0
<7> [59.578504] i915 0000:00:02.0: ycc quantization range: Full
<7> [59.578507] i915 0000:00:02.0: hdmi content type: Graphics
<7> [59.578510] i915 0000:00:02.0: pixel repeat: 0
<7> [59.578514] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [59.578518] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe B] fastset requirement not met in spd infoframe
<7> [59.579087] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [59.579456] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [59.580745] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [59.580750] i915 0000:00:02.0: vendor: Intel
<7> [59.580754] i915 0000:00:02.0: product: Integrated gfx
<7> [59.580757] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [59.580761] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe B] fastset requirement not met in hdmi infoframe
<7> [59.581393] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [59.581763] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [59.582290] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [59.582294] i915 0000:00:02.0: empty frame
<7> [59.582299] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:108:pipe B] fastset requirement not met, forcing full modeset
<7> [59.582711] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:108:pipe B] dbuf slices 0x0 -> 0x1, ddb (0 - 0) -> (0 - 510), active pipes 0x4 -> 0x6
<7> [59.583246] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:146:pipe C] dbuf slices 0x1 -> 0x1, ddb (0 - 1020) -> (510 - 1020), active pipes 0x4 -> 0x6
<7> [59.583683] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:71:plane 1B] ddb ( 0 - 0) -> ( 0 - 502), size 0 -> 502
<7> [59.584785] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:103:cursor B] ddb ( 0 - 0) -> ( 502 - 510), size 0 -> 8
<7> [59.585311] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:71:plane 1B] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm
<7> [59.585670] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:71:plane 1B] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 1, 1, 2, 2, 2, 2, 2, 0, 0, 0
<7> [59.586172] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:71:plane 1B] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 16, 16, 33, 33, 33, 33, 33, 30, 0, 0
<7> [59.586530] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:71:plane 1B] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 17, 17, 17, 34, 34, 34, 34, 34, 31, 0, 0
<7> [59.587040] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:109:plane 1C] ddb ( 0 - 988) -> ( 510 - 1012), size 988 -> 502
<7> [59.587395] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:141:cursor C] ddb ( 988 - 1020) -> (1012 - 1020), size 32 -> 8
<7> [59.587772] i915 0000:00:02.0: [drm:intel_plane_calc_min_cdclk [i915]] [PLANE:71:plane 1B] min cdclk (74250 kHz) > [CRTC:108:pipe B] min cdclk (0 kHz)
<7> [59.588792] i915 0000:00:02.0: [drm:intel_bw_calc_min_cdclk [i915]] new bandwidth min cdclk (18563 kHz) > old min cdclk (9282 kHz)
<7> [59.589380] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 316800 kHz, actual 316800 kHz
<7> [59.589755] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 13, actual 13
<7> [59.590285] i915 0000:00:02.0: [drm:bxt_get_dpll [i915]] [CRTC:108:pipe B] using pre-allocated PORT PLL B
<7> [59.590659] i915 0000:00:02.0: [drm:intel_reference_shared_dpll_crtc [i915]] [CRTC:108:pipe B] reserving PORT PLL B
<7> [59.591188] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:108:pipe B] enable: yes [modeset]
<7> [59.591562] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [59.592072] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: B, pipe bpp: 36, dithering: 0
<7> [59.592444] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [59.592811] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [59.593844] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [59.594366] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [59.594739] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0
<7> [59.595249] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [59.595620] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] GCP: 0x6
<7> [59.596141] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [59.596146] i915 0000:00:02.0: colorspace: RGB
<7> [59.596150] i915 0000:00:02.0: scan mode: Underscan
<7> [59.596153] i915 0000:00:02.0: colorimetry: No Data
<7> [59.596156] i915 0000:00:02.0: picture aspect: No Data
<7> [59.596160] i915 0000:00:02.0: active aspect: Same as Picture
<7> [59.596163] i915 0000:00:02.0: itc: No Data
<7> [59.596166] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [59.596170] i915 0000:00:02.0: quantization range: Full
<7> [59.596173] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [59.596176] i915 0000:00:02.0: video code: 0
<7> [59.596179] i915 0000:00:02.0: ycc quantization range: Full
<7> [59.596183] i915 0000:00:02.0: hdmi content type: Graphics
<7> [59.596186] i915 0000:00:02.0: pixel repeat: 0
<7> [59.596189] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [59.596193] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [59.596196] i915 0000:00:02.0: vendor: Intel
<7> [59.596200] i915 0000:00:02.0: product: Integrated gfx
<7> [59.596203] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [59.596207] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [59.596210] i915 0000:00:02.0: empty frame
<7> [59.596215] ELD: 10 00 07 00 66 10 00 01 00 00 00 00 00 00 00 00
<7> [59.596218] ELD: 05 e3 cd 0c 32 38 45 38 35 30 09 07 07 00 00 00
<7> [59.596222] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, vmin: 0, vmax: 0, pipeline full: 0, guardband: 0 flipline: 0, vmin vblank: -1, vmax vblank: -2
<7> [59.596594] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9
<7> [59.598137] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9
<7> [59.598594] i915 0000:00:02.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=1083-1088 vt=1125, flags=0x9
<7> [59.599115] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x40 0x9
<7> [59.599489] i915 0000:00:02.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=1083-1088 vt=1125, flags=0x9
<7> [59.599859] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 222750, pipe src: 1920x1080+0+0, pixel rate 148500
<7> [59.600389] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 59, ips linetime: 0
<7> [59.600761] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [59.601267] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [59.602108] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [59.602481] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: ebb0: 0x6200, ebb4: 0x2000,pll0: 0x21, pll1: 0x100, pll2: 0x1a6666, pll3: 0x10000, pll6: 0x30904, pll8: 0x8, pll9: 0xa, pll10: 0x8003c00, pcsdw12: 0x4d
<7> [59.602853] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [59.603750] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [59.604260] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [59.604634] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [59.605157] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [59.605528] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [59.605896] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [59.606428] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:71:plane 1B] fb: [FB:170] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [59.607149] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [59.607522] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [59.607892] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:79:plane 2B] fb: [NOFB], visible: no
<7> [59.608720] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:87:plane 3B] fb: [NOFB], visible: no
<7> [59.609258] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:95:plane 4B] fb: [NOFB], visible: no
<7> [59.609631] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:103:cursor B] fb: [NOFB], visible: no
<7> [59.610443] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling dpio-common-b
<7> [59.611213] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:147:DDI B/PHY B]
<7> [59.611582] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:157:DDI C/PHY C]
<7> [59.617020] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enable PORT PLL B (active 0x2, on? 0) for [CRTC:108:pipe B]
<7> [59.617441] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enabling PORT PLL B
<7> [59.617947] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_B
<7> [59.618909] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe B
<7> [59.636963] i915 0000:00:02.0: [drm:intel_audio_codec_enable [i915]] [CONNECTOR:148:HDMI-A-1][ENCODER:147:DDI B/PHY B] Enable audio codec on [CRTC:108:pipe B], 32 bytes ELD
<7> [59.653614] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] Configuring HDMI audio for pixel clock 148500 (0x00090000)
<7> [59.654003] i915 0000:00:02.0: [drm:hsw_hdmi_audio_config_update.isra.0 [i915]] using automatic N
<7> [59.655159] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:148:HDMI-A-1]
<7> [59.655711] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:108:pipe B]
<6> [68.117890] [IGT] kms_flip: finished subtest BC-HDMI-A1-HDMI-A2, FAIL
<6> [68.118045] [IGT] kms_flip: finished subtest 2x-plain-flip-ts-check, FAIL
<7> [68.132955] i915 0000:00:02.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:170] from all active usage due to RMFB ioctl
<7> [68.133055] i915 0000:00:02.0: [drm:atomic_remove_fb] Disabling [PLANE:71:plane 1B] because [FB:170] is removed
<7> [68.133094] i915 0000:00:02.0: [drm:atomic_remove_fb] Disabling [PLANE:109:plane 1C] because [FB:170] is removed
<7> [68.133183] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:71:plane 1B] ddb ( 0 - 502) -> ( 0 - 0), size 502 -> 0
<7> [68.133640] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:71:plane 1B] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [68.134913] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:71:plane 1B] lines 1, 1, 1, 2, 2, 2, 2, 2, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [68.135281] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:71:plane 1B] blocks 16, 16, 16, 33, 33, 33, 33, 33, 30, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [68.136032] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:71:plane 1B] min_ddb 17, 17, 17, 34, 34, 34, 34, 34, 31, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [68.136396] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:109:plane 1C] ddb ( 510 - 1012) -> ( 0 - 0), size 502 -> 0
<7> [68.136737] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:109: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> [68.137141] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:109:plane 1C] lines 1, 1, 1, 2, 2, 2, 2, 2, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [68.137542] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:109:plane 1C] blocks 16, 16, 16, 33, 33, 33, 33, 33, 30, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [68.137920] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:109:plane 1C] min_ddb 17, 17, 17, 34, 34, 34, 34, 34, 31, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<6> [68.162672] [IGT] kms_flip: exiting, ret=98
<7> [68.194855] i915 0000:00:02.0: [drm:intel_hdmi_compute_clock [i915]] picking 12 bpc for HDMI output (pipe bpp: 36)
<7> [68.195381] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:70:pipe A] hw max bpp: 36, pipe bpp: 36, dithering: 0
<7> [68.195803] i915 0000:00:02.0: [drm:intel_hdmi_compute_clock [i915]] picking 12 bpc for HDMI output (pipe bpp: 36)
<7> [68.196533] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:108:pipe B] hw max bpp: 36, pipe bpp: 36, dithering: 0
<7> [68.196941] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:147:DDI B/PHY B] [CRTC:70:pipe A]
<7> [68.197459] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:157:DDI C/PHY C] [CRTC:108:pipe B]
<7> [68.197818] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [68.198480] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [68.198856] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [68.199386] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [68.199758] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in lane_lat_optim_mask (expected 0x00000000, found 0x0000000d)
<7> [68.200286] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [68.200664] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [68.201032] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [68.201599] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [68.201970] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [68.202505] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [68.202875] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [68.203724] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [68.204258] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [68.204632] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [68.205004] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1083)
<7> [68.205533] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1088)
<7> [68.205908] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [68.206438] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [68.206811] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [68.207328] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [68.207702] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [68.208225] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [68.208597] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [68.208968] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [68.209520] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [68.209896] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [68.210419] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1083)
<7> [68.210798] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1088)
<7> [68.211316] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [68.211859] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [68.212394] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [68.212767] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [68.213285] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [68.213658] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in has_hdmi_sink (expected no, found yes)
<7> [68.214027] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in has_infoframe (expected no, found yes)
<7> [68.214569] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 36)
<7> [68.214947] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [68.215461] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [68.215834] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in port_clock (expected 0, found 222750)
<7> [68.216352] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071)
<7> [68.216724] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in infoframes.gcp (expected 0x00000000, found 0x00000006)
<7> [68.217257] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in avi infoframe
<7> [68.217630] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [68.217999] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [68.218513] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [68.218521] i915 0000:00:02.0: colorspace: RGB
<7> [68.218525] i915 0000:00:02.0: scan mode: Underscan
<7> [68.218529] i915 0000:00:02.0: colorimetry: No Data
<7> [68.218533] i915 0000:00:02.0: picture aspect: No Data
<7> [68.218537] i915 0000:00:02.0: active aspect: Same as Picture
<7> [68.218542] i915 0000:00:02.0: itc: No Data
<7> [68.218546] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [68.218550] i915 0000:00:02.0: quantization range: Full
<7> [68.218554] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [68.218558] i915 0000:00:02.0: video code: 0
<7> [68.218562] i915 0000:00:02.0: ycc quantization range: Full
<7> [68.218566] i915 0000:00:02.0: hdmi content type: Graphics
<7> [68.218570] i915 0000:00:02.0: pixel repeat: 0
<7> [68.218574] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [68.218578] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in spd infoframe
<7> [68.218950] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [68.219563] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [68.219932] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [68.219938] i915 0000:00:02.0: vendor: Intel
<7> [68.219942] i915 0000:00:02.0: product: Integrated gfx
<7> [68.219946] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [68.219950] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:70:pipe A] fastset requirement not met in hdmi infoframe
<7> [68.220485] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [68.220854] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [68.221326] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [68.221332] i915 0000:00:02.0: empty frame
<7> [68.221337] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:70:pipe A] fastset requirement not met, forcing full modeset
<7> [68.221782] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.enable (expected yes, found no)
<7> [68.222332] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.active (expected yes, found no)
<7> [68.222704] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in cpu_transcoder (expected 2, found -1)
<7> [68.223072] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in lane_count (expected 4, found 0)
<7> [68.223595] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in lane_lat_optim_mask (expected 0x0000000d, found 0x00000000)
<7> [68.223967] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [68.224420] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [68.224793] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [68.225375] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [68.225746] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [68.226115] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [68.226602] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [68.227039] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [68.227576] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [68.227947] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [68.228480] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1083, found 0)
<7> [68.228851] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1088, found 0)
<7> [68.229333] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [68.229767] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [68.230287] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [68.230658] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [68.231028] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [68.231563] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [68.231935] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [68.232453] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [68.232824] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [68.233351] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [68.233722] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1083, found 0)
<7> [68.234089] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1088, found 0)
<7> [68.234629] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [68.235004] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [68.235541] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [68.235914] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [68.236432] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [68.236802] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [68.238060] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [68.238589] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in pipe_bpp (expected 36, found 0)
<7> [68.238961] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [68.239495] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [68.239867] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in port_clock (expected 222750, found 0)
<7> [68.240391] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [68.240762] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in infoframes.gcp (expected 0x00000006, found 0x00000000)
<7> [68.241131] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in avi infoframe
<7> [68.241668] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [68.242037] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [68.242042] i915 0000:00:02.0: colorspace: RGB
<7> [68.242049] i915 0000:00:02.0: scan mode: Underscan
<7> [68.242052] i915 0000:00:02.0: colorimetry: No Data
<7> [68.242056] i915 0000:00:02.0: picture aspect: No Data
<7> [68.242060] i915 0000:00:02.0: active aspect: Same as Picture
<7> [68.242064] i915 0000:00:02.0: itc: No Data
<7> [68.242068] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [68.242071] i915 0000:00:02.0: quantization range: Full
<7> [68.242075] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [68.242079] i915 0000:00:02.0: video code: 0
<7> [68.242082] i915 0000:00:02.0: ycc quantization range: Full
<7> [68.242086] i915 0000:00:02.0: hdmi content type: Graphics
<7> [68.242090] i915 0000:00:02.0: pixel repeat: 0
<7> [68.242094] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [68.242098] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [68.242685] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in spd infoframe
<7> [68.243059] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [68.243577] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [68.243582] i915 0000:00:02.0: vendor: Intel
<7> [68.243586] i915 0000:00:02.0: product: Integrated gfx
<7> [68.243590] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [68.243594] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [68.243965] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:146:pipe C] fastset requirement not met in hdmi infoframe
<7> [68.244428] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [68.244797] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [68.244801] i915 0000:00:02.0: empty frame
<7> [68.244805] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [68.245363] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:146:pipe C] fastset requirement not met, forcing full modeset
<7> [68.245738] i915 0000:00:02.0: [drm:intel_unreference_shared_dpll_crtc [i915]] [CRTC:108:pipe B] releasing PORT PLL B
<7> [68.246109] i915 0000:00:02.0: [drm:intel_unreference_shared_dpll_crtc [i915]] [CRTC:146:pipe C] releasing PORT PLL C
<7> [68.246713] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:70:pipe A] dbuf slices 0x0 -> 0x1, ddb (0 - 0) -> (0 - 510), active pipes 0x6 -> 0x3
<7> [68.247087] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:108:pipe B] dbuf slices 0x1 -> 0x1, ddb (0 - 510) -> (510 - 1020), active pipes 0x6 -> 0x3
<7> [68.247592] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:146:pipe C] dbuf slices 0x1 -> 0x0, ddb (510 - 1020) -> (0 - 0), active pipes 0x6 -> 0x3
<7> [68.247987] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:33:plane 1A] ddb ( 0 - 0) -> ( 0 - 502), size 0 -> 502
<7> [68.248531] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:65:cursor A] ddb ( 0 - 0) -> ( 502 - 510), size 0 -> 8
<7> [68.248890] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:33:plane 1A] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm
<7> [68.249395] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:33:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 1, 1, 2, 2, 2, 2, 2, 0, 0, 0
<7> [68.249755] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:33:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 16, 16, 33, 33, 33, 33, 33, 30, 0, 0
<7> [68.250110] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:33:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 17, 17, 17, 34, 34, 34, 34, 34, 31, 0, 0
<7> [68.250656] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:71:plane 1B] ddb ( 0 - 0) -> ( 510 - 1012), size 0 -> 502
<7> [68.251016] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:103:cursor B] ddb ( 502 - 510) -> (1012 - 1020), size 8 -> 8
<7> [68.251522] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:71:plane 1B] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm
<7> [68.251878] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:71:plane 1B] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 1, 1, 2, 2, 2, 2, 2, 0, 0, 0
<7> [68.252377] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:71:plane 1B] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 16, 16, 33, 33, 33, 33, 33, 30, 0, 0
<7> [68.252737] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:71:plane 1B] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 17, 17, 17, 34, 34, 34, 34, 34, 31, 0, 0
<7> [68.253092] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:141:cursor C] ddb (1012 - 1020) -> ( 0 - 0), size 8 -> 0
<7> [68.253639] i915 0000:00:02.0: [drm:intel_plane_calc_min_cdclk [i915]] [PLANE:33:plane 1A] min cdclk (74250 kHz) > [CRTC:70:pipe A] min cdclk (0 kHz)
<7> [68.254039] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 316800 kHz, actual 316800 kHz
<7> [68.254517] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 13, actual 13
<7> [68.254964] i915 0000:00:02.0: [drm:bxt_get_dpll [i915]] [CRTC:70:pipe A] using pre-allocated PORT PLL B
<7> [68.255487] i915 0000:00:02.0: [drm:intel_reference_shared_dpll_crtc [i915]] [CRTC:70:pipe A] reserving PORT PLL B
<7> [68.255864] i915 0000:00:02.0: [drm:bxt_get_dpll [i915]] [CRTC:108:pipe B] using pre-allocated PORT PLL C
<7> [68.256396] i915 0000:00:02.0: [drm:intel_reference_shared_dpll_crtc [i915]] [CRTC:108:pipe B] reserving PORT PLL C
<7> [68.256772] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:70:pipe A] enable: yes [modeset]
<7> [68.257144] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [68.257689] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 36, dithering: 0
<7> [68.258059] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [68.258584] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [68.258958] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [68.259474] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [68.259846] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0
<7> [68.260369] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [68.260742] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] GCP: 0x6
<7> [68.261109] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [68.261114] i915 0000:00:02.0: colorspace: RGB
<7> [68.261117] i915 0000:00:02.0: scan mode: Underscan
<7> [68.261121] i915 0000:00:02.0: colorimetry: No Data
<7> [68.261125] i915 0000:00:02.0: picture aspect: No Data
<7> [68.261129] i915 0000:00:02.0: active aspect: Same as Picture
<7> [68.261132] i915 0000:00:02.0: itc: No Data
<7> [68.261136] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [68.261140] i915 0000:00:02.0: quantization range: Full
<7> [68.261144] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [68.261148] i915 0000:00:02.0: video code: 0
<7> [68.261151] i915 0000:00:02.0: ycc quantization range: Full
<7> [68.261155] i915 0000:00:02.0: hdmi content type: Graphics
<7> [68.261159] i915 0000:00:02.0: pixel repeat: 0
<7> [68.261163] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [68.261167] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [68.261171] i915 0000:00:02.0: vendor: Intel
<7> [68.261175] i915 0000:00:02.0: product: Integrated gfx
<7> [68.261179] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [68.261183] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [68.261187] i915 0000:00:02.0: empty frame
<7> [68.261192] ELD: 10 00 07 00 66 10 00 01 00 00 00 00 00 00 00 00
<7> [68.261196] ELD: 05 e3 cd 0c 32 38 45 38 35 30 09 07 07 00 00 00
<7> [68.261200] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, vmin: 0, vmax: 0, pipeline full: 0, guardband: 0 flipline: 0, vmin vblank: -1, vmax vblank: -2
<7> [68.261819] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9
<7> [68.262192] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9
<7> [68.262669] i915 0000:00:02.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=1083-1088 vt=1125, flags=0x9
<7> [68.263128] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x40 0x9
<7> [68.263614] i915 0000:00:02.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=1083-1088 vt=1125, flags=0x9
<7> [68.263987] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 222750, pipe src: 1920x1080+0+0, pixel rate 148500
<7> [68.264549] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 59, ips linetime: 0
<7> [68.264923] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [68.265462] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [68.265838] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [68.266207] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: ebb0: 0x6200, ebb4: 0x2000,pll0: 0x21, pll1: 0x100, pll2: 0x1a6666, pll3: 0x10000, pll6: 0x30904, pll8: 0x8, pll9: 0xa, pll10: 0x8003c00, pcsdw12: 0x4d
<7> [68.266749] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [68.267120] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [68.267597] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [68.267967] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [68.268484] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [68.268855] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [68.269224] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [68.269764] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:33:plane 1A] fb: [FB:163] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [68.270142] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [68.270621] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [68.270995] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:41:plane 2A] fb: [NOFB], visible: no
<7> [68.271513] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:49:plane 3A] fb: [NOFB], visible: no
<7> [68.271886] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:57:plane 4A] fb: [NOFB], visible: no
<7> [68.272429] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:65:cursor A] fb: [NOFB], visible: no
<7> [68.272804] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:108:pipe B] enable: yes [modeset]
<7> [68.273176] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [68.273724] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: B, pipe bpp: 36, dithering: 0
<7> [68.274095] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [68.274593] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [68.274964] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [68.275482] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [68.275855] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0
<7> [68.276224] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [68.276780] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] GCP: 0x6
<7> [68.277154] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [68.277159] i915 0000:00:02.0: colorspace: RGB
<7> [68.277163] i915 0000:00:02.0: scan mode: Underscan
<7> [68.277166] i915 0000:00:02.0: colorimetry: No Data
<7> [68.277170] i915 0000:00:02.0: picture aspect: No Data
<7> [68.277174] i915 0000:00:02.0: active aspect: Same as Picture
<7> [68.277177] i915 0000:00:02.0: itc: No Data
<7> [68.277181] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [68.277185] i915 0000:00:02.0: quantization range: Full
<7> [68.277189] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [68.277192] i915 0000:00:02.0: video code: 0
<7> [68.277196] i915 0000:00:02.0: ycc quantization range: Full
<7> [68.277200] i915 0000:00:02.0: hdmi content type: Graphics
<7> [68.277204] i915 0000:00:02.0: pixel repeat: 0
<7> [68.277207] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [68.277212] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [68.277216] i915 0000:00:02.0: vendor: Intel
<7> [68.277220] i915 0000:00:02.0: product: Integrated gfx
<7> [68.277223] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [68.277228] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [68.277232] i915 0000:00:02.0: empty frame
<7> [68.277235] ELD: 10 00 07 00 66 10 00 01 00 00 00 00 00 00 00 00
<7> [68.277239] ELD: 05 e3 cd 0c 32 38 45 38 35 30 09 07 07 00 00 00
<7> [68.277243] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, vmin: 0, vmax: 0, pipeline full: 0, guardband: 0 flipline: 0, vmin vblank: -1, vmax vblank: -2
<7> [68.277834] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9
<7> [68.278210] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9
<7> [68.278743] i915 0000:00:02.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=1083-1088 vt=1125, flags=0x9
<7> [68.279118] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x40 0x9
<7> [68.279614] i915 0000:00:02.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=1083-1088 vt=1125, flags=0x9
<7> [68.279989] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 222750, pipe src: 1920x1080+0+0, pixel rate 148500
<7> [68.280432] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 59, ips linetime: 0
<7> [68.280802] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [68.281171] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [68.281765] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [68.282138] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: ebb0: 0x6200, ebb4: 0x2000,pll0: 0x21, pll1: 0x100, pll2: 0x1a6666, pll3: 0x10000, pll6: 0x30904, pll8: 0x8, pll9: 0xa, pll10: 0x8003c00, pcsdw12: 0x4d
<7> [68.282619] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [68.282992] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [68.283514] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [68.283885] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [68.284252] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [68.284790] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [68.285161] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [68.285639] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:71:plane 1B] fb: [FB:163] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [68.286013] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [68.286528] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [68.286902] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:79:plane 2B] fb: [NOFB], visible: no
<7> [68.287271] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:87:plane 3B] fb: [NOFB], visible: no
<7> [68.287815] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:95:plane 4B] fb: [NOFB], visible: no
<7> [68.288187] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:103:cursor B] fb: [NOFB], visible: no
<7> [68.288714] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:146:pipe C] enable: no [modeset]
<7> [68.289086] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:109:plane 1C] fb: [NOFB], visible: no
<7> [68.289564] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:117:plane 2C] fb: [NOFB], visible: no
<7> [68.289937] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:125:plane 3C] fb: [NOFB], visible: no
<7> [68.290468] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:133:plane 4C] fb: [NOFB], visible: no
<7> [68.290844] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:141:cursor C] fb: [NOFB], visible: no
<7> [68.291684] i915 0000:00:02.0: [drm:intel_audio_codec_disable [i915]] [CONNECTOR:148:HDMI-A-1][ENCODER:147:DDI B/PHY B] Disable audio codec on [CRTC:108:pipe B]
<7> [68.320291] i915 0000:00:02.0: [drm:intel_audio_codec_disable [i915]] [CONNECTOR:158:HDMI-A-2][ENCODER:157:DDI C/PHY C] Disable audio codec on [CRTC:146:pipe C]
<7> [68.351283] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe B
<7> [68.354528] i915 0000:00:02.0: [drm:intel_ddi_disable_transcoder_func [i915]] Quirk Increase DDI disabled time
<7> [68.457818] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_B
<7> [68.458338] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disable PORT PLL B (active 0x2, on? 1) for [CRTC:108:pipe B]
<7> [68.459440] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disabling PORT PLL B
<7> [68.460242] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe C
<7> [68.467805] i915 0000:00:02.0: [drm:intel_ddi_disable_transcoder_func [i915]] Quirk Increase DDI disabled time
<7> [68.569413] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_C
<7> [68.570869] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disable PORT PLL C (active 0x4, on? 1) for [CRTC:146:pipe C]
<7> [68.573303] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disabling PORT PLL C
<7> [68.573710] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:147:DDI B/PHY B]
<7> [68.574421] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:157:DDI C/PHY C]
<7> [68.575441] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enable PORT PLL B (active 0x1, on? 0) for [CRTC:70:pipe A]
<7> [68.575824] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enabling PORT PLL B
<7> [68.577243] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_B
<7> [68.578452] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [68.580072] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enable PORT PLL C (active 0x2, on? 0) for [CRTC:108:pipe B]
<7> [68.580536] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enabling PORT PLL C
<7> [68.581258] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_C
<7> [68.582264] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe B
<7> [68.583418] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] reserved 16588800 bytes of contiguous stolen space for FBC, limit: 1
<7> [68.583893] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:33:plane 1A]
<7> [68.600219] i915 0000:00:02.0: [drm:intel_audio_codec_enable [i915]] [CONNECTOR:148:HDMI-A-1][ENCODER:147:DDI B/PHY B] Enable audio codec on [CRTC:70:pipe A], 32 bytes ELD
<7> [68.613418] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] Configuring HDMI audio for pixel clock 148500 (0x00090000)
<7> [68.613864] i915 0000:00:02.0: [drm:hsw_hdmi_audio_config_update.isra.0 [i915]] using automatic N
<7> [68.614470] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:148:HDMI-A-1]
<7> [68.614988] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:70:pipe A]
<7> [68.616335] i915 0000:00:02.0: [drm:intel_audio_codec_enable [i915]] [CONNECTOR:158:HDMI-A-2][ENCODER:157:DDI C/PHY C] Enable audio codec on [CRTC:108:pipe B], 32 bytes ELD
<7> [68.633359] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] Configuring HDMI audio for pixel clock 148500 (0x00090000)
<7> [68.633903] i915 0000:00:02.0: [drm:hsw_hdmi_audio_config_update.isra.0 [i915]] using automatic N
<7> [68.634928] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:158:HDMI-A-2]
<7> [68.635752] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:108:pipe B]
<7> [68.637865] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:146:pipe C]
<7> [68.640182] i915 0000:00:02.0: [drm:drm_client_dev_restore] intel-fbdev: ret=0
Created at 2024-10-15 17:33:42