Machine description: shard-glk7
Result:
integration-manifest git-log-oneline i915_display_info13 igt_runner13 run13 runtimes13 results13.json i915_display_info_post_exec13 boot13 dmesg13
Detail | Value |
---|---|
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
|