igt@kms_flip@flip-vs-expired-vblank-interruptible@a-edp1 on shard-mtlp@IGT

Detail	Value
Duration	4.50 seconds
Hostname	shard-mtlp-8
Igt-Version	IGT-Version: 2.4-g2c7e45fc9 (x86_64) (Linux: 7.1.0-rc3-CI_DRM_18493-gb9819223b7e9+ x86_64)
Out	Using IGT_SRANDOM=1778835541 for randomisation Opened device: /dev/dri/card0 Using monotonic timestamps Starting subtest: flip-vs-expired-vblank-interruptible Starting dynamic subtest: A-eDP1 2880x1800: 90 513820 2880 2928 2960 3040 1800 1803 1809 1878 0x48 0xa Stack trace: #0 ../lib/igt_core.c:2074 __igt_fail_assert() #1 ../tests/kms_flip.c:926 run_test_on_crtc_set() #2 ../tests/kms_flip.c:1920 run_test() #3 ../tests/kms_flip.c:2220 __igt_unique____real_main2105() #4 ../tests/kms_flip.c:2105 main() #5 [__libc_init_first+0x8a] #6 [__libc_start_main+0x8b] #7 [_start+0x25] Dynamic subtest A-eDP1: FAIL (4.505s)
Err	Starting subtest: flip-vs-expired-vblank-interruptible Starting dynamic subtest: A-eDP1 [126.372868] (kms_flip:2582) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931: [126.373066] (kms_flip:2582) CRITICAL: Failed assertion: end - start < 500 [126.373103] (kms_flip:2582) CRITICAL: Last errno: 4, Interrupted system call Dynamic subtest A-eDP1 failed. ** DEBUG [125.027837] (kms_flip:2582) DEBUG: Vblank took 5us [125.038940] (kms_flip:2582) DEBUG: name = flip last_ts = 127.865112 last_received_ts = 127.864784 last_seq = 3058 current_ts = 127.876236 current_received_ts = 127.875916 current_seq = 3059 count = 167 seq_step = 1 [125.038949] (kms_flip:2582) DEBUG: Vblank took 4us [125.050113] (kms_flip:2582) DEBUG: name = flip last_ts = 127.876236 last_received_ts = 127.875916 last_seq = 3059 current_ts = 127.887360 current_received_ts = 127.887100 current_seq = 3060 count = 168 seq_step = 1 [125.050121] (kms_flip:2582) DEBUG: Vblank took 5us [125.061199] (kms_flip:2582) DEBUG: name = flip last_ts = 127.887360 last_received_ts = 127.887100 last_seq = 3060 current_ts = 127.898483 current_received_ts = 127.898201 current_seq = 3061 count = 169 seq_step = 1 [125.061207] (kms_flip:2582) DEBUG: Vblank took 4us [125.072283] (kms_flip:2582) DEBUG: name = flip last_ts = 127.898483 last_received_ts = 127.898201 last_seq = 3061 current_ts = 127.909615 current_received_ts = 127.909302 current_seq = 3062 count = 170 seq_step = 1 [125.072290] (kms_flip:2582) DEBUG: Vblank took 4us [125.083402] (kms_flip:2582) DEBUG: name = flip last_ts = 127.909615 last_received_ts = 127.909302 last_seq = 3062 current_ts = 127.920731 current_received_ts = 127.920425 current_seq = 3063 count = 171 seq_step = 1 [125.083411] (kms_flip:2582) DEBUG: Vblank took 4us [125.094508] (kms_flip:2582) DEBUG: name = flip last_ts = 127.920731 last_received_ts = 127.920425 last_seq = 3063 current_ts = 127.931862 current_received_ts = 127.931549 current_seq = 3064 count = 172 seq_step = 1 [125.094516] (kms_flip:2582) DEBUG: Vblank took 4us [125.105624] (kms_flip:2582) DEBUG: name = flip last_ts = 127.931862 last_received_ts = 127.931549 last_seq = 3064 current_ts = 127.942986 current_received_ts = 127.942680 current_seq = 3065 count = 173 seq_step = 1 [125.105632] (kms_flip:2582) DEBUG: Vblank took 4us [125.116719] (kms_flip:2582) DEBUG: name = flip last_ts = 127.942986 last_received_ts = 127.942680 last_seq = 3065 current_ts = 127.954109 current_received_ts = 127.953789 current_seq = 3066 count = 174 seq_step = 1 [125.116727] (kms_flip:2582) DEBUG: Vblank took 4us [125.127846] (kms_flip:2582) DEBUG: name = flip last_ts = 127.954109 last_received_ts = 127.953789 last_seq = 3066 current_ts = 127.965233 current_received_ts = 127.964928 current_seq = 3067 count = 175 seq_step = 1 [125.127853] (kms_flip:2582) DEBUG: Vblank took 3us [125.138944] (kms_flip:2582) DEBUG: name = flip last_ts = 127.965233 last_received_ts = 127.964928 last_seq = 3067 current_ts = 127.976357 current_received_ts = 127.976044 current_seq = 3068 count = 176 seq_step = 1 [125.138952] (kms_flip:2582) DEBUG: Vblank took 4us [125.150055] (kms_flip:2582) DEBUG: name = flip last_ts = 127.976357 last_received_ts = 127.976044 last_seq = 3068 current_ts = 127.987488 current_received_ts = 127.987167 current_seq = 3069 count = 177 seq_step = 1 [125.150062] (kms_flip:2582) DEBUG: Vblank took 4us [125.161169] (kms_flip:2582) DEBUG: name = flip last_ts = 127.987488 last_received_ts = 127.987167 last_seq = 3069 current_ts = 127.998611 current_received_ts = 127.998291 current_seq = 3070 count = 178 seq_step = 1 [125.161177] (kms_flip:2582) DEBUG: Vblank took 4us [125.172295] (kms_flip:2582) DEBUG: name = flip last_ts = 127.998611 last_received_ts = 127.998291 last_seq = 3070 current_ts = 128.009735 current_received_ts = 128.009430 current_seq = 3071 count = 179 seq_step = 1 [125.172302] (kms_flip:2582) DEBUG: Vblank took 4us [125.183318] (kms_flip:2582) DEBUG: name = flip last_ts = 128.009735 last_received_ts = 128.009430 last_seq = 3071 current_ts = 128.020859 current_received_ts = 128.020477 current_seq = 3072 count = 180 seq_step = 1 [125.183327] (kms_flip:2582) DEBUG: Vblank took 4us [125.194411] (kms_flip:2582) DEBUG: name = flip last_ts = 128.020859 last_received_ts = 128.020477 last_seq = 3072 current_ts = 128.031982 current_received_ts = 128.031570 current_seq = 3073 count = 181 seq_step = 1 [125.194418] (kms_flip:2582) DEBUG: Vblank took 4us [125.205525] (kms_flip:2582) DEBUG: name = flip last_ts = 128.031982 last_received_ts = 128.031570 last_seq = 3073 current_ts = 128.043106 current_received_ts = 128.042709 current_seq = 3074 count = 182 seq_step = 1 [125.205533] (kms_flip:2582) DEBUG: Vblank took 4us [125.216711] (kms_flip:2582) DEBUG: name = flip last_ts = 128.043106 last_received_ts = 128.042709 last_seq = 3074 current_ts = 128.054230 current_received_ts = 128.053909 current_seq = 3075 count = 183 seq_step = 1 [125.216718] (kms_flip:2582) DEBUG: Vblank took 4us [125.227838] (kms_flip:2582) DEBUG: name = flip last_ts = 128.054230 last_received_ts = 128.053909 last_seq = 3075 current_ts = 128.065353 current_received_ts = 128.065048 current_seq = 3076 count = 184 seq_step = 1 [125.227846] (kms_flip:2582) DEBUG: Vblank took 3us [125.238955] (kms_flip:2582) DEBUG: name = flip last_ts = 128.065353 last_received_ts = 128.065048 last_seq = 3076 current_ts = 128.076477 current_received_ts = 128.076172 current_seq = 3077 count = 185 seq_step = 1 [125.238962] (kms_flip:2582) DEBUG: Vblank took 3us [125.250061] (kms_flip:2582) DEBUG: name = flip last_ts = 128.076477 last_received_ts = 128.076172 last_seq = 3077 current_ts = 128.087616 current_received_ts = 128.087296 current_seq = 3078 count = 186 seq_step = 1 [125.250068] (kms_flip:2582) DEBUG: Vblank took 4us [125.261163] (kms_flip:2582) DEBUG: name = flip last_ts = 128.087616 last_received_ts = 128.087296 last_seq = 3078 current_ts = 128.098724 current_received_ts = 128.098419 current_seq = 3079 count = 187 seq_step = 1 [125.261170] (kms_flip:2582) DEBUG: Vblank took 4us [125.272291] (kms_flip:2582) DEBUG: name = flip last_ts = 128.098724 last_received_ts = 128.098419 last_seq = 3079 current_ts = 128.109863 current_received_ts = 128.109558 current_seq = 3080 count = 188 seq_step = 1 [125.272299] (kms_flip:2582) DEBUG: Vblank took 4us [125.283434] (kms_flip:2582) DEBUG: name = flip last_ts = 128.109863 last_received_ts = 128.109558 last_seq = 3080 current_ts = 128.120987 current_received_ts = 128.120712 current_seq = 3081 count = 189 seq_step = 1 [125.283444] (kms_flip:2582) DEBUG: Vblank took 5us [125.294495] (kms_flip:2582) DEBUG: name = flip last_ts = 128.120987 last_received_ts = 128.120712 last_seq = 3081 current_ts = 128.132111 current_received_ts = 128.131790 current_seq = 3082 count = 190 seq_step = 1 [125.294502] (kms_flip:2582) DEBUG: Vblank took 4us [125.305610] (kms_flip:2582) DEBUG: name = flip last_ts = 128.132111 last_received_ts = 128.131790 last_seq = 3082 current_ts = 128.143234 current_received_ts = 128.142914 current_seq = 3083 count = 191 seq_step = 1 [125.305618] (kms_flip:2582) DEBUG: Vblank took 4us [125.316744] (kms_flip:2582) DEBUG: name = flip last_ts = 128.143234 last_received_ts = 128.142914 last_seq = 3083 current_ts = 128.154358 current_received_ts = 128.154068 current_seq = 3084 count = 192 seq_step = 1 [125.316751] (kms_flip:2582) DEBUG: Vblank took 3us [125.327829] (kms_flip:2582) DEBUG: name = flip last_ts = 128.154358 last_received_ts = 128.154068 last_seq = 3084 current_ts = 128.165482 current_received_ts = 128.165161 current_seq = 3085 count = 193 seq_step = 1 [125.327836] (kms_flip:2582) DEBUG: Vblank took 4us [125.338939] (kms_flip:2582) DEBUG: name = flip last_ts = 128.165482 last_received_ts = 128.165161 last_seq = 3085 current_ts = 128.176605 current_received_ts = 128.176285 current_seq = 3086 count = 194 seq_step = 1 [125.338945] (kms_flip:2582) DEBUG: Vblank took 3us [125.350075] (kms_flip:2582) DEBUG: name = flip last_ts = 128.176605 last_received_ts = 128.176285 last_seq = 3086 current_ts = 128.187729 current_received_ts = 128.187439 current_seq = 3087 count = 195 seq_step = 1 [125.350084] (kms_flip:2582) DEBUG: Vblank took 4us [125.361172] (kms_flip:2582) DEBUG: name = flip last_ts = 128.187729 last_received_ts = 128.187439 last_seq = 3087 current_ts = 128.198853 current_received_ts = 128.198547 current_seq = 3088 count = 196 seq_step = 1 [125.361180] (kms_flip:2582) DEBUG: Vblank took 4us [125.372282] (kms_flip:2582) DEBUG: name = flip last_ts = 128.198853 last_received_ts = 128.198547 last_seq = 3088 current_ts = 128.209991 current_received_ts = 128.209671 current_seq = 3089 count = 197 seq_step = 1 [125.372290] (kms_flip:2582) DEBUG: Vblank took 4us [125.383393] (kms_flip:2582) DEBUG: name = flip last_ts = 128.209991 last_received_ts = 128.209671 last_seq = 3089 current_ts = 128.221115 current_received_ts = 128.220795 current_seq = 3090 count = 198 seq_step = 1 [125.383402] (kms_flip:2582) DEBUG: Vblank took 3us [125.394496] (kms_flip:2582) DEBUG: name = flip last_ts = 128.221115 last_received_ts = 128.220795 last_seq = 3090 current_ts = 128.232239 current_received_ts = 128.231903 current_seq = 3091 count = 199 seq_step = 1 [125.394513] (kms_flip:2582) DEBUG: Vblank took 8us [125.405619] (kms_flip:2582) DEBUG: name = flip last_ts = 128.232239 last_received_ts = 128.231903 last_seq = 3091 current_ts = 128.243362 current_received_ts = 128.243042 current_seq = 3092 count = 200 seq_step = 1 [125.405632] (kms_flip:2582) DEBUG: Vblank took 6us [125.416736] (kms_flip:2582) DEBUG: name = flip last_ts = 128.243362 last_received_ts = 128.243042 last_seq = 3092 current_ts = 128.254486 current_received_ts = 128.254181 current_seq = 3093 count = 201 seq_step = 1 [125.416743] (kms_flip:2582) DEBUG: Vblank took 4us [125.427840] (kms_flip:2582) DEBUG: name = flip last_ts = 128.254486 last_received_ts = 128.254181 last_seq = 3093 current_ts = 128.265610 current_received_ts = 128.265289 current_seq = 3094 count = 202 seq_step = 1 [125.427847] (kms_flip:2582) DEBUG: Vblank took 4us [125.438985] (kms_flip:2582) DEBUG: name = flip last_ts = 128.265610 last_received_ts = 128.265289 last_seq = 3094 current_ts = 128.276733 current_received_ts = 128.276459 current_seq = 3095 count = 203 seq_step = 1 [125.438993] (kms_flip:2582) DEBUG: Vblank took 4us [125.450070] (kms_flip:2582) DEBUG: name = flip last_ts = 128.276733 last_received_ts = 128.276459 last_seq = 3095 current_ts = 128.287857 current_received_ts = 128.287552 current_seq = 3096 count = 204 seq_step = 1 [125.450080] (kms_flip:2582) DEBUG: Vblank took 5us [125.461178] (kms_flip:2582) DEBUG: name = flip last_ts = 128.287857 last_received_ts = 128.287552 last_seq = 3096 current_ts = 128.298981 current_received_ts = 128.298676 current_seq = 3097 count = 205 seq_step = 1 [125.461186] (kms_flip:2582) DEBUG: Vblank took 5us [125.472301] (kms_flip:2582) DEBUG: name = flip last_ts = 128.298981 last_received_ts = 128.298676 last_seq = 3097 current_ts = 128.310104 current_received_ts = 128.309814 current_seq = 3098 count = 206 seq_step = 1 [125.472309] (kms_flip:2582) DEBUG: Vblank took 4us [125.483408] (kms_flip:2582) DEBUG: name = flip last_ts = 128.310104 last_received_ts = 128.309814 last_seq = 3098 current_ts = 128.321228 current_received_ts = 128.320923 current_seq = 3099 count = 207 seq_step = 1 [125.483416] (kms_flip:2582) DEBUG: Vblank took 4us [125.494497] (kms_flip:2582) DEBUG: name = flip last_ts = 128.321228 last_received_ts = 128.320923 last_seq = 3099 current_ts = 128.332352 current_received_ts = 128.332031 current_seq = 3100 count = 208 seq_step = 1 [125.494504] (kms_flip:2582) DEBUG: Vblank took 4us [125.505605] (kms_flip:2582) DEBUG: name = flip last_ts = 128.332352 last_received_ts = 128.332031 last_seq = 3100 current_ts = 128.343475 current_received_ts = 128.343155 current_seq = 3101 count = 209 seq_step = 1 [125.505612] (kms_flip:2582) DEBUG: Vblank took 3us [125.516724] (kms_flip:2582) DEBUG: name = flip last_ts = 128.343475 last_received_ts = 128.343155 last_seq = 3101 current_ts = 128.354599 current_received_ts = 128.354294 current_seq = 3102 count = 210 seq_step = 1 [125.516731] (kms_flip:2582) DEBUG: Vblank took 4us [125.527819] (kms_flip:2582) DEBUG: name = flip last_ts = 128.354599 last_received_ts = 128.354294 last_seq = 3102 current_ts = 128.365723 current_received_ts = 128.365402 current_seq = 3103 count = 211 seq_step = 1 [125.527826] (kms_flip:2582) DEBUG: Vblank took 4us [125.538946] (kms_flip:2582) DEBUG: name = flip last_ts = 128.365723 last_received_ts = 128.365402 last_seq = 3103 current_ts = 128.376846 current_received_ts = 128.376541 current_seq = 3104 count = 212 seq_step = 1 [125.538953] (kms_flip:2582) DEBUG: Vblank took 4us [125.550043] (kms_flip:2582) DEBUG: name = flip last_ts = 128.376846 last_received_ts = 128.376541 last_seq = 3104 current_ts = 128.387970 current_received_ts = 128.387650 current_seq = 3105 count = 213 seq_step = 1 [125.550050] (kms_flip:2582) DEBUG: Vblank took 4us [125.561182] (kms_flip:2582) DEBUG: name = flip last_ts = 128.387970 last_received_ts = 128.387650 last_seq = 3105 current_ts = 128.399109 current_received_ts = 128.398804 current_seq = 3106 count = 214 seq_step = 1 [125.561188] (kms_flip:2582) DEBUG: Vblank took 3us [125.572307] (kms_flip:2582) DEBUG: name = flip last_ts = 128.399109 last_received_ts = 128.398804 last_seq = 3106 current_ts = 128.410233 current_received_ts = 128.409943 current_seq = 3107 count = 215 seq_step = 1 [125.572314] (kms_flip:2582) DEBUG: Vblank took 4us [125.583392] (kms_flip:2582) DEBUG: name = flip last_ts = 128.410233 last_received_ts = 128.409943 last_seq = 3107 current_ts = 128.421356 current_received_ts = 128.421036 current_seq = 3108 count = 216 seq_step = 1 [125.583399] (kms_flip:2582) DEBUG: Vblank took 3us [125.594513] (kms_flip:2582) DEBUG: name = flip last_ts = 128.421356 last_received_ts = 128.421036 last_seq = 3108 current_ts = 128.432480 current_received_ts = 128.432175 current_seq = 3109 count = 217 seq_step = 1 [125.594520] (kms_flip:2582) DEBUG: Vblank took 3us [125.605615] (kms_flip:2582) DEBUG: name = flip last_ts = 128.432480 last_received_ts = 128.432175 last_seq = 3109 current_ts = 128.443604 current_received_ts = 128.443283 current_seq = 3110 count = 218 seq_step = 1 [125.605622] (kms_flip:2582) DEBUG: Vblank took 4us [125.616722] (kms_flip:2582) DEBUG: name = flip last_ts = 128.443604 last_received_ts = 128.443283 last_seq = 3110 current_ts = 128.454727 current_received_ts = 128.454407 current_seq = 3111 count = 219 seq_step = 1 [125.616729] (kms_flip:2582) DEBUG: Vblank took 4us [125.627830] (kms_flip:2582) DEBUG: name = flip last_ts = 128.454727 last_received_ts = 128.454407 last_seq = 3111 current_ts = 128.465851 current_received_ts = 128.465530 current_seq = 3112 count = 220 seq_step = 1 [125.627836] (kms_flip:2582) DEBUG: Vblank took 3us [125.638948] (kms_flip:2582) DEBUG: name = flip last_ts = 128.465851 last_received_ts = 128.465530 last_seq = 3112 current_ts = 128.476974 current_received_ts = 128.476669 current_seq = 3113 count = 221 seq_step = 1 [125.638956] (kms_flip:2582) DEBUG: Vblank took 4us [125.650060] (kms_flip:2582) DEBUG: name = flip last_ts = 128.476974 last_received_ts = 128.476669 last_seq = 3113 current_ts = 128.488098 current_received_ts = 128.487793 current_seq = 3114 count = 222 seq_step = 1 [125.650072] (kms_flip:2582) DEBUG: Vblank took 6us [125.661152] (kms_flip:2582) DEBUG: name = flip last_ts = 128.488098 last_received_ts = 128.487793 last_seq = 3114 current_ts = 128.499237 current_received_ts = 128.498901 current_seq = 3115 count = 223 seq_step = 1 [125.661160] (kms_flip:2582) DEBUG: Vblank took 5us [125.672302] (kms_flip:2582) DEBUG: name = flip last_ts = 128.499237 last_received_ts = 128.498901 last_seq = 3115 current_ts = 128.510361 current_received_ts = 128.510056 current_seq = 3116 count = 224 seq_step = 1 [125.672309] (kms_flip:2582) DEBUG: Vblank took 4us [125.683396] (kms_flip:2582) DEBUG: name = flip last_ts = 128.510361 last_received_ts = 128.510056 last_seq = 3116 current_ts = 128.521484 current_received_ts = 128.521164 current_seq = 3117 count = 225 seq_step = 1 [125.683403] (kms_flip:2582) DEBUG: Vblank took 4us [125.694513] (kms_flip:2582) DEBUG: name = flip last_ts = 128.521484 last_received_ts = 128.521164 last_seq = 3117 current_ts = 128.532608 current_received_ts = 128.532303 current_seq = 3118 count = 226 seq_step = 1 [125.694520] (kms_flip:2582) DEBUG: Vblank took 4us [125.705618] (kms_flip:2582) DEBUG: name = flip last_ts = 128.532608 last_received_ts = 128.532303 last_seq = 3118 current_ts = 128.543732 current_received_ts = 128.543411 current_seq = 3119 count = 227 seq_step = 1 [125.705628] (kms_flip:2582) DEBUG: Vblank took 5us [125.716761] (kms_flip:2582) DEBUG: name = flip last_ts = 128.543732 last_received_ts = 128.543411 last_seq = 3119 current_ts = 128.554855 current_received_ts = 128.554565 current_seq = 3120 count = 228 seq_step = 1 [125.716776] (kms_flip:2582) DEBUG: Vblank took 7us [125.727837] (kms_flip:2582) DEBUG: name = flip last_ts = 128.554855 last_received_ts = 128.554565 last_seq = 3120 current_ts = 128.565979 current_received_ts = 128.565659 current_seq = 3121 count = 229 seq_step = 1 [125.727852] (kms_flip:2582) DEBUG: Vblank took 7us [125.738997] (kms_flip:2582) DEBUG: name = flip last_ts = 128.565979 last_received_ts = 128.565659 last_seq = 3121 current_ts = 128.577103 current_received_ts = 128.576828 current_seq = 3122 count = 230 seq_step = 1 [125.739005] (kms_flip:2582) DEBUG: Vblank took 5us [125.750066] (kms_flip:2582) DEBUG: name = flip last_ts = 128.577103 last_received_ts = 128.576828 last_seq = 3122 current_ts = 128.588226 current_received_ts = 128.587921 current_seq = 3123 count = 231 seq_step = 1 [125.750075] (kms_flip:2582) DEBUG: Vblank took 5us [125.761175] (kms_flip:2582) DEBUG: name = flip last_ts = 128.588226 last_received_ts = 128.587921 last_seq = 3123 current_ts = 128.599350 current_received_ts = 128.599045 current_seq = 3124 count = 232 seq_step = 1 [125.761184] (kms_flip:2582) DEBUG: Vblank took 4us [125.772321] (kms_flip:2582) DEBUG: name = flip last_ts = 128.599350 last_received_ts = 128.599045 last_seq = 3124 current_ts = 128.610474 current_received_ts = 128.610199 current_seq = 3125 count = 233 seq_step = 1 [125.772329] (kms_flip:2582) DEBUG: Vblank took 4us [125.783383] (kms_flip:2582) DEBUG: name = flip last_ts = 128.610474 last_received_ts = 128.610199 last_seq = 3125 current_ts = 128.621597 current_received_ts = 128.621277 current_seq = 3126 count = 234 seq_step = 1 [125.783390] (kms_flip:2582) DEBUG: Vblank took 3us [125.794535] (kms_flip:2582) DEBUG: name = flip last_ts = 128.621597 last_received_ts = 128.621277 last_seq = 3126 current_ts = 128.632721 current_received_ts = 128.632431 current_seq = 3127 count = 235 seq_step = 1 [125.794544] (kms_flip:2582) DEBUG: Vblank took 5us [125.805626] (kms_flip:2582) DEBUG: name = flip last_ts = 128.632721 last_received_ts = 128.632431 last_seq = 3127 current_ts = 128.643814 current_received_ts = 128.643509 current_seq = 3128 count = 236 seq_step = 1 [125.805635] (kms_flip:2582) DEBUG: Vblank took 4us [125.816723] (kms_flip:2582) DEBUG: name = flip last_ts = 128.643814 last_received_ts = 128.643509 last_seq = 3128 current_ts = 128.654922 current_received_ts = 128.654602 current_seq = 3129 count = 237 seq_step = 1 [125.816731] (kms_flip:2582) DEBUG: Vblank took 5us [125.827868] (kms_flip:2582) DEBUG: name = flip last_ts = 128.654922 last_received_ts = 128.654602 last_seq = 3129 current_ts = 128.666031 current_received_ts = 128.665741 current_seq = 3130 count = 238 seq_step = 1 [125.827875] (kms_flip:2582) DEBUG: Vblank took 3us [125.838949] (kms_flip:2582) DEBUG: name = flip last_ts = 128.666031 last_received_ts = 128.665741 last_seq = 3130 current_ts = 128.677124 current_received_ts = 128.676804 current_seq = 3131 count = 239 seq_step = 1 [125.838961] (kms_flip:2582) DEBUG: Vblank took 5us [125.850049] (kms_flip:2582) DEBUG: name = flip last_ts = 128.677124 last_received_ts = 128.676804 last_seq = 3131 current_ts = 128.688232 current_received_ts = 128.687912 current_seq = 3132 count = 240 seq_step = 1 [125.850058] (kms_flip:2582) DEBUG: Vblank took 5us [125.861252] (kms_flip:2582) DEBUG: name = flip last_ts = 128.688232 last_received_ts = 128.687912 last_seq = 3132 current_ts = 128.699341 current_received_ts = 128.699097 current_seq = 3133 count = 241 seq_step = 1 [125.861259] (kms_flip:2582) DEBUG: Vblank took 4us [125.872279] (kms_flip:2582) DEBUG: name = flip last_ts = 128.699341 last_received_ts = 128.699097 last_seq = 3133 current_ts = 128.710434 current_received_ts = 128.710129 current_seq = 3134 count = 242 seq_step = 1 [125.872288] (kms_flip:2582) DEBUG: Vblank took 4us [125.883453] (kms_flip:2582) DEBUG: name = flip last_ts = 128.710434 last_received_ts = 128.710129 last_seq = 3134 current_ts = 128.721542 current_received_ts = 128.721283 current_seq = 3135 count = 243 seq_step = 1 [125.883470] (kms_flip:2582) DEBUG: Vblank took 9us [125.894438] (kms_flip:2582) DEBUG: name = flip last_ts = 128.721542 last_received_ts = 128.721283 last_seq = 3135 current_ts = 128.732651 current_received_ts = 128.732269 current_seq = 3136 count = 244 seq_step = 1 [125.894451] (kms_flip:2582) DEBUG: Vblank took 6us [125.905528] (kms_flip:2582) DEBUG: name = flip last_ts = 128.732651 last_received_ts = 128.732269 last_seq = 3136 current_ts = 128.743759 current_received_ts = 128.743347 current_seq = 3137 count = 245 seq_step = 1 [125.905537] (kms_flip:2582) DEBUG: Vblank took 5us [125.916786] (kms_flip:2582) DEBUG: name = flip last_ts = 128.743759 last_received_ts = 128.743347 last_seq = 3137 current_ts = 128.754852 current_received_ts = 128.754608 current_seq = 3138 count = 246 seq_step = 1 [125.916794] (kms_flip:2582) DEBUG: Vblank took 4us [125.927854] (kms_flip:2582) DEBUG: name = flip last_ts = 128.754852 last_received_ts = 128.754608 last_seq = 3138 current_ts = 128.765961 current_received_ts = 128.765656 current_seq = 3139 count = 247 seq_step = 1 [125.927862] (kms_flip:2582) DEBUG: Vblank took 4us [125.938979] (kms_flip:2582) DEBUG: name = flip last_ts = 128.765961 last_received_ts = 128.765656 last_seq = 3139 current_ts = 128.777069 current_received_ts = 128.776779 current_seq = 3140 count = 248 seq_step = 1 [125.938987] (kms_flip:2582) DEBUG: Vblank took 3us [125.950045] (kms_flip:2582) DEBUG: name = flip last_ts = 128.777069 last_received_ts = 128.776779 last_seq = 3140 current_ts = 128.788162 current_received_ts = 128.787842 current_seq = 3141 count = 249 seq_step = 1 [125.950053] (kms_flip:2582) DEBUG: Vblank took 4us [125.961195] (kms_flip:2582) DEBUG: name = flip last_ts = 128.788162 last_received_ts = 128.787842 last_seq = 3141 current_ts = 128.799271 current_received_ts = 128.798981 current_seq = 3142 count = 250 seq_step = 1 [125.961204] (kms_flip:2582) DEBUG: Vblank took 5us [125.972273] (kms_flip:2582) DEBUG: name = flip last_ts = 128.799271 last_received_ts = 128.798981 last_seq = 3142 current_ts = 128.810379 current_received_ts = 128.810059 current_seq = 3143 count = 251 seq_step = 1 [125.972279] (kms_flip:2582) DEBUG: Vblank took 3us [125.983399] (kms_flip:2582) DEBUG: name = flip last_ts = 128.810379 last_received_ts = 128.810059 last_seq = 3143 current_ts = 128.821472 current_received_ts = 128.821167 current_seq = 3144 count = 252 seq_step = 1 [125.983405] (kms_flip:2582) DEBUG: Vblank took 4us [125.994494] (kms_flip:2582) DEBUG: name = flip last_ts = 128.821472 last_received_ts = 128.821167 last_seq = 3144 current_ts = 128.832581 current_received_ts = 128.832260 current_seq = 3145 count = 253 seq_step = 1 [125.994500] (kms_flip:2582) DEBUG: Vblank took 3us [126.005613] (kms_flip:2582) DEBUG: name = flip last_ts = 128.832581 last_received_ts = 128.832260 last_seq = 3145 current_ts = 128.843689 current_received_ts = 128.843369 current_seq = 3146 count = 254 seq_step = 1 [126.005622] (kms_flip:2582) DEBUG: Vblank took 5us [126.016754] (kms_flip:2582) DEBUG: name = flip last_ts = 128.843689 last_received_ts = 128.843369 last_seq = 3146 current_ts = 128.854797 current_received_ts = 128.854507 current_seq = 3147 count = 255 seq_step = 1 [126.016761] (kms_flip:2582) DEBUG: Vblank took 3us [126.027853] (kms_flip:2582) DEBUG: name = flip last_ts = 128.854797 last_received_ts = 128.854507 last_seq = 3147 current_ts = 128.865891 current_received_ts = 128.865601 current_seq = 3148 count = 256 seq_step = 1 [126.027861] (kms_flip:2582) DEBUG: Vblank took 4us [126.038946] (kms_flip:2582) DEBUG: name = flip last_ts = 128.865891 last_received_ts = 128.865601 last_seq = 3148 current_ts = 128.876999 current_received_ts = 128.876678 current_seq = 3149 count = 257 seq_step = 1 [126.038953] (kms_flip:2582) DEBUG: Vblank took 4us [126.050058] (kms_flip:2582) DEBUG: name = flip last_ts = 128.876999 last_received_ts = 128.876678 last_seq = 3149 current_ts = 128.888107 current_received_ts = 128.887787 current_seq = 3150 count = 258 seq_step = 1 [126.050066] (kms_flip:2582) DEBUG: Vblank took 3us [126.061164] (kms_flip:2582) DEBUG: name = flip last_ts = 128.888107 last_received_ts = 128.887787 last_seq = 3150 current_ts = 128.899200 current_received_ts = 128.898880 current_seq = 3151 count = 259 seq_step = 1 [126.061171] (kms_flip:2582) DEBUG: Vblank took 3us [126.072265] (kms_flip:2582) DEBUG: name = flip last_ts = 128.899200 last_received_ts = 128.898880 last_seq = 3151 current_ts = 128.910309 current_received_ts = 128.909973 current_seq = 3152 count = 260 seq_step = 1 [126.072272] (kms_flip:2582) DEBUG: Vblank took 4us [126.083416] (kms_flip:2582) DEBUG: name = flip last_ts = 128.910309 last_received_ts = 128.909973 last_seq = 3152 current_ts = 128.921417 current_received_ts = 128.921127 current_seq = 3153 count = 261 seq_step = 1 [126.083424] (kms_flip:2582) DEBUG: Vblank took 4us [126.094508] (kms_flip:2582) DEBUG: name = flip last_ts = 128.921417 last_received_ts = 128.921127 last_seq = 3153 current_ts = 128.932526 current_received_ts = 128.932205 current_seq = 3154 count = 262 seq_step = 1 [126.094514] (kms_flip:2582) DEBUG: Vblank took 3us [126.105614] (kms_flip:2582) DEBUG: name = flip last_ts = 128.932526 last_received_ts = 128.932205 last_seq = 3154 current_ts = 128.943619 current_received_ts = 128.943314 current_seq = 3155 count = 263 seq_step = 1 [126.105622] (kms_flip:2582) DEBUG: Vblank took 3us [126.116751] (kms_flip:2582) DEBUG: name = flip last_ts = 128.943619 last_received_ts = 128.943314 last_seq = 3155 current_ts = 128.954727 current_received_ts = 128.954437 current_seq = 3156 count = 264 seq_step = 1 [126.116759] (kms_flip:2582) DEBUG: Vblank took 4us [126.127830] (kms_flip:2582) DEBUG: name = flip last_ts = 128.954727 last_received_ts = 128.954437 last_seq = 3156 current_ts = 128.965836 current_received_ts = 128.965515 current_seq = 3157 count = 265 seq_step = 1 [126.127837] (kms_flip:2582) DEBUG: Vblank took 4us [126.138957] (kms_flip:2582) DEBUG: name = flip last_ts = 128.965836 last_received_ts = 128.965515 last_seq = 3157 current_ts = 128.976929 current_received_ts = 128.976624 current_seq = 3158 count = 266 seq_step = 1 [126.138964] (kms_flip:2582) DEBUG: Vblank took 3us [126.150083] (kms_flip:2582) DEBUG: name = flip last_ts = 128.976929 last_received_ts = 128.976624 last_seq = 3158 current_ts = 128.988037 current_received_ts = 128.987747 current_seq = 3159 count = 267 seq_step = 1 [126.150090] (kms_flip:2582) DEBUG: Vblank took 4us [126.161168] (kms_flip:2582) DEBUG: name = flip last_ts = 128.988037 last_received_ts = 128.987747 last_seq = 3159 current_ts = 128.999146 current_received_ts = 128.998825 current_seq = 3160 count = 268 seq_step = 1 [126.161176] (kms_flip:2582) DEBUG: Vblank took 4us [126.172276] (kms_flip:2582) DEBUG: name = flip last_ts = 128.999146 last_received_ts = 128.998825 last_seq = 3160 current_ts = 129.010239 current_received_ts = 129.009933 current_seq = 3161 count = 269 seq_step = 1 [126.172282] (kms_flip:2582) DEBUG: Vblank took 3us [126.183414] (kms_flip:2582) DEBUG: name = flip last_ts = 129.010239 last_received_ts = 129.009933 last_seq = 3161 current_ts = 129.021347 current_received_ts = 129.021057 current_seq = 3162 count = 270 seq_step = 1 [126.183421] (kms_flip:2582) DEBUG: Vblank took 4us [126.194500] (kms_flip:2582) DEBUG: name = flip last_ts = 129.021347 last_received_ts = 129.021057 last_seq = 3162 current_ts = 129.032455 current_received_ts = 129.032135 current_seq = 3163 count = 271 seq_step = 1 [126.194508] (kms_flip:2582) DEBUG: Vblank took 4us [126.205604] (kms_flip:2582) DEBUG: name = flip last_ts = 129.032455 last_received_ts = 129.032135 last_seq = 3163 current_ts = 129.043564 current_received_ts = 129.043228 current_seq = 3164 count = 272 seq_step = 1 [126.205610] (kms_flip:2582) DEBUG: Vblank took 3us [126.216740] (kms_flip:2582) DEBUG: name = flip last_ts = 129.043564 last_received_ts = 129.043228 last_seq = 3164 current_ts = 129.054672 current_received_ts = 129.054367 current_seq = 3165 count = 273 seq_step = 1 [126.216747] (kms_flip:2582) DEBUG: Vblank took 4us [126.227827] (kms_flip:2582) DEBUG: name = flip last_ts = 129.054672 last_received_ts = 129.054367 last_seq = 3165 current_ts = 129.065765 current_received_ts = 129.065445 current_seq = 3166 count = 274 seq_step = 1 [126.227835] (kms_flip:2582) DEBUG: Vblank took 4us [126.238944] (kms_flip:2582) DEBUG: name = flip last_ts = 129.065765 last_received_ts = 129.065445 last_seq = 3166 current_ts = 129.076874 current_received_ts = 129.076553 current_seq = 3167 count = 275 seq_step = 1 [126.238951] (kms_flip:2582) DEBUG: Vblank took 3us [126.250074] (kms_flip:2582) DEBUG: name = flip last_ts = 129.076874 last_received_ts = 129.076553 last_seq = 3167 current_ts = 129.087982 current_received_ts = 129.087677 current_seq = 3168 count = 276 seq_step = 1 [126.250081] (kms_flip:2582) DEBUG: Vblank took 4us [126.261163] (kms_flip:2582) DEBUG: name = flip last_ts = 129.087982 last_received_ts = 129.087677 last_seq = 3168 current_ts = 129.099075 current_received_ts = 129.098755 current_seq = 3169 count = 277 seq_step = 1 [126.261169] (kms_flip:2582) DEBUG: Vblank took 3us [126.272275] (kms_flip:2582) DEBUG: name = flip last_ts = 129.099075 last_received_ts = 129.098755 last_seq = 3169 current_ts = 129.110184 current_received_ts = 129.109863 current_seq = 3170 count = 278 seq_step = 1 [126.272281] (kms_flip:2582) DEBUG: Vblank took 3us [126.283393] (kms_flip:2582) DEBUG: name = flip last_ts = 129.110184 last_received_ts = 129.109863 last_seq = 3170 current_ts = 129.121292 current_received_ts = 129.120972 current_seq = 3171 count = 279 seq_step = 1 [126.283404] (kms_flip:2582) DEBUG: Vblank took 6us [126.294498] (kms_flip:2582) DEBUG: name = flip last_ts = 129.121292 last_received_ts = 129.120972 last_seq = 3171 current_ts = 129.132385 current_received_ts = 129.132065 current_seq = 3172 count = 280 seq_step = 1 [126.294505] (kms_flip:2582) DEBUG: Vblank took 3us [126.305619] (kms_flip:2582) DEBUG: name = flip last_ts = 129.132385 last_received_ts = 129.132065 last_seq = 3172 current_ts = 129.143494 current_received_ts = 129.143188 current_seq = 3173 count = 281 seq_step = 1 [126.305626] (kms_flip:2582) DEBUG: Vblank took 4us [126.316736] (kms_flip:2582) DEBUG: name = flip last_ts = 129.143494 last_received_ts = 129.143188 last_seq = 3173 current_ts = 129.154602 current_received_ts = 129.154297 current_seq = 3174 count = 282 seq_step = 1 [126.316743] (kms_flip:2582) DEBUG: Vblank took 3us [126.327817] (kms_flip:2582) DEBUG: name = flip last_ts = 129.154602 last_received_ts = 129.154297 last_seq = 3174 current_ts = 129.165710 current_received_ts = 129.165359 current_seq = 3175 count = 283 seq_step = 1 [126.327824] (kms_flip:2582) DEBUG: Vblank took 4us [126.338945] (kms_flip:2582) DEBUG: name = flip last_ts = 129.165710 last_received_ts = 129.165359 last_seq = 3175 current_ts = 129.176804 current_received_ts = 129.176483 current_seq = 3176 count = 284 seq_step = 1 [126.338952] (kms_flip:2582) DEBUG: Vblank took 4us [126.350066] (kms_flip:2582) DEBUG: name = flip last_ts = 129.176804 last_received_ts = 129.176483 last_seq = 3176 current_ts = 129.187912 current_received_ts = 129.187607 current_seq = 3177 count = 285 seq_step = 1 [126.350074] (kms_flip:2582) DEBUG: Vblank took 4us [126.361161] (kms_flip:2582) DEBUG: name = flip last_ts = 129.187912 last_received_ts = 129.187607 last_seq = 3177 current_ts = 129.199020 current_received_ts = 129.198685 current_seq = 3178 count = 286 seq_step = 1 [126.361169] (kms_flip:2582) DEBUG: Vblank took 4us [126.372296] (kms_flip:2582) DEBUG: name = flip last_ts = 129.199020 last_received_ts = 129.198685 last_seq = 3178 current_ts = 129.210114 current_received_ts = 129.209824 current_seq = 3179 count = 287 seq_step = 1 [126.372863] (kms_flip:2582) DEBUG: Vblank took 562us [126.372868] (kms_flip:2582) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931: [126.373066] (kms_flip:2582) CRITICAL: Failed assertion: end - start < 500 [126.373103] (kms_flip:2582) CRITICAL: Last errno: 4, Interrupted system call [126.378375] (kms_flip:2582) igt_core-INFO: Stack trace: [126.394177] (kms_flip:2582) igt_core-INFO: #0 ../lib/igt_core.c:2074 __igt_fail_assert() [126.394648] (kms_flip:2582) igt_core-INFO: #1 ../tests/kms_flip.c:926 run_test_on_crtc_set() [126.394673] (kms_flip:2582) igt_core-INFO: #2 ../tests/kms_flip.c:1920 run_test() [126.394687] (kms_flip:2582) igt_core-INFO: #3 ../tests/kms_flip.c:2220 __igt_unique____real_main2105() [126.394705] (kms_flip:2582) igt_core-INFO: #4 ../tests/kms_flip.c:2105 main() [126.399622] (kms_flip:2582) igt_core-INFO: #5 [__libc_init_first+0x8a] [126.400126] (kms_flip:2582) igt_core-INFO: #6 [__libc_start_main+0x8b] [126.400251] (kms_flip:2582) igt_core-INFO: #7 [_start+0x25] END ** Dynamic subtest A-eDP1: FAIL (4.505s)
Dmesg	<6> [183.385416] Console: switching to colour dummy device 80x25 <6> [183.385601] [IGT] kms_flip: executing <7> [183.393423] i915 0000:00:02.0: [drm:i915_gem_open [i915]] <7> [183.395199] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc] <7> [183.423096] i915 0000:00:02.0: [drm:i915_gem_open [i915]] <7> [183.433006] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:517:DP-1] <7> [183.433015] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:517:DP-1] <7> [183.437242] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:517:DP-1] disconnected <7> [183.437495] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:529:HDMI-A-1] <7> [183.437498] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:529:HDMI-A-1] <7> [183.441740] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:529:HDMI-A-1] disconnected <7> [183.442009] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:535:DP-2] <7> [183.442013] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:535:DP-2] <7> [183.442303] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port D/TC#1: TC port mode reset (disconnected -> tbt-alt) pin assignment: - max lanes: 4 <7> [183.442522] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:535:DP-2] disconnected <7> [183.442768] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:544:HDMI-A-2] <7> [183.442771] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:544:HDMI-A-2] <7> [183.443103] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port E/TC#2: TC port mode reset (disconnected -> legacy) pin assignment: - max lanes: 4 <7> [183.447390] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:544:HDMI-A-2] disconnected <7> [183.447641] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:548:DP-3] <7> [183.447644] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:548:DP-3] <7> [183.447921] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port F/TC#3: TC port mode reset (disconnected -> tbt-alt) pin assignment: - max lanes: 4 <7> [183.448132] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:548:DP-3] disconnected <7> [183.448378] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:557:DP-4] <7> [183.448380] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:557:DP-4] <7> [183.448607] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port G/TC#4: TC port mode reset (disconnected -> tbt-alt) pin assignment: - max lanes: 4 <7> [183.448822] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:557:DP-4] disconnected <7> [183.449252] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:508:eDP-1] <7> [183.449256] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:508:eDP-1] <7> [183.449837] i915 0000:00:02.0: [drm:intel_dp_read_dsc_dpcd [i915]] DSC DPCD: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 <7> [183.450241] i915 0000:00:02.0: [drm:intel_dp_print_rates [i915]] source rates: 162000, 216000, 243000, 270000, 324000, 432000, 540000, 675000, 810000 <7> [183.450374] i915 0000:00:02.0: [drm:intel_dp_print_rates [i915]] sink rates: 162000, 216000, 243000, 270000, 324000, 378000, 432000, 540000 <7> [183.450506] i915 0000:00:02.0: [drm:intel_dp_print_rates [i915]] common rates: 162000, 216000, 243000, 270000, 324000, 432000, 540000 <7> [183.450655] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:508:eDP-1] Assigning EDID-1.4 digital sink color depth as 10 bpc. <7> [183.450660] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:508:eDP-1] ELD monitor <7> [183.450663] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:508:eDP-1] ELD size 20, SAD count 0 <7> [183.450678] i915 0000:00:02.0: [drm:intel_dp_set_edid [i915]] [CONNECTOR:508:eDP-1] VRR capable: no <7> [183.450808] i915 0000:00:02.0: [drm:intel_dp_set_edid [i915]] [CONNECTOR:508:eDP-1] DFP max bpc 0, max dotclock 0, TMDS clock 0-0, PCON Max FRL BW 0Gbps <7> [183.450978] i915 0000:00:02.0: [drm:intel_dp_set_edid [i915]] [CONNECTOR:508:eDP-1] RGB->YcbCr conversion? no, YCbCr 4:2:0 allowed? yes, YCbCr 4:4:4->4:2:0 conversion? no <7> [183.451430] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:508:eDP-1] probed modes: <7> [183.451434] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2880x1800": 90 513820 2880 2928 2960 3040 1800 1803 1809 1878 0x48 0xa <7> [183.451447] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:517:DP-1] <7> [183.451450] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:517:DP-1] <7> [183.455647] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:517:DP-1] disconnected <7> [183.455659] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:529:HDMI-A-1] <7> [183.455662] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:529:HDMI-A-1] <7> [183.459895] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:529:HDMI-A-1] disconnected <7> [183.459905] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:535:DP-2] <7> [183.459908] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:535:DP-2] <7> [183.460152] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:535:DP-2] disconnected <7> [183.460160] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:544:HDMI-A-2] <7> [183.460163] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:544:HDMI-A-2] <7> [183.464478] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:544:HDMI-A-2] disconnected <7> [183.464489] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:548:DP-3] <7> [183.464491] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:548:DP-3] <7> [183.464732] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:548:DP-3] disconnected <7> [183.464740] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:557:DP-4] <7> [183.464743] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:557:DP-4] <7> [183.465020] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:557:DP-4] disconnected <6> [183.468544] [IGT] kms_flip: starting subtest flip-vs-expired-vblank-interruptible <6> [183.471017] [IGT] kms_flip: starting dynamic subtest A-eDP1 <7> [183.471345] i915 0000:00:02.0: [drm:i915_dpt_create [i915]] Allocating dpt from smem <7> [183.471494] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:567] <7> [183.471564] i915 0000:00:02.0: [drm:i915_dpt_create [i915]] Allocating dpt from smem <7> [183.471705] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:580] <7> [183.474580] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509 <7> [183.474976] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509 <7> [183.533159] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509 <7> [183.533432] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509 <7> [183.541533] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509 <7> [183.541835] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509 <7> [183.559868] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509 <7> [183.560131] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509 <7> [183.567732] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:151:pipe A] <7> [183.567828] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.enable (expected yes, found no) <7> [183.567999] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.active (expected yes, found no) <7> [183.568155] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1) <7> [183.568307] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in lane_count (expected 4, found 0) <7> [183.568457] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in dp_m_n (expected tu 64 data 7483046/8388608 link 623587/524288, found tu 0, data 0/0 link 0/0) <7> [183.568611] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in output_types (expected 0x00000100, found 0x00000000) <7> [183.568786] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0) <7> [183.568936] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 2880, found 0) <7> [183.569082] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 3040, found 0) <7> [183.569227] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 2880, found 0) <7> [183.569371] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 3040, found 0) <7> [183.569513] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2928, found 0) <7> [183.569665] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2960, found 0) <7> [183.569812] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1800, found 0) <7> [183.569961] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1801, found 0) <7> [183.570102] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1803, found 0) <7> [183.570247] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1809, found 0) <7> [183.570391] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1878, found 0) <7> [183.570534] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1878, found 0) <7> [183.570685] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 2880, found 0) <7> [183.570830] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 3040, found 0) <7> [183.570970] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 2880, found 0) <7> [183.571114] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 3040, found 0) <7> [183.571258] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2928, found 0) <7> [183.571401] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2960, found 0) <7> [183.571541] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1800, found 0) <7> [183.571733] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1801, found 0) <7> [183.571877] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1803, found 0) <7> [183.572019] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1809, found 0) <7> [183.572160] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1878, found 0) <7> [183.572299] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1878, found 0) <7> [183.572438] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0) <7> [183.572577] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0) <7> [183.572749] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0) <7> [183.572908] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in enhanced_framing (expected yes, found no) <7> [183.573056] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0) <7> [183.573197] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 513820, found 0) <7> [183.573337] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 513820, found 0) <7> [183.573478] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in port_clock (expected 432000, found 0) <7> [183.573627] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in set_context_latency (expected 1, found 0) <7> [183.573773] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:151:pipe A] fastset requirement not met, forcing full modeset <7> [183.573914] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:151:pipe A] releasing DPLL 0 <7> [183.574094] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0xf -> 0x1 (total dbuf slices 0xf), mbus joined? yes->no <7> [183.574232] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:151:pipe A] dbuf slices 0xf -> 0x0, ddb (0 - 4096) -> (0 - 0), active pipes 0x1 -> 0x0 <7> [183.574397] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:35:plane 1A] ddb ( 0 - 4001) -> ( 0 - 0), size 4001 -> 0 <7> [183.574535] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:145:cursor A] ddb (4001 - 4096) -> ( 0 - 0), size 95 -> 0 <7> [183.574693] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] level wm0,wm1,wm2,wm3,wm4,wm5, wm6, wm7,twm,swm,stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm <7> [183.574830] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] lines 2, 16, 16, 19, 19, 28, 0, 0, 0, 3, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 <7> [183.574961] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] blocks 49, 385, 385, 457, 457, 673, 0, 0, 63, 73, 87 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 <7> [183.575095] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] min_ddb 55, 425, 425, 504, 504, 742, 0, 0, 64, 82, 88 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 <7> [183.575247] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:151:pipe A] data rate 0 num active planes 0 <7> [183.575393] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 28913 required 0 qgv_peak_bw: 51200 <7> [183.575537] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 1: max bw 38000 required 0 qgv_peak_bw: 51200 <7> [183.575696] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 2: max bw 38000 required 0 qgv_peak_bw: 51200 <7> [183.575843] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 3: max bw 38000 required 0 qgv_peak_bw: 51200 <7> [183.575992] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] Matching peaks QGV bw: 51200 for required data rate: 0 <7> [183.576140] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:151:pipe A] min cdclk: 256910 kHz -> 0 kHz <7> [183.576294] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 32114 kHz -> 0 kHz <7> [183.576436] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Can change cdclk via squashing <7> [183.576579] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 172800 kHz, actual 172800 kHz <7> [183.576740] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0 <7> [183.576899] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:151:pipe A] enable: no [modeset] <7> [183.577041] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:plane 1A] fb: [NOFB], visible: no <7> [183.577186] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:65:plane 2A] fb: [NOFB], visible: no <7> [183.577327] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:95:plane 3A] fb: [NOFB], visible: no <7> [183.577467] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:125:plane 4A] fb: [NOFB], visible: no <7> [183.577618] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:135:plane 5A] fb: [NOFB], visible: no <7> [183.577790] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:145:cursor A] fb: [NOFB], visible: no <7> [183.577979] i915 0000:00:02.0: [drm:intel_psr_disable_locked [i915]] Disabling PSR2 <7> [183.579254] i915 0000:00:02.0: [drm:intel_edp_backlight_off [i915]] <7> [183.786446] i915 0000:00:02.0: [drm:intel_backlight_set_pwm_level [i915]] [CONNECTOR:508:eDP-1] set backlight PWM = 0 <7> [183.787184] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A <7> [183.790901] i915 0000:00:02.0: [drm:intel_pps_off_unlocked [i915]] [ENCODER:507:DDI A/PHY A] PPS 0 turn panel power off <7> [183.791228] i915 0000:00:02.0: [drm:icp_irq_handler [i915]] hotplug event received, stat 0x00010000, dig 0x00000089, pins 0x00000010, long 0x00000000 <7> [183.791517] i915 0000:00:02.0: [drm:intel_pps_off_unlocked [i915]] [ENCODER:507:DDI A/PHY A] PPS 0 wait for panel power off time <7> [183.791826] i915 0000:00:02.0: [drm:intel_hpd_irq_handler [i915]] digital hpd on [ENCODER:507:DDI A/PHY A] - short <7> [183.792068] i915 0000:00:02.0: [drm:wait_panel_status [i915]] [ENCODER:507:DDI A/PHY A] PPS 0 mask: 0xb0000000 value: 0x00000000 PP_STATUS: 0xa0000002 PP_CONTROL: 0x00000060 <7> [183.844003] i915 0000:00:02.0: [drm:icp_irq_handler [i915]] hotplug event received, stat 0x00010000, dig 0x0000008a, pins 0x00000010, long 0x00000010 <7> [183.844586] i915 0000:00:02.0: [drm:intel_hpd_irq_handler [i915]] digital hpd on [ENCODER:507:DDI A/PHY A] - long <7> [183.845125] i915 0000:00:02.0: [drm:intel_hpd_irq_handler [i915]] Received HPD interrupt on PIN 4 - cnt: 10 <7> [183.845335] i915 0000:00:02.0: [drm:wait_panel_status [i915]] Wait complete <7> [183.845852] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x1, on? 1) for [CRTC:151:pipe A] <7> [183.846028] i915 0000:00:02.0: [drm:intel_dp_hpd_pulse [i915]] ignoring short hpd on eDP [ENCODER:507:DDI A/PHY A] <7> [183.846710] i915 0000:00:02.0: [drm:intel_dp_hpd_pulse [i915]] ignoring long hpd on eDP [ENCODER:507:DDI A/PHY A] <7> [183.847654] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0 <7> [183.848153] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling AUX_A <7> [183.848605] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:507:DDI A/PHY A] <7> [183.848978] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:516:DDI B/PHY B] <7> [183.849336] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:518:DP-MST A] <7> [183.849823] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:519:DP-MST B] <7> [183.850290] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:520:DP-MST C] <7> [183.850717] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:521:DP-MST D] <7> [183.850928] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:534:DDI TC1/PHY TC1] <7> [183.851067] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:536:DP-MST A] <7> [183.851213] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:537:DP-MST B] <7> [183.851366] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:538:DP-MST C] <7> [183.851526] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:539:DP-MST D] <7> [183.851665] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:543:DDI TC2/PHY TC2] <7> [183.851800] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:547:DDI TC3/PHY TC3] <7> [183.851935] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:549:DP-MST A] <7> [183.852079] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:550:DP-MST B] <7> [183.852230] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:551:DP-MST C] <7> [183.852375] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:552:DP-MST D] <7> [183.852505] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:556:DDI TC4/PHY TC4] <7> [183.852646] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:558:DP-MST A] <7> [183.852788] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:559:DP-MST B] <7> [183.852924] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:560:DP-MST C] <7> [183.853052] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:561:DP-MST D] <7> [183.853208] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:508:eDP-1] <7> [183.853421] i915 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [i915]] Updating dbuf ratio to 2 (mbus joined: no) <7> [183.853624] i915 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [i915]] Changing mbus joined: yes -> no (pipe: ) <7> [183.853765] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1 <7> [183.854009] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_A <7> [183.854193] i915 0000:00:02.0: [drm:dss_pipe_gating_enable_disable [i915]] DSS clock gating disabled for pipe_mask=0x1 (CLKGATE_DIS_DSSDSC=0xaa000000) <7> [183.854399] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:151:pipe A] <7> [183.854590] i915 0000:00:02.0: [drm:intel_set_cdclk [i915]] Post changing CDCLK to 172800 kHz, VCO 614400 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0 <7> [183.854771] i915 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [i915]] aud_ts_cdclk set to M=60, N=432 <7> [183.855099] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:269:pipe B] <7> [183.855252] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:387:pipe C] <7> [183.855351] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:505:pipe D] <7> [183.855452] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:151:pipe A] <7> [183.855478] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:508:eDP-1] <7> [183.855532] i915 0000:00:02.0: [drm:compute_baseline_pipe_bpp [i915]] [CONNECTOR:508:eDP-1] Limiting target display pipe bpp to 24 (EDID bpp 30, max requested bpp 24, max platform bpp 36) <7> [183.855705] i915 0000:00:02.0: [drm:intel_dp_compute_config_limits [i915]] [ENCODER:507:DDI A/PHY A][CRTC:151:pipe A] DP link limits: pixel clock 513820 kHz DSC off max lanes 4 max rate 540000 max pipe_bpp 24 min link_bpp 18.0000 max link_bpp 24.0000 <7> [183.855837] i915 0000:00:02.0: [drm:intel_dp_compute_output_format [i915]] DP lane count 4 clock 432000 bpp input 24 compressed 0.0000 HDR no link rate required 1541460 available 1728000 <7> [183.855978] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:151:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0 <7> [183.856132] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:507:DDI A/PHY A] [CRTC:151:pipe A] <7> [183.856269] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.enable (expected no, found yes) <7> [183.856419] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.active (expected no, found yes) <7> [183.856563] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0) <7> [183.856709] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in lane_count (expected 0, found 4) <7> [183.856849] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in dp_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 7483046/8388608 link 623587/524288) <7> [183.856991] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000100) <7> [183.857135] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1) <7> [183.857275] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 2880) <7> [183.857414] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 3040) <7> [183.857554] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 2880) <7> [183.857693] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 3040) <7> [183.857831] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2928) <7> [183.857969] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2960) <7> [183.858113] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1800) <7> [183.858260] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1801) <7> [183.858398] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1803) <7> [183.858546] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1809) <7> [183.858692] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1878) <7> [183.858836] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1878) <7> [183.858975] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 2880) <7> [183.859134] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 3040) <7> [183.859275] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 2880) <7> [183.859416] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 3040) <7> [183.859556] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2928) <7> [183.859695] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2960) <7> [183.859833] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1800) <7> [183.859970] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1801) <7> [183.860115] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1803) <7> [183.860258] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1809) <7> [183.860397] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1878) <7> [183.860536] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1878) <7> [183.860674] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1) <7> [183.860813] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2) <7> [183.860954] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8) <7> [183.861100] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in enhanced_framing (expected no, found yes) <7> [183.861243] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24) <7> [183.861381] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 513820) <7> [183.861520] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 513820) <7> [183.861656] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in port_clock (expected 0, found 432000) <7> [183.861795] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in set_context_latency (expected 0, found 1) <7> [183.861936] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:151:pipe A] fastset requirement not met, forcing full modeset <7> [183.862132] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0xf (total dbuf slices 0xf), mbus joined? no->yes <7> [183.862271] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:151:pipe A] dbuf slices 0x0 -> 0xf, ddb (0 - 0) -> (0 - 4096), active pipes 0x0 -> 0x1 <7> [183.862436] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:35:plane 1A] ddb ( 0 - 0) -> ( 0 - 4001), size 0 -> 4001 <7> [183.862572] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:145:cursor A] ddb ( 0 - 0) -> (4001 - 4096), size 0 -> 95 <7> [183.862708] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> wm0,wm1,wm2,wm3,wm4,wm5, wm6, wm7,twm,swm,stwm <7> [183.862840] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 4, 16, 16, 19, 19, 28, 0, 0, 0, 4, 0 <7> [183.862974] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 92, 365, 365, 434, 434, 638, 0, 0, 197, 92, 197 <7> [183.863128] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 183, 456, 456, 547, 547, 729, 0, 0, 198, 183, 198 <7> [183.863277] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:151:pipe A] data rate 2055280 num active planes 1 <7> [183.863421] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 28913 required 2159 qgv_peak_bw: 51200 <7> [183.863569] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 1: max bw 38000 required 2159 qgv_peak_bw: 51200 <7> [183.863757] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 2: max bw 38000 required 2159 qgv_peak_bw: 51200 <7> [183.863924] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 3: max bw 38000 required 2159 qgv_peak_bw: 51200 <7> [183.864067] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] Matching peaks QGV bw: 51200 for required data rate: 2159 <7> [183.864229] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:151:pipe A] min cdclk: 0 kHz -> 256910 kHz <7> [183.864391] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 0 kHz -> 32114 kHz <7> [183.864535] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Can change cdclk via squashing <7> [183.864676] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 307200 kHz, actual 307200 kHz <7> [183.864816] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0 <7> [183.864972] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:151:pipe A] allocated DPLL 0 <7> [183.865136] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:151:pipe A] reserving DPLL 0 <7> [183.865285] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:151:pipe A] enable: yes [modeset] <7> [183.865428] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: EDP (0x100), output format: RGB, sink format: RGB <7> [183.865570] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0 <7> [183.865714] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid> <7> [183.865857] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0 <7> [183.865997] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0 <7> [183.866206] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0 <7> [183.866360] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dp m_n: lanes: 4; data_m: 7483046, data_n: 8388608, link_m: 623587, link_n: 524288, tu: 64 <7> [183.866528] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dp m2_n2: lanes: 4; data_m: 0, data_n: 0, link_m: 0, link_n: 0, tu: 0 <7> [183.866670] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] fec: disabled, enhanced framing: enabled <7> [183.866809] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sdp split: disabled <7> [183.866947] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] psr: enabled, selective update: enabled, panel replay: disabled, selective fetch: enabled <7> [183.867101] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] minimum hblank: 0 <7> [183.867245] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x4 <7> [183.867383] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] DP SDP: VSC, revision 4, length 14 <7> [183.867520] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pixelformat: RGB <7> [183.867657] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] colorimetry: sRGB <7> [183.867793] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] bpc: 0 <7> [183.867927] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dynamic range: VESA range <7> [183.868065] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] content type: Not defined <7> [183.868226] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 1 <7> [183.868365] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 1 <7> [183.868503] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: yes, vmin: 1878, vmax: 1878, flipline: 1878, pipeline full: 0, guardband: 76 vsync start: 75, vsync end: 69 <7> [183.868641] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 1802, vmax vblank: 1802, vmin vtotal: 1878, vmax vtotal: 1878 <7> [183.868783] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0 <7> [183.868949] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "2880x1800": 90 513820 2880 2928 2960 3040 1800 1803 1809 1878 0x48 0xa <7> [183.869094] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "2880x1800": 90 513820 2880 2928 2960 3040 1800 1803 1809 1878 0x48 0xa <7> [183.869239] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=513820, hd=2880 hb=2880-3040 hs=2928-2960 ht=3040, vd=1800 vb=1801-1878 vs=1803-1809 vt=1878, flags=0xa <7> [183.869379] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "2880x1800": 90 513820 2880 2928 2960 3040 1800 1803 1809 1878 0x40 0xa <7> [183.869517] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=513820, hd=2880 hb=2880-3040 hs=2928-2960 ht=3040, vd=1800 vb=1801-1878 vs=1803-1809 vt=1878, flags=0xa <7> [183.869654] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 432000, pixel rate 513820, min cdclk 256910, min voltage level 0 <7> [183.869790] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 48, ips linetime: 0 <7> [183.869928] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0 <7> [183.870070] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe src: 2880x1800+0+0 <7> [183.870228] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no <7> [183.870364] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0 <7> [183.870499] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0 <7> [183.870635] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cx0pll_hw_state: lane_count: 4, ssc_enabled: no, use_c10: yes, tbt_mode: no <7> [183.870774] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] c10pll_hw_state: fracen: no, <7> [183.870913] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] multiplier: 225, tx_clk_div: 1. <7> [183.871050] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] c10pll_rawhw_state: <7> [183.871198] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] tx: 0x10, cmn: 0x21 <7> [183.871342] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pll[0] = 0x4, pll[1] = 0x0, pll[2] = 0xa2, pll[3] = 0x1 <7> [183.871504] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pll[4] = 0x0, pll[5] = 0x0, pll[6] = 0x0, pll[7] = 0x0 <7> [183.871642] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pll[8] = 0x0, pll[9] = 0x1, pll[10] = 0x0, pll[11] = 0x0 <7> [183.871780] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pll[12] = 0x0, pll[13] = 0x0, pll[14] = 0x0, pll[15] = 0x1 <7> [183.871916] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pll[16] = 0x85, pll[17] = 0xf, pll[18] = 0xe6, pll[19] = 0x23 <7> [183.872056] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0 <7> [183.872208] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries <7> [183.872347] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000 <7> [183.872485] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000 <7> [183.872622] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000 <7> [183.872758] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000 <7> [183.872898] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000 <7> [183.873037] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000 <7> [183.873195] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000 <7> [183.873215] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off <7> [183.873355] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000 <7> [183.873491] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000 <7> [183.873493] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6 <7> [183.873626] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000 <7> [183.873711] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02 <7> [183.873764] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:plane 1A] fb: [FB:567] 2880x1800 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes <7> [183.873907] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0 <7> [183.874061] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 2880.000000x1800.000000+0.000000+0.000000 dst: 2880x1800+0+0 <7> [183.874236] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:65:plane 2A] fb: [NOFB], visible: no <7> [183.874375] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:95:plane 3A] fb: [NOFB], visible: no <7> [183.874514] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:125:plane 4A] fb: [NOFB], visible: no <7> [183.874655] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:135:plane 5A] fb: [NOFB], visible: no <7> [183.874794] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:145:cursor A] fb: [NOFB], visible: no <7> [183.878884] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off <7> [183.879219] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00 <7> [183.879513] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_A <7> [183.879708] i915 0000:00:02.0: [drm:dss_pipe_gating_enable_disable [i915]] DSS clock gating enabled for pipe_mask=0x1 (CLKGATE_DIS_DSSDSC=0xa8000000) <7> [183.879883] i915 0000:00:02.0: [drm:intel_set_cdclk [i915]] Pre changing CDCLK to 307200 kHz, VCO 614400 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0 <7> [183.880226] i915 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [i915]] aud_ts_cdclk set to M=60, N=768 <7> [183.880375] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:507:DDI A/PHY A] <7> [183.880520] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:516:DDI B/PHY B] <7> [183.880665] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:518:DP-MST A] <7> [183.880812] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:519:DP-MST B] <7> [183.880955] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:520:DP-MST C] <7> [183.881105] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:521:DP-MST D] <7> [183.881244] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:534:DDI TC1/PHY TC1] <7> [183.881382] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:536:DP-MST A] <7> [183.881523] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:537:DP-MST B] <7> [183.881681] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:538:DP-MST C] <7> [183.881822] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:539:DP-MST D] <7> [183.881961] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:543:DDI TC2/PHY TC2] <7> [183.882108] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:547:DDI TC3/PHY TC3] <7> [183.882245] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:549:DP-MST A] <7> [183.882381] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:550:DP-MST B] <7> [183.882519] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:551:DP-MST C] <7> [183.882659] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:552:DP-MST D] <7> [183.882798] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:556:DDI TC4/PHY TC4] <7> [183.882936] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:558:DP-MST A] <7> [183.883086] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:559:DP-MST B] <7> [183.883225] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:560:DP-MST C] <7> [183.883362] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:561:DP-MST D] <7> [183.883528] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0xf <7> [183.883767] i915 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [i915]] Changing mbus joined: no -> yes (pipe: ) <7> [183.883910] i915 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [i915]] Updating dbuf ratio to 4 (mbus joined: yes) <7> [183.884123] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling AUX_A <7> [183.885112] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:151:pipe A] <7> [183.885259] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0 <7> [183.887386] i915 0000:00:02.0: [drm:intel_pps_on_unlocked [i915]] [ENCODER:507:DDI A/PHY A] PPS 0 turn panel power on <7> [183.887540] i915 0000:00:02.0: [drm:wait_panel_power_cycle [i915]] [ENCODER:507:DDI A/PHY A] PPS 0 wait for panel power cycle (459 ms remaining) <7> [184.353744] i915 0000:00:02.0: [drm:wait_panel_status [i915]] [ENCODER:507:DDI A/PHY A] PPS 0 mask: 0xb800000f value: 0x00000000 PP_STATUS: 0x08000001 PP_CONTROL: 0x00000060 <7> [184.364550] i915 0000:00:02.0: [drm:wait_panel_status [i915]] Wait complete <7> [184.365154] i915 0000:00:02.0: [drm:intel_pps_on_unlocked [i915]] [ENCODER:507:DDI A/PHY A] PPS 0 wait for panel power on <7> [184.365732] i915 0000:00:02.0: [drm:wait_panel_status [i915]] [ENCODER:507:DDI A/PHY A] PPS 0 mask: 0xb000000f value: 0x80000008 PP_STATUS: 0x9000000a PP_CONTROL: 0x00000063 <7> [184.397281] i915 0000:00:02.0: [drm:icp_irq_handler [i915]] hotplug event received, stat 0x00010000, dig 0x0000008a, pins 0x00000010, long 0x00000010 <7> [184.397867] i915 0000:00:02.0: [drm:intel_hpd_irq_handler [i915]] digital hpd on [ENCODER:507:DDI A/PHY A] - long <7> [184.398406] i915 0000:00:02.0: [drm:intel_hpd_irq_handler [i915]] Received HPD interrupt on PIN 4 - cnt: 20 <7> [184.398962] i915 0000:00:02.0: [drm:intel_dp_hpd_pulse [i915]] ignoring long hpd on eDP [ENCODER:507:DDI A/PHY A] <7> [184.481505] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port G/TC#4: TC port mode reset (tbt-alt -> disconnected) pin assignment: - max lanes: 4 <7> [184.481505] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port F/TC#3: TC port mode reset (tbt-alt -> disconnected) pin assignment: - max lanes: 4 <7> [184.481824] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port E/TC#2: TC port mode reset (legacy -> disconnected) pin assignment: - max lanes: 4 <7> [184.482442] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port D/TC#1: TC port mode reset (tbt-alt -> disconnected) pin assignment: - max lanes: 4 <7> [184.571054] i915 0000:00:02.0: [drm:wait_panel_status [i915]] Wait complete <7> [184.571772] i915 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [i915]] [ENCODER:507:DDI A/PHY A] PPS 0 turning VDD on <7> [184.572507] i915 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [i915]] [ENCODER:507:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006b <7> [184.576179] i915 0000:00:02.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX A/DDI A/PHY A: DPCD: 14 14 c4 41 00 00 01 c0 02 00 00 00 00 0b 80 <7> [184.578302] i915 0000:00:02.0: [drm:intel_dp_start_link_train [i915]] [CONNECTOR:508:eDP-1][ENCODER:507:DDI A/PHY A][DPRX] Reloading eDP link rates <7> [184.579463] i915 0000:00:02.0: [drm:intel_dp_start_link_train [i915]] [CONNECTOR:508:eDP-1][ENCODER:507:DDI A/PHY A][DPRX] Using LINK_RATE_SET value 06 <7> [184.581323] i915 0000:00:02.0: [drm:intel_dp_set_signal_levels [i915]] [CONNECTOR:508:eDP-1][ENCODER:507:DDI A/PHY A][DPRX] 8b/10b, lanes: 4, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0 <7> [184.582902] i915 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [i915]] [CONNECTOR:508:eDP-1][ENCODER:507:DDI A/PHY A][DPRX] Using DP training pattern TPS1 <7> [184.583818] i915 0000:00:02.0: [drm:intel_dp_link_train_phy [i915]] [CONNECTOR:508:eDP-1][ENCODER:507:DDI A/PHY A][DPRX] Clock recovery OK <7> [184.583979] i915 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [i915]] [CONNECTOR:508:eDP-1][ENCODER:507:DDI A/PHY A][DPRX] Using DP training pattern TPS3 <7> [184.585294] i915 0000:00:02.0: [drm:intel_dp_link_train_phy [i915]] [CONNECTOR:508:eDP-1][ENCODER:507:DDI A/PHY A][DPRX] Channel EQ done. DP Training successful <7> [184.585526] i915 0000:00:02.0: [drm:intel_dp_link_train_phy [i915]] [CONNECTOR:508:eDP-1][ENCODER:507:DDI A/PHY A][DPRX] Link Training passed at link rate = 432000, lane count = 4 <7> [184.586267] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A <7> [184.586653] i915 0000:00:02.0: [drm:intel_edp_backlight_on [i915]] <7> [184.586858] i915 0000:00:02.0: [drm:intel_backlight_enable [i915]] pipe A <7> [184.587126] i915 0000:00:02.0: [drm:intel_backlight_set_pwm_level [i915]] [CONNECTOR:508:eDP-1] set backlight PWM = 13892 <7> [184.608942] i915 0000:00:02.0: [drm:intel_psr_post_plane_update [i915]] Enabling PSR2 <7> [184.611629] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:508:eDP-1] <7> [184.612197] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:151:pipe A] <7> [187.165287] i915 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [i915]] [ENCODER:507:DDI A/PHY A] PPS 0 turning VDD off <7> [187.165910] i915 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [i915]] [ENCODER:507:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x00000067 <7> [187.848622] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off <7> [187.849219] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6 <7> [187.849791] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02 <6> [187.978693] [IGT] kms_flip: finished subtest A-eDP1, FAIL

