Result:
integration-manifest git-log-oneline i915_display_info3 igt_runner3 runtimes3 results3.json results3-i915-load.json guc_logs3.tar i915_display_info_post_exec3 boot3 dmesg3
| Detail | Value |
|---|---|
| Duration | 18.54 seconds |
| Hostname |
shard-tglu-2 |
| Igt-Version |
IGT-Version: 2.3-g33f5420a2 (x86_64) (Linux: 7.0.0-rc4-CI_DRM_18160-ga25d8c583e1e+ x86_64) |
| Out |
Using IGT_SRANDOM=1773765028 for randomisation Opened device: /dev/dri/card0 Using monotonic timestamps Starting subtest: blocking-wf_vblank Starting dynamic subtest: A-HDMI-A1 1920x1080: 120 285500 1920 1968 2000 2080 1080 1083 1088 1144 0x48 0x5 Expected frametime: 8335us; measured 8748.4us +- 3.793us accuracy 0.13% vblank interval differs from modeline! expected 8334.6us, measured 8748us +- 3.793us, difference 413.8us (109.1 sigma) Event vblank: expected 865, counted 880, passrate = 10.23%, encoder type 2 Expected frametime: 8335us; measured 8432.8us +- 3.152us accuracy 0.11% vblank interval differs from modeline! expected 8334.6us, measured 8433us +- 3.152us, difference 98.2us (31.2 sigma) 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 [__libc_init_first+0x8a] #6 [__libc_start_main+0x8b] #7 [_start+0x25] Dynamic subtest A-HDMI-A1: FAIL (18.544s) |
| Err |
Starting subtest: blocking-wf_vblank Starting dynamic subtest: A-HDMI-A1 (kms_flip:2677) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1707: (kms_flip:2677) CRITICAL: Failed assertion: !retried Dynamic subtest A-HDMI-A1 failed. **** DEBUG **** (kms_flip:2677) DEBUG: vblank ts/seq: last 339.995941/28486, current 340.080963/28496: elapsed=84996.0us expected=87483.8us +- 437.4us, error 2.8% (kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 339.995941/28486, current 340.080963/28496: elapsed=84996.0us expected=87483.8us (kms_flip:2677) DEBUG: name = vblank last_ts = 340.080963 last_received_ts = 340.080811 last_seq = 28496 current_ts = 340.165955 current_received_ts = 340.165833 current_seq = 28506 count = 81 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 340.080963/28496, current 340.165955/28506: elapsed=84992.0us expected=87483.8us +- 437.4us, error 2.8% (kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 340.080963/28496, current 340.165955/28506: elapsed=84992.0us expected=87483.8us (kms_flip:2677) DEBUG: name = vblank last_ts = 340.165955 last_received_ts = 340.165833 last_seq = 28506 current_ts = 340.250946 current_received_ts = 340.250671 current_seq = 28516 count = 82 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 340.165955/28506, current 340.250946/28516: elapsed=84991.0us expected=87483.8us +- 437.4us, error 2.8% (kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 340.165955/28506, current 340.250946/28516: elapsed=84991.0us expected=87483.8us (kms_flip:2677) DEBUG: name = vblank last_ts = 340.250946 last_received_ts = 340.250671 last_seq = 28516 current_ts = 340.335938 current_received_ts = 340.335815 current_seq = 28526 count = 83 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 340.250946/28516, current 340.335938/28526: elapsed=85002.0us expected=87483.8us +- 437.4us, error 2.8% (kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 340.250946/28516, current 340.335938/28526: elapsed=85002.0us expected=87483.8us (kms_flip:2677) DEBUG: name = vblank last_ts = 340.335938 last_received_ts = 340.335815 last_seq = 28526 current_ts = 340.420929 current_received_ts = 340.420807 current_seq = 28536 count = 84 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 340.335938/28526, current 340.420929/28536: elapsed=84994.0us expected=87483.8us +- 437.4us, error 2.8% (kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 340.335938/28526, current 340.420929/28536: elapsed=84994.0us expected=87483.8us (kms_flip:2677) DEBUG: name = vblank last_ts = 340.420929 last_received_ts = 340.420807 last_seq = 28536 current_ts = 340.505920 current_received_ts = 340.505798 current_seq = 28546 count = 85 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 340.420929/28536, current 340.505920/28546: elapsed=84992.0us expected=87483.8us +- 437.4us, error 2.8% (kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 340.420929/28536, current 340.505920/28546: elapsed=84992.0us expected=87483.8us (kms_flip:2677) DEBUG: name = vblank last_ts = 340.505920 last_received_ts = 340.505798 last_seq = 28546 current_ts = 340.590912 current_received_ts = 340.590790 current_seq = 28556 count = 86 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 340.505920/28546, current 340.590912/28556: elapsed=84997.0us expected=87483.8us +- 437.4us, error 2.8% (kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 340.505920/28546, current 340.590912/28556: elapsed=84997.0us expected=87483.8us (kms_flip:2677) DEBUG: name = vblank last_ts = 340.590912 last_received_ts = 340.590790 last_seq = 28556 current_ts = 340.675903 current_received_ts = 340.675781 current_seq = 28566 count = 87 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 340.590912/28556, current 340.675903/28566: elapsed=84993.0us expected=87483.8us +- 437.4us, error 2.8% (kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 340.590912/28556, current 340.675903/28566: elapsed=84993.0us expected=87483.8us (kms_flip:2677) INFO: Event vblank: expected 865, counted 880, passrate = 10.23%, encoder type 2 (kms_flip:2677) DEBUG: dropped frames, expected 865, counted 880, passrate = 10.23%, encoder type 2 (kms_flip:2677) DEBUG: Retrying without a hotplug event (kms_flip:2677) DEBUG: No stale events found (kms_flip:2677) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0 (kms_flip:2677) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0 (kms_flip:2677) INFO: Expected frametime: 8335us; measured 8432.8us +- 3.152us accuracy 0.11% (kms_flip:2677) INFO: vblank interval differs from modeline! expected 8334.6us, measured 8433us +- 3.152us, difference 98.2us (31.2 sigma) (kms_flip:2677) DEBUG: name = vblank last_ts = 0.000000 last_received_ts = 0.000000 last_seq = 0 current_ts = 344.046478 current_received_ts = 344.046265 current_seq = 28965 count = 0 seq_step = 10 (kms_flip:2677) DEBUG: name = vblank last_ts = 344.046478 last_received_ts = 344.046265 last_seq = 28965 current_ts = 344.130798 current_received_ts = 344.130585 current_seq = 28975 count = 1 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 344.046478/28965, current 344.130798/28975: elapsed=84328.0us expected=84327.5us +- 421.6us, error 0.0% (kms_flip:2677) DEBUG: name = vblank last_ts = 344.130798 last_received_ts = 344.130585 last_seq = 28975 current_ts = 344.215149 current_received_ts = 344.215057 current_seq = 28985 count = 2 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 344.130798/28975, current 344.215149/28985: elapsed=84332.0us expected=84327.5us +- 421.6us, error 0.0% (kms_flip:2677) DEBUG: name = vblank last_ts = 344.215149 last_received_ts = 344.215057 last_seq = 28985 current_ts = 344.299469 current_received_ts = 344.299255 current_seq = 28995 count = 3 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 344.215149/28985, current 344.299469/28995: elapsed=84328.0us expected=84327.5us +- 421.6us, error 0.0% (kms_flip:2677) DEBUG: name = vblank last_ts = 344.299469 last_received_ts = 344.299255 last_seq = 28995 current_ts = 344.383789 current_received_ts = 344.383667 current_seq = 29005 count = 4 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 344.299469/28995, current 344.383789/29005: elapsed=84332.0us expected=84327.5us +- 421.6us, error 0.0% (kms_flip:2677) DEBUG: name = vblank last_ts = 344.383789 last_received_ts = 344.383667 last_seq = 29005 current_ts = 344.468140 current_received_ts = 344.468048 current_seq = 29015 count = 5 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 344.383789/29005, current 344.468140/29015: elapsed=84328.0us expected=84327.5us +- 421.6us, error 0.0% (kms_flip:2677) DEBUG: name = vblank last_ts = 344.468140 last_received_ts = 344.468048 last_seq = 29015 current_ts = 344.552460 current_received_ts = 344.552368 current_seq = 29025 count = 6 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 344.468140/29015, current 344.552460/29025: elapsed=84327.0us expected=84327.5us +- 421.6us, error 0.0% (kms_flip:2677) DEBUG: name = vblank last_ts = 344.552460 last_received_ts = 344.552368 last_seq = 29025 current_ts = 344.636780 current_received_ts = 344.636688 current_seq = 29035 count = 7 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 344.552460/29025, current 344.636780/29035: elapsed=84334.0us expected=84327.5us +- 421.6us, error 0.0% (kms_flip:2677) DEBUG: name = vblank last_ts = 344.636780 last_received_ts = 344.636688 last_seq = 29035 current_ts = 344.721130 current_received_ts = 344.721039 current_seq = 29045 count = 8 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 344.636780/29035, current 344.721130/29045: elapsed=84328.0us expected=84327.5us +- 421.6us, error 0.0% (kms_flip:2677) DEBUG: name = vblank last_ts = 344.721130 last_received_ts = 344.721039 last_seq = 29045 current_ts = 344.805450 current_received_ts = 344.805328 current_seq = 29055 count = 9 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 344.721130/29045, current 344.805450/29055: elapsed=84330.0us expected=84327.5us +- 421.6us, error 0.0% (kms_flip:2677) DEBUG: name = vblank last_ts = 344.805450 last_received_ts = 344.805328 last_seq = 29055 current_ts = 344.889679 current_received_ts = 344.889587 current_seq = 29065 count = 10 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 344.805450/29055, current 344.889679/29065: elapsed=84235.0us expected=84327.5us +- 421.6us, error 0.1% (kms_flip:2677) DEBUG: name = vblank last_ts = 344.889679 last_received_ts = 344.889587 last_seq = 29065 current_ts = 344.973877 current_received_ts = 344.973572 current_seq = 29075 count = 11 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 344.889679/29065, current 344.973877/29075: elapsed=84207.0us expected=84327.5us +- 421.6us, error 0.1% (kms_flip:2677) DEBUG: name = vblank last_ts = 344.973877 last_received_ts = 344.973572 last_seq = 29075 current_ts = 345.058105 current_received_ts = 345.058075 current_seq = 29085 count = 12 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 344.973877/29075, current 345.058105/29085: elapsed=84219.0us expected=84327.5us +- 421.6us, error 0.1% (kms_flip:2677) DEBUG: name = vblank last_ts = 345.058105 last_received_ts = 345.058075 last_seq = 29085 current_ts = 345.142334 current_received_ts = 345.142181 current_seq = 29095 count = 13 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 345.058105/29085, current 345.142334/29095: elapsed=84209.0us expected=84327.5us +- 421.6us, error 0.1% (kms_flip:2677) DEBUG: name = vblank last_ts = 345.142334 last_received_ts = 345.142181 last_seq = 29095 current_ts = 345.226532 current_received_ts = 345.226410 current_seq = 29105 count = 14 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 345.142334/29095, current 345.226532/29105: elapsed=84208.0us expected=84327.5us +- 421.6us, error 0.1% (kms_flip:2677) DEBUG: name = vblank last_ts = 345.226532 last_received_ts = 345.226410 last_seq = 29105 current_ts = 345.310730 current_received_ts = 345.310638 current_seq = 29115 count = 15 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 345.226532/29105, current 345.310730/29115: elapsed=84217.0us expected=84327.5us +- 421.6us, error 0.1% (kms_flip:2677) DEBUG: name = vblank last_ts = 345.310730 last_received_ts = 345.310638 last_seq = 29115 current_ts = 345.394958 current_received_ts = 345.394836 current_seq = 29125 count = 16 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 345.310730/29115, current 345.394958/29125: elapsed=84210.0us expected=84327.5us +- 421.6us, error 0.1% (kms_flip:2677) DEBUG: name = vblank last_ts = 345.394958 last_received_ts = 345.394836 last_seq = 29125 current_ts = 345.479156 current_received_ts = 345.479065 current_seq = 29135 count = 17 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 345.394958/29125, current 345.479156/29135: elapsed=84207.0us expected=84327.5us +- 421.6us, error 0.1% (kms_flip:2677) DEBUG: name = vblank last_ts = 345.479156 last_received_ts = 345.479065 last_seq = 29135 current_ts = 345.563385 current_received_ts = 345.563293 current_seq = 29145 count = 18 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 345.479156/29135, current 345.563385/29145: elapsed=84217.0us expected=84327.5us +- 421.6us, error 0.1% (kms_flip:2677) DEBUG: name = vblank last_ts = 345.563385 last_received_ts = 345.563293 last_seq = 29145 current_ts = 345.647583 current_received_ts = 345.647491 current_seq = 29155 count = 19 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 345.563385/29145, current 345.647583/29155: elapsed=84209.0us expected=84327.5us +- 421.6us, error 0.1% (kms_flip:2677) DEBUG: name = vblank last_ts = 345.647583 last_received_ts = 345.647491 last_seq = 29155 current_ts = 345.731812 current_received_ts = 345.731689 current_seq = 29165 count = 20 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 345.647583/29155, current 345.731812/29165: elapsed=84210.0us expected=84327.5us +- 421.6us, error 0.1% (kms_flip:2677) DEBUG: name = vblank last_ts = 345.731812 last_received_ts = 345.731689 last_seq = 29165 current_ts = 345.816010 current_received_ts = 345.815857 current_seq = 29175 count = 21 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 345.731812/29165, current 345.816010/29175: elapsed=84211.0us expected=84327.5us +- 421.6us, error 0.1% (kms_flip:2677) DEBUG: name = vblank last_ts = 345.816010 last_received_ts = 345.815857 last_seq = 29175 current_ts = 345.900146 current_received_ts = 345.900055 current_seq = 29185 count = 22 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 345.816010/29175, current 345.900146/29185: elapsed=84126.0us expected=84327.5us +- 421.6us, error 0.2% (kms_flip:2677) DEBUG: name = vblank last_ts = 345.900146 last_received_ts = 345.900055 last_seq = 29185 current_ts = 345.984253 current_received_ts = 345.984161 current_seq = 29195 count = 23 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 345.900146/29185, current 345.984253/29195: elapsed=84109.0us expected=84327.5us +- 421.6us, error 0.3% (kms_flip:2677) DEBUG: name = vblank last_ts = 345.984253 last_received_ts = 345.984161 last_seq = 29195 current_ts = 346.068359 current_received_ts = 346.068268 current_seq = 29205 count = 24 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 345.984253/29195, current 346.068359/29205: elapsed=84108.0us expected=84327.5us +- 421.6us, error 0.3% (kms_flip:2677) DEBUG: name = vblank last_ts = 346.068359 last_received_ts = 346.068268 last_seq = 29205 current_ts = 346.152466 current_received_ts = 346.152374 current_seq = 29215 count = 25 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 346.068359/29205, current 346.152466/29215: elapsed=84109.0us expected=84327.5us +- 421.6us, error 0.3% (kms_flip:2677) DEBUG: name = vblank last_ts = 346.152466 last_received_ts = 346.152374 last_seq = 29215 current_ts = 346.236572 current_received_ts = 346.236450 current_seq = 29225 count = 26 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 346.152466/29215, current 346.236572/29225: elapsed=84107.0us expected=84327.5us +- 421.6us, error 0.3% (kms_flip:2677) DEBUG: name = vblank last_ts = 346.236572 last_received_ts = 346.236450 last_seq = 29225 current_ts = 346.320679 current_received_ts = 346.320587 current_seq = 29235 count = 27 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 346.236572/29225, current 346.320679/29235: elapsed=84105.0us expected=84327.5us +- 421.6us, error 0.3% (kms_flip:2677) DEBUG: name = vblank last_ts = 346.320679 last_received_ts = 346.320587 last_seq = 29235 current_ts = 346.404785 current_received_ts = 346.404633 current_seq = 29245 count = 28 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 346.320679/29235, current 346.404785/29245: elapsed=84114.0us expected=84327.5us +- 421.6us, error 0.3% (kms_flip:2677) DEBUG: name = vblank last_ts = 346.404785 last_received_ts = 346.404633 last_seq = 29245 current_ts = 346.488892 current_received_ts = 346.488770 current_seq = 29255 count = 29 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 346.404785/29245, current 346.488892/29255: elapsed=84107.0us expected=84327.5us +- 421.6us, error 0.3% (kms_flip:2677) DEBUG: name = vblank last_ts = 346.488892 last_received_ts = 346.488770 last_seq = 29255 current_ts = 346.572998 current_received_ts = 346.572876 current_seq = 29265 count = 30 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 346.488892/29255, current 346.572998/29265: elapsed=84110.0us expected=84327.5us +- 421.6us, error 0.3% (kms_flip:2677) DEBUG: name = vblank last_ts = 346.572998 last_received_ts = 346.572876 last_seq = 29265 current_ts = 346.657104 current_received_ts = 346.656891 current_seq = 29275 count = 31 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 346.572998/29265, current 346.657104/29275: elapsed=84102.0us expected=84327.5us +- 421.6us, error 0.3% (kms_flip:2677) DEBUG: name = vblank last_ts = 346.657104 last_received_ts = 346.656891 last_seq = 29275 current_ts = 346.741211 current_received_ts = 346.741089 current_seq = 29285 count = 32 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 346.657104/29275, current 346.741211/29285: elapsed=84114.0us expected=84327.5us +- 421.6us, error 0.3% (kms_flip:2677) DEBUG: name = vblank last_ts = 346.741211 last_received_ts = 346.741089 last_seq = 29285 current_ts = 346.825317 current_received_ts = 346.825226 current_seq = 29295 count = 33 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 346.741211/29285, current 346.825317/29295: elapsed=84095.0us expected=84327.5us +- 421.6us, error 0.3% (kms_flip:2677) DEBUG: name = vblank last_ts = 346.825317 last_received_ts = 346.825226 last_seq = 29295 current_ts = 346.909332 current_received_ts = 346.909241 current_seq = 29305 count = 34 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 346.825317/29295, current 346.909332/29305: elapsed=84023.0us expected=84327.5us +- 421.6us, error 0.4% (kms_flip:2677) DEBUG: name = vblank last_ts = 346.909332 last_received_ts = 346.909241 last_seq = 29305 current_ts = 346.993347 current_received_ts = 346.993225 current_seq = 29315 count = 35 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 346.909332/29305, current 346.993347/29315: elapsed=84015.0us expected=84327.5us +- 421.6us, error 0.4% (kms_flip:2677) DEBUG: name = vblank last_ts = 346.993347 last_received_ts = 346.993225 last_seq = 29315 current_ts = 347.077362 current_received_ts = 347.077240 current_seq = 29325 count = 36 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 346.993347/29315, current 347.077362/29325: elapsed=84022.0us expected=84327.5us +- 421.6us, error 0.4% (kms_flip:2677) DEBUG: name = vblank last_ts = 347.077362 last_received_ts = 347.077240 last_seq = 29325 current_ts = 347.161377 current_received_ts = 347.161285 current_seq = 29335 count = 37 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 347.077362/29325, current 347.161377/29335: elapsed=84015.0us expected=84327.5us +- 421.6us, error 0.4% (kms_flip:2677) DEBUG: name = vblank last_ts = 347.161377 last_received_ts = 347.161285 last_seq = 29335 current_ts = 347.245392 current_received_ts = 347.245239 current_seq = 29345 count = 38 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 347.161377/29335, current 347.245392/29345: elapsed=84018.0us expected=84327.5us +- 421.6us, error 0.4% (kms_flip:2677) DEBUG: name = vblank last_ts = 347.245392 last_received_ts = 347.245239 last_seq = 29345 current_ts = 347.329437 current_received_ts = 347.329346 current_seq = 29355 count = 39 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 347.245392/29345, current 347.329437/29355: elapsed=84019.0us expected=84327.5us +- 421.6us, error 0.4% (kms_flip:2677) DEBUG: name = vblank last_ts = 347.329437 last_received_ts = 347.329346 last_seq = 29355 current_ts = 347.413452 current_received_ts = 347.413361 current_seq = 29365 count = 40 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 347.329437/29355, current 347.413452/29365: elapsed=84017.0us expected=84327.5us +- 421.6us, error 0.4% (kms_flip:2677) DEBUG: name = vblank last_ts = 347.413452 last_received_ts = 347.413361 last_seq = 29365 current_ts = 347.497467 current_received_ts = 347.497375 current_seq = 29375 count = 41 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 347.413452/29365, current 347.497467/29375: elapsed=84024.0us expected=84327.5us +- 421.6us, error 0.4% (kms_flip:2677) DEBUG: name = vblank last_ts = 347.497467 last_received_ts = 347.497375 last_seq = 29375 current_ts = 347.581482 current_received_ts = 347.581390 current_seq = 29385 count = 42 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 347.497467/29375, current 347.581482/29385: elapsed=84016.0us expected=84327.5us +- 421.6us, error 0.4% (kms_flip:2677) DEBUG: name = vblank last_ts = 347.581482 last_received_ts = 347.581390 last_seq = 29385 current_ts = 347.665497 current_received_ts = 347.665436 current_seq = 29395 count = 43 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 347.581482/29385, current 347.665497/29395: elapsed=84020.0us expected=84327.5us +- 421.6us, error 0.4% (kms_flip:2677) DEBUG: name = vblank last_ts = 347.665497 last_received_ts = 347.665436 last_seq = 29395 current_ts = 347.749512 current_received_ts = 347.749420 current_seq = 29405 count = 44 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 347.665497/29395, current 347.749512/29405: elapsed=84017.0us expected=84327.5us +- 421.6us, error 0.4% (kms_flip:2677) DEBUG: name = vblank last_ts = 347.749512 last_received_ts = 347.749420 last_seq = 29405 current_ts = 347.833527 current_received_ts = 347.833405 current_seq = 29415 count = 45 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 347.749512/29405, current 347.833527/29415: elapsed=84004.0us expected=84327.5us +- 421.6us, error 0.4% (kms_flip:2677) DEBUG: name = vblank last_ts = 347.833527 last_received_ts = 347.833405 last_seq = 29415 current_ts = 347.917450 current_received_ts = 347.917328 current_seq = 29425 count = 46 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 347.833527/29415, current 347.917450/29425: elapsed=83938.0us expected=84327.5us +- 421.6us, error 0.5% (kms_flip:2677) DEBUG: name = vblank last_ts = 347.917450 last_received_ts = 347.917328 last_seq = 29425 current_ts = 348.001404 current_received_ts = 348.001312 current_seq = 29435 count = 47 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 347.917450/29425, current 348.001404/29435: elapsed=83939.0us expected=84327.5us +- 421.6us, error 0.5% (kms_flip:2677) DEBUG: name = vblank last_ts = 348.001404 last_received_ts = 348.001312 last_seq = 29435 current_ts = 348.085327 current_received_ts = 348.085236 current_seq = 29445 count = 48 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 348.001404/29435, current 348.085327/29445: elapsed=83941.0us expected=84327.5us +- 421.6us, error 0.5% (kms_flip:2677) DEBUG: name = vblank last_ts = 348.085327 last_received_ts = 348.085236 last_seq = 29445 current_ts = 348.169281 current_received_ts = 348.169189 current_seq = 29455 count = 49 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 348.085327/29445, current 348.169281/29455: elapsed=83942.0us expected=84327.5us +- 421.6us, error 0.5% (kms_flip:2677) DEBUG: name = vblank last_ts = 348.169281 last_received_ts = 348.169189 last_seq = 29455 current_ts = 348.253235 current_received_ts = 348.253143 current_seq = 29465 count = 50 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 348.169281/29455, current 348.253235/29465: elapsed=83937.0us expected=84327.5us +- 421.6us, error 0.5% (kms_flip:2677) DEBUG: name = vblank last_ts = 348.253235 last_received_ts = 348.253143 last_seq = 29465 current_ts = 348.337158 current_received_ts = 348.337097 current_seq = 29475 count = 51 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 348.253235/29465, current 348.337158/29475: elapsed=83940.0us expected=84327.5us +- 421.6us, error 0.5% (kms_flip:2677) DEBUG: name = vblank last_ts = 348.337158 last_received_ts = 348.337097 last_seq = 29475 current_ts = 348.421112 current_received_ts = 348.421021 current_seq = 29485 count = 52 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 348.337158/29475, current 348.421112/29485: elapsed=83937.0us expected=84327.5us +- 421.6us, error 0.5% (kms_flip:2677) DEBUG: name = vblank last_ts = 348.421112 last_received_ts = 348.421021 last_seq = 29485 current_ts = 348.505035 current_received_ts = 348.504944 current_seq = 29495 count = 53 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 348.421112/29485, current 348.505035/29495: elapsed=83944.0us expected=84327.5us +- 421.6us, error 0.5% (kms_flip:2677) DEBUG: name = vblank last_ts = 348.505035 last_received_ts = 348.504944 last_seq = 29495 current_ts = 348.588989 current_received_ts = 348.588776 current_seq = 29505 count = 54 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 348.505035/29495, current 348.588989/29505: elapsed=83937.0us expected=84327.5us +- 421.6us, error 0.5% (kms_flip:2677) DEBUG: name = vblank last_ts = 348.588989 last_received_ts = 348.588776 last_seq = 29505 current_ts = 348.672913 current_received_ts = 348.672791 current_seq = 29515 count = 55 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 348.588989/29505, current 348.672913/29515: elapsed=83939.0us expected=84327.5us +- 421.6us, error 0.5% (kms_flip:2677) DEBUG: name = vblank last_ts = 348.672913 last_received_ts = 348.672791 last_seq = 29515 current_ts = 348.756866 current_received_ts = 348.756775 current_seq = 29525 count = 56 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 348.672913/29515, current 348.756866/29525: elapsed=83942.0us expected=84327.5us +- 421.6us, error 0.5% (kms_flip:2677) DEBUG: name = vblank last_ts = 348.756866 last_received_ts = 348.756775 last_seq = 29525 current_ts = 348.840790 current_received_ts = 348.840576 current_seq = 29535 count = 57 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 348.756866/29525, current 348.840790/29535: elapsed=83925.0us expected=84327.5us +- 421.6us, error 0.5% (kms_flip:2677) DEBUG: name = vblank last_ts = 348.840790 last_received_ts = 348.840576 last_seq = 29535 current_ts = 348.924652 current_received_ts = 348.924530 current_seq = 29545 count = 58 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 348.840790/29535, current 348.924652/29545: elapsed=83870.0us expected=84327.5us +- 421.6us, error 0.5% (kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 348.840790/29535, current 348.924652/29545: elapsed=83870.0us expected=84327.5us (kms_flip:2677) DEBUG: name = vblank last_ts = 348.924652 last_received_ts = 348.924530 last_seq = 29545 current_ts = 349.008514 current_received_ts = 349.008209 current_seq = 29555 count = 59 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 348.924652/29545, current 349.008514/29555: elapsed=83867.0us expected=84327.5us +- 421.6us, error 0.5% (kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 348.924652/29545, current 349.008514/29555: elapsed=83867.0us expected=84327.5us (kms_flip:2677) DEBUG: name = vblank last_ts = 349.008514 last_received_ts = 349.008209 last_seq = 29555 current_ts = 349.092407 current_received_ts = 349.092285 current_seq = 29565 count = 60 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 349.008514/29555, current 349.092407/29565: elapsed=83876.0us expected=84327.5us +- 421.6us, error 0.5% (kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 349.008514/29555, current 349.092407/29565: elapsed=83876.0us expected=84327.5us (kms_flip:2677) DEBUG: name = vblank last_ts = 349.092407 last_received_ts = 349.092285 last_seq = 29565 current_ts = 349.176270 current_received_ts = 349.176147 current_seq = 29575 count = 61 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 349.092407/29565, current 349.176270/29575: elapsed=83865.0us expected=84327.5us +- 421.6us, error 0.5% (kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 349.092407/29565, current 349.176270/29575: elapsed=83865.0us expected=84327.5us (kms_flip:2677) DEBUG: name = vblank last_ts = 349.176270 last_received_ts = 349.176147 last_seq = 29575 current_ts = 349.260132 current_received_ts = 349.260071 current_seq = 29585 count = 62 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 349.176270/29575, current 349.260132/29585: elapsed=83871.0us expected=84327.5us +- 421.6us, error 0.5% (kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 349.176270/29575, current 349.260132/29585: elapsed=83871.0us expected=84327.5us (kms_flip:2677) DEBUG: name = vblank last_ts = 349.260132 last_received_ts = 349.260071 last_seq = 29585 current_ts = 349.343994 current_received_ts = 349.343842 current_seq = 29595 count = 63 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 349.260132/29585, current 349.343994/29595: elapsed=83874.0us expected=84327.5us +- 421.6us, error 0.5% (kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 349.260132/29585, current 349.343994/29595: elapsed=83874.0us expected=84327.5us (kms_flip:2677) DEBUG: name = vblank last_ts = 349.343994 last_received_ts = 349.343842 last_seq = 29595 current_ts = 349.427887 current_received_ts = 349.427795 current_seq = 29605 count = 64 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 349.343994/29595, current 349.427887/29605: elapsed=83871.0us expected=84327.5us +- 421.6us, error 0.5% (kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 349.343994/29595, current 349.427887/29605: elapsed=83871.0us expected=84327.5us (kms_flip:2677) DEBUG: name = vblank last_ts = 349.427887 last_received_ts = 349.427795 last_seq = 29605 current_ts = 349.511749 current_received_ts = 349.511658 current_seq = 29615 count = 65 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 349.427887/29605, current 349.511749/29615: elapsed=83871.0us expected=84327.5us +- 421.6us, error 0.5% (kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 349.427887/29605, current 349.511749/29615: elapsed=83871.0us expected=84327.5us (kms_flip:2677) DEBUG: name = vblank last_ts = 349.511749 last_received_ts = 349.511658 last_seq = 29615 current_ts = 349.595612 current_received_ts = 349.595367 current_seq = 29625 count = 66 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 349.511749/29615, current 349.595612/29625: elapsed=83872.0us expected=84327.5us +- 421.6us, error 0.5% (kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 349.511749/29615, current 349.595612/29625: elapsed=83872.0us expected=84327.5us (kms_flip:2677) DEBUG: name = vblank last_ts = 349.595612 last_received_ts = 349.595367 last_seq = 29625 current_ts = 349.679504 current_received_ts = 349.679382 current_seq = 29635 count = 67 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 349.595612/29625, current 349.679504/29635: elapsed=83869.0us expected=84327.5us +- 421.6us, error 0.5% (kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 349.595612/29625, current 349.679504/29635: elapsed=83869.0us expected=84327.5us (kms_flip:2677) DEBUG: name = vblank last_ts = 349.679504 last_received_ts = 349.679382 last_seq = 29635 current_ts = 349.763367 current_received_ts = 349.763275 current_seq = 29645 count = 68 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 349.679504/29635, current 349.763367/29645: elapsed=83870.0us expected=84327.5us +- 421.6us, error 0.5% (kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 349.679504/29635, current 349.763367/29645: elapsed=83870.0us expected=84327.5us (kms_flip:2677) DEBUG: name = vblank last_ts = 349.763367 last_received_ts = 349.763275 last_seq = 29645 current_ts = 349.847229 current_received_ts = 349.846863 current_seq = 29655 count = 69 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 349.763367/29645, current 349.847229/29655: elapsed=83853.0us expected=84327.5us +- 421.6us, error 0.6% (kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 349.763367/29645, current 349.847229/29655: elapsed=83853.0us expected=84327.5us (kms_flip:2677) DEBUG: name = vblank last_ts = 349.847229 last_received_ts = 349.846863 last_seq = 29655 current_ts = 349.931030 current_received_ts = 349.930939 current_seq = 29665 count = 70 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 349.847229/29655, current 349.931030/29665: elapsed=83809.0us expected=84327.5us +- 421.6us, error 0.6% (kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 349.847229/29655, current 349.931030/29665: elapsed=83809.0us expected=84327.5us (kms_flip:2677) DEBUG: name = vblank last_ts = 349.931030 last_received_ts = 349.930939 last_seq = 29665 current_ts = 350.014832 current_received_ts = 350.014740 current_seq = 29675 count = 71 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 349.931030/29665, current 350.014832/29675: elapsed=83814.0us expected=84327.5us +- 421.6us, error 0.6% (kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 349.931030/29665, current 350.014832/29675: elapsed=83814.0us expected=84327.5us (kms_flip:2677) DEBUG: name = vblank last_ts = 350.014832 last_received_ts = 350.014740 last_seq = 29675 current_ts = 350.098633 current_received_ts = 350.098541 current_seq = 29685 count = 72 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 350.014832/29675, current 350.098633/29685: elapsed=83809.0us expected=84327.5us +- 421.6us, error 0.6% (kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 350.014832/29675, current 350.098633/29685: elapsed=83809.0us expected=84327.5us (kms_flip:2677) DEBUG: name = vblank last_ts = 350.098633 last_received_ts = 350.098541 last_seq = 29685 current_ts = 350.182465 current_received_ts = 350.182373 current_seq = 29695 count = 73 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 350.098633/29685, current 350.182465/29695: elapsed=83807.0us expected=84327.5us +- 421.6us, error 0.6% (kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 350.098633/29685, current 350.182465/29695: elapsed=83807.0us expected=84327.5us (kms_flip:2677) DEBUG: name = vblank last_ts = 350.182465 last_received_ts = 350.182373 last_seq = 29695 current_ts = 350.266266 current_received_ts = 350.266174 current_seq = 29705 count = 74 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 350.182465/29695, current 350.266266/29705: elapsed=83814.0us expected=84327.5us +- 421.6us, error 0.6% (kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 350.182465/29695, current 350.266266/29705: elapsed=83814.0us expected=84327.5us (kms_flip:2677) DEBUG: name = vblank last_ts = 350.266266 last_received_ts = 350.266174 last_seq = 29705 current_ts = 350.350067 current_received_ts = 350.349945 current_seq = 29715 count = 75 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 350.266266/29705, current 350.350067/29715: elapsed=83810.0us expected=84327.5us +- 421.6us, error 0.6% (kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 350.266266/29705, current 350.350067/29715: elapsed=83810.0us expected=84327.5us (kms_flip:2677) DEBUG: name = vblank last_ts = 350.350067 last_received_ts = 350.349945 last_seq = 29715 current_ts = 350.433899 current_received_ts = 350.433777 current_seq = 29725 count = 76 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 350.350067/29715, current 350.433899/29725: elapsed=83808.0us expected=84327.5us +- 421.6us, error 0.6% (kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 350.350067/29715, current 350.433899/29725: elapsed=83808.0us expected=84327.5us (kms_flip:2677) DEBUG: name = vblank last_ts = 350.433899 last_received_ts = 350.433777 last_seq = 29725 current_ts = 350.517700 current_received_ts = 350.517609 current_seq = 29735 count = 77 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 350.433899/29725, current 350.517700/29735: elapsed=83809.0us expected=84327.5us +- 421.6us, error 0.6% (kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 350.433899/29725, current 350.517700/29735: elapsed=83809.0us expected=84327.5us (kms_flip:2677) DEBUG: name = vblank last_ts = 350.517700 last_received_ts = 350.517609 last_seq = 29735 current_ts = 350.601501 current_received_ts = 350.601410 current_seq = 29745 count = 78 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 350.517700/29735, current 350.601501/29745: elapsed=83813.0us expected=84327.5us +- 421.6us, error 0.6% (kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 350.517700/29735, current 350.601501/29745: elapsed=83813.0us expected=84327.5us (kms_flip:2677) DEBUG: name = vblank last_ts = 350.601501 last_received_ts = 350.601410 last_seq = 29745 current_ts = 350.685303 current_received_ts = 350.685120 current_seq = 29755 count = 79 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 350.601501/29745, current 350.685303/29755: elapsed=83804.0us expected=84327.5us +- 421.6us, error 0.6% (kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 350.601501/29745, current 350.685303/29755: elapsed=83804.0us expected=84327.5us (kms_flip:2677) DEBUG: name = vblank last_ts = 350.685303 last_received_ts = 350.685120 last_seq = 29755 current_ts = 350.769135 current_received_ts = 350.769043 current_seq = 29765 count = 80 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 350.685303/29755, current 350.769135/29765: elapsed=83814.0us expected=84327.5us +- 421.6us, error 0.6% (kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 350.685303/29755, current 350.769135/29765: elapsed=83814.0us expected=84327.5us (kms_flip:2677) DEBUG: name = vblank last_ts = 350.769135 last_received_ts = 350.769043 last_seq = 29765 current_ts = 350.852905 current_received_ts = 350.852814 current_seq = 29775 count = 81 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 350.769135/29765, current 350.852905/29775: elapsed=83787.0us expected=84327.5us +- 421.6us, error 0.6% (kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 350.769135/29765, current 350.852905/29775: elapsed=83787.0us expected=84327.5us (kms_flip:2677) DEBUG: name = vblank last_ts = 350.852905 last_received_ts = 350.852814 last_seq = 29775 current_ts = 350.936676 current_received_ts = 350.936554 current_seq = 29785 count = 82 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 350.852905/29775, current 350.936676/29785: elapsed=83761.0us expected=84327.5us +- 421.6us, error 0.7% (kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 350.852905/29775, current 350.936676/29785: elapsed=83761.0us expected=84327.5us (kms_flip:2677) DEBUG: name = vblank last_ts = 350.936676 last_received_ts = 350.936554 last_seq = 29785 current_ts = 351.020416 current_received_ts = 351.020294 current_seq = 29795 count = 83 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 350.936676/29785, current 351.020416/29795: elapsed=83752.0us expected=84327.5us +- 421.6us, error 0.7% (kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 350.936676/29785, current 351.020416/29795: elapsed=83752.0us expected=84327.5us (kms_flip:2677) DEBUG: name = vblank last_ts = 351.020416 last_received_ts = 351.020294 last_seq = 29795 current_ts = 351.104187 current_received_ts = 351.104095 current_seq = 29805 count = 84 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 351.020416/29795, current 351.104187/29805: elapsed=83758.0us expected=84327.5us +- 421.6us, error 0.7% (kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 351.020416/29795, current 351.104187/29805: elapsed=83758.0us expected=84327.5us (kms_flip:2677) DEBUG: name = vblank last_ts = 351.104187 last_received_ts = 351.104095 last_seq = 29805 current_ts = 351.187958 current_received_ts = 351.187805 current_seq = 29815 count = 85 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 351.104187/29805, current 351.187958/29815: elapsed=83762.0us expected=84327.5us +- 421.6us, error 0.7% (kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 351.104187/29805, current 351.187958/29815: elapsed=83762.0us expected=84327.5us (kms_flip:2677) DEBUG: name = vblank last_ts = 351.187958 last_received_ts = 351.187805 last_seq = 29815 current_ts = 351.271698 current_received_ts = 351.271576 current_seq = 29825 count = 86 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 351.187958/29815, current 351.271698/29825: elapsed=83752.0us expected=84327.5us +- 421.6us, error 0.7% (kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 351.187958/29815, current 351.271698/29825: elapsed=83752.0us expected=84327.5us (kms_flip:2677) DEBUG: name = vblank last_ts = 351.271698 last_received_ts = 351.271576 last_seq = 29825 current_ts = 351.355469 current_received_ts = 351.355347 current_seq = 29835 count = 87 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 351.271698/29825, current 351.355469/29835: elapsed=83763.0us expected=84327.5us +- 421.6us, error 0.7% (kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 351.271698/29825, current 351.355469/29835: elapsed=83763.0us expected=84327.5us (kms_flip:2677) DEBUG: name = vblank last_ts = 351.355469 last_received_ts = 351.355347 last_seq = 29835 current_ts = 351.439209 current_received_ts = 351.439087 current_seq = 29845 count = 88 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 351.355469/29835, current 351.439209/29845: elapsed=83755.0us expected=84327.5us +- 421.6us, error 0.7% (kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 351.355469/29835, current 351.439209/29845: elapsed=83755.0us expected=84327.5us (kms_flip:2677) DEBUG: name = vblank last_ts = 351.439209 last_received_ts = 351.439087 last_seq = 29845 current_ts = 351.522980 current_received_ts = 351.522858 current_seq = 29855 count = 89 seq_step = 10 (kms_flip:2677) DEBUG: vblank ts/seq: last 351.439209/29845, current 351.522980/29855: elapsed=83755.0us expected=84327.5us +- 421.6us, error 0.7% (kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 351.439209/29845, current 351.522980/29855: elapsed=83755.0us expected=84327.5us (kms_flip:2677) INFO: Event vblank: expected 896, counted 900, passrate = 64.44%, encoder type 2 (kms_flip:2677) DEBUG: dropped frames, expected 896, counted 900, passrate = 64.44%, encoder type 2 (kms_flip:2677) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1707: (kms_flip:2677) CRITICAL: Failed assertion: !retried (kms_flip:2677) igt_core-INFO: Stack trace: (kms_flip:2677) igt_core-INFO: #0 ../lib/igt_core.c:2075 __igt_fail_assert() (kms_flip:2677) igt_core-INFO: #1 ../tests/kms_flip.c:428 run_test_on_crtc_set.constprop.0() (kms_flip:2677) igt_core-INFO: #2 ../tests/kms_flip.c:1887 run_test() (kms_flip:2677) igt_core-INFO: #3 ../tests/kms_flip.c:2122 __igt_unique____real_main2043() (kms_flip:2677) igt_core-INFO: #4 ../tests/kms_flip.c:2043 main() (kms_flip:2677) igt_core-INFO: #5 [__libc_init_first+0x8a] (kms_flip:2677) igt_core-INFO: #6 [__libc_start_main+0x8b] (kms_flip:2677) igt_core-INFO: #7 [_start+0x25] **** END **** Dynamic subtest A-HDMI-A1: FAIL (18.544s) |
| Dmesg |
<6> [347.553992] Console: switching to colour dummy device 80x25
<6> [347.554459] [IGT] kms_flip: executing
<7> [347.559446] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [347.560177] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [347.560511] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [347.569991] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:597:DP-1]
<7> [347.570000] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:597:DP-1]
<7> [347.570181] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling TC_cold_off
<7> [347.570346] i915 0000:00:02.0: [drm:tgl_tc_cold_request [i915]] TC cold block succeeded
<7> [347.570612] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling TC_cold_off
<7> [347.570786] i915 0000:00:02.0: [drm:tgl_tc_cold_request [i915]] TC cold unblock succeeded
<7> [347.570968] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling TC_cold_off
<7> [347.571134] i915 0000:00:02.0: [drm:tgl_tc_cold_request [i915]] TC cold block succeeded
<7> [347.571465] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port D/TC#1: TC port mode reset (disconnected -> legacy) pin assignment: - max lanes: 4
<7> [347.575788] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:597:DP-1] disconnected
<7> [347.576188] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:607:DP-2]
<7> [347.576192] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:607:DP-2]
<7> [347.576519] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port E/TC#2: TC port mode reset (disconnected -> tbt-alt) pin assignment: - max lanes: 4
<7> [347.576776] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:607:DP-2] disconnected
<7> [347.577207] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:616:DP-3]
<7> [347.577212] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:616:DP-3]
<7> [347.577543] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port F/TC#3: TC port mode reset (disconnected -> tbt-alt) pin assignment: - max lanes: 4
<7> [347.577807] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:616:DP-3] disconnected
<7> [347.578224] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:625:DP-4]
<7> [347.578228] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:625:DP-4]
<7> [347.578571] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port G/TC#4: TC port mode reset (disconnected -> tbt-alt) pin assignment: - max lanes: 4
<7> [347.578835] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:625:DP-4] disconnected
<7> [347.579460] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:587:HDMI-A-1]
<7> [347.579471] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:587:HDMI-A-1]
<7> [347.606279] 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> [347.606290] 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> [347.606294] 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> [347.606297] 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> [347.606300] 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> [347.606303] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:587:HDMI-A-1] ELD monitor fitHeadlessGS
<7> [347.606306] 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> [347.606309] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:587:HDMI-A-1] ELD size 64, SAD count 10
<7> [347.606721] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0040 w(1)
<7> [347.606905] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [347.607461] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0040 w(1)
<7> [347.607645] i915 0000:00:02.0: [drm:drm_dp_dual_mode_detect [drm_display_helper]] DP dual mode HDMI ID: (err -6)
<7> [347.608459] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:587:HDMI-A-1] probed modes:
<7> [347.608465] 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> [347.608468] 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> [347.608472] 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> [347.608475] 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> [347.608478] 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> [347.608481] 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> [347.608484] 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> [347.608487] 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> [347.608490] 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> [347.608493] 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> [347.608496] 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> [347.608499] 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> [347.608502] 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> [347.608505] 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> [347.608508] 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> [347.608511] 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> [347.608514] 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> [347.608517] 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> [347.608520] 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> [347.608523] 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> [347.608525] 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> [347.608528] 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> [347.608532] 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> [347.608535] 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> [347.608538] 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> [347.608541] 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> [347.608544] 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> [347.608547] 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> [347.608550] 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> [347.608553] 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> [347.608555] 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> [347.608558] 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> [347.608561] 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> [347.608564] 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> [347.608567] 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> [347.608570] 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> [347.608573] 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> [347.608576] 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> [347.608579] 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> [347.608582] 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> [347.608585] 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> [347.608588] 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> [347.608591] 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> [347.608594] 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> [347.608597] 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> [347.608600] 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> [347.608603] 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> [347.608606] 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> [347.608609] 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> [347.608612] 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> [347.608615] 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> [347.608618] 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> [347.608621] 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> [347.608624] 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> [347.608626] 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> [347.608629] 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> [347.608632] 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> [347.608635] 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> [347.608638] 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> [347.608641] 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> [347.608644] 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> [347.608647] 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> [347.608650] 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> [347.608653] 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> [347.608656] 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> [347.608659] 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> [347.608662] 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> [347.608664] 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> [347.608667] 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> [347.608671] 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> [347.608673] 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> [347.608676] 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> [347.608679] 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> [347.608726] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:597:DP-1]
<7> [347.608731] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:597:DP-1]
<7> [347.613098] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:597:DP-1] disconnected
<7> [347.613118] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:607:DP-2]
<7> [347.613121] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:607:DP-2]
<7> [347.613457] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:607:DP-2] disconnected
<7> [347.613472] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:616:DP-3]
<7> [347.613476] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:616:DP-3]
<7> [347.613788] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:616:DP-3] disconnected
<7> [347.613801] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:625:DP-4]
<7> [347.613804] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:625:DP-4]
<7> [347.614117] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:625:DP-4] disconnected
<6> [347.615005] [IGT] kms_flip: starting subtest blocking-wf_vblank
<6> [347.618059] [IGT] kms_flip: starting dynamic subtest A-HDMI-A1
<7> [347.618521] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:635]
<7> [347.618629] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:637]
<7> [347.663854] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:170:pipe A]
<7> [347.663981] 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> [347.664231] 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> [347.664445] 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> [347.664651] 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> [347.664851] 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> [347.665077] 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> [347.665313] 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> [347.665551] 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> [347.665768] 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> [347.666003] 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> [347.666209] 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> [347.666410] 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> [347.666609] 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> [347.666855] 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> [347.667085] 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> [347.667297] 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> [347.667490] 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> [347.667647] 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> [347.667810] 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> [347.668022] 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> [347.668182] 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> [347.668348] 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> [347.668514] 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> [347.668671] 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> [347.668837] 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> [347.668996] 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> [347.669188] 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> [347.669353] 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> [347.669517] 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> [347.669673] 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> [347.669857] 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> [347.670013] 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> [347.670170] 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> [347.670363] 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> [347.670520] 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> [347.670684] 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> [347.670866] 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> [347.671023] 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> [347.671179] 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> [347.671336] 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> [347.671529] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in avi infoframe
<7> [347.671694] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [347.671857] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [347.671860] i915 0000:00:02.0: colorspace: RGB
<7> [347.671862] i915 0000:00:02.0: scan mode: Underscan
<7> [347.671863] i915 0000:00:02.0: colorimetry: No Data
<7> [347.671865] i915 0000:00:02.0: picture aspect: No Data
<7> [347.671866] i915 0000:00:02.0: active aspect: Same as Picture
<7> [347.671868] i915 0000:00:02.0: itc: No Data
<7> [347.671869] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [347.671871] i915 0000:00:02.0: quantization range: Full
<7> [347.671872] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [347.671874] i915 0000:00:02.0: video code: 0
<7> [347.671875] i915 0000:00:02.0: ycc quantization range: Full
<7> [347.671876] i915 0000:00:02.0: hdmi content type: Graphics
<7> [347.671878] i915 0000:00:02.0: pixel repeat: 0
<7> [347.671879] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [347.671881] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [347.672046] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in spd infoframe
<7> [347.672207] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [347.672369] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [347.672371] i915 0000:00:02.0: vendor: Intel
<7> [347.672372] i915 0000:00:02.0: product: Integrated gfx
<7> [347.672374] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [347.672376] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [347.672536] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hdmi infoframe
<7> [347.672749] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [347.672907] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [347.672910] i915 0000:00:02.0: empty frame
<7> [347.672911] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [347.673076] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:170:pipe A] fastset requirement not met, forcing full modeset
<7> [347.673245] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:170:pipe A] releasing DPLL 0
<7> [347.673442] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [347.673612] 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> [347.673811] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:34:plane 1A] ddb ( 0 - 1993) -> ( 0 - 0), size 1993 -> 0
<7> [347.673997] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:164:cursor A] ddb (1993 - 2048) -> ( 0 - 0), size 55 -> 0
<7> [347.674152] 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> [347.674318] 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> [347.674483] 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> [347.674662] 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> [347.674839] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:170:pipe A] data rate 0 num active planes 0
<7> [347.675005] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14993 required 0
<7> [347.675225] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:170:pipe A] min cdclk: 192000 kHz -> 0 kHz
<7> [347.675415] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 17844 kHz -> 0 kHz
<7> [347.675621] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [347.675792] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 172800 kHz, actual 172800 kHz
<7> [347.675952] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [347.676112] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:170:pipe A] enable: no [modeset]
<7> [347.676308] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:34:plane 1A] fb: [NOFB], visible: no
<7> [347.676477] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:64:plane 2A] fb: [NOFB], visible: no
<7> [347.676644] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:94:plane 3A] fb: [NOFB], visible: no
<7> [347.676808] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:124:plane 4A] fb: [NOFB], visible: no
<7> [347.676965] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:134:plane 5A] fb: [NOFB], visible: no
<7> [347.677124] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:144:plane 6A] fb: [NOFB], visible: no
<7> [347.677294] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:154:plane 7A] fb: [NOFB], visible: no
<7> [347.677488] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:164:cursor A] fb: [NOFB], visible: no
<7> [347.677735] 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> [347.691707] i915 0000:00:02.0: [drm:intel_audio_component_get_eld [i915]] Not valid for port B
<7> [347.702058] 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> [347.702651] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [347.702859] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [347.704267] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [347.704588] 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> [347.704613] 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> [347.704888] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [347.708873] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_B
<7> [347.709185] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x1, on? 1) for [CRTC:170:pipe A]
<7> [347.709449] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0
<7> [347.709689] i915 0000:00:02.0: [drm:__intel_fbc_disable [i915]] Disabling FBC on [PLANE:34:plane 1A]
<7> [347.709935] 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> [347.710214] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:586:DDI B/PHY B]
<7> [347.710417] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:596:DDI TC1/PHY TC1]
<7> [347.710596] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:598:DP-MST A]
<7> [347.710814] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:599:DP-MST B]
<7> [347.710992] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:600:DP-MST C]
<7> [347.711167] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:601:DP-MST D]
<7> [347.711341] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:606:DDI TC2/PHY TC2]
<7> [347.711514] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:608:DP-MST A]
<7> [347.711687] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:609:DP-MST B]
<7> [347.711919] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DP-MST C]
<7> [347.712096] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:611:DP-MST D]
<7> [347.712271] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:615:DDI TC3/PHY TC3]
<7> [347.712444] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:617:DP-MST A]
<7> [347.712616] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:618:DP-MST B]
<7> [347.712844] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:619:DP-MST C]
<7> [347.713017] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:620:DP-MST D]
<7> [347.713189] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:624:DDI TC4/PHY TC4]
<7> [347.713362] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:626:DP-MST A]
<7> [347.713533] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST B]
<7> [347.713737] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:628:DP-MST C]
<7> [347.713910] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:629:DP-MST D]
<7> [347.714081] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:587:HDMI-A-1]
<7> [347.714460] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [347.714728] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_3
<7> [347.714912] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_2
<7> [347.715097] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:170:pipe A]
<7> [347.715549] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:308:pipe B]
<7> [347.715689] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:446:pipe C]
<7> [347.715791] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:584:pipe D]
<7> [347.715904] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:170:pipe A]
<7> [347.715936] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:587:HDMI-A-1]
<7> [347.716016] 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> [347.716196] i915 0000:00:02.0: [drm:intel_hdmi_compute_clock [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [347.716356] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:170:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [347.716544] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:586:DDI B/PHY B] [CRTC:170:pipe A]
<7> [347.716704] 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> [347.716866] 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> [347.717030] 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> [347.717194] 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> [347.717354] 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> [347.717524] 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> [347.717683] 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> [347.717840] 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> [347.717998] 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> [347.718156] 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> [347.718312] 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> [347.718502] 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> [347.718734] 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> [347.718913] 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> [347.719069] 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> [347.719225] 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> [347.719401] 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> [347.719561] 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> [347.719717] 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> [347.719879] 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> [347.720035] 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> [347.720193] 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> [347.720378] 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> [347.720535] 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> [347.720691] 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> [347.720846] 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> [347.721003] 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> [347.721158] 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> [347.721328] 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> [347.721485] 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> [347.721641] 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> [347.721797] 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> [347.721953] 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> [347.722109] 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> [347.722276] 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> [347.722434] 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> [347.722590] 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> [347.722746] 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> [347.722901] 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> [347.723058] 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> [347.723228] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in avi infoframe
<7> [347.723383] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [347.723540] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [347.723695] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [347.723697] i915 0000:00:02.0: colorspace: RGB
<7> [347.723699] i915 0000:00:02.0: scan mode: Underscan
<7> [347.723700] i915 0000:00:02.0: colorimetry: No Data
<7> [347.723702] i915 0000:00:02.0: picture aspect: No Data
<7> [347.723703] i915 0000:00:02.0: active aspect: Same as Picture
<7> [347.723705] i915 0000:00:02.0: itc: No Data
<7> [347.723706] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [347.723708] i915 0000:00:02.0: quantization range: Full
<7> [347.723709] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [347.723711] i915 0000:00:02.0: video code: 0
<7> [347.723712] i915 0000:00:02.0: ycc quantization range: Full
<7> [347.723713] i915 0000:00:02.0: hdmi content type: Graphics
<7> [347.723715] i915 0000:00:02.0: pixel repeat: 0
<7> [347.723716] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [347.723718] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in spd infoframe
<7> [347.723875] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [347.724030] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [347.724206] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [347.724208] i915 0000:00:02.0: vendor: Intel
<7> [347.724209] i915 0000:00:02.0: product: Integrated gfx
<7> [347.724211] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [347.724213] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hdmi infoframe
<7> [347.724368] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [347.724524] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [347.724686] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [347.724688] i915 0000:00:02.0: empty frame
<7> [347.724690] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:170:pipe A] fastset requirement not met, forcing full modeset
<7> [347.724894] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [347.725074] 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> [347.725305] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:34:plane 1A] ddb ( 0 - 0) -> ( 0 - 1993), size 0 -> 1993
<7> [347.725459] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:164:cursor A] ddb ( 0 - 0) -> (1993 - 2048), size 0 -> 55
<7> [347.725612] 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> [347.725765] 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> [347.725917] 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> [347.726085] 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> [347.726266] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:170:pipe A] data rate 1142000 num active planes 1
<7> [347.726430] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14993 required 1142
<7> [347.726589] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [347.726768] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:170:pipe A] min cdclk: 0 kHz -> 192000 kHz
<7> [347.726958] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 0 kHz -> 17844 kHz
<7> [347.727147] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [347.727305] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 192000 kHz, actual 192000 kHz
<7> [347.727463] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [347.727622] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:170:pipe A] allocated DPLL 0
<7> [347.727788] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:170:pipe A] reserving DPLL 0
<7> [347.727976] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:170:pipe A] enable: yes [modeset]
<7> [347.728139] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [347.728300] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [347.728461] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [347.728620] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [347.728778] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [347.728954] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [347.729112] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [347.729269] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] GCP: 0x0
<7> [347.729426] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [347.729428] i915 0000:00:02.0: colorspace: RGB
<7> [347.729429] i915 0000:00:02.0: scan mode: Underscan
<7> [347.729431] i915 0000:00:02.0: colorimetry: No Data
<7> [347.729432] i915 0000:00:02.0: picture aspect: No Data
<7> [347.729434] i915 0000:00:02.0: active aspect: Same as Picture
<7> [347.729435] i915 0000:00:02.0: itc: No Data
<7> [347.729436] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [347.729438] i915 0000:00:02.0: quantization range: Full
<7> [347.729439] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [347.729441] i915 0000:00:02.0: video code: 0
<7> [347.729442] i915 0000:00:02.0: ycc quantization range: Full
<7> [347.729444] i915 0000:00:02.0: hdmi content type: Graphics
<7> [347.729445] i915 0000:00:02.0: pixel repeat: 0
<7> [347.729447] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [347.729448] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [347.729450] i915 0000:00:02.0: vendor: Intel
<7> [347.729452] i915 0000:00:02.0: product: Integrated gfx
<7> [347.729453] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [347.729455] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [347.729457] i915 0000:00:02.0: empty frame
<7> [347.729458] 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> [347.729616] 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> [347.729772] 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> [347.729945] 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> [347.730102] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [347.730258] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [347.730417] 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> [347.730577] 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> [347.730732] 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> [347.730907] 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> [347.731064] 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> [347.731222] 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> [347.731379] 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> [347.731536] 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> [347.731697] 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> [347.731861] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 59, ips linetime: 0
<7> [347.732017] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [347.732173] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [347.732329] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [347.732485] 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> [347.732643] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [347.732815] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [347.732971] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [347.733129] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [347.733284] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [347.733440] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [347.733597] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [347.733769] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [347.733933] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [347.734092] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [347.734252] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [347.734411] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [347.734575] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [347.734734] 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> [347.734893] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [347.735050] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [347.735209] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:64:plane 2A] fb: [NOFB], visible: no
<7> [347.735366] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:94:plane 3A] fb: [NOFB], visible: no
<7> [347.735533] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:124:plane 4A] fb: [NOFB], visible: no
<7> [347.735692] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:134:plane 5A] fb: [NOFB], visible: no
<7> [347.735848] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:144:plane 6A] fb: [NOFB], visible: no
<7> [347.736003] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:154:plane 7A] fb: [NOFB], visible: no
<7> [347.736158] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:164:cursor A] fb: [NOFB], visible: no
<7> [347.736555] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_2
<7> [347.736751] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_3
<7> [347.736936] i915 0000:00:02.0: [drm:intel_audio_component_get_power [i915]] restored AUD_FREQ_CNTRL to 0x810
<7> [347.736969] 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> [347.737231] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:586:DDI B/PHY B]
<7> [347.737502] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:596:DDI TC1/PHY TC1]
<7> [347.737722] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:598:DP-MST A]
<7> [347.737923] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:599:DP-MST B]
<7> [347.738082] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:600:DP-MST C]
<7> [347.738250] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:601:DP-MST D]
<7> [347.738450] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:606:DDI TC2/PHY TC2]
<7> [347.738614] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:608:DP-MST A]
<7> [347.738781] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:609:DP-MST B]
<7> [347.738941] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DP-MST C]
<7> [347.739097] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:611:DP-MST D]
<7> [347.739279] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:615:DDI TC3/PHY TC3]
<7> [347.739463] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:617:DP-MST A]
<7> [347.739623] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:618:DP-MST B]
<7> [347.739811] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:619:DP-MST C]
<7> [347.739970] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:620:DP-MST D]
<7> [347.740137] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:624:DDI TC4/PHY TC4]
<7> [347.740370] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:626:DP-MST A]
<7> [347.740553] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST B]
<7> [347.740732] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:628:DP-MST C]
<7> [347.740911] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:629:DP-MST D]
<7> [347.741283] azx_init_pci: snd_hda_intel 0000:00:1f.3: Clearing TCSEL
<7> [347.741326] azx_init_pci: snd_hda_intel 0000:00:1f.3: SCH snoop: Enabled
<7> [347.741262] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [347.741545] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:170:pipe A]
<7> [347.741734] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0
<7> [347.741982] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_B
<7> [347.742262] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [347.742627] 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> [347.743222] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [347.743393] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [347.743939] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [347.744160] 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> [347.744179] 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> [347.747919] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1
<7> [347.748101] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:34:plane 1A]
<7> [347.752834] 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> [347.761208] 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> [347.761399] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] Configuring HDMI audio for pixel clock 25200 (0x00010000)
<7> [347.761560] i915 0000:00:02.0: [drm:hsw_audio_config_update [i915]] using automatic N
<6> [347.761725] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 5 not expected
<6> [347.761727] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 0 not expected
<6> [347.761729] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 10 not expected
<6> [347.761731] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 6 not expected
<7> [347.761870] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:587:HDMI-A-1]
<7> [347.762126] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:170:pipe A]
<7> [347.818798] i915 0000:00:02.0: [drm:intel_audio_component_get_power [i915]] restored AUD_FREQ_CNTRL to 0x810
<7> [347.823224] azx_init_pci: snd_hda_intel 0000:00:1f.3: Clearing TCSEL
<7> [347.823268] azx_init_pci: snd_hda_intel 0000:00:1f.3: SCH snoop: Enabled
<7> [348.618519] 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> [348.618536] 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> [348.618572] 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> [348.618595] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling TC_cold_off
<7> [348.619233] i915 0000:00:02.0: [drm:tgl_tc_cold_request [i915]] TC cold unblock succeeded
<7> [348.620853] 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> [358.230177] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:170:pipe A]
<7> [358.230561] 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> [358.231139] 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> [358.231737] 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> [358.232261] 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> [358.232826] 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> [358.233395] 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> [358.233881] 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> [358.234053] 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> [358.234211] 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> [358.234379] 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> [358.234550] 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> [358.234708] 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> [358.234866] 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> [358.235025] 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> [358.235181] 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> [358.235348] 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> [358.235519] 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> [358.235677] 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> [358.235832] 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> [358.235989] 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> [358.236144] 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> [358.236300] 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> [358.236476] 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> [358.236648] 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> [358.236805] 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> [358.236961] 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> [358.237118] 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> [358.237275] 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> [358.237450] 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> [358.237621] 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> [358.237779] 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> [358.237937] 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> [358.238094] 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> [358.238251] 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> [358.238426] 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> [358.238604] 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> [358.238764] 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> [358.238924] 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> [358.239082] 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> [358.239239] 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> [358.239419] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in avi infoframe
<7> [358.239589] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [358.239746] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [358.239748] i915 0000:00:02.0: colorspace: RGB
<7> [358.239750] i915 0000:00:02.0: scan mode: Underscan
<7> [358.239751] i915 0000:00:02.0: colorimetry: No Data
<7> [358.239753] i915 0000:00:02.0: picture aspect: No Data
<7> [358.239754] i915 0000:00:02.0: active aspect: Same as Picture
<7> [358.239756] i915 0000:00:02.0: itc: No Data
<7> [358.239757] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [358.239758] i915 0000:00:02.0: quantization range: Full
<7> [358.239760] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [358.239761] i915 0000:00:02.0: video code: 0
<7> [358.239763] i915 0000:00:02.0: ycc quantization range: Full
<7> [358.239764] i915 0000:00:02.0: hdmi content type: Graphics
<7> [358.239766] i915 0000:00:02.0: pixel repeat: 0
<7> [358.239767] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [358.239769] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [358.239926] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in spd infoframe
<7> [358.240084] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [358.240241] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [358.240242] i915 0000:00:02.0: vendor: Intel
<7> [358.240244] i915 0000:00:02.0: product: Integrated gfx
<7> [358.240246] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [358.240247] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [358.240425] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hdmi infoframe
<7> [358.240605] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [358.240760] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [358.240762] i915 0000:00:02.0: empty frame
<7> [358.240764] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [358.240921] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:170:pipe A] fastset requirement not met, forcing full modeset
<7> [358.241079] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:170:pipe A] releasing DPLL 0
<7> [358.241285] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [358.241456] 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> [358.241650] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:34:plane 1A] ddb ( 0 - 1993) -> ( 0 - 0), size 1993 -> 0
<7> [358.241807] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:164:cursor A] ddb (1993 - 2048) -> ( 0 - 0), size 55 -> 0
<7> [358.241962] 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> [358.242115] 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> [358.242282] 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> [358.242448] 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> [358.242621] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:170:pipe A] data rate 0 num active planes 0
<7> [358.242783] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14993 required 0
<7> [358.242963] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:170:pipe A] min cdclk: 192000 kHz -> 0 kHz
<7> [358.243145] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 17844 kHz -> 0 kHz
<7> [358.243365] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [358.243526] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 172800 kHz, actual 172800 kHz
<7> [358.243683] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [358.243843] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:170:pipe A] enable: no [modeset]
<7> [358.244006] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:34:plane 1A] fb: [NOFB], visible: no
<7> [358.244165] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:64:plane 2A] fb: [NOFB], visible: no
<7> [358.244343] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:94:plane 3A] fb: [NOFB], visible: no
<7> [358.244513] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:124:plane 4A] fb: [NOFB], visible: no
<7> [358.244671] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:134:plane 5A] fb: [NOFB], visible: no
<7> [358.244829] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:144:plane 6A] fb: [NOFB], visible: no
<7> [358.244988] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:154:plane 7A] fb: [NOFB], visible: no
<7> [358.245145] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:164:cursor A] fb: [NOFB], visible: no
<7> [358.245409] 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> [358.254406] i915 0000:00:02.0: [drm:intel_audio_component_get_eld [i915]] Not valid for port B
<7> [358.264775] 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> [358.265360] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [358.265569] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [358.266169] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [358.266337] 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> [358.266352] 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> [358.266542] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [358.271883] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_B
<7> [358.272103] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x1, on? 1) for [CRTC:170:pipe A]
<7> [358.272313] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0
<7> [358.272507] i915 0000:00:02.0: [drm:__intel_fbc_disable [i915]] Disabling FBC on [PLANE:34:plane 1A]
<7> [358.272697] 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> [358.273010] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:586:DDI B/PHY B]
<7> [358.273318] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:596:DDI TC1/PHY TC1]
<7> [358.273584] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:598:DP-MST A]
<7> [358.273979] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:599:DP-MST B]
<7> [358.274172] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:600:DP-MST C]
<7> [358.274353] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:601:DP-MST D]
<7> [358.274529] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:606:DDI TC2/PHY TC2]
<7> [358.274759] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:608:DP-MST A]
<7> [358.275209] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:609:DP-MST B]
<7> [358.275488] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DP-MST C]
<7> [358.275762] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:611:DP-MST D]
<7> [358.276077] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:615:DDI TC3/PHY TC3]
<7> [358.276268] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:617:DP-MST A]
<7> [358.276457] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:618:DP-MST B]
<7> [358.276666] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:619:DP-MST C]
<7> [358.276969] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:620:DP-MST D]
<7> [358.277201] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:624:DDI TC4/PHY TC4]
<7> [358.277434] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:626:DP-MST A]
<7> [358.277657] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST B]
<7> [358.277838] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:628:DP-MST C]
<7> [358.277996] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:629:DP-MST D]
<7> [358.278153] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:587:HDMI-A-1]
<7> [358.278593] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [358.278953] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_3
<7> [358.279222] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_2
<7> [358.279418] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:170:pipe A]
<7> [358.280114] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:308:pipe B]
<7> [358.280407] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:446:pipe C]
<7> [358.280652] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:584:pipe D]
<7> [358.280881] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:170:pipe A]
<7> [358.280933] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:587:HDMI-A-1]
<7> [358.281043] 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> [358.281234] i915 0000:00:02.0: [drm:intel_hdmi_compute_clock [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [358.281462] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:170:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [358.281703] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:586:DDI B/PHY B] [CRTC:170:pipe A]
<7> [358.281965] 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> [358.282207] 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> [358.282387] 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> [358.282547] 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> [358.282720] 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> [358.282902] 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> [358.283139] 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> [358.283379] 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> [358.283617] 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> [358.283864] 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> [358.284059] 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> [358.284218] 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> [358.284375] 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> [358.284532] 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> [358.284878] 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> [358.285115] 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> [358.285354] 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> [358.285587] 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> [358.285788] 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> [358.285948] 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> [358.286106] 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> [358.286266] 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> [358.286500] 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> [358.286749] 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> [358.286985] 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> [358.287221] 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> [358.287438] 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> [358.287600] 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> [358.287779] 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> [358.287937] 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> [358.288094] 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> [358.288332] 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> [358.288571] 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> [358.288854] 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> [358.289086] 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> [358.289252] 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> [358.289411] 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> [358.289573] 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> [358.289803] 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> [358.290042] 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> [358.290279] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in avi infoframe
<7> [358.290515] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [358.290764] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [358.290943] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [358.290946] i915 0000:00:02.0: colorspace: RGB
<7> [358.290947] i915 0000:00:02.0: scan mode: Underscan
<7> [358.290949] i915 0000:00:02.0: colorimetry: No Data
<7> [358.290950] i915 0000:00:02.0: picture aspect: No Data
<7> [358.290952] i915 0000:00:02.0: active aspect: Same as Picture
<7> [358.290953] i915 0000:00:02.0: itc: No Data
<7> [358.290955] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [358.290956] i915 0000:00:02.0: quantization range: Full
<7> [358.290958] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [358.290959] i915 0000:00:02.0: video code: 0
<7> [358.290961] i915 0000:00:02.0: ycc quantization range: Full
<7> [358.290962] i915 0000:00:02.0: hdmi content type: Graphics
<7> [358.290963] i915 0000:00:02.0: pixel repeat: 0
<7> [358.290965] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [358.290967] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in spd infoframe
<7> [358.291125] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [358.291283] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [358.291441] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [358.291443] i915 0000:00:02.0: vendor: Intel
<7> [358.291445] i915 0000:00:02.0: product: Integrated gfx
<7> [358.291446] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [358.291448] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hdmi infoframe
<7> [358.291677] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [358.291915] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [358.292150] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [358.292153] i915 0000:00:02.0: empty frame
<7> [358.292157] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:170:pipe A] fastset requirement not met, forcing full modeset
<7> [358.292452] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [358.292707] 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> [358.292947] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:34:plane 1A] ddb ( 0 - 0) -> ( 0 - 1993), size 0 -> 1993
<7> [358.293103] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:164:cursor A] ddb ( 0 - 0) -> (1993 - 2048), size 0 -> 55
<7> [358.293257] 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> [358.293415] 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> [358.293662] 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> [358.293898] 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> [358.294161] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:170:pipe A] data rate 1142000 num active planes 1
<7> [358.294396] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14993 required 1142
<7> [358.294596] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [358.294782] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:170:pipe A] min cdclk: 0 kHz -> 192000 kHz
<7> [358.294962] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 0 kHz -> 17844 kHz
<7> [358.295164] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [358.295403] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 192000 kHz, actual 192000 kHz
<7> [358.295659] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [358.295897] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:170:pipe A] allocated DPLL 0
<7> [358.296131] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:170:pipe A] reserving DPLL 0
<7> [358.296301] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:170:pipe A] enable: yes [modeset]
<7> [358.296463] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [358.296641] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [358.296850] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [358.297088] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [358.297328] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [358.297580] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [358.297781] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [358.297943] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] GCP: 0x0
<7> [358.298104] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [358.298106] i915 0000:00:02.0: colorspace: RGB
<7> [358.298107] i915 0000:00:02.0: scan mode: Underscan
<7> [358.298109] i915 0000:00:02.0: colorimetry: No Data
<7> [358.298111] i915 0000:00:02.0: picture aspect: No Data
<7> [358.298112] i915 0000:00:02.0: active aspect: Same as Picture
<7> [358.298114] i915 0000:00:02.0: itc: No Data
<7> [358.298115] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [358.298117] i915 0000:00:02.0: quantization range: Full
<7> [358.298118] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [358.298120] i915 0000:00:02.0: video code: 0
<7> [358.298121] i915 0000:00:02.0: ycc quantization range: Full
<7> [358.298122] i915 0000:00:02.0: hdmi content type: Graphics
<7> [358.298124] i915 0000:00:02.0: pixel repeat: 0
<7> [358.298125] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [358.298127] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [358.298129] i915 0000:00:02.0: vendor: Intel
<7> [358.298130] i915 0000:00:02.0: product: Integrated gfx
<7> [358.298131] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [358.298133] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [358.298135] i915 0000:00:02.0: empty frame
<7> [358.298136] 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> [358.298297] 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> [358.298534] 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> [358.298769] 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> [358.299007] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [358.299242] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [358.299473] 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> [358.299675] 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> [358.299856] 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> [358.300015] 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> [358.300174] 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> [358.300331] 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> [358.300506] 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> [358.300739] 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> [358.300978] 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> [358.301213] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 59, ips linetime: 0
<7> [358.301441] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [358.301669] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [358.301844] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [358.302004] 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> [358.302165] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [358.302350] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [358.302606] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [358.302841] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [358.303075] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [358.303297] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [358.303466] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [358.303625] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [358.303783] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [358.303966] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [358.304203] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [358.304447] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [358.304691] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [358.304925] 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> [358.305100] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [358.305260] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [358.305439] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:64:plane 2A] fb: [NOFB], visible: no
<7> [358.305603] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:94:plane 3A] fb: [NOFB], visible: no
<7> [358.305826] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:124:plane 4A] fb: [NOFB], visible: no
<7> [358.306067] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:134:plane 5A] fb: [NOFB], visible: no
<7> [358.306303] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:144:plane 6A] fb: [NOFB], visible: no
<7> [358.306556] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:154:plane 7A] fb: [NOFB], visible: no
<7> [358.306745] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:164:cursor A] fb: [NOFB], visible: no
<7> [358.307039] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_2
<7> [358.307213] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_3
<7> [358.307470] 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> [358.307802] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:586:DDI B/PHY B]
<7> [358.308041] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:596:DDI TC1/PHY TC1]
<7> [358.308245] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:598:DP-MST A]
<7> [358.308422] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:599:DP-MST B]
<7> [358.308580] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:600:DP-MST C]
<7> [358.308738] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:601:DP-MST D]
<7> [358.308966] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:606:DDI TC2/PHY TC2]
<7> [358.309200] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:608:DP-MST A]
<7> [358.309457] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:609:DP-MST B]
<7> [358.309685] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DP-MST C]
<7> [358.309852] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:611:DP-MST D]
<7> [358.310009] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:615:DDI TC3/PHY TC3]
<7> [358.310173] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:617:DP-MST A]
<7> [358.310352] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:618:DP-MST B]
<7> [358.310587] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:619:DP-MST C]
<7> [358.310821] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:620:DP-MST D]
<7> [358.311057] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:624:DDI TC4/PHY TC4]
<7> [358.311283] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:626:DP-MST A]
<7> [358.311524] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST B]
<7> [358.311695] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:628:DP-MST C]
<7> [358.311850] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:629:DP-MST D]
<7> [358.312134] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [358.312400] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:170:pipe A]
<7> [358.312481] i915 0000:00:02.0: [drm:intel_audio_component_get_power [i915]] restored AUD_FREQ_CNTRL to 0x810
<7> [358.312567] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0
<7> [358.312802] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_B
<7> [358.313045] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [358.313388] 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> [358.313941] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [358.314096] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [358.314656] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [358.314879] 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> [358.314905] 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> [358.317291] azx_init_pci: snd_hda_intel 0000:00:1f.3: Clearing TCSEL
<7> [358.317328] azx_init_pci: snd_hda_intel 0000:00:1f.3: SCH snoop: Enabled
<7> [358.318257] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1
<7> [358.318524] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:34:plane 1A]
<7> [358.323616] 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> [358.331973] 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> [358.332205] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] Configuring HDMI audio for pixel clock 25200 (0x00010000)
<7> [358.332365] i915 0000:00:02.0: [drm:hsw_audio_config_update [i915]] using automatic N
<6> [358.332531] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 5 not expected
<6> [358.332534] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 0 not expected
<6> [358.332535] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 10 not expected
<6> [358.332537] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 6 not expected
<7> [358.332671] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:587:HDMI-A-1]
<7> [358.332926] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:170:pipe A]
<6> [366.163559] [IGT] kms_flip: finished subtest A-HDMI-A1, FAIL
|