Result:
integration-manifest git-log-oneline i915_display_info14 igt_runner14 runtimes14 results14.json results14-i915-load.json guc_logs14.tar i915_display_info_post_exec14 boot14 dmesg14
| Detail | Value |
|---|---|
| Duration | 18.62 seconds |
| Hostname |
shard-tglu-6 |
| Igt-Version |
IGT-Version: 2.4-ge322bfd77 (x86_64) (Linux: 7.1.0-rc6-CI_DRM_18617-g4354c7059304+ x86_64) |
| Out |
Using IGT_SRANDOM=1780533481 for randomisation Opened device: /dev/dri/card0 Using monotonic timestamps Starting subtest: flip-vs-blocking-wf-vblank Starting dynamic subtest: A-HDMI-A1 1920x1080: 120 285500 1920 1968 2000 2080 1080 1083 1088 1144 0x48 0x5 Expected frametime: 8335us; measured 8677.2us +- 3.582us accuracy 0.12% vblank interval differs from modeline! expected 8334.6us, measured 8677us +- 3.582us, difference 342.6us (95.7 sigma) Event flip: expected 867, counted 880, passrate = 22.73%, encoder type 2 Event vblank: expected 867, counted 880, passrate = 19.32%, encoder type 2 Expected frametime: 8335us; measured 8422.2us +- 5.639us accuracy 0.20% vblank interval differs from modeline! expected 8334.6us, measured 8422us +- 5.639us, difference 87.7us (15.5 sigma) Event flip: expected 896, counted 900, passrate = 66.67%, encoder type 2 Event vblank: expected 896, counted 900, passrate = 65.56%, encoder type 2 Stack trace: #0 ../lib/igt_core.c:2106 __igt_fail_assert() #1 ../tests/kms_flip.c:1071 run_test_on_crtc_set() #2 ../tests/kms_flip.c:1920 run_test() #3 ../tests/kms_flip.c:2184 __igt_unique____real_main2105() #4 ../tests/kms_flip.c:2105 main() #5 [__libc_init_first+0x8a] #6 [__libc_start_main+0x8b] #7 [_start+0x25] Dynamic subtest A-HDMI-A1: FAIL (18.623s) |
| Err |
Starting subtest: flip-vs-blocking-wf-vblank Starting dynamic subtest: A-HDMI-A1 [66.085483] (kms_flip:2068) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1722: [66.085501] (kms_flip:2068) CRITICAL: Failed assertion: !retried Dynamic subtest A-HDMI-A1 failed. **** DEBUG **** [62.418209] (kms_flip:2068) DEBUG: flip ts/seq: last 62.660446/2606, current 62.744320/2616: elapsed=83873.0us expected=84222.5us +- 421.1us, error 0.4% [62.418215] (kms_flip:2068) DEBUG: name = vblank last_ts = 62.735935 last_received_ts = 62.735622 last_seq = 2615 current_ts = 62.819805 current_received_ts = 62.819508 current_seq = 2625 count = 45 seq_step = 10 [62.418221] (kms_flip:2068) DEBUG: vblank ts/seq: last 62.735935/2615, current 62.819805/2625: elapsed=83872.0us expected=84222.5us +- 421.1us, error 0.4% [62.501549] (kms_flip:2068) DEBUG: name = flip last_ts = 62.744320 last_received_ts = 62.819534 last_seq = 2616 current_ts = 62.828194 current_received_ts = 62.903416 current_seq = 2626 count = 46 seq_step = 1 [62.501562] (kms_flip:2068) DEBUG: flip ts/seq: last 62.744320/2616, current 62.828194/2626: elapsed=83873.0us expected=84222.5us +- 421.1us, error 0.4% [62.501567] (kms_flip:2068) DEBUG: name = vblank last_ts = 62.819805 last_received_ts = 62.819508 last_seq = 2625 current_ts = 62.903683 current_received_ts = 62.903389 current_seq = 2635 count = 46 seq_step = 10 [62.501572] (kms_flip:2068) DEBUG: vblank ts/seq: last 62.819805/2625, current 62.903683/2635: elapsed=83875.0us expected=84222.5us +- 421.1us, error 0.4% [62.584884] (kms_flip:2068) DEBUG: name = flip last_ts = 62.828194 last_received_ts = 62.903416 last_seq = 2626 current_ts = 62.912064 current_received_ts = 62.987217 current_seq = 2636 count = 47 seq_step = 1 [62.584897] (kms_flip:2068) DEBUG: flip ts/seq: last 62.828194/2626, current 62.912064/2636: elapsed=83871.0us expected=84222.5us +- 421.1us, error 0.4% [62.584903] (kms_flip:2068) DEBUG: name = vblank last_ts = 62.903683 last_received_ts = 62.903389 last_seq = 2635 current_ts = 62.987492 current_received_ts = 62.987190 current_seq = 2645 count = 47 seq_step = 10 [62.584910] (kms_flip:2068) DEBUG: vblank ts/seq: last 62.903683/2635, current 62.987492/2645: elapsed=83811.0us expected=84222.5us +- 421.1us, error 0.5% [62.668639] (kms_flip:2068) DEBUG: name = flip last_ts = 62.912064 last_received_ts = 62.987217 last_seq = 2636 current_ts = 62.995872 current_received_ts = 63.071438 current_seq = 2646 count = 48 seq_step = 1 [62.668652] (kms_flip:2068) DEBUG: flip ts/seq: last 62.912064/2636, current 62.995872/2646: elapsed=83808.0us expected=84222.5us +- 421.1us, error 0.5% [62.668657] (kms_flip:2068) DEBUG: name = vblank last_ts = 62.987492 last_received_ts = 62.987190 last_seq = 2645 current_ts = 63.071301 current_received_ts = 63.070988 current_seq = 2655 count = 48 seq_step = 10 [62.668664] (kms_flip:2068) DEBUG: vblank ts/seq: last 62.987492/2645, current 63.071301/2655: elapsed=83810.0us expected=84222.5us +- 421.1us, error 0.5% [62.751581] (kms_flip:2068) DEBUG: name = flip last_ts = 62.995872 last_received_ts = 63.071438 last_seq = 2646 current_ts = 63.079681 current_received_ts = 63.154839 current_seq = 2656 count = 49 seq_step = 1 [62.751594] (kms_flip:2068) DEBUG: flip ts/seq: last 62.995872/2646, current 63.079681/2656: elapsed=83809.0us expected=84222.5us +- 421.1us, error 0.5% [62.751599] (kms_flip:2068) DEBUG: name = vblank last_ts = 63.071301 last_received_ts = 63.070988 last_seq = 2655 current_ts = 63.155113 current_received_ts = 63.154816 current_seq = 2665 count = 49 seq_step = 10 [62.751605] (kms_flip:2068) DEBUG: vblank ts/seq: last 63.071301/2655, current 63.155113/2665: elapsed=83811.0us expected=84222.5us +- 421.1us, error 0.5% [62.834898] (kms_flip:2068) DEBUG: name = flip last_ts = 63.079681 last_received_ts = 63.154839 last_seq = 2656 current_ts = 63.163490 current_received_ts = 63.238617 current_seq = 2666 count = 50 seq_step = 1 [62.834911] (kms_flip:2068) DEBUG: flip ts/seq: last 63.079681/2656, current 63.163490/2666: elapsed=83810.0us expected=84222.5us +- 421.1us, error 0.5% [62.834918] (kms_flip:2068) DEBUG: name = vblank last_ts = 63.155113 last_received_ts = 63.154816 last_seq = 2665 current_ts = 63.238914 current_received_ts = 63.238594 current_seq = 2675 count = 50 seq_step = 10 [62.834923] (kms_flip:2068) DEBUG: vblank ts/seq: last 63.155113/2665, current 63.238914/2675: elapsed=83802.0us expected=84222.5us +- 421.1us, error 0.5% [62.918271] (kms_flip:2068) DEBUG: name = flip last_ts = 63.163490 last_received_ts = 63.238617 last_seq = 2666 current_ts = 63.247299 current_received_ts = 63.322453 current_seq = 2676 count = 51 seq_step = 1 [62.918283] (kms_flip:2068) DEBUG: flip ts/seq: last 63.163490/2666, current 63.247299/2676: elapsed=83808.0us expected=84222.5us +- 421.1us, error 0.5% [62.918288] (kms_flip:2068) DEBUG: name = vblank last_ts = 63.238914 last_received_ts = 63.238594 last_seq = 2675 current_ts = 63.322727 current_received_ts = 63.322430 current_seq = 2685 count = 51 seq_step = 10 [62.918294] (kms_flip:2068) DEBUG: vblank ts/seq: last 63.238914/2675, current 63.322727/2685: elapsed=83811.0us expected=84222.5us +- 421.1us, error 0.5% [63.001610] (kms_flip:2068) DEBUG: name = flip last_ts = 63.247299 last_received_ts = 63.322453 last_seq = 2676 current_ts = 63.331104 current_received_ts = 63.406254 current_seq = 2686 count = 52 seq_step = 1 [63.001622] (kms_flip:2068) DEBUG: flip ts/seq: last 63.247299/2676, current 63.331104/2686: elapsed=83806.0us expected=84222.5us +- 421.1us, error 0.5% [63.001627] (kms_flip:2068) DEBUG: name = vblank last_ts = 63.322727 last_received_ts = 63.322430 last_seq = 2685 current_ts = 63.406536 current_received_ts = 63.406227 current_seq = 2695 count = 52 seq_step = 10 [63.001633] (kms_flip:2068) DEBUG: vblank ts/seq: last 63.322727/2685, current 63.406536/2695: elapsed=83809.0us expected=84222.5us +- 421.1us, error 0.5% [63.084956] (kms_flip:2068) DEBUG: name = flip last_ts = 63.331104 last_received_ts = 63.406254 last_seq = 2686 current_ts = 63.414913 current_received_ts = 63.490063 current_seq = 2696 count = 53 seq_step = 1 [63.084970] (kms_flip:2068) DEBUG: flip ts/seq: last 63.331104/2686, current 63.414913/2696: elapsed=83810.0us expected=84222.5us +- 421.1us, error 0.5% [63.084976] (kms_flip:2068) DEBUG: name = vblank last_ts = 63.406536 last_received_ts = 63.406227 last_seq = 2695 current_ts = 63.490341 current_received_ts = 63.490036 current_seq = 2705 count = 53 seq_step = 10 [63.084983] (kms_flip:2068) DEBUG: vblank ts/seq: last 63.406536/2695, current 63.490341/2705: elapsed=83808.0us expected=84222.5us +- 421.1us, error 0.5% [63.168307] (kms_flip:2068) DEBUG: name = flip last_ts = 63.414913 last_received_ts = 63.490063 last_seq = 2696 current_ts = 63.498718 current_received_ts = 63.573875 current_seq = 2706 count = 54 seq_step = 1 [63.168320] (kms_flip:2068) DEBUG: flip ts/seq: last 63.414913/2696, current 63.498718/2706: elapsed=83805.0us expected=84222.5us +- 421.1us, error 0.5% [63.168325] (kms_flip:2068) DEBUG: name = vblank last_ts = 63.490341 last_received_ts = 63.490036 last_seq = 2705 current_ts = 63.574146 current_received_ts = 63.573849 current_seq = 2715 count = 54 seq_step = 10 [63.168330] (kms_flip:2068) DEBUG: vblank ts/seq: last 63.490341/2705, current 63.574146/2715: elapsed=83805.0us expected=84222.5us +- 421.1us, error 0.5% [63.251647] (kms_flip:2068) DEBUG: name = flip last_ts = 63.498718 last_received_ts = 63.573875 last_seq = 2706 current_ts = 63.582527 current_received_ts = 63.657677 current_seq = 2716 count = 55 seq_step = 1 [63.251659] (kms_flip:2068) DEBUG: flip ts/seq: last 63.498718/2706, current 63.582527/2716: elapsed=83809.0us expected=84222.5us +- 421.1us, error 0.5% [63.251664] (kms_flip:2068) DEBUG: name = vblank last_ts = 63.574146 last_received_ts = 63.573849 last_seq = 2715 current_ts = 63.657959 current_received_ts = 63.657650 current_seq = 2725 count = 55 seq_step = 10 [63.251669] (kms_flip:2068) DEBUG: vblank ts/seq: last 63.574146/2715, current 63.657959/2725: elapsed=83810.0us expected=84222.5us +- 421.1us, error 0.5% [63.334968] (kms_flip:2068) DEBUG: name = flip last_ts = 63.582527 last_received_ts = 63.657677 last_seq = 2716 current_ts = 63.666340 current_received_ts = 63.741459 current_seq = 2726 count = 56 seq_step = 1 [63.334981] (kms_flip:2068) DEBUG: flip ts/seq: last 63.582527/2716, current 63.666340/2726: elapsed=83809.0us expected=84222.5us +- 421.1us, error 0.5% [63.334986] (kms_flip:2068) DEBUG: name = vblank last_ts = 63.657959 last_received_ts = 63.657650 last_seq = 2725 current_ts = 63.741764 current_received_ts = 63.741432 current_seq = 2735 count = 56 seq_step = 10 [63.334991] (kms_flip:2068) DEBUG: vblank ts/seq: last 63.657959/2725, current 63.741764/2735: elapsed=83806.0us expected=84222.5us +- 421.1us, error 0.5% [63.418340] (kms_flip:2068) DEBUG: name = flip last_ts = 63.666340 last_received_ts = 63.741459 last_seq = 2726 current_ts = 63.750149 current_received_ts = 63.825294 current_seq = 2736 count = 57 seq_step = 1 [63.418352] (kms_flip:2068) DEBUG: flip ts/seq: last 63.666340/2726, current 63.750149/2736: elapsed=83809.0us expected=84222.5us +- 421.1us, error 0.5% [63.418357] (kms_flip:2068) DEBUG: name = vblank last_ts = 63.741764 last_received_ts = 63.741432 last_seq = 2735 current_ts = 63.825573 current_received_ts = 63.825268 current_seq = 2745 count = 57 seq_step = 10 [63.418362] (kms_flip:2068) DEBUG: vblank ts/seq: last 63.741764/2735, current 63.825573/2745: elapsed=83809.0us expected=84222.5us +- 421.1us, error 0.5% [63.501689] (kms_flip:2068) DEBUG: name = flip last_ts = 63.750149 last_received_ts = 63.825294 last_seq = 2736 current_ts = 63.833950 current_received_ts = 63.909103 current_seq = 2746 count = 58 seq_step = 1 [63.501702] (kms_flip:2068) DEBUG: flip ts/seq: last 63.750149/2736, current 63.833950/2746: elapsed=83804.0us expected=84222.5us +- 421.1us, error 0.5% [63.501707] (kms_flip:2068) DEBUG: name = vblank last_ts = 63.825573 last_received_ts = 63.825268 last_seq = 2745 current_ts = 63.909382 current_received_ts = 63.909081 current_seq = 2755 count = 58 seq_step = 10 [63.501712] (kms_flip:2068) DEBUG: vblank ts/seq: last 63.825573/2745, current 63.909382/2755: elapsed=83808.0us expected=84222.5us +- 421.1us, error 0.5% [63.585033] (kms_flip:2068) DEBUG: name = flip last_ts = 63.833950 last_received_ts = 63.909103 last_seq = 2746 current_ts = 63.917755 current_received_ts = 63.992855 current_seq = 2756 count = 59 seq_step = 1 [63.585045] (kms_flip:2068) DEBUG: flip ts/seq: last 63.833950/2746, current 63.917755/2756: elapsed=83803.0us expected=84222.5us +- 421.1us, error 0.5% [63.585051] (kms_flip:2068) DEBUG: name = vblank last_ts = 63.909382 last_received_ts = 63.909081 last_seq = 2755 current_ts = 63.993130 current_received_ts = 63.992828 current_seq = 2765 count = 59 seq_step = 10 [63.585056] (kms_flip:2068) DEBUG: vblank ts/seq: last 63.909382/2755, current 63.993130/2765: elapsed=83748.0us expected=84222.5us +- 421.1us, error 0.6% [63.585060] (kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 63.909382/2755, current 63.993130/2765: elapsed=83748.0us expected=84222.5us [63.668388] (kms_flip:2068) DEBUG: name = flip last_ts = 63.917755 last_received_ts = 63.992855 last_seq = 2756 current_ts = 64.001503 current_received_ts = 64.076614 current_seq = 2766 count = 60 seq_step = 1 [63.668400] (kms_flip:2068) DEBUG: flip ts/seq: last 63.917755/2756, current 64.001503/2766: elapsed=83750.0us expected=84222.5us +- 421.1us, error 0.6% [63.668405] (kms_flip:2068) DEBUG: inconsistent flip ts/seq: last 63.917755/2756, current 64.001503/2766: elapsed=83750.0us expected=84222.5us [63.668409] (kms_flip:2068) DEBUG: name = vblank last_ts = 63.993130 last_received_ts = 63.992828 last_seq = 2765 current_ts = 64.076881 current_received_ts = 64.076591 current_seq = 2775 count = 60 seq_step = 10 [63.668414] (kms_flip:2068) DEBUG: vblank ts/seq: last 63.993130/2765, current 64.076881/2775: elapsed=83754.0us expected=84222.5us +- 421.1us, error 0.6% [63.668418] (kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 63.993130/2765, current 64.076881/2775: elapsed=83754.0us expected=84222.5us [63.751727] (kms_flip:2068) DEBUG: name = flip last_ts = 64.001503 last_received_ts = 64.076614 last_seq = 2766 current_ts = 64.085251 current_received_ts = 64.160355 current_seq = 2776 count = 61 seq_step = 1 [63.751739] (kms_flip:2068) DEBUG: flip ts/seq: last 64.001503/2766, current 64.085251/2776: elapsed=83749.0us expected=84222.5us +- 421.1us, error 0.6% [63.751744] (kms_flip:2068) DEBUG: inconsistent flip ts/seq: last 64.001503/2766, current 64.085251/2776: elapsed=83749.0us expected=84222.5us [63.751748] (kms_flip:2068) DEBUG: name = vblank last_ts = 64.076881 last_received_ts = 64.076591 last_seq = 2775 current_ts = 64.160629 current_received_ts = 64.160332 current_seq = 2785 count = 61 seq_step = 10 [63.751753] (kms_flip:2068) DEBUG: vblank ts/seq: last 64.076881/2775, current 64.160629/2785: elapsed=83749.0us expected=84222.5us +- 421.1us, error 0.6% [63.751757] (kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 64.076881/2775, current 64.160629/2785: elapsed=83749.0us expected=84222.5us [63.835120] (kms_flip:2068) DEBUG: name = flip last_ts = 64.085251 last_received_ts = 64.160355 last_seq = 2776 current_ts = 64.169006 current_received_ts = 64.244156 current_seq = 2786 count = 62 seq_step = 1 [63.835133] (kms_flip:2068) DEBUG: flip ts/seq: last 64.085251/2776, current 64.169006/2786: elapsed=83753.0us expected=84222.5us +- 421.1us, error 0.6% [63.835139] (kms_flip:2068) DEBUG: inconsistent flip ts/seq: last 64.085251/2776, current 64.169006/2786: elapsed=83753.0us expected=84222.5us [63.835144] (kms_flip:2068) DEBUG: name = vblank last_ts = 64.160629 last_received_ts = 64.160332 last_seq = 2785 current_ts = 64.244385 current_received_ts = 64.244125 current_seq = 2795 count = 62 seq_step = 10 [63.835150] (kms_flip:2068) DEBUG: vblank ts/seq: last 64.160629/2785, current 64.244385/2795: elapsed=83749.0us expected=84222.5us +- 421.1us, error 0.6% [63.835153] (kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 64.160629/2785, current 64.244385/2795: elapsed=83749.0us expected=84222.5us [63.918419] (kms_flip:2068) DEBUG: name = flip last_ts = 64.169006 last_received_ts = 64.244156 last_seq = 2786 current_ts = 64.252762 current_received_ts = 64.327858 current_seq = 2796 count = 63 seq_step = 1 [63.918432] (kms_flip:2068) DEBUG: flip ts/seq: last 64.169006/2786, current 64.252762/2796: elapsed=83753.0us expected=84222.5us +- 421.1us, error 0.6% [63.918437] (kms_flip:2068) DEBUG: inconsistent flip ts/seq: last 64.169006/2786, current 64.252762/2796: elapsed=83753.0us expected=84222.5us [63.918441] (kms_flip:2068) DEBUG: name = vblank last_ts = 64.244385 last_received_ts = 64.244125 last_seq = 2795 current_ts = 64.328133 current_received_ts = 64.327835 current_seq = 2805 count = 63 seq_step = 10 [63.918446] (kms_flip:2068) DEBUG: vblank ts/seq: last 64.244385/2795, current 64.328133/2805: elapsed=83750.0us expected=84222.5us +- 421.1us, error 0.6% [63.918450] (kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 64.244385/2795, current 64.328133/2805: elapsed=83750.0us expected=84222.5us [64.001764] (kms_flip:2068) DEBUG: name = flip last_ts = 64.252762 last_received_ts = 64.327858 last_seq = 2796 current_ts = 64.336502 current_received_ts = 64.411606 current_seq = 2806 count = 64 seq_step = 1 [64.001777] (kms_flip:2068) DEBUG: flip ts/seq: last 64.252762/2796, current 64.336502/2806: elapsed=83743.0us expected=84222.5us +- 421.1us, error 0.6% [64.001781] (kms_flip:2068) DEBUG: inconsistent flip ts/seq: last 64.252762/2796, current 64.336502/2806: elapsed=83743.0us expected=84222.5us [64.001786] (kms_flip:2068) DEBUG: name = vblank last_ts = 64.328133 last_received_ts = 64.327835 last_seq = 2805 current_ts = 64.411880 current_received_ts = 64.411583 current_seq = 2815 count = 64 seq_step = 10 [64.001792] (kms_flip:2068) DEBUG: vblank ts/seq: last 64.328133/2805, current 64.411880/2815: elapsed=83751.0us expected=84222.5us +- 421.1us, error 0.6% [64.001795] (kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 64.328133/2805, current 64.411880/2815: elapsed=83751.0us expected=84222.5us [64.085113] (kms_flip:2068) DEBUG: name = flip last_ts = 64.336502 last_received_ts = 64.411606 last_seq = 2806 current_ts = 64.420258 current_received_ts = 64.495361 current_seq = 2816 count = 65 seq_step = 1 [64.085126] (kms_flip:2068) DEBUG: flip ts/seq: last 64.336502/2806, current 64.420258/2816: elapsed=83753.0us expected=84222.5us +- 421.1us, error 0.6% [64.085130] (kms_flip:2068) DEBUG: inconsistent flip ts/seq: last 64.336502/2806, current 64.420258/2816: elapsed=83753.0us expected=84222.5us [64.085136] (kms_flip:2068) DEBUG: name = vblank last_ts = 64.411880 last_received_ts = 64.411583 last_seq = 2815 current_ts = 64.495628 current_received_ts = 64.495338 current_seq = 2825 count = 65 seq_step = 10 [64.085141] (kms_flip:2068) DEBUG: vblank ts/seq: last 64.411880/2815, current 64.495628/2825: elapsed=83750.0us expected=84222.5us +- 421.1us, error 0.6% [64.085144] (kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 64.411880/2815, current 64.495628/2825: elapsed=83750.0us expected=84222.5us [64.168462] (kms_flip:2068) DEBUG: name = flip last_ts = 64.420258 last_received_ts = 64.495361 last_seq = 2816 current_ts = 64.504005 current_received_ts = 64.579117 current_seq = 2826 count = 66 seq_step = 1 [64.168474] (kms_flip:2068) DEBUG: flip ts/seq: last 64.420258/2816, current 64.504005/2826: elapsed=83751.0us expected=84222.5us +- 421.1us, error 0.6% [64.168479] (kms_flip:2068) DEBUG: inconsistent flip ts/seq: last 64.420258/2816, current 64.504005/2826: elapsed=83751.0us expected=84222.5us [64.168483] (kms_flip:2068) DEBUG: name = vblank last_ts = 64.495628 last_received_ts = 64.495338 last_seq = 2825 current_ts = 64.579384 current_received_ts = 64.579086 current_seq = 2835 count = 66 seq_step = 10 [64.168488] (kms_flip:2068) DEBUG: vblank ts/seq: last 64.495628/2825, current 64.579384/2835: elapsed=83749.0us expected=84222.5us +- 421.1us, error 0.6% [64.168492] (kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 64.495628/2825, current 64.579384/2835: elapsed=83749.0us expected=84222.5us [64.251807] (kms_flip:2068) DEBUG: name = flip last_ts = 64.504005 last_received_ts = 64.579117 last_seq = 2826 current_ts = 64.587753 current_received_ts = 64.662865 current_seq = 2836 count = 67 seq_step = 1 [64.251819] (kms_flip:2068) DEBUG: flip ts/seq: last 64.504005/2826, current 64.587753/2836: elapsed=83747.0us expected=84222.5us +- 421.1us, error 0.6% [64.251824] (kms_flip:2068) DEBUG: inconsistent flip ts/seq: last 64.504005/2826, current 64.587753/2836: elapsed=83747.0us expected=84222.5us [64.251829] (kms_flip:2068) DEBUG: name = vblank last_ts = 64.579384 last_received_ts = 64.579086 last_seq = 2835 current_ts = 64.663132 current_received_ts = 64.662834 current_seq = 2845 count = 67 seq_step = 10 [64.251834] (kms_flip:2068) DEBUG: vblank ts/seq: last 64.579384/2835, current 64.663132/2845: elapsed=83747.0us expected=84222.5us +- 421.1us, error 0.6% [64.251837] (kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 64.579384/2835, current 64.663132/2845: elapsed=83747.0us expected=84222.5us [64.335159] (kms_flip:2068) DEBUG: name = flip last_ts = 64.587753 last_received_ts = 64.662865 last_seq = 2836 current_ts = 64.671501 current_received_ts = 64.746620 current_seq = 2846 count = 68 seq_step = 1 [64.335171] (kms_flip:2068) DEBUG: flip ts/seq: last 64.587753/2836, current 64.671501/2846: elapsed=83750.0us expected=84222.5us +- 421.1us, error 0.6% [64.335176] (kms_flip:2068) DEBUG: inconsistent flip ts/seq: last 64.587753/2836, current 64.671501/2846: elapsed=83750.0us expected=84222.5us [64.335180] (kms_flip:2068) DEBUG: name = vblank last_ts = 64.663132 last_received_ts = 64.662834 last_seq = 2845 current_ts = 64.746880 current_received_ts = 64.746590 current_seq = 2855 count = 68 seq_step = 10 [64.335186] (kms_flip:2068) DEBUG: vblank ts/seq: last 64.663132/2845, current 64.746880/2855: elapsed=83753.0us expected=84222.5us +- 421.1us, error 0.6% [64.335190] (kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 64.663132/2845, current 64.746880/2855: elapsed=83753.0us expected=84222.5us [64.418492] (kms_flip:2068) DEBUG: name = flip last_ts = 64.671501 last_received_ts = 64.746620 last_seq = 2846 current_ts = 64.755257 current_received_ts = 64.830353 current_seq = 2856 count = 69 seq_step = 1 [64.418504] (kms_flip:2068) DEBUG: flip ts/seq: last 64.671501/2846, current 64.755257/2856: elapsed=83753.0us expected=84222.5us +- 421.1us, error 0.6% [64.418509] (kms_flip:2068) DEBUG: inconsistent flip ts/seq: last 64.671501/2846, current 64.755257/2856: elapsed=83753.0us expected=84222.5us [64.418514] (kms_flip:2068) DEBUG: name = vblank last_ts = 64.746880 last_received_ts = 64.746590 last_seq = 2855 current_ts = 64.830627 current_received_ts = 64.830330 current_seq = 2865 count = 69 seq_step = 10 [64.418519] (kms_flip:2068) DEBUG: vblank ts/seq: last 64.746880/2855, current 64.830627/2865: elapsed=83749.0us expected=84222.5us +- 421.1us, error 0.6% [64.418523] (kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 64.746880/2855, current 64.830627/2865: elapsed=83749.0us expected=84222.5us [64.501843] (kms_flip:2068) DEBUG: name = flip last_ts = 64.755257 last_received_ts = 64.830353 last_seq = 2856 current_ts = 64.839005 current_received_ts = 64.914108 current_seq = 2866 count = 70 seq_step = 1 [64.501855] (kms_flip:2068) DEBUG: flip ts/seq: last 64.755257/2856, current 64.839005/2866: elapsed=83750.0us expected=84222.5us +- 421.1us, error 0.6% [64.501860] (kms_flip:2068) DEBUG: inconsistent flip ts/seq: last 64.755257/2856, current 64.839005/2866: elapsed=83750.0us expected=84222.5us [64.501865] (kms_flip:2068) DEBUG: name = vblank last_ts = 64.830627 last_received_ts = 64.830330 last_seq = 2865 current_ts = 64.914383 current_received_ts = 64.914085 current_seq = 2875 count = 70 seq_step = 10 [64.501870] (kms_flip:2068) DEBUG: vblank ts/seq: last 64.830627/2865, current 64.914383/2875: elapsed=83752.0us expected=84222.5us +- 421.1us, error 0.6% [64.501873] (kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 64.830627/2865, current 64.914383/2875: elapsed=83752.0us expected=84222.5us [64.585183] (kms_flip:2068) DEBUG: name = flip last_ts = 64.839005 last_received_ts = 64.914108 last_seq = 2866 current_ts = 64.922752 current_received_ts = 64.997803 current_seq = 2876 count = 71 seq_step = 1 [64.585196] (kms_flip:2068) DEBUG: flip ts/seq: last 64.839005/2866, current 64.922752/2876: elapsed=83746.0us expected=84222.5us +- 421.1us, error 0.6% [64.585200] (kms_flip:2068) DEBUG: inconsistent flip ts/seq: last 64.839005/2866, current 64.922752/2876: elapsed=83746.0us expected=84222.5us [64.585205] (kms_flip:2068) DEBUG: name = vblank last_ts = 64.914383 last_received_ts = 64.914085 last_seq = 2875 current_ts = 64.998077 current_received_ts = 64.997780 current_seq = 2885 count = 71 seq_step = 10 [64.585209] (kms_flip:2068) DEBUG: vblank ts/seq: last 64.914383/2875, current 64.998077/2885: elapsed=83697.0us expected=84222.5us +- 421.1us, error 0.6% [64.585213] (kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 64.914383/2875, current 64.998077/2885: elapsed=83697.0us expected=84222.5us [64.668530] (kms_flip:2068) DEBUG: name = flip last_ts = 64.922752 last_received_ts = 64.997803 last_seq = 2876 current_ts = 65.006447 current_received_ts = 65.081505 current_seq = 2886 count = 72 seq_step = 1 [64.668543] (kms_flip:2068) DEBUG: flip ts/seq: last 64.922752/2876, current 65.006447/2886: elapsed=83697.0us expected=84222.5us +- 421.1us, error 0.6% [64.668547] (kms_flip:2068) DEBUG: inconsistent flip ts/seq: last 64.922752/2876, current 65.006447/2886: elapsed=83697.0us expected=84222.5us [64.668552] (kms_flip:2068) DEBUG: name = vblank last_ts = 64.998077 last_received_ts = 64.997780 last_seq = 2885 current_ts = 65.081779 current_received_ts = 65.081482 current_seq = 2895 count = 72 seq_step = 10 [64.668557] (kms_flip:2068) DEBUG: vblank ts/seq: last 64.998077/2885, current 65.081779/2895: elapsed=83700.0us expected=84222.5us +- 421.1us, error 0.6% [64.668561] (kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 64.998077/2885, current 65.081779/2895: elapsed=83700.0us expected=84222.5us [64.751854] (kms_flip:2068) DEBUG: name = flip last_ts = 65.006447 last_received_ts = 65.081505 last_seq = 2886 current_ts = 65.090149 current_received_ts = 65.165184 current_seq = 2896 count = 73 seq_step = 1 [64.751867] (kms_flip:2068) DEBUG: flip ts/seq: last 65.006447/2886, current 65.090149/2896: elapsed=83697.0us expected=84222.5us +- 421.1us, error 0.6% [64.751871] (kms_flip:2068) DEBUG: inconsistent flip ts/seq: last 65.006447/2886, current 65.090149/2896: elapsed=83697.0us expected=84222.5us [64.751876] (kms_flip:2068) DEBUG: name = vblank last_ts = 65.081779 last_received_ts = 65.081482 last_seq = 2895 current_ts = 65.165482 current_received_ts = 65.165154 current_seq = 2905 count = 73 seq_step = 10 [64.751881] (kms_flip:2068) DEBUG: vblank ts/seq: last 65.081779/2895, current 65.165482/2905: elapsed=83699.0us expected=84222.5us +- 421.1us, error 0.6% [64.751885] (kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 65.081779/2895, current 65.165482/2905: elapsed=83699.0us expected=84222.5us [64.835220] (kms_flip:2068) DEBUG: name = flip last_ts = 65.090149 last_received_ts = 65.165184 last_seq = 2896 current_ts = 65.173843 current_received_ts = 65.248901 current_seq = 2906 count = 74 seq_step = 1 [64.835232] (kms_flip:2068) DEBUG: flip ts/seq: last 65.090149/2896, current 65.173843/2906: elapsed=83698.0us expected=84222.5us +- 421.1us, error 0.6% [64.835236] (kms_flip:2068) DEBUG: inconsistent flip ts/seq: last 65.090149/2896, current 65.173843/2906: elapsed=83698.0us expected=84222.5us [64.835242] (kms_flip:2068) DEBUG: name = vblank last_ts = 65.165482 last_received_ts = 65.165154 last_seq = 2905 current_ts = 65.249176 current_received_ts = 65.248878 current_seq = 2915 count = 74 seq_step = 10 [64.835247] (kms_flip:2068) DEBUG: vblank ts/seq: last 65.165482/2905, current 65.249176/2915: elapsed=83699.0us expected=84222.5us +- 421.1us, error 0.6% [64.835250] (kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 65.165482/2905, current 65.249176/2915: elapsed=83699.0us expected=84222.5us [64.918568] (kms_flip:2068) DEBUG: name = flip last_ts = 65.173843 last_received_ts = 65.248901 last_seq = 2906 current_ts = 65.257545 current_received_ts = 65.332603 current_seq = 2916 count = 75 seq_step = 1 [64.918580] (kms_flip:2068) DEBUG: flip ts/seq: last 65.173843/2906, current 65.257545/2916: elapsed=83699.0us expected=84222.5us +- 421.1us, error 0.6% [64.918585] (kms_flip:2068) DEBUG: inconsistent flip ts/seq: last 65.173843/2906, current 65.257545/2916: elapsed=83699.0us expected=84222.5us [64.918590] (kms_flip:2068) DEBUG: name = vblank last_ts = 65.249176 last_received_ts = 65.248878 last_seq = 2915 current_ts = 65.332878 current_received_ts = 65.332581 current_seq = 2925 count = 75 seq_step = 10 [64.918595] (kms_flip:2068) DEBUG: vblank ts/seq: last 65.249176/2915, current 65.332878/2925: elapsed=83700.0us expected=84222.5us +- 421.1us, error 0.6% [64.918599] (kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 65.249176/2915, current 65.332878/2925: elapsed=83700.0us expected=84222.5us [65.001913] (kms_flip:2068) DEBUG: name = flip last_ts = 65.257545 last_received_ts = 65.332603 last_seq = 2916 current_ts = 65.341248 current_received_ts = 65.416306 current_seq = 2926 count = 76 seq_step = 1 [65.001926] (kms_flip:2068) DEBUG: flip ts/seq: last 65.257545/2916, current 65.341248/2926: elapsed=83701.0us expected=84222.5us +- 421.1us, error 0.6% [65.001930] (kms_flip:2068) DEBUG: inconsistent flip ts/seq: last 65.257545/2916, current 65.341248/2926: elapsed=83701.0us expected=84222.5us [65.001935] (kms_flip:2068) DEBUG: name = vblank last_ts = 65.332878 last_received_ts = 65.332581 last_seq = 2925 current_ts = 65.416573 current_received_ts = 65.416275 current_seq = 2935 count = 76 seq_step = 10 [65.001941] (kms_flip:2068) DEBUG: vblank ts/seq: last 65.332878/2925, current 65.416573/2935: elapsed=83695.0us expected=84222.5us +- 421.1us, error 0.6% [65.001944] (kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 65.332878/2925, current 65.416573/2935: elapsed=83695.0us expected=84222.5us [65.085283] (kms_flip:2068) DEBUG: name = flip last_ts = 65.341248 last_received_ts = 65.416306 last_seq = 2926 current_ts = 65.424942 current_received_ts = 65.500023 current_seq = 2936 count = 77 seq_step = 1 [65.085296] (kms_flip:2068) DEBUG: flip ts/seq: last 65.341248/2926, current 65.424942/2936: elapsed=83700.0us expected=84222.5us +- 421.1us, error 0.6% [65.085302] (kms_flip:2068) DEBUG: inconsistent flip ts/seq: last 65.341248/2926, current 65.424942/2936: elapsed=83700.0us expected=84222.5us [65.085308] (kms_flip:2068) DEBUG: name = vblank last_ts = 65.416573 last_received_ts = 65.416275 last_seq = 2935 current_ts = 65.500275 current_received_ts = 65.499977 current_seq = 2945 count = 77 seq_step = 10 [65.085315] (kms_flip:2068) DEBUG: vblank ts/seq: last 65.416573/2935, current 65.500275/2945: elapsed=83699.0us expected=84222.5us +- 421.1us, error 0.6% [65.085319] (kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 65.416573/2935, current 65.500275/2945: elapsed=83699.0us expected=84222.5us [65.168601] (kms_flip:2068) DEBUG: name = flip last_ts = 65.424942 last_received_ts = 65.500023 last_seq = 2936 current_ts = 65.508644 current_received_ts = 65.583694 current_seq = 2946 count = 78 seq_step = 1 [65.168613] (kms_flip:2068) DEBUG: flip ts/seq: last 65.424942/2936, current 65.508644/2946: elapsed=83698.0us expected=84222.5us +- 421.1us, error 0.6% [65.168618] (kms_flip:2068) DEBUG: inconsistent flip ts/seq: last 65.424942/2936, current 65.508644/2946: elapsed=83698.0us expected=84222.5us [65.168622] (kms_flip:2068) DEBUG: name = vblank last_ts = 65.500275 last_received_ts = 65.499977 last_seq = 2945 current_ts = 65.583977 current_received_ts = 65.583672 current_seq = 2955 count = 78 seq_step = 10 [65.168628] (kms_flip:2068) DEBUG: vblank ts/seq: last 65.500275/2945, current 65.583977/2955: elapsed=83706.0us expected=84222.5us +- 421.1us, error 0.6% [65.168631] (kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 65.500275/2945, current 65.583977/2955: elapsed=83706.0us expected=84222.5us [65.251953] (kms_flip:2068) DEBUG: name = flip last_ts = 65.508644 last_received_ts = 65.583694 last_seq = 2946 current_ts = 65.592339 current_received_ts = 65.667404 current_seq = 2956 count = 79 seq_step = 1 [65.251967] (kms_flip:2068) DEBUG: flip ts/seq: last 65.508644/2946, current 65.592339/2956: elapsed=83700.0us expected=84222.5us +- 421.1us, error 0.6% [65.251972] (kms_flip:2068) DEBUG: inconsistent flip ts/seq: last 65.508644/2946, current 65.592339/2956: elapsed=83700.0us expected=84222.5us [65.251977] (kms_flip:2068) DEBUG: name = vblank last_ts = 65.583977 last_received_ts = 65.583672 last_seq = 2955 current_ts = 65.667671 current_received_ts = 65.667374 current_seq = 2965 count = 79 seq_step = 10 [65.251982] (kms_flip:2068) DEBUG: vblank ts/seq: last 65.583977/2955, current 65.667671/2965: elapsed=83697.0us expected=84222.5us +- 421.1us, error 0.6% [65.251986] (kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 65.583977/2955, current 65.667671/2965: elapsed=83697.0us expected=84222.5us [65.335312] (kms_flip:2068) DEBUG: name = flip last_ts = 65.592339 last_received_ts = 65.667404 last_seq = 2956 current_ts = 65.676041 current_received_ts = 65.751114 current_seq = 2966 count = 80 seq_step = 1 [65.335324] (kms_flip:2068) DEBUG: flip ts/seq: last 65.592339/2956, current 65.676041/2966: elapsed=83701.0us expected=84222.5us +- 421.1us, error 0.6% [65.335329] (kms_flip:2068) DEBUG: inconsistent flip ts/seq: last 65.592339/2956, current 65.676041/2966: elapsed=83701.0us expected=84222.5us [65.335334] (kms_flip:2068) DEBUG: name = vblank last_ts = 65.667671 last_received_ts = 65.667374 last_seq = 2965 current_ts = 65.751373 current_received_ts = 65.751091 current_seq = 2975 count = 80 seq_step = 10 [65.335340] (kms_flip:2068) DEBUG: vblank ts/seq: last 65.667671/2965, current 65.751373/2975: elapsed=83696.0us expected=84222.5us +- 421.1us, error 0.6% [65.335343] (kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 65.667671/2965, current 65.751373/2975: elapsed=83696.0us expected=84222.5us [65.418644] (kms_flip:2068) DEBUG: name = flip last_ts = 65.676041 last_received_ts = 65.751114 last_seq = 2966 current_ts = 65.759743 current_received_ts = 65.834801 current_seq = 2976 count = 81 seq_step = 1 [65.418657] (kms_flip:2068) DEBUG: flip ts/seq: last 65.676041/2966, current 65.759743/2976: elapsed=83699.0us expected=84222.5us +- 421.1us, error 0.6% [65.418662] (kms_flip:2068) DEBUG: inconsistent flip ts/seq: last 65.676041/2966, current 65.759743/2976: elapsed=83699.0us expected=84222.5us [65.418667] (kms_flip:2068) DEBUG: name = vblank last_ts = 65.751373 last_received_ts = 65.751091 last_seq = 2975 current_ts = 65.835075 current_received_ts = 65.834778 current_seq = 2985 count = 81 seq_step = 10 [65.418672] (kms_flip:2068) DEBUG: vblank ts/seq: last 65.751373/2975, current 65.835075/2985: elapsed=83704.0us expected=84222.5us +- 421.1us, error 0.6% [65.418675] (kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 65.751373/2975, current 65.835075/2985: elapsed=83704.0us expected=84222.5us [65.501981] (kms_flip:2068) DEBUG: name = flip last_ts = 65.759743 last_received_ts = 65.834801 last_seq = 2976 current_ts = 65.843445 current_received_ts = 65.918495 current_seq = 2986 count = 82 seq_step = 1 [65.501993] (kms_flip:2068) DEBUG: flip ts/seq: last 65.759743/2976, current 65.843445/2986: elapsed=83701.0us expected=84222.5us +- 421.1us, error 0.6% [65.501997] (kms_flip:2068) DEBUG: inconsistent flip ts/seq: last 65.759743/2976, current 65.843445/2986: elapsed=83701.0us expected=84222.5us [65.502002] (kms_flip:2068) DEBUG: name = vblank last_ts = 65.835075 last_received_ts = 65.834778 last_seq = 2985 current_ts = 65.918777 current_received_ts = 65.918465 current_seq = 2995 count = 82 seq_step = 10 [65.502008] (kms_flip:2068) DEBUG: vblank ts/seq: last 65.835075/2985, current 65.918777/2995: elapsed=83700.0us expected=84222.5us +- 421.1us, error 0.6% [65.502011] (kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 65.835075/2985, current 65.918777/2995: elapsed=83700.0us expected=84222.5us [65.585336] (kms_flip:2068) DEBUG: name = flip last_ts = 65.843445 last_received_ts = 65.918495 last_seq = 2986 current_ts = 65.927132 current_received_ts = 66.002159 current_seq = 2996 count = 83 seq_step = 1 [65.585348] (kms_flip:2068) DEBUG: flip ts/seq: last 65.843445/2986, current 65.927132/2996: elapsed=83690.0us expected=84222.5us +- 421.1us, error 0.6% [65.585353] (kms_flip:2068) DEBUG: inconsistent flip ts/seq: last 65.843445/2986, current 65.927132/2996: elapsed=83690.0us expected=84222.5us [65.585358] (kms_flip:2068) DEBUG: name = vblank last_ts = 65.918777 last_received_ts = 65.918465 last_seq = 2995 current_ts = 66.002426 current_received_ts = 66.002129 current_seq = 3005 count = 83 seq_step = 10 [65.585364] (kms_flip:2068) DEBUG: vblank ts/seq: last 65.918777/2995, current 66.002426/3005: elapsed=83655.0us expected=84222.5us +- 421.1us, error 0.7% [65.585368] (kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 65.918777/2995, current 66.002426/3005: elapsed=83655.0us expected=84222.5us [65.668674] (kms_flip:2068) DEBUG: name = flip last_ts = 65.927132 last_received_ts = 66.002159 last_seq = 2996 current_ts = 66.010796 current_received_ts = 66.085808 current_seq = 3006 count = 84 seq_step = 1 [65.668686] (kms_flip:2068) DEBUG: flip ts/seq: last 65.927132/2996, current 66.010796/3006: elapsed=83662.0us expected=84222.5us +- 421.1us, error 0.7% [65.668691] (kms_flip:2068) DEBUG: inconsistent flip ts/seq: last 65.927132/2996, current 66.010796/3006: elapsed=83662.0us expected=84222.5us [65.668696] (kms_flip:2068) DEBUG: name = vblank last_ts = 66.002426 last_received_ts = 66.002129 last_seq = 3005 current_ts = 66.086082 current_received_ts = 66.085777 current_seq = 3015 count = 84 seq_step = 10 [65.668701] (kms_flip:2068) DEBUG: vblank ts/seq: last 66.002426/3005, current 66.086082/3015: elapsed=83655.0us expected=84222.5us +- 421.1us, error 0.7% [65.668704] (kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 66.002426/3005, current 66.086082/3015: elapsed=83655.0us expected=84222.5us [65.752018] (kms_flip:2068) DEBUG: name = flip last_ts = 66.010796 last_received_ts = 66.085808 last_seq = 3006 current_ts = 66.094452 current_received_ts = 66.169456 current_seq = 3016 count = 85 seq_step = 1 [65.752031] (kms_flip:2068) DEBUG: flip ts/seq: last 66.010796/3006, current 66.094452/3016: elapsed=83655.0us expected=84222.5us +- 421.1us, error 0.7% [65.752035] (kms_flip:2068) DEBUG: inconsistent flip ts/seq: last 66.010796/3006, current 66.094452/3016: elapsed=83655.0us expected=84222.5us [65.752040] (kms_flip:2068) DEBUG: name = vblank last_ts = 66.086082 last_received_ts = 66.085777 last_seq = 3015 current_ts = 66.169739 current_received_ts = 66.169434 current_seq = 3025 count = 85 seq_step = 10 [65.752045] (kms_flip:2068) DEBUG: vblank ts/seq: last 66.086082/3015, current 66.169739/3025: elapsed=83655.0us expected=84222.5us +- 421.1us, error 0.7% [65.752049] (kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 66.086082/3015, current 66.169739/3025: elapsed=83655.0us expected=84222.5us [65.835366] (kms_flip:2068) DEBUG: name = flip last_ts = 66.094452 last_received_ts = 66.169456 last_seq = 3016 current_ts = 66.178108 current_received_ts = 66.253113 current_seq = 3026 count = 86 seq_step = 1 [65.835379] (kms_flip:2068) DEBUG: flip ts/seq: last 66.094452/3016, current 66.178108/3026: elapsed=83655.0us expected=84222.5us +- 421.1us, error 0.7% [65.835385] (kms_flip:2068) DEBUG: inconsistent flip ts/seq: last 66.094452/3016, current 66.178108/3026: elapsed=83655.0us expected=84222.5us [65.835390] (kms_flip:2068) DEBUG: name = vblank last_ts = 66.169739 last_received_ts = 66.169434 last_seq = 3025 current_ts = 66.253395 current_received_ts = 66.253090 current_seq = 3035 count = 86 seq_step = 10 [65.835395] (kms_flip:2068) DEBUG: vblank ts/seq: last 66.169739/3025, current 66.253395/3035: elapsed=83656.0us expected=84222.5us +- 421.1us, error 0.7% [65.835399] (kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 66.169739/3025, current 66.253395/3035: elapsed=83656.0us expected=84222.5us [65.918713] (kms_flip:2068) DEBUG: name = flip last_ts = 66.178108 last_received_ts = 66.253113 last_seq = 3026 current_ts = 66.261757 current_received_ts = 66.336769 current_seq = 3036 count = 87 seq_step = 1 [65.918726] (kms_flip:2068) DEBUG: flip ts/seq: last 66.178108/3026, current 66.261757/3036: elapsed=83655.0us expected=84222.5us +- 421.1us, error 0.7% [65.918732] (kms_flip:2068) DEBUG: inconsistent flip ts/seq: last 66.178108/3026, current 66.261757/3036: elapsed=83655.0us expected=84222.5us [65.918737] (kms_flip:2068) DEBUG: name = vblank last_ts = 66.253395 last_received_ts = 66.253090 last_seq = 3035 current_ts = 66.337051 current_received_ts = 66.336746 current_seq = 3045 count = 87 seq_step = 10 [65.918742] (kms_flip:2068) DEBUG: vblank ts/seq: last 66.253395/3035, current 66.337051/3045: elapsed=83654.0us expected=84222.5us +- 421.1us, error 0.7% [65.918746] (kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 66.253395/3035, current 66.337051/3045: elapsed=83654.0us expected=84222.5us [66.001995] (kms_flip:2068) DEBUG: name = flip last_ts = 66.261757 last_received_ts = 66.336769 last_seq = 3036 current_ts = 66.345413 current_received_ts = 66.420364 current_seq = 3046 count = 88 seq_step = 1 [66.002009] (kms_flip:2068) DEBUG: flip ts/seq: last 66.261757/3036, current 66.345413/3046: elapsed=83653.0us expected=84222.5us +- 421.1us, error 0.7% [66.002014] (kms_flip:2068) DEBUG: inconsistent flip ts/seq: last 66.261757/3036, current 66.345413/3046: elapsed=83653.0us expected=84222.5us [66.002021] (kms_flip:2068) DEBUG: name = vblank last_ts = 66.337051 last_received_ts = 66.336746 last_seq = 3045 current_ts = 66.420708 current_received_ts = 66.420334 current_seq = 3055 count = 88 seq_step = 10 [66.002026] (kms_flip:2068) DEBUG: vblank ts/seq: last 66.337051/3045, current 66.420708/3055: elapsed=83656.0us expected=84222.5us +- 421.1us, error 0.7% [66.002030] (kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 66.337051/3045, current 66.420708/3055: elapsed=83656.0us expected=84222.5us [66.085378] (kms_flip:2068) DEBUG: name = flip last_ts = 66.345413 last_received_ts = 66.420364 last_seq = 3046 current_ts = 66.429070 current_received_ts = 66.504044 current_seq = 3056 count = 89 seq_step = 1 [66.085395] (kms_flip:2068) DEBUG: flip ts/seq: last 66.345413/3046, current 66.429070/3056: elapsed=83653.0us expected=84222.5us +- 421.1us, error 0.7% [66.085401] (kms_flip:2068) DEBUG: inconsistent flip ts/seq: last 66.345413/3046, current 66.429070/3056: elapsed=83653.0us expected=84222.5us [66.085406] (kms_flip:2068) DEBUG: name = vblank last_ts = 66.420708 last_received_ts = 66.420334 last_seq = 3055 current_ts = 66.504356 current_received_ts = 66.503983 current_seq = 3065 count = 89 seq_step = 10 [66.085411] (kms_flip:2068) DEBUG: vblank ts/seq: last 66.420708/3055, current 66.504356/3065: elapsed=83655.0us expected=84222.5us +- 421.1us, error 0.7% [66.085414] (kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 66.420708/3055, current 66.504356/3065: elapsed=83655.0us expected=84222.5us [66.085417] (kms_flip:2068) INFO: Event flip: expected 896, counted 900, passrate = 66.67%, encoder type 2 [66.085457] (kms_flip:2068) DEBUG: dropped frames, expected 896, counted 900, passrate = 66.67%, encoder type 2 [66.085460] (kms_flip:2068) INFO: Event vblank: expected 896, counted 900, passrate = 65.56%, encoder type 2 [66.085480] (kms_flip:2068) DEBUG: dropped frames, expected 896, counted 900, passrate = 65.56%, encoder type 2 [66.085483] (kms_flip:2068) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1722: [66.085501] (kms_flip:2068) CRITICAL: Failed assertion: !retried [66.122945] (kms_flip:2068) igt_core-INFO: Stack trace: [66.135558] (kms_flip:2068) igt_core-INFO: #0 ../lib/igt_core.c:2106 __igt_fail_assert() [66.136930] (kms_flip:2068) igt_core-INFO: #1 ../tests/kms_flip.c:1071 run_test_on_crtc_set() [66.137024] (kms_flip:2068) igt_core-INFO: #2 ../tests/kms_flip.c:1920 run_test() [66.137066] (kms_flip:2068) igt_core-INFO: #3 ../tests/kms_flip.c:2184 __igt_unique____real_main2105() [66.137102] (kms_flip:2068) igt_core-INFO: #4 ../tests/kms_flip.c:2105 main() [66.144904] (kms_flip:2068) igt_core-INFO: #5 [__libc_init_first+0x8a] [66.146165] (kms_flip:2068) igt_core-INFO: #6 [__libc_start_main+0x8b] [66.146485] (kms_flip:2068) igt_core-INFO: #7 [_start+0x25] **** END **** Dynamic subtest A-HDMI-A1: FAIL (18.623s) |
| Dmesg |
<6> [47.719498] Console: switching to colour dummy device 80x25
<6> [47.719564] [IGT] kms_flip: executing
<7> [47.722748] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [47.723539] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [47.723859] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [47.736340] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:598:DP-1]
<7> [47.736350] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:598:DP-1]
<7> [47.740750] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:598:DP-1] disconnected
<7> [47.741209] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:608:DP-2]
<7> [47.741215] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:608:DP-2]
<7> [47.741551] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:608:DP-2] disconnected
<7> [47.742215] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:617:DP-3]
<7> [47.742221] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:617:DP-3]
<7> [47.742550] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:617:DP-3] disconnected
<7> [47.742976] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:626:DP-4]
<7> [47.742980] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:626:DP-4]
<7> [47.743306] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:626:DP-4] disconnected
<7> [47.743733] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:588:HDMI-A-1]
<7> [47.743744] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:588:HDMI-A-1]
<7> [47.770533] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:588:HDMI-A-1] Supported Monitor Refresh rate range is 0 Hz - 0 Hz
<7> [47.770542] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:588:HDMI-A-1] HDMI: DVI dual 0, max TMDS clock 300000 kHz
<7> [47.770545] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:588:HDMI-A-1] No deep color support on this HDMI sink.
<7> [47.770547] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:588:HDMI-A-1] HF-VSDB: max TMDS clock: 600000 KHz, HDMI 2.1 support: no, DSC 1.2 support: no
<7> [47.770550] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:588:HDMI-A-1] Assigning EDID-1.4 digital sink color depth as 8 bpc.
<7> [47.770553] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:588:HDMI-A-1] ELD monitor fitHeadlessGS
<7> [47.770556] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:588:HDMI-A-1] HDMI: latency present 0 0, video latency 0 0, audio latency 0 0
<7> [47.770559] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:588:HDMI-A-1] ELD size 64, SAD count 10
<7> [47.771055] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0040 w(1)
<7> [47.771235] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [47.771758] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0040 w(1)
<7> [47.771931] i915 0000:00:02.0: [drm:drm_dp_dual_mode_detect [drm_display_helper]] DP dual mode HDMI ID: (err -6)
<7> [47.772793] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:588:HDMI-A-1] probed modes:
<7> [47.772800] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 120 285500 1920 1968 2000 2080 1080 1083 1088 1144 0x48 0x5
<7> [47.772807] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "4096x2160": 60 594000 4096 4184 4272 4400 2160 2168 2178 2250 0x40 0x5
<7> [47.772811] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "4096x2160": 60 593407 4096 4184 4272 4400 2160 2168 2178 2250 0x40 0x5
<7> [47.772814] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "4096x2160": 30 297000 4096 4184 4272 4400 2160 2168 2178 2250 0x40 0x5
<7> [47.772818] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "4096x2160": 30 296703 4096 4184 4272 4400 2160 2168 2178 2250 0x40 0x5
<7> [47.772822] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "3840x2160": 60 594000 3840 4016 4104 4400 2160 2168 2178 2250 0x40 0x5
<7> [47.772825] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "3840x2160": 60 593407 3840 4016 4104 4400 2160 2168 2178 2250 0x40 0x5
<7> [47.772829] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "3840x2160": 30 297000 3840 4016 4104 4400 2160 2168 2178 2250 0x40 0x5
<7> [47.772833] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "3840x2160": 30 296703 3840 4016 4104 4400 2160 2168 2178 2250 0x40 0x5
<7> [47.772836] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "3840x2160": 25 297000 3840 4896 4984 5280 2160 2168 2178 2250 0x40 0x5
<7> [47.772840] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "3840x2160": 24 297000 3840 5116 5204 5500 2160 2168 2178 2250 0x40 0x5
<7> [47.772843] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "3840x2160": 24 296703 3840 5116 5204 5500 2160 2168 2178 2250 0x40 0x5
<7> [47.772847] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "3440x1440": 90 476140 3440 3448 3480 3520 1440 1441 1449 1503 0x40 0xa
<7> [47.772850] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2560x1600": 75 443250 2560 2768 3048 3536 1600 1603 1609 1672 0x40 0x6
<7> [47.772854] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2560x1600": 60 348500 2560 2752 3032 3504 1600 1603 1609 1658 0x40 0x6
<7> [47.772857] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2560x1440": 120 497770 2560 2608 2640 2720 1440 1443 1448 1525 0x40 0xa
<7> [47.772861] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2048x1536": 60 267027 2048 2200 2424 2800 1536 1537 1540 1589 0x0 0x6
<7> [47.772864] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2560x1080": 120 495000 2560 3108 3152 3300 1080 1084 1089 1250 0x40 0x5
<7> [47.772868] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2560x1080": 120 494505 2560 3108 3152 3300 1080 1084 1089 1250 0x40 0x5
<7> [47.772871] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1440": 75 297000 1920 2064 2288 2640 1440 1441 1444 1500 0x40 0x6
<7> [47.772875] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1440": 60 234000 1920 2048 2256 2600 1440 1441 1444 1500 0x40 0x6
<7> [47.772878] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2160x1200": 90 261420 2160 2208 2240 2320 1200 1203 1208 1252 0x40 0xa
<7> [47.772882] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1856x1392": 75 288000 1856 1984 2208 2560 1392 1393 1396 1500 0x40 0x6
<7> [47.772885] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1856x1392": 60 218250 1856 1952 2176 2528 1392 1393 1396 1439 0x40 0x6
<7> [47.772889] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1792x1344": 75 261000 1792 1888 2104 2456 1344 1345 1348 1417 0x40 0x6
<7> [47.772892] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1792x1344": 60 204750 1792 1920 2120 2448 1344 1345 1348 1394 0x40 0x6
<7> [47.772896] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2048x1152": 60 162000 2048 2074 2154 2250 1152 1153 1156 1200 0x40 0x5
<7> [47.772899] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1200": 120 317250 1920 1968 2000 2080 1200 1203 1209 1271 0x40 0xa
<7> [47.772903] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1200": 75 245250 1920 2056 2264 2608 1200 1203 1209 1255 0x40 0x6
<7> [47.772906] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1200": 60 193250 1920 2056 2256 2592 1200 1203 1209 1245 0x40 0x6
<7> [47.772910] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 120 297000 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [47.772913] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 120 296703 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [47.772917] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 75 220597 1920 2056 2264 2608 1080 1081 1084 1128 0x0 0x6
<7> [47.772920] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [47.772924] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 60 148352 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [47.772928] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1600x1200": 75 202500 1600 1664 1856 2160 1200 1201 1204 1250 0x40 0x5
<7> [47.772931] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1600x1200": 70 189000 1600 1664 1856 2160 1200 1201 1204 1250 0x40 0x5
<7> [47.772935] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1600x1200": 65 175500 1600 1664 1856 2160 1200 1201 1204 1250 0x40 0x5
<7> [47.772938] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1600x1200": 60 162000 1600 1664 1856 2160 1200 1201 1204 1250 0x40 0x5
<7> [47.772942] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1680x1050": 75 187000 1680 1800 1976 2272 1050 1053 1059 1099 0x40 0x6
<7> [47.772945] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1680x1050": 60 146250 1680 1784 1960 2240 1050 1053 1059 1089 0x40 0x6
<7> [47.772949] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1680x945": 60 131481 1680 1784 1960 2240 945 946 949 978 0x0 0x6
<7> [47.772952] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1400x1050": 75 156000 1400 1504 1648 1896 1050 1053 1057 1099 0x40 0x6
<7> [47.772956] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1400x1050": 60 121750 1400 1488 1632 1864 1050 1053 1057 1089 0x40 0x6
<7> [47.772959] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1600x900": 60 108000 1600 1624 1704 1800 900 901 904 1000 0x40 0x5
<7> [47.772963] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x1024": 75 135000 1280 1296 1440 1688 1024 1025 1028 1066 0x40 0x5
<7> [47.772966] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x1024": 60 108000 1280 1328 1440 1688 1024 1025 1028 1066 0x40 0x5
<7> [47.772970] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1440x900": 75 136750 1440 1536 1688 1936 900 903 909 942 0x40 0x6
<7> [47.772973] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1440x900": 60 106500 1440 1520 1672 1904 900 903 909 934 0x40 0x6
<7> [47.772977] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x960": 60 108000 1280 1376 1488 1800 960 961 964 1000 0x40 0x5
<7> [47.772980] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1360x768": 60 84963 1366 1431 1567 1776 768 771 781 798 0x0 0x6
<7> [47.772984] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1360x768": 60 85500 1360 1424 1536 1792 768 771 777 795 0x40 0x5
<7> [47.772988] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x800": 75 106500 1280 1360 1488 1696 800 803 809 838 0x40 0x6
<7> [47.772991] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x800": 60 83500 1280 1352 1480 1680 800 803 809 831 0x40 0x6
<7> [47.772995] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1152x864": 75 108000 1152 1216 1344 1600 864 865 868 900 0x40 0x5
<7> [47.772998] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x768": 75 102250 1280 1360 1488 1696 768 771 778 805 0x40 0x6
<7> [47.773002] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x768": 60 79500 1280 1344 1472 1664 768 771 778 798 0x40 0x6
<7> [47.773005] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x720": 60 74250 1280 1390 1430 1650 720 725 730 750 0x40 0x5
<7> [47.773009] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1024x768": 75 78750 1024 1040 1136 1312 768 769 772 800 0x40 0x5
<7> [47.773012] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1024x768": 70 75000 1024 1048 1184 1328 768 771 777 806 0x40 0xa
<7> [47.773016] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [47.773019] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1024x576": 60 46970 1024 1064 1168 1312 576 577 580 597 0x0 0x6
<7> [47.773023] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "832x624": 75 57284 832 864 928 1152 624 625 628 667 0x40 0xa
<7> [47.773026] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "800x600": 75 49500 800 816 896 1056 600 601 604 625 0x40 0x5
<7> [47.773030] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "800x600": 72 50000 800 856 976 1040 600 637 643 666 0x40 0x5
<7> [47.773033] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "800x600": 60 40000 800 840 968 1056 600 601 605 628 0x40 0x5
<7> [47.773037] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "800x600": 56 36000 800 824 896 1024 600 601 603 625 0x40 0x5
<7> [47.773040] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "848x480": 60 33750 848 864 976 1088 480 486 494 517 0x40 0x5
<7> [47.773044] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "640x480": 75 31500 640 656 720 840 480 481 484 500 0x40 0xa
<7> [47.773047] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "640x480": 73 31500 640 664 704 832 480 489 492 520 0x40 0xa
<7> [47.773051] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "640x480": 60 25200 640 656 752 800 480 490 492 525 0x40 0xa
<7> [47.773054] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "640x480": 60 25175 640 656 752 800 480 490 492 525 0x40 0xa
<7> [47.773058] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "720x400": 70 28320 720 738 846 900 400 412 414 449 0x40 0x6
<7> [47.773100] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:598:DP-1]
<7> [47.773104] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:598:DP-1]
<7> [47.777447] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:598:DP-1] disconnected
<7> [47.777465] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:608:DP-2]
<7> [47.777469] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:608:DP-2]
<7> [47.778035] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:608:DP-2] disconnected
<7> [47.778052] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:617:DP-3]
<7> [47.778056] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:617:DP-3]
<7> [47.778382] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:617:DP-3] disconnected
<7> [47.778395] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:626:DP-4]
<7> [47.778399] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:626:DP-4]
<7> [47.778716] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:626:DP-4] disconnected
<6> [47.779227] [IGT] kms_flip: starting subtest flip-vs-blocking-wf-vblank
<6> [47.781868] [IGT] kms_flip: starting dynamic subtest A-HDMI-A1
<7> [47.782270] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:637]
<7> [47.782366] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:638]
<7> [47.851898] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:171:pipe A]
<7> [47.852363] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [47.852690] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [47.853019] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [47.853460] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [47.853909] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [47.854336] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [47.854740] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [47.855154] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2080, found 0)
<7> [47.855518] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [47.855904] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2080, found 0)
<7> [47.856264] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 1968, found 0)
<7> [47.856636] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2000, found 0)
<7> [47.857395] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [47.857702] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [47.858026] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1083, found 0)
<7> [47.858319] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1088, found 0)
<7> [47.858610] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1144, found 0)
<7> [47.858922] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1144, found 0)
<7> [47.859213] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [47.859503] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2080, found 0)
<7> [47.859801] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [47.860098] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2080, found 0)
<7> [47.860725] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 1968, found 0)
<7> [47.860994] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2000, found 0)
<7> [47.861231] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [47.861468] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [47.861709] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1083, found 0)
<7> [47.861967] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1088, found 0)
<7> [47.862204] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1144, found 0)
<7> [47.862440] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1144, found 0)
<7> [47.862676] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [47.862934] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [47.863174] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [47.863420] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [47.863664] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [47.863923] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [47.864170] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 285500, found 0)
<7> [47.864411] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 285500, found 0)
<7> [47.864652] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in port_clock (expected 285500, found 0)
<7> [47.864911] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [47.865151] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in avi infoframe
<7> [47.865391] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [47.865625] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [47.865629] i915 0000:00:02.0: colorspace: RGB
<7> [47.865632] i915 0000:00:02.0: scan mode: Underscan
<7> [47.865635] i915 0000:00:02.0: colorimetry: No Data
<7> [47.865638] i915 0000:00:02.0: picture aspect: No Data
<7> [47.865641] i915 0000:00:02.0: active aspect: Same as Picture
<7> [47.865643] i915 0000:00:02.0: itc: No Data
<7> [47.865646] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [47.865649] i915 0000:00:02.0: quantization range: Full
<7> [47.865652] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [47.865655] i915 0000:00:02.0: video code: 0
<7> [47.865658] i915 0000:00:02.0: ycc quantization range: Full
<7> [47.865661] i915 0000:00:02.0: hdmi content type: Graphics
<7> [47.865664] i915 0000:00:02.0: pixel repeat: 0
<7> [47.865666] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [47.865670] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [47.865927] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in spd infoframe
<7> [47.866162] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [47.866396] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [47.866399] i915 0000:00:02.0: vendor: Intel
<7> [47.866403] i915 0000:00:02.0: product: Integrated gfx
<7> [47.866406] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [47.866409] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [47.866647] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hdmi infoframe
<7> [47.866902] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [47.867140] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [47.867143] i915 0000:00:02.0: empty frame
<7> [47.867146] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [47.867383] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:171:pipe A] fastset requirement not met, forcing full modeset
<7> [47.867620] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:171:pipe A] releasing DPLL 0
<7> [47.867930] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [47.868170] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:171:pipe A] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x1 -> 0x0
<7> [47.868473] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:35:plane 1A] ddb ( 0 - 1993) -> ( 0 - 0), size 1993 -> 0
<7> [47.868709] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:165:cursor A] ddb (1993 - 2048) -> ( 0 - 0), size 55 -> 0
<7> [47.868967] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [47.869203] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] lines 1, 8, 8, 8, 8, 11, 16, 16, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [47.869438] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] blocks 16, 129, 129, 129, 129, 177, 257, 257, 30, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [47.869671] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] min_ddb 19, 143, 143, 143, 143, 196, 284, 284, 31, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [47.869955] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:171:pipe A] data rate 0 num active planes 0
<7> [47.870204] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 18870 required 0
<7> [47.870446] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [47.870720] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:171:pipe A] min cdclk: 192000 kHz -> 0 kHz
<7> [47.871023] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 17844 kHz -> 0 kHz
<7> [47.871309] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [47.871549] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 172800 kHz, actual 172800 kHz
<7> [47.871798] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [47.872039] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:171:pipe A] enable: no [modeset]
<7> [47.872283] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:plane 1A] fb: [NOFB], visible: no
<7> [47.872527] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [47.872766] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [47.873035] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [47.873275] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [47.873514] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:145:plane 6A] fb: [NOFB], visible: no
<7> [47.873751] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:155:plane 7A] fb: [NOFB], visible: no
<7> [47.874019] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:165:cursor A] fb: [NOFB], visible: no
<7> [47.874435] i915 0000:00:02.0: [drm:intel_audio_codec_disable [i915]] [CONNECTOR:588:HDMI-A-1][ENCODER:587:DDI B/PHY B] Disable audio codec on [CRTC:171:pipe A]
<7> [47.890576] i915 0000:00:02.0: [drm:intel_audio_component_get_eld [i915]] Not valid for port B
<7> [47.901800] i915 0000:00:02.0: [drm:intel_hdmi_handle_sink_scrambling [i915]] [CONNECTOR:588:HDMI-A-1] scrambling=no, TMDS bit clock ratio=1/10
<7> [47.902566] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [47.902833] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [47.903466] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [47.903726] i915 0000:00:02.0: [drm:drm_scdc_set_high_tmds_clock_ratio [drm_display_helper]] [CONNECTOR:588:HDMI-A-1] Failed to read TMDS config: -6
<7> [47.903752] i915 0000:00:02.0: [drm:intel_ddi_disable [i915]] [CONNECTOR:588:HDMI-A-1] Failed to reset sink scrambling/TMDS bit clock ratio
<7> [47.904204] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [47.908814] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_B
<7> [47.909131] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x1, on? 1) for [CRTC:171:pipe A]
<7> [47.909484] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0
<7> [47.909746] i915 0000:00:02.0: [drm:__intel_fbc_disable [i915]] Disabling FBC on [PLANE:35:plane 1A]
<7> [47.910687] i915 0000:00:02.0: [drm:intel_set_cdclk [i915]] Pre changing CDCLK to 172800 kHz, VCO 345600 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0
<7> [47.911223] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:587:DDI B/PHY B]
<7> [47.911490] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:597:DDI TC1/PHY TC1]
<7> [47.911661] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:599:DP-MST A]
<7> [47.911841] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:600:DP-MST B]
<7> [47.912015] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:601:DP-MST C]
<7> [47.912192] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:602:DP-MST D]
<7> [47.912356] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:607:DDI TC2/PHY TC2]
<7> [47.912515] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:609:DP-MST A]
<7> [47.912675] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DP-MST B]
<7> [47.912870] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:611:DP-MST C]
<7> [47.913060] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:612:DP-MST D]
<7> [47.913219] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:616:DDI TC3/PHY TC3]
<7> [47.913376] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:618:DP-MST A]
<7> [47.913534] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:619:DP-MST B]
<7> [47.913691] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:620:DP-MST C]
<7> [47.913861] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:621:DP-MST D]
<7> [47.914047] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:625:DDI TC4/PHY TC4]
<7> [47.914203] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST A]
<7> [47.914360] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:628:DP-MST B]
<7> [47.914516] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:629:DP-MST C]
<7> [47.914674] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:630:DP-MST D]
<7> [47.914860] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:588:HDMI-A-1]
<7> [47.915423] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [47.915741] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_3
<7> [47.916366] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_2
<7> [47.916632] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:171:pipe A]
<7> [47.917988] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:309:pipe B]
<7> [47.918192] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:447:pipe C]
<7> [47.918332] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:585:pipe D]
<7> [47.918477] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:171:pipe A]
<7> [47.918509] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:588:HDMI-A-1]
<7> [47.918582] i915 0000:00:02.0: [drm:compute_baseline_pipe_bpp [i915]] [CONNECTOR:588:HDMI-A-1] Limiting target display pipe bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36)
<7> [47.918772] i915 0000:00:02.0: [drm:intel_hdmi_compute_output_format [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [47.918972] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:171:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [47.919141] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:587:DDI B/PHY B] [CRTC:171:pipe A]
<7> [47.919301] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [47.919469] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [47.919634] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [47.919812] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [47.919979] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [47.920144] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [47.920306] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [47.920467] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2080)
<7> [47.920628] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [47.920820] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2080)
<7> [47.920981] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 1968)
<7> [47.921142] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2000)
<7> [47.921309] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [47.921469] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [47.921633] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1083)
<7> [47.921852] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1088)
<7> [47.922070] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1144)
<7> [47.922297] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1144)
<7> [47.922539] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [47.922771] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2080)
<7> [47.923017] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [47.923251] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2080)
<7> [47.923492] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 1968)
<7> [47.923720] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2000)
<7> [47.924056] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [47.924291] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [47.924532] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1083)
<7> [47.924764] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1088)
<7> [47.925016] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1144)
<7> [47.925179] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1144)
<7> [47.925341] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [47.925503] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [47.925663] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [47.925837] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in has_hdmi_sink (expected no, found yes)
<7> [47.925997] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in has_infoframe (expected no, found yes)
<7> [47.926155] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [47.926339] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 285500)
<7> [47.926499] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 285500)
<7> [47.926657] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in port_clock (expected 0, found 285500)
<7> [47.926828] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071)
<7> [47.926988] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in avi infoframe
<7> [47.927149] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [47.927307] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [47.927466] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [47.927468] i915 0000:00:02.0: colorspace: RGB
<7> [47.927470] i915 0000:00:02.0: scan mode: Underscan
<7> [47.927471] i915 0000:00:02.0: colorimetry: No Data
<7> [47.927473] i915 0000:00:02.0: picture aspect: No Data
<7> [47.927474] i915 0000:00:02.0: active aspect: Same as Picture
<7> [47.927475] i915 0000:00:02.0: itc: No Data
<7> [47.927477] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [47.927478] i915 0000:00:02.0: quantization range: Full
<7> [47.927480] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [47.927481] i915 0000:00:02.0: video code: 0
<7> [47.927483] i915 0000:00:02.0: ycc quantization range: Full
<7> [47.927484] i915 0000:00:02.0: hdmi content type: Graphics
<7> [47.927486] i915 0000:00:02.0: pixel repeat: 0
<7> [47.927487] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [47.927489] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in spd infoframe
<7> [47.927648] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [47.927814] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [47.927973] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [47.927975] i915 0000:00:02.0: vendor: Intel
<7> [47.927976] i915 0000:00:02.0: product: Integrated gfx
<7> [47.927978] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [47.927980] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hdmi infoframe
<7> [47.928144] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [47.928324] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [47.928483] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [47.928485] i915 0000:00:02.0: empty frame
<7> [47.928488] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:171:pipe A] fastset requirement not met, forcing full modeset
<7> [47.928684] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [47.928859] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:171:pipe A] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x1
<7> [47.929114] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:35:plane 1A] ddb ( 0 - 0) -> ( 0 - 1993), size 0 -> 1993
<7> [47.929342] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:165:cursor A] ddb ( 0 - 0) -> (1993 - 2048), size 0 -> 55
<7> [47.929567] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm
<7> [47.929798] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 8, 8, 8, 8, 11, 16, 16, 0, 0, 0
<7> [47.930016] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 129, 129, 129, 129, 177, 257, 257, 30, 0, 0
<7> [47.930246] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 19, 143, 143, 143, 143, 196, 284, 284, 31, 0, 0
<7> [47.930503] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:171:pipe A] data rate 1142000 num active planes 1
<7> [47.930734] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14986 required 1142
<7> [47.930970] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [47.931228] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:171:pipe A] min cdclk: 0 kHz -> 192000 kHz
<7> [47.931483] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 0 kHz -> 17844 kHz
<7> [47.931685] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [47.931878] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 192000 kHz, actual 192000 kHz
<7> [47.932068] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [47.932244] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:171:pipe A] allocated DPLL 0
<7> [47.932425] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:171:pipe A] reserving DPLL 0
<7> [47.932596] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:171:pipe A] enable: yes [modeset]
<7> [47.932758] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [47.932997] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [47.933181] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [47.933341] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [47.933499] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [47.933658] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [47.933828] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [47.934054] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] GCP: 0x0
<7> [47.934295] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [47.934299] i915 0000:00:02.0: colorspace: RGB
<7> [47.934302] i915 0000:00:02.0: scan mode: Underscan
<7> [47.934304] i915 0000:00:02.0: colorimetry: No Data
<7> [47.934307] i915 0000:00:02.0: picture aspect: No Data
<7> [47.934309] i915 0000:00:02.0: active aspect: Same as Picture
<7> [47.934312] i915 0000:00:02.0: itc: No Data
<7> [47.934314] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [47.934317] i915 0000:00:02.0: quantization range: Full
<7> [47.934319] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [47.934322] i915 0000:00:02.0: video code: 0
<7> [47.934324] i915 0000:00:02.0: ycc quantization range: Full
<7> [47.934326] i915 0000:00:02.0: hdmi content type: Graphics
<7> [47.934329] i915 0000:00:02.0: pixel repeat: 0
<7> [47.934331] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [47.934334] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [47.934337] i915 0000:00:02.0: vendor: Intel
<7> [47.934340] i915 0000:00:02.0: product: Integrated gfx
<7> [47.934342] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [47.934345] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [47.934348] i915 0000:00:02.0: empty frame
<7> [47.934350] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 10 00 0f 00 6d a2 00 7f 00 00 00 00 00 00 00 00
<7> [47.934575] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 0d 82 34 12 66 69 74 48 65 61 64 6c 65 73 73 47
<7> [47.934811] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 53 7f 7f 28 0f 7f 07 17 7f ff 3f 7f ff 47 7f ff
<7> [47.935033] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 5f 7f 82 7f 7f 00 7f 7f 51 7f 7f 30 77 7f 03 00
<7> [47.935268] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [47.935498] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [47.935727] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: yes, vmin: 1144, vmax: 1144, flipline: 1144, pipeline full: 61, guardband: 63 vsync start: 0, vsync end: 0
<7> [47.935948] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 1081, vmax vblank: 1081, vmin vtotal: 1144, vmax vtotal: 1144
<7> [47.936184] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [47.936414] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 120 285500 1920 1968 2000 2080 1080 1083 1088 1144 0x48 0x5
<7> [47.936653] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 120 285500 1920 1968 2000 2080 1080 1083 1088 1144 0x48 0x5
<7> [47.936882] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=285500, hd=1920 hb=1920-2080 hs=1968-2000 ht=2080, vd=1080 vb=1080-1144 vs=1083-1088 vt=1144, flags=0x5
<7> [47.937121] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 120 285500 1920 1968 2000 2080 1080 1083 1088 1144 0x40 0x5
<7> [47.937365] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=285500, hd=1920 hb=1920-2080 hs=1968-2000 ht=2080, vd=1080 vb=1080-1144 vs=1083-1088 vt=1144, flags=0x5
<7> [47.937604] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 285500, pixel rate 285500, min cdclk 192000, min voltage level 0
<7> [47.937826] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 59, ips linetime: 0
<7> [47.938057] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0
<7> [47.938289] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1920x1080+0+0
<7> [47.938508] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [47.938783] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [47.939004] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [47.939238] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: cfgcr0: 0x1801be, cfgcr1: 0x488, div0: 0x0, mg_refclkin_ctl: 0x0, hg_clktop2_coreclkctl1: 0x0, mg_clktop2_hsclkctl: 0x0, mg_pll_div0: 0x0, mg_pll_div2: 0x0, mg_pll_lf: 0x0, mg_pll_frac_lock: 0x0, mg_pll_ssc: 0x0, mg_pll_bias: 0x0, mg_pll_tdc_coldst_bias: 0x0
<7> [47.939467] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [47.939679] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [47.939880] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [47.940091] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [47.940318] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [47.940566] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [47.940803] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [47.941025] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [47.941248] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [47.941481] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [47.941701] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [47.941931] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [47.942177] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:plane 1A] fb: [FB:637] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [47.942420] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [47.942646] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [47.942871] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [47.943108] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [47.943335] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [47.943581] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [47.943817] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:145:plane 6A] fb: [NOFB], visible: no
<7> [47.944051] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:155:plane 7A] fb: [NOFB], visible: no
<7> [47.944272] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:165:cursor A] fb: [NOFB], visible: no
<7> [47.946736] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_2
<7> [47.947010] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_3
<7> [47.947279] i915 0000:00:02.0: [drm:intel_set_cdclk [i915]] Pre changing CDCLK to 192000 kHz, VCO 384000 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0
<7> [47.947739] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:587:DDI B/PHY B]
<7> [47.947979] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:597:DDI TC1/PHY TC1]
<7> [47.948217] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:599:DP-MST A]
<7> [47.948451] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:600:DP-MST B]
<7> [47.948683] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:601:DP-MST C]
<7> [47.948910] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:602:DP-MST D]
<7> [47.949156] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:607:DDI TC2/PHY TC2]
<7> [47.949392] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:609:DP-MST A]
<7> [47.949619] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DP-MST B]
<7> [47.949839] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:611:DP-MST C]
<7> [47.950062] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:612:DP-MST D]
<7> [47.950309] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:616:DDI TC3/PHY TC3]
<7> [47.950541] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:618:DP-MST A]
<7> [47.950773] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:619:DP-MST B]
<7> [47.951003] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:620:DP-MST C]
<7> [47.951268] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:621:DP-MST D]
<7> [47.951490] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:625:DDI TC4/PHY TC4]
<7> [47.951728] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST A]
<7> [47.951951] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:628:DP-MST B]
<7> [47.952193] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:629:DP-MST C]
<7> [47.952428] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:630:DP-MST D]
<7> [47.952880] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [47.953238] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:171:pipe A]
<7> [47.953431] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0
<7> [47.953775] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_B
<7> [47.954205] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [47.954588] i915 0000:00:02.0: [drm:intel_hdmi_handle_sink_scrambling [i915]] [CONNECTOR:588:HDMI-A-1] scrambling=no, TMDS bit clock ratio=1/10
<7> [47.955233] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [47.955387] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [47.955938] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [47.956113] i915 0000:00:02.0: [drm:drm_scdc_set_high_tmds_clock_ratio [drm_display_helper]] [CONNECTOR:588:HDMI-A-1] Failed to read TMDS config: -6
<7> [47.956130] i915 0000:00:02.0: [drm:intel_ddi_enable [i915]] [CONNECTOR:588:HDMI-A-1] Failed to configure sink scrambling/TMDS bit clock ratio
<7> [47.964556] i915 0000:00:02.0: [drm:intel_audio_component_get_power [i915]] restored AUD_FREQ_CNTRL to 0x810
<7> [47.964844] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1
<7> [47.965060] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:35:plane 1A]
<7> [47.968887] azx_init_pci: snd_hda_intel 0000:00:1f.3: Clearing TCSEL
<7> [47.968925] azx_init_pci: snd_hda_intel 0000:00:1f.3: SCH snoop: Enabled
<7> [47.973098] i915 0000:00:02.0: [drm:intel_audio_codec_enable [i915]] [CONNECTOR:588:HDMI-A-1][ENCODER:587:DDI B/PHY B] Enable audio codec on [CRTC:171:pipe A], 64 bytes ELD
<7> [47.981444] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] HDMI audio pixel clock setting for 285500 not found, falling back to defaults
<7> [47.981651] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] Configuring HDMI audio for pixel clock 25200 (0x00010000)
<7> [47.981835] i915 0000:00:02.0: [drm:hsw_audio_config_update [i915]] using automatic N
<6> [47.982039] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 5 not expected
<6> [47.982049] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 0 not expected
<6> [47.982052] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 10 not expected
<6> [47.982055] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 6 not expected
<7> [47.982215] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:588:HDMI-A-1]
<7> [47.982490] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:171:pipe A]
<7> [48.780135] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port G/TC#4: TC port mode reset (tbt-alt -> disconnected) pin assignment: - max lanes: 4
<7> [48.780291] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling TC_cold_off
<7> [48.780224] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port E/TC#2: TC port mode reset (tbt-alt -> disconnected) pin assignment: - max lanes: 4
<7> [48.780214] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port F/TC#3: TC port mode reset (tbt-alt -> disconnected) pin assignment: - max lanes: 4
<7> [48.780746] i915 0000:00:02.0: [drm:tgl_tc_cold_request [i915]] TC cold unblock succeeded
<7> [48.781265] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port D/TC#1: TC port mode reset (legacy -> disconnected) pin assignment: - max lanes: 4
<7> [58.450906] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:171:pipe A]
<7> [58.451131] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [58.451592] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [58.452065] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [58.452522] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [58.452983] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [58.453422] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [58.453855] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [58.454289] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2080, found 0)
<7> [58.454695] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [58.455141] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2080, found 0)
<7> [58.455571] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 1968, found 0)
<7> [58.456030] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2000, found 0)
<7> [58.456222] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [58.456381] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [58.456541] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1083, found 0)
<7> [58.456700] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1088, found 0)
<7> [58.456880] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1144, found 0)
<7> [58.457051] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1144, found 0)
<7> [58.457210] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [58.457369] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2080, found 0)
<7> [58.457528] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [58.457686] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2080, found 0)
<7> [58.457862] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 1968, found 0)
<7> [58.458034] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2000, found 0)
<7> [58.458194] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [58.458352] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [58.458511] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1083, found 0)
<7> [58.458670] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1088, found 0)
<7> [58.458844] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1144, found 0)
<7> [58.459018] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1144, found 0)
<7> [58.459176] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [58.459334] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [58.459493] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [58.459654] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [58.459839] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [58.460015] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [58.460176] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 285500, found 0)
<7> [58.460337] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 285500, found 0)
<7> [58.460496] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in port_clock (expected 285500, found 0)
<7> [58.460656] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [58.460836] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in avi infoframe
<7> [58.461010] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [58.461168] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [58.461170] i915 0000:00:02.0: colorspace: RGB
<7> [58.461171] i915 0000:00:02.0: scan mode: Underscan
<7> [58.461173] i915 0000:00:02.0: colorimetry: No Data
<7> [58.461174] i915 0000:00:02.0: picture aspect: No Data
<7> [58.461176] i915 0000:00:02.0: active aspect: Same as Picture
<7> [58.461177] i915 0000:00:02.0: itc: No Data
<7> [58.461179] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [58.461181] i915 0000:00:02.0: quantization range: Full
<7> [58.461182] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [58.461184] i915 0000:00:02.0: video code: 0
<7> [58.461185] i915 0000:00:02.0: ycc quantization range: Full
<7> [58.461186] i915 0000:00:02.0: hdmi content type: Graphics
<7> [58.461188] i915 0000:00:02.0: pixel repeat: 0
<7> [58.461190] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [58.461191] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [58.461351] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in spd infoframe
<7> [58.461510] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [58.461669] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [58.461670] i915 0000:00:02.0: vendor: Intel
<7> [58.461672] i915 0000:00:02.0: product: Integrated gfx
<7> [58.461673] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [58.461675] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [58.461850] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hdmi infoframe
<7> [58.462035] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [58.462192] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [58.462194] i915 0000:00:02.0: empty frame
<7> [58.462195] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [58.462354] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:171:pipe A] fastset requirement not met, forcing full modeset
<7> [58.462513] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:171:pipe A] releasing DPLL 0
<7> [58.462706] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [58.462885] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:171:pipe A] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x1 -> 0x0
<7> [58.463095] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:35:plane 1A] ddb ( 0 - 1993) -> ( 0 - 0), size 1993 -> 0
<7> [58.463256] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:165:cursor A] ddb (1993 - 2048) -> ( 0 - 0), size 55 -> 0
<7> [58.463416] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [58.463571] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] lines 1, 8, 8, 8, 8, 11, 16, 16, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [58.463743] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] blocks 16, 129, 129, 129, 129, 177, 257, 257, 30, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [58.463909] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] min_ddb 19, 143, 143, 143, 143, 196, 284, 284, 31, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [58.464084] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:171:pipe A] data rate 0 num active planes 0
<7> [58.464249] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 18870 required 0
<7> [58.464412] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [58.464593] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:171:pipe A] min cdclk: 192000 kHz -> 0 kHz
<7> [58.464806] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 17844 kHz -> 0 kHz
<7> [58.464999] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [58.465158] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 172800 kHz, actual 172800 kHz
<7> [58.465316] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [58.465478] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:171:pipe A] enable: no [modeset]
<7> [58.465641] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:plane 1A] fb: [NOFB], visible: no
<7> [58.465820] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [58.465993] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [58.466151] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [58.466310] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [58.466468] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:145:plane 6A] fb: [NOFB], visible: no
<7> [58.466628] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:155:plane 7A] fb: [NOFB], visible: no
<7> [58.466802] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:165:cursor A] fb: [NOFB], visible: no
<7> [58.467051] i915 0000:00:02.0: [drm:intel_audio_codec_disable [i915]] [CONNECTOR:588:HDMI-A-1][ENCODER:587:DDI B/PHY B] Disable audio codec on [CRTC:171:pipe A]
<7> [58.483030] i915 0000:00:02.0: [drm:intel_audio_component_get_eld [i915]] Not valid for port B
<7> [58.494377] i915 0000:00:02.0: [drm:intel_hdmi_handle_sink_scrambling [i915]] [CONNECTOR:588:HDMI-A-1] scrambling=no, TMDS bit clock ratio=1/10
<7> [58.495250] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [58.495615] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [58.496579] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [58.496984] i915 0000:00:02.0: [drm:drm_scdc_set_high_tmds_clock_ratio [drm_display_helper]] [CONNECTOR:588:HDMI-A-1] Failed to read TMDS config: -6
<7> [58.497036] i915 0000:00:02.0: [drm:intel_ddi_disable [i915]] [CONNECTOR:588:HDMI-A-1] Failed to reset sink scrambling/TMDS bit clock ratio
<7> [58.497542] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [58.500380] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_B
<7> [58.500700] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x1, on? 1) for [CRTC:171:pipe A]
<7> [58.501020] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0
<7> [58.501314] i915 0000:00:02.0: [drm:__intel_fbc_disable [i915]] Disabling FBC on [PLANE:35:plane 1A]
<7> [58.501611] i915 0000:00:02.0: [drm:intel_set_cdclk [i915]] Pre changing CDCLK to 172800 kHz, VCO 345600 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0
<7> [58.502069] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:587:DDI B/PHY B]
<7> [58.502484] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:597:DDI TC1/PHY TC1]
<7> [58.502829] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:599:DP-MST A]
<7> [58.503168] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:600:DP-MST B]
<7> [58.503523] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:601:DP-MST C]
<7> [58.503817] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:602:DP-MST D]
<7> [58.504108] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:607:DDI TC2/PHY TC2]
<7> [58.504374] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:609:DP-MST A]
<7> [58.504611] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DP-MST B]
<7> [58.504848] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:611:DP-MST C]
<7> [58.505083] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:612:DP-MST D]
<7> [58.505330] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:616:DDI TC3/PHY TC3]
<7> [58.505565] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:618:DP-MST A]
<7> [58.505811] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:619:DP-MST B]
<7> [58.506048] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:620:DP-MST C]
<7> [58.506292] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:621:DP-MST D]
<7> [58.506527] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:625:DDI TC4/PHY TC4]
<7> [58.506760] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST A]
<7> [58.506995] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:628:DP-MST B]
<7> [58.507229] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:629:DP-MST C]
<7> [58.507483] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:630:DP-MST D]
<7> [58.507718] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:588:HDMI-A-1]
<7> [58.508257] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [58.508580] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_3
<7> [58.508847] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_2
<7> [58.509127] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:171:pipe A]
<7> [58.510110] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:309:pipe B]
<7> [58.510392] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:447:pipe C]
<7> [58.510636] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:585:pipe D]
<7> [58.510884] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:171:pipe A]
<7> [58.510934] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:588:HDMI-A-1]
<7> [58.511051] i915 0000:00:02.0: [drm:compute_baseline_pipe_bpp [i915]] [CONNECTOR:588:HDMI-A-1] Limiting target display pipe bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36)
<7> [58.511324] i915 0000:00:02.0: [drm:intel_hdmi_compute_output_format [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [58.511571] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:171:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [58.511816] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:587:DDI B/PHY B] [CRTC:171:pipe A]
<7> [58.512046] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [58.512302] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [58.512545] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [58.512787] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [58.513027] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [58.513281] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [58.513521] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [58.513776] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2080)
<7> [58.514013] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [58.514260] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2080)
<7> [58.514500] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 1968)
<7> [58.514736] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2000)
<7> [58.514971] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [58.515214] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [58.515452] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1083)
<7> [58.515694] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1088)
<7> [58.515933] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1144)
<7> [58.516179] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1144)
<7> [58.516416] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [58.516651] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2080)
<7> [58.516886] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [58.517124] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2080)
<7> [58.517385] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 1968)
<7> [58.517634] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2000)
<7> [58.517875] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [58.518114] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [58.518378] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1083)
<7> [58.518616] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1088)
<7> [58.518853] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1144)
<7> [58.519093] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1144)
<7> [58.519351] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [58.519590] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [58.519827] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [58.520065] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in has_hdmi_sink (expected no, found yes)
<7> [58.520320] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in has_infoframe (expected no, found yes)
<7> [58.520556] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [58.520794] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 285500)
<7> [58.521032] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 285500)
<7> [58.521288] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in port_clock (expected 0, found 285500)
<7> [58.521524] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071)
<7> [58.521761] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in avi infoframe
<7> [58.521998] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [58.522250] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [58.522482] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [58.522486] i915 0000:00:02.0: colorspace: RGB
<7> [58.522489] i915 0000:00:02.0: scan mode: Underscan
<7> [58.522492] i915 0000:00:02.0: colorimetry: No Data
<7> [58.522495] i915 0000:00:02.0: picture aspect: No Data
<7> [58.522498] i915 0000:00:02.0: active aspect: Same as Picture
<7> [58.522501] i915 0000:00:02.0: itc: No Data
<7> [58.522504] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [58.522507] i915 0000:00:02.0: quantization range: Full
<7> [58.522510] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [58.522512] i915 0000:00:02.0: video code: 0
<7> [58.522515] i915 0000:00:02.0: ycc quantization range: Full
<7> [58.522518] i915 0000:00:02.0: hdmi content type: Graphics
<7> [58.522521] i915 0000:00:02.0: pixel repeat: 0
<7> [58.522524] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [58.522527] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in spd infoframe
<7> [58.522761] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [58.522998] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [58.523250] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [58.523253] i915 0000:00:02.0: vendor: Intel
<7> [58.523256] i915 0000:00:02.0: product: Integrated gfx
<7> [58.523259] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [58.523262] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hdmi infoframe
<7> [58.523496] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [58.523734] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [58.523970] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [58.523973] i915 0000:00:02.0: empty frame
<7> [58.523977] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:171:pipe A] fastset requirement not met, forcing full modeset
<7> [58.524275] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [58.524513] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:171:pipe A] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x1
<7> [58.524811] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:35:plane 1A] ddb ( 0 - 0) -> ( 0 - 1993), size 0 -> 1993
<7> [58.525045] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:165:cursor A] ddb ( 0 - 0) -> (1993 - 2048), size 0 -> 55
<7> [58.525299] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm
<7> [58.525528] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 8, 8, 8, 8, 11, 16, 16, 0, 0, 0
<7> [58.525762] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 129, 129, 129, 129, 177, 257, 257, 30, 0, 0
<7> [58.525995] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 19, 143, 143, 143, 143, 196, 284, 284, 31, 0, 0
<7> [58.526274] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:171:pipe A] data rate 1142000 num active planes 1
<7> [58.526517] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14986 required 1142
<7> [58.526756] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [58.527018] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:171:pipe A] min cdclk: 0 kHz -> 192000 kHz
<7> [58.527314] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 0 kHz -> 17844 kHz
<7> [58.527596] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [58.527617] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_2
<7> [58.527838] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 192000 kHz, actual 192000 kHz
<7> [58.528088] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [58.528326] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:171:pipe A] allocated DPLL 0
<7> [58.528439] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_3
<7> [58.528576] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:171:pipe A] reserving DPLL 0
<7> [58.528773] i915 0000:00:02.0: [drm:intel_audio_component_get_power [i915]] restored AUD_FREQ_CNTRL to 0x810
<7> [58.528831] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:171:pipe A] enable: yes [modeset]
<7> [58.529099] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [58.529362] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [58.529604] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [58.529843] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [58.530094] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [58.530334] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [58.530573] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [58.530810] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] GCP: 0x0
<7> [58.531052] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [58.531055] i915 0000:00:02.0: colorspace: RGB
<7> [58.531058] i915 0000:00:02.0: scan mode: Underscan
<7> [58.531061] i915 0000:00:02.0: colorimetry: No Data
<7> [58.531064] i915 0000:00:02.0: picture aspect: No Data
<7> [58.531067] i915 0000:00:02.0: active aspect: Same as Picture
<7> [58.531070] i915 0000:00:02.0: itc: No Data
<7> [58.531073] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [58.531076] i915 0000:00:02.0: quantization range: Full
<7> [58.531078] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [58.531081] i915 0000:00:02.0: video code: 0
<7> [58.531084] i915 0000:00:02.0: ycc quantization range: Full
<7> [58.531087] i915 0000:00:02.0: hdmi content type: Graphics
<7> [58.531090] i915 0000:00:02.0: pixel repeat: 0
<7> [58.531093] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [58.531097] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [58.531100] i915 0000:00:02.0: vendor: Intel
<7> [58.531103] i915 0000:00:02.0: product: Integrated gfx
<7> [58.531106] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [58.531109] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [58.531112] i915 0000:00:02.0: empty frame
<7> [58.531115] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 10 00 0f 00 6d a2 00 7f 00 00 00 00 00 00 00 00
<7> [58.531351] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 0d 82 34 12 66 69 74 48 65 61 64 6c 65 73 73 47
<7> [58.531588] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 53 7f 7f 28 0f 7f 07 17 7f ff 3f 7f ff 47 7f ff
<7> [58.531824] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 5f 7f 82 7f 7f 00 7f 7f 51 7f 7f 30 77 7f 03 00
<7> [58.532076] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [58.532312] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [58.532548] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: yes, vmin: 1144, vmax: 1144, flipline: 1144, pipeline full: 61, guardband: 63 vsync start: 0, vsync end: 0
<7> [58.532784] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 1081, vmax vblank: 1081, vmin vtotal: 1144, vmax vtotal: 1144
<7> [58.533029] azx_init_pci: snd_hda_intel 0000:00:1f.3: Clearing TCSEL
<7> [58.533071] azx_init_pci: snd_hda_intel 0000:00:1f.3: SCH snoop: Enabled
<7> [58.533029] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [58.533264] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 120 285500 1920 1968 2000 2080 1080 1083 1088 1144 0x48 0x5
<7> [58.533499] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 120 285500 1920 1968 2000 2080 1080 1083 1088 1144 0x48 0x5
<7> [58.533737] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=285500, hd=1920 hb=1920-2080 hs=1968-2000 ht=2080, vd=1080 vb=1080-1144 vs=1083-1088 vt=1144, flags=0x5
<7> [58.533981] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 120 285500 1920 1968 2000 2080 1080 1083 1088 1144 0x40 0x5
<7> [58.534222] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=285500, hd=1920 hb=1920-2080 hs=1968-2000 ht=2080, vd=1080 vb=1080-1144 vs=1083-1088 vt=1144, flags=0x5
<7> [58.534459] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 285500, pixel rate 285500, min cdclk 192000, min voltage level 0
<7> [58.534694] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 59, ips linetime: 0
<7> [58.534932] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0
<7> [58.535194] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1920x1080+0+0
<7> [58.535434] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [58.535674] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [58.535912] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [58.536173] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: cfgcr0: 0x1801be, cfgcr1: 0x488, div0: 0x0, mg_refclkin_ctl: 0x0, hg_clktop2_coreclkctl1: 0x0, mg_clktop2_hsclkctl: 0x0, mg_pll_div0: 0x0, mg_pll_div2: 0x0, mg_pll_lf: 0x0, mg_pll_frac_lock: 0x0, mg_pll_ssc: 0x0, mg_pll_bias: 0x0, mg_pll_tdc_coldst_bias: 0x0
<7> [58.536412] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [58.536653] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [58.536893] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [58.537156] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [58.537393] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [58.537627] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [58.537861] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [58.538256] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [58.538494] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [58.538734] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [58.539027] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [58.539276] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [58.539515] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:plane 1A] fb: [FB:637] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [58.539752] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [58.540001] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [58.540238] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [58.540475] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [58.540733] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_3
<7> [58.540714] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [58.540953] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_2
<7> [58.540960] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [58.541198] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:145:plane 6A] fb: [NOFB], visible: no
<7> [58.541433] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:155:plane 7A] fb: [NOFB], visible: no
<7> [58.541673] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:165:cursor A] fb: [NOFB], visible: no
<7> [58.542163] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_2
<7> [58.542429] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_3
<7> [58.542702] i915 0000:00:02.0: [drm:intel_set_cdclk [i915]] Pre changing CDCLK to 192000 kHz, VCO 384000 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0
<7> [58.543061] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:587:DDI B/PHY B]
<7> [58.543307] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:597:DDI TC1/PHY TC1]
<7> [58.543549] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:599:DP-MST A]
<7> [58.543787] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:600:DP-MST B]
<7> [58.544051] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:601:DP-MST C]
<7> [58.544297] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:602:DP-MST D]
<7> [58.544534] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:607:DDI TC2/PHY TC2]
<7> [58.544772] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:609:DP-MST A]
<7> [58.545024] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DP-MST B]
<7> [58.545261] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:611:DP-MST C]
<7> [58.545496] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:612:DP-MST D]
<7> [58.545731] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:616:DDI TC3/PHY TC3]
<7> [58.545984] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:618:DP-MST A]
<7> [58.546217] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:619:DP-MST B]
<7> [58.546452] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:620:DP-MST C]
<7> [58.546690] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:621:DP-MST D]
<7> [58.546938] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:625:DDI TC4/PHY TC4]
<7> [58.547178] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST A]
<7> [58.547414] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:628:DP-MST B]
<7> [58.547646] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:629:DP-MST C]
<7> [58.547892] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:630:DP-MST D]
<7> [58.548369] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [58.548719] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:171:pipe A]
<7> [58.548982] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0
<7> [58.549271] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_B
<7> [58.549620] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [58.550129] i915 0000:00:02.0: [drm:intel_hdmi_handle_sink_scrambling [i915]] [CONNECTOR:588:HDMI-A-1] scrambling=no, TMDS bit clock ratio=1/10
<7> [58.550775] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [58.551031] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [58.551660] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [58.551917] i915 0000:00:02.0: [drm:drm_scdc_set_high_tmds_clock_ratio [drm_display_helper]] [CONNECTOR:588:HDMI-A-1] Failed to read TMDS config: -6
<7> [58.551945] i915 0000:00:02.0: [drm:intel_ddi_enable [i915]] [CONNECTOR:588:HDMI-A-1] Failed to configure sink scrambling/TMDS bit clock ratio
<7> [58.560644] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1
<7> [58.560934] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:35:plane 1A]
<7> [58.568988] i915 0000:00:02.0: [drm:intel_audio_codec_enable [i915]] [CONNECTOR:588:HDMI-A-1][ENCODER:587:DDI B/PHY B] Enable audio codec on [CRTC:171:pipe A], 64 bytes ELD
<7> [58.577360] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] HDMI audio pixel clock setting for 285500 not found, falling back to defaults
<7> [58.577571] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] Configuring HDMI audio for pixel clock 25200 (0x00010000)
<7> [58.577733] i915 0000:00:02.0: [drm:hsw_audio_config_update [i915]] using automatic N
<6> [58.577901] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 5 not expected
<6> [58.577904] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 0 not expected
<6> [58.577906] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 10 not expected
<6> [58.577907] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 6 not expected
<7> [58.578046] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:588:HDMI-A-1]
<7> [58.578303] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:171:pipe A]
<7> [58.614060] i915 0000:00:02.0: [drm:intel_audio_component_get_power [i915]] restored AUD_FREQ_CNTRL to 0x810
<7> [58.618634] azx_init_pci: snd_hda_intel 0000:00:1f.3: Clearing TCSEL
<7> [58.618674] azx_init_pci: snd_hda_intel 0000:00:1f.3: SCH snoop: Enabled
<6> [66.404509] [IGT] kms_flip: finished subtest A-HDMI-A1, FAIL
|