<6> [183.385416] Console: switching to colour dummy device 80x25

<7> [183.393423] i915 0000:00:02.0: [drm:i915_gem_open [i915]]

<7> [183.395199] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]

<7> [183.423096] i915 0000:00:02.0: [drm:i915_gem_open [i915]]

<7> [183.433006] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:517:DP-1]

<7> [183.433015] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:517:DP-1]

<7> [183.437242] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:517:DP-1] disconnected

<7> [183.437495] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:529:HDMI-A-1]

<7> [183.437498] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:529:HDMI-A-1]

<7> [183.441740] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:529:HDMI-A-1] disconnected

<7> [183.442009] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:535:DP-2]

<7> [183.442013] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:535:DP-2]

<7> [183.442303] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port D/TC#1: TC port mode reset (disconnected -> tbt-alt) pin assignment: - max lanes: 4

<7> [183.442522] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:535:DP-2] disconnected

<7> [183.442768] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:544:HDMI-A-2]

<7> [183.442771] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:544:HDMI-A-2]

<7> [183.443103] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port E/TC#2: TC port mode reset (disconnected -> legacy) pin assignment: - max lanes: 4

<7> [183.447390] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:544:HDMI-A-2] disconnected

<7> [183.447641] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:548:DP-3]

<7> [183.447644] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:548:DP-3]

