Result:
integration-manifest git-log-oneline i915_display_info9 igt_runner9 runtimes9 results9.json results9-i915-load.json guc_logs9.tar i915_display_info_post_exec9 boot9 dmesg9
| Detail | Value |
|---|---|
| Duration | 18.60 seconds |
| Hostname |
shard-tglu-10 |
| Igt-Version |
IGT-Version: 2.3-g4c8773922 (x86_64) (Linux: 7.0.0-rc3-CI_DRM_18141-g721db23a9c23+ x86_64) |
| Out |
Using IGT_SRANDOM=1773457146 for randomisation Opened device: /dev/dri/card0 Using monotonic timestamps Starting subtest: flip-vs-absolute-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.3us +- 3.341us accuracy 0.12% Event flip: expected 903, counted 880, passrate = 10.23%, encoder type 2 Event vblank: expected 903, counted 880, passrate = 9.09%, encoder type 2 Expected frametime: 8335us; measured 8430.3us +- 6.290us accuracy 0.22% vblank interval differs from modeline! expected 8334.6us, measured 8430us +- 6.290us, difference 95.7us (15.2 sigma) Event flip: expected 896, counted 900, passrate = 65.56%, encoder type 2 Event vblank: expected 896, counted 900, passrate = 64.44%, 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:2122 __igt_unique____real_main2043() #4 ../tests/kms_flip.c:2043 main() #5 ../sysdeps/nptl/libc_start_call_main.h:74 __libc_start_call_main() #6 ../csu/libc-start.c:128 __libc_start_main@@GLIBC_2.34() #7 [_start+0x25] Dynamic subtest A-HDMI-A1: FAIL (18.599s) |
| Err |
Starting subtest: flip-vs-absolute-wf_vblank Starting dynamic subtest: A-HDMI-A1 (kms_flip:2064) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1707: (kms_flip:2064) CRITICAL: Failed assertion: !retried Dynamic subtest A-HDMI-A1 failed. **** DEBUG **** (kms_flip:2064) DEBUG: vblank ts/seq: last 61.567551/2920, current 61.651516/2930: elapsed=83965.0us expected=84303.1us +- 421.5us, error 0.4% (kms_flip:2064) DEBUG: name = flip last_ts = 61.575947 last_received_ts = 61.575806 last_seq = 2921 current_ts = 61.659912 current_received_ts = 61.659748 current_seq = 2931 count = 46 seq_step = 1 (kms_flip:2064) DEBUG: flip ts/seq: last 61.575947/2921, current 61.659912/2931: elapsed=83966.0us expected=84303.1us +- 421.5us, error 0.4% (kms_flip:2064) DEBUG: name = vblank last_ts = 61.651516 last_received_ts = 61.651478 last_seq = 2930 current_ts = 61.735428 current_received_ts = 61.735180 current_seq = 2940 count = 46 seq_step = 10 (kms_flip:2064) DEBUG: vblank ts/seq: last 61.651516/2930, current 61.735428/2940: elapsed=83909.0us expected=84303.1us +- 421.5us, error 0.5% (kms_flip:2064) DEBUG: name = flip last_ts = 61.659912 last_received_ts = 61.659748 last_seq = 2931 current_ts = 61.743813 current_received_ts = 61.743607 current_seq = 2941 count = 47 seq_step = 1 (kms_flip:2064) DEBUG: flip ts/seq: last 61.659912/2931, current 61.743813/2941: elapsed=83900.0us expected=84303.1us +- 421.5us, error 0.5% (kms_flip:2064) DEBUG: name = vblank last_ts = 61.735428 last_received_ts = 61.735180 last_seq = 2940 current_ts = 61.819321 current_received_ts = 61.819252 current_seq = 2950 count = 47 seq_step = 10 (kms_flip:2064) DEBUG: vblank ts/seq: last 61.735428/2940, current 61.819321/2950: elapsed=83893.0us expected=84303.1us +- 421.5us, error 0.5% (kms_flip:2064) DEBUG: name = flip last_ts = 61.743813 last_received_ts = 61.743607 last_seq = 2941 current_ts = 61.827709 current_received_ts = 61.827515 current_seq = 2951 count = 48 seq_step = 1 (kms_flip:2064) DEBUG: flip ts/seq: last 61.743813/2941, current 61.827709/2951: elapsed=83895.0us expected=84303.1us +- 421.5us, error 0.5% (kms_flip:2064) DEBUG: name = vblank last_ts = 61.819321 last_received_ts = 61.819252 last_seq = 2950 current_ts = 61.903214 current_received_ts = 61.903145 current_seq = 2960 count = 48 seq_step = 10 (kms_flip:2064) DEBUG: vblank ts/seq: last 61.819321/2950, current 61.903214/2960: elapsed=83893.0us expected=84303.1us +- 421.5us, error 0.5% (kms_flip:2064) DEBUG: name = flip last_ts = 61.827709 last_received_ts = 61.827515 last_seq = 2951 current_ts = 61.911598 current_received_ts = 61.911377 current_seq = 2961 count = 49 seq_step = 1 (kms_flip:2064) DEBUG: flip ts/seq: last 61.827709/2951, current 61.911598/2961: elapsed=83891.0us expected=84303.1us +- 421.5us, error 0.5% (kms_flip:2064) DEBUG: name = vblank last_ts = 61.903214 last_received_ts = 61.903145 last_seq = 2960 current_ts = 61.987103 current_received_ts = 61.986877 current_seq = 2970 count = 49 seq_step = 10 (kms_flip:2064) DEBUG: vblank ts/seq: last 61.903214/2960, current 61.987103/2970: elapsed=83890.0us expected=84303.1us +- 421.5us, error 0.5% (kms_flip:2064) DEBUG: name = flip last_ts = 61.911598 last_received_ts = 61.911377 last_seq = 2961 current_ts = 61.995491 current_received_ts = 61.995228 current_seq = 2971 count = 50 seq_step = 1 (kms_flip:2064) DEBUG: flip ts/seq: last 61.911598/2961, current 61.995491/2971: elapsed=83891.0us expected=84303.1us +- 421.5us, error 0.5% (kms_flip:2064) DEBUG: name = vblank last_ts = 61.987103 last_received_ts = 61.986877 last_seq = 2970 current_ts = 62.070992 current_received_ts = 62.070915 current_seq = 2980 count = 50 seq_step = 10 (kms_flip:2064) DEBUG: vblank ts/seq: last 61.987103/2970, current 62.070992/2980: elapsed=83888.0us expected=84303.1us +- 421.5us, error 0.5% (kms_flip:2064) DEBUG: name = flip last_ts = 61.995491 last_received_ts = 61.995228 last_seq = 2971 current_ts = 62.079380 current_received_ts = 62.079109 current_seq = 2981 count = 51 seq_step = 1 (kms_flip:2064) DEBUG: flip ts/seq: last 61.995491/2971, current 62.079380/2981: elapsed=83888.0us expected=84303.1us +- 421.5us, error 0.5% (kms_flip:2064) DEBUG: name = vblank last_ts = 62.070992 last_received_ts = 62.070915 last_seq = 2980 current_ts = 62.154884 current_received_ts = 62.154819 current_seq = 2990 count = 51 seq_step = 10 (kms_flip:2064) DEBUG: vblank ts/seq: last 62.070992/2980, current 62.154884/2990: elapsed=83894.0us expected=84303.1us +- 421.5us, error 0.5% (kms_flip:2064) DEBUG: name = flip last_ts = 62.079380 last_received_ts = 62.079109 last_seq = 2981 current_ts = 62.163277 current_received_ts = 62.163181 current_seq = 2991 count = 52 seq_step = 1 (kms_flip:2064) DEBUG: flip ts/seq: last 62.079380/2981, current 62.163277/2991: elapsed=83896.0us expected=84303.1us +- 421.5us, error 0.5% (kms_flip:2064) DEBUG: name = vblank last_ts = 62.154884 last_received_ts = 62.154819 last_seq = 2990 current_ts = 62.238773 current_received_ts = 62.238590 current_seq = 3000 count = 52 seq_step = 10 (kms_flip:2064) DEBUG: vblank ts/seq: last 62.154884/2990, current 62.238773/3000: elapsed=83891.0us expected=84303.1us +- 421.5us, error 0.5% (kms_flip:2064) DEBUG: name = flip last_ts = 62.163277 last_received_ts = 62.163181 last_seq = 2991 current_ts = 62.247166 current_received_ts = 62.246960 current_seq = 3001 count = 53 seq_step = 1 (kms_flip:2064) DEBUG: flip ts/seq: last 62.163277/2991, current 62.247166/3001: elapsed=83892.0us expected=84303.1us +- 421.5us, error 0.5% (kms_flip:2064) DEBUG: name = vblank last_ts = 62.238773 last_received_ts = 62.238590 last_seq = 3000 current_ts = 62.322666 current_received_ts = 62.322609 current_seq = 3010 count = 53 seq_step = 10 (kms_flip:2064) DEBUG: vblank ts/seq: last 62.238773/3000, current 62.322666/3010: elapsed=83893.0us expected=84303.1us +- 421.5us, error 0.5% (kms_flip:2064) DEBUG: name = flip last_ts = 62.247166 last_received_ts = 62.246960 last_seq = 3001 current_ts = 62.331059 current_received_ts = 62.330906 current_seq = 3011 count = 54 seq_step = 1 (kms_flip:2064) DEBUG: flip ts/seq: last 62.247166/3001, current 62.331059/3011: elapsed=83892.0us expected=84303.1us +- 421.5us, error 0.5% (kms_flip:2064) DEBUG: name = vblank last_ts = 62.322666 last_received_ts = 62.322609 last_seq = 3010 current_ts = 62.406555 current_received_ts = 62.406498 current_seq = 3020 count = 54 seq_step = 10 (kms_flip:2064) DEBUG: vblank ts/seq: last 62.322666/3010, current 62.406555/3020: elapsed=83888.0us expected=84303.1us +- 421.5us, error 0.5% (kms_flip:2064) DEBUG: name = flip last_ts = 62.331059 last_received_ts = 62.330906 last_seq = 3011 current_ts = 62.414944 current_received_ts = 62.414707 current_seq = 3021 count = 55 seq_step = 1 (kms_flip:2064) DEBUG: flip ts/seq: last 62.331059/3011, current 62.414944/3021: elapsed=83886.0us expected=84303.1us +- 421.5us, error 0.5% (kms_flip:2064) DEBUG: name = vblank last_ts = 62.406555 last_received_ts = 62.406498 last_seq = 3020 current_ts = 62.490452 current_received_ts = 62.490269 current_seq = 3030 count = 55 seq_step = 10 (kms_flip:2064) DEBUG: vblank ts/seq: last 62.406555/3020, current 62.490452/3030: elapsed=83894.0us expected=84303.1us +- 421.5us, error 0.5% (kms_flip:2064) DEBUG: name = flip last_ts = 62.414944 last_received_ts = 62.414707 last_seq = 3021 current_ts = 62.498837 current_received_ts = 62.498608 current_seq = 3031 count = 56 seq_step = 1 (kms_flip:2064) DEBUG: flip ts/seq: last 62.414944/3021, current 62.498837/3031: elapsed=83893.0us expected=84303.1us +- 421.5us, error 0.5% (kms_flip:2064) DEBUG: name = vblank last_ts = 62.490452 last_received_ts = 62.490269 last_seq = 3030 current_ts = 62.574345 current_received_ts = 62.574276 current_seq = 3040 count = 56 seq_step = 10 (kms_flip:2064) DEBUG: vblank ts/seq: last 62.490452/3030, current 62.574345/3040: elapsed=83895.0us expected=84303.1us +- 421.5us, error 0.5% (kms_flip:2064) DEBUG: name = flip last_ts = 62.498837 last_received_ts = 62.498608 last_seq = 3031 current_ts = 62.582729 current_received_ts = 62.582478 current_seq = 3041 count = 57 seq_step = 1 (kms_flip:2064) DEBUG: flip ts/seq: last 62.498837/3031, current 62.582729/3041: elapsed=83893.0us expected=84303.1us +- 421.5us, error 0.5% (kms_flip:2064) DEBUG: name = vblank last_ts = 62.574345 last_received_ts = 62.574276 last_seq = 3040 current_ts = 62.658230 current_received_ts = 62.658176 current_seq = 3050 count = 57 seq_step = 10 (kms_flip:2064) DEBUG: vblank ts/seq: last 62.574345/3040, current 62.658230/3050: elapsed=83886.0us expected=84303.1us +- 421.5us, error 0.5% (kms_flip:2064) DEBUG: name = flip last_ts = 62.582729 last_received_ts = 62.582478 last_seq = 3041 current_ts = 62.666618 current_received_ts = 62.666424 current_seq = 3051 count = 58 seq_step = 1 (kms_flip:2064) DEBUG: flip ts/seq: last 62.582729/3041, current 62.666618/3051: elapsed=83889.0us expected=84303.1us +- 421.5us, error 0.5% (kms_flip:2064) DEBUG: name = vblank last_ts = 62.658230 last_received_ts = 62.658176 last_seq = 3050 current_ts = 62.742065 current_received_ts = 62.741993 current_seq = 3060 count = 58 seq_step = 10 (kms_flip:2064) DEBUG: vblank ts/seq: last 62.658230/3050, current 62.742065/3060: elapsed=83836.0us expected=84303.1us +- 421.5us, error 0.6% (kms_flip:2064) DEBUG: inconsistent vblank ts/seq: last 62.658230/3050, current 62.742065/3060: elapsed=83836.0us expected=84303.1us (kms_flip:2064) DEBUG: name = flip last_ts = 62.666618 last_received_ts = 62.666424 last_seq = 3051 current_ts = 62.750446 current_received_ts = 62.750217 current_seq = 3061 count = 59 seq_step = 1 (kms_flip:2064) DEBUG: flip ts/seq: last 62.666618/3051, current 62.750446/3061: elapsed=83826.0us expected=84303.1us +- 421.5us, error 0.6% (kms_flip:2064) DEBUG: inconsistent flip ts/seq: last 62.666618/3051, current 62.750446/3061: elapsed=83826.0us expected=84303.1us (kms_flip:2064) DEBUG: name = vblank last_ts = 62.742065 last_received_ts = 62.741993 last_seq = 3060 current_ts = 62.825890 current_received_ts = 62.825836 current_seq = 3070 count = 59 seq_step = 10 (kms_flip:2064) DEBUG: vblank ts/seq: last 62.742065/3060, current 62.825890/3070: elapsed=83824.0us expected=84303.1us +- 421.5us, error 0.6% (kms_flip:2064) DEBUG: inconsistent vblank ts/seq: last 62.742065/3060, current 62.825890/3070: elapsed=83824.0us expected=84303.1us (kms_flip:2064) DEBUG: name = flip last_ts = 62.750446 last_received_ts = 62.750217 last_seq = 3061 current_ts = 62.834267 current_received_ts = 62.834099 current_seq = 3071 count = 60 seq_step = 1 (kms_flip:2064) DEBUG: flip ts/seq: last 62.750446/3061, current 62.834267/3071: elapsed=83822.0us expected=84303.1us +- 421.5us, error 0.6% (kms_flip:2064) DEBUG: inconsistent flip ts/seq: last 62.750446/3061, current 62.834267/3071: elapsed=83822.0us expected=84303.1us (kms_flip:2064) DEBUG: name = vblank last_ts = 62.825890 last_received_ts = 62.825836 last_seq = 3070 current_ts = 62.909710 current_received_ts = 62.909660 current_seq = 3080 count = 60 seq_step = 10 (kms_flip:2064) DEBUG: vblank ts/seq: last 62.825890/3070, current 62.909710/3080: elapsed=83819.0us expected=84303.1us +- 421.5us, error 0.6% (kms_flip:2064) DEBUG: inconsistent vblank ts/seq: last 62.825890/3070, current 62.909710/3080: elapsed=83819.0us expected=84303.1us (kms_flip:2064) DEBUG: name = flip last_ts = 62.834267 last_received_ts = 62.834099 last_seq = 3071 current_ts = 62.918091 current_received_ts = 62.917843 current_seq = 3081 count = 61 seq_step = 1 (kms_flip:2064) DEBUG: flip ts/seq: last 62.834267/3071, current 62.918091/3081: elapsed=83824.0us expected=84303.1us +- 421.5us, error 0.6% (kms_flip:2064) DEBUG: inconsistent flip ts/seq: last 62.834267/3071, current 62.918091/3081: elapsed=83824.0us expected=84303.1us (kms_flip:2064) DEBUG: name = vblank last_ts = 62.909710 last_received_ts = 62.909660 last_seq = 3080 current_ts = 62.993538 current_received_ts = 62.993374 current_seq = 3090 count = 61 seq_step = 10 (kms_flip:2064) DEBUG: vblank ts/seq: last 62.909710/3080, current 62.993538/3090: elapsed=83827.0us expected=84303.1us +- 421.5us, error 0.6% (kms_flip:2064) DEBUG: inconsistent vblank ts/seq: last 62.909710/3080, current 62.993538/3090: elapsed=83827.0us expected=84303.1us (kms_flip:2064) DEBUG: name = flip last_ts = 62.918091 last_received_ts = 62.917843 last_seq = 3081 current_ts = 63.001915 current_received_ts = 63.001740 current_seq = 3091 count = 62 seq_step = 1 (kms_flip:2064) DEBUG: flip ts/seq: last 62.918091/3081, current 63.001915/3091: elapsed=83822.0us expected=84303.1us +- 421.5us, error 0.6% (kms_flip:2064) DEBUG: inconsistent flip ts/seq: last 62.918091/3081, current 63.001915/3091: elapsed=83822.0us expected=84303.1us (kms_flip:2064) DEBUG: name = vblank last_ts = 62.993538 last_received_ts = 62.993374 last_seq = 3090 current_ts = 63.077358 current_received_ts = 63.077183 current_seq = 3100 count = 62 seq_step = 10 (kms_flip:2064) DEBUG: vblank ts/seq: last 62.993538/3090, current 63.077358/3100: elapsed=83821.0us expected=84303.1us +- 421.5us, error 0.6% (kms_flip:2064) DEBUG: inconsistent vblank ts/seq: last 62.993538/3090, current 63.077358/3100: elapsed=83821.0us expected=84303.1us (kms_flip:2064) DEBUG: name = flip last_ts = 63.001915 last_received_ts = 63.001740 last_seq = 3091 current_ts = 63.085743 current_received_ts = 63.085499 current_seq = 3101 count = 63 seq_step = 1 (kms_flip:2064) DEBUG: flip ts/seq: last 63.001915/3091, current 63.085743/3101: elapsed=83828.0us expected=84303.1us +- 421.5us, error 0.6% (kms_flip:2064) DEBUG: inconsistent flip ts/seq: last 63.001915/3091, current 63.085743/3101: elapsed=83828.0us expected=84303.1us (kms_flip:2064) DEBUG: name = vblank last_ts = 63.077358 last_received_ts = 63.077183 last_seq = 3100 current_ts = 63.161182 current_received_ts = 63.161129 current_seq = 3110 count = 63 seq_step = 10 (kms_flip:2064) DEBUG: vblank ts/seq: last 63.077358/3100, current 63.161182/3110: elapsed=83823.0us expected=84303.1us +- 421.5us, error 0.6% (kms_flip:2064) DEBUG: inconsistent vblank ts/seq: last 63.077358/3100, current 63.161182/3110: elapsed=83823.0us expected=84303.1us (kms_flip:2064) DEBUG: name = flip last_ts = 63.085743 last_received_ts = 63.085499 last_seq = 3101 current_ts = 63.169563 current_received_ts = 63.169506 current_seq = 3111 count = 64 seq_step = 1 (kms_flip:2064) DEBUG: flip ts/seq: last 63.085743/3101, current 63.169563/3111: elapsed=83821.0us expected=84303.1us +- 421.5us, error 0.6% (kms_flip:2064) DEBUG: inconsistent flip ts/seq: last 63.085743/3101, current 63.169563/3111: elapsed=83821.0us expected=84303.1us (kms_flip:2064) DEBUG: name = vblank last_ts = 63.161182 last_received_ts = 63.161129 last_seq = 3110 current_ts = 63.245007 current_received_ts = 63.244949 current_seq = 3120 count = 64 seq_step = 10 (kms_flip:2064) DEBUG: vblank ts/seq: last 63.161182/3110, current 63.245007/3120: elapsed=83826.0us expected=84303.1us +- 421.5us, error 0.6% (kms_flip:2064) DEBUG: inconsistent vblank ts/seq: last 63.161182/3110, current 63.245007/3120: elapsed=83826.0us expected=84303.1us (kms_flip:2064) DEBUG: name = flip last_ts = 63.169563 last_received_ts = 63.169506 last_seq = 3111 current_ts = 63.253387 current_received_ts = 63.253132 current_seq = 3121 count = 65 seq_step = 1 (kms_flip:2064) DEBUG: flip ts/seq: last 63.169563/3111, current 63.253387/3121: elapsed=83824.0us expected=84303.1us +- 421.5us, error 0.6% (kms_flip:2064) DEBUG: inconsistent flip ts/seq: last 63.169563/3111, current 63.253387/3121: elapsed=83824.0us expected=84303.1us (kms_flip:2064) DEBUG: name = vblank last_ts = 63.245007 last_received_ts = 63.244949 last_seq = 3120 current_ts = 63.328827 current_received_ts = 63.328770 current_seq = 3130 count = 65 seq_step = 10 (kms_flip:2064) DEBUG: vblank ts/seq: last 63.245007/3120, current 63.328827/3130: elapsed=83820.0us expected=84303.1us +- 421.5us, error 0.6% (kms_flip:2064) DEBUG: inconsistent vblank ts/seq: last 63.245007/3120, current 63.328827/3130: elapsed=83820.0us expected=84303.1us (kms_flip:2064) DEBUG: name = flip last_ts = 63.253387 last_received_ts = 63.253132 last_seq = 3121 current_ts = 63.337208 current_received_ts = 63.336922 current_seq = 3131 count = 66 seq_step = 1 (kms_flip:2064) DEBUG: flip ts/seq: last 63.253387/3121, current 63.337208/3131: elapsed=83820.0us expected=84303.1us +- 421.5us, error 0.6% (kms_flip:2064) DEBUG: inconsistent flip ts/seq: last 63.253387/3121, current 63.337208/3131: elapsed=83820.0us expected=84303.1us (kms_flip:2064) DEBUG: name = vblank last_ts = 63.328827 last_received_ts = 63.328770 last_seq = 3130 current_ts = 63.412647 current_received_ts = 63.412605 current_seq = 3140 count = 66 seq_step = 10 (kms_flip:2064) DEBUG: vblank ts/seq: last 63.328827/3130, current 63.412647/3140: elapsed=83822.0us expected=84303.1us +- 421.5us, error 0.6% (kms_flip:2064) DEBUG: inconsistent vblank ts/seq: last 63.328827/3130, current 63.412647/3140: elapsed=83822.0us expected=84303.1us (kms_flip:2064) DEBUG: name = flip last_ts = 63.337208 last_received_ts = 63.336922 last_seq = 3131 current_ts = 63.421028 current_received_ts = 63.420856 current_seq = 3141 count = 67 seq_step = 1 (kms_flip:2064) DEBUG: flip ts/seq: last 63.337208/3131, current 63.421028/3141: elapsed=83823.0us expected=84303.1us +- 421.5us, error 0.6% (kms_flip:2064) DEBUG: inconsistent flip ts/seq: last 63.337208/3131, current 63.421028/3141: elapsed=83823.0us expected=84303.1us (kms_flip:2064) DEBUG: name = vblank last_ts = 63.412647 last_received_ts = 63.412605 last_seq = 3140 current_ts = 63.496475 current_received_ts = 63.496410 current_seq = 3150 count = 67 seq_step = 10 (kms_flip:2064) DEBUG: vblank ts/seq: last 63.412647/3140, current 63.496475/3150: elapsed=83826.0us expected=84303.1us +- 421.5us, error 0.6% (kms_flip:2064) DEBUG: inconsistent vblank ts/seq: last 63.412647/3140, current 63.496475/3150: elapsed=83826.0us expected=84303.1us (kms_flip:2064) DEBUG: name = flip last_ts = 63.421028 last_received_ts = 63.420856 last_seq = 3141 current_ts = 63.504860 current_received_ts = 63.504646 current_seq = 3151 count = 68 seq_step = 1 (kms_flip:2064) DEBUG: flip ts/seq: last 63.421028/3141, current 63.504860/3151: elapsed=83829.0us expected=84303.1us +- 421.5us, error 0.6% (kms_flip:2064) DEBUG: inconsistent flip ts/seq: last 63.421028/3141, current 63.504860/3151: elapsed=83829.0us expected=84303.1us (kms_flip:2064) DEBUG: name = vblank last_ts = 63.496475 last_received_ts = 63.496410 last_seq = 3150 current_ts = 63.580299 current_received_ts = 63.580128 current_seq = 3160 count = 68 seq_step = 10 (kms_flip:2064) DEBUG: vblank ts/seq: last 63.496475/3150, current 63.580299/3160: elapsed=83826.0us expected=84303.1us +- 421.5us, error 0.6% (kms_flip:2064) DEBUG: inconsistent vblank ts/seq: last 63.496475/3150, current 63.580299/3160: elapsed=83826.0us expected=84303.1us (kms_flip:2064) DEBUG: name = flip last_ts = 63.504860 last_received_ts = 63.504646 last_seq = 3151 current_ts = 63.588676 current_received_ts = 63.588352 current_seq = 3161 count = 69 seq_step = 1 (kms_flip:2064) DEBUG: flip ts/seq: last 63.504860/3151, current 63.588676/3161: elapsed=83819.0us expected=84303.1us +- 421.5us, error 0.6% (kms_flip:2064) DEBUG: inconsistent flip ts/seq: last 63.504860/3151, current 63.588676/3161: elapsed=83819.0us expected=84303.1us (kms_flip:2064) DEBUG: name = vblank last_ts = 63.580299 last_received_ts = 63.580128 last_seq = 3160 current_ts = 63.664124 current_received_ts = 63.664104 current_seq = 3170 count = 69 seq_step = 10 (kms_flip:2064) DEBUG: vblank ts/seq: last 63.580299/3160, current 63.664124/3170: elapsed=83822.0us expected=84303.1us +- 421.5us, error 0.6% (kms_flip:2064) DEBUG: inconsistent vblank ts/seq: last 63.580299/3160, current 63.664124/3170: elapsed=83822.0us expected=84303.1us (kms_flip:2064) DEBUG: name = flip last_ts = 63.588676 last_received_ts = 63.588352 last_seq = 3161 current_ts = 63.672504 current_received_ts = 63.672348 current_seq = 3171 count = 70 seq_step = 1 (kms_flip:2064) DEBUG: flip ts/seq: last 63.588676/3161, current 63.672504/3171: elapsed=83825.0us expected=84303.1us +- 421.5us, error 0.6% (kms_flip:2064) DEBUG: inconsistent flip ts/seq: last 63.588676/3161, current 63.672504/3171: elapsed=83825.0us expected=84303.1us (kms_flip:2064) DEBUG: name = vblank last_ts = 63.664124 last_received_ts = 63.664104 last_seq = 3170 current_ts = 63.747887 current_received_ts = 63.747826 current_seq = 3180 count = 70 seq_step = 10 (kms_flip:2064) DEBUG: vblank ts/seq: last 63.664124/3170, current 63.747887/3180: elapsed=83762.0us expected=84303.1us +- 421.5us, error 0.6% (kms_flip:2064) DEBUG: inconsistent vblank ts/seq: last 63.664124/3170, current 63.747887/3180: elapsed=83762.0us expected=84303.1us (kms_flip:2064) DEBUG: name = flip last_ts = 63.672504 last_received_ts = 63.672348 last_seq = 3171 current_ts = 63.756260 current_received_ts = 63.755970 current_seq = 3181 count = 71 seq_step = 1 (kms_flip:2064) DEBUG: flip ts/seq: last 63.672504/3171, current 63.756260/3181: elapsed=83758.0us expected=84303.1us +- 421.5us, error 0.6% (kms_flip:2064) DEBUG: inconsistent flip ts/seq: last 63.672504/3171, current 63.756260/3181: elapsed=83758.0us expected=84303.1us (kms_flip:2064) DEBUG: name = vblank last_ts = 63.747887 last_received_ts = 63.747826 last_seq = 3180 current_ts = 63.831654 current_received_ts = 63.831585 current_seq = 3190 count = 71 seq_step = 10 (kms_flip:2064) DEBUG: vblank ts/seq: last 63.747887/3180, current 63.831654/3190: elapsed=83768.0us expected=84303.1us +- 421.5us, error 0.6% (kms_flip:2064) DEBUG: inconsistent vblank ts/seq: last 63.747887/3180, current 63.831654/3190: elapsed=83768.0us expected=84303.1us (kms_flip:2064) DEBUG: name = flip last_ts = 63.756260 last_received_ts = 63.755970 last_seq = 3181 current_ts = 63.840023 current_received_ts = 63.839756 current_seq = 3191 count = 72 seq_step = 1 (kms_flip:2064) DEBUG: flip ts/seq: last 63.756260/3181, current 63.840023/3191: elapsed=83763.0us expected=84303.1us +- 421.5us, error 0.6% (kms_flip:2064) DEBUG: inconsistent flip ts/seq: last 63.756260/3181, current 63.840023/3191: elapsed=83763.0us expected=84303.1us (kms_flip:2064) DEBUG: name = vblank last_ts = 63.831654 last_received_ts = 63.831585 last_seq = 3190 current_ts = 63.915417 current_received_ts = 63.915359 current_seq = 3200 count = 72 seq_step = 10 (kms_flip:2064) DEBUG: vblank ts/seq: last 63.831654/3190, current 63.915417/3200: elapsed=83765.0us expected=84303.1us +- 421.5us, error 0.6% (kms_flip:2064) DEBUG: inconsistent vblank ts/seq: last 63.831654/3190, current 63.915417/3200: elapsed=83765.0us expected=84303.1us (kms_flip:2064) DEBUG: name = flip last_ts = 63.840023 last_received_ts = 63.839756 last_seq = 3191 current_ts = 63.923786 current_received_ts = 63.923519 current_seq = 3201 count = 73 seq_step = 1 (kms_flip:2064) DEBUG: flip ts/seq: last 63.840023/3191, current 63.923786/3201: elapsed=83764.0us expected=84303.1us +- 421.5us, error 0.6% (kms_flip:2064) DEBUG: inconsistent flip ts/seq: last 63.840023/3191, current 63.923786/3201: elapsed=83764.0us expected=84303.1us (kms_flip:2064) DEBUG: name = vblank last_ts = 63.915417 last_received_ts = 63.915359 last_seq = 3200 current_ts = 63.999184 current_received_ts = 63.999100 current_seq = 3210 count = 73 seq_step = 10 (kms_flip:2064) DEBUG: vblank ts/seq: last 63.915417/3200, current 63.999184/3210: elapsed=83764.0us expected=84303.1us +- 421.5us, error 0.6% (kms_flip:2064) DEBUG: inconsistent vblank ts/seq: last 63.915417/3200, current 63.999184/3210: elapsed=83764.0us expected=84303.1us (kms_flip:2064) DEBUG: name = flip last_ts = 63.923786 last_received_ts = 63.923519 last_seq = 3201 current_ts = 64.007553 current_received_ts = 64.007492 current_seq = 3211 count = 74 seq_step = 1 (kms_flip:2064) DEBUG: flip ts/seq: last 63.923786/3201, current 64.007553/3211: elapsed=83768.0us expected=84303.1us +- 421.5us, error 0.6% (kms_flip:2064) DEBUG: inconsistent flip ts/seq: last 63.923786/3201, current 64.007553/3211: elapsed=83768.0us expected=84303.1us (kms_flip:2064) DEBUG: name = vblank last_ts = 63.999184 last_received_ts = 63.999100 last_seq = 3210 current_ts = 64.082939 current_received_ts = 64.082779 current_seq = 3220 count = 74 seq_step = 10 (kms_flip:2064) DEBUG: vblank ts/seq: last 63.999184/3210, current 64.082939/3220: elapsed=83759.0us expected=84303.1us +- 421.5us, error 0.6% (kms_flip:2064) DEBUG: inconsistent vblank ts/seq: last 63.999184/3210, current 64.082939/3220: elapsed=83759.0us expected=84303.1us (kms_flip:2064) DEBUG: name = flip last_ts = 64.007553 last_received_ts = 64.007492 last_seq = 3211 current_ts = 64.091316 current_received_ts = 64.091072 current_seq = 3221 count = 75 seq_step = 1 (kms_flip:2064) DEBUG: flip ts/seq: last 64.007553/3211, current 64.091316/3221: elapsed=83764.0us expected=84303.1us +- 421.5us, error 0.6% (kms_flip:2064) DEBUG: inconsistent flip ts/seq: last 64.007553/3211, current 64.091316/3221: elapsed=83764.0us expected=84303.1us (kms_flip:2064) DEBUG: name = vblank last_ts = 64.082939 last_received_ts = 64.082779 last_seq = 3220 current_ts = 64.166702 current_received_ts = 64.166443 current_seq = 3230 count = 75 seq_step = 10 (kms_flip:2064) DEBUG: vblank ts/seq: last 64.082939/3220, current 64.166702/3230: elapsed=83761.0us expected=84303.1us +- 421.5us, error 0.6% (kms_flip:2064) DEBUG: inconsistent vblank ts/seq: last 64.082939/3220, current 64.166702/3230: elapsed=83761.0us expected=84303.1us (kms_flip:2064) DEBUG: name = flip last_ts = 64.091316 last_received_ts = 64.091072 last_seq = 3221 current_ts = 64.175087 current_received_ts = 64.174767 current_seq = 3231 count = 76 seq_step = 1 (kms_flip:2064) DEBUG: flip ts/seq: last 64.091316/3221, current 64.175087/3231: elapsed=83764.0us expected=84303.1us +- 421.5us, error 0.6% (kms_flip:2064) DEBUG: inconsistent flip ts/seq: last 64.091316/3221, current 64.175087/3231: elapsed=83764.0us expected=84303.1us (kms_flip:2064) DEBUG: name = vblank last_ts = 64.166702 last_received_ts = 64.166443 last_seq = 3230 current_ts = 64.250473 current_received_ts = 64.250275 current_seq = 3240 count = 76 seq_step = 10 (kms_flip:2064) DEBUG: vblank ts/seq: last 64.166702/3230, current 64.250473/3240: elapsed=83770.0us expected=84303.1us +- 421.5us, error 0.6% (kms_flip:2064) DEBUG: inconsistent vblank ts/seq: last 64.166702/3230, current 64.250473/3240: elapsed=83770.0us expected=84303.1us (kms_flip:2064) DEBUG: name = flip last_ts = 64.175087 last_received_ts = 64.174767 last_seq = 3231 current_ts = 64.258850 current_received_ts = 64.258545 current_seq = 3241 count = 77 seq_step = 1 (kms_flip:2064) DEBUG: flip ts/seq: last 64.175087/3231, current 64.258850/3241: elapsed=83764.0us expected=84303.1us +- 421.5us, error 0.6% (kms_flip:2064) DEBUG: inconsistent flip ts/seq: last 64.175087/3231, current 64.258850/3241: elapsed=83764.0us expected=84303.1us (kms_flip:2064) DEBUG: name = vblank last_ts = 64.250473 last_received_ts = 64.250275 last_seq = 3240 current_ts = 64.334236 current_received_ts = 64.334167 current_seq = 3250 count = 77 seq_step = 10 (kms_flip:2064) DEBUG: vblank ts/seq: last 64.250473/3240, current 64.334236/3250: elapsed=83765.0us expected=84303.1us +- 421.5us, error 0.6% (kms_flip:2064) DEBUG: inconsistent vblank ts/seq: last 64.250473/3240, current 64.334236/3250: elapsed=83765.0us expected=84303.1us (kms_flip:2064) DEBUG: name = flip last_ts = 64.258850 last_received_ts = 64.258545 last_seq = 3241 current_ts = 64.342606 current_received_ts = 64.342361 current_seq = 3251 count = 78 seq_step = 1 (kms_flip:2064) DEBUG: flip ts/seq: last 64.258850/3241, current 64.342606/3251: elapsed=83761.0us expected=84303.1us +- 421.5us, error 0.6% (kms_flip:2064) DEBUG: inconsistent flip ts/seq: last 64.258850/3241, current 64.342606/3251: elapsed=83761.0us expected=84303.1us (kms_flip:2064) DEBUG: name = vblank last_ts = 64.334236 last_received_ts = 64.334167 last_seq = 3250 current_ts = 64.417999 current_received_ts = 64.417725 current_seq = 3260 count = 78 seq_step = 10 (kms_flip:2064) DEBUG: vblank ts/seq: last 64.334236/3250, current 64.417999/3260: elapsed=83759.0us expected=84303.1us +- 421.5us, error 0.6% (kms_flip:2064) DEBUG: inconsistent vblank ts/seq: last 64.334236/3250, current 64.417999/3260: elapsed=83759.0us expected=84303.1us (kms_flip:2064) DEBUG: name = flip last_ts = 64.342606 last_received_ts = 64.342361 last_seq = 3251 current_ts = 64.426369 current_received_ts = 64.426094 current_seq = 3261 count = 79 seq_step = 1 (kms_flip:2064) DEBUG: flip ts/seq: last 64.342606/3251, current 64.426369/3261: elapsed=83761.0us expected=84303.1us +- 421.5us, error 0.6% (kms_flip:2064) DEBUG: inconsistent flip ts/seq: last 64.342606/3251, current 64.426369/3261: elapsed=83761.0us expected=84303.1us (kms_flip:2064) DEBUG: name = vblank last_ts = 64.417999 last_received_ts = 64.417725 last_seq = 3260 current_ts = 64.501762 current_received_ts = 64.501686 current_seq = 3270 count = 79 seq_step = 10 (kms_flip:2064) DEBUG: vblank ts/seq: last 64.417999/3260, current 64.501762/3270: elapsed=83765.0us expected=84303.1us +- 421.5us, error 0.6% (kms_flip:2064) DEBUG: inconsistent vblank ts/seq: last 64.417999/3260, current 64.501762/3270: elapsed=83765.0us expected=84303.1us (kms_flip:2064) DEBUG: name = flip last_ts = 64.426369 last_received_ts = 64.426094 last_seq = 3261 current_ts = 64.510139 current_received_ts = 64.509949 current_seq = 3271 count = 80 seq_step = 1 (kms_flip:2064) DEBUG: flip ts/seq: last 64.426369/3261, current 64.510139/3271: elapsed=83767.0us expected=84303.1us +- 421.5us, error 0.6% (kms_flip:2064) DEBUG: inconsistent flip ts/seq: last 64.426369/3261, current 64.510139/3271: elapsed=83767.0us expected=84303.1us (kms_flip:2064) DEBUG: name = vblank last_ts = 64.501762 last_received_ts = 64.501686 last_seq = 3270 current_ts = 64.585526 current_received_ts = 64.585457 current_seq = 3280 count = 80 seq_step = 10 (kms_flip:2064) DEBUG: vblank ts/seq: last 64.501762/3270, current 64.585526/3280: elapsed=83763.0us expected=84303.1us +- 421.5us, error 0.6% (kms_flip:2064) DEBUG: inconsistent vblank ts/seq: last 64.501762/3270, current 64.585526/3280: elapsed=83763.0us expected=84303.1us (kms_flip:2064) DEBUG: name = flip last_ts = 64.510139 last_received_ts = 64.509949 last_seq = 3271 current_ts = 64.593903 current_received_ts = 64.593681 current_seq = 3281 count = 81 seq_step = 1 (kms_flip:2064) DEBUG: flip ts/seq: last 64.510139/3271, current 64.593903/3281: elapsed=83764.0us expected=84303.1us +- 421.5us, error 0.6% (kms_flip:2064) DEBUG: inconsistent flip ts/seq: last 64.510139/3271, current 64.593903/3281: elapsed=83764.0us expected=84303.1us (kms_flip:2064) DEBUG: name = vblank last_ts = 64.585526 last_received_ts = 64.585457 last_seq = 3280 current_ts = 64.669289 current_received_ts = 64.669212 current_seq = 3290 count = 81 seq_step = 10 (kms_flip:2064) DEBUG: vblank ts/seq: last 64.585526/3280, current 64.669289/3290: elapsed=83764.0us expected=84303.1us +- 421.5us, error 0.6% (kms_flip:2064) DEBUG: inconsistent vblank ts/seq: last 64.585526/3280, current 64.669289/3290: elapsed=83764.0us expected=84303.1us (kms_flip:2064) DEBUG: name = flip last_ts = 64.593903 last_received_ts = 64.593681 last_seq = 3281 current_ts = 64.677658 current_received_ts = 64.677582 current_seq = 3291 count = 82 seq_step = 1 (kms_flip:2064) DEBUG: flip ts/seq: last 64.593903/3281, current 64.677658/3291: elapsed=83760.0us expected=84303.1us +- 421.5us, error 0.6% (kms_flip:2064) DEBUG: inconsistent flip ts/seq: last 64.593903/3281, current 64.677658/3291: elapsed=83760.0us expected=84303.1us (kms_flip:2064) DEBUG: name = vblank last_ts = 64.669289 last_received_ts = 64.669212 last_seq = 3290 current_ts = 64.752998 current_received_ts = 64.752930 current_seq = 3300 count = 82 seq_step = 10 (kms_flip:2064) DEBUG: vblank ts/seq: last 64.669289/3290, current 64.752998/3300: elapsed=83712.0us expected=84303.1us +- 421.5us, error 0.7% (kms_flip:2064) DEBUG: inconsistent vblank ts/seq: last 64.669289/3290, current 64.752998/3300: elapsed=83712.0us expected=84303.1us (kms_flip:2064) DEBUG: name = flip last_ts = 64.677658 last_received_ts = 64.677582 last_seq = 3291 current_ts = 64.761368 current_received_ts = 64.761101 current_seq = 3301 count = 83 seq_step = 1 (kms_flip:2064) DEBUG: flip ts/seq: last 64.677658/3291, current 64.761368/3301: elapsed=83708.0us expected=84303.1us +- 421.5us, error 0.7% (kms_flip:2064) DEBUG: inconsistent flip ts/seq: last 64.677658/3291, current 64.761368/3301: elapsed=83708.0us expected=84303.1us (kms_flip:2064) DEBUG: name = vblank last_ts = 64.752998 last_received_ts = 64.752930 last_seq = 3300 current_ts = 64.836716 current_received_ts = 64.836647 current_seq = 3310 count = 83 seq_step = 10 (kms_flip:2064) DEBUG: vblank ts/seq: last 64.752998/3300, current 64.836716/3310: elapsed=83712.0us expected=84303.1us +- 421.5us, error 0.7% (kms_flip:2064) DEBUG: inconsistent vblank ts/seq: last 64.752998/3300, current 64.836716/3310: elapsed=83712.0us expected=84303.1us (kms_flip:2064) DEBUG: name = flip last_ts = 64.761368 last_received_ts = 64.761101 last_seq = 3301 current_ts = 64.845078 current_received_ts = 64.844818 current_seq = 3311 count = 84 seq_step = 1 (kms_flip:2064) DEBUG: flip ts/seq: last 64.761368/3301, current 64.845078/3311: elapsed=83711.0us expected=84303.1us +- 421.5us, error 0.7% (kms_flip:2064) DEBUG: inconsistent flip ts/seq: last 64.761368/3301, current 64.845078/3311: elapsed=83711.0us expected=84303.1us (kms_flip:2064) DEBUG: name = vblank last_ts = 64.836716 last_received_ts = 64.836647 last_seq = 3310 current_ts = 64.920425 current_received_ts = 64.920357 current_seq = 3320 count = 84 seq_step = 10 (kms_flip:2064) DEBUG: vblank ts/seq: last 64.836716/3310, current 64.920425/3320: elapsed=83713.0us expected=84303.1us +- 421.5us, error 0.7% (kms_flip:2064) DEBUG: inconsistent vblank ts/seq: last 64.836716/3310, current 64.920425/3320: elapsed=83713.0us expected=84303.1us (kms_flip:2064) DEBUG: name = flip last_ts = 64.845078 last_received_ts = 64.844818 last_seq = 3311 current_ts = 64.928795 current_received_ts = 64.928528 current_seq = 3321 count = 85 seq_step = 1 (kms_flip:2064) DEBUG: flip ts/seq: last 64.845078/3311, current 64.928795/3321: elapsed=83715.0us expected=84303.1us +- 421.5us, error 0.7% (kms_flip:2064) DEBUG: inconsistent flip ts/seq: last 64.845078/3311, current 64.928795/3321: elapsed=83715.0us expected=84303.1us (kms_flip:2064) DEBUG: name = vblank last_ts = 64.920425 last_received_ts = 64.920357 last_seq = 3320 current_ts = 65.004135 current_received_ts = 65.004089 current_seq = 3330 count = 85 seq_step = 10 (kms_flip:2064) DEBUG: vblank ts/seq: last 64.920425/3320, current 65.004135/3330: elapsed=83709.0us expected=84303.1us +- 421.5us, error 0.7% (kms_flip:2064) DEBUG: inconsistent vblank ts/seq: last 64.920425/3320, current 65.004135/3330: elapsed=83709.0us expected=84303.1us (kms_flip:2064) DEBUG: name = flip last_ts = 64.928795 last_received_ts = 64.928528 last_seq = 3321 current_ts = 65.012505 current_received_ts = 65.012253 current_seq = 3331 count = 86 seq_step = 1 (kms_flip:2064) DEBUG: flip ts/seq: last 64.928795/3321, current 65.012505/3331: elapsed=83708.0us expected=84303.1us +- 421.5us, error 0.7% (kms_flip:2064) DEBUG: inconsistent flip ts/seq: last 64.928795/3321, current 65.012505/3331: elapsed=83708.0us expected=84303.1us (kms_flip:2064) DEBUG: name = vblank last_ts = 65.004135 last_received_ts = 65.004089 last_seq = 3330 current_ts = 65.087845 current_received_ts = 65.087769 current_seq = 3340 count = 86 seq_step = 10 (kms_flip:2064) DEBUG: vblank ts/seq: last 65.004135/3330, current 65.087845/3340: elapsed=83711.0us expected=84303.1us +- 421.5us, error 0.7% (kms_flip:2064) DEBUG: inconsistent vblank ts/seq: last 65.004135/3330, current 65.087845/3340: elapsed=83711.0us expected=84303.1us (kms_flip:2064) DEBUG: name = flip last_ts = 65.012505 last_received_ts = 65.012253 last_seq = 3331 current_ts = 65.096222 current_received_ts = 65.095924 current_seq = 3341 count = 87 seq_step = 1 (kms_flip:2064) DEBUG: flip ts/seq: last 65.012505/3331, current 65.096222/3341: elapsed=83716.0us expected=84303.1us +- 421.5us, error 0.7% (kms_flip:2064) DEBUG: inconsistent flip ts/seq: last 65.012505/3331, current 65.096222/3341: elapsed=83716.0us expected=84303.1us (kms_flip:2064) DEBUG: name = vblank last_ts = 65.087845 last_received_ts = 65.087769 last_seq = 3340 current_ts = 65.171562 current_received_ts = 65.171494 current_seq = 3350 count = 87 seq_step = 10 (kms_flip:2064) DEBUG: vblank ts/seq: last 65.087845/3340, current 65.171562/3350: elapsed=83715.0us expected=84303.1us +- 421.5us, error 0.7% (kms_flip:2064) DEBUG: inconsistent vblank ts/seq: last 65.087845/3340, current 65.171562/3350: elapsed=83715.0us expected=84303.1us (kms_flip:2064) DEBUG: name = flip last_ts = 65.096222 last_received_ts = 65.095924 last_seq = 3341 current_ts = 65.179932 current_received_ts = 65.179741 current_seq = 3351 count = 88 seq_step = 1 (kms_flip:2064) DEBUG: flip ts/seq: last 65.096222/3341, current 65.179932/3351: elapsed=83709.0us expected=84303.1us +- 421.5us, error 0.7% (kms_flip:2064) DEBUG: inconsistent flip ts/seq: last 65.096222/3341, current 65.179932/3351: elapsed=83709.0us expected=84303.1us (kms_flip:2064) DEBUG: name = vblank last_ts = 65.171562 last_received_ts = 65.171494 last_seq = 3350 current_ts = 65.255264 current_received_ts = 65.255112 current_seq = 3360 count = 88 seq_step = 10 (kms_flip:2064) DEBUG: vblank ts/seq: last 65.171562/3350, current 65.255264/3360: elapsed=83706.0us expected=84303.1us +- 421.5us, error 0.7% (kms_flip:2064) DEBUG: inconsistent vblank ts/seq: last 65.171562/3350, current 65.255264/3360: elapsed=83706.0us expected=84303.1us (kms_flip:2064) DEBUG: name = flip last_ts = 65.179932 last_received_ts = 65.179741 last_seq = 3351 current_ts = 65.263634 current_received_ts = 65.263306 current_seq = 3361 count = 89 seq_step = 1 (kms_flip:2064) DEBUG: flip ts/seq: last 65.179932/3351, current 65.263634/3361: elapsed=83708.0us expected=84303.1us +- 421.5us, error 0.7% (kms_flip:2064) DEBUG: inconsistent flip ts/seq: last 65.179932/3351, current 65.263634/3361: elapsed=83708.0us expected=84303.1us (kms_flip:2064) DEBUG: name = vblank last_ts = 65.255264 last_received_ts = 65.255112 last_seq = 3360 current_ts = 65.338982 current_received_ts = 65.338921 current_seq = 3370 count = 89 seq_step = 10 (kms_flip:2064) DEBUG: vblank ts/seq: last 65.255264/3360, current 65.338982/3370: elapsed=83717.0us expected=84303.1us +- 421.5us, error 0.7% (kms_flip:2064) DEBUG: inconsistent vblank ts/seq: last 65.255264/3360, current 65.338982/3370: elapsed=83717.0us expected=84303.1us (kms_flip:2064) INFO: Event flip: expected 896, counted 900, passrate = 65.56%, encoder type 2 (kms_flip:2064) DEBUG: dropped frames, expected 896, counted 900, passrate = 65.56%, encoder type 2 (kms_flip:2064) INFO: Event vblank: expected 896, counted 900, passrate = 64.44%, encoder type 2 (kms_flip:2064) DEBUG: dropped frames, expected 896, counted 900, passrate = 64.44%, encoder type 2 (kms_flip:2064) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1707: (kms_flip:2064) CRITICAL: Failed assertion: !retried (kms_flip:2064) igt_core-INFO: Stack trace: (kms_flip:2064) igt_core-INFO: #0 ../lib/igt_core.c:2075 __igt_fail_assert() (kms_flip:2064) igt_core-INFO: #1 ../tests/kms_flip.c:428 run_test_on_crtc_set.constprop.0() (kms_flip:2064) igt_core-INFO: #2 ../tests/kms_flip.c:1887 run_test() (kms_flip:2064) igt_core-INFO: #3 ../tests/kms_flip.c:2122 __igt_unique____real_main2043() (kms_flip:2064) igt_core-INFO: #4 ../tests/kms_flip.c:2043 main() (kms_flip:2064) igt_core-INFO: #5 ../sysdeps/nptl/libc_start_call_main.h:74 __libc_start_call_main() (kms_flip:2064) igt_core-INFO: #6 ../csu/libc-start.c:128 __libc_start_main@@GLIBC_2.34() (kms_flip:2064) igt_core-INFO: #7 [_start+0x25] **** END **** Dynamic subtest A-HDMI-A1: FAIL (18.599s) |
| Dmesg |
<6> [46.607547] Console: switching to colour dummy device 80x25
<6> [46.607604] [IGT] kms_flip: executing
<7> [46.610752] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [46.611409] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [46.611701] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [46.621525] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:597:DP-1]
<7> [46.621534] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:597:DP-1]
<7> [46.625946] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:597:DP-1] disconnected
<7> [46.626343] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:607:DP-2]
<7> [46.626347] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:607:DP-2]
<7> [46.626679] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:607:DP-2] disconnected
<7> [46.627044] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:616:DP-3]
<7> [46.627047] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:616:DP-3]
<7> [46.627384] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:616:DP-3] disconnected
<7> [46.627743] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:625:DP-4]
<7> [46.627746] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:625:DP-4]
<7> [46.628064] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:625:DP-4] disconnected
<7> [46.628625] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:587:HDMI-A-1]
<7> [46.628634] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:587:HDMI-A-1]
<7> [46.655397] 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> [46.655407] 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> [46.655410] 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> [46.655413] 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> [46.655416] 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> [46.655419] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:587:HDMI-A-1] ELD monitor fitHeadlessGS
<7> [46.655423] 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> [46.655426] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:587:HDMI-A-1] ELD size 64, SAD count 10
<7> [46.655842] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0040 w(1)
<7> [46.655987] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [46.656499] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0040 w(1)
<7> [46.656652] i915 0000:00:02.0: [drm:drm_dp_dual_mode_detect [drm_display_helper]] DP dual mode HDMI ID: (err -6)
<7> [46.657485] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:587:HDMI-A-1] probed modes:
<7> [46.657490] 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> [46.657494] 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> [46.657497] 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> [46.657500] 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> [46.657503] 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> [46.657506] 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> [46.657509] 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> [46.657511] 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> [46.657514] 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> [46.657517] 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> [46.657520] 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> [46.657523] 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> [46.657526] 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> [46.657529] 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> [46.657532] 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> [46.657535] 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> [46.657537] 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> [46.657540] 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> [46.657543] 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> [46.657546] 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> [46.657549] 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> [46.657552] 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> [46.657555] 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> [46.657558] 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> [46.657561] 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> [46.657564] 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> [46.657567] 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> [46.657570] 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> [46.657573] 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> [46.657575] 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> [46.657578] 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> [46.657581] 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> [46.657584] 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> [46.657587] 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> [46.657590] 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> [46.657593] 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> [46.657596] 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> [46.657598] 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> [46.657601] 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> [46.657604] 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> [46.657607] 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> [46.657610] 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> [46.657613] 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> [46.657616] 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> [46.657619] 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> [46.657622] 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> [46.657625] 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> [46.657628] 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> [46.657631] 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> [46.657634] 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> [46.657636] 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> [46.657639] 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> [46.657642] 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> [46.657645] 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> [46.657648] 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> [46.657651] 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> [46.657654] 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> [46.657657] 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> [46.657660] 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> [46.657662] 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> [46.657665] 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> [46.657668] 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> [46.657672] 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> [46.657674] 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> [46.657677] 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> [46.657680] 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> [46.657683] 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> [46.657686] 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> [46.657689] 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> [46.657692] 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> [46.657694] 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> [46.657697] 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> [46.657700] 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> [46.657741] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:597:DP-1]
<7> [46.657744] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:597:DP-1]
<7> [46.662092] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:597:DP-1] disconnected
<7> [46.662121] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:607:DP-2]
<7> [46.662124] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:607:DP-2]
<7> [46.662447] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:607:DP-2] disconnected
<7> [46.662460] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:616:DP-3]
<7> [46.662463] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:616:DP-3]
<7> [46.662785] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:616:DP-3] disconnected
<7> [46.662797] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:625:DP-4]
<7> [46.662800] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:625:DP-4]
<7> [46.663134] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:625:DP-4] disconnected
<6> [46.663577] [IGT] kms_flip: starting subtest flip-vs-absolute-wf_vblank
<6> [46.666056] [IGT] kms_flip: starting dynamic subtest A-HDMI-A1
<7> [46.666443] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:635]
<7> [46.666536] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:637]
<7> [46.732915] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:170:pipe A]
<7> [46.733338] 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> [46.733665] 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> [46.733963] 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> [46.734279] 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> [46.734570] 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> [46.734857] 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> [46.735160] 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> [46.735455] 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> [46.735747] 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> [46.736036] 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> [46.736349] 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> [46.736635] 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> [46.736882] 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> [46.737119] 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> [46.737351] 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> [46.737580] 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> [46.737811] 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> [46.738038] 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> [46.738283] 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> [46.738512] 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> [46.738741] 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> [46.738969] 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> [46.739213] 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> [46.739443] 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> [46.739617] 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> [46.739796] 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> [46.739969] 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> [46.740152] 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> [46.740326] 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> [46.740500] 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> [46.740674] 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> [46.740848] 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> [46.741024] 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> [46.741210] 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> [46.741384] 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> [46.741557] 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> [46.741732] 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> [46.741906] 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> [46.742082] 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> [46.742271] 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> [46.742453] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in avi infoframe
<7> [46.742608] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [46.742765] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [46.742767] i915 0000:00:02.0: colorspace: RGB
<7> [46.742768] i915 0000:00:02.0: scan mode: Underscan
<7> [46.742770] i915 0000:00:02.0: colorimetry: No Data
<7> [46.742771] i915 0000:00:02.0: picture aspect: No Data
<7> [46.742773] i915 0000:00:02.0: active aspect: Same as Picture
<7> [46.742774] i915 0000:00:02.0: itc: No Data
<7> [46.742776] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [46.742777] i915 0000:00:02.0: quantization range: Full
<7> [46.742778] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [46.742780] i915 0000:00:02.0: video code: 0
<7> [46.742781] i915 0000:00:02.0: ycc quantization range: Full
<7> [46.742783] i915 0000:00:02.0: hdmi content type: Graphics
<7> [46.742784] i915 0000:00:02.0: pixel repeat: 0
<7> [46.742785] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [46.742787] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [46.742942] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in spd infoframe
<7> [46.743097] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [46.743265] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [46.743267] i915 0000:00:02.0: vendor: Intel
<7> [46.743269] i915 0000:00:02.0: product: Integrated gfx
<7> [46.743270] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [46.743272] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [46.743427] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hdmi infoframe
<7> [46.743581] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [46.743737] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [46.743739] i915 0000:00:02.0: empty frame
<7> [46.743740] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [46.743896] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:170:pipe A] fastset requirement not met, forcing full modeset
<7> [46.744053] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:170:pipe A] releasing DPLL 0
<7> [46.744257] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [46.744417] 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> [46.744609] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:34:plane 1A] ddb ( 0 - 1993) -> ( 0 - 0), size 1993 -> 0
<7> [46.744767] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:164:cursor A] ddb (1993 - 2048) -> ( 0 - 0), size 55 -> 0
<7> [46.744922] 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> [46.745077] 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> [46.745246] 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> [46.745398] 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> [46.745576] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:170:pipe A] data rate 0 num active planes 0
<7> [46.745740] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14993 required 0
<7> [46.745920] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:170:pipe A] min cdclk: 192000 kHz -> 0 kHz
<7> [46.746099] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 17844 kHz -> 0 kHz
<7> [46.746299] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [46.746458] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 172800 kHz, actual 172800 kHz
<7> [46.746614] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [46.746776] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:170:pipe A] enable: no [modeset]
<7> [46.746937] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:34:plane 1A] fb: [NOFB], visible: no
<7> [46.747097] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:64:plane 2A] fb: [NOFB], visible: no
<7> [46.747270] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:94:plane 3A] fb: [NOFB], visible: no
<7> [46.747429] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:124:plane 4A] fb: [NOFB], visible: no
<7> [46.747586] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:134:plane 5A] fb: [NOFB], visible: no
<7> [46.747744] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:144:plane 6A] fb: [NOFB], visible: no
<7> [46.747902] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:154:plane 7A] fb: [NOFB], visible: no
<7> [46.748060] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:164:cursor A] fb: [NOFB], visible: no
<7> [46.748313] 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> [46.764230] i915 0000:00:02.0: [drm:intel_audio_component_get_eld [i915]] Not valid for port B
<7> [46.775129] 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> [46.775752] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [46.776001] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [46.776679] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [46.776948] 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> [46.776971] 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> [46.777285] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [46.782140] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_B
<7> [46.782338] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x1, on? 1) for [CRTC:170:pipe A]
<7> [46.782530] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0
<7> [46.782703] i915 0000:00:02.0: [drm:__intel_fbc_disable [i915]] Disabling FBC on [PLANE:34:plane 1A]
<7> [46.782874] 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> [46.783213] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:586:DDI B/PHY B]
<7> [46.783396] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:596:DDI TC1/PHY TC1]
<7> [46.783556] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:598:DP-MST A]
<7> [46.783737] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:599:DP-MST B]
<7> [46.783896] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:600:DP-MST C]
<7> [46.784058] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:601:DP-MST D]
<7> [46.784245] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:606:DDI TC2/PHY TC2]
<7> [46.784470] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:608:DP-MST A]
<7> [46.784677] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:609:DP-MST B]
<7> [46.784832] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DP-MST C]
<7> [46.784986] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:611:DP-MST D]
<7> [46.785159] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:615:DDI TC3/PHY TC3]
<7> [46.785370] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:617:DP-MST A]
<7> [46.785565] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:618:DP-MST B]
<7> [46.785722] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:619:DP-MST C]
<7> [46.785882] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:620:DP-MST D]
<7> [46.786038] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:624:DDI TC4/PHY TC4]
<7> [46.786264] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:626:DP-MST A]
<7> [46.786455] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST B]
<7> [46.786609] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:628:DP-MST C]
<7> [46.786764] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:629:DP-MST D]
<7> [46.786918] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:587:HDMI-A-1]
<7> [46.787358] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [46.787581] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_3
<7> [46.787773] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_2
<7> [46.788007] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:170:pipe A]
<7> [46.788628] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:308:pipe B]
<7> [46.788888] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:446:pipe C]
<7> [46.789095] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:584:pipe D]
<7> [46.789272] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:170:pipe A]
<7> [46.789308] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:587:HDMI-A-1]
<7> [46.789381] 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> [46.789561] i915 0000:00:02.0: [drm:intel_hdmi_compute_clock [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [46.789723] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:170:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [46.789963] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:586:DDI B/PHY B] [CRTC:170:pipe A]
<7> [46.790158] 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> [46.790335] 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> [46.790499] 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> [46.790668] 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> [46.790910] 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> [46.791074] 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> [46.791253] 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> [46.791423] 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> [46.791610] 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> [46.791826] 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> [46.791986] 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> [46.792157] 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> [46.792326] 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> [46.792525] 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> [46.792728] 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> [46.792885] 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> [46.793042] 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> [46.793215] 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> [46.793441] 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> [46.793653] 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> [46.793810] 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> [46.793968] 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> [46.794135] 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> [46.794321] 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> [46.794556] 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> [46.794719] 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> [46.794878] 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> [46.795036] 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> [46.795237] 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> [46.795456] 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> [46.795614] 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> [46.795770] 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> [46.795927] 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> [46.796125] 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> [46.796364] 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> [46.796538] 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> [46.796699] 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> [46.796857] 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> [46.797015] 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> [46.797224] 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> [46.797435] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in avi infoframe
<7> [46.797593] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [46.797753] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [46.797915] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [46.797917] i915 0000:00:02.0: colorspace: RGB
<7> [46.797919] i915 0000:00:02.0: scan mode: Underscan
<7> [46.797921] i915 0000:00:02.0: colorimetry: No Data
<7> [46.797922] i915 0000:00:02.0: picture aspect: No Data
<7> [46.797923] i915 0000:00:02.0: active aspect: Same as Picture
<7> [46.797925] i915 0000:00:02.0: itc: No Data
<7> [46.797926] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [46.797927] i915 0000:00:02.0: quantization range: Full
<7> [46.797929] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [46.797930] i915 0000:00:02.0: video code: 0
<7> [46.797932] i915 0000:00:02.0: ycc quantization range: Full
<7> [46.797933] i915 0000:00:02.0: hdmi content type: Graphics
<7> [46.797934] i915 0000:00:02.0: pixel repeat: 0
<7> [46.797936] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [46.797937] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in spd infoframe
<7> [46.798159] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [46.798398] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [46.798589] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [46.798591] i915 0000:00:02.0: vendor: Intel
<7> [46.798592] i915 0000:00:02.0: product: Integrated gfx
<7> [46.798593] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [46.798595] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hdmi infoframe
<7> [46.798752] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [46.798909] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [46.799065] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [46.799067] i915 0000:00:02.0: empty frame
<7> [46.799069] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:170:pipe A] fastset requirement not met, forcing full modeset
<7> [46.799350] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [46.799558] 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> [46.799753] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:34:plane 1A] ddb ( 0 - 0) -> ( 0 - 1993), size 0 -> 1993
<7> [46.799907] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:164:cursor A] ddb ( 0 - 0) -> (1993 - 2048), size 0 -> 55
<7> [46.800063] 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> [46.800271] 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> [46.800477] 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> [46.800635] 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> [46.800811] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:170:pipe A] data rate 1142000 num active planes 1
<7> [46.800975] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14993 required 1142
<7> [46.801202] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [46.801413] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:170:pipe A] min cdclk: 0 kHz -> 192000 kHz
<7> [46.801589] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 0 kHz -> 17844 kHz
<7> [46.801773] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [46.801941] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 192000 kHz, actual 192000 kHz
<7> [46.802186] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [46.802372] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:170:pipe A] allocated DPLL 0
<7> [46.802538] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:170:pipe A] reserving DPLL 0
<7> [46.802709] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:170:pipe A] enable: yes [modeset]
<7> [46.802874] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [46.803080] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [46.803314] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [46.803485] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [46.803643] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [46.803800] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [46.804028] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [46.804214] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] GCP: 0x0
<7> [46.804383] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [46.804385] i915 0000:00:02.0: colorspace: RGB
<7> [46.804387] i915 0000:00:02.0: scan mode: Underscan
<7> [46.804388] i915 0000:00:02.0: colorimetry: No Data
<7> [46.804390] i915 0000:00:02.0: picture aspect: No Data
<7> [46.804391] i915 0000:00:02.0: active aspect: Same as Picture
<7> [46.804392] i915 0000:00:02.0: itc: No Data
<7> [46.804394] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [46.804395] i915 0000:00:02.0: quantization range: Full
<7> [46.804397] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [46.804398] i915 0000:00:02.0: video code: 0
<7> [46.804399] i915 0000:00:02.0: ycc quantization range: Full
<7> [46.804401] i915 0000:00:02.0: hdmi content type: Graphics
<7> [46.804402] i915 0000:00:02.0: pixel repeat: 0
<7> [46.804403] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [46.804405] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [46.804407] i915 0000:00:02.0: vendor: Intel
<7> [46.804408] i915 0000:00:02.0: product: Integrated gfx
<7> [46.804409] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [46.804411] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [46.804413] i915 0000:00:02.0: empty frame
<7> [46.804414] 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> [46.804572] 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> [46.804768] 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> [46.805003] 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> [46.805249] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [46.805439] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [46.805601] 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> [46.805761] 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> [46.805955] 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> [46.806122] 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> [46.806281] 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> [46.806439] 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> [46.806625] 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> [46.806742] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_2
<7> [46.806865] 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> [46.807034] 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> [46.807229] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 59, ips linetime: 0
<7> [46.807267] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_3
<7> [46.807394] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [46.807586] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [46.807614] i915 0000:00:02.0: [drm:intel_audio_component_get_power [i915]] restored AUD_FREQ_CNTRL to 0x810
<7> [46.807813] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [46.807984] 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> [46.808160] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [46.808318] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [46.808510] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [46.808750] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [46.808943] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [46.809113] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [46.809271] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [46.809428] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [46.809584] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [46.809811] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [46.809987] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [46.810156] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [46.810315] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [46.810470] 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> [46.810700] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [46.810877] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [46.811036] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:64:plane 2A] fb: [NOFB], visible: no
<7> [46.811213] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:94:plane 3A] fb: [NOFB], visible: no
<7> [46.811378] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:124:plane 4A] fb: [NOFB], visible: no
<7> [46.811614] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:134:plane 5A] fb: [NOFB], visible: no
<7> [46.811770] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:144:plane 6A] fb: [NOFB], visible: no
<7> [46.811924] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:154:plane 7A] fb: [NOFB], visible: no
<7> [46.812156] azx_init_pci: snd_hda_intel 0000:00:1f.3: Clearing TCSEL
<7> [46.812079] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:164:cursor A] fb: [NOFB], visible: no
<7> [46.812195] azx_init_pci: snd_hda_intel 0000:00:1f.3: SCH snoop: Enabled
<7> [46.814088] 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> [46.814898] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:586:DDI B/PHY B]
<7> [46.815158] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:596:DDI TC1/PHY TC1]
<7> [46.815399] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:598:DP-MST A]
<7> [46.815627] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:599:DP-MST B]
<7> [46.815850] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:600:DP-MST C]
<7> [46.816075] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:601:DP-MST D]
<7> [46.816316] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:606:DDI TC2/PHY TC2]
<7> [46.816547] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:608:DP-MST A]
<7> [46.816776] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:609:DP-MST B]
<7> [46.817008] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DP-MST C]
<7> [46.817386] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:611:DP-MST D]
<7> [46.817623] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:615:DDI TC3/PHY TC3]
<7> [46.817848] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:617:DP-MST A]
<7> [46.818063] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:618:DP-MST B]
<7> [46.818321] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:619:DP-MST C]
<7> [46.818550] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:620:DP-MST D]
<7> [46.818771] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:624:DDI TC4/PHY TC4]
<7> [46.818994] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:626:DP-MST A]
<7> [46.819236] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST B]
<7> [46.819467] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:628:DP-MST C]
<7> [46.819688] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:629:DP-MST D]
<7> [46.820143] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [46.820475] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:170:pipe A]
<7> [46.820710] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0
<7> [46.821245] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_B
<7> [46.821575] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [46.821909] 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> [46.822479] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [46.822647] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [46.823177] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [46.823349] 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> [46.823365] 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> [46.827165] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1
<7> [46.827397] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:34:plane 1A]
<7> [46.831987] 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> [46.840314] 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> [46.840551] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] Configuring HDMI audio for pixel clock 25200 (0x00010000)
<7> [46.840768] i915 0000:00:02.0: [drm:hsw_audio_config_update [i915]] using automatic N
<6> [46.840995] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 5 not expected
<6> [46.840998] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 0 not expected
<6> [46.841001] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 10 not expected
<6> [46.841004] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 6 not expected
<7> [46.841207] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:587:HDMI-A-1]
<7> [46.841603] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:170:pipe A]
<7> [46.900417] i915 0000:00:02.0: [drm:intel_audio_component_get_power [i915]] restored AUD_FREQ_CNTRL to 0x810
<7> [46.905146] azx_init_pci: snd_hda_intel 0000:00:1f.3: Clearing TCSEL
<7> [46.905178] azx_init_pci: snd_hda_intel 0000:00:1f.3: SCH snoop: Enabled
<7> [47.663350] 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> [47.725611] 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> [47.725698] 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> [47.725740] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling TC_cold_off
<7> [47.726241] i915 0000:00:02.0: [drm:tgl_tc_cold_request [i915]] TC cold unblock succeeded
<7> [47.726741] 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> [57.304935] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:170:pipe A]
<7> [57.305216] 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> [57.305801] 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> [57.306279] 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> [57.306798] 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> [57.307268] 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> [57.307787] 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> [57.308251] 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> [57.308759] 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> [57.309153] 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> [57.309320] 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> [57.309476] 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> [57.309631] 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> [57.309787] 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> [57.309944] 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> [57.310100] 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> [57.310256] 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> [57.310428] 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> [57.310586] 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> [57.310743] 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> [57.310899] 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> [57.311056] 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> [57.311213] 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> [57.311380] 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> [57.311538] 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> [57.311695] 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> [57.311851] 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> [57.312007] 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> [57.312162] 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> [57.312330] 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> [57.312488] 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> [57.312643] 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> [57.312800] 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> [57.312956] 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> [57.313112] 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> [57.313280] 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> [57.313437] 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> [57.313593] 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> [57.313751] 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> [57.313907] 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> [57.314064] 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> [57.314220] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in avi infoframe
<7> [57.314393] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [57.314550] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [57.314553] i915 0000:00:02.0: colorspace: RGB
<7> [57.314554] i915 0000:00:02.0: scan mode: Underscan
<7> [57.314556] i915 0000:00:02.0: colorimetry: No Data
<7> [57.314557] i915 0000:00:02.0: picture aspect: No Data
<7> [57.314559] i915 0000:00:02.0: active aspect: Same as Picture
<7> [57.314560] i915 0000:00:02.0: itc: No Data
<7> [57.314561] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [57.314563] i915 0000:00:02.0: quantization range: Full
<7> [57.314564] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [57.314566] i915 0000:00:02.0: video code: 0
<7> [57.314567] i915 0000:00:02.0: ycc quantization range: Full
<7> [57.314569] i915 0000:00:02.0: hdmi content type: Graphics
<7> [57.314570] i915 0000:00:02.0: pixel repeat: 0
<7> [57.314572] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [57.314573] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [57.314728] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in spd infoframe
<7> [57.314883] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [57.315039] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [57.315041] i915 0000:00:02.0: vendor: Intel
<7> [57.315042] i915 0000:00:02.0: product: Integrated gfx
<7> [57.315043] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [57.315045] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [57.315201] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hdmi infoframe
<7> [57.315372] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [57.315529] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [57.315531] i915 0000:00:02.0: empty frame
<7> [57.315532] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [57.315689] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:170:pipe A] fastset requirement not met, forcing full modeset
<7> [57.315845] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:170:pipe A] releasing DPLL 0
<7> [57.316035] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [57.316195] 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> [57.316399] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:34:plane 1A] ddb ( 0 - 1993) -> ( 0 - 0), size 1993 -> 0
<7> [57.316556] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:164:cursor A] ddb (1993 - 2048) -> ( 0 - 0), size 55 -> 0
<7> [57.316716] 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> [57.316874] 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> [57.317027] 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> [57.317180] 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> [57.317371] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:170:pipe A] data rate 0 num active planes 0
<7> [57.317536] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14993 required 0
<7> [57.317719] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:170:pipe A] min cdclk: 192000 kHz -> 0 kHz
<7> [57.317899] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 17844 kHz -> 0 kHz
<7> [57.318085] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [57.318254] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 172800 kHz, actual 172800 kHz
<7> [57.318413] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [57.318575] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:170:pipe A] enable: no [modeset]
<7> [57.318736] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:34:plane 1A] fb: [NOFB], visible: no
<7> [57.318894] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:64:plane 2A] fb: [NOFB], visible: no
<7> [57.319053] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:94:plane 3A] fb: [NOFB], visible: no
<7> [57.319220] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:124:plane 4A] fb: [NOFB], visible: no
<7> [57.319379] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:134:plane 5A] fb: [NOFB], visible: no
<7> [57.319538] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:144:plane 6A] fb: [NOFB], visible: no
<7> [57.319695] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:154:plane 7A] fb: [NOFB], visible: no
<7> [57.319852] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:164:cursor A] fb: [NOFB], visible: no
<7> [57.320086] 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> [57.333592] i915 0000:00:02.0: [drm:intel_audio_component_get_eld [i915]] Not valid for port B
<7> [57.343869] 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> [57.344528] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [57.344726] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [57.345408] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [57.345655] 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> [57.345674] 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> [57.346178] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [57.350796] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_B
<7> [57.351057] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x1, on? 1) for [CRTC:170:pipe A]
<7> [57.351325] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0
<7> [57.351575] i915 0000:00:02.0: [drm:__intel_fbc_disable [i915]] Disabling FBC on [PLANE:34:plane 1A]
<7> [57.351841] 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> [57.352172] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:586:DDI B/PHY B]
<7> [57.352438] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:596:DDI TC1/PHY TC1]
<7> [57.352670] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:598:DP-MST A]
<7> [57.352910] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:599:DP-MST B]
<7> [57.353144] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:600:DP-MST C]
<7> [57.353378] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:601:DP-MST D]
<7> [57.353595] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:606:DDI TC2/PHY TC2]
<7> [57.353831] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:608:DP-MST A]
<7> [57.354047] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:609:DP-MST B]
<7> [57.354271] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DP-MST C]
<7> [57.354496] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:611:DP-MST D]
<7> [57.354732] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:615:DDI TC3/PHY TC3]
<7> [57.354966] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:617:DP-MST A]
<7> [57.355195] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:618:DP-MST B]
<7> [57.355430] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:619:DP-MST C]
<7> [57.355661] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:620:DP-MST D]
<7> [57.355918] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:624:DDI TC4/PHY TC4]
<7> [57.356142] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:626:DP-MST A]
<7> [57.356366] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST B]
<7> [57.356587] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:628:DP-MST C]
<7> [57.356821] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:629:DP-MST D]
<7> [57.357053] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:587:HDMI-A-1]
<7> [57.357564] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [57.357882] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_3
<7> [57.358134] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_2
<7> [57.358405] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:170:pipe A]
<7> [57.359349] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:308:pipe B]
<7> [57.359624] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:446:pipe C]
<7> [57.359881] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:584:pipe D]
<7> [57.360137] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:170:pipe A]
<7> [57.360188] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:587:HDMI-A-1]
<7> [57.360296] 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> [57.360544] i915 0000:00:02.0: [drm:intel_hdmi_compute_clock [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [57.360801] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:170:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [57.361027] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:586:DDI B/PHY B] [CRTC:170:pipe A]
<7> [57.361246] 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> [57.361479] 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> [57.361732] 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> [57.361966] 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> [57.362201] 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> [57.362426] 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> [57.362664] 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> [57.362891] 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> [57.363119] 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> [57.363352] 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> [57.363584] 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> [57.363833] 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> [57.364051] 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> [57.364276] 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> [57.364501] 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> [57.364744] 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> [57.364982] 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> [57.365211] 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> [57.365435] 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> [57.365675] 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> [57.365902] 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> [57.366126] 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> [57.366353] 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> [57.366594] 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> [57.366829] 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> [57.367053] 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> [57.367271] 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> [57.367487] 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> [57.367730] 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> [57.367956] 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> [57.368189] 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> [57.368409] 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> [57.368781] 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> [57.369011] 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> [57.369236] 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> [57.369473] 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> [57.369753] 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> [57.369981] 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> [57.370203] 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> [57.370428] 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> [57.370673] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in avi infoframe
<7> [57.370901] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [57.371124] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [57.371343] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [57.371347] i915 0000:00:02.0: colorspace: RGB
<7> [57.371350] i915 0000:00:02.0: scan mode: Underscan
<7> [57.371353] i915 0000:00:02.0: colorimetry: No Data
<7> [57.371355] i915 0000:00:02.0: picture aspect: No Data
<7> [57.371358] i915 0000:00:02.0: active aspect: Same as Picture
<7> [57.371361] i915 0000:00:02.0: itc: No Data
<7> [57.371364] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [57.371366] i915 0000:00:02.0: quantization range: Full
<7> [57.371369] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [57.371372] i915 0000:00:02.0: video code: 0
<7> [57.371375] i915 0000:00:02.0: ycc quantization range: Full
<7> [57.371377] i915 0000:00:02.0: hdmi content type: Graphics
<7> [57.371380] i915 0000:00:02.0: pixel repeat: 0
<7> [57.371383] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [57.371386] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in spd infoframe
<7> [57.371628] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [57.371851] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [57.372084] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [57.372087] i915 0000:00:02.0: vendor: Intel
<7> [57.372090] i915 0000:00:02.0: product: Integrated gfx
<7> [57.372092] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [57.372094] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hdmi infoframe
<7> [57.372321] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [57.372568] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [57.372798] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [57.372801] i915 0000:00:02.0: empty frame
<7> [57.372804] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:170:pipe A] fastset requirement not met, forcing full modeset
<7> [57.373066] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [57.373288] 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> [57.373579] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:34:plane 1A] ddb ( 0 - 0) -> ( 0 - 1993), size 0 -> 1993
<7> [57.373816] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:164:cursor A] ddb ( 0 - 0) -> (1993 - 2048), size 0 -> 55
<7> [57.374042] 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> [57.374263] 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> [57.374505] 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> [57.374720] 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> [57.374987] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:170:pipe A] data rate 1142000 num active planes 1
<7> [57.375214] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14993 required 1142
<7> [57.375443] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [57.375722] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:170:pipe A] min cdclk: 0 kHz -> 192000 kHz
<7> [57.375985] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 0 kHz -> 17844 kHz
<7> [57.376269] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [57.376511] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 192000 kHz, actual 192000 kHz
<7> [57.376710] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [57.376914] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:170:pipe A] allocated DPLL 0
<7> [57.377152] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:170:pipe A] reserving DPLL 0
<7> [57.377394] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:170:pipe A] enable: yes [modeset]
<7> [57.377649] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [57.377849] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [57.378008] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [57.378166] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [57.378324] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [57.378495] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [57.378705] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [57.378872] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] GCP: 0x0
<7> [57.379037] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [57.379039] i915 0000:00:02.0: colorspace: RGB
<7> [57.379041] i915 0000:00:02.0: scan mode: Underscan
<7> [57.379042] i915 0000:00:02.0: colorimetry: No Data
<7> [57.379044] i915 0000:00:02.0: picture aspect: No Data
<7> [57.379045] i915 0000:00:02.0: active aspect: Same as Picture
<7> [57.379046] i915 0000:00:02.0: itc: No Data
<7> [57.379048] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [57.379049] i915 0000:00:02.0: quantization range: Full
<7> [57.379050] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [57.379052] i915 0000:00:02.0: video code: 0
<7> [57.379053] i915 0000:00:02.0: ycc quantization range: Full
<7> [57.379055] i915 0000:00:02.0: hdmi content type: Graphics
<7> [57.379056] i915 0000:00:02.0: pixel repeat: 0
<7> [57.379057] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [57.379059] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [57.379061] i915 0000:00:02.0: vendor: Intel
<7> [57.379062] i915 0000:00:02.0: product: Integrated gfx
<7> [57.379064] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [57.379065] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [57.379067] i915 0000:00:02.0: empty frame
<7> [57.379068] 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> [57.379235] 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> [57.379395] 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> [57.379568] 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> [57.379748] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [57.379926] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [57.380094] 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> [57.380254] 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> [57.380433] 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> [57.380590] 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> [57.380802] 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> [57.380958] 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> [57.381124] 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> [57.381292] 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> [57.381467] 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> [57.381653] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 59, ips linetime: 0
<7> [57.381814] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [57.381969] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [57.382131] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [57.382319] 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> [57.382540] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [57.382762] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [57.382956] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [57.383132] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [57.383311] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [57.383496] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [57.383657] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [57.383821] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [57.383976] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [57.384181] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [57.384348] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [57.384514] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [57.384681] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [57.384850] 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> [57.385029] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [57.385198] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [57.385372] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:64:plane 2A] fb: [NOFB], visible: no
<7> [57.385536] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:94:plane 3A] fb: [NOFB], visible: no
<7> [57.385725] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:124:plane 4A] fb: [NOFB], visible: no
<7> [57.385884] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:134:plane 5A] fb: [NOFB], visible: no
<7> [57.386046] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:144:plane 6A] fb: [NOFB], visible: no
<7> [57.386210] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:154:plane 7A] fb: [NOFB], visible: no
<7> [57.386410] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:164:cursor A] fb: [NOFB], visible: no
<7> [57.386715] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_2
<7> [57.386897] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_3
<7> [57.387090] 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> [57.387090] i915 0000:00:02.0: [drm:intel_audio_component_get_power [i915]] restored AUD_FREQ_CNTRL to 0x810
<7> [57.387347] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:586:DDI B/PHY B]
<7> [57.387513] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:596:DDI TC1/PHY TC1]
<7> [57.387681] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:598:DP-MST A]
<7> [57.387852] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:599:DP-MST B]
<7> [57.388012] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:600:DP-MST C]
<7> [57.388171] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:601:DP-MST D]
<7> [57.388373] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:606:DDI TC2/PHY TC2]
<7> [57.388553] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:608:DP-MST A]
<7> [57.388711] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:609:DP-MST B]
<7> [57.388867] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DP-MST C]
<7> [57.389022] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:611:DP-MST D]
<7> [57.389177] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:615:DDI TC3/PHY TC3]
<7> [57.389354] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:617:DP-MST A]
<7> [57.389522] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:618:DP-MST B]
<7> [57.389690] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:619:DP-MST C]
<7> [57.389847] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:620:DP-MST D]
<7> [57.390008] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:624:DDI TC4/PHY TC4]
<7> [57.390194] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:626:DP-MST A]
<7> [57.390372] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST B]
<7> [57.390527] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:628:DP-MST C]
<7> [57.390688] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:629:DP-MST D]
<7> [57.391025] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [57.391299] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:170:pipe A]
<7> [57.391475] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0
<7> [57.391825] azx_init_pci: snd_hda_intel 0000:00:1f.3: Clearing TCSEL
<7> [57.391865] azx_init_pci: snd_hda_intel 0000:00:1f.3: SCH snoop: Enabled
<7> [57.391933] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_B
<7> [57.392182] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [57.392511] 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> [57.393185] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [57.393359] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [57.393974] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [57.394154] 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> [57.394170] 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> [57.398181] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1
<7> [57.398354] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:34:plane 1A]
<7> [57.402822] 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> [57.411142] 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> [57.411321] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] Configuring HDMI audio for pixel clock 25200 (0x00010000)
<7> [57.411481] i915 0000:00:02.0: [drm:hsw_audio_config_update [i915]] using automatic N
<6> [57.411645] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 5 not expected
<6> [57.411648] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 0 not expected
<6> [57.411649] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 10 not expected
<6> [57.411651] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 6 not expected
<7> [57.411787] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:587:HDMI-A-1]
<7> [57.412055] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:170:pipe A]
<7> [57.474054] i915 0000:00:02.0: [drm:intel_audio_component_get_power [i915]] restored AUD_FREQ_CNTRL to 0x810
<7> [57.478768] azx_init_pci: snd_hda_intel 0000:00:1f.3: Clearing TCSEL
<7> [57.478800] azx_init_pci: snd_hda_intel 0000:00:1f.3: SCH snoop: Enabled
<6> [57.894524] /proc/cgroups lists only v1 controllers, use cgroup.controllers of root cgroup for v2 info
<6> [65.264776] [IGT] kms_flip: finished subtest A-HDMI-A1, FAIL
|