Result:
i915_display_info3 igt_runner3 results3.json results3-xe-load.json boot3 dmesg3
| Detail | Value |
|---|---|
| Duration | 20.61 seconds |
| Hostname |
shard-bmg-6 |
| Igt-Version |
IGT-Version: 2.2-g5af861520 (x86_64) (Linux: 6.18.0-rc5-lgci-xe-xe-pw-157576v1+ x86_64) |
| Out |
Starting dynamic subtest: B-HDMI-A3 1920x1080: 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9 Expected frametime: 16667us; measured 15951.0us +- 1.033us accuracy 0.02% vblank interval differs from modeline! expected 16666.7us, measured 15951us +- 1.033us, difference 715.7us (692.9 sigma) Event flip: expected 470, counted 463, passrate = 4.54%, encoder type 2 Expected frametime: 16667us; measured 16502.3us +- 2.024us accuracy 0.04% vblank interval differs from modeline! expected 16666.7us, measured 16502us +- 2.024us, difference 164.4us (81.2 sigma) Event flip: expected 454, counted 453, passrate = 67.11%, encoder type 2 Stack trace: #0 ../lib/igt_core.c:2075 __igt_fail_assert() #1 ../tests/kms_flip.c:428 run_test_on_crtc_set.constprop.0() #2 ../tests/kms_flip.c:1889 run_test() #3 ../tests/kms_flip.c:2122 __igt_unique____real_main2045() #4 ../tests/kms_flip.c:2045 main() #5 [__libc_init_first+0x8a] #6 [__libc_start_main+0x8b] #7 [_start+0x25] Dynamic subtest B-HDMI-A3: FAIL (20.612s) |
| Err |
Starting dynamic subtest: B-HDMI-A3 (kms_flip:2142) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1709: (kms_flip:2142) CRITICAL: Failed assertion: !retried Dynamic subtest B-HDMI-A3 failed. **** DEBUG **** (kms_flip:2142) DEBUG: flip ts/seq: last 62.324097/1061, current 62.340687/1062: elapsed=16591.0us expected=16502.3us +- 82.5us, error 0.5% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 62.324097/1061, current 62.340687/1062: elapsed=16591.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 62.340687 last_received_ts = 62.340069 last_seq = 1062 current_ts = 62.357281 current_received_ts = 62.356667 current_seq = 1063 count = 373 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 62.340687/1062, current 62.357281/1063: elapsed=16593.0us expected=16502.3us +- 82.5us, error 0.5% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 62.340687/1062, current 62.357281/1063: elapsed=16593.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 62.357281 last_received_ts = 62.356667 last_seq = 1063 current_ts = 62.373871 current_received_ts = 62.373260 current_seq = 1064 count = 374 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 62.357281/1063, current 62.373871/1064: elapsed=16593.0us expected=16502.3us +- 82.5us, error 0.5% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 62.357281/1063, current 62.373871/1064: elapsed=16593.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 62.373871 last_received_ts = 62.373260 last_seq = 1064 current_ts = 62.390465 current_received_ts = 62.389854 current_seq = 1065 count = 375 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 62.373871/1064, current 62.390465/1065: elapsed=16594.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 62.373871/1064, current 62.390465/1065: elapsed=16594.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 62.390465 last_received_ts = 62.389854 last_seq = 1065 current_ts = 62.407059 current_received_ts = 62.406445 current_seq = 1066 count = 376 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 62.390465/1065, current 62.407059/1066: elapsed=16593.0us expected=16502.3us +- 82.5us, error 0.5% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 62.390465/1065, current 62.407059/1066: elapsed=16593.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 62.407059 last_received_ts = 62.406445 last_seq = 1066 current_ts = 62.423656 current_received_ts = 62.423046 current_seq = 1067 count = 377 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 62.407059/1066, current 62.423656/1067: elapsed=16596.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 62.407059/1066, current 62.423656/1067: elapsed=16596.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 62.423656 last_received_ts = 62.423046 last_seq = 1067 current_ts = 62.440247 current_received_ts = 62.439636 current_seq = 1068 count = 378 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 62.423656/1067, current 62.440247/1068: elapsed=16592.0us expected=16502.3us +- 82.5us, error 0.5% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 62.423656/1067, current 62.440247/1068: elapsed=16592.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 62.440247 last_received_ts = 62.439636 last_seq = 1068 current_ts = 62.456841 current_received_ts = 62.456223 current_seq = 1069 count = 379 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 62.440247/1068, current 62.456841/1069: elapsed=16592.0us expected=16502.3us +- 82.5us, error 0.5% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 62.440247/1068, current 62.456841/1069: elapsed=16592.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 62.456841 last_received_ts = 62.456223 last_seq = 1069 current_ts = 62.473434 current_received_ts = 62.472820 current_seq = 1070 count = 380 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 62.456841/1069, current 62.473434/1070: elapsed=16594.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 62.456841/1069, current 62.473434/1070: elapsed=16594.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 62.473434 last_received_ts = 62.472820 last_seq = 1070 current_ts = 62.490025 current_received_ts = 62.489410 current_seq = 1071 count = 381 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 62.473434/1070, current 62.490025/1071: elapsed=16593.0us expected=16502.3us +- 82.5us, error 0.5% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 62.473434/1070, current 62.490025/1071: elapsed=16593.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 62.490025 last_received_ts = 62.489410 last_seq = 1071 current_ts = 62.506618 current_received_ts = 62.506027 current_seq = 1072 count = 382 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 62.490025/1071, current 62.506618/1072: elapsed=16593.0us expected=16502.3us +- 82.5us, error 0.5% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 62.490025/1071, current 62.506618/1072: elapsed=16593.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 62.506618 last_received_ts = 62.506027 last_seq = 1072 current_ts = 62.523228 current_received_ts = 62.522617 current_seq = 1073 count = 383 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 62.506618/1072, current 62.523228/1073: elapsed=16609.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 62.506618/1072, current 62.523228/1073: elapsed=16609.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 62.523228 last_received_ts = 62.522617 last_seq = 1073 current_ts = 62.539825 current_received_ts = 62.539211 current_seq = 1074 count = 384 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 62.523228/1073, current 62.539825/1074: elapsed=16598.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 62.523228/1073, current 62.539825/1074: elapsed=16598.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 62.539825 last_received_ts = 62.539211 last_seq = 1074 current_ts = 62.556427 current_received_ts = 62.555820 current_seq = 1075 count = 385 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 62.539825/1074, current 62.556427/1075: elapsed=16602.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 62.539825/1074, current 62.556427/1075: elapsed=16602.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 62.556427 last_received_ts = 62.555820 last_seq = 1075 current_ts = 62.573032 current_received_ts = 62.572422 current_seq = 1076 count = 386 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 62.556427/1075, current 62.573032/1076: elapsed=16603.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 62.556427/1075, current 62.573032/1076: elapsed=16603.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 62.573032 last_received_ts = 62.572422 last_seq = 1076 current_ts = 62.589634 current_received_ts = 62.589046 current_seq = 1077 count = 387 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 62.573032/1076, current 62.589634/1077: elapsed=16602.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 62.573032/1076, current 62.589634/1077: elapsed=16602.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 62.589634 last_received_ts = 62.589046 last_seq = 1077 current_ts = 62.606236 current_received_ts = 62.605625 current_seq = 1078 count = 388 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 62.589634/1077, current 62.606236/1078: elapsed=16603.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 62.589634/1077, current 62.606236/1078: elapsed=16603.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 62.606236 last_received_ts = 62.605625 last_seq = 1078 current_ts = 62.622837 current_received_ts = 62.622223 current_seq = 1079 count = 389 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 62.606236/1078, current 62.622837/1079: elapsed=16602.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 62.606236/1078, current 62.622837/1079: elapsed=16602.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 62.622837 last_received_ts = 62.622223 last_seq = 1079 current_ts = 62.639442 current_received_ts = 62.638832 current_seq = 1080 count = 390 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 62.622837/1079, current 62.639442/1080: elapsed=16603.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 62.622837/1079, current 62.639442/1080: elapsed=16603.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 62.639442 last_received_ts = 62.638832 last_seq = 1080 current_ts = 62.656044 current_received_ts = 62.655430 current_seq = 1081 count = 391 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 62.639442/1080, current 62.656044/1081: elapsed=16602.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 62.639442/1080, current 62.656044/1081: elapsed=16602.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 62.656044 last_received_ts = 62.655430 last_seq = 1081 current_ts = 62.672646 current_received_ts = 62.672039 current_seq = 1082 count = 392 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 62.656044/1081, current 62.672646/1082: elapsed=16603.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 62.656044/1081, current 62.672646/1082: elapsed=16603.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 62.672646 last_received_ts = 62.672039 last_seq = 1082 current_ts = 62.689251 current_received_ts = 62.688641 current_seq = 1083 count = 393 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 62.672646/1082, current 62.689251/1083: elapsed=16606.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 62.672646/1082, current 62.689251/1083: elapsed=16606.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 62.689251 last_received_ts = 62.688641 last_seq = 1083 current_ts = 62.705853 current_received_ts = 62.705238 current_seq = 1084 count = 394 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 62.689251/1083, current 62.705853/1084: elapsed=16599.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 62.689251/1083, current 62.705853/1084: elapsed=16599.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 62.705853 last_received_ts = 62.705238 last_seq = 1084 current_ts = 62.722454 current_received_ts = 62.721840 current_seq = 1085 count = 395 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 62.705853/1084, current 62.722454/1085: elapsed=16603.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 62.705853/1084, current 62.722454/1085: elapsed=16603.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 62.722454 last_received_ts = 62.721840 last_seq = 1085 current_ts = 62.739056 current_received_ts = 62.738441 current_seq = 1086 count = 396 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 62.722454/1085, current 62.739056/1086: elapsed=16603.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 62.722454/1085, current 62.739056/1086: elapsed=16603.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 62.739056 last_received_ts = 62.738441 last_seq = 1086 current_ts = 62.755661 current_received_ts = 62.755058 current_seq = 1087 count = 397 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 62.739056/1086, current 62.755661/1087: elapsed=16603.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 62.739056/1086, current 62.755661/1087: elapsed=16603.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 62.755661 last_received_ts = 62.755058 last_seq = 1087 current_ts = 62.772263 current_received_ts = 62.771652 current_seq = 1088 count = 398 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 62.755661/1087, current 62.772263/1088: elapsed=16602.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 62.755661/1087, current 62.772263/1088: elapsed=16602.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 62.772263 last_received_ts = 62.771652 last_seq = 1088 current_ts = 62.788864 current_received_ts = 62.788250 current_seq = 1089 count = 399 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 62.772263/1088, current 62.788864/1089: elapsed=16603.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 62.772263/1088, current 62.788864/1089: elapsed=16603.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 62.788864 last_received_ts = 62.788250 last_seq = 1089 current_ts = 62.805466 current_received_ts = 62.804855 current_seq = 1090 count = 400 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 62.788864/1089, current 62.805466/1090: elapsed=16602.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 62.788864/1089, current 62.805466/1090: elapsed=16602.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 62.805466 last_received_ts = 62.804855 last_seq = 1090 current_ts = 62.822071 current_received_ts = 62.821457 current_seq = 1091 count = 401 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 62.805466/1090, current 62.822071/1091: elapsed=16603.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 62.805466/1090, current 62.822071/1091: elapsed=16603.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 62.822071 last_received_ts = 62.821457 last_seq = 1091 current_ts = 62.838673 current_received_ts = 62.838066 current_seq = 1092 count = 402 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 62.822071/1091, current 62.838673/1092: elapsed=16604.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 62.822071/1091, current 62.838673/1092: elapsed=16604.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 62.838673 last_received_ts = 62.838066 last_seq = 1092 current_ts = 62.855282 current_received_ts = 62.854671 current_seq = 1093 count = 403 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 62.838673/1092, current 62.855282/1093: elapsed=16606.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 62.838673/1092, current 62.855282/1093: elapsed=16606.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 62.855282 last_received_ts = 62.854671 last_seq = 1093 current_ts = 62.871880 current_received_ts = 62.871265 current_seq = 1094 count = 404 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 62.855282/1093, current 62.871880/1094: elapsed=16598.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 62.855282/1093, current 62.871880/1094: elapsed=16598.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 62.871880 last_received_ts = 62.871265 last_seq = 1094 current_ts = 62.888481 current_received_ts = 62.887867 current_seq = 1095 count = 405 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 62.871880/1094, current 62.888481/1095: elapsed=16603.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 62.871880/1094, current 62.888481/1095: elapsed=16603.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 62.888481 last_received_ts = 62.887867 last_seq = 1095 current_ts = 62.905083 current_received_ts = 62.904469 current_seq = 1096 count = 406 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 62.888481/1095, current 62.905083/1096: elapsed=16602.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 62.888481/1095, current 62.905083/1096: elapsed=16602.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 62.905083 last_received_ts = 62.904469 last_seq = 1096 current_ts = 62.921684 current_received_ts = 62.921074 current_seq = 1097 count = 407 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 62.905083/1096, current 62.921684/1097: elapsed=16603.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 62.905083/1096, current 62.921684/1097: elapsed=16603.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 62.921684 last_received_ts = 62.921074 last_seq = 1097 current_ts = 62.938290 current_received_ts = 62.937679 current_seq = 1098 count = 408 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 62.921684/1097, current 62.938290/1098: elapsed=16604.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 62.921684/1097, current 62.938290/1098: elapsed=16604.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 62.938290 last_received_ts = 62.937679 last_seq = 1098 current_ts = 62.954891 current_received_ts = 62.954281 current_seq = 1099 count = 409 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 62.938290/1098, current 62.954891/1099: elapsed=16601.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 62.938290/1098, current 62.954891/1099: elapsed=16601.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 62.954891 last_received_ts = 62.954281 last_seq = 1099 current_ts = 62.971493 current_received_ts = 62.970886 current_seq = 1100 count = 410 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 62.954891/1099, current 62.971493/1100: elapsed=16603.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 62.954891/1099, current 62.971493/1100: elapsed=16603.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 62.971493 last_received_ts = 62.970886 last_seq = 1100 current_ts = 62.988098 current_received_ts = 62.987484 current_seq = 1101 count = 411 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 62.971493/1100, current 62.988098/1101: elapsed=16603.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 62.971493/1100, current 62.988098/1101: elapsed=16603.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 62.988098 last_received_ts = 62.987484 last_seq = 1101 current_ts = 63.004700 current_received_ts = 63.004086 current_seq = 1102 count = 412 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 62.988098/1101, current 63.004700/1102: elapsed=16602.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 62.988098/1101, current 63.004700/1102: elapsed=16602.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 63.004700 last_received_ts = 63.004086 last_seq = 1102 current_ts = 63.021301 current_received_ts = 63.020695 current_seq = 1103 count = 413 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 63.004700/1102, current 63.021301/1103: elapsed=16603.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 63.004700/1102, current 63.021301/1103: elapsed=16603.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 63.021301 last_received_ts = 63.020695 last_seq = 1103 current_ts = 63.037907 current_received_ts = 63.037289 current_seq = 1104 count = 414 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 63.021301/1103, current 63.037907/1104: elapsed=16603.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 63.021301/1103, current 63.037907/1104: elapsed=16603.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 63.037907 last_received_ts = 63.037289 last_seq = 1104 current_ts = 63.054508 current_received_ts = 63.053898 current_seq = 1105 count = 415 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 63.037907/1104, current 63.054508/1105: elapsed=16602.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 63.037907/1104, current 63.054508/1105: elapsed=16602.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 63.054508 last_received_ts = 63.053898 last_seq = 1105 current_ts = 63.071110 current_received_ts = 63.070496 current_seq = 1106 count = 416 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 63.054508/1105, current 63.071110/1106: elapsed=16603.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 63.054508/1105, current 63.071110/1106: elapsed=16603.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 63.071110 last_received_ts = 63.070496 last_seq = 1106 current_ts = 63.087711 current_received_ts = 63.087097 current_seq = 1107 count = 417 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 63.071110/1106, current 63.087711/1107: elapsed=16603.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 63.071110/1106, current 63.087711/1107: elapsed=16603.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 63.087711 last_received_ts = 63.087097 last_seq = 1107 current_ts = 63.104317 current_received_ts = 63.103706 current_seq = 1108 count = 418 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 63.087711/1107, current 63.104317/1108: elapsed=16603.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 63.087711/1107, current 63.104317/1108: elapsed=16603.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 63.104317 last_received_ts = 63.103706 last_seq = 1108 current_ts = 63.120918 current_received_ts = 63.120304 current_seq = 1109 count = 419 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 63.104317/1108, current 63.120918/1109: elapsed=16602.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 63.104317/1108, current 63.120918/1109: elapsed=16602.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 63.120918 last_received_ts = 63.120304 last_seq = 1109 current_ts = 63.137524 current_received_ts = 63.136925 current_seq = 1110 count = 420 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 63.120918/1109, current 63.137524/1110: elapsed=16606.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 63.120918/1109, current 63.137524/1110: elapsed=16606.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 63.137524 last_received_ts = 63.136925 last_seq = 1110 current_ts = 63.154129 current_received_ts = 63.153622 current_seq = 1111 count = 421 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 63.137524/1110, current 63.154129/1111: elapsed=16605.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 63.137524/1110, current 63.154129/1111: elapsed=16605.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 63.154129 last_received_ts = 63.153622 last_seq = 1111 current_ts = 63.170734 current_received_ts = 63.170303 current_seq = 1112 count = 422 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 63.154129/1111, current 63.170734/1112: elapsed=16604.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 63.154129/1111, current 63.170734/1112: elapsed=16604.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 63.170734 last_received_ts = 63.170303 last_seq = 1112 current_ts = 63.187336 current_received_ts = 63.186893 current_seq = 1113 count = 423 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 63.170734/1112, current 63.187336/1113: elapsed=16604.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 63.170734/1112, current 63.187336/1113: elapsed=16604.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 63.187336 last_received_ts = 63.186893 last_seq = 1113 current_ts = 63.203930 current_received_ts = 63.203491 current_seq = 1114 count = 424 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 63.187336/1113, current 63.203930/1114: elapsed=16594.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 63.187336/1113, current 63.203930/1114: elapsed=16594.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 63.203930 last_received_ts = 63.203491 last_seq = 1114 current_ts = 63.220535 current_received_ts = 63.220055 current_seq = 1115 count = 425 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 63.203930/1114, current 63.220535/1115: elapsed=16604.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 63.203930/1114, current 63.220535/1115: elapsed=16604.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 63.220535 last_received_ts = 63.220055 last_seq = 1115 current_ts = 63.237133 current_received_ts = 63.236603 current_seq = 1116 count = 426 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 63.220535/1115, current 63.237133/1116: elapsed=16599.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 63.220535/1115, current 63.237133/1116: elapsed=16599.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 63.237133 last_received_ts = 63.236603 last_seq = 1116 current_ts = 63.253746 current_received_ts = 63.253204 current_seq = 1117 count = 427 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 63.237133/1116, current 63.253746/1117: elapsed=16611.0us expected=16502.3us +- 82.5us, error 0.7% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 63.237133/1116, current 63.253746/1117: elapsed=16611.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 63.253746 last_received_ts = 63.253204 last_seq = 1117 current_ts = 63.270340 current_received_ts = 63.269810 current_seq = 1118 count = 428 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 63.253746/1117, current 63.270340/1118: elapsed=16594.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 63.253746/1117, current 63.270340/1118: elapsed=16594.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 63.270340 last_received_ts = 63.269810 last_seq = 1118 current_ts = 63.286953 current_received_ts = 63.286407 current_seq = 1119 count = 429 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 63.270340/1118, current 63.286953/1119: elapsed=16613.0us expected=16502.3us +- 82.5us, error 0.7% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 63.270340/1118, current 63.286953/1119: elapsed=16613.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 63.286953 last_received_ts = 63.286407 last_seq = 1119 current_ts = 63.303543 current_received_ts = 63.303024 current_seq = 1120 count = 430 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 63.286953/1119, current 63.303543/1120: elapsed=16592.0us expected=16502.3us +- 82.5us, error 0.5% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 63.286953/1119, current 63.303543/1120: elapsed=16592.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 63.303543 last_received_ts = 63.303024 last_seq = 1120 current_ts = 63.320145 current_received_ts = 63.319603 current_seq = 1121 count = 431 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 63.303543/1120, current 63.320145/1121: elapsed=16602.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 63.303543/1120, current 63.320145/1121: elapsed=16602.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 63.320145 last_received_ts = 63.319603 last_seq = 1121 current_ts = 63.336754 current_received_ts = 63.336208 current_seq = 1122 count = 432 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 63.320145/1121, current 63.336754/1122: elapsed=16607.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 63.320145/1121, current 63.336754/1122: elapsed=16607.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 63.336754 last_received_ts = 63.336208 last_seq = 1122 current_ts = 63.353352 current_received_ts = 63.352806 current_seq = 1123 count = 433 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 63.336754/1122, current 63.353352/1123: elapsed=16598.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 63.336754/1122, current 63.353352/1123: elapsed=16598.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 63.353352 last_received_ts = 63.352806 last_seq = 1123 current_ts = 63.369953 current_received_ts = 63.369411 current_seq = 1124 count = 434 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 63.353352/1123, current 63.369953/1124: elapsed=16604.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 63.353352/1123, current 63.369953/1124: elapsed=16604.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 63.369953 last_received_ts = 63.369411 last_seq = 1124 current_ts = 63.386562 current_received_ts = 63.386028 current_seq = 1125 count = 435 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 63.369953/1124, current 63.386562/1125: elapsed=16606.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 63.369953/1124, current 63.386562/1125: elapsed=16606.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 63.386562 last_received_ts = 63.386028 last_seq = 1125 current_ts = 63.403156 current_received_ts = 63.402618 current_seq = 1126 count = 436 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 63.386562/1125, current 63.403156/1126: elapsed=16597.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 63.386562/1125, current 63.403156/1126: elapsed=16597.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 63.403156 last_received_ts = 63.402618 last_seq = 1126 current_ts = 63.419769 current_received_ts = 63.419224 current_seq = 1127 count = 437 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 63.403156/1126, current 63.419769/1127: elapsed=16611.0us expected=16502.3us +- 82.5us, error 0.7% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 63.403156/1126, current 63.419769/1127: elapsed=16611.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 63.419769 last_received_ts = 63.419224 last_seq = 1127 current_ts = 63.436375 current_received_ts = 63.435833 current_seq = 1128 count = 438 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 63.419769/1127, current 63.436375/1128: elapsed=16605.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 63.419769/1127, current 63.436375/1128: elapsed=16605.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 63.436375 last_received_ts = 63.435833 last_seq = 1128 current_ts = 63.452969 current_received_ts = 63.452423 current_seq = 1129 count = 439 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 63.436375/1128, current 63.452969/1129: elapsed=16596.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 63.436375/1128, current 63.452969/1129: elapsed=16596.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 63.452969 last_received_ts = 63.452423 last_seq = 1129 current_ts = 63.469582 current_received_ts = 63.469044 current_seq = 1130 count = 440 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 63.452969/1129, current 63.469582/1130: elapsed=16611.0us expected=16502.3us +- 82.5us, error 0.7% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 63.452969/1129, current 63.469582/1130: elapsed=16611.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 63.469582 last_received_ts = 63.469044 last_seq = 1130 current_ts = 63.486176 current_received_ts = 63.485634 current_seq = 1131 count = 441 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 63.469582/1130, current 63.486176/1131: elapsed=16594.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 63.469582/1130, current 63.486176/1131: elapsed=16594.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 63.486176 last_received_ts = 63.485634 last_seq = 1131 current_ts = 63.502777 current_received_ts = 63.502232 current_seq = 1132 count = 442 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 63.486176/1131, current 63.502777/1132: elapsed=16603.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 63.486176/1131, current 63.502777/1132: elapsed=16603.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 63.502777 last_received_ts = 63.502232 last_seq = 1132 current_ts = 63.519382 current_received_ts = 63.518845 current_seq = 1133 count = 443 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 63.502777/1132, current 63.519382/1133: elapsed=16606.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 63.502777/1132, current 63.519382/1133: elapsed=16606.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 63.519382 last_received_ts = 63.518845 last_seq = 1133 current_ts = 63.536007 current_received_ts = 63.535465 current_seq = 1134 count = 444 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 63.519382/1133, current 63.536007/1134: elapsed=16622.0us expected=16502.3us +- 82.5us, error 0.7% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 63.519382/1133, current 63.536007/1134: elapsed=16622.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 63.536007 last_received_ts = 63.535465 last_seq = 1134 current_ts = 63.552605 current_received_ts = 63.552059 current_seq = 1135 count = 445 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 63.536007/1134, current 63.552605/1135: elapsed=16600.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 63.536007/1134, current 63.552605/1135: elapsed=16600.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 63.552605 last_received_ts = 63.552059 last_seq = 1135 current_ts = 63.569214 current_received_ts = 63.568684 current_seq = 1136 count = 446 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 63.552605/1135, current 63.569214/1136: elapsed=16608.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 63.552605/1135, current 63.569214/1136: elapsed=16608.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 63.569214 last_received_ts = 63.568684 last_seq = 1136 current_ts = 63.585827 current_received_ts = 63.585281 current_seq = 1137 count = 447 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 63.569214/1136, current 63.585827/1137: elapsed=16613.0us expected=16502.3us +- 82.5us, error 0.7% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 63.569214/1136, current 63.585827/1137: elapsed=16613.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 63.585827 last_received_ts = 63.585281 last_seq = 1137 current_ts = 63.602448 current_received_ts = 63.601902 current_seq = 1138 count = 448 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 63.585827/1137, current 63.602448/1138: elapsed=16620.0us expected=16502.3us +- 82.5us, error 0.7% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 63.585827/1137, current 63.602448/1138: elapsed=16620.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 63.602448 last_received_ts = 63.601902 last_seq = 1138 current_ts = 63.619049 current_received_ts = 63.618504 current_seq = 1139 count = 449 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 63.602448/1138, current 63.619049/1139: elapsed=16601.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 63.602448/1138, current 63.619049/1139: elapsed=16601.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 63.619049 last_received_ts = 63.618504 last_seq = 1139 current_ts = 63.635666 current_received_ts = 63.635139 current_seq = 1140 count = 450 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 63.619049/1139, current 63.635666/1140: elapsed=16616.0us expected=16502.3us +- 82.5us, error 0.7% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 63.619049/1139, current 63.635666/1140: elapsed=16616.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 63.635666 last_received_ts = 63.635139 last_seq = 1140 current_ts = 63.652267 current_received_ts = 63.651730 current_seq = 1141 count = 451 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 63.635666/1140, current 63.652267/1141: elapsed=16605.0us expected=16502.3us +- 82.5us, error 0.6% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 63.635666/1140, current 63.652267/1141: elapsed=16605.0us expected=16502.3us (kms_flip:2142) DEBUG: name = flip last_ts = 63.652267 last_received_ts = 63.651730 last_seq = 1141 current_ts = 63.668880 current_received_ts = 63.668335 current_seq = 1142 count = 452 seq_step = 1 (kms_flip:2142) DEBUG: flip ts/seq: last 63.652267/1141, current 63.668880/1142: elapsed=16612.0us expected=16502.3us +- 82.5us, error 0.7% (kms_flip:2142) DEBUG: inconsistent flip ts/seq: last 63.652267/1141, current 63.668880/1142: elapsed=16612.0us expected=16502.3us (kms_flip:2142) INFO: Event flip: expected 454, counted 453, passrate = 67.11%, encoder type 2 (kms_flip:2142) DEBUG: dropped frames, expected 454, counted 453, passrate = 67.11%, encoder type 2 (kms_flip:2142) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1709: (kms_flip:2142) CRITICAL: Failed assertion: !retried (kms_flip:2142) igt_core-INFO: Stack trace: (kms_flip:2142) igt_core-INFO: #0 ../lib/igt_core.c:2075 __igt_fail_assert() (kms_flip:2142) igt_core-INFO: #1 ../tests/kms_flip.c:428 run_test_on_crtc_set.constprop.0() (kms_flip:2142) igt_core-INFO: #2 ../tests/kms_flip.c:1889 run_test() (kms_flip:2142) igt_core-INFO: #3 ../tests/kms_flip.c:2122 __igt_unique____real_main2045() (kms_flip:2142) igt_core-INFO: #4 ../tests/kms_flip.c:2045 main() (kms_flip:2142) igt_core-INFO: #5 [__libc_init_first+0x8a] (kms_flip:2142) igt_core-INFO: #6 [__libc_start_main+0x8b] (kms_flip:2142) igt_core-INFO: #7 [_start+0x25] **** END **** Dynamic subtest B-HDMI-A3: FAIL (20.612s) |
| Dmesg |
<6> [44.763122] [IGT] kms_flip: starting dynamic subtest B-HDMI-A3
<7> [44.763778] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:313]
<7> [44.764178] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:314]
<7> [44.820404] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:88:pipe A]
<7> [44.820517] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [44.820731] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [44.820920] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [44.821120] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [44.821284] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [44.821447] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [44.821610] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [44.821772] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [44.821928] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [44.822093] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [44.822237] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [44.822381] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [44.822528] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [44.822675] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [44.822820] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1083, found 0)
<7> [44.822957] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1088, found 0)
<7> [44.823093] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [44.823241] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [44.823367] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [44.823492] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [44.823614] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [44.823741] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [44.823860] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [44.823990] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [44.824113] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [44.824248] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [44.824368] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1083, found 0)
<7> [44.824484] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1088, found 0)
<7> [44.824595] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [44.824705] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [44.824815] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [44.824923] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [44.825028] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [44.825133] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [44.825246] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [44.825352] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in pipe_bpp (expected 36, found 0)
<7> [44.825455] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [44.825556] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [44.825657] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in port_clock (expected 222750, found 0)
<7> [44.825758] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [44.825854] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in infoframes.gcp (expected 0x00000006, found 0x00000000)
<7> [44.825949] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in avi infoframe
<7> [44.826044] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [44.826136] xe 0000:03:00.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [44.826137] xe 0000:03:00.0: colorspace: RGB
<7> [44.826139] xe 0000:03:00.0: scan mode: Underscan
<7> [44.826140] xe 0000:03:00.0: colorimetry: No Data
<7> [44.826141] xe 0000:03:00.0: picture aspect: No Data
<7> [44.826142] xe 0000:03:00.0: active aspect: Same as Picture
<7> [44.826143] xe 0000:03:00.0: itc: No Data
<7> [44.826144] xe 0000:03:00.0: extended colorimetry: xvYCC 601
<7> [44.826146] xe 0000:03:00.0: quantization range: Full
<7> [44.826147] xe 0000:03:00.0: nups: Unknown Non-uniform Scaling
<7> [44.826148] xe 0000:03:00.0: video code: 0
<7> [44.826149] xe 0000:03:00.0: ycc quantization range: Full
<7> [44.826150] xe 0000:03:00.0: hdmi content type: Graphics
<7> [44.826151] xe 0000:03:00.0: pixel repeat: 0
<7> [44.826152] xe 0000:03:00.0: bar top 0, bottom 0, left 0, right 0
<7> [44.826154] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [44.826255] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in spd infoframe
<7> [44.826345] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [44.826435] xe 0000:03:00.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [44.826437] xe 0000:03:00.0: vendor: Intel
<7> [44.826438] xe 0000:03:00.0: product: Discrete gfx
<7> [44.826439] xe 0000:03:00.0: source device information: PC General (0x9)
<7> [44.826440] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [44.826531] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:88:pipe A] fastset requirement not met in hdmi infoframe
<7> [44.826621] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [44.826712] xe 0000:03:00.0: HDMI infoframe: Vendor, version 1, length 4
<7> [44.826713] xe 0000:03:00.0: empty frame
<7> [44.826714] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [44.826800] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:88:pipe A] fastset requirement not met, forcing full modeset
<7> [44.826915] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xf -> 0x1 (total dbuf slices 0xf), mbus joined? yes->no
<7> [44.826995] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:88:pipe A] dbuf slices 0xf -> 0x0, ddb (0 - 4096) -> (0 - 0), active pipes 0x1 -> 0x0
<7> [44.827092] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:33:plane 1A] ddb ( 0 - 4037) -> ( 0 - 0), size 4037 -> 0
<7> [44.827168] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:83:cursor A] ddb (4037 - 4096) -> ( 0 - 0), size 59 -> 0
<7> [44.827242] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:33:plane 1A] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [44.827327] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:33:plane 1A] lines 4, 4, 9, 9, 17, 17, 17, 17, 0, 15, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [44.827400] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:33:plane 1A] blocks 62, 62, 139, 139, 261, 261, 261, 261, 137, 230, 244 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [44.827471] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:33:plane 1A] min_ddb 123, 123, 245, 245, 367, 367, 0, 0, 138, 306, 306 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [44.827553] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] [CRTC:88:pipe A] data rate 0 num active planes 0
<7> [44.827649] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 33600 required 0 qgv_peak_bw: 48000
<7> [44.827741] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 53000 required 0 qgv_peak_bw: 48000
<7> [44.827832] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 48000 for required data rate: 0
<7> [44.827922] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:88:pipe A] min cdclk: 192000 kHz -> 0 kHz
<7> [44.828025] xe 0000:03:00.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [xe]] dbuf bandwidth min cdclk: 9282 kHz -> 0 kHz
<7> [44.828128] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:88:pipe A] enable: no [modeset]
<7> [44.828221] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:33:plane 1A] fb: [NOFB], visible: no
<7> [44.828312] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:43:plane 2A] fb: [NOFB], visible: no
<7> [44.828410] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:53:plane 3A] fb: [NOFB], visible: no
<7> [44.828501] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:63:plane 4A] fb: [NOFB], visible: no
<7> [44.828589] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:73:plane 5A] fb: [NOFB], visible: no
<7> [44.828676] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:83:cursor A] fb: [NOFB], visible: no
<7> [44.828969] xe 0000:03:00.0: [drm:intel_audio_codec_disable [xe]] [CONNECTOR:293:HDMI-A-3][ENCODER:292:DDI TC3/PHY H] Disable audio codec on [CRTC:88:pipe A]
<7> [44.858194] xe 0000:03:00.0: [drm:intel_audio_component_get_eld [xe]] Not valid for port F
<7> [44.858892] xe 0000:03:00.0: [drm:intel_disable_transcoder [xe]] disabling pipe A
<7> [44.879472] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:261:DDI TC1/PHY F]
<7> [44.879952] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:263:DP-MST A]
<7> [44.880376] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:264:DP-MST B]
<7> [44.880820] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:265:DP-MST C]
<7> [44.881241] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:266:DP-MST D]
<7> [44.881666] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:280:DDI TC2/PHY G]
<7> [44.882052] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:282:DP-MST A]
<7> [44.882432] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:283:DP-MST B]
<7> [44.882836] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:284:DP-MST C]
<7> [44.882941] xe 0000:03:00.0: [drm:intel_audio_component_get_power [xe]] restored AUD_FREQ_CNTRL to 0x8010
<7> [44.883227] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:285:DP-MST D]
<7> [44.883609] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:292:DDI TC3/PHY H]
<7> [44.884034] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:296:DDI TC4/PHY I]
<7> [44.884409] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:298:DP-MST A]
<7> [44.884803] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:299:DP-MST B]
<6> [44.885116] pcieport 0000:00:01.0: PME: Spurious native interrupt!
<7> [44.885183] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:300:DP-MST C]
<7> [44.885561] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:301:DP-MST D]
<7> [44.885959] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:293:HDMI-A-3]
<7> [44.886369] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 2 (mbus joined: no)
<7> [44.886751] xe 0000:03:00.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: yes -> no (pipe: *)
<7> [44.887167] xe 0000:03:00.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0x1
<7> [44.887886] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling PW_A
<7> [44.888416] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:88:pipe A]
<7> [44.889425] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:145:pipe B]
<7> [44.889838] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:202:pipe C]
<7> [44.890142] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:259:pipe D]
<7> [44.890419] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:145:pipe B]
<7> [44.890480] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CONNECTOR:293:HDMI-A-3]
<7> [44.890666] xe 0000:03:00.0: [drm:intel_hdmi_compute_clock [xe]] picking 12 bpc for HDMI output (pipe bpp: 36)
<7> [44.891070] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:145:pipe B] hw max bpp: 36, pipe bpp: 36, dithering: 0
<7> [44.891422] xe 0000:03:00.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:292:DDI TC3/PHY H] [CRTC:145:pipe B]
<7> [44.891755] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.enable (expected no, found yes)
<7> [44.892062] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.active (expected no, found yes)
<7> [44.892388] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in cpu_transcoder (expected -1, found 1)
<7> [44.892660] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in lane_count (expected 0, found 4)
<7> [44.892920] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [44.893185] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [44.893434] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [44.893677] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [44.893904] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [44.894116] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [44.894352] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [44.894566] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [44.894780] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [44.894983] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [44.895175] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1083)
<7> [44.895379] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1088)
<7> [44.895557] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [44.895735] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [44.895907] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [44.896079] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [44.896249] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [44.896433] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [44.896587] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [44.896738] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [44.896892] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [44.897040] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [44.897029] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling PW_2
<7> [44.897187] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1083)
<7> [44.897341] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1088)
<7> [44.897477] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [44.897615] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [44.897750] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [44.897884] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [44.898013] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [44.898137] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in has_hdmi_sink (expected no, found yes)
<7> [44.898260] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in has_infoframe (expected no, found yes)
<7> [44.898387] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in dpll_hw_state.cx0pll C20
<7> [44.898504] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [44.898620] xe 0000:03:00.0: [drm:intel_cx0pll_dump_hw_state [xe]] c20pll_hw_state:
<7> [44.898749] xe 0000:03:00.0: [drm:intel_cx0pll_dump_hw_state [xe]] tx[0] = 0x0000, tx[1] = 0x0000, tx[2] = 0x0000
<7> [44.898866] xe 0000:03:00.0: [drm:intel_cx0pll_dump_hw_state [xe]] cmn[0] = 0x0000, cmn[1] = 0x0000, cmn[2] = 0x0000, cmn[3] = 0x0000
<7> [44.898980] xe 0000:03:00.0: [drm:intel_cx0pll_dump_hw_state [xe]] mplla[0] = 0x0000
<7> [44.899087] xe 0000:03:00.0: [drm:intel_cx0pll_dump_hw_state [xe]] mplla[1] = 0x0000
<7> [44.899196] xe 0000:03:00.0: [drm:intel_cx0pll_dump_hw_state [xe]] mplla[2] = 0x0000
<7> [44.899297] xe 0000:03:00.0: [drm:intel_cx0pll_dump_hw_state [xe]] mplla[3] = 0x0000
<7> [44.899407] xe 0000:03:00.0: [drm:intel_cx0pll_dump_hw_state [xe]] mplla[4] = 0x0000
<7> [44.899510] xe 0000:03:00.0: [drm:intel_cx0pll_dump_hw_state [xe]] mplla[5] = 0x0000
<7> [44.899613] xe 0000:03:00.0: [drm:intel_cx0pll_dump_hw_state [xe]] mplla[6] = 0x0000
<7> [44.899712] xe 0000:03:00.0: [drm:intel_cx0pll_dump_hw_state [xe]] mplla[7] = 0x0000
<7> [44.899808] xe 0000:03:00.0: [drm:intel_cx0pll_dump_hw_state [xe]] mplla[8] = 0x0000
<7> [44.899903] xe 0000:03:00.0: [drm:intel_cx0pll_dump_hw_state [xe]] mplla[9] = 0x0000
<7> [44.899994] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [44.900091] xe 0000:03:00.0: [drm:intel_cx0pll_dump_hw_state [xe]] c20pll_hw_state:
<7> [44.900184] xe 0000:03:00.0: [drm:intel_cx0pll_dump_hw_state [xe]] tx[0] = 0xbe88, tx[1] = 0x5800, tx[2] = 0x0000
<7> [44.900273] xe 0000:03:00.0: [drm:intel_cx0pll_dump_hw_state [xe]] cmn[0] = 0x0500, cmn[1] = 0x0005, cmn[2] = 0x0000, cmn[3] = 0x0000
<7> [44.900361] xe 0000:03:00.0: [drm:intel_cx0pll_dump_hw_state [xe]] mpllb[0] = 0x40e8
<7> [44.900455] xe 0000:03:00.0: [drm:intel_cx0pll_dump_hw_state [xe]] mpllb[1] = 0x7d20
<7> [44.900541] xe 0000:03:00.0: [drm:intel_cx0pll_dump_hw_state [xe]] mpllb[2] = 0x0a06
<7> [44.900626] xe 0000:03:00.0: [drm:intel_cx0pll_dump_hw_state [xe]] mpllb[3] = 0x8f1c
<7> [44.900709] xe 0000:03:00.0: [drm:intel_cx0pll_dump_hw_state [xe]] mpllb[4] = 0x0000
<7> [44.900794] xe 0000:03:00.0: [drm:intel_cx0pll_dump_hw_state [xe]] mpllb[5] = 0x0000
<7> [44.900876] xe 0000:03:00.0: [drm:intel_cx0pll_dump_hw_state [xe]] mpllb[6] = 0x2200
<7> [44.900959] xe 0000:03:00.0: [drm:intel_cx0pll_dump_hw_state [xe]] mpllb[7] = 0xffff
<7> [44.901042] xe 0000:03:00.0: [drm:intel_cx0pll_dump_hw_state [xe]] mpllb[8] = 0x0400
<7> [44.901125] xe 0000:03:00.0: [drm:intel_cx0pll_dump_hw_state [xe]] mpllb[9] = 0x0000
<7> [44.901208] xe 0000:03:00.0: [drm:intel_cx0pll_dump_hw_state [xe]] mpllb[10] = 0x0001
<7> [44.901291] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in pipe_bpp (expected 0, found 36)
<7> [44.901382] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [44.901476] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [44.901575] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in port_clock (expected 0, found 222750)
<7> [44.901662] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071)
<7> [44.901749] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in infoframes.gcp (expected 0x00000000, found 0x00000006)
<7> [44.901836] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in avi infoframe
<7> [44.901922] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [44.902008] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [44.902092] xe 0000:03:00.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [44.902094] xe 0000:03:00.0: colorspace: RGB
<7> [44.902095] xe 0000:03:00.0: scan mode: Underscan
<7> [44.902096] xe 0000:03:00.0: colorimetry: No Data
<7> [44.902097] xe 0000:03:00.0: picture aspect: No Data
<7> [44.902098] xe 0000:03:00.0: active aspect: Same as Picture
<7> [44.902099] xe 0000:03:00.0: itc: No Data
<7> [44.902100] xe 0000:03:00.0: extended colorimetry: xvYCC 601
<7> [44.902101] xe 0000:03:00.0: quantization range: Full
<7> [44.902102] xe 0000:03:00.0: nups: Unknown Non-uniform Scaling
<7> [44.902103] xe 0000:03:00.0: video code: 0
<7> [44.902104] xe 0000:03:00.0: ycc quantization range: Full
<7> [44.902105] xe 0000:03:00.0: hdmi content type: Graphics
<7> [44.902106] xe 0000:03:00.0: pixel repeat: 0
<7> [44.902107] xe 0000:03:00.0: bar top 0, bottom 0, left 0, right 0
<7> [44.902109] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in spd infoframe
<7> [44.902193] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [44.902278] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [44.902363] xe 0000:03:00.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [44.902364] xe 0000:03:00.0: vendor: Intel
<7> [44.902365] xe 0000:03:00.0: product: Discrete gfx
<7> [44.902367] xe 0000:03:00.0: source device information: PC General (0x9)
<7> [44.902368] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hdmi infoframe
<7> [44.902453] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [44.902544] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [44.902629] xe 0000:03:00.0: HDMI infoframe: Vendor, version 1, length 4
<7> [44.902631] xe 0000:03:00.0: empty frame
<7> [44.902632] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:145:pipe B] fastset requirement not met, forcing full modeset
<7> [44.902748] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0x1 -> 0xf (total dbuf slices 0xf), mbus joined? no->yes
<7> [44.902839] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:145:pipe B] dbuf slices 0x0 -> 0xf, ddb (0 - 0) -> (0 - 4096), active pipes 0x0 -> 0x2
<7> [44.902934] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:90:plane 1B] ddb ( 0 - 0) -> ( 0 - 4037), size 0 -> 4037
<7> [44.903009] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:140:cursor B] ddb ( 0 - 0) -> (4037 - 4096), size 0 -> 59
<7> [44.903086] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:90:plane 1B] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm
<7> [44.903160] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:90:plane 1B] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 4, 4, 9, 9, 17, 17, 17, 17, 0, 15, 0
<7> [44.903234] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:90:plane 1B] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 62, 62, 139, 139, 261, 261, 261, 261, 137, 230, 244
<7> [44.903307] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:90:plane 1B] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 123, 123, 245, 245, 367, 367, 0, 0, 138, 306, 306
<7> [44.903387] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] [CRTC:145:pipe B] data rate 594000 num active planes 1
<7> [44.903481] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 33600 required 624 qgv_peak_bw: 48000
<7> [44.903581] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 53000 required 624 qgv_peak_bw: 48000
<7> [44.903673] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 48000 for required data rate: 624
<7> [44.903765] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:145:pipe B] min cdclk: 0 kHz -> 192000 kHz
<7> [44.903868] xe 0000:03:00.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [xe]] dbuf bandwidth min cdclk: 0 kHz -> 9282 kHz
<7> [44.903968] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:145:pipe B] enable: yes [modeset]
<7> [44.904061] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [44.904151] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: B, pipe bpp: 36, dithering: 0
<7> [44.904240] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [44.904326] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [44.904412] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [44.904498] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [44.904583] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [44.904678] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] GCP: 0x6
<7> [44.904765] xe 0000:03:00.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [44.904766] xe 0000:03:00.0: colorspace: RGB
<7> [44.904767] xe 0000:03:00.0: scan mode: Underscan
<7> [44.904768] xe 0000:03:00.0: colorimetry: No Data
<7> [44.904769] xe 0000:03:00.0: picture aspect: No Data
<7> [44.904770] xe 0000:03:00.0: active aspect: Same as Picture
<7> [44.904771] xe 0000:03:00.0: itc: No Data
<7> [44.904772] xe 0000:03:00.0: extended colorimetry: xvYCC 601
<7> [44.904773] xe 0000:03:00.0: quantization range: Full
<7> [44.904774] xe 0000:03:00.0: nups: Unknown Non-uniform Scaling
<7> [44.904775] xe 0000:03:00.0: video code: 0
<7> [44.904776] xe 0000:03:00.0: ycc quantization range: Full
<7> [44.904777] xe 0000:03:00.0: hdmi content type: Graphics
<7> [44.904778] xe 0000:03:00.0: pixel repeat: 0
<7> [44.904779] xe 0000:03:00.0: bar top 0, bottom 0, left 0, right 0
<7> [44.904780] xe 0000:03:00.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [44.904782] xe 0000:03:00.0: vendor: Intel
<7> [44.904783] xe 0000:03:00.0: product: Discrete gfx
<7> [44.904784] xe 0000:03:00.0: source device information: PC General (0x9)
<7> [44.904785] xe 0000:03:00.0: HDMI infoframe: Vendor, version 1, length 4
<7> [44.904786] xe 0000:03:00.0: empty frame
<7> [44.904787] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 10 00 07 00 66 10 00 01 00 00 00 00 00 00 00 00
<7> [44.904873] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 05 e3 cd 0c 32 38 45 38 35 30 09 07 07 00 00 00
<7> [44.904958] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [44.905042] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [44.905127] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vrr: no, fixed rr: yes, vmin: 1125, vmax: 1125, flipline: 1125, pipeline full: 0, guardband: 45 vsync start: 42, vsync end: 37
<7> [44.905214] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vrr: vmin vblank: 1080, vmax vblank: 1080, vmin vtotal: 1125, vmax vtotal: 1125
<7> [44.905298] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] requested mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9
<7> [44.905382] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] adjusted mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9
<7> [44.905465] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1083-1088 vt=1125, flags=0x9
<7> [44.905549] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x40 0x9
<7> [44.905634] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1083-1088 vt=1125, flags=0x9
<7> [44.905726] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port clock: 222750, pipe src: 1920x1080+0+0, pixel rate 148500, min cdclk 192000
<7> [44.905812] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] linetime: 119, ips linetime: 0
<7> [44.905896] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [44.905979] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [44.906063] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [44.906149] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] dpll_hw_state: dpll: 0x3661e, dpll_md: 0x5800be88, fp0: 0x5000000, fp1: 0x5
<7> [44.906234] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [44.906319] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [44.906405] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [44.906489] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [44.906574] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [44.906659] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [44.906755] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [44.906840] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [44.906924] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [44.907008] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [44.907091] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [44.907175] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [44.907259] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [44.907341] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:90:plane 1B] fb: [FB:313] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [44.907425] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [44.907508] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [44.907591] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:100:plane 2B] fb: [NOFB], visible: no
<7> [44.907675] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:110:plane 3B] fb: [NOFB], visible: no
<7> [44.907765] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:120:plane 4B] fb: [NOFB], visible: no
<7> [44.907850] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:130:plane 5B] fb: [NOFB], visible: no
<7> [44.907934] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:140:cursor B] fb: [NOFB], visible: no
<7> [44.908330] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling PW_2
<7> [44.908459] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling PW_B
<7> [44.908597] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:261:DDI TC1/PHY F]
<7> [44.908680] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:263:DP-MST A]
<7> [44.908760] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:264:DP-MST B]
<7> [44.908848] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:265:DP-MST C]
<7> [44.908925] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:266:DP-MST D]
<7> [44.909003] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:280:DDI TC2/PHY G]
<7> [44.909080] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:282:DP-MST A]
<7> [44.909156] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:283:DP-MST B]
<7> [44.909230] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:284:DP-MST C]
<7> [44.909305] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:285:DP-MST D]
<7> [44.909380] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:292:DDI TC3/PHY H]
<7> [44.909457] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:296:DDI TC4/PHY I]
<7> [44.909536] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:298:DP-MST A]
<7> [44.909615] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:299:DP-MST B]
<7> [44.909692] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:300:DP-MST C]
<7> [44.909769] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:301:DP-MST D]
<7> [44.909879] xe 0000:03:00.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xf
<7> [44.910036] xe 0000:03:00.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: no -> yes (pipe: *)
<7> [44.910112] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: yes)
<7> [44.912341] xe 0000:03:00.0: [drm:intel_enable_transcoder [xe]] enabling pipe B
<7> [44.930527] xe 0000:03:00.0: [drm:intel_audio_codec_enable [xe]] [CONNECTOR:293:HDMI-A-3][ENCODER:292:DDI TC3/PHY H] Enable audio codec on [CRTC:145:pipe B], 32 bytes ELD
<7> [44.943076] xe 0000:03:00.0: [drm:intel_audio_component_get_power [xe]] restored AUD_FREQ_CNTRL to 0x8010
<7> [44.947062] xe 0000:03:00.0: [drm:audio_config_hdmi_pixel_clock [xe]] Configuring HDMI audio for pixel clock 148500 (0x00090000)
<7> [44.947221] xe 0000:03:00.0: [drm:hsw_audio_config_update [xe]] using automatic N
<7> [44.947534] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:293:HDMI-A-3]
<7> [44.947691] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:145:pipe B]
<7> [56.026368] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:88:pipe A]
<7> [56.027254] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:145:pipe B]
<7> [56.027514] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.enable (expected yes, found no)
<7> [56.028095] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.active (expected yes, found no)
<7> [56.028580] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in cpu_transcoder (expected 1, found -1)
<7> [56.029085] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in lane_count (expected 4, found 0)
<7> [56.029557] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [56.030040] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [56.030492] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [56.030956] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [56.031403] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [56.031837] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [56.031936] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [56.032021] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [56.032100] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [56.032177] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [56.032255] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1083, found 0)
<7> [56.032331] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1088, found 0)
<7> [56.032408] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [56.032483] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [56.032558] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [56.032633] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [56.032709] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [56.032784] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [56.032860] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [56.032955] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [56.033031] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [56.033108] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [56.033186] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1083, found 0)
<7> [56.033262] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1088, found 0)
<7> [56.033339] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [56.033416] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [56.033493] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [56.033572] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [56.033651] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [56.033727] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [56.033804] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [56.033884] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in pipe_bpp (expected 36, found 0)
<7> [56.033963] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [56.034041] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [56.034118] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in port_clock (expected 222750, found 0)
<7> [56.034195] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [56.034272] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in infoframes.gcp (expected 0x00000006, found 0x00000000)
<7> [56.034349] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in avi infoframe
<7> [56.034426] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [56.034504] xe 0000:03:00.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [56.034506] xe 0000:03:00.0: colorspace: RGB
<7> [56.034507] xe 0000:03:00.0: scan mode: Underscan
<7> [56.034508] xe 0000:03:00.0: colorimetry: No Data
<7> [56.034509] xe 0000:03:00.0: picture aspect: No Data
<7> [56.034510] xe 0000:03:00.0: active aspect: Same as Picture
<7> [56.034511] xe 0000:03:00.0: itc: No Data
<7> [56.034512] xe 0000:03:00.0: extended colorimetry: xvYCC 601
<7> [56.034513] xe 0000:03:00.0: quantization range: Full
<7> [56.034514] xe 0000:03:00.0: nups: Unknown Non-uniform Scaling
<7> [56.034515] xe 0000:03:00.0: video code: 0
<7> [56.034516] xe 0000:03:00.0: ycc quantization range: Full
<7> [56.034516] xe 0000:03:00.0: hdmi content type: Graphics
<7> [56.034517] xe 0000:03:00.0: pixel repeat: 0
<7> [56.034518] xe 0000:03:00.0: bar top 0, bottom 0, left 0, right 0
<7> [56.034520] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [56.034598] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in spd infoframe
<7> [56.034675] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [56.034753] xe 0000:03:00.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [56.034754] xe 0000:03:00.0: vendor: Intel
<7> [56.034755] xe 0000:03:00.0: product: Discrete gfx
<7> [56.034757] xe 0000:03:00.0: source device information: PC General (0x9)
<7> [56.034758] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [56.034836] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hdmi infoframe
<7> [56.034920] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [56.034999] xe 0000:03:00.0: HDMI infoframe: Vendor, version 1, length 4
<7> [56.035000] xe 0000:03:00.0: empty frame
<7> [56.035001] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [56.035078] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:145:pipe B] fastset requirement not met, forcing full modeset
<7> [56.035183] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xf -> 0x1 (total dbuf slices 0xf), mbus joined? yes->no
<7> [56.035255] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:145:pipe B] dbuf slices 0xf -> 0x0, ddb (0 - 4096) -> (0 - 0), active pipes 0x2 -> 0x0
<7> [56.035341] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:90:plane 1B] ddb ( 0 - 4037) -> ( 0 - 0), size 4037 -> 0
<7> [56.035410] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:140:cursor B] ddb (4037 - 4096) -> ( 0 - 0), size 59 -> 0
<7> [56.035478] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:90:plane 1B] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [56.035545] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:90:plane 1B] lines 4, 4, 9, 9, 17, 17, 17, 17, 0, 15, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [56.035612] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:90:plane 1B] blocks 62, 62, 139, 139, 261, 261, 261, 261, 137, 230, 244 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [56.035677] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:90:plane 1B] min_ddb 123, 123, 245, 245, 367, 367, 0, 0, 138, 306, 306 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [56.035756] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] [CRTC:145:pipe B] data rate 0 num active planes 0
<7> [56.035844] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 33600 required 0 qgv_peak_bw: 48000
<7> [56.035947] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 53000 required 0 qgv_peak_bw: 48000
<7> [56.036057] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 48000 for required data rate: 0
<7> [56.036142] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:145:pipe B] min cdclk: 192000 kHz -> 0 kHz
<7> [56.036239] xe 0000:03:00.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [xe]] dbuf bandwidth min cdclk: 9282 kHz -> 0 kHz
<7> [56.036336] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:145:pipe B] enable: no [modeset]
<7> [56.036422] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:90:plane 1B] fb: [NOFB], visible: no
<7> [56.036505] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:100:plane 2B] fb: [NOFB], visible: no
<7> [56.036586] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:110:plane 3B] fb: [NOFB], visible: no
<7> [56.036667] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:120:plane 4B] fb: [NOFB], visible: no
<7> [56.036748] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:130:plane 5B] fb: [NOFB], visible: no
<7> [56.036828] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:140:cursor B] fb: [NOFB], visible: no
<7> [56.037145] xe 0000:03:00.0: [drm:intel_audio_codec_disable [xe]] [CONNECTOR:293:HDMI-A-3][ENCODER:292:DDI TC3/PHY H] Disable audio codec on [CRTC:145:pipe B]
<7> [56.064314] xe 0000:03:00.0: [drm:intel_audio_component_get_eld [xe]] Not valid for port F
<7> [56.064985] xe 0000:03:00.0: [drm:intel_disable_transcoder [xe]] disabling pipe B
<7> [56.085959] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:261:DDI TC1/PHY F]
<7> [56.086540] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:263:DP-MST A]
<7> [56.087013] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:264:DP-MST B]
<7> [56.087494] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:265:DP-MST C]
<7> [56.087929] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:266:DP-MST D]
<7> [56.088362] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:280:DDI TC2/PHY G]
<7> [56.088851] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:282:DP-MST A]
<7> [56.088692] xe 0000:03:00.0: [drm:intel_audio_component_get_power [xe]] restored AUD_FREQ_CNTRL to 0x8010
<7> [56.089270] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:283:DP-MST B]
<6> [56.089680] pcieport 0000:00:01.0: PME: Spurious native interrupt!
<7> [56.089755] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:284:DP-MST C]
<7> [56.090183] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:285:DP-MST D]
<7> [56.090625] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:292:DDI TC3/PHY H]
<7> [56.091062] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:296:DDI TC4/PHY I]
<7> [56.091485] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:298:DP-MST A]
<7> [56.091900] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:299:DP-MST B]
<7> [56.092316] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:300:DP-MST C]
<7> [56.092759] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:301:DP-MST D]
<7> [56.093194] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:293:HDMI-A-3]
<7> [56.093689] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 2 (mbus joined: no)
<7> [56.094141] xe 0000:03:00.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: yes -> no (pipe: *)
<7> [56.094573] xe 0000:03:00.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0x1
<7> [56.095342] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling PW_B
<7> [56.095892] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:145:pipe B]
<7> [56.096809] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:202:pipe C]
<7> [56.097322] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:259:pipe D]
<7> [56.097801] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:145:pipe B]
<7> [56.097861] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CONNECTOR:293:HDMI-A-3]
<7> [56.098043] xe 0000:03:00.0: [drm:intel_hdmi_compute_clock [xe]] picking 12 bpc for HDMI output (pipe bpp: 36)
<7> [56.098401] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:145:pipe B] hw max bpp: 36, pipe bpp: 36, dithering: 0
<7> [56.098775] xe 0000:03:00.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:292:DDI TC3/PHY H] [CRTC:145:pipe B]
<7> [56.099096] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.enable (expected no, found yes)
<7> [56.099402] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.active (expected no, found yes)
<7> [56.099726] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in cpu_transcoder (expected -1, found 1)
<7> [56.100006] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in lane_count (expected 0, found 4)
<7> [56.100279] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [56.100550] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [56.100801] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [56.101040] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [56.101285] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [56.101510] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [56.101759] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [56.101971] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [56.102179] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [56.102377] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [56.102584] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1083)
<7> [56.102577] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling PW_2
<7> [56.102785] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1088)
<7> [56.102979] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [56.103161] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [56.103347] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [56.103532] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [56.103724] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [56.103889] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [56.104048] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [56.104206] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [56.104361] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [56.104516] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [56.104671] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1083)
<7> [56.104818] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1088)
<7> [56.104958] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [56.105094] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [56.105228] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [56.105358] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [56.105487] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [56.105660] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in has_hdmi_sink (expected no, found yes)
<7> [56.105792] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in has_infoframe (expected no, found yes)
<7> [56.105913] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in pipe_bpp (expected 0, found 36)
<7> [56.106032] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [56.106147] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [56.106263] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in port_clock (expected 0, found 222750)
<7> [56.106377] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071)
<7> [56.106491] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in infoframes.gcp (expected 0x00000000, found 0x00000006)
<7> [56.106611] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in avi infoframe
<7> [56.106725] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [56.106826] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [56.106929] xe 0000:03:00.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [56.106930] xe 0000:03:00.0: colorspace: RGB
<7> [56.106932] xe 0000:03:00.0: scan mode: Underscan
<7> [56.106933] xe 0000:03:00.0: colorimetry: No Data
<7> [56.106934] xe 0000:03:00.0: picture aspect: No Data
<7> [56.106935] xe 0000:03:00.0: active aspect: Same as Picture
<7> [56.106936] xe 0000:03:00.0: itc: No Data
<7> [56.106937] xe 0000:03:00.0: extended colorimetry: xvYCC 601
<7> [56.106939] xe 0000:03:00.0: quantization range: Full
<7> [56.106940] xe 0000:03:00.0: nups: Unknown Non-uniform Scaling
<7> [56.106941] xe 0000:03:00.0: video code: 0
<7> [56.106942] xe 0000:03:00.0: ycc quantization range: Full
<7> [56.106943] xe 0000:03:00.0: hdmi content type: Graphics
<7> [56.106944] xe 0000:03:00.0: pixel repeat: 0
<7> [56.106945] xe 0000:03:00.0: bar top 0, bottom 0, left 0, right 0
<7> [56.106947] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in spd infoframe
<7> [56.107048] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [56.107151] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [56.107250] xe 0000:03:00.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [56.107251] xe 0000:03:00.0: vendor: Intel
<7> [56.107252] xe 0000:03:00.0: product: Discrete gfx
<7> [56.107253] xe 0000:03:00.0: source device information: PC General (0x9)
<7> [56.107255] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hdmi infoframe
<7> [56.107354] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [56.107453] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [56.107551] xe 0000:03:00.0: HDMI infoframe: Vendor, version 1, length 4
<7> [56.107552] xe 0000:03:00.0: empty frame
<7> [56.107554] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:145:pipe B] fastset requirement not met, forcing full modeset
<7> [56.107684] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0x1 -> 0xf (total dbuf slices 0xf), mbus joined? no->yes
<7> [56.107769] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:145:pipe B] dbuf slices 0x0 -> 0xf, ddb (0 - 0) -> (0 - 4096), active pipes 0x0 -> 0x2
<7> [56.107869] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:90:plane 1B] ddb ( 0 - 0) -> ( 0 - 4037), size 0 -> 4037
<7> [56.107951] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:140:cursor B] ddb ( 0 - 0) -> (4037 - 4096), size 0 -> 59
<7> [56.108034] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:90:plane 1B] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm
<7> [56.108115] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:90:plane 1B] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 4, 4, 9, 9, 17, 17, 17, 17, 0, 15, 0
<7> [56.108196] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:90:plane 1B] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 62, 62, 139, 139, 261, 261, 261, 261, 137, 230, 244
<7> [56.108274] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:90:plane 1B] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 123, 123, 245, 245, 367, 367, 0, 0, 138, 306, 306
<7> [56.108358] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] [CRTC:145:pipe B] data rate 594000 num active planes 1
<7> [56.108459] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 33600 required 624 qgv_peak_bw: 48000
<7> [56.108558] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 53000 required 624 qgv_peak_bw: 48000
<7> [56.108664] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 48000 for required data rate: 624
<7> [56.108762] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:145:pipe B] min cdclk: 0 kHz -> 192000 kHz
<7> [56.108871] xe 0000:03:00.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [xe]] dbuf bandwidth min cdclk: 0 kHz -> 9282 kHz
<7> [56.108979] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:145:pipe B] enable: yes [modeset]
<7> [56.109077] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [56.109174] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: B, pipe bpp: 36, dithering: 0
<7> [56.109269] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [56.109363] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [56.109456] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [56.109549] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [56.109647] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [56.109740] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] GCP: 0x6
<7> [56.109832] xe 0000:03:00.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [56.109834] xe 0000:03:00.0: colorspace: RGB
<7> [56.109835] xe 0000:03:00.0: scan mode: Underscan
<7> [56.109836] xe 0000:03:00.0: colorimetry: No Data
<7> [56.109837] xe 0000:03:00.0: picture aspect: No Data
<7> [56.109838] xe 0000:03:00.0: active aspect: Same as Picture
<7> [56.109839] xe 0000:03:00.0: itc: No Data
<7> [56.109840] xe 0000:03:00.0: extended colorimetry: xvYCC 601
<7> [56.109841] xe 0000:03:00.0: quantization range: Full
<7> [56.109842] xe 0000:03:00.0: nups: Unknown Non-uniform Scaling
<7> [56.109843] xe 0000:03:00.0: video code: 0
<7> [56.109844] xe 0000:03:00.0: ycc quantization range: Full
<7> [56.109845] xe 0000:03:00.0: hdmi content type: Graphics
<7> [56.109846] xe 0000:03:00.0: pixel repeat: 0
<7> [56.109847] xe 0000:03:00.0: bar top 0, bottom 0, left 0, right 0
<7> [56.109848] xe 0000:03:00.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [56.109850] xe 0000:03:00.0: vendor: Intel
<7> [56.109851] xe 0000:03:00.0: product: Discrete gfx
<7> [56.109852] xe 0000:03:00.0: source device information: PC General (0x9)
<7> [56.109853] xe 0000:03:00.0: HDMI infoframe: Vendor, version 1, length 4
<7> [56.109854] xe 0000:03:00.0: empty frame
<7> [56.109855] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 10 00 07 00 66 10 00 01 00 00 00 00 00 00 00 00
<7> [56.109949] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 05 e3 cd 0c 32 38 45 38 35 30 09 07 07 00 00 00
<7> [56.110041] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [56.110132] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [56.110222] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vrr: no, fixed rr: yes, vmin: 1125, vmax: 1125, flipline: 1125, pipeline full: 0, guardband: 45 vsync start: 42, vsync end: 37
<7> [56.110314] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vrr: vmin vblank: 1080, vmax vblank: 1080, vmin vtotal: 1125, vmax vtotal: 1125
<7> [56.110405] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] requested mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9
<7> [56.110498] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] adjusted mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9
<7> [56.110590] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1083-1088 vt=1125, flags=0x9
<7> [56.110690] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x40 0x9
<7> [56.110782] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1083-1088 vt=1125, flags=0x9
<7> [56.110874] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port clock: 222750, pipe src: 1920x1080+0+0, pixel rate 148500, min cdclk 192000
<7> [56.110966] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] linetime: 119, ips linetime: 0
<7> [56.111057] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [56.111147] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [56.111239] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [56.111331] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] dpll_hw_state: dpll: 0x3661e, dpll_md: 0x5800be88, fp0: 0x5000000, fp1: 0x5
<7> [56.111421] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [56.111512] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [56.111602] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [56.111700] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [56.111792] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [56.111881] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [56.111968] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [56.112053] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [56.112138] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [56.112222] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [56.112305] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [56.112388] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [56.112470] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [56.112552] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:90:plane 1B] fb: [FB:313] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [56.112636] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [56.112735] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [56.112819] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:100:plane 2B] fb: [NOFB], visible: no
<7> [56.112902] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:110:plane 3B] fb: [NOFB], visible: no
<7> [56.112984] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:120:plane 4B] fb: [NOFB], visible: no
<7> [56.113067] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:130:plane 5B] fb: [NOFB], visible: no
<7> [56.113153] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:140:cursor B] fb: [NOFB], visible: no
<7> [56.113553] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling PW_2
<7> [56.113705] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling PW_B
<7> [56.113844] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:261:DDI TC1/PHY F]
<7> [56.113928] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:263:DP-MST A]
<7> [56.114009] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:264:DP-MST B]
<7> [56.114087] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:265:DP-MST C]
<7> [56.114164] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:266:DP-MST D]
<7> [56.114242] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:280:DDI TC2/PHY G]
<7> [56.114320] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:282:DP-MST A]
<7> [56.114395] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:283:DP-MST B]
<7> [56.114471] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:284:DP-MST C]
<7> [56.114548] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:285:DP-MST D]
<7> [56.114625] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:292:DDI TC3/PHY H]
<7> [56.114709] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:296:DDI TC4/PHY I]
<7> [56.114785] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:298:DP-MST A]
<7> [56.114860] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:299:DP-MST B]
<7> [56.114936] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:300:DP-MST C]
<7> [56.115013] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:301:DP-MST D]
<7> [56.115089] xe 0000:03:00.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xf
<7> [56.115246] xe 0000:03:00.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: no -> yes (pipe: *)
<7> [56.115324] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: yes)
<7> [56.117533] xe 0000:03:00.0: [drm:intel_enable_transcoder [xe]] enabling pipe B
<7> [56.135726] xe 0000:03:00.0: [drm:intel_audio_codec_enable [xe]] [CONNECTOR:293:HDMI-A-3][ENCODER:292:DDI TC3/PHY H] Enable audio codec on [CRTC:145:pipe B], 32 bytes ELD
<7> [56.148255] xe 0000:03:00.0: [drm:intel_audio_component_get_power [xe]] restored AUD_FREQ_CNTRL to 0x8010
<7> [56.152213] xe 0000:03:00.0: [drm:audio_config_hdmi_pixel_clock [xe]] Configuring HDMI audio for pixel clock 148500 (0x00090000)
<7> [56.152372] xe 0000:03:00.0: [drm:hsw_audio_config_update [xe]] using automatic N
<7> [56.152680] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:293:HDMI-A-3]
<7> [56.152867] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:145:pipe B]
<6> [65.375684] [IGT] kms_flip: finished subtest B-HDMI-A3, FAIL
|