<7> [183.447921] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port F/TC#3: TC port mode reset (disconnected -> tbt-alt) pin assignment: - max lanes: 4

<7> [183.448132] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:548:DP-3] disconnected

<7> [183.448378] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:557:DP-4]

<7> [183.448380] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:557:DP-4]

<7> [183.448607] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port G/TC#4: TC port mode reset (disconnected -> tbt-alt) pin assignment: - max lanes: 4

<7> [183.448822] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:557:DP-4] disconnected

<7> [183.449252] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:508:eDP-1]

<7> [183.449256] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:508:eDP-1]

<7> [183.449837] i915 0000:00:02.0: [drm:intel_dp_read_dsc_dpcd [i915]] DSC DPCD: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00

<7> [183.450241] i915 0000:00:02.0: [drm:intel_dp_print_rates [i915]] source rates: 162000, 216000, 243000, 270000, 324000, 432000, 540000, 675000, 810000

<7> [183.450374] i915 0000:00:02.0: [drm:intel_dp_print_rates [i915]] sink rates: 162000, 216000, 243000, 270000, 324000, 378000, 432000, 540000

<7> [183.450506] i915 0000:00:02.0: [drm:intel_dp_print_rates [i915]] common rates: 162000, 216000, 243000, 270000, 324000, 432000, 540000

