Result:
integration-manifest git-log-oneline i915_display_info10 igt_runner10 runtimes10 results10.json results10-i915-load.json guc_logs10.tar i915_display_info_post_exec10 boot10 dmesg10
| Detail | Value |
|---|---|
| Duration | 3.97 seconds |
| Hostname |
shard-dg1-12 |
| Igt-Version |
IGT-Version: 2.4-g4568b2c14 (x86_64) (Linux: 7.1.0-rc1-Patchwork_165534v2-ge066eb26712b+ x86_64) |
| Out |
Starting dynamic subtest: D-HDMI-A3 1920x1080: 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5 Stack trace: #0 ../lib/igt_core.c:2075 __igt_fail_assert() #1 ../tests/kms_flip.c:926 run_test_on_crtc_set() #2 ../tests/kms_flip.c:1920 run_test() #3 ../tests/kms_flip.c:2184 __igt_unique____real_main2105() #4 ../tests/kms_flip.c:2105 main() #5 [__libc_init_first+0x8a] #6 [__libc_start_main+0x8b] #7 [_start+0x25] Dynamic subtest D-HDMI-A3: FAIL (3.968s) Subtest flip-vs-expired-vblank: FAIL (27.335s) Stack trace: #0 ../lib/igt_core.c:2075 __igt_fail_assert() #1 ../lib/igt_kms.c:2352 kmstest_set_connector_dpms() #2 ../lib/igt_core.c:2961 igt_atexit_handler() #3 [erand48_r+0x1d6] #4 [exit+0x1e] #5 ../lib/igt_core.c:2416 igt_exit() #6 [main+0x3f] #7 [__libc_init_first+0x8a] #8 [__libc_start_main+0x8b] #9 [_start+0x25] |
| Err |
Starting dynamic subtest: D-HDMI-A3 (kms_flip:2048) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931: (kms_flip:2048) CRITICAL: Failed assertion: end - start < 500 Dynamic subtest D-HDMI-A3 failed. **** DEBUG **** (kms_flip:2048) DEBUG: name = flip last_ts = 90.141441 last_received_ts = 90.141075 last_seq = 86 current_ts = 90.158112 current_received_ts = 90.157700 current_seq = 87 count = 83 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 57us (kms_flip:2048) DEBUG: name = flip last_ts = 90.158112 last_received_ts = 90.157700 last_seq = 87 current_ts = 90.174774 current_received_ts = 90.174370 current_seq = 88 count = 84 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 57us (kms_flip:2048) DEBUG: name = flip last_ts = 90.174774 last_received_ts = 90.174370 last_seq = 88 current_ts = 90.191437 current_received_ts = 90.191071 current_seq = 89 count = 85 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 57us (kms_flip:2048) DEBUG: name = flip last_ts = 90.191437 last_received_ts = 90.191071 last_seq = 89 current_ts = 90.208107 current_received_ts = 90.207703 current_seq = 90 count = 86 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 57us (kms_flip:2048) DEBUG: name = flip last_ts = 90.208107 last_received_ts = 90.207703 last_seq = 90 current_ts = 90.224770 current_received_ts = 90.224365 current_seq = 91 count = 87 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 57us (kms_flip:2048) DEBUG: name = flip last_ts = 90.224770 last_received_ts = 90.224365 last_seq = 91 current_ts = 90.241440 current_received_ts = 90.241074 current_seq = 92 count = 88 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 60us (kms_flip:2048) DEBUG: name = flip last_ts = 90.241440 last_received_ts = 90.241074 last_seq = 92 current_ts = 90.258102 current_received_ts = 90.257706 current_seq = 93 count = 89 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 57us (kms_flip:2048) DEBUG: name = flip last_ts = 90.258102 last_received_ts = 90.257706 last_seq = 93 current_ts = 90.274773 current_received_ts = 90.274368 current_seq = 94 count = 90 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 56us (kms_flip:2048) DEBUG: name = flip last_ts = 90.274773 last_received_ts = 90.274368 last_seq = 94 current_ts = 90.291435 current_received_ts = 90.291084 current_seq = 95 count = 91 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 57us (kms_flip:2048) DEBUG: name = flip last_ts = 90.291435 last_received_ts = 90.291084 last_seq = 95 current_ts = 90.308098 current_received_ts = 90.307693 current_seq = 96 count = 92 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 56us (kms_flip:2048) DEBUG: name = flip last_ts = 90.308098 last_received_ts = 90.307693 last_seq = 96 current_ts = 90.324768 current_received_ts = 90.324364 current_seq = 97 count = 93 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 56us (kms_flip:2048) DEBUG: name = flip last_ts = 90.324768 last_received_ts = 90.324364 last_seq = 97 current_ts = 90.341431 current_received_ts = 90.341064 current_seq = 98 count = 94 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 57us (kms_flip:2048) DEBUG: name = flip last_ts = 90.341431 last_received_ts = 90.341064 last_seq = 98 current_ts = 90.358101 current_received_ts = 90.357697 current_seq = 99 count = 95 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 57us (kms_flip:2048) DEBUG: name = flip last_ts = 90.358101 last_received_ts = 90.357697 last_seq = 99 current_ts = 90.374756 current_received_ts = 90.374367 current_seq = 100 count = 96 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 56us (kms_flip:2048) DEBUG: name = flip last_ts = 90.374756 last_received_ts = 90.374367 last_seq = 100 current_ts = 90.391434 current_received_ts = 90.391068 current_seq = 101 count = 97 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 58us (kms_flip:2048) DEBUG: name = flip last_ts = 90.391434 last_received_ts = 90.391068 last_seq = 101 current_ts = 90.408096 current_received_ts = 90.407692 current_seq = 102 count = 98 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 56us (kms_flip:2048) DEBUG: name = flip last_ts = 90.408096 last_received_ts = 90.407692 last_seq = 102 current_ts = 90.424767 current_received_ts = 90.424362 current_seq = 103 count = 99 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 56us (kms_flip:2048) DEBUG: name = flip last_ts = 90.424767 last_received_ts = 90.424362 last_seq = 103 current_ts = 90.441429 current_received_ts = 90.441071 current_seq = 104 count = 100 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 56us (kms_flip:2048) DEBUG: name = flip last_ts = 90.441429 last_received_ts = 90.441071 last_seq = 104 current_ts = 90.458092 current_received_ts = 90.457687 current_seq = 105 count = 101 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 56us (kms_flip:2048) DEBUG: name = flip last_ts = 90.458092 last_received_ts = 90.457687 last_seq = 105 current_ts = 90.474754 current_received_ts = 90.474350 current_seq = 106 count = 102 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 57us (kms_flip:2048) DEBUG: name = flip last_ts = 90.474754 last_received_ts = 90.474350 last_seq = 106 current_ts = 90.491425 current_received_ts = 90.490997 current_seq = 107 count = 103 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 54us (kms_flip:2048) DEBUG: name = flip last_ts = 90.491425 last_received_ts = 90.490997 last_seq = 107 current_ts = 90.508080 current_received_ts = 90.507652 current_seq = 108 count = 104 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 52us (kms_flip:2048) DEBUG: name = flip last_ts = 90.508080 last_received_ts = 90.507652 last_seq = 108 current_ts = 90.524750 current_received_ts = 90.524315 current_seq = 109 count = 105 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 52us (kms_flip:2048) DEBUG: name = flip last_ts = 90.524750 last_received_ts = 90.524315 last_seq = 109 current_ts = 90.541412 current_received_ts = 90.540985 current_seq = 110 count = 106 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 52us (kms_flip:2048) DEBUG: name = flip last_ts = 90.541412 last_received_ts = 90.540985 last_seq = 110 current_ts = 90.558083 current_received_ts = 90.557648 current_seq = 111 count = 107 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 52us (kms_flip:2048) DEBUG: name = flip last_ts = 90.558083 last_received_ts = 90.557648 last_seq = 111 current_ts = 90.574753 current_received_ts = 90.574318 current_seq = 112 count = 108 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 52us (kms_flip:2048) DEBUG: name = flip last_ts = 90.574753 last_received_ts = 90.574318 last_seq = 112 current_ts = 90.591415 current_received_ts = 90.590981 current_seq = 113 count = 109 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 53us (kms_flip:2048) DEBUG: name = flip last_ts = 90.591415 last_received_ts = 90.590981 last_seq = 113 current_ts = 90.608078 current_received_ts = 90.607643 current_seq = 114 count = 110 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 52us (kms_flip:2048) DEBUG: name = flip last_ts = 90.608078 last_received_ts = 90.607643 last_seq = 114 current_ts = 90.624748 current_received_ts = 90.624298 current_seq = 115 count = 111 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 52us (kms_flip:2048) DEBUG: name = flip last_ts = 90.624748 last_received_ts = 90.624298 last_seq = 115 current_ts = 90.641411 current_received_ts = 90.640984 current_seq = 116 count = 112 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 53us (kms_flip:2048) DEBUG: name = flip last_ts = 90.641411 last_received_ts = 90.640984 last_seq = 116 current_ts = 90.658073 current_received_ts = 90.657639 current_seq = 117 count = 113 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 52us (kms_flip:2048) DEBUG: name = flip last_ts = 90.658073 last_received_ts = 90.657639 last_seq = 117 current_ts = 90.674744 current_received_ts = 90.674301 current_seq = 118 count = 114 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 52us (kms_flip:2048) DEBUG: name = flip last_ts = 90.674744 last_received_ts = 90.674301 last_seq = 118 current_ts = 90.691406 current_received_ts = 90.690979 current_seq = 119 count = 115 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 53us (kms_flip:2048) DEBUG: name = flip last_ts = 90.691406 last_received_ts = 90.690979 last_seq = 119 current_ts = 90.708076 current_received_ts = 90.707642 current_seq = 120 count = 116 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 52us (kms_flip:2048) DEBUG: name = flip last_ts = 90.708076 last_received_ts = 90.707642 last_seq = 120 current_ts = 90.724747 current_received_ts = 90.724297 current_seq = 121 count = 117 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 52us (kms_flip:2048) DEBUG: name = flip last_ts = 90.724747 last_received_ts = 90.724297 last_seq = 121 current_ts = 90.741402 current_received_ts = 90.740974 current_seq = 122 count = 118 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 54us (kms_flip:2048) DEBUG: name = flip last_ts = 90.741402 last_received_ts = 90.740974 last_seq = 122 current_ts = 90.758072 current_received_ts = 90.757637 current_seq = 123 count = 119 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 52us (kms_flip:2048) DEBUG: name = flip last_ts = 90.758072 last_received_ts = 90.757637 last_seq = 123 current_ts = 90.774742 current_received_ts = 90.774307 current_seq = 124 count = 120 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 52us (kms_flip:2048) DEBUG: name = flip last_ts = 90.774742 last_received_ts = 90.774307 last_seq = 124 current_ts = 90.791405 current_received_ts = 90.790977 current_seq = 125 count = 121 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 53us (kms_flip:2048) DEBUG: name = flip last_ts = 90.791405 last_received_ts = 90.790977 last_seq = 125 current_ts = 90.808067 current_received_ts = 90.807632 current_seq = 126 count = 122 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 52us (kms_flip:2048) DEBUG: name = flip last_ts = 90.808067 last_received_ts = 90.807632 last_seq = 126 current_ts = 90.824738 current_received_ts = 90.824295 current_seq = 127 count = 123 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 52us (kms_flip:2048) DEBUG: name = flip last_ts = 90.824738 last_received_ts = 90.824295 last_seq = 127 current_ts = 90.841400 current_received_ts = 90.840965 current_seq = 128 count = 124 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 54us (kms_flip:2048) DEBUG: name = flip last_ts = 90.841400 last_received_ts = 90.840965 last_seq = 128 current_ts = 90.858063 current_received_ts = 90.857635 current_seq = 129 count = 125 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 52us (kms_flip:2048) DEBUG: name = flip last_ts = 90.858063 last_received_ts = 90.857635 last_seq = 129 current_ts = 90.874733 current_received_ts = 90.874298 current_seq = 130 count = 126 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 52us (kms_flip:2048) DEBUG: name = flip last_ts = 90.874733 last_received_ts = 90.874298 last_seq = 130 current_ts = 90.891396 current_received_ts = 90.890968 current_seq = 131 count = 127 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 54us (kms_flip:2048) DEBUG: name = flip last_ts = 90.891396 last_received_ts = 90.890968 last_seq = 131 current_ts = 90.908066 current_received_ts = 90.907631 current_seq = 132 count = 128 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 53us (kms_flip:2048) DEBUG: name = flip last_ts = 90.908066 last_received_ts = 90.907631 last_seq = 132 current_ts = 90.924736 current_received_ts = 90.924301 current_seq = 133 count = 129 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 53us (kms_flip:2048) DEBUG: name = flip last_ts = 90.924736 last_received_ts = 90.924301 last_seq = 133 current_ts = 90.941399 current_received_ts = 90.940964 current_seq = 134 count = 130 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 96us (kms_flip:2048) DEBUG: name = flip last_ts = 90.941399 last_received_ts = 90.940964 last_seq = 134 current_ts = 90.958061 current_received_ts = 90.957626 current_seq = 135 count = 131 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 52us (kms_flip:2048) DEBUG: name = flip last_ts = 90.958061 last_received_ts = 90.957626 last_seq = 135 current_ts = 90.974731 current_received_ts = 90.974281 current_seq = 136 count = 132 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 52us (kms_flip:2048) DEBUG: name = flip last_ts = 90.974731 last_received_ts = 90.974281 last_seq = 136 current_ts = 90.991394 current_received_ts = 90.990959 current_seq = 137 count = 133 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 97us (kms_flip:2048) DEBUG: name = flip last_ts = 90.991394 last_received_ts = 90.990959 last_seq = 137 current_ts = 91.008057 current_received_ts = 91.007622 current_seq = 138 count = 134 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 52us (kms_flip:2048) DEBUG: name = flip last_ts = 91.008057 last_received_ts = 91.007622 last_seq = 138 current_ts = 91.024734 current_received_ts = 91.024284 current_seq = 139 count = 135 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 52us (kms_flip:2048) DEBUG: name = flip last_ts = 91.024734 last_received_ts = 91.024284 last_seq = 139 current_ts = 91.041389 current_received_ts = 91.040962 current_seq = 140 count = 136 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 99us (kms_flip:2048) DEBUG: name = flip last_ts = 91.041389 last_received_ts = 91.040962 last_seq = 140 current_ts = 91.058052 current_received_ts = 91.057625 current_seq = 141 count = 137 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 52us (kms_flip:2048) DEBUG: name = flip last_ts = 91.058052 last_received_ts = 91.057625 last_seq = 141 current_ts = 91.074730 current_received_ts = 91.074280 current_seq = 142 count = 138 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 52us (kms_flip:2048) DEBUG: name = flip last_ts = 91.074730 last_received_ts = 91.074280 last_seq = 142 current_ts = 91.091385 current_received_ts = 91.090958 current_seq = 143 count = 139 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 95us (kms_flip:2048) DEBUG: name = flip last_ts = 91.091385 last_received_ts = 91.090958 last_seq = 143 current_ts = 91.108055 current_received_ts = 91.107620 current_seq = 144 count = 140 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 52us (kms_flip:2048) DEBUG: name = flip last_ts = 91.108055 last_received_ts = 91.107620 last_seq = 144 current_ts = 91.124725 current_received_ts = 91.124275 current_seq = 145 count = 141 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 52us (kms_flip:2048) DEBUG: name = flip last_ts = 91.124725 last_received_ts = 91.124275 last_seq = 145 current_ts = 91.141388 current_received_ts = 91.140953 current_seq = 146 count = 142 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 96us (kms_flip:2048) DEBUG: name = flip last_ts = 91.141388 last_received_ts = 91.140953 last_seq = 146 current_ts = 91.158051 current_received_ts = 91.157616 current_seq = 147 count = 143 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 52us (kms_flip:2048) DEBUG: name = flip last_ts = 91.158051 last_received_ts = 91.157616 last_seq = 147 current_ts = 91.174721 current_received_ts = 91.174286 current_seq = 148 count = 144 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 53us (kms_flip:2048) DEBUG: name = flip last_ts = 91.174721 last_received_ts = 91.174286 last_seq = 148 current_ts = 91.191383 current_received_ts = 91.190956 current_seq = 149 count = 145 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 95us (kms_flip:2048) DEBUG: name = flip last_ts = 91.191383 last_received_ts = 91.190956 last_seq = 149 current_ts = 91.208046 current_received_ts = 91.207619 current_seq = 150 count = 146 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 52us (kms_flip:2048) DEBUG: name = flip last_ts = 91.208046 last_received_ts = 91.207619 last_seq = 150 current_ts = 91.224724 current_received_ts = 91.224274 current_seq = 151 count = 147 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 52us (kms_flip:2048) DEBUG: name = flip last_ts = 91.224724 last_received_ts = 91.224274 last_seq = 151 current_ts = 91.241379 current_received_ts = 91.240952 current_seq = 152 count = 148 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 96us (kms_flip:2048) DEBUG: name = flip last_ts = 91.241379 last_received_ts = 91.240952 last_seq = 152 current_ts = 91.258049 current_received_ts = 91.257614 current_seq = 153 count = 149 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 52us (kms_flip:2048) DEBUG: name = flip last_ts = 91.258049 last_received_ts = 91.257614 last_seq = 153 current_ts = 91.274719 current_received_ts = 91.274284 current_seq = 154 count = 150 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 52us (kms_flip:2048) DEBUG: name = flip last_ts = 91.274719 last_received_ts = 91.274284 last_seq = 154 current_ts = 91.291374 current_received_ts = 91.290947 current_seq = 155 count = 151 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 96us (kms_flip:2048) DEBUG: name = flip last_ts = 91.291374 last_received_ts = 91.290947 last_seq = 155 current_ts = 91.308044 current_received_ts = 91.307610 current_seq = 156 count = 152 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 53us (kms_flip:2048) DEBUG: name = flip last_ts = 91.308044 last_received_ts = 91.307610 last_seq = 156 current_ts = 91.324715 current_received_ts = 91.324280 current_seq = 157 count = 153 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 52us (kms_flip:2048) DEBUG: name = flip last_ts = 91.324715 last_received_ts = 91.324280 last_seq = 157 current_ts = 91.341377 current_received_ts = 91.340942 current_seq = 158 count = 154 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 95us (kms_flip:2048) DEBUG: name = flip last_ts = 91.341377 last_received_ts = 91.340942 last_seq = 158 current_ts = 91.358040 current_received_ts = 91.357605 current_seq = 159 count = 155 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 53us (kms_flip:2048) DEBUG: name = flip last_ts = 91.358040 last_received_ts = 91.357605 last_seq = 159 current_ts = 91.374710 current_received_ts = 91.374275 current_seq = 160 count = 156 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 52us (kms_flip:2048) DEBUG: name = flip last_ts = 91.374710 last_received_ts = 91.374275 last_seq = 160 current_ts = 91.391373 current_received_ts = 91.390945 current_seq = 161 count = 157 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 96us (kms_flip:2048) DEBUG: name = flip last_ts = 91.391373 last_received_ts = 91.390945 last_seq = 161 current_ts = 91.408035 current_received_ts = 91.407608 current_seq = 162 count = 158 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 52us (kms_flip:2048) DEBUG: name = flip last_ts = 91.408035 last_received_ts = 91.407608 last_seq = 162 current_ts = 91.424706 current_received_ts = 91.424278 current_seq = 163 count = 159 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 52us (kms_flip:2048) DEBUG: name = flip last_ts = 91.424706 last_received_ts = 91.424278 last_seq = 163 current_ts = 91.441376 current_received_ts = 91.440941 current_seq = 164 count = 160 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 97us (kms_flip:2048) DEBUG: name = flip last_ts = 91.441376 last_received_ts = 91.440941 last_seq = 164 current_ts = 91.458038 current_received_ts = 91.457603 current_seq = 165 count = 161 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 53us (kms_flip:2048) DEBUG: name = flip last_ts = 91.458038 last_received_ts = 91.457603 last_seq = 165 current_ts = 91.474709 current_received_ts = 91.474274 current_seq = 166 count = 162 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 52us (kms_flip:2048) DEBUG: name = flip last_ts = 91.474709 last_received_ts = 91.474274 last_seq = 166 current_ts = 91.491371 current_received_ts = 91.490936 current_seq = 167 count = 163 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 102us (kms_flip:2048) DEBUG: name = flip last_ts = 91.491371 last_received_ts = 91.490936 last_seq = 167 current_ts = 91.508034 current_received_ts = 91.507599 current_seq = 168 count = 164 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 51us (kms_flip:2048) DEBUG: name = flip last_ts = 91.508034 last_received_ts = 91.507599 last_seq = 168 current_ts = 91.524712 current_received_ts = 91.524261 current_seq = 169 count = 165 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 52us (kms_flip:2048) DEBUG: name = flip last_ts = 91.524712 last_received_ts = 91.524261 last_seq = 169 current_ts = 91.541367 current_received_ts = 91.540932 current_seq = 170 count = 166 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 98us (kms_flip:2048) DEBUG: name = flip last_ts = 91.541367 last_received_ts = 91.540932 last_seq = 170 current_ts = 91.558037 current_received_ts = 91.557602 current_seq = 171 count = 167 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 51us (kms_flip:2048) DEBUG: name = flip last_ts = 91.558037 last_received_ts = 91.557602 last_seq = 171 current_ts = 91.574699 current_received_ts = 91.574265 current_seq = 172 count = 168 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 53us (kms_flip:2048) DEBUG: name = flip last_ts = 91.574699 last_received_ts = 91.574265 last_seq = 172 current_ts = 91.591362 current_received_ts = 91.590935 current_seq = 173 count = 169 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 98us (kms_flip:2048) DEBUG: name = flip last_ts = 91.591362 last_received_ts = 91.590935 last_seq = 173 current_ts = 91.608032 current_received_ts = 91.607597 current_seq = 174 count = 170 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 53us (kms_flip:2048) DEBUG: name = flip last_ts = 91.608032 last_received_ts = 91.607597 last_seq = 174 current_ts = 91.624702 current_received_ts = 91.624268 current_seq = 175 count = 171 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 52us (kms_flip:2048) DEBUG: name = flip last_ts = 91.624702 last_received_ts = 91.624268 last_seq = 175 current_ts = 91.641373 current_received_ts = 91.640961 current_seq = 176 count = 172 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 102us (kms_flip:2048) DEBUG: name = flip last_ts = 91.641373 last_received_ts = 91.640961 last_seq = 176 current_ts = 91.658035 current_received_ts = 91.657585 current_seq = 177 count = 173 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 24us (kms_flip:2048) DEBUG: name = flip last_ts = 91.658035 last_received_ts = 91.657585 last_seq = 177 current_ts = 91.674706 current_received_ts = 91.674210 current_seq = 178 count = 174 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 26us (kms_flip:2048) DEBUG: name = flip last_ts = 91.674706 last_received_ts = 91.674210 last_seq = 178 current_ts = 91.691360 current_received_ts = 91.690880 current_seq = 179 count = 175 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 31us (kms_flip:2048) DEBUG: name = flip last_ts = 91.691360 last_received_ts = 91.690880 last_seq = 179 current_ts = 91.708031 current_received_ts = 91.707626 current_seq = 180 count = 176 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 52us (kms_flip:2048) DEBUG: name = flip last_ts = 91.708031 last_received_ts = 91.707626 last_seq = 180 current_ts = 91.724693 current_received_ts = 91.724304 current_seq = 181 count = 177 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 57us (kms_flip:2048) DEBUG: name = flip last_ts = 91.724693 last_received_ts = 91.724304 last_seq = 181 current_ts = 91.741356 current_received_ts = 91.740952 current_seq = 182 count = 178 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 93us (kms_flip:2048) DEBUG: name = flip last_ts = 91.741356 last_received_ts = 91.740952 last_seq = 182 current_ts = 91.758026 current_received_ts = 91.757622 current_seq = 183 count = 179 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 56us (kms_flip:2048) DEBUG: name = flip last_ts = 91.758026 last_received_ts = 91.757622 last_seq = 183 current_ts = 91.774689 current_received_ts = 91.774284 current_seq = 184 count = 180 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 57us (kms_flip:2048) DEBUG: name = flip last_ts = 91.774689 last_received_ts = 91.774284 last_seq = 184 current_ts = 91.791351 current_received_ts = 91.790955 current_seq = 185 count = 181 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 106us (kms_flip:2048) DEBUG: name = flip last_ts = 91.791351 last_received_ts = 91.790955 last_seq = 185 current_ts = 91.808022 current_received_ts = 91.807617 current_seq = 186 count = 182 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 56us (kms_flip:2048) DEBUG: name = flip last_ts = 91.808022 last_received_ts = 91.807617 last_seq = 186 current_ts = 91.824684 current_received_ts = 91.824280 current_seq = 187 count = 183 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 55us (kms_flip:2048) DEBUG: name = flip last_ts = 91.824684 last_received_ts = 91.824280 last_seq = 187 current_ts = 91.841354 current_received_ts = 91.840950 current_seq = 188 count = 184 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 94us (kms_flip:2048) DEBUG: name = flip last_ts = 91.841354 last_received_ts = 91.840950 last_seq = 188 current_ts = 91.858017 current_received_ts = 91.857613 current_seq = 189 count = 185 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 57us (kms_flip:2048) DEBUG: name = flip last_ts = 91.858017 last_received_ts = 91.857613 last_seq = 189 current_ts = 91.874687 current_received_ts = 91.874283 current_seq = 190 count = 186 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 57us (kms_flip:2048) DEBUG: name = flip last_ts = 91.874687 last_received_ts = 91.874283 last_seq = 190 current_ts = 91.891350 current_received_ts = 91.890953 current_seq = 191 count = 187 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 104us (kms_flip:2048) DEBUG: name = flip last_ts = 91.891350 last_received_ts = 91.890953 last_seq = 191 current_ts = 91.908020 current_received_ts = 91.907616 current_seq = 192 count = 188 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 56us (kms_flip:2048) DEBUG: name = flip last_ts = 91.908020 last_received_ts = 91.907616 last_seq = 192 current_ts = 91.924690 current_received_ts = 91.924286 current_seq = 193 count = 189 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 56us (kms_flip:2048) DEBUG: name = flip last_ts = 91.924690 last_received_ts = 91.924286 last_seq = 193 current_ts = 91.941345 current_received_ts = 91.940948 current_seq = 194 count = 190 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 95us (kms_flip:2048) DEBUG: name = flip last_ts = 91.941345 last_received_ts = 91.940948 last_seq = 194 current_ts = 91.958015 current_received_ts = 91.957611 current_seq = 195 count = 191 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 56us (kms_flip:2048) DEBUG: name = flip last_ts = 91.958015 last_received_ts = 91.957611 last_seq = 195 current_ts = 91.974686 current_received_ts = 91.974281 current_seq = 196 count = 192 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 56us (kms_flip:2048) DEBUG: name = flip last_ts = 91.974686 last_received_ts = 91.974281 last_seq = 196 current_ts = 91.991348 current_received_ts = 91.990944 current_seq = 197 count = 193 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 92us (kms_flip:2048) DEBUG: name = flip last_ts = 91.991348 last_received_ts = 91.990944 last_seq = 197 current_ts = 92.008011 current_received_ts = 92.007614 current_seq = 198 count = 194 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 56us (kms_flip:2048) DEBUG: name = flip last_ts = 92.008011 last_received_ts = 92.007614 last_seq = 198 current_ts = 92.024681 current_received_ts = 92.024292 current_seq = 199 count = 195 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 55us (kms_flip:2048) DEBUG: name = flip last_ts = 92.024681 last_received_ts = 92.024292 last_seq = 199 current_ts = 92.041344 current_received_ts = 92.040947 current_seq = 200 count = 196 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 105us (kms_flip:2048) DEBUG: name = flip last_ts = 92.041344 last_received_ts = 92.040947 last_seq = 200 current_ts = 92.058014 current_received_ts = 92.057610 current_seq = 201 count = 197 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 56us (kms_flip:2048) DEBUG: name = flip last_ts = 92.058014 last_received_ts = 92.057610 last_seq = 201 current_ts = 92.074692 current_received_ts = 92.074287 current_seq = 202 count = 198 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 57us (kms_flip:2048) DEBUG: name = flip last_ts = 92.074692 last_received_ts = 92.074287 last_seq = 202 current_ts = 92.091339 current_received_ts = 92.090942 current_seq = 203 count = 199 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 105us (kms_flip:2048) DEBUG: name = flip last_ts = 92.091339 last_received_ts = 92.090942 last_seq = 203 current_ts = 92.108009 current_received_ts = 92.107605 current_seq = 204 count = 200 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 55us (kms_flip:2048) DEBUG: name = flip last_ts = 92.108009 last_received_ts = 92.107605 last_seq = 204 current_ts = 92.124672 current_received_ts = 92.124283 current_seq = 205 count = 201 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 55us (kms_flip:2048) DEBUG: name = flip last_ts = 92.124672 last_received_ts = 92.124283 last_seq = 205 current_ts = 92.141342 current_received_ts = 92.140938 current_seq = 206 count = 202 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 104us (kms_flip:2048) DEBUG: name = flip last_ts = 92.141342 last_received_ts = 92.140938 last_seq = 206 current_ts = 92.158005 current_received_ts = 92.157600 current_seq = 207 count = 203 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 56us (kms_flip:2048) DEBUG: name = flip last_ts = 92.158005 last_received_ts = 92.157600 last_seq = 207 current_ts = 92.174675 current_received_ts = 92.174309 current_seq = 208 count = 204 seq_step = 1 (kms_flip:2048) DEBUG: Vblank took 763us (kms_flip:2048) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931: (kms_flip:2048) CRITICAL: Failed assertion: end - start < 500 (kms_flip:2048) igt_core-INFO: Stack trace: (kms_flip:2048) igt_core-INFO: #0 ../lib/igt_core.c:2075 __igt_fail_assert() (kms_flip:2048) igt_core-INFO: #1 ../tests/kms_flip.c:926 run_test_on_crtc_set() (kms_flip:2048) igt_core-INFO: #2 ../tests/kms_flip.c:1920 run_test() (kms_flip:2048) igt_core-INFO: #3 ../tests/kms_flip.c:2184 __igt_unique____real_main2105() (kms_flip:2048) igt_core-INFO: #4 ../tests/kms_flip.c:2105 main() (kms_flip:2048) igt_core-INFO: #5 [__libc_init_first+0x8a] (kms_flip:2048) igt_core-INFO: #6 [__libc_start_main+0x8b] (kms_flip:2048) igt_core-INFO: #7 [_start+0x25] **** END **** Dynamic subtest D-HDMI-A3: FAIL (3.968s) Subtest flip-vs-expired-vblank: FAIL (27.335s) (kms_flip:2048) igt_kms-CRITICAL: Test assertion failure function kmstest_set_connector_dpms, file ../lib/igt_kms.c:2352: (kms_flip:2048) igt_kms-CRITICAL: Failed assertion: found_it (kms_flip:2048) igt_kms-CRITICAL: Last errno: 9, Bad file descriptor (kms_flip:2048) igt_kms-CRITICAL: DPMS property not found on 619 |
| Dmesg |
<6> [88.682131] [IGT] kms_flip: starting dynamic subtest D-HDMI-A3
<7> [88.682589] i915 0000:03:00.0: [drm:drm_mode_addfb2] [FB:634]
<7> [88.682733] i915 0000:03:00.0: [drm:drm_mode_addfb2] [FB:637]
<7> [88.683271] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [88.683923] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [88.704996] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [88.705421] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [88.713310] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [88.713659] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [88.733801] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [88.734235] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [88.761224] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:171:pipe A]
<7> [88.761411] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:309:pipe C]
<7> [88.761566] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:447:pipe B]
<7> [88.761664] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in hw.enable (expected yes, found no)
<7> [88.761873] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in hw.active (expected yes, found no)
<7> [88.762073] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in cpu_transcoder (expected 1, found -1)
<7> [88.762262] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in lane_count (expected 4, found 0)
<7> [88.762450] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [88.762637] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [88.762824] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [88.763020] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [88.763206] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [88.763391] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [88.763654] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [88.763843] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [88.764039] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [88.764227] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [88.764414] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [88.764601] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [88.764788] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [88.764980] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [88.765166] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [88.765356] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [88.765545] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [88.765739] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [88.765925] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [88.766118] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [88.766304] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [88.766490] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [88.766676] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [88.766863] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [88.767064] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [88.767250] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [88.767437] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [88.767623] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [88.767809] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [88.768003] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [88.768190] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [88.768377] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [88.768567] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in port_clock (expected 148500, found 0)
<7> [88.768753] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:447:pipe B] fastset requirement not met, forcing full modeset
<7> [88.768940] i915 0000:03:00.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:447:pipe B] releasing DPLL 2
<7> [88.769153] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [88.769334] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [CRTC:447:pipe B] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x2 -> 0x0
<7> [88.769559] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:441:cursor B] ddb (2012 - 2048) -> ( 0 - 0), size 36 -> 0
<7> [88.769764] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:447:pipe B] min cdclk: 74250 kHz -> 0 kHz
<7> [88.769951] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [CRTC:447:pipe B] enable: no [modeset]
<7> [88.770144] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:311:plane 1B] fb: [NOFB], visible: no
<7> [88.770330] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:341:plane 2B] fb: [NOFB], visible: no
<7> [88.770514] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:371:plane 3B] fb: [NOFB], visible: no
<7> [88.770699] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:401:plane 4B] fb: [NOFB], visible: no
<7> [88.770885] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:411:plane 5B] fb: [NOFB], visible: no
<7> [88.771089] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:421:plane 6B] fb: [NOFB], visible: no
<7> [88.771273] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:431:plane 7B] fb: [NOFB], visible: no
<7> [88.771458] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:441:cursor B] fb: [NOFB], visible: no
<7> [88.771803] i915 0000:03:00.0: [drm:intel_disable_transcoder [i915]] disabling pipe B
<7> [88.783054] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_TC1
<7> [88.783280] i915 0000:03:00.0: [drm:intel_dpll_disable [i915]] disable DPLL 2 (active 0x2, on? 1) for [CRTC:447:pipe B]
<7> [88.783500] i915 0000:03:00.0: [drm:intel_dpll_disable [i915]] disabling DPLL 2
<7> [88.783707] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:587:DDI A/PHY A]
<7> [88.783893] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:589:DP-MST A]
<7> [88.784097] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:590:DP-MST B]
<7> [88.784281] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:591:DP-MST C]
<7> [88.784467] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:592:DP-MST D]
<7> [88.784650] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:606:DDI B/PHY B]
<7> [88.784834] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DDI TC1/PHY C]
<7> [88.785048] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:612:DP-MST A]
<7> [88.785234] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:613:DP-MST B]
<7> [88.785418] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:614:DP-MST C]
<7> [88.785601] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:615:DP-MST D]
<7> [88.785787] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:622:DDI TC2/PHY D]
<7> [88.785977] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:624:DP-MST A]
<7> [88.786163] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:625:DP-MST B]
<7> [88.786348] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:626:DP-MST C]
<7> [88.786531] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST D]
<7> [88.786714] i915 0000:03:00.0: [drm:verify_connector_state [i915]] [CONNECTOR:619:HDMI-A-3]
<7> [88.787026] i915 0000:03:00.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [88.787275] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling PW_3
<7> [88.787502] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling PW_2
<7> [88.787725] i915 0000:03:00.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:447:pipe B]
<7> [88.788183] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:585:pipe D]
<7> [88.788386] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:585:pipe D]
<7> [88.788428] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CONNECTOR:619:HDMI-A-3]
<7> [88.788511] i915 0000:03:00.0: [drm:compute_baseline_pipe_bpp [i915]] [CONNECTOR:619:HDMI-A-3] Limiting target display pipe bpp to 24 (EDID bpp 0, max requested bpp 36, max platform bpp 36)
<7> [88.788702] i915 0000:03:00.0: [drm:intel_hdmi_compute_output_format [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [88.788876] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:585:pipe D] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [88.789073] i915 0000:03:00.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:610:DDI TC1/PHY C] [CRTC:585:pipe D]
<7> [88.789250] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.enable (expected no, found yes)
<7> [88.789438] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.active (expected no, found yes)
<7> [88.789626] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in cpu_transcoder (expected -1, found 3)
<7> [88.789814] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in lane_count (expected 0, found 4)
<7> [88.790008] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [88.790196] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [88.790384] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [88.790576] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [88.790762] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [88.790949] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [88.791140] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [88.791327] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [88.791516] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [88.791702] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [88.791888] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [88.792082] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [88.792282] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [88.792463] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [88.792646] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [88.792829] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [88.793050] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [88.793233] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [88.793415] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [88.793597] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [88.793781] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [88.793965] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [88.794215] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [88.794398] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [88.794583] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [88.794764] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [88.794945] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [88.795165] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [88.795349] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [88.795531] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in dpll_hw_state
<7> [88.795714] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [88.795895] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: cfgcr0: 0x0, cfgcr1: 0x0, div0: 0x0, mg_refclkin_ctl: 0x0, hg_clktop2_coreclkctl1: 0x0, mg_clktop2_hsclkctl: 0x0, mg_pll_div0: 0x0, mg_pll_div2: 0x0, mg_pll_lf: 0x0, mg_pll_frac_lock: 0x0, mg_pll_ssc: 0x0, mg_pll_bias: 0x0, mg_pll_tdc_coldst_bias: 0x0
<7> [88.796120] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [88.796301] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: cfgcr0: 0x1001d0, cfgcr1: 0xe84, div0: 0x0, mg_refclkin_ctl: 0x0, hg_clktop2_coreclkctl1: 0x0, mg_clktop2_hsclkctl: 0x0, mg_pll_div0: 0x0, mg_pll_div2: 0x0, mg_pll_lf: 0x0, mg_pll_frac_lock: 0x0, mg_pll_ssc: 0x0, mg_pll_bias: 0x0, mg_pll_tdc_coldst_bias: 0x0
<7> [88.796484] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [88.796667] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [88.796848] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [88.797069] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in port_clock (expected 0, found 148500)
<7> [88.797252] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:585:pipe D] fastset requirement not met, forcing full modeset
<7> [88.797461] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [88.797638] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [CRTC:585:pipe D] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x8
<7> [88.797856] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:449:plane 1D] ddb ( 0 - 0) -> ( 0 - 2012), size 0 -> 2012
<7> [88.798072] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:579:cursor D] ddb ( 0 - 0) -> (2012 - 2048), size 0 -> 36
<7> [88.798247] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:449:plane 1D] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm
<7> [88.798427] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:449:plane 1D] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 5, 5, 5, 8, 8, 9, 10, 0, 0, 0
<7> [88.798603] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:449:plane 1D] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 81, 81, 81, 129, 129, 145, 161, 30, 0, 0
<7> [88.798781] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:449:plane 1D] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 19, 91, 91, 91, 143, 143, 161, 179, 31, 0, 0
<7> [88.799000] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] [CRTC:585:pipe D] data rate 594000 num active planes 1
<7> [88.799195] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 34000 required 594
<7> [88.799376] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [88.799575] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:585:pipe D] min cdclk: 0 kHz -> 74250 kHz
<7> [88.799777] i915 0000:03:00.0: [drm:intel_find_dpll [i915]] [CRTC:585:pipe D] allocated DPLL 2
<7> [88.799959] i915 0000:03:00.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:585:pipe D] reserving DPLL 2
<7> [88.800229] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [CRTC:585:pipe D] enable: yes [modeset]
<7> [88.800412] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [88.800593] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: D, pipe bpp: 24, dithering: 0
<7> [88.800776] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [88.800955] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [88.801175] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [88.801358] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [88.801540] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [88.801720] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [88.801901] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [88.802124] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: yes, vmin: 1125, vmax: 1125, flipline: 1125, pipeline full: 42, guardband: 44 vsync start: 0, vsync end: 0
<7> [88.802307] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 1081, vmax vblank: 1081, vmin vtotal: 1125, vmax vtotal: 1125
<7> [88.802490] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [88.802671] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
<7> [88.802852] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
<7> [88.803077] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [88.803259] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [88.803443] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [88.803625] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] port clock: 148500, pixel rate 148500, min cdclk 74250, min voltage level 0
<7> [88.803808] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] linetime: 119, ips linetime: 0
<7> [88.804017] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0
<7> [88.804213] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1920x1080+0+0
<7> [88.804395] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [88.804577] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [88.804758] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [88.804939] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: cfgcr0: 0x1001d0, cfgcr1: 0xe84, div0: 0x0, mg_refclkin_ctl: 0x0, hg_clktop2_coreclkctl1: 0x0, mg_clktop2_hsclkctl: 0x0, mg_pll_div0: 0x0, mg_pll_div2: 0x0, mg_pll_lf: 0x0, mg_pll_frac_lock: 0x0, mg_pll_ssc: 0x0, mg_pll_bias: 0x0, mg_pll_tdc_coldst_bias: 0x0
<7> [88.805159] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [88.805341] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [88.805520] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [88.805702] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [88.805882] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [88.806114] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [88.806152] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [88.806295] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [88.806373] i915 0000:03:00.0: [drm:gen9_enable_dc5 [i915]] Enabling DC5
<7> [88.806478] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [88.806561] i915 0000:03:00.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 01
<7> [88.806659] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [88.806840] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [88.808224] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [88.808408] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [88.808591] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:449:plane 1D] fb: [FB:634] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [88.808776] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [88.808958] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [88.809830] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:479:plane 2D] fb: [NOFB], visible: no
<7> [88.810048] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:509:plane 3D] fb: [NOFB], visible: no
<7> [88.810230] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:539:plane 4D] fb: [NOFB], visible: no
<7> [88.810413] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:549:plane 5D] fb: [NOFB], visible: no
<7> [88.810593] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:559:plane 6D] fb: [NOFB], visible: no
<7> [88.810774] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:569:plane 7D] fb: [NOFB], visible: no
<7> [88.810956] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:579:cursor D] fb: [NOFB], visible: no
<7> [88.811408] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [88.812369] i915 0000:03:00.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 01 to 00
<7> [88.814061] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling PW_2
<7> [88.814261] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling PW_3
<7> [88.814471] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling PW_4
<7> [88.814678] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling PW_5
<7> [88.814904] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:587:DDI A/PHY A]
<7> [88.815132] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:589:DP-MST A]
<7> [88.815314] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:590:DP-MST B]
<7> [88.815493] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:591:DP-MST C]
<7> [88.815673] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:592:DP-MST D]
<7> [88.815851] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:606:DDI B/PHY B]
<7> [88.816072] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DDI TC1/PHY C]
<7> [88.816252] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:612:DP-MST A]
<7> [88.816433] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:613:DP-MST B]
<7> [88.816613] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:614:DP-MST C]
<7> [88.816792] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:615:DP-MST D]
<7> [88.816999] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:622:DDI TC2/PHY D]
<7> [88.817192] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:624:DP-MST A]
<7> [88.817371] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:625:DP-MST B]
<7> [88.817552] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:626:DP-MST C]
<7> [88.817731] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST D]
<7> [88.818017] i915 0000:03:00.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [88.818262] i915 0000:03:00.0: [drm:intel_dpll_enable [i915]] enable DPLL 2 (active 0x8, on? 0) for [CRTC:585:pipe D]
<7> [88.818446] i915 0000:03:00.0: [drm:intel_dpll_enable [i915]] enabling DPLL 2
<7> [88.818682] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_TC1
<7> [88.818946] i915 0000:03:00.0: [drm:intel_enable_transcoder [i915]] enabling pipe D
<7> [88.837158] i915 0000:03:00.0: [drm:verify_connector_state [i915]] [CONNECTOR:619:HDMI-A-3]
<7> [88.837470] i915 0000:03:00.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:585:pipe D]
<6> [92.650078] [IGT] kms_flip: finished subtest D-HDMI-A3, FAIL
<6> [92.653563] [IGT] kms_flip: finished subtest flip-vs-expired-vblank, FAIL
<7> [92.663025] i915 0000:03:00.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:634] from all active usage due to RMFB ioctl
<7> [92.663235] i915 0000:03:00.0: [drm:atomic_remove_fb] Disabling [PLANE:449:plane 1D] because [FB:634] is removed
<7> [92.663288] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:449:plane 1D] ddb ( 0 - 2012) -> ( 0 - 0), size 2012 -> 0
<7> [92.663599] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:449:plane 1D] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [92.663817] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:449:plane 1D] lines 1, 5, 5, 5, 8, 8, 9, 10, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [92.663990] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:449:plane 1D] blocks 16, 81, 81, 81, 129, 129, 145, 161, 30, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [92.664163] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:449:plane 1D] min_ddb 19, 91, 91, 91, 143, 143, 161, 179, 31, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [92.664443] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] [CRTC:585:pipe D] data rate 0 num active planes 0
<7> [92.664637] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 34000 required 0
<7> [92.664843] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<6> [92.671213] [IGT] kms_flip: exiting, ret=98
<7> [92.687958] i915 0000:03:00.0: [drm:compute_baseline_pipe_bpp [i915]] [CONNECTOR:619:HDMI-A-3] Limiting target display pipe bpp to 24 (EDID bpp 0, max requested bpp 36, max platform bpp 36)
<7> [92.688189] i915 0000:03:00.0: [drm:intel_hdmi_compute_output_format [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [92.688410] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:171:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [92.688633] i915 0000:03:00.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:610:DDI TC1/PHY C] [CRTC:171:pipe A]
<7> [92.688816] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [92.689009] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [92.689201] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [92.689539] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [92.689733] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [92.689926] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [92.690116] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [92.690661] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [92.690856] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [92.691048] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [92.691239] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [92.691534] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [92.691725] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [92.691915] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [92.692106] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [92.692297] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [92.692921] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [92.693117] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [92.693318] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [92.693511] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [92.693702] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [92.693892] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [92.694084] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [92.694275] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [92.694476] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [92.695008] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [92.695204] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [92.695420] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [92.695611] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [92.695803] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [92.695993] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [92.696185] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [92.696388] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [92.696583] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [92.696776] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [92.696968] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [92.697159] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in port_clock (expected 0, found 148500)
<7> [92.697358] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:171:pipe A] fastset requirement not met, forcing full modeset
<7> [92.697550] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.enable (expected yes, found no)
<7> [92.697743] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.active (expected yes, found no)
<7> [92.697934] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in cpu_transcoder (expected 3, found -1)
<7> [92.698126] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in lane_count (expected 4, found 0)
<7> [92.698324] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [92.698516] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [92.698706] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [92.698897] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [92.699094] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [92.699285] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [92.699517] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [92.699710] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [92.699902] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [92.700095] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [92.700285] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [92.700487] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [92.700678] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [92.700869] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [92.701061] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [92.701252] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [92.701451] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [92.701645] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [92.701838] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [92.702029] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [92.702222] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [92.702567] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [92.702775] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [92.702966] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [92.703161] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [92.703452] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [92.703644] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [92.703823] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [92.704034] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [92.704212] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [92.704483] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [92.704706] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [92.704886] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in port_clock (expected 148500, found 0)
<7> [92.705107] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:585:pipe D] fastset requirement not met, forcing full modeset
<7> [92.705289] i915 0000:03:00.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:585:pipe D] releasing DPLL 2
<7> [92.705583] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [CRTC:171:pipe A] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x8 -> 0x1
<7> [92.705799] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [CRTC:585:pipe D] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x8 -> 0x1
<7> [92.706003] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:35:plane 1A] ddb ( 0 - 0) -> ( 0 - 2012), size 0 -> 2012
<7> [92.706176] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:165:cursor A] ddb ( 0 - 0) -> (2012 - 2048), size 0 -> 36
<7> [92.706410] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm
<7> [92.706642] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 5, 5, 5, 8, 8, 9, 10, 0, 0, 0
<7> [92.706842] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 81, 81, 81, 129, 129, 145, 161, 30, 0, 0
<7> [92.707014] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 19, 91, 91, 91, 143, 143, 161, 179, 31, 0, 0
<7> [92.707190] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:579:cursor D] ddb (2012 - 2048) -> ( 0 - 0), size 36 -> 0
<7> [92.707481] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] [CRTC:171:pipe A] data rate 594000 num active planes 1
<7> [92.707680] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 34000 required 594
<7> [92.707859] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [92.708055] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:171:pipe A] min cdclk: 0 kHz -> 74250 kHz
<7> [92.708233] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:585:pipe D] min cdclk: 74250 kHz -> 0 kHz
<7> [92.708497] i915 0000:03:00.0: [drm:intel_find_dpll [i915]] [CRTC:171:pipe A] allocated DPLL 2
<7> [92.708693] i915 0000:03:00.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:171:pipe A] reserving DPLL 2
<7> [92.708876] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [CRTC:171:pipe A] enable: yes [modeset]
<7> [92.709056] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [92.709254] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [92.709457] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [92.709692] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [92.709869] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [92.710073] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [92.710268] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [92.710468] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [92.710675] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [92.710882] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: yes, vmin: 1125, vmax: 1125, flipline: 1125, pipeline full: 42, guardband: 44 vsync start: 0, vsync end: 0
<7> [92.711077] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 1081, vmax vblank: 1081, vmin vtotal: 1125, vmax vtotal: 1125
<7> [92.711261] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [92.711479] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
<7> [92.711692] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
<7> [92.711900] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [92.712084] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [92.712268] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [92.712547] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] port clock: 148500, pixel rate 148500, min cdclk 74250, min voltage level 0
<7> [92.712774] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] linetime: 119, ips linetime: 0
<7> [92.712952] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0
<7> [92.713130] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1920x1080+0+0
<7> [92.713336] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [92.713573] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [92.713749] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [92.713926] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: cfgcr0: 0x1001d0, cfgcr1: 0xe84, div0: 0x0, mg_refclkin_ctl: 0x0, hg_clktop2_coreclkctl1: 0x0, mg_clktop2_hsclkctl: 0x0, mg_pll_div0: 0x0, mg_pll_div2: 0x0, mg_pll_lf: 0x0, mg_pll_frac_lock: 0x0, mg_pll_ssc: 0x0, mg_pll_bias: 0x0, mg_pll_tdc_coldst_bias: 0x0
<7> [92.714106] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [92.714300] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [92.714563] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [92.714772] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [92.714950] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [92.715128] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [92.715367] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [92.715589] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [92.715766] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [92.715943] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [92.716138] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [92.716347] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [92.716608] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:plane 1A] fb: [FB:636] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [92.716788] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [92.717007] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [92.717198] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [92.717432] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [92.717638] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [92.717872] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [92.718050] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:145:plane 6A] fb: [NOFB], visible: no
<7> [92.718227] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:155:plane 7A] fb: [NOFB], visible: no
<7> [92.718464] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:165:cursor A] fb: [NOFB], visible: no
<7> [92.718689] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [CRTC:585:pipe D] enable: no [modeset]
<7> [92.718880] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:449:plane 1D] fb: [NOFB], visible: no
<7> [92.719059] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:479:plane 2D] fb: [NOFB], visible: no
<7> [92.719237] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:509:plane 3D] fb: [NOFB], visible: no
<7> [92.719514] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:539:plane 4D] fb: [NOFB], visible: no
<7> [92.719706] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:549:plane 5D] fb: [NOFB], visible: no
<7> [92.719884] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:559:plane 6D] fb: [NOFB], visible: no
<7> [92.720064] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:569:plane 7D] fb: [NOFB], visible: no
<7> [92.720242] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:579:cursor D] fb: [NOFB], visible: no
<7> [92.721013] i915 0000:03:00.0: [drm:intel_disable_transcoder [i915]] disabling pipe D
<7> [92.738382] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_TC1
<7> [92.738786] i915 0000:03:00.0: [drm:intel_dpll_disable [i915]] disable DPLL 2 (active 0x8, on? 1) for [CRTC:585:pipe D]
<7> [92.739047] i915 0000:03:00.0: [drm:intel_dpll_disable [i915]] disabling DPLL 2
<7> [92.739267] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:587:DDI A/PHY A]
<7> [92.739515] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:589:DP-MST A]
<7> [92.739707] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:590:DP-MST B]
<7> [92.739883] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:591:DP-MST C]
<7> [92.740059] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:592:DP-MST D]
<7> [92.740253] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:606:DDI B/PHY B]
<7> [92.740498] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DDI TC1/PHY C]
<7> [92.740688] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:612:DP-MST A]
<7> [92.740862] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:613:DP-MST B]
<7> [92.741039] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:614:DP-MST C]
<7> [92.741233] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:615:DP-MST D]
<7> [92.741470] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:622:DDI TC2/PHY D]
<7> [92.741702] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:624:DP-MST A]
<7> [92.741879] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:625:DP-MST B]
<7> [92.742083] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:626:DP-MST C]
<7> [92.742276] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST D]
<7> [92.742863] i915 0000:03:00.0: [drm:intel_dpll_enable [i915]] enable DPLL 2 (active 0x1, on? 0) for [CRTC:171:pipe A]
<7> [92.743073] i915 0000:03:00.0: [drm:intel_dpll_enable [i915]] enabling DPLL 2
<7> [92.743306] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_TC1
<7> [92.743850] i915 0000:03:00.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [92.745280] i915 0000:03:00.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1
<7> [92.745547] i915 0000:03:00.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:35:plane 1A]
<7> [92.762453] i915 0000:03:00.0: [drm:verify_connector_state [i915]] [CONNECTOR:619:HDMI-A-3]
<7> [92.762776] i915 0000:03:00.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:171:pipe A]
<7> [92.763853] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling PW_5
<7> [92.764179] i915 0000:03:00.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:585:pipe D]
<7> [92.764668] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling PW_4
<7> [92.765685] i915 0000:03:00.0: [drm:drm_client_dev_restore] fbdev: ret=0
|