Result:
git-log-oneline i915_display_info12 igt_runner12 runtimes12 results12.json results12-i915-load.json guc_logs12.tar boot12 dmesg12
| Detail | Value |
|---|---|
| Duration | 19.17 seconds |
| Hostname |
shard-tglu-8 |
| Igt-Version |
IGT-Version: 2.3-gbef147d2a (x86_64) (Linux: 7.0.0-rc2-CI_DRM_18085-gcfc20c776480+ x86_64) |
| Out |
Using IGT_SRANDOM=1772611643 for randomisation Opened device: /dev/dri/card0 Using monotonic timestamps Starting subtest: flip-vs-blocking-wf-vblank Starting dynamic subtest: A-HDMI-A1 1920x1080: 120 285500 1920 1968 2000 2080 1080 1083 1088 1144 0x48 0x5 Expected frametime: 8335us; measured 8334.8us +- 3.563us accuracy 0.13% Event flip: expected 901, counted 930, passrate = 4.30%, encoder type 2 Event vblank: expected 901, counted 930, passrate = 3.23%, encoder type 2 Expected frametime: 8335us; measured 8233.2us +- 2.713us accuracy 0.10% vblank interval differs from modeline! expected 8334.6us, measured 8233us +- 2.713us, difference 101.4us (37.4 sigma) Event flip: expected 914, counted 910, passrate = 48.35%, encoder type 2 Event vblank: expected 914, counted 910, passrate = 47.25%, 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:1887 run_test() #3 ../tests/kms_flip.c:2120 __igt_unique____real_main2043() #4 ../tests/kms_flip.c:2043 main() #5 [__libc_init_first+0x8a] #6 [__libc_start_main+0x8b] #7 [_start+0x25] Dynamic subtest A-HDMI-A1: FAIL (19.174s) |
| Err |
Starting subtest: flip-vs-blocking-wf-vblank Starting dynamic subtest: A-HDMI-A1 (kms_flip:2086) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1707: (kms_flip:2086) CRITICAL: Failed assertion: !retried Dynamic subtest A-HDMI-A1 failed. **** DEBUG **** (kms_flip:2086) DEBUG: name = flip last_ts = 62.297024 last_received_ts = 62.371414 last_seq = 2980 current_ts = 62.379776 current_received_ts = 62.454140 current_seq = 2990 count = 51 seq_step = 1 (kms_flip:2086) DEBUG: flip ts/seq: last 62.297024/2980, current 62.379776/2990: elapsed=82752.0us expected=82331.9us +- 411.7us, error 0.5% (kms_flip:2086) DEBUG: inconsistent flip ts/seq: last 62.297024/2980, current 62.379776/2990: elapsed=82752.0us expected=82331.9us (kms_flip:2086) DEBUG: name = vblank last_ts = 62.371498 last_received_ts = 62.371361 last_seq = 2989 current_ts = 62.454250 current_received_ts = 62.454086 current_seq = 2999 count = 51 seq_step = 10 (kms_flip:2086) DEBUG: vblank ts/seq: last 62.371498/2989, current 62.454250/2999: elapsed=82754.0us expected=82331.9us +- 411.7us, error 0.5% (kms_flip:2086) DEBUG: inconsistent vblank ts/seq: last 62.371498/2989, current 62.454250/2999: elapsed=82754.0us expected=82331.9us (kms_flip:2086) DEBUG: name = flip last_ts = 62.379776 last_received_ts = 62.454140 last_seq = 2990 current_ts = 62.462528 current_received_ts = 62.536900 current_seq = 3000 count = 52 seq_step = 1 (kms_flip:2086) DEBUG: flip ts/seq: last 62.379776/2990, current 62.462528/3000: elapsed=82752.0us expected=82331.9us +- 411.7us, error 0.5% (kms_flip:2086) DEBUG: inconsistent flip ts/seq: last 62.379776/2990, current 62.462528/3000: elapsed=82752.0us expected=82331.9us (kms_flip:2086) DEBUG: name = vblank last_ts = 62.454250 last_received_ts = 62.454086 last_seq = 2999 current_ts = 62.537003 current_received_ts = 62.536846 current_seq = 3009 count = 52 seq_step = 10 (kms_flip:2086) DEBUG: vblank ts/seq: last 62.454250/2999, current 62.537003/3009: elapsed=82752.0us expected=82331.9us +- 411.7us, error 0.5% (kms_flip:2086) DEBUG: inconsistent vblank ts/seq: last 62.454250/2999, current 62.537003/3009: elapsed=82752.0us expected=82331.9us (kms_flip:2086) DEBUG: name = flip last_ts = 62.462528 last_received_ts = 62.536900 last_seq = 3000 current_ts = 62.545280 current_received_ts = 62.619656 current_seq = 3010 count = 53 seq_step = 1 (kms_flip:2086) DEBUG: flip ts/seq: last 62.462528/3000, current 62.545280/3010: elapsed=82754.0us expected=82331.9us +- 411.7us, error 0.5% (kms_flip:2086) DEBUG: inconsistent flip ts/seq: last 62.462528/3000, current 62.545280/3010: elapsed=82754.0us expected=82331.9us (kms_flip:2086) DEBUG: name = vblank last_ts = 62.537003 last_received_ts = 62.536846 last_seq = 3009 current_ts = 62.619759 current_received_ts = 62.619602 current_seq = 3019 count = 53 seq_step = 10 (kms_flip:2086) DEBUG: vblank ts/seq: last 62.537003/3009, current 62.619759/3019: elapsed=82755.0us expected=82331.9us +- 411.7us, error 0.5% (kms_flip:2086) DEBUG: inconsistent vblank ts/seq: last 62.537003/3009, current 62.619759/3019: elapsed=82755.0us expected=82331.9us (kms_flip:2086) DEBUG: name = flip last_ts = 62.545280 last_received_ts = 62.619656 last_seq = 3010 current_ts = 62.628029 current_received_ts = 62.702431 current_seq = 3020 count = 54 seq_step = 1 (kms_flip:2086) DEBUG: flip ts/seq: last 62.545280/3010, current 62.628029/3020: elapsed=82748.0us expected=82331.9us +- 411.7us, error 0.5% (kms_flip:2086) DEBUG: inconsistent flip ts/seq: last 62.545280/3010, current 62.628029/3020: elapsed=82748.0us expected=82331.9us (kms_flip:2086) DEBUG: name = vblank last_ts = 62.619759 last_received_ts = 62.619602 last_seq = 3019 current_ts = 62.702541 current_received_ts = 62.702381 current_seq = 3029 count = 54 seq_step = 10 (kms_flip:2086) DEBUG: vblank ts/seq: last 62.619759/3019, current 62.702541/3029: elapsed=82785.0us expected=82331.9us +- 411.7us, error 0.6% (kms_flip:2086) DEBUG: inconsistent vblank ts/seq: last 62.619759/3019, current 62.702541/3029: elapsed=82785.0us expected=82331.9us (kms_flip:2086) DEBUG: name = flip last_ts = 62.628029 last_received_ts = 62.702431 last_seq = 3020 current_ts = 62.710827 current_received_ts = 62.785267 current_seq = 3030 count = 55 seq_step = 1 (kms_flip:2086) DEBUG: flip ts/seq: last 62.628029/3020, current 62.710827/3030: elapsed=82799.0us expected=82331.9us +- 411.7us, error 0.6% (kms_flip:2086) DEBUG: inconsistent flip ts/seq: last 62.628029/3020, current 62.710827/3030: elapsed=82799.0us expected=82331.9us (kms_flip:2086) DEBUG: name = vblank last_ts = 62.702541 last_received_ts = 62.702381 last_seq = 3029 current_ts = 62.785374 current_received_ts = 62.785217 current_seq = 3039 count = 55 seq_step = 10 (kms_flip:2086) DEBUG: vblank ts/seq: last 62.702541/3029, current 62.785374/3039: elapsed=82830.0us expected=82331.9us +- 411.7us, error 0.6% (kms_flip:2086) DEBUG: inconsistent vblank ts/seq: last 62.702541/3029, current 62.785374/3039: elapsed=82830.0us expected=82331.9us (kms_flip:2086) DEBUG: name = flip last_ts = 62.710827 last_received_ts = 62.785267 last_seq = 3030 current_ts = 62.793652 current_received_ts = 62.868111 current_seq = 3040 count = 56 seq_step = 1 (kms_flip:2086) DEBUG: flip ts/seq: last 62.710827/3030, current 62.793652/3040: elapsed=82825.0us expected=82331.9us +- 411.7us, error 0.6% (kms_flip:2086) DEBUG: inconsistent flip ts/seq: last 62.710827/3030, current 62.793652/3040: elapsed=82825.0us expected=82331.9us (kms_flip:2086) DEBUG: name = vblank last_ts = 62.785374 last_received_ts = 62.785217 last_seq = 3039 current_ts = 62.868198 current_received_ts = 62.868057 current_seq = 3049 count = 56 seq_step = 10 (kms_flip:2086) DEBUG: vblank ts/seq: last 62.785374/3039, current 62.868198/3049: elapsed=82826.0us expected=82331.9us +- 411.7us, error 0.6% (kms_flip:2086) DEBUG: inconsistent vblank ts/seq: last 62.785374/3039, current 62.868198/3049: elapsed=82826.0us expected=82331.9us (kms_flip:2086) DEBUG: name = flip last_ts = 62.793652 last_received_ts = 62.868111 last_seq = 3040 current_ts = 62.876480 current_received_ts = 62.950928 current_seq = 3050 count = 57 seq_step = 1 (kms_flip:2086) DEBUG: flip ts/seq: last 62.793652/3040, current 62.876480/3050: elapsed=82828.0us expected=82331.9us +- 411.7us, error 0.6% (kms_flip:2086) DEBUG: inconsistent flip ts/seq: last 62.793652/3040, current 62.876480/3050: elapsed=82828.0us expected=82331.9us (kms_flip:2086) DEBUG: name = vblank last_ts = 62.868198 last_received_ts = 62.868057 last_seq = 3049 current_ts = 62.951023 current_received_ts = 62.950874 current_seq = 3059 count = 57 seq_step = 10 (kms_flip:2086) DEBUG: vblank ts/seq: last 62.868198/3049, current 62.951023/3059: elapsed=82825.0us expected=82331.9us +- 411.7us, error 0.6% (kms_flip:2086) DEBUG: inconsistent vblank ts/seq: last 62.868198/3049, current 62.951023/3059: elapsed=82825.0us expected=82331.9us (kms_flip:2086) DEBUG: name = flip last_ts = 62.876480 last_received_ts = 62.950928 last_seq = 3050 current_ts = 62.959309 current_received_ts = 63.033749 current_seq = 3060 count = 58 seq_step = 1 (kms_flip:2086) DEBUG: flip ts/seq: last 62.876480/3050, current 62.959309/3060: elapsed=82828.0us expected=82331.9us +- 411.7us, error 0.6% (kms_flip:2086) DEBUG: inconsistent flip ts/seq: last 62.876480/3050, current 62.959309/3060: elapsed=82828.0us expected=82331.9us (kms_flip:2086) DEBUG: name = vblank last_ts = 62.951023 last_received_ts = 62.950874 last_seq = 3059 current_ts = 63.033852 current_received_ts = 63.033695 current_seq = 3069 count = 58 seq_step = 10 (kms_flip:2086) DEBUG: vblank ts/seq: last 62.951023/3059, current 63.033852/3069: elapsed=82829.0us expected=82331.9us +- 411.7us, error 0.6% (kms_flip:2086) DEBUG: inconsistent vblank ts/seq: last 62.951023/3059, current 63.033852/3069: elapsed=82829.0us expected=82331.9us (kms_flip:2086) DEBUG: name = flip last_ts = 62.959309 last_received_ts = 63.033749 last_seq = 3060 current_ts = 63.042137 current_received_ts = 63.116570 current_seq = 3070 count = 59 seq_step = 1 (kms_flip:2086) DEBUG: flip ts/seq: last 62.959309/3060, current 63.042137/3070: elapsed=82828.0us expected=82331.9us +- 411.7us, error 0.6% (kms_flip:2086) DEBUG: inconsistent flip ts/seq: last 62.959309/3060, current 63.042137/3070: elapsed=82828.0us expected=82331.9us (kms_flip:2086) DEBUG: name = vblank last_ts = 63.033852 last_received_ts = 63.033695 last_seq = 3069 current_ts = 63.116676 current_received_ts = 63.116520 current_seq = 3079 count = 59 seq_step = 10 (kms_flip:2086) DEBUG: vblank ts/seq: last 63.033852/3069, current 63.116676/3079: elapsed=82823.0us expected=82331.9us +- 411.7us, error 0.6% (kms_flip:2086) DEBUG: inconsistent vblank ts/seq: last 63.033852/3069, current 63.116676/3079: elapsed=82823.0us expected=82331.9us (kms_flip:2086) DEBUG: name = flip last_ts = 63.042137 last_received_ts = 63.116570 last_seq = 3070 current_ts = 63.124962 current_received_ts = 63.199409 current_seq = 3080 count = 60 seq_step = 1 (kms_flip:2086) DEBUG: flip ts/seq: last 63.042137/3070, current 63.124962/3080: elapsed=82825.0us expected=82331.9us +- 411.7us, error 0.6% (kms_flip:2086) DEBUG: inconsistent flip ts/seq: last 63.042137/3070, current 63.124962/3080: elapsed=82825.0us expected=82331.9us (kms_flip:2086) DEBUG: name = vblank last_ts = 63.116676 last_received_ts = 63.116520 last_seq = 3079 current_ts = 63.199505 current_received_ts = 63.199360 current_seq = 3089 count = 60 seq_step = 10 (kms_flip:2086) DEBUG: vblank ts/seq: last 63.116676/3079, current 63.199505/3089: elapsed=82827.0us expected=82331.9us +- 411.7us, error 0.6% (kms_flip:2086) DEBUG: inconsistent vblank ts/seq: last 63.116676/3079, current 63.199505/3089: elapsed=82827.0us expected=82331.9us (kms_flip:2086) DEBUG: name = flip last_ts = 63.124962 last_received_ts = 63.199409 last_seq = 3080 current_ts = 63.207787 current_received_ts = 63.282242 current_seq = 3090 count = 61 seq_step = 1 (kms_flip:2086) DEBUG: flip ts/seq: last 63.124962/3080, current 63.207787/3090: elapsed=82823.0us expected=82331.9us +- 411.7us, error 0.6% (kms_flip:2086) DEBUG: inconsistent flip ts/seq: last 63.124962/3080, current 63.207787/3090: elapsed=82823.0us expected=82331.9us (kms_flip:2086) DEBUG: name = vblank last_ts = 63.199505 last_received_ts = 63.199360 last_seq = 3089 current_ts = 63.282333 current_received_ts = 63.282192 current_seq = 3099 count = 61 seq_step = 10 (kms_flip:2086) DEBUG: vblank ts/seq: last 63.199505/3089, current 63.282333/3099: elapsed=82829.0us expected=82331.9us +- 411.7us, error 0.6% (kms_flip:2086) DEBUG: inconsistent vblank ts/seq: last 63.199505/3089, current 63.282333/3099: elapsed=82829.0us expected=82331.9us (kms_flip:2086) DEBUG: name = flip last_ts = 63.207787 last_received_ts = 63.282242 last_seq = 3090 current_ts = 63.290611 current_received_ts = 63.365105 current_seq = 3100 count = 62 seq_step = 1 (kms_flip:2086) DEBUG: flip ts/seq: last 63.207787/3090, current 63.290611/3100: elapsed=82827.0us expected=82331.9us +- 411.7us, error 0.6% (kms_flip:2086) DEBUG: inconsistent flip ts/seq: last 63.207787/3090, current 63.290611/3100: elapsed=82827.0us expected=82331.9us (kms_flip:2086) DEBUG: name = vblank last_ts = 63.282333 last_received_ts = 63.282192 last_seq = 3099 current_ts = 63.365154 current_received_ts = 63.365055 current_seq = 3109 count = 62 seq_step = 10 (kms_flip:2086) DEBUG: vblank ts/seq: last 63.282333/3099, current 63.365154/3109: elapsed=82821.0us expected=82331.9us +- 411.7us, error 0.6% (kms_flip:2086) DEBUG: inconsistent vblank ts/seq: last 63.282333/3099, current 63.365154/3109: elapsed=82821.0us expected=82331.9us (kms_flip:2086) DEBUG: name = flip last_ts = 63.290611 last_received_ts = 63.365105 last_seq = 3100 current_ts = 63.373444 current_received_ts = 63.447872 current_seq = 3110 count = 63 seq_step = 1 (kms_flip:2086) DEBUG: flip ts/seq: last 63.290611/3100, current 63.373444/3110: elapsed=82831.0us expected=82331.9us +- 411.7us, error 0.6% (kms_flip:2086) DEBUG: inconsistent flip ts/seq: last 63.290611/3100, current 63.373444/3110: elapsed=82831.0us expected=82331.9us (kms_flip:2086) DEBUG: name = vblank last_ts = 63.365154 last_received_ts = 63.365055 last_seq = 3109 current_ts = 63.447983 current_received_ts = 63.447819 current_seq = 3119 count = 63 seq_step = 10 (kms_flip:2086) DEBUG: vblank ts/seq: last 63.365154/3109, current 63.447983/3119: elapsed=82830.0us expected=82331.9us +- 411.7us, error 0.6% (kms_flip:2086) DEBUG: inconsistent vblank ts/seq: last 63.365154/3109, current 63.447983/3119: elapsed=82830.0us expected=82331.9us (kms_flip:2086) DEBUG: name = flip last_ts = 63.373444 last_received_ts = 63.447872 last_seq = 3110 current_ts = 63.456264 current_received_ts = 63.530697 current_seq = 3120 count = 64 seq_step = 1 (kms_flip:2086) DEBUG: flip ts/seq: last 63.373444/3110, current 63.456264/3120: elapsed=82822.0us expected=82331.9us +- 411.7us, error 0.6% (kms_flip:2086) DEBUG: inconsistent flip ts/seq: last 63.373444/3110, current 63.456264/3120: elapsed=82822.0us expected=82331.9us (kms_flip:2086) DEBUG: name = vblank last_ts = 63.447983 last_received_ts = 63.447819 last_seq = 3119 current_ts = 63.530807 current_received_ts = 63.530643 current_seq = 3129 count = 64 seq_step = 10 (kms_flip:2086) DEBUG: vblank ts/seq: last 63.447983/3119, current 63.530807/3129: elapsed=82826.0us expected=82331.9us +- 411.7us, error 0.6% (kms_flip:2086) DEBUG: inconsistent vblank ts/seq: last 63.447983/3119, current 63.530807/3129: elapsed=82826.0us expected=82331.9us (kms_flip:2086) DEBUG: name = flip last_ts = 63.456264 last_received_ts = 63.530697 last_seq = 3120 current_ts = 63.539097 current_received_ts = 63.613365 current_seq = 3130 count = 65 seq_step = 1 (kms_flip:2086) DEBUG: flip ts/seq: last 63.456264/3120, current 63.539097/3130: elapsed=82831.0us expected=82331.9us +- 411.7us, error 0.6% (kms_flip:2086) DEBUG: inconsistent flip ts/seq: last 63.456264/3120, current 63.539097/3130: elapsed=82831.0us expected=82331.9us (kms_flip:2086) DEBUG: name = vblank last_ts = 63.530807 last_received_ts = 63.530643 last_seq = 3129 current_ts = 63.613636 current_received_ts = 63.613316 current_seq = 3139 count = 65 seq_step = 10 (kms_flip:2086) DEBUG: vblank ts/seq: last 63.530807/3129, current 63.613636/3139: elapsed=82827.0us expected=82331.9us +- 411.7us, error 0.6% (kms_flip:2086) DEBUG: inconsistent vblank ts/seq: last 63.530807/3129, current 63.613636/3139: elapsed=82827.0us expected=82331.9us (kms_flip:2086) DEBUG: name = flip last_ts = 63.539097 last_received_ts = 63.613365 last_seq = 3130 current_ts = 63.621922 current_received_ts = 63.696384 current_seq = 3140 count = 66 seq_step = 1 (kms_flip:2086) DEBUG: flip ts/seq: last 63.539097/3130, current 63.621922/3140: elapsed=82826.0us expected=82331.9us +- 411.7us, error 0.6% (kms_flip:2086) DEBUG: inconsistent flip ts/seq: last 63.539097/3130, current 63.621922/3140: elapsed=82826.0us expected=82331.9us (kms_flip:2086) DEBUG: name = vblank last_ts = 63.613636 last_received_ts = 63.613316 last_seq = 3139 current_ts = 63.696487 current_received_ts = 63.696335 current_seq = 3149 count = 66 seq_step = 10 (kms_flip:2086) DEBUG: vblank ts/seq: last 63.613636/3139, current 63.696487/3149: elapsed=82853.0us expected=82331.9us +- 411.7us, error 0.6% (kms_flip:2086) DEBUG: inconsistent vblank ts/seq: last 63.613636/3139, current 63.696487/3149: elapsed=82853.0us expected=82331.9us (kms_flip:2086) DEBUG: name = flip last_ts = 63.621922 last_received_ts = 63.696384 last_seq = 3140 current_ts = 63.704781 current_received_ts = 63.779293 current_seq = 3150 count = 67 seq_step = 1 (kms_flip:2086) DEBUG: flip ts/seq: last 63.621922/3140, current 63.704781/3150: elapsed=82857.0us expected=82331.9us +- 411.7us, error 0.6% (kms_flip:2086) DEBUG: inconsistent flip ts/seq: last 63.621922/3140, current 63.704781/3150: elapsed=82857.0us expected=82331.9us (kms_flip:2086) DEBUG: name = vblank last_ts = 63.696487 last_received_ts = 63.696335 last_seq = 3149 current_ts = 63.779385 current_received_ts = 63.779243 current_seq = 3159 count = 67 seq_step = 10 (kms_flip:2086) DEBUG: vblank ts/seq: last 63.696487/3149, current 63.779385/3159: elapsed=82894.0us expected=82331.9us +- 411.7us, error 0.7% (kms_flip:2086) DEBUG: inconsistent vblank ts/seq: last 63.696487/3149, current 63.779385/3159: elapsed=82894.0us expected=82331.9us (kms_flip:2086) DEBUG: name = flip last_ts = 63.704781 last_received_ts = 63.779293 last_seq = 3150 current_ts = 63.787670 current_received_ts = 63.862175 current_seq = 3160 count = 68 seq_step = 1 (kms_flip:2086) DEBUG: flip ts/seq: last 63.704781/3150, current 63.787670/3160: elapsed=82893.0us expected=82331.9us +- 411.7us, error 0.7% (kms_flip:2086) DEBUG: inconsistent flip ts/seq: last 63.704781/3150, current 63.787670/3160: elapsed=82893.0us expected=82331.9us (kms_flip:2086) DEBUG: name = vblank last_ts = 63.779385 last_received_ts = 63.779243 last_seq = 3159 current_ts = 63.862274 current_received_ts = 63.862125 current_seq = 3169 count = 68 seq_step = 10 (kms_flip:2086) DEBUG: vblank ts/seq: last 63.779385/3159, current 63.862274/3169: elapsed=82890.0us expected=82331.9us +- 411.7us, error 0.7% (kms_flip:2086) DEBUG: inconsistent vblank ts/seq: last 63.779385/3159, current 63.862274/3169: elapsed=82890.0us expected=82331.9us (kms_flip:2086) DEBUG: name = flip last_ts = 63.787670 last_received_ts = 63.862175 last_seq = 3160 current_ts = 63.870560 current_received_ts = 63.945107 current_seq = 3170 count = 69 seq_step = 1 (kms_flip:2086) DEBUG: flip ts/seq: last 63.787670/3160, current 63.870560/3170: elapsed=82887.0us expected=82331.9us +- 411.7us, error 0.7% (kms_flip:2086) DEBUG: inconsistent flip ts/seq: last 63.787670/3160, current 63.870560/3170: elapsed=82887.0us expected=82331.9us (kms_flip:2086) DEBUG: name = vblank last_ts = 63.862274 last_received_ts = 63.862125 last_seq = 3169 current_ts = 63.945160 current_received_ts = 63.945057 current_seq = 3179 count = 69 seq_step = 10 (kms_flip:2086) DEBUG: vblank ts/seq: last 63.862274/3169, current 63.945160/3179: elapsed=82887.0us expected=82331.9us +- 411.7us, error 0.7% (kms_flip:2086) DEBUG: inconsistent vblank ts/seq: last 63.862274/3169, current 63.945160/3179: elapsed=82887.0us expected=82331.9us (kms_flip:2086) DEBUG: name = flip last_ts = 63.870560 last_received_ts = 63.945107 last_seq = 3170 current_ts = 63.953449 current_received_ts = 64.027954 current_seq = 3180 count = 70 seq_step = 1 (kms_flip:2086) DEBUG: flip ts/seq: last 63.870560/3170, current 63.953449/3180: elapsed=82891.0us expected=82331.9us +- 411.7us, error 0.7% (kms_flip:2086) DEBUG: inconsistent flip ts/seq: last 63.870560/3170, current 63.953449/3180: elapsed=82891.0us expected=82331.9us (kms_flip:2086) DEBUG: name = vblank last_ts = 63.945160 last_received_ts = 63.945057 last_seq = 3179 current_ts = 64.028053 current_received_ts = 64.027901 current_seq = 3189 count = 70 seq_step = 10 (kms_flip:2086) DEBUG: vblank ts/seq: last 63.945160/3179, current 64.028053/3189: elapsed=82893.0us expected=82331.9us +- 411.7us, error 0.7% (kms_flip:2086) DEBUG: inconsistent vblank ts/seq: last 63.945160/3179, current 64.028053/3189: elapsed=82893.0us expected=82331.9us (kms_flip:2086) DEBUG: name = flip last_ts = 63.953449 last_received_ts = 64.027954 last_seq = 3180 current_ts = 64.036346 current_received_ts = 64.110840 current_seq = 3190 count = 71 seq_step = 1 (kms_flip:2086) DEBUG: flip ts/seq: last 63.953449/3180, current 64.036346/3190: elapsed=82895.0us expected=82331.9us +- 411.7us, error 0.7% (kms_flip:2086) DEBUG: inconsistent flip ts/seq: last 63.953449/3180, current 64.036346/3190: elapsed=82895.0us expected=82331.9us (kms_flip:2086) DEBUG: name = vblank last_ts = 64.028053 last_received_ts = 64.027901 last_seq = 3189 current_ts = 64.110939 current_received_ts = 64.110794 current_seq = 3199 count = 71 seq_step = 10 (kms_flip:2086) DEBUG: vblank ts/seq: last 64.028053/3189, current 64.110939/3199: elapsed=82889.0us expected=82331.9us +- 411.7us, error 0.7% (kms_flip:2086) DEBUG: inconsistent vblank ts/seq: last 64.028053/3189, current 64.110939/3199: elapsed=82889.0us expected=82331.9us (kms_flip:2086) DEBUG: name = flip last_ts = 64.036346 last_received_ts = 64.110840 last_seq = 3190 current_ts = 64.119232 current_received_ts = 64.193726 current_seq = 3200 count = 72 seq_step = 1 (kms_flip:2086) DEBUG: flip ts/seq: last 64.036346/3190, current 64.119232/3200: elapsed=82887.0us expected=82331.9us +- 411.7us, error 0.7% (kms_flip:2086) DEBUG: inconsistent flip ts/seq: last 64.036346/3190, current 64.119232/3200: elapsed=82887.0us expected=82331.9us (kms_flip:2086) DEBUG: name = vblank last_ts = 64.110939 last_received_ts = 64.110794 last_seq = 3199 current_ts = 64.193840 current_received_ts = 64.193680 current_seq = 3209 count = 72 seq_step = 10 (kms_flip:2086) DEBUG: vblank ts/seq: last 64.110939/3199, current 64.193840/3209: elapsed=82895.0us expected=82331.9us +- 411.7us, error 0.7% (kms_flip:2086) DEBUG: inconsistent vblank ts/seq: last 64.110939/3199, current 64.193840/3209: elapsed=82895.0us expected=82331.9us (kms_flip:2086) DEBUG: name = flip last_ts = 64.119232 last_received_ts = 64.193726 last_seq = 3200 current_ts = 64.202126 current_received_ts = 64.276619 current_seq = 3210 count = 73 seq_step = 1 (kms_flip:2086) DEBUG: flip ts/seq: last 64.119232/3200, current 64.202126/3210: elapsed=82895.0us expected=82331.9us +- 411.7us, error 0.7% (kms_flip:2086) DEBUG: inconsistent flip ts/seq: last 64.119232/3200, current 64.202126/3210: elapsed=82895.0us expected=82331.9us (kms_flip:2086) DEBUG: name = vblank last_ts = 64.193840 last_received_ts = 64.193680 last_seq = 3209 current_ts = 64.276726 current_received_ts = 64.276566 current_seq = 3219 count = 73 seq_step = 10 (kms_flip:2086) DEBUG: vblank ts/seq: last 64.193840/3209, current 64.276726/3219: elapsed=82888.0us expected=82331.9us +- 411.7us, error 0.7% (kms_flip:2086) DEBUG: inconsistent vblank ts/seq: last 64.193840/3209, current 64.276726/3219: elapsed=82888.0us expected=82331.9us (kms_flip:2086) DEBUG: name = flip last_ts = 64.202126 last_received_ts = 64.276619 last_seq = 3210 current_ts = 64.285019 current_received_ts = 64.359528 current_seq = 3220 count = 74 seq_step = 1 (kms_flip:2086) DEBUG: flip ts/seq: last 64.202126/3210, current 64.285019/3220: elapsed=82893.0us expected=82331.9us +- 411.7us, error 0.7% (kms_flip:2086) DEBUG: inconsistent flip ts/seq: last 64.202126/3210, current 64.285019/3220: elapsed=82893.0us expected=82331.9us (kms_flip:2086) DEBUG: name = vblank last_ts = 64.276726 last_received_ts = 64.276566 last_seq = 3219 current_ts = 64.359619 current_received_ts = 64.359474 current_seq = 3229 count = 74 seq_step = 10 (kms_flip:2086) DEBUG: vblank ts/seq: last 64.276726/3219, current 64.359619/3229: elapsed=82894.0us expected=82331.9us +- 411.7us, error 0.7% (kms_flip:2086) DEBUG: inconsistent vblank ts/seq: last 64.276726/3219, current 64.359619/3229: elapsed=82894.0us expected=82331.9us (kms_flip:2086) DEBUG: name = flip last_ts = 64.285019 last_received_ts = 64.359528 last_seq = 3220 current_ts = 64.367912 current_received_ts = 64.442406 current_seq = 3230 count = 75 seq_step = 1 (kms_flip:2086) DEBUG: flip ts/seq: last 64.285019/3220, current 64.367912/3230: elapsed=82891.0us expected=82331.9us +- 411.7us, error 0.7% (kms_flip:2086) DEBUG: inconsistent flip ts/seq: last 64.285019/3220, current 64.367912/3230: elapsed=82891.0us expected=82331.9us (kms_flip:2086) DEBUG: name = vblank last_ts = 64.359619 last_received_ts = 64.359474 last_seq = 3229 current_ts = 64.442513 current_received_ts = 64.442352 current_seq = 3239 count = 75 seq_step = 10 (kms_flip:2086) DEBUG: vblank ts/seq: last 64.359619/3229, current 64.442513/3239: elapsed=82893.0us expected=82331.9us +- 411.7us, error 0.7% (kms_flip:2086) DEBUG: inconsistent vblank ts/seq: last 64.359619/3229, current 64.442513/3239: elapsed=82893.0us expected=82331.9us (kms_flip:2086) DEBUG: name = flip last_ts = 64.367912 last_received_ts = 64.442406 last_seq = 3230 current_ts = 64.450806 current_received_ts = 64.525299 current_seq = 3240 count = 76 seq_step = 1 (kms_flip:2086) DEBUG: flip ts/seq: last 64.367912/3230, current 64.450806/3240: elapsed=82891.0us expected=82331.9us +- 411.7us, error 0.7% (kms_flip:2086) DEBUG: inconsistent flip ts/seq: last 64.367912/3230, current 64.450806/3240: elapsed=82891.0us expected=82331.9us (kms_flip:2086) DEBUG: name = vblank last_ts = 64.442513 last_received_ts = 64.442352 last_seq = 3239 current_ts = 64.525398 current_received_ts = 64.525246 current_seq = 3249 count = 76 seq_step = 10 (kms_flip:2086) DEBUG: vblank ts/seq: last 64.442513/3239, current 64.525398/3249: elapsed=82890.0us expected=82331.9us +- 411.7us, error 0.7% (kms_flip:2086) DEBUG: inconsistent vblank ts/seq: last 64.442513/3239, current 64.525398/3249: elapsed=82890.0us expected=82331.9us (kms_flip:2086) DEBUG: name = flip last_ts = 64.450806 last_received_ts = 64.525299 last_seq = 3240 current_ts = 64.533691 current_received_ts = 64.608192 current_seq = 3250 count = 77 seq_step = 1 (kms_flip:2086) DEBUG: flip ts/seq: last 64.450806/3240, current 64.533691/3250: elapsed=82887.0us expected=82331.9us +- 411.7us, error 0.7% (kms_flip:2086) DEBUG: inconsistent flip ts/seq: last 64.450806/3240, current 64.533691/3250: elapsed=82887.0us expected=82331.9us (kms_flip:2086) DEBUG: name = vblank last_ts = 64.525398 last_received_ts = 64.525246 last_seq = 3249 current_ts = 64.608299 current_received_ts = 64.608139 current_seq = 3259 count = 77 seq_step = 10 (kms_flip:2086) DEBUG: vblank ts/seq: last 64.525398/3249, current 64.608299/3259: elapsed=82895.0us expected=82331.9us +- 411.7us, error 0.7% (kms_flip:2086) DEBUG: inconsistent vblank ts/seq: last 64.525398/3249, current 64.608299/3259: elapsed=82895.0us expected=82331.9us (kms_flip:2086) DEBUG: name = flip last_ts = 64.533691 last_received_ts = 64.608192 last_seq = 3250 current_ts = 64.616585 current_received_ts = 64.691124 current_seq = 3260 count = 78 seq_step = 1 (kms_flip:2086) DEBUG: flip ts/seq: last 64.533691/3250, current 64.616585/3260: elapsed=82896.0us expected=82331.9us +- 411.7us, error 0.7% (kms_flip:2086) DEBUG: inconsistent flip ts/seq: last 64.533691/3250, current 64.616585/3260: elapsed=82896.0us expected=82331.9us (kms_flip:2086) DEBUG: name = vblank last_ts = 64.608299 last_received_ts = 64.608139 last_seq = 3259 current_ts = 64.691208 current_received_ts = 64.691071 current_seq = 3269 count = 78 seq_step = 10 (kms_flip:2086) DEBUG: vblank ts/seq: last 64.608299/3259, current 64.691208/3269: elapsed=82914.0us expected=82331.9us +- 411.7us, error 0.7% (kms_flip:2086) DEBUG: inconsistent vblank ts/seq: last 64.608299/3259, current 64.691208/3269: elapsed=82914.0us expected=82331.9us (kms_flip:2086) DEBUG: name = flip last_ts = 64.616585 last_received_ts = 64.691124 last_seq = 3260 current_ts = 64.699501 current_received_ts = 64.774094 current_seq = 3270 count = 79 seq_step = 1 (kms_flip:2086) DEBUG: flip ts/seq: last 64.616585/3260, current 64.699501/3270: elapsed=82918.0us expected=82331.9us +- 411.7us, error 0.7% (kms_flip:2086) DEBUG: inconsistent flip ts/seq: last 64.616585/3260, current 64.699501/3270: elapsed=82918.0us expected=82331.9us (kms_flip:2086) DEBUG: name = vblank last_ts = 64.691208 last_received_ts = 64.691071 last_seq = 3269 current_ts = 64.774155 current_received_ts = 64.774048 current_seq = 3279 count = 79 seq_step = 10 (kms_flip:2086) DEBUG: vblank ts/seq: last 64.691208/3269, current 64.774155/3279: elapsed=82947.0us expected=82331.9us +- 411.7us, error 0.7% (kms_flip:2086) DEBUG: inconsistent vblank ts/seq: last 64.691208/3269, current 64.774155/3279: elapsed=82947.0us expected=82331.9us (kms_flip:2086) DEBUG: name = flip last_ts = 64.699501 last_received_ts = 64.774094 last_seq = 3270 current_ts = 64.782455 current_received_ts = 64.856995 current_seq = 3280 count = 80 seq_step = 1 (kms_flip:2086) DEBUG: flip ts/seq: last 64.699501/3270, current 64.782455/3280: elapsed=82951.0us expected=82331.9us +- 411.7us, error 0.8% (kms_flip:2086) DEBUG: inconsistent flip ts/seq: last 64.699501/3270, current 64.782455/3280: elapsed=82951.0us expected=82331.9us (kms_flip:2086) DEBUG: name = vblank last_ts = 64.774155 last_received_ts = 64.774048 last_seq = 3279 current_ts = 64.857101 current_received_ts = 64.856941 current_seq = 3289 count = 80 seq_step = 10 (kms_flip:2086) DEBUG: vblank ts/seq: last 64.774155/3279, current 64.857101/3289: elapsed=82947.0us expected=82331.9us +- 411.7us, error 0.7% (kms_flip:2086) DEBUG: inconsistent vblank ts/seq: last 64.774155/3279, current 64.857101/3289: elapsed=82947.0us expected=82331.9us (kms_flip:2086) DEBUG: name = flip last_ts = 64.782455 last_received_ts = 64.856995 last_seq = 3280 current_ts = 64.865402 current_received_ts = 64.939964 current_seq = 3290 count = 81 seq_step = 1 (kms_flip:2086) DEBUG: flip ts/seq: last 64.782455/3280, current 64.865402/3290: elapsed=82945.0us expected=82331.9us +- 411.7us, error 0.7% (kms_flip:2086) DEBUG: inconsistent flip ts/seq: last 64.782455/3280, current 64.865402/3290: elapsed=82945.0us expected=82331.9us (kms_flip:2086) DEBUG: name = vblank last_ts = 64.857101 last_received_ts = 64.856941 last_seq = 3289 current_ts = 64.940048 current_received_ts = 64.939919 current_seq = 3299 count = 81 seq_step = 10 (kms_flip:2086) DEBUG: vblank ts/seq: last 64.857101/3289, current 64.940048/3299: elapsed=82945.0us expected=82331.9us +- 411.7us, error 0.7% (kms_flip:2086) DEBUG: inconsistent vblank ts/seq: last 64.857101/3289, current 64.940048/3299: elapsed=82945.0us expected=82331.9us (kms_flip:2086) DEBUG: name = flip last_ts = 64.865402 last_received_ts = 64.939964 last_seq = 3290 current_ts = 64.948349 current_received_ts = 65.022774 current_seq = 3300 count = 82 seq_step = 1 (kms_flip:2086) DEBUG: flip ts/seq: last 64.865402/3290, current 64.948349/3300: elapsed=82948.0us expected=82331.9us +- 411.7us, error 0.7% (kms_flip:2086) DEBUG: inconsistent flip ts/seq: last 64.865402/3290, current 64.948349/3300: elapsed=82948.0us expected=82331.9us (kms_flip:2086) DEBUG: name = vblank last_ts = 64.940048 last_received_ts = 64.939919 last_seq = 3299 current_ts = 65.023003 current_received_ts = 65.022720 current_seq = 3309 count = 82 seq_step = 10 (kms_flip:2086) DEBUG: vblank ts/seq: last 64.940048/3299, current 65.023003/3309: elapsed=82949.0us expected=82331.9us +- 411.7us, error 0.7% (kms_flip:2086) DEBUG: inconsistent vblank ts/seq: last 64.940048/3299, current 65.023003/3309: elapsed=82949.0us expected=82331.9us (kms_flip:2086) DEBUG: name = flip last_ts = 64.948349 last_received_ts = 65.022774 last_seq = 3300 current_ts = 65.031296 current_received_ts = 65.105850 current_seq = 3310 count = 83 seq_step = 1 (kms_flip:2086) DEBUG: flip ts/seq: last 64.948349/3300, current 65.031296/3310: elapsed=82948.0us expected=82331.9us +- 411.7us, error 0.7% (kms_flip:2086) DEBUG: inconsistent flip ts/seq: last 64.948349/3300, current 65.031296/3310: elapsed=82948.0us expected=82331.9us (kms_flip:2086) DEBUG: name = vblank last_ts = 65.023003 last_received_ts = 65.022720 last_seq = 3309 current_ts = 65.105949 current_received_ts = 65.105804 current_seq = 3319 count = 83 seq_step = 10 (kms_flip:2086) DEBUG: vblank ts/seq: last 65.023003/3309, current 65.105949/3319: elapsed=82951.0us expected=82331.9us +- 411.7us, error 0.8% (kms_flip:2086) DEBUG: inconsistent vblank ts/seq: last 65.023003/3309, current 65.105949/3319: elapsed=82951.0us expected=82331.9us (kms_flip:2086) DEBUG: name = flip last_ts = 65.031296 last_received_ts = 65.105850 last_seq = 3310 current_ts = 65.114243 current_received_ts = 65.188652 current_seq = 3320 count = 84 seq_step = 1 (kms_flip:2086) DEBUG: flip ts/seq: last 65.031296/3310, current 65.114243/3320: elapsed=82948.0us expected=82331.9us +- 411.7us, error 0.7% (kms_flip:2086) DEBUG: inconsistent flip ts/seq: last 65.031296/3310, current 65.114243/3320: elapsed=82948.0us expected=82331.9us (kms_flip:2086) DEBUG: name = vblank last_ts = 65.105949 last_received_ts = 65.105804 last_seq = 3319 current_ts = 65.188896 current_received_ts = 65.188599 current_seq = 3329 count = 84 seq_step = 10 (kms_flip:2086) DEBUG: vblank ts/seq: last 65.105949/3319, current 65.188896/3329: elapsed=82946.0us expected=82331.9us +- 411.7us, error 0.7% (kms_flip:2086) DEBUG: inconsistent vblank ts/seq: last 65.105949/3319, current 65.188896/3329: elapsed=82946.0us expected=82331.9us (kms_flip:2086) DEBUG: name = flip last_ts = 65.114243 last_received_ts = 65.188652 last_seq = 3320 current_ts = 65.197189 current_received_ts = 65.271736 current_seq = 3330 count = 85 seq_step = 1 (kms_flip:2086) DEBUG: flip ts/seq: last 65.114243/3320, current 65.197189/3330: elapsed=82948.0us expected=82331.9us +- 411.7us, error 0.7% (kms_flip:2086) DEBUG: inconsistent flip ts/seq: last 65.114243/3320, current 65.197189/3330: elapsed=82948.0us expected=82331.9us (kms_flip:2086) DEBUG: name = vblank last_ts = 65.188896 last_received_ts = 65.188599 last_seq = 3329 current_ts = 65.271851 current_received_ts = 65.271683 current_seq = 3339 count = 85 seq_step = 10 (kms_flip:2086) DEBUG: vblank ts/seq: last 65.188896/3329, current 65.271851/3339: elapsed=82953.0us expected=82331.9us +- 411.7us, error 0.8% (kms_flip:2086) DEBUG: inconsistent vblank ts/seq: last 65.188896/3329, current 65.271851/3339: elapsed=82953.0us expected=82331.9us (kms_flip:2086) DEBUG: name = flip last_ts = 65.197189 last_received_ts = 65.271736 last_seq = 3330 current_ts = 65.280136 current_received_ts = 65.354691 current_seq = 3340 count = 86 seq_step = 1 (kms_flip:2086) DEBUG: flip ts/seq: last 65.197189/3330, current 65.280136/3340: elapsed=82948.0us expected=82331.9us +- 411.7us, error 0.7% (kms_flip:2086) DEBUG: inconsistent flip ts/seq: last 65.197189/3330, current 65.280136/3340: elapsed=82948.0us expected=82331.9us (kms_flip:2086) DEBUG: name = vblank last_ts = 65.271851 last_received_ts = 65.271683 last_seq = 3339 current_ts = 65.354797 current_received_ts = 65.354637 current_seq = 3349 count = 86 seq_step = 10 (kms_flip:2086) DEBUG: vblank ts/seq: last 65.271851/3339, current 65.354797/3349: elapsed=82947.0us expected=82331.9us +- 411.7us, error 0.7% (kms_flip:2086) DEBUG: inconsistent vblank ts/seq: last 65.271851/3339, current 65.354797/3349: elapsed=82947.0us expected=82331.9us (kms_flip:2086) DEBUG: name = flip last_ts = 65.280136 last_received_ts = 65.354691 last_seq = 3340 current_ts = 65.363091 current_received_ts = 65.437645 current_seq = 3350 count = 87 seq_step = 1 (kms_flip:2086) DEBUG: flip ts/seq: last 65.280136/3340, current 65.363091/3350: elapsed=82951.0us expected=82331.9us +- 411.7us, error 0.8% (kms_flip:2086) DEBUG: inconsistent flip ts/seq: last 65.280136/3340, current 65.363091/3350: elapsed=82951.0us expected=82331.9us (kms_flip:2086) DEBUG: name = vblank last_ts = 65.354797 last_received_ts = 65.354637 last_seq = 3349 current_ts = 65.437744 current_received_ts = 65.437592 current_seq = 3359 count = 87 seq_step = 10 (kms_flip:2086) DEBUG: vblank ts/seq: last 65.354797/3349, current 65.437744/3359: elapsed=82948.0us expected=82331.9us +- 411.7us, error 0.7% (kms_flip:2086) DEBUG: inconsistent vblank ts/seq: last 65.354797/3349, current 65.437744/3359: elapsed=82948.0us expected=82331.9us (kms_flip:2086) DEBUG: name = flip last_ts = 65.363091 last_received_ts = 65.437645 last_seq = 3350 current_ts = 65.446037 current_received_ts = 65.520592 current_seq = 3360 count = 88 seq_step = 1 (kms_flip:2086) DEBUG: flip ts/seq: last 65.363091/3350, current 65.446037/3360: elapsed=82948.0us expected=82331.9us +- 411.7us, error 0.7% (kms_flip:2086) DEBUG: inconsistent flip ts/seq: last 65.363091/3350, current 65.446037/3360: elapsed=82948.0us expected=82331.9us (kms_flip:2086) DEBUG: name = vblank last_ts = 65.437744 last_received_ts = 65.437592 last_seq = 3359 current_ts = 65.520691 current_received_ts = 65.520538 current_seq = 3369 count = 88 seq_step = 10 (kms_flip:2086) DEBUG: vblank ts/seq: last 65.437744/3359, current 65.520691/3369: elapsed=82945.0us expected=82331.9us +- 411.7us, error 0.7% (kms_flip:2086) DEBUG: inconsistent vblank ts/seq: last 65.437744/3359, current 65.520691/3369: elapsed=82945.0us expected=82331.9us (kms_flip:2086) DEBUG: name = flip last_ts = 65.446037 last_received_ts = 65.520592 last_seq = 3360 current_ts = 65.528984 current_received_ts = 65.603546 current_seq = 3370 count = 89 seq_step = 1 (kms_flip:2086) DEBUG: flip ts/seq: last 65.446037/3360, current 65.528984/3370: elapsed=82946.0us expected=82331.9us +- 411.7us, error 0.7% (kms_flip:2086) DEBUG: inconsistent flip ts/seq: last 65.446037/3360, current 65.528984/3370: elapsed=82946.0us expected=82331.9us (kms_flip:2086) DEBUG: name = vblank last_ts = 65.520691 last_received_ts = 65.520538 last_seq = 3369 current_ts = 65.603638 current_received_ts = 65.603493 current_seq = 3379 count = 89 seq_step = 10 (kms_flip:2086) DEBUG: vblank ts/seq: last 65.520691/3369, current 65.603638/3379: elapsed=82948.0us expected=82331.9us +- 411.7us, error 0.7% (kms_flip:2086) DEBUG: inconsistent vblank ts/seq: last 65.520691/3369, current 65.603638/3379: elapsed=82948.0us expected=82331.9us (kms_flip:2086) DEBUG: name = flip last_ts = 65.528984 last_received_ts = 65.603546 last_seq = 3370 current_ts = 65.611931 current_received_ts = 65.686493 current_seq = 3380 count = 90 seq_step = 1 (kms_flip:2086) DEBUG: flip ts/seq: last 65.528984/3370, current 65.611931/3380: elapsed=82946.0us expected=82331.9us +- 411.7us, error 0.7% (kms_flip:2086) DEBUG: inconsistent flip ts/seq: last 65.528984/3370, current 65.611931/3380: elapsed=82946.0us expected=82331.9us (kms_flip:2086) DEBUG: name = vblank last_ts = 65.603638 last_received_ts = 65.603493 last_seq = 3379 current_ts = 65.686600 current_received_ts = 65.686440 current_seq = 3389 count = 90 seq_step = 10 (kms_flip:2086) DEBUG: vblank ts/seq: last 65.603638/3379, current 65.686600/3389: elapsed=82966.0us expected=82331.9us +- 411.7us, error 0.8% (kms_flip:2086) DEBUG: inconsistent vblank ts/seq: last 65.603638/3379, current 65.686600/3389: elapsed=82966.0us expected=82331.9us (kms_flip:2086) INFO: Event flip: expected 914, counted 910, passrate = 48.35%, encoder type 2 (kms_flip:2086) DEBUG: dropped frames, expected 914, counted 910, passrate = 48.35%, encoder type 2 (kms_flip:2086) INFO: Event vblank: expected 914, counted 910, passrate = 47.25%, encoder type 2 (kms_flip:2086) DEBUG: dropped frames, expected 914, counted 910, passrate = 47.25%, encoder type 2 (kms_flip:2086) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1707: (kms_flip:2086) CRITICAL: Failed assertion: !retried (kms_flip:2086) igt_core-INFO: Stack trace: (kms_flip:2086) igt_core-INFO: #0 ../lib/igt_core.c:2075 __igt_fail_assert() (kms_flip:2086) igt_core-INFO: #1 ../tests/kms_flip.c:428 run_test_on_crtc_set.constprop.0() (kms_flip:2086) igt_core-INFO: #2 ../tests/kms_flip.c:1887 run_test() (kms_flip:2086) igt_core-INFO: #3 ../tests/kms_flip.c:2120 __igt_unique____real_main2043() (kms_flip:2086) igt_core-INFO: #4 ../tests/kms_flip.c:2043 main() (kms_flip:2086) igt_core-INFO: #5 [__libc_init_first+0x8a] (kms_flip:2086) igt_core-INFO: #6 [__libc_start_main+0x8b] (kms_flip:2086) igt_core-INFO: #7 [_start+0x25] **** END **** Dynamic subtest A-HDMI-A1: FAIL (19.174s) |
| Dmesg |
<6> [47.026678] Console: switching to colour dummy device 80x25
<6> [47.026736] [IGT] kms_flip: executing
<7> [47.029238] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [47.029880] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [47.030161] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [47.040107] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:597:DP-1]
<7> [47.040117] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:597:DP-1]
<7> [47.044523] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:597:DP-1] disconnected
<7> [47.044913] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:607:DP-2]
<7> [47.044917] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:607:DP-2]
<7> [47.045267] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:607:DP-2] disconnected
<7> [47.045630] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:616:DP-3]
<7> [47.045634] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:616:DP-3]
<7> [47.045958] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:616:DP-3] disconnected
<7> [47.046328] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:625:DP-4]
<7> [47.046331] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:625:DP-4]
<7> [47.046649] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:625:DP-4] disconnected
<7> [47.047203] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:587:HDMI-A-1]
<7> [47.047286] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:587:HDMI-A-1]
<7> [47.074122] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:587:HDMI-A-1] Supported Monitor Refresh rate range is 0 Hz - 0 Hz
<7> [47.074132] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:587:HDMI-A-1] HDMI: DVI dual 0, max TMDS clock 300000 kHz
<7> [47.074135] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:587:HDMI-A-1] No deep color support on this HDMI sink.
<7> [47.074138] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:587:HDMI-A-1] HF-VSDB: max TMDS clock: 600000 KHz, HDMI 2.1 support: no, DSC 1.2 support: no
<7> [47.074142] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:587:HDMI-A-1] Assigning EDID-1.4 digital sink color depth as 8 bpc.
<7> [47.074145] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:587:HDMI-A-1] ELD monitor fitHeadlessGS
<7> [47.074148] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:587:HDMI-A-1] HDMI: latency present 0 0, video latency 0 0, audio latency 0 0
<7> [47.074151] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:587:HDMI-A-1] ELD size 64, SAD count 10
<7> [47.074533] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0040 w(1)
<7> [47.074685] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [47.075205] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0040 w(1)
<7> [47.075369] i915 0000:00:02.0: [drm:drm_dp_dual_mode_detect [drm_display_helper]] DP dual mode HDMI ID: (err -6)
<7> [47.076162] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:587:HDMI-A-1] probed modes:
<7> [47.076166] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 120 285500 1920 1968 2000 2080 1080 1083 1088 1144 0x48 0x5
<7> [47.076170] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "4096x2160": 60 594000 4096 4184 4272 4400 2160 2168 2178 2250 0x40 0x5
<7> [47.076173] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "4096x2160": 60 593407 4096 4184 4272 4400 2160 2168 2178 2250 0x40 0x5
<7> [47.076176] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "4096x2160": 30 297000 4096 4184 4272 4400 2160 2168 2178 2250 0x40 0x5
<7> [47.076179] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "4096x2160": 30 296703 4096 4184 4272 4400 2160 2168 2178 2250 0x40 0x5
<7> [47.076182] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "3840x2160": 60 594000 3840 4016 4104 4400 2160 2168 2178 2250 0x40 0x5
<7> [47.076185] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "3840x2160": 60 593407 3840 4016 4104 4400 2160 2168 2178 2250 0x40 0x5
<7> [47.076188] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "3840x2160": 30 297000 3840 4016 4104 4400 2160 2168 2178 2250 0x40 0x5
<7> [47.076190] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "3840x2160": 30 296703 3840 4016 4104 4400 2160 2168 2178 2250 0x40 0x5
<7> [47.076193] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "3840x2160": 25 297000 3840 4896 4984 5280 2160 2168 2178 2250 0x40 0x5
<7> [47.076196] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "3840x2160": 24 297000 3840 5116 5204 5500 2160 2168 2178 2250 0x40 0x5
<7> [47.076199] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "3840x2160": 24 296703 3840 5116 5204 5500 2160 2168 2178 2250 0x40 0x5
<7> [47.076202] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "3440x1440": 90 476140 3440 3448 3480 3520 1440 1441 1449 1503 0x40 0xa
<7> [47.076205] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2560x1600": 75 443250 2560 2768 3048 3536 1600 1603 1609 1672 0x40 0x6
<7> [47.076208] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2560x1600": 60 348500 2560 2752 3032 3504 1600 1603 1609 1658 0x40 0x6
<7> [47.076217] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2560x1440": 120 497770 2560 2608 2640 2720 1440 1443 1448 1525 0x40 0xa
<7> [47.076220] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2048x1536": 60 267027 2048 2200 2424 2800 1536 1537 1540 1589 0x0 0x6
<7> [47.076222] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2560x1080": 120 495000 2560 3108 3152 3300 1080 1084 1089 1250 0x40 0x5
<7> [47.076225] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2560x1080": 120 494505 2560 3108 3152 3300 1080 1084 1089 1250 0x40 0x5
<7> [47.076228] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1440": 75 297000 1920 2064 2288 2640 1440 1441 1444 1500 0x40 0x6
<7> [47.076231] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1440": 60 234000 1920 2048 2256 2600 1440 1441 1444 1500 0x40 0x6
<7> [47.076234] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2160x1200": 90 261420 2160 2208 2240 2320 1200 1203 1208 1252 0x40 0xa
<7> [47.076237] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1856x1392": 75 288000 1856 1984 2208 2560 1392 1393 1396 1500 0x40 0x6
<7> [47.076240] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1856x1392": 60 218250 1856 1952 2176 2528 1392 1393 1396 1439 0x40 0x6
<7> [47.076243] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1792x1344": 75 261000 1792 1888 2104 2456 1344 1345 1348 1417 0x40 0x6
<7> [47.076246] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1792x1344": 60 204750 1792 1920 2120 2448 1344 1345 1348 1394 0x40 0x6
<7> [47.076248] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2048x1152": 60 162000 2048 2074 2154 2250 1152 1153 1156 1200 0x40 0x5
<7> [47.076251] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1200": 120 317250 1920 1968 2000 2080 1200 1203 1209 1271 0x40 0xa
<7> [47.076254] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1200": 75 245250 1920 2056 2264 2608 1200 1203 1209 1255 0x40 0x6
<7> [47.076257] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1200": 60 193250 1920 2056 2256 2592 1200 1203 1209 1245 0x40 0x6
<7> [47.076260] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 120 297000 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [47.076263] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 120 296703 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [47.076265] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 75 220597 1920 2056 2264 2608 1080 1081 1084 1128 0x0 0x6
<7> [47.076268] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [47.076271] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 60 148352 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [47.076274] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1600x1200": 75 202500 1600 1664 1856 2160 1200 1201 1204 1250 0x40 0x5
<7> [47.076277] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1600x1200": 70 189000 1600 1664 1856 2160 1200 1201 1204 1250 0x40 0x5
<7> [47.076280] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1600x1200": 65 175500 1600 1664 1856 2160 1200 1201 1204 1250 0x40 0x5
<7> [47.076283] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1600x1200": 60 162000 1600 1664 1856 2160 1200 1201 1204 1250 0x40 0x5
<7> [47.076286] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1680x1050": 75 187000 1680 1800 1976 2272 1050 1053 1059 1099 0x40 0x6
<7> [47.076288] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1680x1050": 60 146250 1680 1784 1960 2240 1050 1053 1059 1089 0x40 0x6
<7> [47.076291] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1680x945": 60 131481 1680 1784 1960 2240 945 946 949 978 0x0 0x6
<7> [47.076294] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1400x1050": 75 156000 1400 1504 1648 1896 1050 1053 1057 1099 0x40 0x6
<7> [47.076297] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1400x1050": 60 121750 1400 1488 1632 1864 1050 1053 1057 1089 0x40 0x6
<7> [47.076300] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1600x900": 60 108000 1600 1624 1704 1800 900 901 904 1000 0x40 0x5
<7> [47.076303] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x1024": 75 135000 1280 1296 1440 1688 1024 1025 1028 1066 0x40 0x5
<7> [47.076306] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x1024": 60 108000 1280 1328 1440 1688 1024 1025 1028 1066 0x40 0x5
<7> [47.076309] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1440x900": 75 136750 1440 1536 1688 1936 900 903 909 942 0x40 0x6
<7> [47.076312] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1440x900": 60 106500 1440 1520 1672 1904 900 903 909 934 0x40 0x6
<7> [47.076314] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x960": 60 108000 1280 1376 1488 1800 960 961 964 1000 0x40 0x5
<7> [47.076317] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1360x768": 60 84963 1366 1431 1567 1776 768 771 781 798 0x0 0x6
<7> [47.076320] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1360x768": 60 85500 1360 1424 1536 1792 768 771 777 795 0x40 0x5
<7> [47.076323] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x800": 75 106500 1280 1360 1488 1696 800 803 809 838 0x40 0x6
<7> [47.076326] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x800": 60 83500 1280 1352 1480 1680 800 803 809 831 0x40 0x6
<7> [47.076329] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1152x864": 75 108000 1152 1216 1344 1600 864 865 868 900 0x40 0x5
<7> [47.076332] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x768": 75 102250 1280 1360 1488 1696 768 771 778 805 0x40 0x6
<7> [47.076335] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x768": 60 79500 1280 1344 1472 1664 768 771 778 798 0x40 0x6
<7> [47.076338] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x720": 60 74250 1280 1390 1430 1650 720 725 730 750 0x40 0x5
<7> [47.076340] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1024x768": 75 78750 1024 1040 1136 1312 768 769 772 800 0x40 0x5
<7> [47.076343] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1024x768": 70 75000 1024 1048 1184 1328 768 771 777 806 0x40 0xa
<7> [47.076346] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [47.076349] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1024x576": 60 46970 1024 1064 1168 1312 576 577 580 597 0x0 0x6
<7> [47.076352] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "832x624": 75 57284 832 864 928 1152 624 625 628 667 0x40 0xa
<7> [47.076355] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "800x600": 75 49500 800 816 896 1056 600 601 604 625 0x40 0x5
<7> [47.076358] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "800x600": 72 50000 800 856 976 1040 600 637 643 666 0x40 0x5
<7> [47.076361] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "800x600": 60 40000 800 840 968 1056 600 601 605 628 0x40 0x5
<7> [47.076363] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "800x600": 56 36000 800 824 896 1024 600 601 603 625 0x40 0x5
<7> [47.076366] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "848x480": 60 33750 848 864 976 1088 480 486 494 517 0x40 0x5
<7> [47.076369] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "640x480": 75 31500 640 656 720 840 480 481 484 500 0x40 0xa
<7> [47.076372] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "640x480": 73 31500 640 664 704 832 480 489 492 520 0x40 0xa
<7> [47.076375] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "640x480": 60 25200 640 656 752 800 480 490 492 525 0x40 0xa
<7> [47.076378] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "640x480": 60 25175 640 656 752 800 480 490 492 525 0x40 0xa
<7> [47.076381] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "720x400": 70 28320 720 738 846 900 400 412 414 449 0x40 0x6
<7> [47.076419] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:597:DP-1]
<7> [47.076423] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:597:DP-1]
<7> [47.080759] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:597:DP-1] disconnected
<7> [47.080776] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:607:DP-2]
<7> [47.080779] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:607:DP-2]
<7> [47.081105] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:607:DP-2] disconnected
<7> [47.081119] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:616:DP-3]
<7> [47.081122] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:616:DP-3]
<7> [47.081462] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:616:DP-3] disconnected
<7> [47.081475] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:625:DP-4]
<7> [47.081478] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:625:DP-4]
<7> [47.081795] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:625:DP-4] disconnected
<6> [47.082237] [IGT] kms_flip: starting subtest flip-vs-blocking-wf-vblank
<6> [47.084720] [IGT] kms_flip: starting dynamic subtest A-HDMI-A1
<7> [47.085101] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:635]
<7> [47.085198] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:637]
<7> [47.151022] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:170:pipe A]
<7> [47.151183] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [47.151480] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [47.151719] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [47.151972] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [47.152259] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [47.152531] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [47.152802] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [47.153070] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2080, found 0)
<7> [47.153357] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [47.153628] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2080, found 0)
<7> [47.153884] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 1968, found 0)
<7> [47.154118] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2000, found 0)
<7> [47.154369] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [47.154604] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [47.154840] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1083, found 0)
<7> [47.155070] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1088, found 0)
<7> [47.155318] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1144, found 0)
<7> [47.155551] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1144, found 0)
<7> [47.155785] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [47.156018] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2080, found 0)
<7> [47.156265] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [47.156499] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2080, found 0)
<7> [47.156730] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 1968, found 0)
<7> [47.156911] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2000, found 0)
<7> [47.157091] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [47.157284] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [47.157466] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1083, found 0)
<7> [47.157647] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1088, found 0)
<7> [47.157829] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1144, found 0)
<7> [47.158009] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1144, found 0)
<7> [47.158190] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [47.158387] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [47.158570] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [47.158751] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [47.158931] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [47.159112] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [47.159308] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 285500, found 0)
<7> [47.159499] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 285500, found 0)
<7> [47.159674] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in port_clock (expected 285500, found 0)
<7> [47.159826] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [47.159979] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in avi infoframe
<7> [47.160131] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [47.160297] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [47.160298] i915 0000:00:02.0: colorspace: RGB
<7> [47.160300] i915 0000:00:02.0: scan mode: Underscan
<7> [47.160302] i915 0000:00:02.0: colorimetry: No Data
<7> [47.160303] i915 0000:00:02.0: picture aspect: No Data
<7> [47.160305] i915 0000:00:02.0: active aspect: Same as Picture
<7> [47.160306] i915 0000:00:02.0: itc: No Data
<7> [47.160308] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [47.160309] i915 0000:00:02.0: quantization range: Full
<7> [47.160311] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [47.160312] i915 0000:00:02.0: video code: 0
<7> [47.160314] i915 0000:00:02.0: ycc quantization range: Full
<7> [47.160315] i915 0000:00:02.0: hdmi content type: Graphics
<7> [47.160316] i915 0000:00:02.0: pixel repeat: 0
<7> [47.160318] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [47.160319] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [47.160474] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in spd infoframe
<7> [47.160627] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [47.160779] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [47.160781] i915 0000:00:02.0: vendor: Intel
<7> [47.160783] i915 0000:00:02.0: product: Integrated gfx
<7> [47.160784] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [47.160786] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [47.160937] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hdmi infoframe
<7> [47.161090] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [47.161249] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [47.161251] i915 0000:00:02.0: empty frame
<7> [47.161252] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [47.161406] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:170:pipe A] fastset requirement not met, forcing full modeset
<7> [47.161560] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:170:pipe A] releasing DPLL 0
<7> [47.161751] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [47.161907] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:170:pipe A] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x1 -> 0x0
<7> [47.162093] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:34:plane 1A] ddb ( 0 - 1993) -> ( 0 - 0), size 1993 -> 0
<7> [47.162255] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:164:cursor A] ddb (1993 - 2048) -> ( 0 - 0), size 55 -> 0
<7> [47.162410] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34: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> [47.162561] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] lines 1, 8, 8, 8, 8, 11, 16, 16, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [47.162711] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] blocks 16, 129, 129, 129, 129, 177, 257, 257, 30, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [47.162859] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] min_ddb 19, 143, 143, 143, 143, 196, 284, 284, 31, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [47.163033] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:170:pipe A] data rate 0 num active planes 0
<7> [47.163193] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14993 required 0
<7> [47.163394] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:170:pipe A] min cdclk: 192000 kHz -> 0 kHz
<7> [47.163573] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 17844 kHz -> 0 kHz
<7> [47.163759] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [47.163916] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 172800 kHz, actual 172800 kHz
<7> [47.164072] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [47.164239] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:170:pipe A] enable: no [modeset]
<7> [47.164400] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:34:plane 1A] fb: [NOFB], visible: no
<7> [47.164557] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:64:plane 2A] fb: [NOFB], visible: no
<7> [47.164714] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:94:plane 3A] fb: [NOFB], visible: no
<7> [47.164869] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:124:plane 4A] fb: [NOFB], visible: no
<7> [47.165025] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:134:plane 5A] fb: [NOFB], visible: no
<7> [47.165180] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:144:plane 6A] fb: [NOFB], visible: no
<7> [47.165350] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:154:plane 7A] fb: [NOFB], visible: no
<7> [47.165506] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:164:cursor A] fb: [NOFB], visible: no
<7> [47.165768] i915 0000:00:02.0: [drm:intel_audio_codec_disable [i915]] [CONNECTOR:587:HDMI-A-1][ENCODER:586:DDI B/PHY B] Disable audio codec on [CRTC:170:pipe A]
<7> [47.175938] i915 0000:00:02.0: [drm:intel_audio_component_get_eld [i915]] Not valid for port B
<7> [47.187235] i915 0000:00:02.0: [drm:intel_hdmi_handle_sink_scrambling [i915]] [CONNECTOR:587:HDMI-A-1] scrambling=no, TMDS bit clock ratio=1/10
<7> [47.187949] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [47.188111] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [47.188772] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [47.188974] i915 0000:00:02.0: [drm:drm_scdc_set_high_tmds_clock_ratio [drm_display_helper]] [CONNECTOR:587:HDMI-A-1] Failed to read TMDS config: -6
<7> [47.188990] i915 0000:00:02.0: [drm:intel_ddi_disable [i915]] [CONNECTOR:587:HDMI-A-1] Failed to reset sink scrambling/TMDS bit clock ratio
<7> [47.189316] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [47.194277] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_B
<7> [47.194749] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x1, on? 1) for [CRTC:170:pipe A]
<7> [47.195238] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0
<7> [47.195423] i915 0000:00:02.0: [drm:__intel_fbc_disable [i915]] Disabling FBC on [PLANE:34:plane 1A]
<7> [47.195598] i915 0000:00:02.0: [drm:intel_set_cdclk [i915]] Pre changing CDCLK to 172800 kHz, VCO 345600 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0
<7> [47.196084] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:586:DDI B/PHY B]
<7> [47.196280] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:596:DDI TC1/PHY TC1]
<7> [47.196486] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:598:DP-MST A]
<7> [47.196643] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:599:DP-MST B]
<7> [47.196800] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:600:DP-MST C]
<7> [47.196960] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:601:DP-MST D]
<7> [47.197117] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:606:DDI TC2/PHY TC2]
<7> [47.197303] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:608:DP-MST A]
<7> [47.197468] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:609:DP-MST B]
<7> [47.197622] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DP-MST C]
<7> [47.197773] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:611:DP-MST D]
<7> [47.197924] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:615:DDI TC3/PHY TC3]
<7> [47.198076] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:617:DP-MST A]
<7> [47.198242] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:618:DP-MST B]
<7> [47.198407] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:619:DP-MST C]
<7> [47.198557] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:620:DP-MST D]
<7> [47.198709] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:624:DDI TC4/PHY TC4]
<7> [47.198859] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:626:DP-MST A]
<7> [47.199011] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST B]
<7> [47.199161] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:628:DP-MST C]
<7> [47.199326] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:629:DP-MST D]
<7> [47.199525] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:587:HDMI-A-1]
<7> [47.199967] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [47.200190] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_3
<7> [47.200521] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_2
<7> [47.200744] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:170:pipe A]
<7> [47.201400] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:308:pipe B]
<7> [47.201593] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:446:pipe C]
<7> [47.201731] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:584:pipe D]
<7> [47.201876] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:170:pipe A]
<7> [47.201906] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:587:HDMI-A-1]
<7> [47.201980] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:587:HDMI-A-1] Limiting target display pipe bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36)
<7> [47.202209] i915 0000:00:02.0: [drm:intel_hdmi_compute_clock [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [47.202471] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:170:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [47.202666] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:586:DDI B/PHY B] [CRTC:170:pipe A]
<7> [47.202823] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [47.202986] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [47.203146] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [47.203364] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [47.203553] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [47.203710] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [47.203865] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [47.204021] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2080)
<7> [47.204176] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [47.204352] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2080)
<7> [47.204507] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 1968)
<7> [47.204660] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2000)
<7> [47.204814] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [47.204967] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [47.205120] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1083)
<7> [47.205286] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1088)
<7> [47.205440] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1144)
<7> [47.205595] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1144)
<7> [47.205751] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [47.205953] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2080)
<7> [47.206107] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [47.206297] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2080)
<7> [47.206482] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 1968)
<7> [47.206636] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2000)
<7> [47.206790] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [47.206944] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [47.207097] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1083)
<7> [47.207265] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1088)
<7> [47.207435] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1144)
<7> [47.207589] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1144)
<7> [47.207741] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [47.207894] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [47.208054] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [47.208207] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in has_hdmi_sink (expected no, found yes)
<7> [47.208475] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in has_infoframe (expected no, found yes)
<7> [47.208689] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [47.208851] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 285500)
<7> [47.209018] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 285500)
<7> [47.209175] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in port_clock (expected 0, found 285500)
<7> [47.209398] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071)
<7> [47.209563] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in avi infoframe
<7> [47.209754] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [47.209908] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [47.210070] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [47.210073] i915 0000:00:02.0: colorspace: RGB
<7> [47.210075] i915 0000:00:02.0: scan mode: Underscan
<7> [47.210076] i915 0000:00:02.0: colorimetry: No Data
<7> [47.210078] i915 0000:00:02.0: picture aspect: No Data
<7> [47.210079] i915 0000:00:02.0: active aspect: Same as Picture
<7> [47.210080] i915 0000:00:02.0: itc: No Data
<7> [47.210082] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [47.210083] i915 0000:00:02.0: quantization range: Full
<7> [47.210085] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [47.210086] i915 0000:00:02.0: video code: 0
<7> [47.210087] i915 0000:00:02.0: ycc quantization range: Full
<7> [47.210089] i915 0000:00:02.0: hdmi content type: Graphics
<7> [47.210090] i915 0000:00:02.0: pixel repeat: 0
<7> [47.210092] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [47.210094] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in spd infoframe
<7> [47.210270] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [47.210448] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [47.210636] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [47.210638] i915 0000:00:02.0: vendor: Intel
<7> [47.210640] i915 0000:00:02.0: product: Integrated gfx
<7> [47.210641] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [47.210643] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hdmi infoframe
<7> [47.210797] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [47.210958] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [47.211115] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [47.211118] i915 0000:00:02.0: empty frame
<7> [47.211121] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:170:pipe A] fastset requirement not met, forcing full modeset
<7> [47.211349] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [47.211507] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:170:pipe A] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x1
<7> [47.211752] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:34:plane 1A] ddb ( 0 - 0) -> ( 0 - 1993), size 0 -> 1993
<7> [47.211905] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:164:cursor A] ddb ( 0 - 0) -> (1993 - 2048), size 0 -> 55
<7> [47.212066] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34: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> [47.212230] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 8, 8, 8, 8, 11, 16, 16, 0, 0, 0
<7> [47.212392] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 129, 129, 129, 129, 177, 257, 257, 30, 0, 0
<7> [47.212572] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 19, 143, 143, 143, 143, 196, 284, 284, 31, 0, 0
<7> [47.212756] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:170:pipe A] data rate 1142000 num active planes 1
<7> [47.212927] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14993 required 1142
<7> [47.213093] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [47.213289] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:170:pipe A] min cdclk: 0 kHz -> 192000 kHz
<7> [47.213463] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 0 kHz -> 17844 kHz
<7> [47.213683] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [47.213838] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 192000 kHz, actual 192000 kHz
<7> [47.214003] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [47.214168] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:170:pipe A] allocated DPLL 0
<7> [47.214349] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:170:pipe A] reserving DPLL 0
<7> [47.214537] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:170:pipe A] enable: yes [modeset]
<7> [47.214726] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [47.214915] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [47.215139] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [47.215384] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [47.215614] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [47.215843] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [47.216084] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [47.216343] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] GCP: 0x0
<7> [47.216567] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [47.216570] i915 0000:00:02.0: colorspace: RGB
<7> [47.216573] i915 0000:00:02.0: scan mode: Underscan
<7> [47.216576] i915 0000:00:02.0: colorimetry: No Data
<7> [47.216579] i915 0000:00:02.0: picture aspect: No Data
<7> [47.216582] i915 0000:00:02.0: active aspect: Same as Picture
<7> [47.216585] i915 0000:00:02.0: itc: No Data
<7> [47.216587] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [47.216590] i915 0000:00:02.0: quantization range: Full
<7> [47.216593] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [47.216596] i915 0000:00:02.0: video code: 0
<7> [47.216598] i915 0000:00:02.0: ycc quantization range: Full
<7> [47.216601] i915 0000:00:02.0: hdmi content type: Graphics
<7> [47.216604] i915 0000:00:02.0: pixel repeat: 0
<7> [47.216606] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [47.216610] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [47.216613] i915 0000:00:02.0: vendor: Intel
<7> [47.216616] i915 0000:00:02.0: product: Integrated gfx
<7> [47.216619] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [47.216622] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [47.216626] i915 0000:00:02.0: empty frame
<7> [47.216628] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 10 00 0f 00 6d a2 00 7f 00 00 00 00 00 00 00 00
<7> [47.216852] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 0d 82 34 12 66 69 74 48 65 61 64 6c 65 73 73 47
<7> [47.217078] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 53 7f 7f 28 0f 7f 07 17 7f ff 3f 7f ff 47 7f ff
<7> [47.217327] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 5f 7f 82 7f 7f 00 7f 7f 51 7f 7f 30 77 7f 03 00
<7> [47.217497] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [47.217704] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [47.217861] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: yes, vmin: 1144, vmax: 1144, flipline: 1144, pipeline full: 61, guardband: 63 vsync start: 0, vsync end: 0
<7> [47.218026] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 1081, vmax vblank: 1081, vmin vtotal: 1144, vmax vtotal: 1144
<7> [47.218192] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [47.218370] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 120 285500 1920 1968 2000 2080 1080 1083 1088 1144 0x48 0x5
<7> [47.218554] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 120 285500 1920 1968 2000 2080 1080 1083 1088 1144 0x48 0x5
<7> [47.218717] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=285500, hd=1920 hb=1920-2080 hs=1968-2000 ht=2080, vd=1080 vb=1080-1144 vs=1083-1088 vt=1144, flags=0x5
<7> [47.218873] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 120 285500 1920 1968 2000 2080 1080 1083 1088 1144 0x40 0x5
<7> [47.219034] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=285500, hd=1920 hb=1920-2080 hs=1968-2000 ht=2080, vd=1080 vb=1080-1144 vs=1083-1088 vt=1144, flags=0x5
<7> [47.219231] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 285500, pipe src: 1920x1080+0+0, pixel rate 285500, min cdclk 192000
<7> [47.219392] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 59, ips linetime: 0
<7> [47.219557] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [47.219720] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [47.219886] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [47.220063] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: cfgcr0: 0x1801be, cfgcr1: 0x488, div0: 0x0, mg_refclkin_ctl: 0x0, hg_clktop2_coreclkctl1: 0x0, mg_clktop2_hsclkctl: 0x0, mg_pll_div0: 0x0, mg_pll_div2: 0x0, mg_pll_lf: 0x0, mg_pll_frac_lock: 0x0, mg_pll_ssc: 0x0, mg_pll_bias: 0x0, mg_pll_tdc_coldst_bias: 0x0
<7> [47.220237] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [47.220391] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [47.220554] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [47.220707] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [47.220903] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [47.221056] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [47.221227] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [47.221401] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [47.221561] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [47.221741] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [47.221901] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [47.222053] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [47.222219] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [47.222390] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:34:plane 1A] fb: [FB:635] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [47.222563] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [47.222722] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [47.222875] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:64:plane 2A] fb: [NOFB], visible: no
<7> [47.223033] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:94:plane 3A] fb: [NOFB], visible: no
<7> [47.223225] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:124:plane 4A] fb: [NOFB], visible: no
<7> [47.223377] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:134:plane 5A] fb: [NOFB], visible: no
<7> [47.223535] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:144:plane 6A] fb: [NOFB], visible: no
<7> [47.223695] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:154:plane 7A] fb: [NOFB], visible: no
<7> [47.223851] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:164:cursor A] fb: [NOFB], visible: no
<7> [47.224645] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_2
<7> [47.224849] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_3
<7> [47.225054] i915 0000:00:02.0: [drm:intel_set_cdclk [i915]] Pre changing CDCLK to 192000 kHz, VCO 384000 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0
<7> [47.225329] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:586:DDI B/PHY B]
<7> [47.225493] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:596:DDI TC1/PHY TC1]
<7> [47.225699] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:598:DP-MST A]
<7> [47.225857] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:599:DP-MST B]
<7> [47.226021] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:600:DP-MST C]
<7> [47.226188] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:601:DP-MST D]
<7> [47.226434] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:606:DDI TC2/PHY TC2]
<7> [47.226643] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:608:DP-MST A]
<7> [47.226800] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:609:DP-MST B]
<7> [47.226963] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DP-MST C]
<7> [47.227124] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:611:DP-MST D]
<7> [47.227300] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:615:DDI TC3/PHY TC3]
<7> [47.227472] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:617:DP-MST A]
<7> [47.227630] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:618:DP-MST B]
<7> [47.227782] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:619:DP-MST C]
<7> [47.227940] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:620:DP-MST D]
<7> [47.228121] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:624:DDI TC4/PHY TC4]
<7> [47.228287] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:626:DP-MST A]
<7> [47.228446] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST B]
<7> [47.228603] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:628:DP-MST C]
<7> [47.228758] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:629:DP-MST D]
<7> [47.229071] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [47.229365] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:170:pipe A]
<7> [47.229556] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0
<7> [47.229789] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_B
<7> [47.230058] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [47.230419] i915 0000:00:02.0: [drm:intel_hdmi_handle_sink_scrambling [i915]] [CONNECTOR:587:HDMI-A-1] scrambling=no, TMDS bit clock ratio=1/10
<7> [47.231081] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [47.231270] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [47.231897] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [47.232069] i915 0000:00:02.0: [drm:drm_scdc_set_high_tmds_clock_ratio [drm_display_helper]] [CONNECTOR:587:HDMI-A-1] Failed to read TMDS config: -6
<7> [47.232089] i915 0000:00:02.0: [drm:intel_ddi_enable [i915]] [CONNECTOR:587:HDMI-A-1] Failed to configure sink scrambling/TMDS bit clock ratio
<7> [47.235233] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1
<7> [47.235405] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:34:plane 1A]
<7> [47.240759] i915 0000:00:02.0: [drm:intel_audio_codec_enable [i915]] [CONNECTOR:587:HDMI-A-1][ENCODER:586:DDI B/PHY B] Enable audio codec on [CRTC:170:pipe A], 64 bytes ELD
<7> [47.241359] i915 0000:00:02.0: [drm:intel_audio_component_get_power [i915]] restored AUD_FREQ_CNTRL to 0x810
<7> [47.245318] azx_init_pci: snd_hda_intel 0000:00:1f.3: Clearing TCSEL
<7> [47.245357] azx_init_pci: snd_hda_intel 0000:00:1f.3: SCH snoop: Enabled
<7> [47.249082] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] HDMI audio pixel clock setting for 285500 not found, falling back to defaults
<7> [47.249379] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] Configuring HDMI audio for pixel clock 25200 (0x00010000)
<7> [47.249613] i915 0000:00:02.0: [drm:hsw_audio_config_update [i915]] using automatic N
<6> [47.249855] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 5 not expected
<6> [47.249862] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 0 not expected
<6> [47.249866] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 10 not expected
<6> [47.249869] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 6 not expected
<7> [47.250048] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:587:HDMI-A-1]
<7> [47.250481] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:170:pipe A]
<7> [48.131138] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port G/TC#4: TC port mode reset (tbt-alt -> disconnected) pin assignment: - max lanes: 4
<7> [48.131205] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port F/TC#3: TC port mode reset (tbt-alt -> disconnected) pin assignment: - max lanes: 4
<7> [48.131276] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port E/TC#2: TC port mode reset (tbt-alt -> disconnected) pin assignment: - max lanes: 4
<7> [48.131320] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling TC_cold_off
<7> [48.131844] i915 0000:00:02.0: [drm:tgl_tc_cold_request [i915]] TC cold unblock succeeded
<7> [48.132432] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port D/TC#1: TC port mode reset (legacy -> disconnected) pin assignment: - max lanes: 4
<7> [58.220934] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:170:pipe A]
<7> [58.221220] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [58.221825] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [58.222492] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [58.223054] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [58.223523] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [58.224043] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [58.224507] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [58.225012] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2080, found 0)
<7> [58.225472] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [58.225985] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2080, found 0)
<7> [58.226449] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 1968, found 0)
<7> [58.226657] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2000, found 0)
<7> [58.226811] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [58.226963] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [58.227118] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1083, found 0)
<7> [58.227273] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1088, found 0)
<7> [58.227429] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1144, found 0)
<7> [58.227582] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1144, found 0)
<7> [58.227750] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [58.227904] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2080, found 0)
<7> [58.228057] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [58.228211] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2080, found 0)
<7> [58.228364] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 1968, found 0)
<7> [58.228519] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2000, found 0)
<7> [58.228682] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [58.228835] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [58.228988] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1083, found 0)
<7> [58.229142] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1088, found 0)
<7> [58.229295] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1144, found 0)
<7> [58.229450] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1144, found 0)
<7> [58.229603] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [58.229776] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [58.229930] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [58.230083] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [58.230236] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [58.230388] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [58.230542] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 285500, found 0)
<7> [58.230709] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 285500, found 0)
<7> [58.230862] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in port_clock (expected 285500, found 0)
<7> [58.231013] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [58.231166] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in avi infoframe
<7> [58.231319] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [58.231471] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [58.231473] i915 0000:00:02.0: colorspace: RGB
<7> [58.231475] i915 0000:00:02.0: scan mode: Underscan
<7> [58.231476] i915 0000:00:02.0: colorimetry: No Data
<7> [58.231478] i915 0000:00:02.0: picture aspect: No Data
<7> [58.231479] i915 0000:00:02.0: active aspect: Same as Picture
<7> [58.231481] i915 0000:00:02.0: itc: No Data
<7> [58.231482] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [58.231484] i915 0000:00:02.0: quantization range: Full
<7> [58.231486] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [58.231487] i915 0000:00:02.0: video code: 0
<7> [58.231488] i915 0000:00:02.0: ycc quantization range: Full
<7> [58.231490] i915 0000:00:02.0: hdmi content type: Graphics
<7> [58.231491] i915 0000:00:02.0: pixel repeat: 0
<7> [58.231493] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [58.231494] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [58.231647] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in spd infoframe
<7> [58.231813] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [58.231965] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [58.231967] i915 0000:00:02.0: vendor: Intel
<7> [58.231968] i915 0000:00:02.0: product: Integrated gfx
<7> [58.231970] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [58.231971] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [58.232123] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hdmi infoframe
<7> [58.232275] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [58.232427] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [58.232429] i915 0000:00:02.0: empty frame
<7> [58.232430] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [58.232584] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:170:pipe A] fastset requirement not met, forcing full modeset
<7> [58.232748] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:170:pipe A] releasing DPLL 0
<7> [58.232940] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [58.233095] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:170:pipe A] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x1 -> 0x0
<7> [58.233287] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:34:plane 1A] ddb ( 0 - 1993) -> ( 0 - 0), size 1993 -> 0
<7> [58.233443] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:164:cursor A] ddb (1993 - 2048) -> ( 0 - 0), size 55 -> 0
<7> [58.233596] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34: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> [58.233762] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] lines 1, 8, 8, 8, 8, 11, 16, 16, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [58.233914] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] blocks 16, 129, 129, 129, 129, 177, 257, 257, 30, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [58.234066] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] min_ddb 19, 143, 143, 143, 143, 196, 284, 284, 31, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [58.234240] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:170:pipe A] data rate 0 num active planes 0
<7> [58.234403] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14993 required 0
<7> [58.234582] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:170:pipe A] min cdclk: 192000 kHz -> 0 kHz
<7> [58.234767] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 17844 kHz -> 0 kHz
<7> [58.234951] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [58.235106] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 172800 kHz, actual 172800 kHz
<7> [58.235259] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [58.235416] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:170:pipe A] enable: no [modeset]
<7> [58.235578] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:34:plane 1A] fb: [NOFB], visible: no
<7> [58.235735] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:64:plane 2A] fb: [NOFB], visible: no
<7> [58.235908] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:94:plane 3A] fb: [NOFB], visible: no
<7> [58.236064] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:124:plane 4A] fb: [NOFB], visible: no
<7> [58.236219] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:134:plane 5A] fb: [NOFB], visible: no
<7> [58.236373] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:144:plane 6A] fb: [NOFB], visible: no
<7> [58.236526] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:154:plane 7A] fb: [NOFB], visible: no
<7> [58.236681] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:164:cursor A] fb: [NOFB], visible: no
<7> [58.236925] i915 0000:00:02.0: [drm:intel_audio_codec_disable [i915]] [CONNECTOR:587:HDMI-A-1][ENCODER:586:DDI B/PHY B] Disable audio codec on [CRTC:170:pipe A]
<7> [58.250862] i915 0000:00:02.0: [drm:intel_audio_component_get_eld [i915]] Not valid for port B
<7> [58.261067] i915 0000:00:02.0: [drm:intel_hdmi_handle_sink_scrambling [i915]] [CONNECTOR:587:HDMI-A-1] scrambling=no, TMDS bit clock ratio=1/10
<7> [58.261730] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [58.261883] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [58.262497] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [58.262712] i915 0000:00:02.0: [drm:drm_scdc_set_high_tmds_clock_ratio [drm_display_helper]] [CONNECTOR:587:HDMI-A-1] Failed to read TMDS config: -6
<7> [58.262728] i915 0000:00:02.0: [drm:intel_ddi_disable [i915]] [CONNECTOR:587:HDMI-A-1] Failed to reset sink scrambling/TMDS bit clock ratio
<7> [58.262916] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [58.268171] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_B
<7> [58.268367] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x1, on? 1) for [CRTC:170:pipe A]
<7> [58.268555] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0
<7> [58.268727] i915 0000:00:02.0: [drm:__intel_fbc_disable [i915]] Disabling FBC on [PLANE:34:plane 1A]
<7> [58.268896] i915 0000:00:02.0: [drm:intel_set_cdclk [i915]] Pre changing CDCLK to 172800 kHz, VCO 345600 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0
<7> [58.269351] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:586:DDI B/PHY B]
<7> [58.269602] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:596:DDI TC1/PHY TC1]
<7> [58.269763] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:598:DP-MST A]
<7> [58.269919] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:599:DP-MST B]
<7> [58.270073] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:600:DP-MST C]
<7> [58.270289] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:601:DP-MST D]
<7> [58.270489] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:606:DDI TC2/PHY TC2]
<7> [58.270643] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:608:DP-MST A]
<7> [58.270801] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:609:DP-MST B]
<7> [58.270956] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DP-MST C]
<7> [58.271173] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:611:DP-MST D]
<7> [58.271408] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:615:DDI TC3/PHY TC3]
<7> [58.271603] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:617:DP-MST A]
<7> [58.271757] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:618:DP-MST B]
<7> [58.271909] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:619:DP-MST C]
<7> [58.272136] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:620:DP-MST D]
<7> [58.272343] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:624:DDI TC4/PHY TC4]
<7> [58.272522] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:626:DP-MST A]
<7> [58.272677] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST B]
<7> [58.272836] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:628:DP-MST C]
<7> [58.273071] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:629:DP-MST D]
<7> [58.273246] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:587:HDMI-A-1]
<7> [58.273584] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [58.273869] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_3
<7> [58.274059] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_2
<7> [58.274266] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:170:pipe A]
<7> [58.274971] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:308:pipe B]
<7> [58.275139] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:446:pipe C]
<7> [58.275313] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:584:pipe D]
<7> [58.275456] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:170:pipe A]
<7> [58.275487] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:587:HDMI-A-1]
<7> [58.275553] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:587:HDMI-A-1] Limiting target display pipe bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36)
<7> [58.275734] i915 0000:00:02.0: [drm:intel_hdmi_compute_clock [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [58.275896] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:170:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [58.276061] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:586:DDI B/PHY B] [CRTC:170:pipe A]
<7> [58.276214] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [58.276397] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [58.276586] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [58.276750] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [58.276921] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [58.277103] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [58.277272] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [58.277467] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2080)
<7> [58.277623] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [58.277780] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2080)
<7> [58.277936] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 1968)
<7> [58.278091] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2000)
<7> [58.278245] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [58.278427] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [58.278639] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1083)
<7> [58.278794] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1088)
<7> [58.278957] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1144)
<7> [58.279122] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1144)
<7> [58.279298] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [58.279475] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2080)
<7> [58.279637] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [58.279791] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2080)
<7> [58.279953] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 1968)
<7> [58.280128] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2000)
<7> [58.280284] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [58.280460] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [58.280614] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1083)
<7> [58.280767] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1088)
<7> [58.280920] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1144)
<7> [58.281073] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1144)
<7> [58.281227] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [58.281395] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [58.281549] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [58.281701] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in has_hdmi_sink (expected no, found yes)
<7> [58.281855] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in has_infoframe (expected no, found yes)
<7> [58.282008] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [58.282160] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 285500)
<7> [58.282323] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 285500)
<7> [58.282494] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in port_clock (expected 0, found 285500)
<7> [58.282649] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071)
<7> [58.282803] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in avi infoframe
<7> [58.282954] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [58.283106] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [58.283284] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [58.283286] i915 0000:00:02.0: colorspace: RGB
<7> [58.283287] i915 0000:00:02.0: scan mode: Underscan
<7> [58.283289] i915 0000:00:02.0: colorimetry: No Data
<7> [58.283290] i915 0000:00:02.0: picture aspect: No Data
<7> [58.283292] i915 0000:00:02.0: active aspect: Same as Picture
<7> [58.283293] i915 0000:00:02.0: itc: No Data
<7> [58.283295] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [58.283296] i915 0000:00:02.0: quantization range: Full
<7> [58.283297] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [58.283299] i915 0000:00:02.0: video code: 0
<7> [58.283301] i915 0000:00:02.0: ycc quantization range: Full
<7> [58.283302] i915 0000:00:02.0: hdmi content type: Graphics
<7> [58.283303] i915 0000:00:02.0: pixel repeat: 0
<7> [58.283305] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [58.283307] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in spd infoframe
<7> [58.283488] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [58.283673] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [58.283830] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [58.283832] i915 0000:00:02.0: vendor: Intel
<7> [58.283834] i915 0000:00:02.0: product: Integrated gfx
<7> [58.283835] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [58.283837] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hdmi infoframe
<7> [58.283993] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [58.284147] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [58.284302] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [58.284304] i915 0000:00:02.0: empty frame
<7> [58.284306] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:170:pipe A] fastset requirement not met, forcing full modeset
<7> [58.284526] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [58.284688] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:170:pipe A] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x1
<7> [58.284879] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:34:plane 1A] ddb ( 0 - 0) -> ( 0 - 1993), size 0 -> 1993
<7> [58.285054] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:164:cursor A] ddb ( 0 - 0) -> (1993 - 2048), size 0 -> 55
<7> [58.285205] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34: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> [58.285368] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 8, 8, 8, 8, 11, 16, 16, 0, 0, 0
<7> [58.285533] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 129, 129, 129, 129, 177, 257, 257, 30, 0, 0
<7> [58.285687] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 19, 143, 143, 143, 143, 196, 284, 284, 31, 0, 0
<7> [58.285859] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:170:pipe A] data rate 1142000 num active planes 1
<7> [58.286019] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14993 required 1142
<7> [58.286178] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [58.286359] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:170:pipe A] min cdclk: 0 kHz -> 192000 kHz
<7> [58.286569] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 0 kHz -> 17844 kHz
<7> [58.286762] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [58.286919] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 192000 kHz, actual 192000 kHz
<7> [58.287072] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [58.287226] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:170:pipe A] allocated DPLL 0
<7> [58.287400] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:170:pipe A] reserving DPLL 0
<7> [58.287578] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:170:pipe A] enable: yes [modeset]
<7> [58.287738] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [58.287896] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [58.288052] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [58.288207] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [58.288382] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [58.288556] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [58.288725] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [58.288899] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] GCP: 0x0
<7> [58.289053] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [58.289055] i915 0000:00:02.0: colorspace: RGB
<7> [58.289057] i915 0000:00:02.0: scan mode: Underscan
<7> [58.289058] i915 0000:00:02.0: colorimetry: No Data
<7> [58.289060] i915 0000:00:02.0: picture aspect: No Data
<7> [58.289061] i915 0000:00:02.0: active aspect: Same as Picture
<7> [58.289063] i915 0000:00:02.0: itc: No Data
<7> [58.289064] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [58.289065] i915 0000:00:02.0: quantization range: Full
<7> [58.289067] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [58.289068] i915 0000:00:02.0: video code: 0
<7> [58.289070] i915 0000:00:02.0: ycc quantization range: Full
<7> [58.289071] i915 0000:00:02.0: hdmi content type: Graphics
<7> [58.289072] i915 0000:00:02.0: pixel repeat: 0
<7> [58.289074] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [58.289076] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [58.289077] i915 0000:00:02.0: vendor: Intel
<7> [58.289079] i915 0000:00:02.0: product: Integrated gfx
<7> [58.289080] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [58.289082] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [58.289083] i915 0000:00:02.0: empty frame
<7> [58.289085] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 10 00 0f 00 6d a2 00 7f 00 00 00 00 00 00 00 00
<7> [58.289239] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 0d 82 34 12 66 69 74 48 65 61 64 6c 65 73 73 47
<7> [58.289394] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 53 7f 7f 28 0f 7f 07 17 7f ff 3f 7f ff 47 7f ff
<7> [58.289569] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 5f 7f 82 7f 7f 00 7f 7f 51 7f 7f 30 77 7f 03 00
<7> [58.289747] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [58.289899] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [58.290052] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: yes, vmin: 1144, vmax: 1144, flipline: 1144, pipeline full: 61, guardband: 63 vsync start: 0, vsync end: 0
<7> [58.290204] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 1081, vmax vblank: 1081, vmin vtotal: 1144, vmax vtotal: 1144
<7> [58.290357] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [58.290530] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 120 285500 1920 1968 2000 2080 1080 1083 1088 1144 0x48 0x5
<7> [58.290705] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 120 285500 1920 1968 2000 2080 1080 1083 1088 1144 0x48 0x5
<7> [58.290869] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=285500, hd=1920 hb=1920-2080 hs=1968-2000 ht=2080, vd=1080 vb=1080-1144 vs=1083-1088 vt=1144, flags=0x5
<7> [58.291021] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 120 285500 1920 1968 2000 2080 1080 1083 1088 1144 0x40 0x5
<7> [58.291172] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=285500, hd=1920 hb=1920-2080 hs=1968-2000 ht=2080, vd=1080 vb=1080-1144 vs=1083-1088 vt=1144, flags=0x5
<7> [58.291324] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 285500, pipe src: 1920x1080+0+0, pixel rate 285500, min cdclk 192000
<7> [58.291493] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 59, ips linetime: 0
<7> [58.291658] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [58.291811] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [58.291963] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [58.292125] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: cfgcr0: 0x1801be, cfgcr1: 0x488, div0: 0x0, mg_refclkin_ctl: 0x0, hg_clktop2_coreclkctl1: 0x0, mg_clktop2_hsclkctl: 0x0, mg_pll_div0: 0x0, mg_pll_div2: 0x0, mg_pll_lf: 0x0, mg_pll_frac_lock: 0x0, mg_pll_ssc: 0x0, mg_pll_bias: 0x0, mg_pll_tdc_coldst_bias: 0x0
<7> [58.292360] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [58.292556] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [58.292728] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [58.292884] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [58.293072] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [58.293291] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [58.293459] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [58.293626] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [58.293780] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [58.293996] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [58.294220] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [58.294375] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [58.294549] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [58.294719] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:34:plane 1A] fb: [FB:635] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [58.294910] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [58.295128] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [58.295282] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:64:plane 2A] fb: [NOFB], visible: no
<7> [58.295435] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:94:plane 3A] fb: [NOFB], visible: no
<7> [58.295610] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:124:plane 4A] fb: [NOFB], visible: no
<7> [58.295840] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:134:plane 5A] fb: [NOFB], visible: no
<7> [58.296018] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:144:plane 6A] fb: [NOFB], visible: no
<7> [58.296176] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:154:plane 7A] fb: [NOFB], visible: no
<7> [58.296331] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:164:cursor A] fb: [NOFB], visible: no
<7> [58.296720] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_2
<7> [58.296903] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_3
<7> [58.297080] i915 0000:00:02.0: [drm:intel_set_cdclk [i915]] Pre changing CDCLK to 192000 kHz, VCO 384000 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0
<7> [58.297315] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:586:DDI B/PHY B]
<7> [58.297569] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:596:DDI TC1/PHY TC1]
<7> [58.297743] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:598:DP-MST A]
<7> [58.297901] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:599:DP-MST B]
<7> [58.298057] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:600:DP-MST C]
<7> [58.298250] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:601:DP-MST D]
<7> [58.298458] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:606:DDI TC2/PHY TC2]
<7> [58.298635] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:608:DP-MST A]
<7> [58.298800] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:609:DP-MST B]
<7> [58.298953] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DP-MST C]
<7> [58.299178] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:611:DP-MST D]
<7> [58.299375] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:615:DDI TC3/PHY TC3]
<7> [58.299538] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:617:DP-MST A]
<7> [58.299706] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:618:DP-MST B]
<7> [58.299857] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:619:DP-MST C]
<7> [58.300057] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:620:DP-MST D]
<7> [58.300265] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:624:DDI TC4/PHY TC4]
<7> [58.300422] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:626:DP-MST A]
<7> [58.300594] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST B]
<7> [58.300761] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:628:DP-MST C]
<7> [58.300989] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:629:DP-MST D]
<7> [58.301273] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [58.301530] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:170:pipe A]
<7> [58.301783] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0
<7> [58.302331] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_B
<7> [58.302610] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [58.302942] i915 0000:00:02.0: [drm:intel_hdmi_handle_sink_scrambling [i915]] [CONNECTOR:587:HDMI-A-1] scrambling=no, TMDS bit clock ratio=1/10
<7> [58.303604] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [58.303784] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [58.304407] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [58.304574] i915 0000:00:02.0: [drm:drm_scdc_set_high_tmds_clock_ratio [drm_display_helper]] [CONNECTOR:587:HDMI-A-1] Failed to read TMDS config: -6
<7> [58.304607] i915 0000:00:02.0: [drm:intel_ddi_enable [i915]] [CONNECTOR:587:HDMI-A-1] Failed to configure sink scrambling/TMDS bit clock ratio
<7> [58.308650] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1
<7> [58.308820] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:34:plane 1A]
<7> [58.309250] i915 0000:00:02.0: [drm:intel_audio_component_get_power [i915]] restored AUD_FREQ_CNTRL to 0x810
<7> [58.313265] i915 0000:00:02.0: [drm:intel_audio_codec_enable [i915]] [CONNECTOR:587:HDMI-A-1][ENCODER:586:DDI B/PHY B] Enable audio codec on [CRTC:170:pipe A], 64 bytes ELD
<7> [58.314272] azx_init_pci: snd_hda_intel 0000:00:1f.3: Clearing TCSEL
<7> [58.314316] azx_init_pci: snd_hda_intel 0000:00:1f.3: SCH snoop: Enabled
<7> [58.321581] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] HDMI audio pixel clock setting for 285500 not found, falling back to defaults
<7> [58.321862] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] Configuring HDMI audio for pixel clock 25200 (0x00010000)
<7> [58.322098] i915 0000:00:02.0: [drm:hsw_audio_config_update [i915]] using automatic N
<6> [58.322339] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 5 not expected
<6> [58.322342] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 0 not expected
<6> [58.322345] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 10 not expected
<6> [58.322347] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 6 not expected
<7> [58.322529] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:587:HDMI-A-1]
<7> [58.322951] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:170:pipe A]
<6> [66.259282] [IGT] kms_flip: finished subtest A-HDMI-A1, FAIL
|