<7> [183.450655] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:508:eDP-1] Assigning EDID-1.4 digital sink color depth as 10 bpc.

<7> [183.450660] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:508:eDP-1] ELD monitor

<7> [183.450663] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:508:eDP-1] ELD size 20, SAD count 0

<7> [183.450678] i915 0000:00:02.0: [drm:intel_dp_set_edid [i915]] [CONNECTOR:508:eDP-1] VRR capable: no

<7> [183.450808] i915 0000:00:02.0: [drm:intel_dp_set_edid [i915]] [CONNECTOR:508:eDP-1] DFP max bpc 0, max dotclock 0, TMDS clock 0-0, PCON Max FRL BW 0Gbps

<7> [183.450978] i915 0000:00:02.0: [drm:intel_dp_set_edid [i915]] [CONNECTOR:508:eDP-1] RGB->YcbCr conversion? no, YCbCr 4:2:0 allowed? yes, YCbCr 4:4:4->4:2:0 conversion? no

<7> [183.451430] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:508:eDP-1] probed modes:

<7> [183.451434] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2880x1800": 90 513820 2880 2928 2960 3040 1800 1803 1809 1878 0x48 0xa

<7> [183.451447] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:517:DP-1]

<7> [183.451450] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:517:DP-1]

<7> [183.455647] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:517:DP-1] disconnected

