Result:
integration-manifest git-log-oneline i915_display_info12 igt_runner12 runtimes12 results12.json results12-i915-load.json guc_logs12.tar i915_display_info_post_exec12 boot12 dmesg12
| Detail | Value |
|---|---|
| Duration | 18.45 seconds |
| Hostname |
shard-tglu-6 |
| Igt-Version |
IGT-Version: 2.4-ge70db143b (x86_64) (Linux: 7.0.0-CI_DRM_18359-g021ccd6f29b3+ x86_64) |
| Out |
Using IGT_SRANDOM=1776974115 for randomisation Opened device: /dev/dri/card0 Using monotonic timestamps Starting subtest: wf_vblank-ts-check-interruptible Starting dynamic subtest: A-HDMI-A1 1920x1080: 120 285500 1920 1968 2000 2080 1080 1083 1088 1144 0x48 0x5 Expected frametime: 8335us; measured 8670.4us +- 2.918us accuracy 0.10% vblank interval differs from modeline! expected 8334.6us, measured 8670us +- 2.918us, difference 335.8us (115.1 sigma) Event vblank: expected 866, counted 880, passrate = 13.64%, encoder type 2 Expected frametime: 8335us; measured 8411.6us +- 3.540us accuracy 0.13% vblank interval differs from modeline! expected 8334.6us, measured 8412us +- 3.540us, difference 77.0us (21.8 sigma) Event vblank: expected 896, counted 900, passrate = 73.33%, encoder type 2 Stack trace: #0 ../lib/igt_core.c:2075 __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:2220 __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.447s) |
| Err |
Starting subtest: wf_vblank-ts-check-interruptible Starting dynamic subtest: A-HDMI-A1 (kms_flip:2087) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1722: (kms_flip:2087) CRITICAL: Failed assertion: !retried (kms_flip:2087) CRITICAL: Last errno: 4, Interrupted system call Dynamic subtest A-HDMI-A1 failed. **** DEBUG **** (kms_flip:2087) DEBUG: name = vblank last_ts = 55.367203 last_received_ts = 55.366943 last_seq = 1759 current_ts = 55.451862 current_received_ts = 55.451580 current_seq = 1769 count = 78 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 55.367203/1759, current 55.451862/1769: elapsed=84658.0us expected=86703.8us +- 433.5us, error 2.4% (kms_flip:2087) DEBUG: inconsistent vblank ts/seq: last 55.367203/1759, current 55.451862/1769: elapsed=84658.0us expected=86703.8us (kms_flip:2087) DEBUG: name = vblank last_ts = 55.451862 last_received_ts = 55.451580 last_seq = 1769 current_ts = 55.536530 current_received_ts = 55.536194 current_seq = 1779 count = 79 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 55.451862/1769, current 55.536530/1779: elapsed=84668.0us expected=86703.8us +- 433.5us, error 2.3% (kms_flip:2087) DEBUG: inconsistent vblank ts/seq: last 55.451862/1769, current 55.536530/1779: elapsed=84668.0us expected=86703.8us (kms_flip:2087) DEBUG: name = vblank last_ts = 55.536530 last_received_ts = 55.536194 last_seq = 1779 current_ts = 55.621185 current_received_ts = 55.620811 current_seq = 1789 count = 80 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 55.536530/1779, current 55.621185/1789: elapsed=84657.0us expected=86703.8us +- 433.5us, error 2.4% (kms_flip:2087) DEBUG: inconsistent vblank ts/seq: last 55.536530/1779, current 55.621185/1789: elapsed=84657.0us expected=86703.8us (kms_flip:2087) DEBUG: name = vblank last_ts = 55.621185 last_received_ts = 55.620811 last_seq = 1789 current_ts = 55.705853 current_received_ts = 55.705853 current_seq = 1799 count = 81 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 55.621185/1789, current 55.705853/1799: elapsed=84666.0us expected=86703.8us +- 433.5us, error 2.4% (kms_flip:2087) DEBUG: inconsistent vblank ts/seq: last 55.621185/1789, current 55.705853/1799: elapsed=84666.0us expected=86703.8us (kms_flip:2087) DEBUG: name = vblank last_ts = 55.705853 last_received_ts = 55.705853 last_seq = 1799 current_ts = 55.790421 current_received_ts = 55.790146 current_seq = 1809 count = 82 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 55.705853/1799, current 55.790421/1809: elapsed=84568.0us expected=86703.8us +- 433.5us, error 2.5% (kms_flip:2087) DEBUG: inconsistent vblank ts/seq: last 55.705853/1799, current 55.790421/1809: elapsed=84568.0us expected=86703.8us (kms_flip:2087) DEBUG: name = vblank last_ts = 55.790421 last_received_ts = 55.790146 last_seq = 1809 current_ts = 55.874916 current_received_ts = 55.874607 current_seq = 1819 count = 83 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 55.790421/1809, current 55.874916/1819: elapsed=84497.0us expected=86703.8us +- 433.5us, error 2.5% (kms_flip:2087) DEBUG: inconsistent vblank ts/seq: last 55.790421/1809, current 55.874916/1819: elapsed=84497.0us expected=86703.8us (kms_flip:2087) DEBUG: name = vblank last_ts = 55.874916 last_received_ts = 55.874607 last_seq = 1819 current_ts = 55.959419 current_received_ts = 55.959076 current_seq = 1829 count = 84 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 55.874916/1819, current 55.959419/1829: elapsed=84503.0us expected=86703.8us +- 433.5us, error 2.5% (kms_flip:2087) DEBUG: inconsistent vblank ts/seq: last 55.874916/1819, current 55.959419/1829: elapsed=84503.0us expected=86703.8us (kms_flip:2087) DEBUG: name = vblank last_ts = 55.959419 last_received_ts = 55.959076 last_seq = 1829 current_ts = 56.043915 current_received_ts = 56.043549 current_seq = 1839 count = 85 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 55.959419/1829, current 56.043915/1839: elapsed=84493.0us expected=86703.8us +- 433.5us, error 2.5% (kms_flip:2087) DEBUG: inconsistent vblank ts/seq: last 55.959419/1829, current 56.043915/1839: elapsed=84493.0us expected=86703.8us (kms_flip:2087) DEBUG: name = vblank last_ts = 56.043915 last_received_ts = 56.043549 last_seq = 1839 current_ts = 56.128414 current_received_ts = 56.128059 current_seq = 1849 count = 86 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 56.043915/1839, current 56.128414/1849: elapsed=84499.0us expected=86703.8us +- 433.5us, error 2.5% (kms_flip:2087) DEBUG: inconsistent vblank ts/seq: last 56.043915/1839, current 56.128414/1849: elapsed=84499.0us expected=86703.8us (kms_flip:2087) DEBUG: name = vblank last_ts = 56.128414 last_received_ts = 56.128059 last_seq = 1849 current_ts = 56.212917 current_received_ts = 56.212543 current_seq = 1859 count = 87 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 56.128414/1849, current 56.212917/1859: elapsed=84503.0us expected=86703.8us +- 433.5us, error 2.5% (kms_flip:2087) DEBUG: inconsistent vblank ts/seq: last 56.128414/1849, current 56.212917/1859: elapsed=84503.0us expected=86703.8us (kms_flip:2087) INFO: Event vblank: expected 866, counted 880, passrate = 13.64%, encoder type 2 (kms_flip:2087) DEBUG: dropped frames, expected 866, counted 880, passrate = 13.64%, encoder type 2 (kms_flip:2087) DEBUG: Retrying without a hotplug event (kms_flip:2087) DEBUG: No stale events found (kms_flip:2087) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0 (kms_flip:2087) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0 (kms_flip:2087) INFO: Expected frametime: 8335us; measured 8411.6us +- 3.540us accuracy 0.13% (kms_flip:2087) INFO: vblank interval differs from modeline! expected 8334.6us, measured 8412us +- 3.540us, difference 77.0us (21.8 sigma) (kms_flip:2087) DEBUG: name = vblank last_ts = 0.000000 last_received_ts = 0.000000 last_seq = 0 current_ts = 59.555923 current_received_ts = 59.555656 current_seq = 2255 count = 0 seq_step = 10 (kms_flip:2087) DEBUG: name = vblank last_ts = 59.555923 last_received_ts = 59.555656 last_seq = 2255 current_ts = 59.640041 current_received_ts = 59.639740 current_seq = 2265 count = 1 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 59.555923/2255, current 59.640041/2265: elapsed=84120.0us expected=84115.6us +- 420.6us, error 0.0% (kms_flip:2087) DEBUG: name = vblank last_ts = 59.640041 last_received_ts = 59.639740 last_seq = 2265 current_ts = 59.724155 current_received_ts = 59.723797 current_seq = 2275 count = 2 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 59.640041/2265, current 59.724155/2275: elapsed=84112.0us expected=84115.6us +- 420.6us, error 0.0% (kms_flip:2087) DEBUG: name = vblank last_ts = 59.724155 last_received_ts = 59.723797 last_seq = 2275 current_ts = 59.808197 current_received_ts = 59.807850 current_seq = 2285 count = 3 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 59.724155/2275, current 59.808197/2285: elapsed=84044.0us expected=84115.6us +- 420.6us, error 0.1% (kms_flip:2087) DEBUG: name = vblank last_ts = 59.808197 last_received_ts = 59.807850 last_seq = 2285 current_ts = 59.892223 current_received_ts = 59.891907 current_seq = 2295 count = 4 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 59.808197/2285, current 59.892223/2295: elapsed=84026.0us expected=84115.6us +- 420.6us, error 0.1% (kms_flip:2087) DEBUG: name = vblank last_ts = 59.892223 last_received_ts = 59.891907 last_seq = 2295 current_ts = 59.976246 current_received_ts = 59.975899 current_seq = 2305 count = 5 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 59.892223/2295, current 59.976246/2305: elapsed=84021.0us expected=84115.6us +- 420.6us, error 0.1% (kms_flip:2087) DEBUG: name = vblank last_ts = 59.976246 last_received_ts = 59.975899 last_seq = 2305 current_ts = 60.060261 current_received_ts = 60.059902 current_seq = 2315 count = 6 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 59.976246/2305, current 60.060261/2315: elapsed=84016.0us expected=84115.6us +- 420.6us, error 0.1% (kms_flip:2087) DEBUG: name = vblank last_ts = 60.060261 last_received_ts = 60.059902 last_seq = 2315 current_ts = 60.144283 current_received_ts = 60.144077 current_seq = 2325 count = 7 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 60.060261/2315, current 60.144283/2325: elapsed=84023.0us expected=84115.6us +- 420.6us, error 0.1% (kms_flip:2087) DEBUG: name = vblank last_ts = 60.144283 last_received_ts = 60.144077 last_seq = 2325 current_ts = 60.228306 current_received_ts = 60.227928 current_seq = 2335 count = 8 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 60.144283/2325, current 60.228306/2335: elapsed=84021.0us expected=84115.6us +- 420.6us, error 0.1% (kms_flip:2087) DEBUG: name = vblank last_ts = 60.228306 last_received_ts = 60.227928 last_seq = 2335 current_ts = 60.312328 current_received_ts = 60.311974 current_seq = 2345 count = 9 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 60.228306/2335, current 60.312328/2345: elapsed=84023.0us expected=84115.6us +- 420.6us, error 0.1% (kms_flip:2087) DEBUG: name = vblank last_ts = 60.312328 last_received_ts = 60.311974 last_seq = 2345 current_ts = 60.396351 current_received_ts = 60.395969 current_seq = 2355 count = 10 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 60.312328/2345, current 60.396351/2355: elapsed=84024.0us expected=84115.6us +- 420.6us, error 0.1% (kms_flip:2087) DEBUG: name = vblank last_ts = 60.396351 last_received_ts = 60.395969 last_seq = 2355 current_ts = 60.480370 current_received_ts = 60.480003 current_seq = 2365 count = 11 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 60.396351/2355, current 60.480370/2365: elapsed=84019.0us expected=84115.6us +- 420.6us, error 0.1% (kms_flip:2087) DEBUG: name = vblank last_ts = 60.480370 last_received_ts = 60.480003 last_seq = 2365 current_ts = 60.564392 current_received_ts = 60.564110 current_seq = 2375 count = 12 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 60.480370/2365, current 60.564392/2375: elapsed=84020.0us expected=84115.6us +- 420.6us, error 0.1% (kms_flip:2087) DEBUG: name = vblank last_ts = 60.564392 last_received_ts = 60.564110 last_seq = 2375 current_ts = 60.648415 current_received_ts = 60.648071 current_seq = 2385 count = 13 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 60.564392/2375, current 60.648415/2385: elapsed=84024.0us expected=84115.6us +- 420.6us, error 0.1% (kms_flip:2087) DEBUG: name = vblank last_ts = 60.648415 last_received_ts = 60.648071 last_seq = 2385 current_ts = 60.732433 current_received_ts = 60.732079 current_seq = 2395 count = 14 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 60.648415/2385, current 60.732433/2395: elapsed=84019.0us expected=84115.6us +- 420.6us, error 0.1% (kms_flip:2087) DEBUG: name = vblank last_ts = 60.732433 last_received_ts = 60.732079 last_seq = 2395 current_ts = 60.816383 current_received_ts = 60.816048 current_seq = 2405 count = 15 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 60.732433/2395, current 60.816383/2405: elapsed=83949.0us expected=84115.6us +- 420.6us, error 0.2% (kms_flip:2087) DEBUG: name = vblank last_ts = 60.816383 last_received_ts = 60.816048 last_seq = 2405 current_ts = 60.900322 current_received_ts = 60.899960 current_seq = 2415 count = 16 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 60.816383/2405, current 60.900322/2415: elapsed=83938.0us expected=84115.6us +- 420.6us, error 0.2% (kms_flip:2087) DEBUG: name = vblank last_ts = 60.900322 last_received_ts = 60.899960 last_seq = 2415 current_ts = 60.984257 current_received_ts = 60.983883 current_seq = 2425 count = 17 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 60.900322/2415, current 60.984257/2425: elapsed=83936.0us expected=84115.6us +- 420.6us, error 0.2% (kms_flip:2087) DEBUG: name = vblank last_ts = 60.984257 last_received_ts = 60.983883 last_seq = 2425 current_ts = 61.068192 current_received_ts = 61.067883 current_seq = 2435 count = 18 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 60.984257/2425, current 61.068192/2435: elapsed=83933.0us expected=84115.6us +- 420.6us, error 0.2% (kms_flip:2087) DEBUG: name = vblank last_ts = 61.068192 last_received_ts = 61.067883 last_seq = 2435 current_ts = 61.152130 current_received_ts = 61.151787 current_seq = 2445 count = 19 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 61.068192/2435, current 61.152130/2445: elapsed=83939.0us expected=84115.6us +- 420.6us, error 0.2% (kms_flip:2087) DEBUG: name = vblank last_ts = 61.152130 last_received_ts = 61.151787 last_seq = 2445 current_ts = 61.236069 current_received_ts = 61.235699 current_seq = 2455 count = 20 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 61.152130/2445, current 61.236069/2455: elapsed=83939.0us expected=84115.6us +- 420.6us, error 0.2% (kms_flip:2087) DEBUG: name = vblank last_ts = 61.236069 last_received_ts = 61.235699 last_seq = 2455 current_ts = 61.320004 current_received_ts = 61.319767 current_seq = 2465 count = 21 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 61.236069/2455, current 61.320004/2465: elapsed=83934.0us expected=84115.6us +- 420.6us, error 0.2% (kms_flip:2087) DEBUG: name = vblank last_ts = 61.320004 last_received_ts = 61.319767 last_seq = 2465 current_ts = 61.403938 current_received_ts = 61.403568 current_seq = 2475 count = 22 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 61.320004/2465, current 61.403938/2475: elapsed=83936.0us expected=84115.6us +- 420.6us, error 0.2% (kms_flip:2087) DEBUG: name = vblank last_ts = 61.403938 last_received_ts = 61.403568 last_seq = 2475 current_ts = 61.487881 current_received_ts = 61.487537 current_seq = 2485 count = 23 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 61.403938/2475, current 61.487881/2485: elapsed=83943.0us expected=84115.6us +- 420.6us, error 0.2% (kms_flip:2087) DEBUG: name = vblank last_ts = 61.487881 last_received_ts = 61.487537 last_seq = 2485 current_ts = 61.571815 current_received_ts = 61.571442 current_seq = 2495 count = 24 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 61.487881/2485, current 61.571815/2495: elapsed=83935.0us expected=84115.6us +- 420.6us, error 0.2% (kms_flip:2087) DEBUG: name = vblank last_ts = 61.571815 last_received_ts = 61.571442 last_seq = 2495 current_ts = 61.655750 current_received_ts = 61.655422 current_seq = 2505 count = 25 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 61.571815/2495, current 61.655750/2505: elapsed=83933.0us expected=84115.6us +- 420.6us, error 0.2% (kms_flip:2087) DEBUG: name = vblank last_ts = 61.655750 last_received_ts = 61.655422 last_seq = 2505 current_ts = 61.739693 current_received_ts = 61.739449 current_seq = 2515 count = 26 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 61.655750/2505, current 61.739693/2515: elapsed=83942.0us expected=84115.6us +- 420.6us, error 0.2% (kms_flip:2087) DEBUG: name = vblank last_ts = 61.739693 last_received_ts = 61.739449 last_seq = 2515 current_ts = 61.823551 current_received_ts = 61.823200 current_seq = 2525 count = 27 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 61.739693/2515, current 61.823551/2525: elapsed=83860.0us expected=84115.6us +- 420.6us, error 0.3% (kms_flip:2087) DEBUG: name = vblank last_ts = 61.823551 last_received_ts = 61.823200 last_seq = 2525 current_ts = 61.907417 current_received_ts = 61.907063 current_seq = 2535 count = 28 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 61.823551/2525, current 61.907417/2535: elapsed=83866.0us expected=84115.6us +- 420.6us, error 0.3% (kms_flip:2087) DEBUG: name = vblank last_ts = 61.907417 last_received_ts = 61.907063 last_seq = 2535 current_ts = 61.991283 current_received_ts = 61.990868 current_seq = 2545 count = 29 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 61.907417/2535, current 61.991283/2545: elapsed=83865.0us expected=84115.6us +- 420.6us, error 0.3% (kms_flip:2087) DEBUG: name = vblank last_ts = 61.991283 last_received_ts = 61.990868 last_seq = 2545 current_ts = 62.075142 current_received_ts = 62.074829 current_seq = 2555 count = 30 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 61.991283/2545, current 62.075142/2555: elapsed=83859.0us expected=84115.6us +- 420.6us, error 0.3% (kms_flip:2087) DEBUG: name = vblank last_ts = 62.075142 last_received_ts = 62.074829 last_seq = 2555 current_ts = 62.159004 current_received_ts = 62.158627 current_seq = 2565 count = 31 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 62.075142/2555, current 62.159004/2565: elapsed=83862.0us expected=84115.6us +- 420.6us, error 0.3% (kms_flip:2087) DEBUG: name = vblank last_ts = 62.159004 last_received_ts = 62.158627 last_seq = 2565 current_ts = 62.242867 current_received_ts = 62.242599 current_seq = 2575 count = 32 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 62.159004/2565, current 62.242867/2575: elapsed=83864.0us expected=84115.6us +- 420.6us, error 0.3% (kms_flip:2087) DEBUG: name = vblank last_ts = 62.242867 last_received_ts = 62.242599 last_seq = 2575 current_ts = 62.326729 current_received_ts = 62.326370 current_seq = 2585 count = 33 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 62.242867/2575, current 62.326729/2585: elapsed=83863.0us expected=84115.6us +- 420.6us, error 0.3% (kms_flip:2087) DEBUG: name = vblank last_ts = 62.326729 last_received_ts = 62.326370 last_seq = 2585 current_ts = 62.410591 current_received_ts = 62.410301 current_seq = 2595 count = 34 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 62.326729/2585, current 62.410591/2595: elapsed=83863.0us expected=84115.6us +- 420.6us, error 0.3% (kms_flip:2087) DEBUG: name = vblank last_ts = 62.410591 last_received_ts = 62.410301 last_seq = 2595 current_ts = 62.494457 current_received_ts = 62.494099 current_seq = 2605 count = 35 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 62.410591/2595, current 62.494457/2605: elapsed=83866.0us expected=84115.6us +- 420.6us, error 0.3% (kms_flip:2087) DEBUG: name = vblank last_ts = 62.494457 last_received_ts = 62.494099 last_seq = 2605 current_ts = 62.578323 current_received_ts = 62.578003 current_seq = 2615 count = 36 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 62.494457/2605, current 62.578323/2615: elapsed=83864.0us expected=84115.6us +- 420.6us, error 0.3% (kms_flip:2087) DEBUG: name = vblank last_ts = 62.578323 last_received_ts = 62.578003 last_seq = 2615 current_ts = 62.662189 current_received_ts = 62.661854 current_seq = 2625 count = 37 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 62.578323/2615, current 62.662189/2625: elapsed=83865.0us expected=84115.6us +- 420.6us, error 0.3% (kms_flip:2087) DEBUG: name = vblank last_ts = 62.662189 last_received_ts = 62.661854 last_seq = 2625 current_ts = 62.746044 current_received_ts = 62.745705 current_seq = 2635 count = 38 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 62.662189/2625, current 62.746044/2635: elapsed=83856.0us expected=84115.6us +- 420.6us, error 0.3% (kms_flip:2087) DEBUG: name = vblank last_ts = 62.746044 last_received_ts = 62.745705 last_seq = 2635 current_ts = 62.829845 current_received_ts = 62.829475 current_seq = 2645 count = 39 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 62.746044/2635, current 62.829845/2645: elapsed=83801.0us expected=84115.6us +- 420.6us, error 0.4% (kms_flip:2087) DEBUG: name = vblank last_ts = 62.829845 last_received_ts = 62.829475 last_seq = 2645 current_ts = 62.913639 current_received_ts = 62.913338 current_seq = 2655 count = 40 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 62.829845/2645, current 62.913639/2655: elapsed=83795.0us expected=84115.6us +- 420.6us, error 0.4% (kms_flip:2087) DEBUG: name = vblank last_ts = 62.913639 last_received_ts = 62.913338 last_seq = 2655 current_ts = 62.997440 current_received_ts = 62.997169 current_seq = 2665 count = 41 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 62.913639/2655, current 62.997440/2665: elapsed=83800.0us expected=84115.6us +- 420.6us, error 0.4% (kms_flip:2087) DEBUG: name = vblank last_ts = 62.997440 last_received_ts = 62.997169 last_seq = 2665 current_ts = 63.081238 current_received_ts = 63.080917 current_seq = 2675 count = 42 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 62.997440/2665, current 63.081238/2675: elapsed=83798.0us expected=84115.6us +- 420.6us, error 0.4% (kms_flip:2087) DEBUG: name = vblank last_ts = 63.081238 last_received_ts = 63.080917 last_seq = 2675 current_ts = 63.165035 current_received_ts = 63.164665 current_seq = 2685 count = 43 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 63.081238/2675, current 63.165035/2685: elapsed=83796.0us expected=84115.6us +- 420.6us, error 0.4% (kms_flip:2087) DEBUG: name = vblank last_ts = 63.165035 last_received_ts = 63.164665 last_seq = 2685 current_ts = 63.248840 current_received_ts = 63.248531 current_seq = 2695 count = 44 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 63.165035/2685, current 63.248840/2695: elapsed=83805.0us expected=84115.6us +- 420.6us, error 0.4% (kms_flip:2087) DEBUG: name = vblank last_ts = 63.248840 last_received_ts = 63.248531 last_seq = 2695 current_ts = 63.332634 current_received_ts = 63.332291 current_seq = 2705 count = 45 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 63.248840/2695, current 63.332634/2705: elapsed=83794.0us expected=84115.6us +- 420.6us, error 0.4% (kms_flip:2087) DEBUG: name = vblank last_ts = 63.332634 last_received_ts = 63.332291 last_seq = 2705 current_ts = 63.416431 current_received_ts = 63.416092 current_seq = 2715 count = 46 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 63.332634/2705, current 63.416431/2715: elapsed=83797.0us expected=84115.6us +- 420.6us, error 0.4% (kms_flip:2087) DEBUG: name = vblank last_ts = 63.416431 last_received_ts = 63.416092 last_seq = 2715 current_ts = 63.500229 current_received_ts = 63.499882 current_seq = 2725 count = 47 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 63.416431/2715, current 63.500229/2725: elapsed=83799.0us expected=84115.6us +- 420.6us, error 0.4% (kms_flip:2087) DEBUG: name = vblank last_ts = 63.500229 last_received_ts = 63.499882 last_seq = 2725 current_ts = 63.584026 current_received_ts = 63.583706 current_seq = 2735 count = 48 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 63.500229/2725, current 63.584026/2735: elapsed=83798.0us expected=84115.6us +- 420.6us, error 0.4% (kms_flip:2087) DEBUG: name = vblank last_ts = 63.584026 last_received_ts = 63.583706 last_seq = 2735 current_ts = 63.667828 current_received_ts = 63.667530 current_seq = 2745 count = 49 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 63.584026/2735, current 63.667828/2745: elapsed=83801.0us expected=84115.6us +- 420.6us, error 0.4% (kms_flip:2087) DEBUG: name = vblank last_ts = 63.667828 last_received_ts = 63.667530 last_seq = 2745 current_ts = 63.751621 current_received_ts = 63.751274 current_seq = 2755 count = 50 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 63.667828/2745, current 63.751621/2755: elapsed=83793.0us expected=84115.6us +- 420.6us, error 0.4% (kms_flip:2087) DEBUG: name = vblank last_ts = 63.751621 last_received_ts = 63.751274 last_seq = 2755 current_ts = 63.835361 current_received_ts = 63.834988 current_seq = 2765 count = 51 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 63.751621/2755, current 63.835361/2765: elapsed=83739.0us expected=84115.6us +- 420.6us, error 0.4% (kms_flip:2087) DEBUG: name = vblank last_ts = 63.835361 last_received_ts = 63.834988 last_seq = 2765 current_ts = 63.919098 current_received_ts = 63.918720 current_seq = 2775 count = 52 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 63.835361/2765, current 63.919098/2775: elapsed=83739.0us expected=84115.6us +- 420.6us, error 0.4% (kms_flip:2087) DEBUG: name = vblank last_ts = 63.919098 last_received_ts = 63.918720 last_seq = 2775 current_ts = 64.002846 current_received_ts = 64.002518 current_seq = 2785 count = 53 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 63.919098/2775, current 64.002846/2785: elapsed=83743.0us expected=84115.6us +- 420.6us, error 0.4% (kms_flip:2087) DEBUG: name = vblank last_ts = 64.002846 last_received_ts = 64.002518 last_seq = 2785 current_ts = 64.086586 current_received_ts = 64.086235 current_seq = 2795 count = 54 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 64.002846/2785, current 64.086586/2795: elapsed=83744.0us expected=84115.6us +- 420.6us, error 0.4% (kms_flip:2087) DEBUG: name = vblank last_ts = 64.086586 last_received_ts = 64.086235 last_seq = 2795 current_ts = 64.170326 current_received_ts = 64.169983 current_seq = 2805 count = 55 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 64.086586/2795, current 64.170326/2805: elapsed=83744.0us expected=84115.6us +- 420.6us, error 0.4% (kms_flip:2087) DEBUG: name = vblank last_ts = 64.170326 last_received_ts = 64.169983 last_seq = 2805 current_ts = 64.254074 current_received_ts = 64.253708 current_seq = 2815 count = 56 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 64.170326/2805, current 64.254074/2815: elapsed=83743.0us expected=84115.6us +- 420.6us, error 0.4% (kms_flip:2087) DEBUG: name = vblank last_ts = 64.254074 last_received_ts = 64.253708 last_seq = 2815 current_ts = 64.337807 current_received_ts = 64.337440 current_seq = 2825 count = 57 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 64.254074/2815, current 64.337807/2825: elapsed=83735.0us expected=84115.6us +- 420.6us, error 0.5% (kms_flip:2087) DEBUG: name = vblank last_ts = 64.337807 last_received_ts = 64.337440 last_seq = 2825 current_ts = 64.421555 current_received_ts = 64.421219 current_seq = 2835 count = 58 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 64.337807/2825, current 64.421555/2835: elapsed=83747.0us expected=84115.6us +- 420.6us, error 0.4% (kms_flip:2087) DEBUG: name = vblank last_ts = 64.421555 last_received_ts = 64.421219 last_seq = 2835 current_ts = 64.505295 current_received_ts = 64.504967 current_seq = 2845 count = 59 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 64.421555/2835, current 64.505295/2845: elapsed=83737.0us expected=84115.6us +- 420.6us, error 0.5% (kms_flip:2087) DEBUG: name = vblank last_ts = 64.505295 last_received_ts = 64.504967 last_seq = 2845 current_ts = 64.589035 current_received_ts = 64.588692 current_seq = 2855 count = 60 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 64.505295/2845, current 64.589035/2855: elapsed=83745.0us expected=84115.6us +- 420.6us, error 0.4% (kms_flip:2087) DEBUG: name = vblank last_ts = 64.589035 last_received_ts = 64.588692 last_seq = 2855 current_ts = 64.672783 current_received_ts = 64.672409 current_seq = 2865 count = 61 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 64.589035/2855, current 64.672783/2865: elapsed=83743.0us expected=84115.6us +- 420.6us, error 0.4% (kms_flip:2087) DEBUG: name = vblank last_ts = 64.672783 last_received_ts = 64.672409 last_seq = 2865 current_ts = 64.756516 current_received_ts = 64.756149 current_seq = 2875 count = 62 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 64.672783/2865, current 64.756516/2875: elapsed=83734.0us expected=84115.6us +- 420.6us, error 0.5% (kms_flip:2087) DEBUG: name = vblank last_ts = 64.756516 last_received_ts = 64.756149 last_seq = 2875 current_ts = 64.840210 current_received_ts = 64.839821 current_seq = 2885 count = 63 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 64.756516/2875, current 64.840210/2885: elapsed=83693.0us expected=84115.6us +- 420.6us, error 0.5% (kms_flip:2087) DEBUG: inconsistent vblank ts/seq: last 64.756516/2875, current 64.840210/2885: elapsed=83693.0us expected=84115.6us (kms_flip:2087) DEBUG: name = vblank last_ts = 64.840210 last_received_ts = 64.839821 last_seq = 2885 current_ts = 64.923897 current_received_ts = 64.923546 current_seq = 2895 count = 64 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 64.840210/2885, current 64.923897/2895: elapsed=83687.0us expected=84115.6us +- 420.6us, error 0.5% (kms_flip:2087) DEBUG: inconsistent vblank ts/seq: last 64.840210/2885, current 64.923897/2895: elapsed=83687.0us expected=84115.6us (kms_flip:2087) DEBUG: name = vblank last_ts = 64.923897 last_received_ts = 64.923546 last_seq = 2895 current_ts = 65.007591 current_received_ts = 65.007217 current_seq = 2905 count = 65 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 64.923897/2895, current 65.007591/2905: elapsed=83696.0us expected=84115.6us +- 420.6us, error 0.5% (kms_flip:2087) DEBUG: name = vblank last_ts = 65.007591 last_received_ts = 65.007217 last_seq = 2905 current_ts = 65.091286 current_received_ts = 65.090927 current_seq = 2915 count = 66 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 65.007591/2905, current 65.091286/2915: elapsed=83695.0us expected=84115.6us +- 420.6us, error 0.5% (kms_flip:2087) DEBUG: inconsistent vblank ts/seq: last 65.007591/2905, current 65.091286/2915: elapsed=83695.0us expected=84115.6us (kms_flip:2087) DEBUG: name = vblank last_ts = 65.091286 last_received_ts = 65.090927 last_seq = 2915 current_ts = 65.174980 current_received_ts = 65.174591 current_seq = 2925 count = 67 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 65.091286/2915, current 65.174980/2925: elapsed=83692.0us expected=84115.6us +- 420.6us, error 0.5% (kms_flip:2087) DEBUG: inconsistent vblank ts/seq: last 65.091286/2915, current 65.174980/2925: elapsed=83692.0us expected=84115.6us (kms_flip:2087) DEBUG: name = vblank last_ts = 65.174980 last_received_ts = 65.174591 last_seq = 2925 current_ts = 65.258667 current_received_ts = 65.258369 current_seq = 2935 count = 68 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 65.174980/2925, current 65.258667/2935: elapsed=83687.0us expected=84115.6us +- 420.6us, error 0.5% (kms_flip:2087) DEBUG: inconsistent vblank ts/seq: last 65.174980/2925, current 65.258667/2935: elapsed=83687.0us expected=84115.6us (kms_flip:2087) DEBUG: name = vblank last_ts = 65.258667 last_received_ts = 65.258369 last_seq = 2935 current_ts = 65.342361 current_received_ts = 65.342049 current_seq = 2945 count = 69 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 65.258667/2935, current 65.342361/2945: elapsed=83698.0us expected=84115.6us +- 420.6us, error 0.5% (kms_flip:2087) DEBUG: name = vblank last_ts = 65.342361 last_received_ts = 65.342049 last_seq = 2945 current_ts = 65.426056 current_received_ts = 65.425674 current_seq = 2955 count = 70 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 65.342361/2945, current 65.426056/2955: elapsed=83692.0us expected=84115.6us +- 420.6us, error 0.5% (kms_flip:2087) DEBUG: inconsistent vblank ts/seq: last 65.342361/2945, current 65.426056/2955: elapsed=83692.0us expected=84115.6us (kms_flip:2087) DEBUG: name = vblank last_ts = 65.426056 last_received_ts = 65.425674 last_seq = 2955 current_ts = 65.509743 current_received_ts = 65.509438 current_seq = 2965 count = 71 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 65.426056/2955, current 65.509743/2965: elapsed=83688.0us expected=84115.6us +- 420.6us, error 0.5% (kms_flip:2087) DEBUG: inconsistent vblank ts/seq: last 65.426056/2955, current 65.509743/2965: elapsed=83688.0us expected=84115.6us (kms_flip:2087) DEBUG: name = vblank last_ts = 65.509743 last_received_ts = 65.509438 last_seq = 2965 current_ts = 65.593430 current_received_ts = 65.593102 current_seq = 2975 count = 72 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 65.509743/2965, current 65.593430/2975: elapsed=83691.0us expected=84115.6us +- 420.6us, error 0.5% (kms_flip:2087) DEBUG: inconsistent vblank ts/seq: last 65.509743/2965, current 65.593430/2975: elapsed=83691.0us expected=84115.6us (kms_flip:2087) DEBUG: name = vblank last_ts = 65.593430 last_received_ts = 65.593102 last_seq = 2975 current_ts = 65.677132 current_received_ts = 65.676796 current_seq = 2985 count = 73 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 65.593430/2975, current 65.677132/2985: elapsed=83696.0us expected=84115.6us +- 420.6us, error 0.5% (kms_flip:2087) DEBUG: name = vblank last_ts = 65.677132 last_received_ts = 65.676796 last_seq = 2985 current_ts = 65.760811 current_received_ts = 65.760437 current_seq = 2995 count = 74 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 65.677132/2985, current 65.760811/2995: elapsed=83683.0us expected=84115.6us +- 420.6us, error 0.5% (kms_flip:2087) DEBUG: inconsistent vblank ts/seq: last 65.677132/2985, current 65.760811/2995: elapsed=83683.0us expected=84115.6us (kms_flip:2087) DEBUG: name = vblank last_ts = 65.760811 last_received_ts = 65.760437 last_seq = 2995 current_ts = 65.844460 current_received_ts = 65.844170 current_seq = 3005 count = 75 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 65.760811/2995, current 65.844460/3005: elapsed=83647.0us expected=84115.6us +- 420.6us, error 0.6% (kms_flip:2087) DEBUG: inconsistent vblank ts/seq: last 65.760811/2995, current 65.844460/3005: elapsed=83647.0us expected=84115.6us (kms_flip:2087) DEBUG: name = vblank last_ts = 65.844460 last_received_ts = 65.844170 last_seq = 3005 current_ts = 65.928108 current_received_ts = 65.927750 current_seq = 3015 count = 76 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 65.844460/3005, current 65.928108/3015: elapsed=83648.0us expected=84115.6us +- 420.6us, error 0.6% (kms_flip:2087) DEBUG: inconsistent vblank ts/seq: last 65.844460/3005, current 65.928108/3015: elapsed=83648.0us expected=84115.6us (kms_flip:2087) DEBUG: name = vblank last_ts = 65.928108 last_received_ts = 65.927750 last_seq = 3015 current_ts = 66.011757 current_received_ts = 66.011444 current_seq = 3025 count = 77 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 65.928108/3015, current 66.011757/3025: elapsed=83648.0us expected=84115.6us +- 420.6us, error 0.6% (kms_flip:2087) DEBUG: inconsistent vblank ts/seq: last 65.928108/3015, current 66.011757/3025: elapsed=83648.0us expected=84115.6us (kms_flip:2087) DEBUG: name = vblank last_ts = 66.011757 last_received_ts = 66.011444 last_seq = 3025 current_ts = 66.095406 current_received_ts = 66.095123 current_seq = 3035 count = 78 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 66.011757/3025, current 66.095406/3035: elapsed=83654.0us expected=84115.6us +- 420.6us, error 0.5% (kms_flip:2087) DEBUG: inconsistent vblank ts/seq: last 66.011757/3025, current 66.095406/3035: elapsed=83654.0us expected=84115.6us (kms_flip:2087) DEBUG: name = vblank last_ts = 66.095406 last_received_ts = 66.095123 last_seq = 3035 current_ts = 66.179054 current_received_ts = 66.178802 current_seq = 3045 count = 79 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 66.095406/3035, current 66.179054/3045: elapsed=83649.0us expected=84115.6us +- 420.6us, error 0.6% (kms_flip:2087) DEBUG: inconsistent vblank ts/seq: last 66.095406/3035, current 66.179054/3045: elapsed=83649.0us expected=84115.6us (kms_flip:2087) DEBUG: name = vblank last_ts = 66.179054 last_received_ts = 66.178802 last_seq = 3045 current_ts = 66.262703 current_received_ts = 66.262344 current_seq = 3055 count = 80 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 66.179054/3045, current 66.262703/3055: elapsed=83643.0us expected=84115.6us +- 420.6us, error 0.6% (kms_flip:2087) DEBUG: inconsistent vblank ts/seq: last 66.179054/3045, current 66.262703/3055: elapsed=83643.0us expected=84115.6us (kms_flip:2087) DEBUG: name = vblank last_ts = 66.262703 last_received_ts = 66.262344 last_seq = 3055 current_ts = 66.346359 current_received_ts = 66.345985 current_seq = 3065 count = 81 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 66.262703/3055, current 66.346359/3065: elapsed=83656.0us expected=84115.6us +- 420.6us, error 0.5% (kms_flip:2087) DEBUG: inconsistent vblank ts/seq: last 66.262703/3055, current 66.346359/3065: elapsed=83656.0us expected=84115.6us (kms_flip:2087) DEBUG: name = vblank last_ts = 66.346359 last_received_ts = 66.345985 last_seq = 3065 current_ts = 66.430000 current_received_ts = 66.429688 current_seq = 3075 count = 82 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 66.346359/3065, current 66.430000/3075: elapsed=83644.0us expected=84115.6us +- 420.6us, error 0.6% (kms_flip:2087) DEBUG: inconsistent vblank ts/seq: last 66.346359/3065, current 66.430000/3075: elapsed=83644.0us expected=84115.6us (kms_flip:2087) DEBUG: name = vblank last_ts = 66.430000 last_received_ts = 66.429688 last_seq = 3075 current_ts = 66.513657 current_received_ts = 66.513359 current_seq = 3085 count = 83 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 66.430000/3075, current 66.513657/3085: elapsed=83653.0us expected=84115.6us +- 420.6us, error 0.5% (kms_flip:2087) DEBUG: inconsistent vblank ts/seq: last 66.430000/3075, current 66.513657/3085: elapsed=83653.0us expected=84115.6us (kms_flip:2087) DEBUG: name = vblank last_ts = 66.513657 last_received_ts = 66.513359 last_seq = 3085 current_ts = 66.597305 current_received_ts = 66.596962 current_seq = 3095 count = 84 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 66.513657/3085, current 66.597305/3095: elapsed=83649.0us expected=84115.6us +- 420.6us, error 0.6% (kms_flip:2087) DEBUG: inconsistent vblank ts/seq: last 66.513657/3085, current 66.597305/3095: elapsed=83649.0us expected=84115.6us (kms_flip:2087) DEBUG: name = vblank last_ts = 66.597305 last_received_ts = 66.596962 last_seq = 3095 current_ts = 66.680946 current_received_ts = 66.680534 current_seq = 3105 count = 85 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 66.597305/3095, current 66.680946/3105: elapsed=83646.0us expected=84115.6us +- 420.6us, error 0.6% (kms_flip:2087) DEBUG: inconsistent vblank ts/seq: last 66.597305/3095, current 66.680946/3105: elapsed=83646.0us expected=84115.6us (kms_flip:2087) DEBUG: name = vblank last_ts = 66.680946 last_received_ts = 66.680534 last_seq = 3105 current_ts = 66.764587 current_received_ts = 66.764160 current_seq = 3115 count = 86 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 66.680946/3105, current 66.764587/3115: elapsed=83636.0us expected=84115.6us +- 420.6us, error 0.6% (kms_flip:2087) DEBUG: inconsistent vblank ts/seq: last 66.680946/3105, current 66.764587/3115: elapsed=83636.0us expected=84115.6us (kms_flip:2087) DEBUG: name = vblank last_ts = 66.764587 last_received_ts = 66.764160 last_seq = 3115 current_ts = 66.848198 current_received_ts = 66.847771 current_seq = 3125 count = 87 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 66.764587/3115, current 66.848198/3125: elapsed=83611.0us expected=84115.6us +- 420.6us, error 0.6% (kms_flip:2087) DEBUG: inconsistent vblank ts/seq: last 66.764587/3115, current 66.848198/3125: elapsed=83611.0us expected=84115.6us (kms_flip:2087) DEBUG: name = vblank last_ts = 66.848198 last_received_ts = 66.847771 last_seq = 3125 current_ts = 66.931808 current_received_ts = 66.931442 current_seq = 3135 count = 88 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 66.848198/3125, current 66.931808/3135: elapsed=83612.0us expected=84115.6us +- 420.6us, error 0.6% (kms_flip:2087) DEBUG: inconsistent vblank ts/seq: last 66.848198/3125, current 66.931808/3135: elapsed=83612.0us expected=84115.6us (kms_flip:2087) DEBUG: name = vblank last_ts = 66.931808 last_received_ts = 66.931442 last_seq = 3135 current_ts = 67.015419 current_received_ts = 67.014999 current_seq = 3145 count = 89 seq_step = 10 (kms_flip:2087) DEBUG: vblank ts/seq: last 66.931808/3135, current 67.015419/3145: elapsed=83610.0us expected=84115.6us +- 420.6us, error 0.6% (kms_flip:2087) DEBUG: inconsistent vblank ts/seq: last 66.931808/3135, current 67.015419/3145: elapsed=83610.0us expected=84115.6us (kms_flip:2087) INFO: Event vblank: expected 896, counted 900, passrate = 73.33%, encoder type 2 (kms_flip:2087) DEBUG: dropped frames, expected 896, counted 900, passrate = 73.33%, encoder type 2 (kms_flip:2087) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1722: (kms_flip:2087) CRITICAL: Failed assertion: !retried (kms_flip:2087) CRITICAL: Last errno: 4, Interrupted system call (kms_flip:2087) igt_core-INFO: Stack trace: (kms_flip:2087) igt_core-INFO: #0 ../lib/igt_core.c:2075 __igt_fail_assert() (kms_flip:2087) igt_core-INFO: #1 ../tests/kms_flip.c:1071 run_test_on_crtc_set() (kms_flip:2087) igt_core-INFO: #2 ../tests/kms_flip.c:1920 run_test() (kms_flip:2087) igt_core-INFO: #3 ../tests/kms_flip.c:2220 __igt_unique____real_main2105() (kms_flip:2087) igt_core-INFO: #4 ../tests/kms_flip.c:2105 main() (kms_flip:2087) igt_core-INFO: #5 [__libc_init_first+0x8a] (kms_flip:2087) igt_core-INFO: #6 [__libc_start_main+0x8b] (kms_flip:2087) igt_core-INFO: #7 [_start+0x25] **** END **** Dynamic subtest A-HDMI-A1: FAIL (18.447s) |
| Dmesg |
<6> [48.283178] Console: switching to colour dummy device 80x25
<6> [48.283239] [IGT] kms_flip: executing
<7> [48.285472] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [48.286119] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [48.286422] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [48.296030] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:598:DP-1]
<7> [48.296039] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:598:DP-1]
<7> [48.300307] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:598:DP-1] disconnected
<7> [48.300853] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:608:DP-2]
<7> [48.300858] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:608:DP-2]
<7> [48.301198] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:608:DP-2] disconnected
<7> [48.301565] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:617:DP-3]
<7> [48.301569] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:617:DP-3]
<7> [48.301905] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:617:DP-3] disconnected
<7> [48.302280] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:626:DP-4]
<7> [48.302284] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:626:DP-4]
<7> [48.302619] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:626:DP-4] disconnected
<7> [48.303182] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:588:HDMI-A-1]
<7> [48.303192] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:588:HDMI-A-1]
<7> [48.330272] 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> [48.330282] 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> [48.330286] 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> [48.330289] 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> [48.330292] 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> [48.330296] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:588:HDMI-A-1] ELD monitor fitHeadlessGS
<7> [48.330300] 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> [48.330303] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:588:HDMI-A-1] ELD size 64, SAD count 10
<7> [48.330697] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0040 w(1)
<7> [48.330898] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [48.331415] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0040 w(1)
<7> [48.331631] i915 0000:00:02.0: [drm:drm_dp_dual_mode_detect [drm_display_helper]] DP dual mode HDMI ID: (err -6)
<7> [48.332414] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:588:HDMI-A-1] probed modes:
<7> [48.332418] 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> [48.332423] 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> [48.332427] 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> [48.332431] 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> [48.332434] 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> [48.332437] 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> [48.332441] 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> [48.332444] 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> [48.332448] 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> [48.332451] 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> [48.332455] 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> [48.332458] 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> [48.332462] 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> [48.332465] 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> [48.332469] 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> [48.332472] 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> [48.332476] 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> [48.332479] 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> [48.332483] 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> [48.332486] 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> [48.332490] 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> [48.332493] 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> [48.332496] 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> [48.332500] 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> [48.332503] 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> [48.332507] 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> [48.332510] 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> [48.332514] 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> [48.332517] 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> [48.332521] 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> [48.332524] 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> [48.332527] 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> [48.332531] 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> [48.332534] 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> [48.332538] 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> [48.332541] 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> [48.332545] 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> [48.332548] 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> [48.332551] 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> [48.332555] 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> [48.332558] 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> [48.332562] 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> [48.332565] 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> [48.332569] 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> [48.332572] 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> [48.332576] 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> [48.332579] 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> [48.332582] 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> [48.332586] 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> [48.332589] 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> [48.332593] 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> [48.332596] 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> [48.332600] 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> [48.332603] 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> [48.332606] 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> [48.332610] 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> [48.332613] 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> [48.332617] 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> [48.332620] 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> [48.332623] 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> [48.332627] 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> [48.332630] 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> [48.332634] 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> [48.332637] 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> [48.332641] 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> [48.332644] 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> [48.332647] 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> [48.332651] 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> [48.332654] 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> [48.332657] 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> [48.332661] 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> [48.332664] 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> [48.332667] 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> [48.332706] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:598:DP-1]
<7> [48.332710] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:598:DP-1]
<7> [48.336980] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:598:DP-1] disconnected
<7> [48.336997] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:608:DP-2]
<7> [48.337001] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:608:DP-2]
<7> [48.337340] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:608:DP-2] disconnected
<7> [48.337354] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:617:DP-3]
<7> [48.337357] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:617:DP-3]
<7> [48.337677] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:617:DP-3] disconnected
<7> [48.337690] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:626:DP-4]
<7> [48.337694] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:626:DP-4]
<7> [48.338013] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:626:DP-4] disconnected
<6> [48.342794] [IGT] kms_flip: starting subtest wf_vblank-ts-check-interruptible
<6> [48.345481] [IGT] kms_flip: starting dynamic subtest A-HDMI-A1
<7> [48.345889] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:637]
<7> [48.345995] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:638]
<7> [48.393459] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:171:pipe A]
<7> [48.393799] 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> [48.394031] 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> [48.394223] 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> [48.394389] 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> [48.394552] 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> [48.394714] 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> [48.394882] 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> [48.395042] 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> [48.395199] 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> [48.395359] 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> [48.395522] 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> [48.395680] 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> [48.395843] 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> [48.396012] 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> [48.396169] 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> [48.396326] 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> [48.396482] 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> [48.396638] 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> [48.396794] 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> [48.396971] 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> [48.397131] 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> [48.397288] 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> [48.397444] 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> [48.397600] 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> [48.397763] 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> [48.397918] 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> [48.398074] 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> [48.398228] 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> [48.398383] 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> [48.398539] 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> [48.398694] 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> [48.398863] 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> [48.399018] 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> [48.399175] 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> [48.399330] 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> [48.399485] 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> [48.399640] 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> [48.399811] 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> [48.399971] 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> [48.400126] 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> [48.400282] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in avi infoframe
<7> [48.400436] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [48.400591] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [48.400593] i915 0000:00:02.0: colorspace: RGB
<7> [48.400594] i915 0000:00:02.0: scan mode: Underscan
<7> [48.400596] i915 0000:00:02.0: colorimetry: No Data
<7> [48.400597] i915 0000:00:02.0: picture aspect: No Data
<7> [48.400599] i915 0000:00:02.0: active aspect: Same as Picture
<7> [48.400600] i915 0000:00:02.0: itc: No Data
<7> [48.400602] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [48.400603] i915 0000:00:02.0: quantization range: Full
<7> [48.400604] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [48.400606] i915 0000:00:02.0: video code: 0
<7> [48.400607] i915 0000:00:02.0: ycc quantization range: Full
<7> [48.400609] i915 0000:00:02.0: hdmi content type: Graphics
<7> [48.400610] i915 0000:00:02.0: pixel repeat: 0
<7> [48.400611] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [48.400613] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [48.400814] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in spd infoframe
<7> [48.400971] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [48.401126] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [48.401128] i915 0000:00:02.0: vendor: Intel
<7> [48.401130] i915 0000:00:02.0: product: Integrated gfx
<7> [48.401131] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [48.401133] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [48.401288] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hdmi infoframe
<7> [48.401443] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [48.401597] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [48.401599] i915 0000:00:02.0: empty frame
<7> [48.401608] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [48.401764] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:171:pipe A] fastset requirement not met, forcing full modeset
<7> [48.401921] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:171:pipe A] releasing DPLL 0
<7> [48.402115] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [48.402274] 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> [48.402470] 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> [48.402636] 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> [48.402792] 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> [48.402947] 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> [48.403100] 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> [48.403255] 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> [48.403429] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:171:pipe A] data rate 0 num active planes 0
<7> [48.403605] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14993 required 0
<7> [48.403772] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [48.403951] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:171:pipe A] min cdclk: 192000 kHz -> 0 kHz
<7> [48.404132] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 17844 kHz -> 0 kHz
<7> [48.404316] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [48.404474] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 172800 kHz, actual 172800 kHz
<7> [48.404645] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [48.404805] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:171:pipe A] enable: no [modeset]
<7> [48.404967] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:plane 1A] fb: [NOFB], visible: no
<7> [48.405128] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [48.405286] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [48.405443] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [48.405613] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [48.405772] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:145:plane 6A] fb: [NOFB], visible: no
<7> [48.405929] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:155:plane 7A] fb: [NOFB], visible: no
<7> [48.406086] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:165:cursor A] fb: [NOFB], visible: no
<7> [48.406357] 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> [48.416616] i915 0000:00:02.0: [drm:intel_audio_component_get_eld [i915]] Not valid for port B
<7> [48.427594] 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> [48.428272] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [48.428437] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [48.428975] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [48.429168] 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> [48.429183] 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> [48.429492] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [48.434378] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_B
<7> [48.434689] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x1, on? 1) for [CRTC:171:pipe A]
<7> [48.434929] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0
<7> [48.435112] i915 0000:00:02.0: [drm:__intel_fbc_disable [i915]] Disabling FBC on [PLANE:35:plane 1A]
<7> [48.435366] 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> [48.435812] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:587:DDI B/PHY B]
<7> [48.436001] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:597:DDI TC1/PHY TC1]
<7> [48.436165] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:599:DP-MST A]
<7> [48.436351] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:600:DP-MST B]
<7> [48.436523] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:601:DP-MST C]
<7> [48.436683] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:602:DP-MST D]
<7> [48.436883] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:607:DDI TC2/PHY TC2]
<7> [48.437040] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:609:DP-MST A]
<7> [48.437218] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DP-MST B]
<7> [48.437402] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:611:DP-MST C]
<7> [48.437558] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:612:DP-MST D]
<7> [48.437714] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:616:DDI TC3/PHY TC3]
<7> [48.437870] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:618:DP-MST A]
<7> [48.438027] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:619:DP-MST B]
<7> [48.438197] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:620:DP-MST C]
<7> [48.438375] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:621:DP-MST D]
<7> [48.438530] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:625:DDI TC4/PHY TC4]
<7> [48.438686] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST A]
<7> [48.438842] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:628:DP-MST B]
<7> [48.438998] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:629:DP-MST C]
<7> [48.439167] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:630:DP-MST D]
<7> [48.439353] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:588:HDMI-A-1]
<7> [48.439735] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [48.439954] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_3
<7> [48.440257] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_2
<7> [48.440451] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:171:pipe A]
<7> [48.441370] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:309:pipe B]
<7> [48.441627] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:447:pipe C]
<7> [48.441860] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:585:pipe D]
<7> [48.442115] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:171:pipe A]
<7> [48.442166] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:588:HDMI-A-1]
<7> [48.442284] 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> [48.442566] i915 0000:00:02.0: [drm:intel_hdmi_compute_output_format [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [48.442821] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:171:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [48.443067] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:587:DDI B/PHY B] [CRTC:171:pipe A]
<7> [48.443281] 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> [48.443500] 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> [48.443740] 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> [48.443988] 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> [48.444167] 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> [48.444334] 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> [48.444494] 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> [48.444661] 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> [48.444848] 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> [48.445052] 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> [48.445222] 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> [48.445397] 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> [48.445570] 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> [48.445755] 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> [48.445936] 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> [48.446094] 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> [48.446262] 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> [48.446440] 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> [48.446597] 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> [48.446754] 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> [48.446928] 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> [48.447084] 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> [48.447239] 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> [48.447393] 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> [48.447550] 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> [48.447706] 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> [48.447914] 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> [48.448070] 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> [48.448225] 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> [48.448380] 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> [48.448535] 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> [48.448694] 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> [48.448899] 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> [48.449121] 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> [48.449346] 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> [48.449569] 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> [48.449807] 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> [48.450036] 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> [48.450272] 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> [48.450497] 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> [48.450729] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in avi infoframe
<7> [48.450952] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [48.451179] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [48.451412] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [48.451415] i915 0000:00:02.0: colorspace: RGB
<7> [48.451418] i915 0000:00:02.0: scan mode: Underscan
<7> [48.451421] i915 0000:00:02.0: colorimetry: No Data
<7> [48.451424] i915 0000:00:02.0: picture aspect: No Data
<7> [48.451427] i915 0000:00:02.0: active aspect: Same as Picture
<7> [48.451430] i915 0000:00:02.0: itc: No Data
<7> [48.451433] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [48.451436] i915 0000:00:02.0: quantization range: Full
<7> [48.451439] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [48.451441] i915 0000:00:02.0: video code: 0
<7> [48.451444] i915 0000:00:02.0: ycc quantization range: Full
<7> [48.451447] i915 0000:00:02.0: hdmi content type: Graphics
<7> [48.451450] i915 0000:00:02.0: pixel repeat: 0
<7> [48.451453] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [48.451456] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in spd infoframe
<7> [48.451697] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [48.451868] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [48.452025] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [48.452027] i915 0000:00:02.0: vendor: Intel
<7> [48.452028] i915 0000:00:02.0: product: Integrated gfx
<7> [48.452030] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [48.452032] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hdmi infoframe
<7> [48.452188] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [48.452344] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [48.452501] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [48.452503] i915 0000:00:02.0: empty frame
<7> [48.452505] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:171:pipe A] fastset requirement not met, forcing full modeset
<7> [48.452768] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [48.452930] 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> [48.453126] 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> [48.453284] 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> [48.453441] 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> [48.453615] 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> [48.453770] 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> [48.453925] 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> [48.454102] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:171:pipe A] data rate 1142000 num active planes 1
<7> [48.454267] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14993 required 1142
<7> [48.454427] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [48.454682] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:171:pipe A] min cdclk: 0 kHz -> 192000 kHz
<7> [48.454862] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 0 kHz -> 17844 kHz
<7> [48.455045] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [48.455201] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 192000 kHz, actual 192000 kHz
<7> [48.455389] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [48.455564] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:171:pipe A] allocated DPLL 0
<7> [48.455730] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:171:pipe A] reserving DPLL 0
<7> [48.455897] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:171:pipe A] enable: yes [modeset]
<7> [48.456061] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [48.456222] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [48.456381] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [48.456556] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [48.456714] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [48.456872] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [48.457054] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [48.457274] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] GCP: 0x0
<7> [48.457508] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [48.457511] i915 0000:00:02.0: colorspace: RGB
<7> [48.457513] i915 0000:00:02.0: scan mode: Underscan
<7> [48.457515] i915 0000:00:02.0: colorimetry: No Data
<7> [48.457518] i915 0000:00:02.0: picture aspect: No Data
<7> [48.457521] i915 0000:00:02.0: active aspect: Same as Picture
<7> [48.457524] i915 0000:00:02.0: itc: No Data
<7> [48.457526] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [48.457529] i915 0000:00:02.0: quantization range: Full
<7> [48.457532] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [48.457534] i915 0000:00:02.0: video code: 0
<7> [48.457537] i915 0000:00:02.0: ycc quantization range: Full
<7> [48.457540] i915 0000:00:02.0: hdmi content type: Graphics
<7> [48.457543] i915 0000:00:02.0: pixel repeat: 0
<7> [48.457546] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [48.457549] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [48.457552] i915 0000:00:02.0: vendor: Intel
<7> [48.457555] i915 0000:00:02.0: product: Integrated gfx
<7> [48.457558] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [48.457561] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [48.457564] i915 0000:00:02.0: empty frame
<7> [48.457567] 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> [48.457801] 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> [48.458034] 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> [48.458265] 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> [48.458480] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [48.458696] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [48.458928] 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> [48.459147] 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> [48.459382] 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> [48.459600] 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> [48.459826] 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> [48.459985] 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> [48.460156] 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> [48.460329] 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> [48.460487] 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> [48.460668] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 59, ips linetime: 0
<7> [48.460824] 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> [48.460980] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1920x1080+0+0
<7> [48.461136] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [48.461343] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [48.461513] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [48.461670] 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> [48.461826] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [48.461982] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [48.462137] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [48.462308] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [48.462464] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [48.462619] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [48.462775] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [48.462929] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [48.463084] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [48.463258] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [48.463438] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [48.463592] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [48.463747] 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> [48.463904] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [48.464066] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [48.464281] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [48.464463] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [48.464622] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [48.464780] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [48.464937] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:145:plane 6A] fb: [NOFB], visible: no
<7> [48.465105] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:155:plane 7A] fb: [NOFB], visible: no
<7> [48.465288] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:165:cursor A] fb: [NOFB], visible: no
<7> [48.467042] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_2
<7> [48.467230] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_3
<7> [48.467413] 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> [48.467761] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:587:DDI B/PHY B]
<7> [48.467941] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:597:DDI TC1/PHY TC1]
<7> [48.468132] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:599:DP-MST A]
<7> [48.468295] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:600:DP-MST B]
<7> [48.468454] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:601:DP-MST C]
<7> [48.468611] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:602:DP-MST D]
<7> [48.468767] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:607:DDI TC2/PHY TC2]
<7> [48.468940] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:609:DP-MST A]
<7> [48.469158] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DP-MST B]
<7> [48.469327] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:611:DP-MST C]
<7> [48.469482] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:612:DP-MST D]
<7> [48.469637] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:616:DDI TC3/PHY TC3]
<7> [48.469794] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:618:DP-MST A]
<7> [48.469963] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:619:DP-MST B]
<7> [48.470139] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:620:DP-MST C]
<7> [48.470293] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:621:DP-MST D]
<7> [48.470450] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:625:DDI TC4/PHY TC4]
<7> [48.470604] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST A]
<7> [48.470758] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:628:DP-MST B]
<7> [48.470926] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:629:DP-MST C]
<7> [48.471104] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:630:DP-MST D]
<7> [48.471392] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [48.471655] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:171:pipe A]
<7> [48.471847] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0
<7> [48.472069] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_B
<7> [48.472336] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [48.472804] 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> [48.473386] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [48.473546] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [48.474116] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [48.474287] 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> [48.474306] 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> [48.476648] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1
<7> [48.476818] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:35:plane 1A]
<7> [48.482945] 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> [48.484288] i915 0000:00:02.0: [drm:intel_audio_component_get_power [i915]] restored AUD_FREQ_CNTRL to 0x810
<7> [48.488700] azx_init_pci: snd_hda_intel 0000:00:1f.3: Clearing TCSEL
<7> [48.488737] azx_init_pci: snd_hda_intel 0000:00:1f.3: SCH snoop: Enabled
<7> [48.491275] 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> [48.491466] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] Configuring HDMI audio for pixel clock 25200 (0x00010000)
<7> [48.491660] i915 0000:00:02.0: [drm:hsw_audio_config_update [i915]] using automatic N
<6> [48.491827] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 5 not expected
<6> [48.491832] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 0 not expected
<6> [48.491834] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 10 not expected
<6> [48.491836] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 6 not expected
<7> [48.492000] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:588:HDMI-A-1]
<7> [48.492336] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:171:pipe A]
<7> [49.349047] 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> [49.349071] 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> [49.349100] 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> [49.349099] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling TC_cold_off
<7> [49.349754] i915 0000:00:02.0: [drm:tgl_tc_cold_request [i915]] TC cold unblock succeeded
<7> [49.350323] 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.945403] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:171:pipe A]
<7> [58.945618] 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.946054] 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.946520] 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.946915] 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.947319] 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.947725] 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.948139] 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.948542] 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.948935] 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.949328] 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.949700] 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.950095] 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.950507] 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.950847] 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.951003] 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.951169] 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.951325] 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.951480] 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.951635] 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.951791] 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.951945] 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.952107] 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.952263] 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.952418] 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.952572] 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.952728] 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.952882] 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.953037] 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.953205] 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.953361] 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.953515] 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.953671] 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.953827] 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.953982] 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.954155] 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.954311] 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.954467] 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.954622] 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.954777] 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.954931] 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.955098] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in avi infoframe
<7> [58.955253] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [58.955407] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [58.955409] i915 0000:00:02.0: colorspace: RGB
<7> [58.955411] i915 0000:00:02.0: scan mode: Underscan
<7> [58.955412] i915 0000:00:02.0: colorimetry: No Data
<7> [58.955414] i915 0000:00:02.0: picture aspect: No Data
<7> [58.955415] i915 0000:00:02.0: active aspect: Same as Picture
<7> [58.955417] i915 0000:00:02.0: itc: No Data
<7> [58.955418] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [58.955419] i915 0000:00:02.0: quantization range: Full
<7> [58.955421] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [58.955422] i915 0000:00:02.0: video code: 0
<7> [58.955424] i915 0000:00:02.0: ycc quantization range: Full
<7> [58.955425] i915 0000:00:02.0: hdmi content type: Graphics
<7> [58.955427] i915 0000:00:02.0: pixel repeat: 0
<7> [58.955428] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [58.955430] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [58.955585] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in spd infoframe
<7> [58.955740] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [58.955895] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [58.955897] i915 0000:00:02.0: vendor: Intel
<7> [58.955898] i915 0000:00:02.0: product: Integrated gfx
<7> [58.955899] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [58.955901] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [58.956060] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hdmi infoframe
<7> [58.956215] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [58.956368] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [58.956370] i915 0000:00:02.0: empty frame
<7> [58.956371] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [58.956527] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:171:pipe A] fastset requirement not met, forcing full modeset
<7> [58.956683] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:171:pipe A] releasing DPLL 0
<7> [58.956877] 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.957042] 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.957240] 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.957398] 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.957554] 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.957708] 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.957863] 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.958025] 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.958203] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:171:pipe A] data rate 0 num active planes 0
<7> [58.958365] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14993 required 0
<7> [58.958525] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [58.958705] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:171:pipe A] min cdclk: 192000 kHz -> 0 kHz
<7> [58.958888] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 17844 kHz -> 0 kHz
<7> [58.959087] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [58.959248] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 172800 kHz, actual 172800 kHz
<7> [58.959406] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [58.959567] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:171:pipe A] enable: no [modeset]
<7> [58.959728] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:plane 1A] fb: [NOFB], visible: no
<7> [58.959888] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [58.960057] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [58.960217] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [58.960374] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [58.960531] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:145:plane 6A] fb: [NOFB], visible: no
<7> [58.960688] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:155:plane 7A] fb: [NOFB], visible: no
<7> [58.960844] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:165:cursor A] fb: [NOFB], visible: no
<7> [58.961082] 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.976227] i915 0000:00:02.0: [drm:intel_audio_component_get_eld [i915]] Not valid for port B
<7> [58.986569] 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.987251] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [58.987473] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [58.988119] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [58.988361] 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.988383] 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.988956] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [58.993718] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_B
<7> [58.994026] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x1, on? 1) for [CRTC:171:pipe A]
<7> [58.994333] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0
<7> [58.994616] i915 0000:00:02.0: [drm:__intel_fbc_disable [i915]] Disabling FBC on [PLANE:35:plane 1A]
<7> [58.994972] 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.995315] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:587:DDI B/PHY B]
<7> [58.995551] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:597:DDI TC1/PHY TC1]
<7> [58.995772] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:599:DP-MST A]
<7> [58.995959] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:600:DP-MST B]
<7> [58.996146] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:601:DP-MST C]
<7> [58.996330] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:602:DP-MST D]
<7> [58.996512] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:607:DDI TC2/PHY TC2]
<7> [58.996715] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:609:DP-MST A]
<7> [58.996898] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DP-MST B]
<7> [58.997079] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:611:DP-MST C]
<7> [58.997259] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:612:DP-MST D]
<7> [58.997440] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:616:DDI TC3/PHY TC3]
<7> [58.997619] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:618:DP-MST A]
<7> [58.997868] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:619:DP-MST B]
<7> [58.998048] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:620:DP-MST C]
<7> [58.998222] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:621:DP-MST D]
<7> [58.998387] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:625:DDI TC4/PHY TC4]
<7> [58.998552] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST A]
<7> [58.998754] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:628:DP-MST B]
<7> [58.998920] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:629:DP-MST C]
<7> [58.999086] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:630:DP-MST D]
<7> [58.999251] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:588:HDMI-A-1]
<7> [58.999635] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [58.999876] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_3
<7> [59.000072] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_2
<7> [59.000276] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:171:pipe A]
<7> [59.000999] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:309:pipe B]
<7> [59.001206] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:447:pipe C]
<7> [59.001378] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:585:pipe D]
<7> [59.001554] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:171:pipe A]
<7> [59.001592] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:588:HDMI-A-1]
<7> [59.001681] 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> [59.001875] i915 0000:00:02.0: [drm:intel_hdmi_compute_output_format [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [59.002058] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:171:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [59.002238] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:587:DDI B/PHY B] [CRTC:171:pipe A]
<7> [59.002406] 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> [59.002583] 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> [59.002821] 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> [59.003027] 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> [59.003200] 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> [59.003373] 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> [59.003547] 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> [59.003771] 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> [59.003944] 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> [59.004118] 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> [59.004293] 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> [59.004465] 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> [59.004664] 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> [59.004836] 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> [59.005005] 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> [59.005176] 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> [59.005346] 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> [59.005517] 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> [59.005725] 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> [59.005894] 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> [59.006063] 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> [59.006232] 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> [59.006401] 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> [59.006575] 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> [59.006744] 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> [59.006912] 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> [59.007103] 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> [59.007273] 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> [59.007443] 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> [59.007640] 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> [59.007810] 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> [59.007979] 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> [59.008148] 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> [59.008317] 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> [59.008488] 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> [59.008694] 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> [59.008864] 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> [59.009033] 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> [59.009201] 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> [59.009370] 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> [59.009544] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in avi infoframe
<7> [59.009713] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [59.009881] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [59.010049] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [59.010052] i915 0000:00:02.0: colorspace: RGB
<7> [59.010054] i915 0000:00:02.0: scan mode: Underscan
<7> [59.010056] i915 0000:00:02.0: colorimetry: No Data
<7> [59.010058] i915 0000:00:02.0: picture aspect: No Data
<7> [59.010059] i915 0000:00:02.0: active aspect: Same as Picture
<7> [59.010061] i915 0000:00:02.0: itc: No Data
<7> [59.010063] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [59.010065] i915 0000:00:02.0: quantization range: Full
<7> [59.010067] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [59.010069] i915 0000:00:02.0: video code: 0
<7> [59.010071] i915 0000:00:02.0: ycc quantization range: Full
<7> [59.010072] i915 0000:00:02.0: hdmi content type: Graphics
<7> [59.010074] i915 0000:00:02.0: pixel repeat: 0
<7> [59.010076] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [59.010078] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in spd infoframe
<7> [59.010247] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [59.010413] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [59.010606] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [59.010609] i915 0000:00:02.0: vendor: Intel
<7> [59.010611] i915 0000:00:02.0: product: Integrated gfx
<7> [59.010613] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [59.010615] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hdmi infoframe
<7> [59.010783] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [59.010950] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [59.011117] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [59.011119] i915 0000:00:02.0: empty frame
<7> [59.011121] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:171:pipe A] fastset requirement not met, forcing full modeset
<7> [59.011328] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [59.011500] 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> [59.011780] 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> [59.011948] 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> [59.012114] 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> [59.012279] 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> [59.012445] 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> [59.012649] 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> [59.012838] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:171:pipe A] data rate 1142000 num active planes 1
<7> [59.013015] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14993 required 1142
<7> [59.013188] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [59.013381] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:171:pipe A] min cdclk: 0 kHz -> 192000 kHz
<7> [59.013603] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 0 kHz -> 17844 kHz
<7> [59.013814] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [59.013985] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 192000 kHz, actual 192000 kHz
<7> [59.014155] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [59.014325] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:171:pipe A] allocated DPLL 0
<7> [59.014510] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:171:pipe A] reserving DPLL 0
<7> [59.014693] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:171:pipe A] enable: yes [modeset]
<7> [59.014870] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [59.015043] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [59.015215] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [59.015387] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [59.015591] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [59.015761] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [59.015930] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [59.016099] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] GCP: 0x0
<7> [59.016268] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [59.016270] i915 0000:00:02.0: colorspace: RGB
<7> [59.016272] i915 0000:00:02.0: scan mode: Underscan
<7> [59.016274] i915 0000:00:02.0: colorimetry: No Data
<7> [59.016276] i915 0000:00:02.0: picture aspect: No Data
<7> [59.016278] i915 0000:00:02.0: active aspect: Same as Picture
<7> [59.016279] i915 0000:00:02.0: itc: No Data
<7> [59.016281] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [59.016283] i915 0000:00:02.0: quantization range: Full
<7> [59.016285] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [59.016287] i915 0000:00:02.0: video code: 0
<7> [59.016288] i915 0000:00:02.0: ycc quantization range: Full
<7> [59.016290] i915 0000:00:02.0: hdmi content type: Graphics
<7> [59.016292] i915 0000:00:02.0: pixel repeat: 0
<7> [59.016294] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [59.016296] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [59.016298] i915 0000:00:02.0: vendor: Intel
<7> [59.016300] i915 0000:00:02.0: product: Integrated gfx
<7> [59.016302] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [59.016304] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [59.016306] i915 0000:00:02.0: empty frame
<7> [59.016308] 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> [59.016482] 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> [59.016651] 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> [59.016819] 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> [59.016987] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [59.017154] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [59.017322] 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> [59.017498] 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> [59.017667] 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> [59.017836] 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> [59.018004] 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> [59.018173] 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> [59.018342] 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> [59.018540] 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> [59.018709] 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> [59.018882] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 59, ips linetime: 0
<7> [59.019053] 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> [59.019224] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1920x1080+0+0
<7> [59.019392] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [59.019603] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [59.019830] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_2
<7> [59.019836] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [59.020077] 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> [59.020272] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [59.020323] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_3
<7> [59.020513] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [59.020693] i915 0000:00:02.0: [drm:intel_audio_component_get_power [i915]] restored AUD_FREQ_CNTRL to 0x810
<7> [59.020735] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [59.020976] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [59.021132] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [59.021288] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [59.021464] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [59.021620] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [59.021775] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [59.021935] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [59.022096] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [59.022255] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [59.022425] 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> [59.022589] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [59.022746] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [59.022903] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [59.023060] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [59.023216] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [59.023372] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [59.023554] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:145:plane 6A] fb: [NOFB], visible: no
<7> [59.023724] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:155:plane 7A] fb: [NOFB], visible: no
<7> [59.023879] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:165:cursor A] fb: [NOFB], visible: no
<7> [59.024182] 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> [59.024453] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:587:DDI B/PHY B]
<7> [59.024623] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:597:DDI TC1/PHY TC1]
<7> [59.024807] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:599:DP-MST A]
<7> [59.024969] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:600:DP-MST B]
<7> [59.025128] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:601:DP-MST C]
<7> [59.025287] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:602:DP-MST D]
<7> [59.025432] azx_init_pci: snd_hda_intel 0000:00:1f.3: Clearing TCSEL
<7> [59.025468] azx_init_pci: snd_hda_intel 0000:00:1f.3: SCH snoop: Enabled
<7> [59.025521] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:607:DDI TC2/PHY TC2]
<7> [59.025700] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:609:DP-MST A]
<7> [59.025862] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DP-MST B]
<7> [59.026022] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:611:DP-MST C]
<7> [59.026181] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:612:DP-MST D]
<7> [59.026338] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:616:DDI TC3/PHY TC3]
<7> [59.026613] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:618:DP-MST A]
<7> [59.026769] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:619:DP-MST B]
<7> [59.026926] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:620:DP-MST C]
<7> [59.027087] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:621:DP-MST D]
<7> [59.027243] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:625:DDI TC4/PHY TC4]
<7> [59.027445] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST A]
<7> [59.027602] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:628:DP-MST B]
<7> [59.027758] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:629:DP-MST C]
<7> [59.027913] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:630:DP-MST D]
<7> [59.028201] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [59.028502] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:171:pipe A]
<7> [59.028667] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0
<7> [59.029076] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_B
<7> [59.029321] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [59.029675] 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> [59.030240] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [59.030445] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [59.030992] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [59.031146] 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> [59.031163] 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> [59.035314] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1
<7> [59.035486] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:35:plane 1A]
<7> [59.039797] 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> [59.048148] 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> [59.048344] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] Configuring HDMI audio for pixel clock 25200 (0x00010000)
<7> [59.048507] i915 0000:00:02.0: [drm:hsw_audio_config_update [i915]] using automatic N
<6> [59.048675] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 5 not expected
<6> [59.048677] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 0 not expected
<6> [59.048679] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 10 not expected
<6> [59.048680] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 6 not expected
<7> [59.048818] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:588:HDMI-A-1]
<7> [59.049077] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:171:pipe A]
<7> [59.109751] i915 0000:00:02.0: [drm:intel_audio_component_get_power [i915]] restored AUD_FREQ_CNTRL to 0x810
<7> [59.114189] azx_init_pci: snd_hda_intel 0000:00:1f.3: Clearing TCSEL
<7> [59.114224] azx_init_pci: snd_hda_intel 0000:00:1f.3: SCH snoop: Enabled
<6> [66.792696] [IGT] kms_flip: finished subtest A-HDMI-A1, FAIL
|