Result:
git-log-oneline i915_display_info10 igt_runner10 runtimes10 results10.json results10-i915-load.json i915_display_info_post_exec10 boot10 dmesg10
| Detail | Value |
|---|---|
| Duration | 18.60 seconds |
| Hostname |
shard-tglu-3 |
| Igt-Version |
IGT-Version: 2.2-g784deedaa (x86_64) (Linux: 6.18.0-rc6-CI_DRM_17572-ga95032b2166b+ x86_64) |
| Out |
Starting dynamic subtest: B-HDMI-A1 1920x1080: 120 285500 1920 1968 2000 2080 1080 1083 1088 1144 0x48 0x5 Expected frametime: 8335us; measured 8334.6us +- 2.335us accuracy 0.08% Event flip: expected 902, counted 890, passrate = 58.43%, encoder type 2 Event vblank: expected 902, counted 890, passrate = 57.30%, encoder type 2 Expected frametime: 8335us; measured 8475.6us +- 2.918us accuracy 0.10% vblank interval differs from modeline! expected 8334.6us, measured 8476us +- 2.918us, difference 141.1us (48.3 sigma) Event flip: expected 894, counted 900, passrate = 33.33%, encoder type 2 Event vblank: expected 894, counted 900, passrate = 32.22%, encoder type 2 Stack trace: #0 ../lib/igt_core.c:2075 __igt_fail_assert() #1 ../tests/kms_flip.c:428 run_test_on_crtc_set.constprop.0() #2 ../tests/kms_flip.c:1889 run_test() #3 ../tests/kms_flip.c:2122 __igt_unique____real_main2045() #4 ../tests/kms_flip.c:2045 main() #5 [__libc_init_first+0x8a] #6 [__libc_start_main+0x8b] #7 [_start+0x25] Dynamic subtest B-HDMI-A1: FAIL (18.600s) |
| Err |
Starting dynamic subtest: B-HDMI-A1 (kms_flip:2111) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1709: (kms_flip:2111) CRITICAL: Failed assertion: !retried Dynamic subtest B-HDMI-A1 failed. **** DEBUG **** (kms_flip:2111) DEBUG: name = flip last_ts = 99.527359 last_received_ts = 99.603050 last_seq = 1792 current_ts = 99.611526 current_received_ts = 99.687202 current_seq = 1802 count = 50 seq_step = 1 (kms_flip:2111) DEBUG: flip ts/seq: last 99.527359/1792, current 99.611526/1802: elapsed=84167.0us expected=84756.3us +- 423.8us, error 0.7% (kms_flip:2111) DEBUG: inconsistent flip ts/seq: last 99.527359/1792, current 99.611526/1802: elapsed=84167.0us expected=84756.3us (kms_flip:2111) DEBUG: name = vblank last_ts = 99.603111 last_received_ts = 99.602951 last_seq = 1801 current_ts = 99.687286 current_received_ts = 99.687149 current_seq = 1811 count = 50 seq_step = 10 (kms_flip:2111) DEBUG: vblank ts/seq: last 99.603111/1801, current 99.687286/1811: elapsed=84177.0us expected=84756.3us +- 423.8us, error 0.7% (kms_flip:2111) DEBUG: inconsistent vblank ts/seq: last 99.603111/1801, current 99.687286/1811: elapsed=84177.0us expected=84756.3us (kms_flip:2111) DEBUG: name = flip last_ts = 99.611526 last_received_ts = 99.687202 last_seq = 1802 current_ts = 99.695702 current_received_ts = 99.771286 current_seq = 1812 count = 51 seq_step = 1 (kms_flip:2111) DEBUG: flip ts/seq: last 99.611526/1802, current 99.695702/1812: elapsed=84178.0us expected=84756.3us +- 423.8us, error 0.7% (kms_flip:2111) DEBUG: inconsistent flip ts/seq: last 99.611526/1802, current 99.695702/1812: elapsed=84178.0us expected=84756.3us (kms_flip:2111) DEBUG: name = vblank last_ts = 99.687286 last_received_ts = 99.687149 last_seq = 1811 current_ts = 99.771454 current_received_ts = 99.771233 current_seq = 1821 count = 51 seq_step = 10 (kms_flip:2111) DEBUG: vblank ts/seq: last 99.687286/1811, current 99.771454/1821: elapsed=84168.0us expected=84756.3us +- 423.8us, error 0.7% (kms_flip:2111) DEBUG: inconsistent vblank ts/seq: last 99.687286/1811, current 99.771454/1821: elapsed=84168.0us expected=84756.3us (kms_flip:2111) DEBUG: name = flip last_ts = 99.695702 last_received_ts = 99.771286 last_seq = 1812 current_ts = 99.779869 current_received_ts = 99.855408 current_seq = 1822 count = 52 seq_step = 1 (kms_flip:2111) DEBUG: flip ts/seq: last 99.695702/1812, current 99.779869/1822: elapsed=84169.0us expected=84756.3us +- 423.8us, error 0.7% (kms_flip:2111) DEBUG: inconsistent flip ts/seq: last 99.695702/1812, current 99.779869/1822: elapsed=84169.0us expected=84756.3us (kms_flip:2111) DEBUG: name = vblank last_ts = 99.771454 last_received_ts = 99.771233 last_seq = 1821 current_ts = 99.855606 current_received_ts = 99.855354 current_seq = 1831 count = 52 seq_step = 10 (kms_flip:2111) DEBUG: vblank ts/seq: last 99.771454/1821, current 99.855606/1831: elapsed=84153.0us expected=84756.3us +- 423.8us, error 0.7% (kms_flip:2111) DEBUG: inconsistent vblank ts/seq: last 99.771454/1821, current 99.855606/1831: elapsed=84153.0us expected=84756.3us (kms_flip:2111) DEBUG: name = flip last_ts = 99.779869 last_received_ts = 99.855408 last_seq = 1822 current_ts = 99.864014 current_received_ts = 99.939507 current_seq = 1832 count = 53 seq_step = 1 (kms_flip:2111) DEBUG: flip ts/seq: last 99.779869/1822, current 99.864014/1832: elapsed=84140.0us expected=84756.3us +- 423.8us, error 0.7% (kms_flip:2111) DEBUG: inconsistent flip ts/seq: last 99.779869/1822, current 99.864014/1832: elapsed=84140.0us expected=84756.3us (kms_flip:2111) DEBUG: name = vblank last_ts = 99.855606 last_received_ts = 99.855354 last_seq = 1831 current_ts = 99.939674 current_received_ts = 99.939453 current_seq = 1841 count = 53 seq_step = 10 (kms_flip:2111) DEBUG: vblank ts/seq: last 99.855606/1831, current 99.939674/1841: elapsed=84071.0us expected=84756.3us +- 423.8us, error 0.8% (kms_flip:2111) DEBUG: inconsistent vblank ts/seq: last 99.855606/1831, current 99.939674/1841: elapsed=84071.0us expected=84756.3us (kms_flip:2111) DEBUG: name = flip last_ts = 99.864014 last_received_ts = 99.939507 last_seq = 1832 current_ts = 99.948082 current_received_ts = 100.023643 current_seq = 1842 count = 54 seq_step = 1 (kms_flip:2111) DEBUG: flip ts/seq: last 99.864014/1832, current 99.948082/1842: elapsed=84070.0us expected=84756.3us +- 423.8us, error 0.8% (kms_flip:2111) DEBUG: inconsistent flip ts/seq: last 99.864014/1832, current 99.948082/1842: elapsed=84070.0us expected=84756.3us (kms_flip:2111) DEBUG: name = vblank last_ts = 99.939674 last_received_ts = 99.939453 last_seq = 1841 current_ts = 100.023743 current_received_ts = 100.023590 current_seq = 1851 count = 54 seq_step = 10 (kms_flip:2111) DEBUG: vblank ts/seq: last 99.939674/1841, current 100.023743/1851: elapsed=84067.0us expected=84756.3us +- 423.8us, error 0.8% (kms_flip:2111) DEBUG: inconsistent vblank ts/seq: last 99.939674/1841, current 100.023743/1851: elapsed=84067.0us expected=84756.3us (kms_flip:2111) DEBUG: name = flip last_ts = 99.948082 last_received_ts = 100.023643 last_seq = 1842 current_ts = 100.032150 current_received_ts = 100.107620 current_seq = 1852 count = 55 seq_step = 1 (kms_flip:2111) DEBUG: flip ts/seq: last 99.948082/1842, current 100.032150/1852: elapsed=84070.0us expected=84756.3us +- 423.8us, error 0.8% (kms_flip:2111) DEBUG: inconsistent flip ts/seq: last 99.948082/1842, current 100.032150/1852: elapsed=84070.0us expected=84756.3us (kms_flip:2111) DEBUG: name = vblank last_ts = 100.023743 last_received_ts = 100.023590 last_seq = 1851 current_ts = 100.107811 current_received_ts = 100.107567 current_seq = 1861 count = 55 seq_step = 10 (kms_flip:2111) DEBUG: vblank ts/seq: last 100.023743/1851, current 100.107811/1861: elapsed=84068.0us expected=84756.3us +- 423.8us, error 0.8% (kms_flip:2111) DEBUG: inconsistent vblank ts/seq: last 100.023743/1851, current 100.107811/1861: elapsed=84068.0us expected=84756.3us (kms_flip:2111) DEBUG: name = flip last_ts = 100.032150 last_received_ts = 100.107620 last_seq = 1852 current_ts = 100.116219 current_received_ts = 100.191689 current_seq = 1862 count = 56 seq_step = 1 (kms_flip:2111) DEBUG: flip ts/seq: last 100.032150/1852, current 100.116219/1862: elapsed=84066.0us expected=84756.3us +- 423.8us, error 0.8% (kms_flip:2111) DEBUG: inconsistent flip ts/seq: last 100.032150/1852, current 100.116219/1862: elapsed=84066.0us expected=84756.3us (kms_flip:2111) DEBUG: name = vblank last_ts = 100.107811 last_received_ts = 100.107567 last_seq = 1861 current_ts = 100.191879 current_received_ts = 100.191635 current_seq = 1871 count = 56 seq_step = 10 (kms_flip:2111) DEBUG: vblank ts/seq: last 100.107811/1861, current 100.191879/1871: elapsed=84067.0us expected=84756.3us +- 423.8us, error 0.8% (kms_flip:2111) DEBUG: inconsistent vblank ts/seq: last 100.107811/1861, current 100.191879/1871: elapsed=84067.0us expected=84756.3us (kms_flip:2111) DEBUG: name = flip last_ts = 100.116219 last_received_ts = 100.191689 last_seq = 1862 current_ts = 100.200287 current_received_ts = 100.275780 current_seq = 1872 count = 57 seq_step = 1 (kms_flip:2111) DEBUG: flip ts/seq: last 100.116219/1862, current 100.200287/1872: elapsed=84066.0us expected=84756.3us +- 423.8us, error 0.8% (kms_flip:2111) DEBUG: inconsistent flip ts/seq: last 100.116219/1862, current 100.200287/1872: elapsed=84066.0us expected=84756.3us (kms_flip:2111) DEBUG: name = vblank last_ts = 100.191879 last_received_ts = 100.191635 last_seq = 1871 current_ts = 100.275948 current_received_ts = 100.275726 current_seq = 1881 count = 57 seq_step = 10 (kms_flip:2111) DEBUG: vblank ts/seq: last 100.191879/1871, current 100.275948/1881: elapsed=84067.0us expected=84756.3us +- 423.8us, error 0.8% (kms_flip:2111) DEBUG: inconsistent vblank ts/seq: last 100.191879/1871, current 100.275948/1881: elapsed=84067.0us expected=84756.3us (kms_flip:2111) DEBUG: name = flip last_ts = 100.200287 last_received_ts = 100.275780 last_seq = 1872 current_ts = 100.284348 current_received_ts = 100.359924 current_seq = 1882 count = 58 seq_step = 1 (kms_flip:2111) DEBUG: flip ts/seq: last 100.200287/1872, current 100.284348/1882: elapsed=84066.0us expected=84756.3us +- 423.8us, error 0.8% (kms_flip:2111) DEBUG: inconsistent flip ts/seq: last 100.200287/1872, current 100.284348/1882: elapsed=84066.0us expected=84756.3us (kms_flip:2111) DEBUG: name = vblank last_ts = 100.275948 last_received_ts = 100.275726 last_seq = 1881 current_ts = 100.360016 current_received_ts = 100.359879 current_seq = 1891 count = 58 seq_step = 10 (kms_flip:2111) DEBUG: vblank ts/seq: last 100.275948/1881, current 100.360016/1891: elapsed=84070.0us expected=84756.3us +- 423.8us, error 0.8% (kms_flip:2111) DEBUG: inconsistent vblank ts/seq: last 100.275948/1881, current 100.360016/1891: elapsed=84070.0us expected=84756.3us (kms_flip:2111) DEBUG: name = flip last_ts = 100.284348 last_received_ts = 100.359924 last_seq = 1882 current_ts = 100.368423 current_received_ts = 100.443916 current_seq = 1892 count = 59 seq_step = 1 (kms_flip:2111) DEBUG: flip ts/seq: last 100.284348/1882, current 100.368423/1892: elapsed=84070.0us expected=84756.3us +- 423.8us, error 0.8% (kms_flip:2111) DEBUG: inconsistent flip ts/seq: last 100.284348/1882, current 100.368423/1892: elapsed=84070.0us expected=84756.3us (kms_flip:2111) DEBUG: name = vblank last_ts = 100.360016 last_received_ts = 100.359879 last_seq = 1891 current_ts = 100.444084 current_received_ts = 100.443871 current_seq = 1901 count = 59 seq_step = 10 (kms_flip:2111) DEBUG: vblank ts/seq: last 100.360016/1891, current 100.444084/1901: elapsed=84066.0us expected=84756.3us +- 423.8us, error 0.8% (kms_flip:2111) DEBUG: inconsistent vblank ts/seq: last 100.360016/1891, current 100.444084/1901: elapsed=84066.0us expected=84756.3us (kms_flip:2111) DEBUG: name = flip last_ts = 100.368423 last_received_ts = 100.443916 last_seq = 1892 current_ts = 100.452492 current_received_ts = 100.528061 current_seq = 1902 count = 60 seq_step = 1 (kms_flip:2111) DEBUG: flip ts/seq: last 100.368423/1892, current 100.452492/1902: elapsed=84069.0us expected=84756.3us +- 423.8us, error 0.8% (kms_flip:2111) DEBUG: inconsistent flip ts/seq: last 100.368423/1892, current 100.452492/1902: elapsed=84069.0us expected=84756.3us (kms_flip:2111) DEBUG: name = vblank last_ts = 100.444084 last_received_ts = 100.443871 last_seq = 1901 current_ts = 100.528152 current_received_ts = 100.527985 current_seq = 1911 count = 60 seq_step = 10 (kms_flip:2111) DEBUG: vblank ts/seq: last 100.444084/1901, current 100.528152/1911: elapsed=84070.0us expected=84756.3us +- 423.8us, error 0.8% (kms_flip:2111) DEBUG: inconsistent vblank ts/seq: last 100.444084/1901, current 100.528152/1911: elapsed=84070.0us expected=84756.3us (kms_flip:2111) DEBUG: name = flip last_ts = 100.452492 last_received_ts = 100.528061 last_seq = 1902 current_ts = 100.536552 current_received_ts = 100.612137 current_seq = 1912 count = 61 seq_step = 1 (kms_flip:2111) DEBUG: flip ts/seq: last 100.452492/1902, current 100.536552/1912: elapsed=84065.0us expected=84756.3us +- 423.8us, error 0.8% (kms_flip:2111) DEBUG: inconsistent flip ts/seq: last 100.452492/1902, current 100.536552/1912: elapsed=84065.0us expected=84756.3us (kms_flip:2111) DEBUG: name = vblank last_ts = 100.528152 last_received_ts = 100.527985 last_seq = 1911 current_ts = 100.612221 current_received_ts = 100.612091 current_seq = 1921 count = 61 seq_step = 10 (kms_flip:2111) DEBUG: vblank ts/seq: last 100.528152/1911, current 100.612221/1921: elapsed=84070.0us expected=84756.3us +- 423.8us, error 0.8% (kms_flip:2111) DEBUG: inconsistent vblank ts/seq: last 100.528152/1911, current 100.612221/1921: elapsed=84070.0us expected=84756.3us (kms_flip:2111) DEBUG: name = flip last_ts = 100.536552 last_received_ts = 100.612137 last_seq = 1912 current_ts = 100.620628 current_received_ts = 100.696091 current_seq = 1922 count = 62 seq_step = 1 (kms_flip:2111) DEBUG: flip ts/seq: last 100.536552/1912, current 100.620628/1922: elapsed=84072.0us expected=84756.3us +- 423.8us, error 0.8% (kms_flip:2111) DEBUG: inconsistent flip ts/seq: last 100.536552/1912, current 100.620628/1922: elapsed=84072.0us expected=84756.3us (kms_flip:2111) DEBUG: name = vblank last_ts = 100.612221 last_received_ts = 100.612091 last_seq = 1921 current_ts = 100.696289 current_received_ts = 100.696037 current_seq = 1931 count = 62 seq_step = 10 (kms_flip:2111) DEBUG: vblank ts/seq: last 100.612221/1921, current 100.696289/1931: elapsed=84067.0us expected=84756.3us +- 423.8us, error 0.8% (kms_flip:2111) DEBUG: inconsistent vblank ts/seq: last 100.612221/1921, current 100.696289/1931: elapsed=84067.0us expected=84756.3us (kms_flip:2111) DEBUG: name = flip last_ts = 100.620628 last_received_ts = 100.696091 last_seq = 1922 current_ts = 100.704689 current_received_ts = 100.780075 current_seq = 1932 count = 63 seq_step = 1 (kms_flip:2111) DEBUG: flip ts/seq: last 100.620628/1922, current 100.704689/1932: elapsed=84066.0us expected=84756.3us +- 423.8us, error 0.8% (kms_flip:2111) DEBUG: inconsistent flip ts/seq: last 100.620628/1922, current 100.704689/1932: elapsed=84066.0us expected=84756.3us (kms_flip:2111) DEBUG: name = vblank last_ts = 100.696289 last_received_ts = 100.696037 last_seq = 1931 current_ts = 100.780350 current_received_ts = 100.780037 current_seq = 1941 count = 63 seq_step = 10 (kms_flip:2111) DEBUG: vblank ts/seq: last 100.696289/1931, current 100.780350/1941: elapsed=84063.0us expected=84756.3us +- 423.8us, error 0.8% (kms_flip:2111) DEBUG: inconsistent vblank ts/seq: last 100.696289/1931, current 100.780350/1941: elapsed=84063.0us expected=84756.3us (kms_flip:2111) DEBUG: name = flip last_ts = 100.704689 last_received_ts = 100.780075 last_seq = 1932 current_ts = 100.788765 current_received_ts = 100.864212 current_seq = 1942 count = 64 seq_step = 1 (kms_flip:2111) DEBUG: flip ts/seq: last 100.704689/1932, current 100.788765/1942: elapsed=84074.0us expected=84756.3us +- 423.8us, error 0.8% (kms_flip:2111) DEBUG: inconsistent flip ts/seq: last 100.704689/1932, current 100.788765/1942: elapsed=84074.0us expected=84756.3us (kms_flip:2111) DEBUG: name = vblank last_ts = 100.780350 last_received_ts = 100.780037 last_seq = 1941 current_ts = 100.864388 current_received_ts = 100.864159 current_seq = 1951 count = 64 seq_step = 10 (kms_flip:2111) DEBUG: vblank ts/seq: last 100.780350/1941, current 100.864388/1951: elapsed=84035.0us expected=84756.3us +- 423.8us, error 0.9% (kms_flip:2111) DEBUG: inconsistent vblank ts/seq: last 100.780350/1941, current 100.864388/1951: elapsed=84035.0us expected=84756.3us (kms_flip:2111) DEBUG: name = flip last_ts = 100.788765 last_received_ts = 100.864212 last_seq = 1942 current_ts = 100.872787 current_received_ts = 100.948273 current_seq = 1952 count = 65 seq_step = 1 (kms_flip:2111) DEBUG: flip ts/seq: last 100.788765/1942, current 100.872787/1952: elapsed=84019.0us expected=84756.3us +- 423.8us, error 0.9% (kms_flip:2111) DEBUG: inconsistent flip ts/seq: last 100.788765/1942, current 100.872787/1952: elapsed=84019.0us expected=84756.3us (kms_flip:2111) DEBUG: name = vblank last_ts = 100.864388 last_received_ts = 100.864159 last_seq = 1951 current_ts = 100.948372 current_received_ts = 100.948219 current_seq = 1961 count = 65 seq_step = 10 (kms_flip:2111) DEBUG: vblank ts/seq: last 100.864388/1951, current 100.948372/1961: elapsed=83981.0us expected=84756.3us +- 423.8us, error 0.9% (kms_flip:2111) DEBUG: inconsistent vblank ts/seq: last 100.864388/1951, current 100.948372/1961: elapsed=83981.0us expected=84756.3us (kms_flip:2111) DEBUG: name = flip last_ts = 100.872787 last_received_ts = 100.948273 last_seq = 1952 current_ts = 100.956764 current_received_ts = 101.032166 current_seq = 1962 count = 66 seq_step = 1 (kms_flip:2111) DEBUG: flip ts/seq: last 100.872787/1952, current 100.956764/1962: elapsed=83980.0us expected=84756.3us +- 423.8us, error 0.9% (kms_flip:2111) DEBUG: inconsistent flip ts/seq: last 100.872787/1952, current 100.956764/1962: elapsed=83980.0us expected=84756.3us (kms_flip:2111) DEBUG: name = vblank last_ts = 100.948372 last_received_ts = 100.948219 last_seq = 1961 current_ts = 101.032349 current_received_ts = 101.032120 current_seq = 1971 count = 66 seq_step = 10 (kms_flip:2111) DEBUG: vblank ts/seq: last 100.948372/1961, current 101.032349/1971: elapsed=83977.0us expected=84756.3us +- 423.8us, error 0.9% (kms_flip:2111) DEBUG: inconsistent vblank ts/seq: last 100.948372/1961, current 101.032349/1971: elapsed=83977.0us expected=84756.3us (kms_flip:2111) DEBUG: name = flip last_ts = 100.956764 last_received_ts = 101.032166 last_seq = 1962 current_ts = 101.040741 current_received_ts = 101.116226 current_seq = 1972 count = 67 seq_step = 1 (kms_flip:2111) DEBUG: flip ts/seq: last 100.956764/1962, current 101.040741/1972: elapsed=83976.0us expected=84756.3us +- 423.8us, error 0.9% (kms_flip:2111) DEBUG: inconsistent flip ts/seq: last 100.956764/1962, current 101.040741/1972: elapsed=83976.0us expected=84756.3us (kms_flip:2111) DEBUG: name = vblank last_ts = 101.032349 last_received_ts = 101.032120 last_seq = 1971 current_ts = 101.116325 current_received_ts = 101.116180 current_seq = 1981 count = 67 seq_step = 10 (kms_flip:2111) DEBUG: vblank ts/seq: last 101.032349/1971, current 101.116325/1981: elapsed=83977.0us expected=84756.3us +- 423.8us, error 0.9% (kms_flip:2111) DEBUG: inconsistent vblank ts/seq: last 101.032349/1971, current 101.116325/1981: elapsed=83977.0us expected=84756.3us (kms_flip:2111) DEBUG: name = flip last_ts = 101.040741 last_received_ts = 101.116226 last_seq = 1972 current_ts = 101.124718 current_received_ts = 101.200195 current_seq = 1982 count = 68 seq_step = 1 (kms_flip:2111) DEBUG: flip ts/seq: last 101.040741/1972, current 101.124718/1982: elapsed=83979.0us expected=84756.3us +- 423.8us, error 0.9% (kms_flip:2111) DEBUG: inconsistent flip ts/seq: last 101.040741/1972, current 101.124718/1982: elapsed=83979.0us expected=84756.3us (kms_flip:2111) DEBUG: name = vblank last_ts = 101.116325 last_received_ts = 101.116180 last_seq = 1981 current_ts = 101.200302 current_received_ts = 101.200142 current_seq = 1991 count = 68 seq_step = 10 (kms_flip:2111) DEBUG: vblank ts/seq: last 101.116325/1981, current 101.200302/1991: elapsed=83976.0us expected=84756.3us +- 423.8us, error 0.9% (kms_flip:2111) DEBUG: inconsistent vblank ts/seq: last 101.116325/1981, current 101.200302/1991: elapsed=83976.0us expected=84756.3us (kms_flip:2111) DEBUG: name = flip last_ts = 101.124718 last_received_ts = 101.200195 last_seq = 1982 current_ts = 101.208702 current_received_ts = 101.284073 current_seq = 1992 count = 69 seq_step = 1 (kms_flip:2111) DEBUG: flip ts/seq: last 101.124718/1982, current 101.208702/1992: elapsed=83981.0us expected=84756.3us +- 423.8us, error 0.9% (kms_flip:2111) DEBUG: inconsistent flip ts/seq: last 101.124718/1982, current 101.208702/1992: elapsed=83981.0us expected=84756.3us (kms_flip:2111) DEBUG: name = vblank last_ts = 101.200302 last_received_ts = 101.200142 last_seq = 1991 current_ts = 101.284279 current_received_ts = 101.283966 current_seq = 2001 count = 69 seq_step = 10 (kms_flip:2111) DEBUG: vblank ts/seq: last 101.200302/1991, current 101.284279/2001: elapsed=83982.0us expected=84756.3us +- 423.8us, error 0.9% (kms_flip:2111) DEBUG: inconsistent vblank ts/seq: last 101.200302/1991, current 101.284279/2001: elapsed=83982.0us expected=84756.3us (kms_flip:2111) DEBUG: name = flip last_ts = 101.208702 last_received_ts = 101.284073 last_seq = 1992 current_ts = 101.292671 current_received_ts = 101.368088 current_seq = 2002 count = 70 seq_step = 1 (kms_flip:2111) DEBUG: flip ts/seq: last 101.208702/1992, current 101.292671/2002: elapsed=83971.0us expected=84756.3us +- 423.8us, error 0.9% (kms_flip:2111) DEBUG: inconsistent flip ts/seq: last 101.208702/1992, current 101.292671/2002: elapsed=83971.0us expected=84756.3us (kms_flip:2111) DEBUG: name = vblank last_ts = 101.284279 last_received_ts = 101.283966 last_seq = 2001 current_ts = 101.368263 current_received_ts = 101.368034 current_seq = 2011 count = 70 seq_step = 10 (kms_flip:2111) DEBUG: vblank ts/seq: last 101.284279/2001, current 101.368263/2011: elapsed=83979.0us expected=84756.3us +- 423.8us, error 0.9% (kms_flip:2111) DEBUG: inconsistent vblank ts/seq: last 101.284279/2001, current 101.368263/2011: elapsed=83979.0us expected=84756.3us (kms_flip:2111) DEBUG: name = flip last_ts = 101.292671 last_received_ts = 101.368088 last_seq = 2002 current_ts = 101.376648 current_received_ts = 101.452141 current_seq = 2012 count = 71 seq_step = 1 (kms_flip:2111) DEBUG: flip ts/seq: last 101.292671/2002, current 101.376648/2012: elapsed=83979.0us expected=84756.3us +- 423.8us, error 0.9% (kms_flip:2111) DEBUG: inconsistent flip ts/seq: last 101.292671/2002, current 101.376648/2012: elapsed=83979.0us expected=84756.3us (kms_flip:2111) DEBUG: name = vblank last_ts = 101.368263 last_received_ts = 101.368034 last_seq = 2011 current_ts = 101.452232 current_received_ts = 101.452095 current_seq = 2021 count = 71 seq_step = 10 (kms_flip:2111) DEBUG: vblank ts/seq: last 101.368263/2011, current 101.452232/2021: elapsed=83974.0us expected=84756.3us +- 423.8us, error 0.9% (kms_flip:2111) DEBUG: inconsistent vblank ts/seq: last 101.368263/2011, current 101.452232/2021: elapsed=83974.0us expected=84756.3us (kms_flip:2111) DEBUG: name = flip last_ts = 101.376648 last_received_ts = 101.452141 last_seq = 2012 current_ts = 101.460625 current_received_ts = 101.536095 current_seq = 2022 count = 72 seq_step = 1 (kms_flip:2111) DEBUG: flip ts/seq: last 101.376648/2012, current 101.460625/2022: elapsed=83977.0us expected=84756.3us +- 423.8us, error 0.9% (kms_flip:2111) DEBUG: inconsistent flip ts/seq: last 101.376648/2012, current 101.460625/2022: elapsed=83977.0us expected=84756.3us (kms_flip:2111) DEBUG: name = vblank last_ts = 101.452232 last_received_ts = 101.452095 last_seq = 2021 current_ts = 101.536209 current_received_ts = 101.535995 current_seq = 2031 count = 72 seq_step = 10 (kms_flip:2111) DEBUG: vblank ts/seq: last 101.452232/2021, current 101.536209/2031: elapsed=83978.0us expected=84756.3us +- 423.8us, error 0.9% (kms_flip:2111) DEBUG: inconsistent vblank ts/seq: last 101.452232/2021, current 101.536209/2031: elapsed=83978.0us expected=84756.3us (kms_flip:2111) DEBUG: name = flip last_ts = 101.460625 last_received_ts = 101.536095 last_seq = 2022 current_ts = 101.544609 current_received_ts = 101.620102 current_seq = 2032 count = 73 seq_step = 1 (kms_flip:2111) DEBUG: flip ts/seq: last 101.460625/2022, current 101.544609/2032: elapsed=83980.0us expected=84756.3us +- 423.8us, error 0.9% (kms_flip:2111) DEBUG: inconsistent flip ts/seq: last 101.460625/2022, current 101.544609/2032: elapsed=83980.0us expected=84756.3us (kms_flip:2111) DEBUG: name = vblank last_ts = 101.536209 last_received_ts = 101.535995 last_seq = 2031 current_ts = 101.620193 current_received_ts = 101.620049 current_seq = 2041 count = 73 seq_step = 10 (kms_flip:2111) DEBUG: vblank ts/seq: last 101.536209/2031, current 101.620193/2041: elapsed=83981.0us expected=84756.3us +- 423.8us, error 0.9% (kms_flip:2111) DEBUG: inconsistent vblank ts/seq: last 101.536209/2031, current 101.620193/2041: elapsed=83981.0us expected=84756.3us (kms_flip:2111) DEBUG: name = flip last_ts = 101.544609 last_received_ts = 101.620102 last_seq = 2032 current_ts = 101.628586 current_received_ts = 101.703957 current_seq = 2042 count = 74 seq_step = 1 (kms_flip:2111) DEBUG: flip ts/seq: last 101.544609/2032, current 101.628586/2042: elapsed=83980.0us expected=84756.3us +- 423.8us, error 0.9% (kms_flip:2111) DEBUG: inconsistent flip ts/seq: last 101.544609/2032, current 101.628586/2042: elapsed=83980.0us expected=84756.3us (kms_flip:2111) DEBUG: name = vblank last_ts = 101.620193 last_received_ts = 101.620049 last_seq = 2041 current_ts = 101.704170 current_received_ts = 101.703903 current_seq = 2051 count = 74 seq_step = 10 (kms_flip:2111) DEBUG: vblank ts/seq: last 101.620193/2041, current 101.704170/2051: elapsed=83979.0us expected=84756.3us +- 423.8us, error 0.9% (kms_flip:2111) DEBUG: inconsistent vblank ts/seq: last 101.620193/2041, current 101.704170/2051: elapsed=83979.0us expected=84756.3us (kms_flip:2111) DEBUG: name = flip last_ts = 101.628586 last_received_ts = 101.703957 last_seq = 2042 current_ts = 101.712570 current_received_ts = 101.787971 current_seq = 2052 count = 75 seq_step = 1 (kms_flip:2111) DEBUG: flip ts/seq: last 101.628586/2042, current 101.712570/2052: elapsed=83980.0us expected=84756.3us +- 423.8us, error 0.9% (kms_flip:2111) DEBUG: inconsistent flip ts/seq: last 101.628586/2042, current 101.712570/2052: elapsed=83980.0us expected=84756.3us (kms_flip:2111) DEBUG: name = vblank last_ts = 101.704170 last_received_ts = 101.703903 last_seq = 2051 current_ts = 101.788147 current_received_ts = 101.787926 current_seq = 2061 count = 75 seq_step = 10 (kms_flip:2111) DEBUG: vblank ts/seq: last 101.704170/2051, current 101.788147/2061: elapsed=83973.0us expected=84756.3us +- 423.8us, error 0.9% (kms_flip:2111) DEBUG: inconsistent vblank ts/seq: last 101.704170/2051, current 101.788147/2061: elapsed=83973.0us expected=84756.3us (kms_flip:2111) DEBUG: name = flip last_ts = 101.712570 last_received_ts = 101.787971 last_seq = 2052 current_ts = 101.796547 current_received_ts = 101.871925 current_seq = 2062 count = 76 seq_step = 1 (kms_flip:2111) DEBUG: flip ts/seq: last 101.712570/2052, current 101.796547/2062: elapsed=83978.0us expected=84756.3us +- 423.8us, error 0.9% (kms_flip:2111) DEBUG: inconsistent flip ts/seq: last 101.712570/2052, current 101.796547/2062: elapsed=83978.0us expected=84756.3us (kms_flip:2111) DEBUG: name = vblank last_ts = 101.788147 last_received_ts = 101.787926 last_seq = 2061 current_ts = 101.872086 current_received_ts = 101.871872 current_seq = 2071 count = 76 seq_step = 10 (kms_flip:2111) DEBUG: vblank ts/seq: last 101.788147/2061, current 101.872086/2071: elapsed=83944.0us expected=84756.3us +- 423.8us, error 1.0% (kms_flip:2111) DEBUG: inconsistent vblank ts/seq: last 101.788147/2061, current 101.872086/2071: elapsed=83944.0us expected=84756.3us (kms_flip:2111) DEBUG: name = flip last_ts = 101.796547 last_received_ts = 101.871925 last_seq = 2062 current_ts = 101.880486 current_received_ts = 101.955887 current_seq = 2072 count = 77 seq_step = 1 (kms_flip:2111) DEBUG: flip ts/seq: last 101.796547/2062, current 101.880486/2072: elapsed=83936.0us expected=84756.3us +- 423.8us, error 1.0% (kms_flip:2111) DEBUG: inconsistent flip ts/seq: last 101.796547/2062, current 101.880486/2072: elapsed=83936.0us expected=84756.3us (kms_flip:2111) DEBUG: name = vblank last_ts = 101.872086 last_received_ts = 101.871872 last_seq = 2071 current_ts = 101.955994 current_received_ts = 101.955833 current_seq = 2081 count = 77 seq_step = 10 (kms_flip:2111) DEBUG: vblank ts/seq: last 101.872086/2071, current 101.955994/2081: elapsed=83904.0us expected=84756.3us +- 423.8us, error 1.0% (kms_flip:2111) DEBUG: inconsistent vblank ts/seq: last 101.872086/2071, current 101.955994/2081: elapsed=83904.0us expected=84756.3us (kms_flip:2111) DEBUG: name = flip last_ts = 101.880486 last_received_ts = 101.955887 last_seq = 2072 current_ts = 101.964378 current_received_ts = 102.039734 current_seq = 2082 count = 78 seq_step = 1 (kms_flip:2111) DEBUG: flip ts/seq: last 101.880486/2072, current 101.964378/2082: elapsed=83897.0us expected=84756.3us +- 423.8us, error 1.0% (kms_flip:2111) DEBUG: inconsistent flip ts/seq: last 101.880486/2072, current 101.964378/2082: elapsed=83897.0us expected=84756.3us (kms_flip:2111) DEBUG: name = vblank last_ts = 101.955994 last_received_ts = 101.955833 last_seq = 2081 current_ts = 102.039894 current_received_ts = 102.039680 current_seq = 2091 count = 78 seq_step = 10 (kms_flip:2111) DEBUG: vblank ts/seq: last 101.955994/2081, current 102.039894/2091: elapsed=83898.0us expected=84756.3us +- 423.8us, error 1.0% (kms_flip:2111) DEBUG: inconsistent vblank ts/seq: last 101.955994/2081, current 102.039894/2091: elapsed=83898.0us expected=84756.3us (kms_flip:2111) DEBUG: name = flip last_ts = 101.964378 last_received_ts = 102.039734 last_seq = 2082 current_ts = 102.048279 current_received_ts = 102.123596 current_seq = 2092 count = 79 seq_step = 1 (kms_flip:2111) DEBUG: flip ts/seq: last 101.964378/2082, current 102.048279/2092: elapsed=83903.0us expected=84756.3us +- 423.8us, error 1.0% (kms_flip:2111) DEBUG: inconsistent flip ts/seq: last 101.964378/2082, current 102.048279/2092: elapsed=83903.0us expected=84756.3us (kms_flip:2111) DEBUG: name = vblank last_ts = 102.039894 last_received_ts = 102.039680 last_seq = 2091 current_ts = 102.123787 current_received_ts = 102.123543 current_seq = 2101 count = 79 seq_step = 10 (kms_flip:2111) DEBUG: vblank ts/seq: last 102.039894/2091, current 102.123787/2101: elapsed=83898.0us expected=84756.3us +- 423.8us, error 1.0% (kms_flip:2111) DEBUG: inconsistent vblank ts/seq: last 102.039894/2091, current 102.123787/2101: elapsed=83898.0us expected=84756.3us (kms_flip:2111) DEBUG: name = flip last_ts = 102.048279 last_received_ts = 102.123596 last_seq = 2092 current_ts = 102.132179 current_received_ts = 102.207603 current_seq = 2102 count = 80 seq_step = 1 (kms_flip:2111) DEBUG: flip ts/seq: last 102.048279/2092, current 102.132179/2102: elapsed=83898.0us expected=84756.3us +- 423.8us, error 1.0% (kms_flip:2111) DEBUG: inconsistent flip ts/seq: last 102.048279/2092, current 102.132179/2102: elapsed=83898.0us expected=84756.3us (kms_flip:2111) DEBUG: name = vblank last_ts = 102.123787 last_received_ts = 102.123543 last_seq = 2101 current_ts = 102.207687 current_received_ts = 102.207550 current_seq = 2111 count = 80 seq_step = 10 (kms_flip:2111) DEBUG: vblank ts/seq: last 102.123787/2101, current 102.207687/2111: elapsed=83897.0us expected=84756.3us +- 423.8us, error 1.0% (kms_flip:2111) DEBUG: inconsistent vblank ts/seq: last 102.123787/2101, current 102.207687/2111: elapsed=83897.0us expected=84756.3us (kms_flip:2111) DEBUG: name = flip last_ts = 102.132179 last_received_ts = 102.207603 last_seq = 2102 current_ts = 102.216072 current_received_ts = 102.291512 current_seq = 2112 count = 81 seq_step = 1 (kms_flip:2111) DEBUG: flip ts/seq: last 102.132179/2102, current 102.216072/2112: elapsed=83895.0us expected=84756.3us +- 423.8us, error 1.0% (kms_flip:2111) DEBUG: inconsistent flip ts/seq: last 102.132179/2102, current 102.216072/2112: elapsed=83895.0us expected=84756.3us (kms_flip:2111) DEBUG: name = vblank last_ts = 102.207687 last_received_ts = 102.207550 last_seq = 2111 current_ts = 102.291588 current_received_ts = 102.291458 current_seq = 2121 count = 81 seq_step = 10 (kms_flip:2111) DEBUG: vblank ts/seq: last 102.207687/2111, current 102.291588/2121: elapsed=83904.0us expected=84756.3us +- 423.8us, error 1.0% (kms_flip:2111) DEBUG: inconsistent vblank ts/seq: last 102.207687/2111, current 102.291588/2121: elapsed=83904.0us expected=84756.3us (kms_flip:2111) DEBUG: name = flip last_ts = 102.216072 last_received_ts = 102.291512 last_seq = 2112 current_ts = 102.299973 current_received_ts = 102.375305 current_seq = 2122 count = 82 seq_step = 1 (kms_flip:2111) DEBUG: flip ts/seq: last 102.216072/2112, current 102.299973/2122: elapsed=83899.0us expected=84756.3us +- 423.8us, error 1.0% (kms_flip:2111) DEBUG: inconsistent flip ts/seq: last 102.216072/2112, current 102.299973/2122: elapsed=83899.0us expected=84756.3us (kms_flip:2111) DEBUG: name = vblank last_ts = 102.291588 last_received_ts = 102.291458 last_seq = 2121 current_ts = 102.375488 current_received_ts = 102.375259 current_seq = 2131 count = 82 seq_step = 10 (kms_flip:2111) DEBUG: vblank ts/seq: last 102.291588/2121, current 102.375488/2131: elapsed=83896.0us expected=84756.3us +- 423.8us, error 1.0% (kms_flip:2111) DEBUG: inconsistent vblank ts/seq: last 102.291588/2121, current 102.375488/2131: elapsed=83896.0us expected=84756.3us (kms_flip:2111) DEBUG: name = flip last_ts = 102.299973 last_received_ts = 102.375305 last_seq = 2122 current_ts = 102.383881 current_received_ts = 102.459290 current_seq = 2132 count = 83 seq_step = 1 (kms_flip:2111) DEBUG: flip ts/seq: last 102.299973/2122, current 102.383881/2132: elapsed=83905.0us expected=84756.3us +- 423.8us, error 1.0% (kms_flip:2111) DEBUG: inconsistent flip ts/seq: last 102.299973/2122, current 102.383881/2132: elapsed=83905.0us expected=84756.3us (kms_flip:2111) DEBUG: name = vblank last_ts = 102.375488 last_received_ts = 102.375259 last_seq = 2131 current_ts = 102.459389 current_received_ts = 102.459244 current_seq = 2141 count = 83 seq_step = 10 (kms_flip:2111) DEBUG: vblank ts/seq: last 102.375488/2131, current 102.459389/2141: elapsed=83903.0us expected=84756.3us +- 423.8us, error 1.0% (kms_flip:2111) DEBUG: inconsistent vblank ts/seq: last 102.375488/2131, current 102.459389/2141: elapsed=83903.0us expected=84756.3us (kms_flip:2111) DEBUG: name = flip last_ts = 102.383881 last_received_ts = 102.459290 last_seq = 2132 current_ts = 102.467773 current_received_ts = 102.543045 current_seq = 2142 count = 84 seq_step = 1 (kms_flip:2111) DEBUG: flip ts/seq: last 102.383881/2132, current 102.467773/2142: elapsed=83897.0us expected=84756.3us +- 423.8us, error 1.0% (kms_flip:2111) DEBUG: inconsistent flip ts/seq: last 102.383881/2132, current 102.467773/2142: elapsed=83897.0us expected=84756.3us (kms_flip:2111) DEBUG: name = vblank last_ts = 102.459389 last_received_ts = 102.459244 last_seq = 2141 current_ts = 102.543282 current_received_ts = 102.542938 current_seq = 2151 count = 84 seq_step = 10 (kms_flip:2111) DEBUG: vblank ts/seq: last 102.459389/2141, current 102.543282/2151: elapsed=83892.0us expected=84756.3us +- 423.8us, error 1.0% (kms_flip:2111) DEBUG: inconsistent vblank ts/seq: last 102.459389/2141, current 102.543282/2151: elapsed=83892.0us expected=84756.3us (kms_flip:2111) DEBUG: name = flip last_ts = 102.467773 last_received_ts = 102.543045 last_seq = 2142 current_ts = 102.551674 current_received_ts = 102.626984 current_seq = 2152 count = 85 seq_step = 1 (kms_flip:2111) DEBUG: flip ts/seq: last 102.467773/2142, current 102.551674/2152: elapsed=83899.0us expected=84756.3us +- 423.8us, error 1.0% (kms_flip:2111) DEBUG: inconsistent flip ts/seq: last 102.467773/2142, current 102.551674/2152: elapsed=83899.0us expected=84756.3us (kms_flip:2111) DEBUG: name = vblank last_ts = 102.543282 last_received_ts = 102.542938 last_seq = 2151 current_ts = 102.627182 current_received_ts = 102.626930 current_seq = 2161 count = 85 seq_step = 10 (kms_flip:2111) DEBUG: vblank ts/seq: last 102.543282/2151, current 102.627182/2161: elapsed=83904.0us expected=84756.3us +- 423.8us, error 1.0% (kms_flip:2111) DEBUG: inconsistent vblank ts/seq: last 102.543282/2151, current 102.627182/2161: elapsed=83904.0us expected=84756.3us (kms_flip:2111) DEBUG: name = flip last_ts = 102.551674 last_received_ts = 102.626984 last_seq = 2152 current_ts = 102.635567 current_received_ts = 102.710892 current_seq = 2162 count = 86 seq_step = 1 (kms_flip:2111) DEBUG: flip ts/seq: last 102.551674/2152, current 102.635567/2162: elapsed=83893.0us expected=84756.3us +- 423.8us, error 1.0% (kms_flip:2111) DEBUG: inconsistent flip ts/seq: last 102.551674/2152, current 102.635567/2162: elapsed=83893.0us expected=84756.3us (kms_flip:2111) DEBUG: name = vblank last_ts = 102.627182 last_received_ts = 102.626930 last_seq = 2161 current_ts = 102.711082 current_received_ts = 102.710838 current_seq = 2171 count = 86 seq_step = 10 (kms_flip:2111) DEBUG: vblank ts/seq: last 102.627182/2161, current 102.711082/2171: elapsed=83894.0us expected=84756.3us +- 423.8us, error 1.0% (kms_flip:2111) DEBUG: inconsistent vblank ts/seq: last 102.627182/2161, current 102.711082/2171: elapsed=83894.0us expected=84756.3us (kms_flip:2111) DEBUG: name = flip last_ts = 102.635567 last_received_ts = 102.710892 last_seq = 2162 current_ts = 102.719467 current_received_ts = 102.794716 current_seq = 2172 count = 87 seq_step = 1 (kms_flip:2111) DEBUG: flip ts/seq: last 102.635567/2162, current 102.719467/2172: elapsed=83902.0us expected=84756.3us +- 423.8us, error 1.0% (kms_flip:2111) DEBUG: inconsistent flip ts/seq: last 102.635567/2162, current 102.719467/2172: elapsed=83902.0us expected=84756.3us (kms_flip:2111) DEBUG: name = vblank last_ts = 102.711082 last_received_ts = 102.710838 last_seq = 2171 current_ts = 102.794983 current_received_ts = 102.794662 current_seq = 2181 count = 87 seq_step = 10 (kms_flip:2111) DEBUG: vblank ts/seq: last 102.711082/2171, current 102.794983/2181: elapsed=83904.0us expected=84756.3us +- 423.8us, error 1.0% (kms_flip:2111) DEBUG: inconsistent vblank ts/seq: last 102.711082/2171, current 102.794983/2181: elapsed=83904.0us expected=84756.3us (kms_flip:2111) DEBUG: name = flip last_ts = 102.719467 last_received_ts = 102.794716 last_seq = 2172 current_ts = 102.803375 current_received_ts = 102.878654 current_seq = 2182 count = 88 seq_step = 1 (kms_flip:2111) DEBUG: flip ts/seq: last 102.719467/2172, current 102.803375/2182: elapsed=83902.0us expected=84756.3us +- 423.8us, error 1.0% (kms_flip:2111) DEBUG: inconsistent flip ts/seq: last 102.719467/2172, current 102.803375/2182: elapsed=83902.0us expected=84756.3us (kms_flip:2111) DEBUG: name = vblank last_ts = 102.794983 last_received_ts = 102.794662 last_seq = 2181 current_ts = 102.878838 current_received_ts = 102.878601 current_seq = 2191 count = 88 seq_step = 10 (kms_flip:2111) DEBUG: vblank ts/seq: last 102.794983/2181, current 102.878838/2191: elapsed=83857.0us expected=84756.3us +- 423.8us, error 1.1% (kms_flip:2111) DEBUG: inconsistent vblank ts/seq: last 102.794983/2181, current 102.878838/2191: elapsed=83857.0us expected=84756.3us (kms_flip:2111) DEBUG: name = flip last_ts = 102.803375 last_received_ts = 102.878654 last_seq = 2182 current_ts = 102.887222 current_received_ts = 102.962540 current_seq = 2192 count = 89 seq_step = 1 (kms_flip:2111) DEBUG: flip ts/seq: last 102.803375/2182, current 102.887222/2192: elapsed=83852.0us expected=84756.3us +- 423.8us, error 1.1% (kms_flip:2111) DEBUG: inconsistent flip ts/seq: last 102.803375/2182, current 102.887222/2192: elapsed=83852.0us expected=84756.3us (kms_flip:2111) DEBUG: name = vblank last_ts = 102.878838 last_received_ts = 102.878601 last_seq = 2191 current_ts = 102.962669 current_received_ts = 102.962486 current_seq = 2201 count = 89 seq_step = 10 (kms_flip:2111) DEBUG: vblank ts/seq: last 102.878838/2191, current 102.962669/2201: elapsed=83827.0us expected=84756.3us +- 423.8us, error 1.1% (kms_flip:2111) DEBUG: inconsistent vblank ts/seq: last 102.878838/2191, current 102.962669/2201: elapsed=83827.0us expected=84756.3us (kms_flip:2111) INFO: Event flip: expected 894, counted 900, passrate = 33.33%, encoder type 2 (kms_flip:2111) DEBUG: dropped frames, expected 894, counted 900, passrate = 33.33%, encoder type 2 (kms_flip:2111) INFO: Event vblank: expected 894, counted 900, passrate = 32.22%, encoder type 2 (kms_flip:2111) DEBUG: dropped frames, expected 894, counted 900, passrate = 32.22%, encoder type 2 (kms_flip:2111) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1709: (kms_flip:2111) CRITICAL: Failed assertion: !retried (kms_flip:2111) igt_core-INFO: Stack trace: (kms_flip:2111) igt_core-INFO: #0 ../lib/igt_core.c:2075 __igt_fail_assert() (kms_flip:2111) igt_core-INFO: #1 ../tests/kms_flip.c:428 run_test_on_crtc_set.constprop.0() (kms_flip:2111) igt_core-INFO: #2 ../tests/kms_flip.c:1889 run_test() (kms_flip:2111) igt_core-INFO: #3 ../tests/kms_flip.c:2122 __igt_unique____real_main2045() (kms_flip:2111) igt_core-INFO: #4 ../tests/kms_flip.c:2045 main() (kms_flip:2111) igt_core-INFO: #5 [__libc_init_first+0x8a] (kms_flip:2111) igt_core-INFO: #6 [__libc_start_main+0x8b] (kms_flip:2111) igt_core-INFO: #7 [_start+0x25] **** END **** Dynamic subtest B-HDMI-A1: FAIL (18.600s) |
| Dmesg |
<6> [84.310422] [IGT] kms_flip: starting dynamic subtest B-HDMI-A1
<7> [84.311240] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:391]
<7> [84.311478] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:392]
<7> [84.354023] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:108:pipe A]
<7> [84.354234] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [84.354689] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [84.355154] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [84.355568] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [84.356002] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [84.356417] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [84.356819] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [84.357262] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2080, found 0)
<7> [84.357665] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [84.358012] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2080, found 0)
<7> [84.358343] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 1968, found 0)
<7> [84.358662] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2000, found 0)
<7> [84.359002] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [84.359328] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [84.359644] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1083, found 0)
<7> [84.359976] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1088, found 0)
<7> [84.360295] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1144, found 0)
<7> [84.360544] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1144, found 0)
<7> [84.360793] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [84.361064] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2080, found 0)
<7> [84.361293] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [84.361450] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2080, found 0)
<7> [84.361606] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 1968, found 0)
<7> [84.361763] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2000, found 0)
<7> [84.361934] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [84.362091] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [84.362246] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1083, found 0)
<7> [84.362401] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1088, found 0)
<7> [84.362557] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1144, found 0)
<7> [84.362715] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1144, found 0)
<7> [84.362880] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [84.363041] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [84.363196] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [84.363352] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [84.363508] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [84.363665] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [84.363823] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 285500, found 0)
<7> [84.363993] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 285500, found 0)
<7> [84.364150] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in port_clock (expected 285500, found 0)
<7> [84.364305] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [84.364461] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in avi infoframe
<7> [84.364617] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [84.364772] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [84.364774] i915 0000:00:02.0: colorspace: RGB
<7> [84.364776] i915 0000:00:02.0: scan mode: Underscan
<7> [84.364777] i915 0000:00:02.0: colorimetry: No Data
<7> [84.364779] i915 0000:00:02.0: picture aspect: No Data
<7> [84.364780] i915 0000:00:02.0: active aspect: Same as Picture
<7> [84.364782] i915 0000:00:02.0: itc: No Data
<7> [84.364783] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [84.364785] i915 0000:00:02.0: quantization range: Full
<7> [84.364786] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [84.364788] i915 0000:00:02.0: video code: 0
<7> [84.364789] i915 0000:00:02.0: ycc quantization range: Full
<7> [84.364790] i915 0000:00:02.0: hdmi content type: Graphics
<7> [84.364792] i915 0000:00:02.0: pixel repeat: 0
<7> [84.364793] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [84.364795] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [84.364964] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in spd infoframe
<7> [84.365124] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [84.365279] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [84.365281] i915 0000:00:02.0: vendor: Intel
<7> [84.365282] i915 0000:00:02.0: product: Integrated gfx
<7> [84.365284] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [84.365286] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [84.365440] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hdmi infoframe
<7> [84.365595] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [84.365753] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [84.365755] i915 0000:00:02.0: empty frame
<7> [84.365756] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [84.365929] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:108:pipe A] fastset requirement not met, forcing full modeset
<7> [84.366087] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:108:pipe A] releasing DPLL 0
<7> [84.366272] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [84.366433] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:108:pipe A] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x1 -> 0x0
<7> [84.366627] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:103:cursor A] ddb (1993 - 2048) -> ( 0 - 0), size 55 -> 0
<7> [84.366820] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:108:pipe A] min cdclk: 192000 kHz -> 0 kHz
<7> [84.367028] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [84.367188] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 172800 kHz, actual 172800 kHz
<7> [84.367345] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [84.367504] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:108:pipe A] enable: no [modeset]
<7> [84.367664] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:33:plane 1A] fb: [NOFB], visible: no
<7> [84.367822] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:43:plane 2A] fb: [NOFB], visible: no
<7> [84.367994] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:53:plane 3A] fb: [NOFB], visible: no
<7> [84.368152] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:63:plane 4A] fb: [NOFB], visible: no
<7> [84.368308] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:73:plane 5A] fb: [NOFB], visible: no
<7> [84.368463] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:83:plane 6A] fb: [NOFB], visible: no
<7> [84.368620] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:93:plane 7A] fb: [NOFB], visible: no
<7> [84.368775] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:103:cursor A] fb: [NOFB], visible: no
<7> [84.369014] i915 0000:00:02.0: [drm:intel_audio_codec_disable [i915]] [CONNECTOR:342:HDMI-A-1][ENCODER:341:DDI B/PHY B] Disable audio codec on [CRTC:108:pipe A]
<7> [84.383374] i915 0000:00:02.0: [drm:intel_audio_component_get_eld [i915]] Not valid for port B
<7> [84.385666] i915 0000:00:02.0: [drm:intel_hdmi_handle_sink_scrambling [i915]] [CONNECTOR:342:HDMI-A-1] scrambling=no, TMDS bit clock ratio=1/10
<7> [84.386224] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [84.386378] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [84.386907] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [84.387067] i915 0000:00:02.0: [drm:drm_scdc_set_high_tmds_clock_ratio [drm_display_helper]] [CONNECTOR:342:HDMI-A-1] Failed to read TMDS config: -6
<7> [84.387083] i915 0000:00:02.0: [drm:intel_ddi_disable [i915]] [CONNECTOR:342:HDMI-A-1] Failed to reset sink scrambling/TMDS bit clock ratio
<7> [84.387264] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [84.391909] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_B
<7> [84.392109] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x1, on? 1) for [CRTC:108:pipe A]
<7> [84.392303] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0
<7> [84.392481] 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> [84.392711] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:341:DDI B/PHY B]
<7> [84.392912] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:351:DDI TC1/PHY TC1]
<7> [84.393074] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:353:DP-MST A]
<7> [84.393235] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:354:DP-MST B]
<7> [84.393397] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:355:DP-MST C]
<7> [84.393559] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:356:DP-MST D]
<7> [84.393720] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:361:DDI TC2/PHY TC2]
<7> [84.393890] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:363:DP-MST A]
<7> [84.394052] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:364:DP-MST B]
<7> [84.394213] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:365:DP-MST C]
<7> [84.394372] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:366:DP-MST D]
<7> [84.394531] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:370:DDI TC3/PHY TC3]
<7> [84.394696] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:372:DP-MST A]
<7> [84.394863] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:373:DP-MST B]
<7> [84.395022] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:374:DP-MST C]
<7> [84.395181] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:375:DP-MST D]
<7> [84.395340] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:379:DDI TC4/PHY TC4]
<7> [84.395503] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:381:DP-MST A]
<7> [84.395664] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:382:DP-MST B]
<7> [84.395824] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:383:DP-MST C]
<7> [84.396010] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:384:DP-MST D]
<7> [84.396172] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:342:HDMI-A-1]
<7> [84.396492] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [84.396715] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_3
<7> [84.397084] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_2
<7> [84.397273] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:108:pipe A]
<7> [84.397844] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:185:pipe B]
<7> [84.398002] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:262:pipe C]
<7> [84.398127] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:339:pipe D]
<7> [84.398256] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:185:pipe B]
<7> [84.398285] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:342:HDMI-A-1]
<7> [84.398344] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:342:HDMI-A-1] Limiting display bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36)
<7> [84.398520] i915 0000:00:02.0: [drm:intel_hdmi_compute_clock [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [84.398683] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:185:pipe B] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [84.398855] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:341:DDI B/PHY B] [CRTC:185:pipe B]
<7> [84.399013] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.enable (expected no, found yes)
<7> [84.399176] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.active (expected no, found yes)
<7> [84.399338] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in cpu_transcoder (expected -1, found 1)
<7> [84.399499] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in lane_count (expected 0, found 4)
<7> [84.399659] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [84.399820] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [84.399993] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [84.400153] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2080)
<7> [84.400312] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [84.400471] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2080)
<7> [84.400630] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 1968)
<7> [84.400790] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2000)
<7> [84.400968] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [84.401131] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [84.401292] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1083)
<7> [84.401451] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1088)
<7> [84.401611] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1144)
<7> [84.401771] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1144)
<7> [84.401939] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [84.402097] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2080)
<7> [84.402254] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [84.402412] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2080)
<7> [84.402569] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 1968)
<7> [84.402727] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2000)
<7> [84.402892] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [84.403048] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [84.403204] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1083)
<7> [84.403360] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1088)
<7> [84.403518] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1144)
<7> [84.403676] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1144)
<7> [84.403838] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [84.403999] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [84.404155] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [84.404311] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in has_hdmi_sink (expected no, found yes)
<7> [84.404467] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in has_infoframe (expected no, found yes)
<7> [84.404624] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in dpll_hw_state
<7> [84.404779] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [84.404959] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: cfgcr0: 0x0, cfgcr1: 0x0, 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> [84.405117] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [84.405272] i915 0000:00:02.0: [drm:intel_pipe_config_compare [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> [84.405430] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [84.405587] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 285500)
<7> [84.405743] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 285500)
<7> [84.405909] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in port_clock (expected 0, found 285500)
<7> [84.406066] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071)
<7> [84.406222] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in avi infoframe
<7> [84.406378] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [84.406532] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [84.406688] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [84.406690] i915 0000:00:02.0: colorspace: RGB
<7> [84.406692] i915 0000:00:02.0: scan mode: Underscan
<7> [84.406693] i915 0000:00:02.0: colorimetry: No Data
<7> [84.406694] i915 0000:00:02.0: picture aspect: No Data
<7> [84.406696] i915 0000:00:02.0: active aspect: Same as Picture
<7> [84.406697] i915 0000:00:02.0: itc: No Data
<7> [84.406699] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [84.406700] i915 0000:00:02.0: quantization range: Full
<7> [84.406702] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [84.406703] i915 0000:00:02.0: video code: 0
<7> [84.406704] i915 0000:00:02.0: ycc quantization range: Full
<7> [84.406706] i915 0000:00:02.0: hdmi content type: Graphics
<7> [84.406707] i915 0000:00:02.0: pixel repeat: 0
<7> [84.406709] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [84.406710] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in spd infoframe
<7> [84.406875] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [84.407030] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [84.407184] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [84.407186] i915 0000:00:02.0: vendor: Intel
<7> [84.407187] i915 0000:00:02.0: product: Integrated gfx
<7> [84.407189] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [84.407191] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hdmi infoframe
<7> [84.407346] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [84.407501] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [84.407656] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [84.407658] i915 0000:00:02.0: empty frame
<7> [84.407660] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:185:pipe B] fastset requirement not met, forcing full modeset
<7> [84.407852] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [84.408015] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:185:pipe B] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x2
<7> [84.408210] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:110:plane 1B] ddb ( 0 - 0) -> ( 0 - 1993), size 0 -> 1993
<7> [84.408367] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:180:cursor B] ddb ( 0 - 0) -> (1993 - 2048), size 0 -> 55
<7> [84.408525] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:110:plane 1B] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm
<7> [84.408681] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:110:plane 1B] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 8, 8, 8, 8, 11, 16, 16, 0, 0, 0
<7> [84.408848] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:110:plane 1B] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 129, 129, 129, 129, 177, 257, 257, 30, 0, 0
<7> [84.409006] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:110:plane 1B] 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> [84.409183] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:185:pipe B] data rate 1142000 num active planes 1
<7> [84.409348] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14993 required 1142
<7> [84.409509] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [84.409683] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:185:pipe B] min cdclk: 0 kHz -> 192000 kHz
<7> [84.409889] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [84.410047] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 192000 kHz, actual 192000 kHz
<7> [84.410203] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [84.410361] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:185:pipe B] allocated DPLL 0
<7> [84.410525] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:185:pipe B] reserving DPLL 0
<7> [84.410691] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:185:pipe B] enable: yes [modeset]
<7> [84.410858] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [84.411018] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: B, pipe bpp: 24, dithering: 0
<7> [84.411178] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [84.411335] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [84.411493] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [84.411650] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [84.411807] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [84.411984] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] GCP: 0x0
<7> [84.412141] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [84.412142] i915 0000:00:02.0: colorspace: RGB
<7> [84.412144] i915 0000:00:02.0: scan mode: Underscan
<7> [84.412145] i915 0000:00:02.0: colorimetry: No Data
<7> [84.412147] i915 0000:00:02.0: picture aspect: No Data
<7> [84.412148] i915 0000:00:02.0: active aspect: Same as Picture
<7> [84.412150] i915 0000:00:02.0: itc: No Data
<7> [84.412151] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [84.412153] i915 0000:00:02.0: quantization range: Full
<7> [84.412154] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [84.412155] i915 0000:00:02.0: video code: 0
<7> [84.412156] i915 0000:00:02.0: ycc quantization range: Full
<7> [84.412158] i915 0000:00:02.0: hdmi content type: Graphics
<7> [84.412159] i915 0000:00:02.0: pixel repeat: 0
<7> [84.412161] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [84.412162] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [84.412164] i915 0000:00:02.0: vendor: Intel
<7> [84.412165] i915 0000:00:02.0: product: Integrated gfx
<7> [84.412167] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [84.412169] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [84.412170] i915 0000:00:02.0: empty frame
<7> [84.412172] 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> [84.412328] 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> [84.412485] 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> [84.412640] 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> [84.412796] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [84.412966] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [84.413122] 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> [84.413278] 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> [84.413435] 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> [84.413593] 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> [84.413749] 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> [84.413916] 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> [84.414073] 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> [84.414228] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 285500, pipe src: 1920x1080+0+0, pixel rate 285500, min cdclk 192000
<7> [84.414384] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 59, ips linetime: 0
<7> [84.414539] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [84.414695] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [84.414857] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [84.415011] 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> [84.415167] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [84.415322] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [84.415477] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [84.415634] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [84.415789] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [84.415955] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [84.416111] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [84.416266] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [84.416420] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [84.416574] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [84.416729] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [84.416896] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [84.417051] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [84.417206] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:110:plane 1B] fb: [FB:391] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [84.417362] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [84.417516] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [84.417672] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:120:plane 2B] fb: [NOFB], visible: no
<7> [84.417826] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:130:plane 3B] fb: [NOFB], visible: no
<7> [84.417992] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:140:plane 4B] fb: [NOFB], visible: no
<7> [84.418148] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:150:plane 5B] fb: [NOFB], visible: no
<7> [84.418303] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:160:plane 6B] fb: [NOFB], visible: no
<7> [84.418458] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:170:plane 7B] fb: [NOFB], visible: no
<7> [84.418613] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:180:cursor B] fb: [NOFB], visible: no
<7> [84.419338] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_2
<7> [84.419511] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_3
<7> [84.419710] 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> [84.420049] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:341:DDI B/PHY B]
<7> [84.420213] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:351:DDI TC1/PHY TC1]
<7> [84.420374] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:353:DP-MST A]
<7> [84.420535] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:354:DP-MST B]
<7> [84.420697] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:355:DP-MST C]
<7> [84.420866] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:356:DP-MST D]
<7> [84.421025] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:361:DDI TC2/PHY TC2]
<7> [84.421183] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:363:DP-MST A]
<7> [84.421342] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:364:DP-MST B]
<7> [84.421501] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:365:DP-MST C]
<7> [84.421664] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:366:DP-MST D]
<7> [84.421827] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:370:DDI TC3/PHY TC3]
<7> [84.422004] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:372:DP-MST A]
<7> [84.422163] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:373:DP-MST B]
<7> [84.422323] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:374:DP-MST C]
<7> [84.422481] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:375:DP-MST D]
<7> [84.422639] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:379:DDI TC4/PHY TC4]
<7> [84.422797] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:381:DP-MST A]
<7> [84.422965] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:382:DP-MST B]
<7> [84.423122] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:383:DP-MST C]
<7> [84.423277] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:384:DP-MST D]
<7> [84.423552] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [84.423753] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x2, on? 0) for [CRTC:185:pipe B]
<7> [84.423927] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0
<7> [84.424154] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_B
<7> [84.424395] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe B
<7> [84.424496] i915 0000:00:02.0: [drm:pxp_session_work [i915]] PXP: processing event-flags 0x0000000d
<7> [84.425183] i915 0000:00:02.0: [drm:intel_hdmi_handle_sink_scrambling [i915]] [CONNECTOR:342:HDMI-A-1] scrambling=no, TMDS bit clock ratio=1/10
<7> [84.425753] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [84.425987] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [84.426526] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [84.426686] i915 0000:00:02.0: [drm:drm_scdc_set_high_tmds_clock_ratio [drm_display_helper]] [CONNECTOR:342:HDMI-A-1] Failed to read TMDS config: -6
<7> [84.426701] i915 0000:00:02.0: [drm:intel_ddi_enable [i915]] [CONNECTOR:342:HDMI-A-1] Failed to configure sink scrambling/TMDS bit clock ratio
<7> [84.429564] i915 0000:00:02.0: [drm:intel_audio_component_get_power [i915]] restored AUD_FREQ_CNTRL to 0x810
<7> [84.435382] i915 0000:00:02.0: [drm:intel_audio_codec_enable [i915]] [CONNECTOR:342:HDMI-A-1][ENCODER:341:DDI B/PHY B] Enable audio codec on [CRTC:185:pipe B], 64 bytes ELD
<7> [84.435872] azx_init_pci: snd_hda_intel 0000:00:1f.3: Clearing TCSEL
<7> [84.435898] azx_init_pci: snd_hda_intel 0000:00:1f.3: SCH snoop: Enabled
<7> [84.443688] 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> [84.443907] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] Configuring HDMI audio for pixel clock 25200 (0x00010000)
<7> [84.444070] i915 0000:00:02.0: [drm:hsw_audio_config_update [i915]] using automatic N
<6> [84.444236] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 5 not expected
<6> [84.444239] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 0 not expected
<6> [84.444240] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 10 not expected
<6> [84.444242] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 6 not expected
<7> [84.444373] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:342:HDMI-A-1]
<7> [84.444624] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:185:pipe B]
<7> [84.446257] i915 0000:00:02.0: [drm:pxp_session_work [i915]] PXP: processing event-flags 0x0000000a
<7> [84.446523] i915 0000:00:02.0: [drm:pxp_session_work [i915]] PXP: creating arb_session after invalidation
<7> [84.476473] i915 0000:00:02.0: [drm:pxp_session_work [i915]] PXP ARB session is alive
<7> [94.974660] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:108:pipe A]
<7> [94.975066] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:185:pipe B]
<7> [94.975255] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.enable (expected yes, found no)
<7> [94.975855] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.active (expected yes, found no)
<7> [94.976361] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in cpu_transcoder (expected 1, found -1)
<7> [94.976889] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in lane_count (expected 4, found 0)
<7> [94.977375] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [94.977884] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [94.978353] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [94.978853] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2080, found 0)
<7> [94.979330] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [94.979865] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2080, found 0)
<7> [94.980295] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 1968, found 0)
<7> [94.980464] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2000, found 0)
<7> [94.980626] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [94.980783] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [94.980941] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1083, found 0)
<7> [94.981097] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1088, found 0)
<7> [94.981255] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1144, found 0)
<7> [94.981418] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1144, found 0)
<7> [94.981600] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [94.981762] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2080, found 0)
<7> [94.981922] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [94.982081] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2080, found 0)
<7> [94.982239] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 1968, found 0)
<7> [94.982396] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2000, found 0)
<7> [94.982570] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [94.982731] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [94.982888] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1083, found 0)
<7> [94.983045] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1088, found 0)
<7> [94.983202] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1144, found 0)
<7> [94.983358] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1144, found 0)
<7> [94.983529] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [94.983689] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [94.983846] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [94.984003] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [94.984159] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [94.984315] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [94.984487] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 285500, found 0)
<7> [94.984647] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 285500, found 0)
<7> [94.984804] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in port_clock (expected 285500, found 0)
<7> [94.984960] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [94.985117] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in avi infoframe
<7> [94.985273] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [94.985450] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [94.985453] i915 0000:00:02.0: colorspace: RGB
<7> [94.985456] i915 0000:00:02.0: scan mode: Underscan
<7> [94.985459] i915 0000:00:02.0: colorimetry: No Data
<7> [94.985461] i915 0000:00:02.0: picture aspect: No Data
<7> [94.985462] i915 0000:00:02.0: active aspect: Same as Picture
<7> [94.985464] i915 0000:00:02.0: itc: No Data
<7> [94.985465] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [94.985466] i915 0000:00:02.0: quantization range: Full
<7> [94.985468] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [94.985469] i915 0000:00:02.0: video code: 0
<7> [94.985471] i915 0000:00:02.0: ycc quantization range: Full
<7> [94.985472] i915 0000:00:02.0: hdmi content type: Graphics
<7> [94.985473] i915 0000:00:02.0: pixel repeat: 0
<7> [94.985475] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [94.985476] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [94.985633] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in spd infoframe
<7> [94.985790] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [94.985946] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [94.985947] i915 0000:00:02.0: vendor: Intel
<7> [94.985949] i915 0000:00:02.0: product: Integrated gfx
<7> [94.985950] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [94.985952] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [94.986107] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hdmi infoframe
<7> [94.986262] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [94.986430] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [94.986433] i915 0000:00:02.0: empty frame
<7> [94.986436] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [94.986595] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:185:pipe B] fastset requirement not met, forcing full modeset
<7> [94.986752] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:185:pipe B] releasing DPLL 0
<7> [94.986960] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [94.987135] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:185:pipe B] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x2 -> 0x0
<7> [94.987345] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:110:plane 1B] ddb ( 0 - 1993) -> ( 0 - 0), size 1993 -> 0
<7> [94.987504] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:180:cursor B] ddb (1993 - 2048) -> ( 0 - 0), size 55 -> 0
<7> [94.987662] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:110:plane 1B] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [94.987819] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:110:plane 1B] lines 1, 8, 8, 8, 8, 11, 16, 16, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [94.987979] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:110:plane 1B] blocks 16, 129, 129, 129, 129, 177, 257, 257, 30, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [94.988139] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:110:plane 1B] 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> [94.988329] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:185:pipe B] data rate 0 num active planes 0
<7> [94.988493] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14993 required 0
<7> [94.988672] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:185:pipe B] min cdclk: 192000 kHz -> 0 kHz
<7> [94.988848] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 17844 kHz -> 0 kHz
<7> [94.989032] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [94.989189] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 172800 kHz, actual 172800 kHz
<7> [94.989363] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [94.989528] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:185:pipe B] enable: no [modeset]
<7> [94.989689] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:110:plane 1B] fb: [NOFB], visible: no
<7> [94.989848] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:120:plane 2B] fb: [NOFB], visible: no
<7> [94.990007] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:130:plane 3B] fb: [NOFB], visible: no
<7> [94.990165] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:140:plane 4B] fb: [NOFB], visible: no
<7> [94.990336] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:150:plane 5B] fb: [NOFB], visible: no
<7> [94.990495] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:160:plane 6B] fb: [NOFB], visible: no
<7> [94.990652] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:170:plane 7B] fb: [NOFB], visible: no
<7> [94.990808] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:180:cursor B] fb: [NOFB], visible: no
<7> [94.991044] i915 0000:00:02.0: [drm:intel_audio_codec_disable [i915]] [CONNECTOR:342:HDMI-A-1][ENCODER:341:DDI B/PHY B] Disable audio codec on [CRTC:185:pipe B]
<7> [95.003656] i915 0000:00:02.0: [drm:intel_audio_component_get_eld [i915]] Not valid for port B
<7> [95.005993] i915 0000:00:02.0: [drm:intel_hdmi_handle_sink_scrambling [i915]] [CONNECTOR:342:HDMI-A-1] scrambling=no, TMDS bit clock ratio=1/10
<7> [95.006628] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [95.006767] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [95.007355] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [95.007520] i915 0000:00:02.0: [drm:drm_scdc_set_high_tmds_clock_ratio [drm_display_helper]] [CONNECTOR:342:HDMI-A-1] Failed to read TMDS config: -6
<7> [95.007535] i915 0000:00:02.0: [drm:intel_ddi_disable [i915]] [CONNECTOR:342:HDMI-A-1] Failed to reset sink scrambling/TMDS bit clock ratio
<7> [95.007724] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe B
<7> [95.012927] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_B
<7> [95.013134] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x2, on? 1) for [CRTC:185:pipe B]
<7> [95.013317] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0
<7> [95.013486] 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> [95.013726] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:341:DDI B/PHY B]
<7> [95.013936] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:351:DDI TC1/PHY TC1]
<7> [95.014111] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:353:DP-MST A]
<7> [95.014312] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:354:DP-MST B]
<7> [95.014475] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:355:DP-MST C]
<7> [95.014644] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:356:DP-MST D]
<7> [95.014812] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:361:DDI TC2/PHY TC2]
<7> [95.015015] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:363:DP-MST A]
<7> [95.015178] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:364:DP-MST B]
<7> [95.015345] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:365:DP-MST C]
<7> [95.015504] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:366:DP-MST D]
<7> [95.015667] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:370:DDI TC3/PHY TC3]
<7> [95.015825] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:372:DP-MST A]
<7> [95.016057] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:373:DP-MST B]
<7> [95.016216] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:374:DP-MST C]
<7> [95.016381] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:375:DP-MST D]
<7> [95.016537] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:379:DDI TC4/PHY TC4]
<7> [95.016704] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:381:DP-MST A]
<7> [95.016919] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:382:DP-MST B]
<7> [95.017075] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:383:DP-MST C]
<7> [95.017239] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:384:DP-MST D]
<7> [95.017398] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:342:HDMI-A-1]
<7> [95.017778] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [95.018019] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_3
<7> [95.018206] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_2
<7> [95.018414] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:185:pipe B]
<7> [95.018826] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:262:pipe C]
<7> [95.018987] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:339:pipe D]
<7> [95.019108] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:185:pipe B]
<7> [95.019137] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:342:HDMI-A-1]
<7> [95.019197] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:342:HDMI-A-1] Limiting display bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36)
<7> [95.019372] i915 0000:00:02.0: [drm:intel_hdmi_compute_clock [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [95.019535] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:185:pipe B] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [95.019699] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:341:DDI B/PHY B] [CRTC:185:pipe B]
<7> [95.019875] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.enable (expected no, found yes)
<7> [95.020038] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.active (expected no, found yes)
<7> [95.020199] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in cpu_transcoder (expected -1, found 1)
<7> [95.020360] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in lane_count (expected 0, found 4)
<7> [95.020520] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [95.020680] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [95.020854] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [95.021032] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2080)
<7> [95.021210] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [95.021371] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2080)
<7> [95.021531] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 1968)
<7> [95.021689] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2000)
<7> [95.021861] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [95.022020] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [95.022178] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1083)
<7> [95.022335] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1088)
<7> [95.022492] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1144)
<7> [95.022649] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1144)
<7> [95.022826] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [95.022984] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2080)
<7> [95.023141] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [95.023297] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2080)
<7> [95.023453] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 1968)
<7> [95.023610] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2000)
<7> [95.023821] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [95.023977] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [95.024133] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1083)
<7> [95.024289] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1088)
<7> [95.024445] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1144)
<7> [95.024601] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1144)
<7> [95.024776] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [95.024933] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [95.025089] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [95.025245] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in has_hdmi_sink (expected no, found yes)
<7> [95.025401] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in has_infoframe (expected no, found yes)
<7> [95.025559] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [95.025731] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 285500)
<7> [95.025888] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 285500)
<7> [95.026044] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in port_clock (expected 0, found 285500)
<7> [95.026199] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071)
<7> [95.026356] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in avi infoframe
<7> [95.026511] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [95.026698] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [95.026874] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [95.026876] i915 0000:00:02.0: colorspace: RGB
<7> [95.026878] i915 0000:00:02.0: scan mode: Underscan
<7> [95.026879] i915 0000:00:02.0: colorimetry: No Data
<7> [95.026881] i915 0000:00:02.0: picture aspect: No Data
<7> [95.026882] i915 0000:00:02.0: active aspect: Same as Picture
<7> [95.026883] i915 0000:00:02.0: itc: No Data
<7> [95.026885] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [95.026886] i915 0000:00:02.0: quantization range: Full
<7> [95.026887] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [95.026889] i915 0000:00:02.0: video code: 0
<7> [95.026890] i915 0000:00:02.0: ycc quantization range: Full
<7> [95.026892] i915 0000:00:02.0: hdmi content type: Graphics
<7> [95.026893] i915 0000:00:02.0: pixel repeat: 0
<7> [95.026894] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [95.026896] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in spd infoframe
<7> [95.027053] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [95.027234] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [95.027393] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [95.027395] i915 0000:00:02.0: vendor: Intel
<7> [95.027396] i915 0000:00:02.0: product: Integrated gfx
<7> [95.027398] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [95.027400] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:185:pipe B] fastset requirement not met in hdmi infoframe
<7> [95.027556] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [95.027731] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [95.027886] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [95.027888] i915 0000:00:02.0: empty frame
<7> [95.027890] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:185:pipe B] fastset requirement not met, forcing full modeset
<7> [95.028101] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [95.028294] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:185:pipe B] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x2
<7> [95.028491] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:110:plane 1B] ddb ( 0 - 0) -> ( 0 - 1993), size 0 -> 1993
<7> [95.028660] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:180:cursor B] ddb ( 0 - 0) -> (1993 - 2048), size 0 -> 55
<7> [95.028819] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:110:plane 1B] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm
<7> [95.028976] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:110:plane 1B] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 8, 8, 8, 8, 11, 16, 16, 0, 0, 0
<7> [95.029133] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:110:plane 1B] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 129, 129, 129, 129, 177, 257, 257, 30, 0, 0
<7> [95.029320] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:110:plane 1B] 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> [95.029499] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:185:pipe B] data rate 1142000 num active planes 1
<7> [95.029681] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14993 required 1142
<7> [95.029843] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [95.030019] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:185:pipe B] min cdclk: 0 kHz -> 192000 kHz
<7> [95.030194] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 0 kHz -> 17844 kHz
<7> [95.030377] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [95.030535] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 192000 kHz, actual 192000 kHz
<7> [95.030704] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [95.030864] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:185:pipe B] allocated DPLL 0
<7> [95.031029] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:185:pipe B] reserving DPLL 0
<7> [95.031195] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:185:pipe B] enable: yes [modeset]
<7> [95.031356] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [95.031516] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: B, pipe bpp: 24, dithering: 0
<7> [95.031686] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [95.031845] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [95.032002] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [95.032160] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [95.032316] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [95.032472] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] GCP: 0x0
<7> [95.032639] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [95.032641] i915 0000:00:02.0: colorspace: RGB
<7> [95.032643] i915 0000:00:02.0: scan mode: Underscan
<7> [95.032644] i915 0000:00:02.0: colorimetry: No Data
<7> [95.032646] i915 0000:00:02.0: picture aspect: No Data
<7> [95.032647] i915 0000:00:02.0: active aspect: Same as Picture
<7> [95.032649] i915 0000:00:02.0: itc: No Data
<7> [95.032650] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [95.032652] i915 0000:00:02.0: quantization range: Full
<7> [95.032653] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [95.032655] i915 0000:00:02.0: video code: 0
<7> [95.032656] i915 0000:00:02.0: ycc quantization range: Full
<7> [95.032658] i915 0000:00:02.0: hdmi content type: Graphics
<7> [95.032659] i915 0000:00:02.0: pixel repeat: 0
<7> [95.032660] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [95.032662] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [95.032664] i915 0000:00:02.0: vendor: Intel
<7> [95.032665] i915 0000:00:02.0: product: Integrated gfx
<7> [95.032667] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [95.032668] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [95.032670] i915 0000:00:02.0: empty frame
<7> [95.032672] 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> [95.032852] 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> [95.033008] 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> [95.033163] 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> [95.033319] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [95.033474] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [95.033647] 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> [95.033804] 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> [95.033960] 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> [95.034115] 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> [95.034271] 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> [95.034428] 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> [95.034593] 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> [95.034750] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 285500, pipe src: 1920x1080+0+0, pixel rate 285500, min cdclk 192000
<7> [95.034918] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 59, ips linetime: 0
<7> [95.035084] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [95.035239] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [95.035393] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [95.035558] 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> [95.035714] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [95.035869] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [95.036023] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [95.036178] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [95.036332] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [95.036486] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [95.036535] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [95.036735] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [95.036804] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [95.036979] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [95.037062] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [95.037231] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [95.038332] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling always-on
<7> [95.038484] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [95.038698] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [95.038855] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [95.039012] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [95.039169] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:110:plane 1B] fb: [FB:391] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [95.039328] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [95.039506] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [95.039701] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:120:plane 2B] fb: [NOFB], visible: no
<7> [95.039862] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:130:plane 3B] fb: [NOFB], visible: no
<7> [95.040020] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:140:plane 4B] fb: [NOFB], visible: no
<7> [95.040176] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:150:plane 5B] fb: [NOFB], visible: no
<7> [95.040332] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:160:plane 6B] fb: [NOFB], visible: no
<7> [95.040501] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:170:plane 7B] fb: [NOFB], visible: no
<7> [95.040684] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:180:cursor B] fb: [NOFB], visible: no
<7> [95.040937] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling always-on
<7> [95.041102] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [95.041795] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [95.042990] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_2
<7> [95.043159] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_3
<7> [95.043357] 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> [95.043913] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:341:DDI B/PHY B]
<7> [95.044079] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:351:DDI TC1/PHY TC1]
<7> [95.044241] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:353:DP-MST A]
<7> [95.044416] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:354:DP-MST B]
<7> [95.044593] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:355:DP-MST C]
<7> [95.044753] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:356:DP-MST D]
<7> [95.044920] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:361:DDI TC2/PHY TC2]
<7> [95.045107] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:363:DP-MST A]
<7> [95.045267] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:364:DP-MST B]
<7> [95.045440] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:365:DP-MST C]
<7> [95.045626] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:366:DP-MST D]
<7> [95.045783] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:370:DDI TC3/PHY TC3]
<7> [95.045940] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:372:DP-MST A]
<7> [95.046096] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:373:DP-MST B]
<7> [95.046253] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:374:DP-MST C]
<7> [95.046422] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:375:DP-MST D]
<7> [95.046603] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:379:DDI TC4/PHY TC4]
<7> [95.046759] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:381:DP-MST A]
<7> [95.046915] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:382:DP-MST B]
<7> [95.047072] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:383:DP-MST C]
<7> [95.047227] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:384:DP-MST D]
<7> [95.047544] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [95.047743] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x2, on? 0) for [CRTC:185:pipe B]
<7> [95.047906] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0
<7> [95.048131] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_B
<7> [95.048390] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe B
<7> [95.048574] i915 0000:00:02.0: [drm:intel_audio_component_get_power [i915]] restored AUD_FREQ_CNTRL to 0x810
<7> [95.048570] i915 0000:00:02.0: [drm:pxp_session_work [i915]] PXP: processing event-flags 0x0000000d
<7> [95.049204] i915 0000:00:02.0: [drm:intel_hdmi_handle_sink_scrambling [i915]] [CONNECTOR:342:HDMI-A-1] scrambling=no, TMDS bit clock ratio=1/10
<7> [95.049786] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [95.049946] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [95.050999] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [95.051245] i915 0000:00:02.0: [drm:drm_scdc_set_high_tmds_clock_ratio [drm_display_helper]] [CONNECTOR:342:HDMI-A-1] Failed to read TMDS config: -6
<7> [95.051276] i915 0000:00:02.0: [drm:intel_ddi_enable [i915]] [CONNECTOR:342:HDMI-A-1] Failed to configure sink scrambling/TMDS bit clock ratio
<7> [95.054230] azx_init_pci: snd_hda_intel 0000:00:1f.3: Clearing TCSEL
<7> [95.054253] azx_init_pci: snd_hda_intel 0000:00:1f.3: SCH snoop: Enabled
<7> [95.070394] i915 0000:00:02.0: [drm:pxp_session_work [i915]] PXP: processing event-flags 0x0000000a
<7> [95.070580] i915 0000:00:02.0: [drm:pxp_session_work [i915]] PXP: creating arb_session after invalidation
<7> [95.076581] i915 0000:00:02.0: [drm:intel_audio_codec_enable [i915]] [CONNECTOR:342:HDMI-A-1][ENCODER:341:DDI B/PHY B] Enable audio codec on [CRTC:185:pipe B], 64 bytes ELD
<7> [95.084936] 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> [95.085122] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] Configuring HDMI audio for pixel clock 25200 (0x00010000)
<7> [95.085283] i915 0000:00:02.0: [drm:hsw_audio_config_update [i915]] using automatic N
<6> [95.085450] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 5 not expected
<6> [95.085453] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 0 not expected
<6> [95.085454] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 10 not expected
<6> [95.085456] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 6 not expected
<7> [95.085594] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:342:HDMI-A-1]
<7> [95.085877] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:185:pipe B]
<7> [95.100194] i915 0000:00:02.0: [drm:pxp_session_work [i915]] PXP ARB session is alive
<7> [95.142613] i915 0000:00:02.0: [drm:intel_audio_component_get_power [i915]] restored AUD_FREQ_CNTRL to 0x810
<7> [95.148658] azx_init_pci: snd_hda_intel 0000:00:1f.3: Clearing TCSEL
<7> [95.148687] azx_init_pci: snd_hda_intel 0000:00:1f.3: SCH snoop: Enabled
<6> [102.910727] [IGT] kms_flip: finished subtest B-HDMI-A1, FAIL
|