<7> [183.455659] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:529:HDMI-A-1]

<7> [183.455662] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:529:HDMI-A-1]

<7> [183.459895] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:529:HDMI-A-1] disconnected

<7> [183.459905] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:535:DP-2]

<7> [183.459908] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:535:DP-2]

<7> [183.460152] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:535:DP-2] disconnected

<7> [183.460160] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:544:HDMI-A-2]

<7> [183.460163] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:544:HDMI-A-2]

<7> [183.464478] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:544:HDMI-A-2] disconnected

<7> [183.464489] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:548:DP-3]

<7> [183.464491] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:548:DP-3]

<7> [183.464732] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:548:DP-3] disconnected

<7> [183.464740] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:557:DP-4]

<7> [183.464743] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:557:DP-4]

<7> [183.465020] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:557:DP-4] disconnected

<6> [183.468544] [IGT] kms_flip: starting subtest flip-vs-expired-vblank-interruptible

<6> [183.471017] [IGT] kms_flip: starting dynamic subtest A-eDP1

<7> [183.471345] i915 0000:00:02.0: [drm:i915_dpt_create [i915]] Allocating dpt from smem

<7> [183.471494] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:567]

<7> [183.471564] i915 0000:00:02.0: [drm:i915_dpt_create [i915]] Allocating dpt from smem

<7> [183.471705] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:580]

<7> [183.474580] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509

<7> [183.474976] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509

<7> [183.533159] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509

<7> [183.533432] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509

<7> [183.541533] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509

<7> [183.541835] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509

<7> [183.559868] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509

<7> [183.560131] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509

<7> [183.567732] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:151:pipe A]

<7> [183.567828] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.enable (expected yes, found no)

<7> [183.567999] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.active (expected yes, found no)

<7> [183.568155] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)

<7> [183.568307] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in lane_count (expected 4, found 0)

<7> [183.568457] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in dp_m_n (expected tu 64 data 7483046/8388608 link 623587/524288, found tu 0, data 0/0 link 0/0)

<7> [183.568611] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in output_types (expected 0x00000100, found 0x00000000)

<7> [183.568786] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)

<7> [183.568936] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 2880, found 0)

<7> [183.569082] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 3040, found 0)

<7> [183.569227] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 2880, found 0)

<7> [183.569371] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 3040, found 0)

<7> [183.569513] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2928, found 0)

<7> [183.569665] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2960, found 0)

<7> [183.569812] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1800, found 0)

<7> [183.569961] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1801, found 0)

<7> [183.570102] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1803, found 0)

<7> [183.570247] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1809, found 0)

<7> [183.570391] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1878, found 0)

<7> [183.570534] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1878, found 0)

<7> [183.570685] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 2880, found 0)

<7> [183.570830] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 3040, found 0)

<7> [183.570970] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 2880, found 0)

<7> [183.571114] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 3040, found 0)

<7> [183.571258] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2928, found 0)

<7> [183.571401] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2960, found 0)

<7> [183.571541] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1800, found 0)

<7> [183.571733] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1801, found 0)

<7> [183.571877] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1803, found 0)

<7> [183.572019] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1809, found 0)

<7> [183.572160] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1878, found 0)

<7> [183.572299] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1878, found 0)

<7> [183.572438] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)

<7> [183.572577] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)

<7> [183.572749] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)

<7> [183.572908] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in enhanced_framing (expected yes, found no)

<7> [183.573056] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)

<7> [183.573197] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 513820, found 0)

<7> [183.573337] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 513820, found 0)

<7> [183.573478] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in port_clock (expected 432000, found 0)

<7> [183.573627] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in set_context_latency (expected 1, found 0)

<7> [183.573773] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:151:pipe A] fastset requirement not met, forcing full modeset

<7> [183.573914] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:151:pipe A] releasing DPLL 0

<7> [183.574094] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0xf -> 0x1 (total dbuf slices 0xf), mbus joined? yes->no

<7> [183.574232] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:151:pipe A] dbuf slices 0xf -> 0x0, ddb (0 - 4096) -> (0 - 0), active pipes 0x1 -> 0x0

<7> [183.574397] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:35:plane 1A] ddb ( 0 - 4001) -> ( 0 - 0), size 4001 -> 0

<7> [183.574535] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:145:cursor A] ddb (4001 - 4096) -> ( 0 - 0), size 95 -> 0

<7> [183.574693] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm

<7> [183.574830] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] lines 2, 16, 16, 19, 19, 28, 0, 0, 0, 3, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0

<7> [183.574961] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] blocks 49, 385, 385, 457, 457, 673, 0, 0, 63, 73, 87 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0

<7> [183.575095] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] min_ddb 55, 425, 425, 504, 504, 742, 0, 0, 64, 82, 88 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0

<7> [183.575247] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:151:pipe A] data rate 0 num active planes 0

<7> [183.575393] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 28913 required 0 qgv_peak_bw: 51200

<7> [183.575537] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 1: max bw 38000 required 0 qgv_peak_bw: 51200

<7> [183.575696] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 2: max bw 38000 required 0 qgv_peak_bw: 51200

<7> [183.575843] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 3: max bw 38000 required 0 qgv_peak_bw: 51200

<7> [183.575992] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] Matching peaks QGV bw: 51200 for required data rate: 0

<7> [183.576140] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:151:pipe A] min cdclk: 256910 kHz -> 0 kHz

<7> [183.576294] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 32114 kHz -> 0 kHz

<7> [183.576436] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Can change cdclk via squashing

<7> [183.576579] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 172800 kHz, actual 172800 kHz

<7> [183.576740] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0

<7> [183.576899] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:151:pipe A] enable: no [modeset]

<7> [183.577041] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:plane 1A] fb: [NOFB], visible: no

<7> [183.577186] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:65:plane 2A] fb: [NOFB], visible: no

<7> [183.577327] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:95:plane 3A] fb: [NOFB], visible: no

<7> [183.577467] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:125:plane 4A] fb: [NOFB], visible: no

<7> [183.577618] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:135:plane 5A] fb: [NOFB], visible: no

<7> [183.577790] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:145:cursor A] fb: [NOFB], visible: no

<7> [183.577979] i915 0000:00:02.0: [drm:intel_psr_disable_locked [i915]] Disabling PSR2

<7> [183.579254] i915 0000:00:02.0: [drm:intel_edp_backlight_off [i915]]

<7> [183.786446] i915 0000:00:02.0: [drm:intel_backlight_set_pwm_level [i915]] [CONNECTOR:508:eDP-1] set backlight PWM = 0

<7> [183.787184] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A

<7> [183.790901] i915 0000:00:02.0: [drm:intel_pps_off_unlocked [i915]] [ENCODER:507:DDI A/PHY A] PPS 0 turn panel power off

<7> [183.791228] i915 0000:00:02.0: [drm:icp_irq_handler [i915]] hotplug event received, stat 0x00010000, dig 0x00000089, pins 0x00000010, long 0x00000000

<7> [183.791517] i915 0000:00:02.0: [drm:intel_pps_off_unlocked [i915]] [ENCODER:507:DDI A/PHY A] PPS 0 wait for panel power off time

<7> [183.791826] i915 0000:00:02.0: [drm:intel_hpd_irq_handler [i915]] digital hpd on [ENCODER:507:DDI A/PHY A] - short

<7> [183.792068] i915 0000:00:02.0: [drm:wait_panel_status [i915]] [ENCODER:507:DDI A/PHY A] PPS 0 mask: 0xb0000000 value: 0x00000000 PP_STATUS: 0xa0000002 PP_CONTROL: 0x00000060

<7> [183.844003] i915 0000:00:02.0: [drm:icp_irq_handler [i915]] hotplug event received, stat 0x00010000, dig 0x0000008a, pins 0x00000010, long 0x00000010

<7> [183.844586] i915 0000:00:02.0: [drm:intel_hpd_irq_handler [i915]] digital hpd on [ENCODER:507:DDI A/PHY A] - long

<7> [183.845125] i915 0000:00:02.0: [drm:intel_hpd_irq_handler [i915]] Received HPD interrupt on PIN 4 - cnt: 10

<7> [183.845335] i915 0000:00:02.0: [drm:wait_panel_status [i915]] Wait complete

<7> [183.845852] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x1, on? 1) for [CRTC:151:pipe A]

<7> [183.846028] i915 0000:00:02.0: [drm:intel_dp_hpd_pulse [i915]] ignoring short hpd on eDP [ENCODER:507:DDI A/PHY A]

<7> [183.846710] i915 0000:00:02.0: [drm:intel_dp_hpd_pulse [i915]] ignoring long hpd on eDP [ENCODER:507:DDI A/PHY A]

<7> [183.847654] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0

<7> [183.848153] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling AUX_A

<7> [183.848605] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:507:DDI A/PHY A]

<7> [183.848978] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:516:DDI B/PHY B]

<7> [183.849336] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:518:DP-MST A]

<7> [183.849823] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:519:DP-MST B]

<7> [183.850290] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:520:DP-MST C]

<7> [183.850717] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:521:DP-MST D]

<7> [183.850928] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:534:DDI TC1/PHY TC1]

<7> [183.851067] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:536:DP-MST A]

<7> [183.851213] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:537:DP-MST B]

<7> [183.851366] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:538:DP-MST C]

<7> [183.851526] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:539:DP-MST D]

<7> [183.851665] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:543:DDI TC2/PHY TC2]

<7> [183.851800] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:547:DDI TC3/PHY TC3]

<7> [183.851935] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:549:DP-MST A]

<7> [183.852079] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:550:DP-MST B]

<7> [183.852230] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:551:DP-MST C]

<7> [183.852375] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:552:DP-MST D]

<7> [183.852505] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:556:DDI TC4/PHY TC4]

<7> [183.852646] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:558:DP-MST A]

<7> [183.852788] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:559:DP-MST B]

<7> [183.852924] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:560:DP-MST C]

<7> [183.853052] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:561:DP-MST D]

<7> [183.853208] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:508:eDP-1]

<7> [183.853421] i915 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [i915]] Updating dbuf ratio to 2 (mbus joined: no)

<7> [183.853624] i915 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [i915]] Changing mbus joined: yes -> no (pipe: *)

<7> [183.853765] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1

<7> [183.854009] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_A

<7> [183.854193] i915 0000:00:02.0: [drm:dss_pipe_gating_enable_disable [i915]] DSS clock gating disabled for pipe_mask=0x1 (CLKGATE_DIS_DSSDSC=0xaa000000)

<7> [183.854399] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:151:pipe A]

<7> [183.854590] i915 0000:00:02.0: [drm:intel_set_cdclk [i915]] Post changing CDCLK to 172800 kHz, VCO 614400 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0

<7> [183.854771] i915 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [i915]] aud_ts_cdclk set to M=60, N=432

<7> [183.855099] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:269:pipe B]

<7> [183.855252] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:387:pipe C]

<7> [183.855351] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:505:pipe D]

<7> [183.855452] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:151:pipe A]

<7> [183.855478] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:508:eDP-1]

<7> [183.855532] i915 0000:00:02.0: [drm:compute_baseline_pipe_bpp [i915]] [CONNECTOR:508:eDP-1] Limiting target display pipe bpp to 24 (EDID bpp 30, max requested bpp 24, max platform bpp 36)

<7> [183.855705] i915 0000:00:02.0: [drm:intel_dp_compute_config_limits [i915]] [ENCODER:507:DDI A/PHY A][CRTC:151:pipe A] DP link limits: pixel clock 513820 kHz DSC off max lanes 4 max rate 540000 max pipe_bpp 24 min link_bpp 18.0000 max link_bpp 24.0000

<7> [183.855837] i915 0000:00:02.0: [drm:intel_dp_compute_output_format [i915]] DP lane count 4 clock 432000 bpp input 24 compressed 0.0000 HDR no link rate required 1541460 available 1728000

<7> [183.855978] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:151:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0

<7> [183.856132] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:507:DDI A/PHY A] [CRTC:151:pipe A]

<7> [183.856269] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.enable (expected no, found yes)

<7> [183.856419] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.active (expected no, found yes)

<7> [183.856563] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)

<7> [183.856709] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in lane_count (expected 0, found 4)

<7> [183.856849] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in dp_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 7483046/8388608 link 623587/524288)

<7> [183.856991] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000100)

<7> [183.857135] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)

<7> [183.857275] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 2880)

<7> [183.857414] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 3040)

<7> [183.857554] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 2880)

<7> [183.857693] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 3040)

<7> [183.857831] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2928)

<7> [183.857969] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2960)

<7> [183.858113] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1800)

<7> [183.858260] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1801)

<7> [183.858398] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1803)

<7> [183.858546] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1809)

<7> [183.858692] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1878)

<7> [183.858836] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1878)

<7> [183.858975] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 2880)

<7> [183.859134] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 3040)

<7> [183.859275] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 2880)

<7> [183.859416] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 3040)

<7> [183.859556] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2928)

<7> [183.859695] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2960)

<7> [183.859833] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1800)

<7> [183.859970] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1801)

<7> [183.860115] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1803)

<7> [183.860258] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1809)

<7> [183.860397] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1878)

<7> [183.860536] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1878)

<7> [183.860674] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)

<7> [183.860813] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)

<7> [183.860954] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)

<7> [183.861100] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in enhanced_framing (expected no, found yes)

<7> [183.861243] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)

<7> [183.861381] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 513820)

<7> [183.861520] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 513820)

<7> [183.861656] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in port_clock (expected 0, found 432000)

<7> [183.861795] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in set_context_latency (expected 0, found 1)

<7> [183.861936] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:151:pipe A] fastset requirement not met, forcing full modeset

<7> [183.862132] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0xf (total dbuf slices 0xf), mbus joined? no->yes

<7> [183.862271] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:151:pipe A] dbuf slices 0x0 -> 0xf, ddb (0 - 0) -> (0 - 4096), active pipes 0x0 -> 0x1

<7> [183.862436] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:35:plane 1A] ddb ( 0 - 0) -> ( 0 - 4001), size 0 -> 4001

<7> [183.862572] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:145:cursor A] ddb ( 0 - 0) -> (4001 - 4096), size 0 -> 95

<7> [183.862708] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm

<7> [183.862840] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 4, 16, 16, 19, 19, 28, 0, 0, 0, 4, 0

<7> [183.862974] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 92, 365, 365, 434, 434, 638, 0, 0, 197, 92, 197

<7> [183.863128] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 183, 456, 456, 547, 547, 729, 0, 0, 198, 183, 198

<7> [183.863277] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:151:pipe A] data rate 2055280 num active planes 1

<7> [183.863421] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 28913 required 2159 qgv_peak_bw: 51200

<7> [183.863569] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 1: max bw 38000 required 2159 qgv_peak_bw: 51200

<7> [183.863757] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 2: max bw 38000 required 2159 qgv_peak_bw: 51200

<7> [183.863924] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 3: max bw 38000 required 2159 qgv_peak_bw: 51200

<7> [183.864067] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] Matching peaks QGV bw: 51200 for required data rate: 2159

<7> [183.864229] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:151:pipe A] min cdclk: 0 kHz -> 256910 kHz

<7> [183.864391] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 0 kHz -> 32114 kHz

<7> [183.864535] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Can change cdclk via squashing

<7> [183.864676] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 307200 kHz, actual 307200 kHz

<7> [183.864816] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0

<7> [183.864972] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:151:pipe A] allocated DPLL 0

<7> [183.865136] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:151:pipe A] reserving DPLL 0

<7> [183.865285] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:151:pipe A] enable: yes [modeset]

<7> [183.865428] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: EDP (0x100), output format: RGB, sink format: RGB

<7> [183.865570] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0

<7> [183.865714] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>

<7> [183.865857] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0

<7> [183.865997] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0

<7> [183.866206] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0

<7> [183.866360] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dp m_n: lanes: 4; data_m: 7483046, data_n: 8388608, link_m: 623587, link_n: 524288, tu: 64

<7> [183.866528] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dp m2_n2: lanes: 4; data_m: 0, data_n: 0, link_m: 0, link_n: 0, tu: 0

<7> [183.866670] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] fec: disabled, enhanced framing: enabled

<7> [183.866809] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sdp split: disabled

<7> [183.866947] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] psr: enabled, selective update: enabled, panel replay: disabled, selective fetch: enabled

<7> [183.867101] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] minimum hblank: 0

<7> [183.867245] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x4

<7> [183.867383] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] DP SDP: VSC, revision 4, length 14

<7> [183.867520] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pixelformat: RGB

<7> [183.867657] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] colorimetry: sRGB

<7> [183.867793] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] bpc: 0

<7> [183.867927] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dynamic range: VESA range

<7> [183.868065] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] content type: Not defined

<7> [183.868226] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 1

<7> [183.868365] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 1

<7> [183.868503] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: yes, vmin: 1878, vmax: 1878, flipline: 1878, pipeline full: 0, guardband: 76 vsync start: 75, vsync end: 69

<7> [183.868641] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 1802, vmax vblank: 1802, vmin vtotal: 1878, vmax vtotal: 1878

<7> [183.868783] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0

<7> [183.868949] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "2880x1800": 90 513820 2880 2928 2960 3040 1800 1803 1809 1878 0x48 0xa

<7> [183.869094] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "2880x1800": 90 513820 2880 2928 2960 3040 1800 1803 1809 1878 0x48 0xa

<7> [183.869239] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=513820, hd=2880 hb=2880-3040 hs=2928-2960 ht=3040, vd=1800 vb=1801-1878 vs=1803-1809 vt=1878, flags=0xa

<7> [183.869379] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "2880x1800": 90 513820 2880 2928 2960 3040 1800 1803 1809 1878 0x40 0xa

<7> [183.869517] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=513820, hd=2880 hb=2880-3040 hs=2928-2960 ht=3040, vd=1800 vb=1801-1878 vs=1803-1809 vt=1878, flags=0xa

<7> [183.869654] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 432000, pixel rate 513820, min cdclk 256910, min voltage level 0

<7> [183.869790] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 48, ips linetime: 0

<7> [183.869928] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0

<7> [183.870070] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe src: 2880x1800+0+0

<7> [183.870228] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no

<7> [183.870364] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0

<7> [183.870499] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0

<7> [183.870635] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cx0pll_hw_state: lane_count: 4, ssc_enabled: no, use_c10: yes, tbt_mode: no

<7> [183.870774] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] c10pll_hw_state: fracen: no,

<7> [183.870913] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] multiplier: 225, tx_clk_div: 1.

<7> [183.871050] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] c10pll_rawhw_state:

<7> [183.871198] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] tx: 0x10, cmn: 0x21

<7> [183.871342] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pll[0] = 0x4, pll[1] = 0x0, pll[2] = 0xa2, pll[3] = 0x1

<7> [183.871504] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pll[4] = 0x0, pll[5] = 0x0, pll[6] = 0x0, pll[7] = 0x0

<7> [183.871642] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pll[8] = 0x0, pll[9] = 0x1, pll[10] = 0x0, pll[11] = 0x0

<7> [183.871780] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pll[12] = 0x0, pll[13] = 0x0, pll[14] = 0x0, pll[15] = 0x1

<7> [183.871916] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pll[16] = 0x85, pll[17] = 0xf, pll[18] = 0xe6, pll[19] = 0x23

<7> [183.872056] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0

<7> [183.872208] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries

<7> [183.872347] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000

<7> [183.872485] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000

<7> [183.872622] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000

<7> [183.872758] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000

<7> [183.872898] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000

<7> [183.873037] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000

<7> [183.873195] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000

<7> [183.873215] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off

<7> [183.873355] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000

<7> [183.873491] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000

<7> [183.873493] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6

<7> [183.873626] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000

<7> [183.873711] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02

<7> [183.873764] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:plane 1A] fb: [FB:567] 2880x1800 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes

<7> [183.873907] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0

<7> [183.874061] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 2880.000000x1800.000000+0.000000+0.000000 dst: 2880x1800+0+0

<7> [183.874236] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:65:plane 2A] fb: [NOFB], visible: no

<7> [183.874375] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:95:plane 3A] fb: [NOFB], visible: no

<7> [183.874514] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:125:plane 4A] fb: [NOFB], visible: no

<7> [183.874655] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:135:plane 5A] fb: [NOFB], visible: no

<7> [183.874794] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:145:cursor A] fb: [NOFB], visible: no

<7> [183.878884] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off

<7> [183.879219] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00

<7> [183.879513] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_A

<7> [183.879708] i915 0000:00:02.0: [drm:dss_pipe_gating_enable_disable [i915]] DSS clock gating enabled for pipe_mask=0x1 (CLKGATE_DIS_DSSDSC=0xa8000000)

<7> [183.879883] i915 0000:00:02.0: [drm:intel_set_cdclk [i915]] Pre changing CDCLK to 307200 kHz, VCO 614400 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0

<7> [183.880226] i915 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [i915]] aud_ts_cdclk set to M=60, N=768

<7> [183.880375] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:507:DDI A/PHY A]

<7> [183.880520] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:516:DDI B/PHY B]

<7> [183.880665] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:518:DP-MST A]

<7> [183.880812] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:519:DP-MST B]

<7> [183.880955] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:520:DP-MST C]

<7> [183.881105] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:521:DP-MST D]

<7> [183.881244] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:534:DDI TC1/PHY TC1]

<7> [183.881382] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:536:DP-MST A]

<7> [183.881523] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:537:DP-MST B]

<7> [183.881681] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:538:DP-MST C]

<7> [183.881822] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:539:DP-MST D]

<7> [183.881961] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:543:DDI TC2/PHY TC2]

<7> [183.882108] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:547:DDI TC3/PHY TC3]

<7> [183.882245] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:549:DP-MST A]

<7> [183.882381] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:550:DP-MST B]

<7> [183.882519] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:551:DP-MST C]

<7> [183.882659] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:552:DP-MST D]

<7> [183.882798] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:556:DDI TC4/PHY TC4]

<7> [183.882936] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:558:DP-MST A]

<7> [183.883086] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:559:DP-MST B]

<7> [183.883225] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:560:DP-MST C]

<7> [183.883362] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:561:DP-MST D]

<7> [183.883528] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0xf

<7> [183.883767] i915 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [i915]] Changing mbus joined: no -> yes (pipe: *)

<7> [183.883910] i915 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [i915]] Updating dbuf ratio to 4 (mbus joined: yes)

<7> [183.884123] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling AUX_A

<7> [183.885112] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:151:pipe A]

<7> [183.885259] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0

<7> [183.887386] i915 0000:00:02.0: [drm:intel_pps_on_unlocked [i915]] [ENCODER:507:DDI A/PHY A] PPS 0 turn panel power on

<7> [183.887540] i915 0000:00:02.0: [drm:wait_panel_power_cycle [i915]] [ENCODER:507:DDI A/PHY A] PPS 0 wait for panel power cycle (459 ms remaining)

<7> [184.353744] i915 0000:00:02.0: [drm:wait_panel_status [i915]] [ENCODER:507:DDI A/PHY A] PPS 0 mask: 0xb800000f value: 0x00000000 PP_STATUS: 0x08000001 PP_CONTROL: 0x00000060

<7> [184.364550] i915 0000:00:02.0: [drm:wait_panel_status [i915]] Wait complete

<7> [184.365154] i915 0000:00:02.0: [drm:intel_pps_on_unlocked [i915]] [ENCODER:507:DDI A/PHY A] PPS 0 wait for panel power on

<7> [184.365732] i915 0000:00:02.0: [drm:wait_panel_status [i915]] [ENCODER:507:DDI A/PHY A] PPS 0 mask: 0xb000000f value: 0x80000008 PP_STATUS: 0x9000000a PP_CONTROL: 0x00000063

<7> [184.397281] i915 0000:00:02.0: [drm:icp_irq_handler [i915]] hotplug event received, stat 0x00010000, dig 0x0000008a, pins 0x00000010, long 0x00000010

<7> [184.397867] i915 0000:00:02.0: [drm:intel_hpd_irq_handler [i915]] digital hpd on [ENCODER:507:DDI A/PHY A] - long

<7> [184.398406] i915 0000:00:02.0: [drm:intel_hpd_irq_handler [i915]] Received HPD interrupt on PIN 4 - cnt: 20

<7> [184.398962] i915 0000:00:02.0: [drm:intel_dp_hpd_pulse [i915]] ignoring long hpd on eDP [ENCODER:507:DDI A/PHY A]

<7> [184.481505] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port G/TC#4: TC port mode reset (tbt-alt -> disconnected) pin assignment: - max lanes: 4

<7> [184.481505] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port F/TC#3: TC port mode reset (tbt-alt -> disconnected) pin assignment: - max lanes: 4

<7> [184.481824] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port E/TC#2: TC port mode reset (legacy -> disconnected) pin assignment: - max lanes: 4

<7> [184.482442] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port D/TC#1: TC port mode reset (tbt-alt -> disconnected) pin assignment: - max lanes: 4

<7> [184.571054] i915 0000:00:02.0: [drm:wait_panel_status [i915]] Wait complete

<7> [184.571772] i915 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [i915]] [ENCODER:507:DDI A/PHY A] PPS 0 turning VDD on

<7> [184.572507] i915 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [i915]] [ENCODER:507:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006b

<7> [184.576179] i915 0000:00:02.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX A/DDI A/PHY A: DPCD: 14 14 c4 41 00 00 01 c0 02 00 00 00 00 0b 80

<7> [184.578302] i915 0000:00:02.0: [drm:intel_dp_start_link_train [i915]] [CONNECTOR:508:eDP-1][ENCODER:507:DDI A/PHY A][DPRX] Reloading eDP link rates

<7> [184.579463] i915 0000:00:02.0: [drm:intel_dp_start_link_train [i915]] [CONNECTOR:508:eDP-1][ENCODER:507:DDI A/PHY A][DPRX] Using LINK_RATE_SET value 06

<7> [184.581323] i915 0000:00:02.0: [drm:intel_dp_set_signal_levels [i915]] [CONNECTOR:508:eDP-1][ENCODER:507:DDI A/PHY A][DPRX] 8b/10b, lanes: 4, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0

<7> [184.582902] i915 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [i915]] [CONNECTOR:508:eDP-1][ENCODER:507:DDI A/PHY A][DPRX] Using DP training pattern TPS1

<7> [184.583818] i915 0000:00:02.0: [drm:intel_dp_link_train_phy [i915]] [CONNECTOR:508:eDP-1][ENCODER:507:DDI A/PHY A][DPRX] Clock recovery OK

<7> [184.583979] i915 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [i915]] [CONNECTOR:508:eDP-1][ENCODER:507:DDI A/PHY A][DPRX] Using DP training pattern TPS3

<7> [184.585294] i915 0000:00:02.0: [drm:intel_dp_link_train_phy [i915]] [CONNECTOR:508:eDP-1][ENCODER:507:DDI A/PHY A][DPRX] Channel EQ done. DP Training successful

<7> [184.585526] i915 0000:00:02.0: [drm:intel_dp_link_train_phy [i915]] [CONNECTOR:508:eDP-1][ENCODER:507:DDI A/PHY A][DPRX] Link Training passed at link rate = 432000, lane count = 4

<7> [184.586267] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A

<7> [184.586653] i915 0000:00:02.0: [drm:intel_edp_backlight_on [i915]]

<7> [184.586858] i915 0000:00:02.0: [drm:intel_backlight_enable [i915]] pipe A

<7> [184.587126] i915 0000:00:02.0: [drm:intel_backlight_set_pwm_level [i915]] [CONNECTOR:508:eDP-1] set backlight PWM = 13892

<7> [184.608942] i915 0000:00:02.0: [drm:intel_psr_post_plane_update [i915]] Enabling PSR2

<7> [184.611629] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:508:eDP-1]

<7> [184.612197] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:151:pipe A]

<7> [187.165287] i915 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [i915]] [ENCODER:507:DDI A/PHY A] PPS 0 turning VDD off

<7> [187.165910] i915 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [i915]] [ENCODER:507:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x00000067

<7> [187.848622] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off

<7> [187.849219] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6

<7> [187.849791] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02

<6> [187.978693] [IGT] kms_flip: finished subtest A-eDP1, FAIL

Results for igt@kms_flip@flip-vs-expired-vblank-interruptible@a-edp1