Result:
integration-manifest git-log-oneline i915_display_info2 igt_runner2 runtimes2 results2.json results2-i915-load.json guc_logs2.tar i915_display_info_post_exec2 boot2 dmesg2
| Detail | Value |
|---|---|
| Duration | 19.06 seconds |
| Hostname |
shard-snb5 |
| Igt-Version |
IGT-Version: 2.4-gd28bd0b9e (x86_64) (Linux: 7.1.0-rc3-Patchwork_166155v3-gf8ee23694aa6+ x86_64) |
| Out |
Using IGT_SRANDOM=1778547274 for randomisation Opened device: /dev/dri/card0 Using monotonic timestamps Starting subtest: wf_vblank-ts-check-interruptible Starting dynamic subtest: A-VGA1 1024x768: 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa Expected frametime: 16666us; measured 16715.8us +- 11.807us accuracy 0.21% vblank interval differs from modeline! expected 16665.6us, measured 16716us +- 11.807us, difference 50.2us (4.2 sigma) Event vblank: expected 457, counted 450, passrate = 37.78%, encoder type 1 Expected frametime: 16666us; measured 16938.9us +- 15.590us accuracy 0.28% vblank interval differs from modeline! expected 16665.6us, measured 16939us +- 15.590us, difference 273.3us (17.5 sigma) Event vblank: expected 447, counted 450, passrate = 51.11%, encoder type 1 Stack trace: #0 ../lib/igt_core.c:2075 __igt_fail_assert() #1 ../tests/kms_flip.c:1071 run_test_on_crtc_set() #2 ../tests/kms_flip.c:1920 run_test() #3 ../tests/kms_flip.c:2220 __igt_unique____real_main2105() #4 ../tests/kms_flip.c:2105 main() #5 [__libc_init_first+0x8a] #6 [__libc_start_main+0x8b] #7 [_start+0x25] Dynamic subtest A-VGA1: FAIL (19.059s) |
| Err |
Starting subtest: wf_vblank-ts-check-interruptible Starting dynamic subtest: A-VGA1 (kms_flip:2594) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1722: (kms_flip:2594) CRITICAL: Failed assertion: !retried (kms_flip:2594) CRITICAL: Last errno: 4, Interrupted system call Dynamic subtest A-VGA1 failed. **** DEBUG **** (kms_flip:2594) DEBUG: No stale events found (kms_flip:2594) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0 (kms_flip:2594) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0 (kms_flip:2594) INFO: Expected frametime: 16666us; measured 16715.8us +- 11.807us accuracy 0.21% (kms_flip:2594) INFO: vblank interval differs from modeline! expected 16665.6us, measured 16716us +- 11.807us, difference 50.2us (4.2 sigma) (kms_flip:2594) DEBUG: name = vblank last_ts = 0.000000 last_received_ts = 0.000000 last_seq = 0 current_ts = 95.641403 current_received_ts = 95.640877 current_seq = 1491 count = 0 seq_step = 10 (kms_flip:2594) DEBUG: name = vblank last_ts = 95.641403 last_received_ts = 95.640877 last_seq = 1491 current_ts = 95.808563 current_received_ts = 95.807976 current_seq = 1501 count = 1 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 95.641403/1491, current 95.808563/1501: elapsed=167165.0us expected=167157.5us +- 835.8us, error 0.0% (kms_flip:2594) DEBUG: name = vblank last_ts = 95.808563 last_received_ts = 95.807976 last_seq = 1501 current_ts = 95.975739 current_received_ts = 95.975136 current_seq = 1511 count = 2 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 95.808563/1501, current 95.975739/1511: elapsed=167173.0us expected=167157.5us +- 835.8us, error 0.0% (kms_flip:2594) DEBUG: name = vblank last_ts = 95.975739 last_received_ts = 95.975136 last_seq = 1511 current_ts = 96.142899 current_received_ts = 96.142250 current_seq = 1521 count = 3 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 95.975739/1511, current 96.142899/1521: elapsed=167160.0us expected=167157.5us +- 835.8us, error 0.0% (kms_flip:2594) DEBUG: name = vblank last_ts = 96.142899 last_received_ts = 96.142250 last_seq = 1521 current_ts = 96.310074 current_received_ts = 96.309402 current_seq = 1531 count = 4 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 96.142899/1521, current 96.310074/1531: elapsed=167175.0us expected=167157.5us +- 835.8us, error 0.0% (kms_flip:2594) DEBUG: name = vblank last_ts = 96.310074 last_received_ts = 96.309402 last_seq = 1531 current_ts = 96.477242 current_received_ts = 96.476601 current_seq = 1541 count = 5 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 96.310074/1531, current 96.477242/1541: elapsed=167166.0us expected=167157.5us +- 835.8us, error 0.0% (kms_flip:2594) DEBUG: name = vblank last_ts = 96.477242 last_received_ts = 96.476601 last_seq = 1541 current_ts = 96.644402 current_received_ts = 96.643730 current_seq = 1551 count = 6 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 96.477242/1541, current 96.644402/1551: elapsed=167164.0us expected=167157.5us +- 835.8us, error 0.0% (kms_flip:2594) DEBUG: name = vblank last_ts = 96.644402 last_received_ts = 96.643730 last_seq = 1551 current_ts = 96.811569 current_received_ts = 96.810951 current_seq = 1561 count = 7 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 96.644402/1551, current 96.811569/1561: elapsed=167165.0us expected=167157.5us +- 835.8us, error 0.0% (kms_flip:2594) DEBUG: name = vblank last_ts = 96.811569 last_received_ts = 96.810951 last_seq = 1561 current_ts = 96.978737 current_received_ts = 96.978104 current_seq = 1571 count = 8 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 96.811569/1561, current 96.978737/1571: elapsed=167167.0us expected=167157.5us +- 835.8us, error 0.0% (kms_flip:2594) DEBUG: name = vblank last_ts = 96.978737 last_received_ts = 96.978104 last_seq = 1571 current_ts = 97.145905 current_received_ts = 97.145576 current_seq = 1581 count = 9 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 96.978737/1571, current 97.145905/1581: elapsed=167171.0us expected=167157.5us +- 835.8us, error 0.0% (kms_flip:2594) DEBUG: name = vblank last_ts = 97.145905 last_received_ts = 97.145576 last_seq = 1581 current_ts = 97.313080 current_received_ts = 97.312454 current_seq = 1591 count = 10 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 97.145905/1581, current 97.313080/1591: elapsed=167173.0us expected=167157.5us +- 835.8us, error 0.0% (kms_flip:2594) DEBUG: name = vblank last_ts = 97.313080 last_received_ts = 97.312454 last_seq = 1591 current_ts = 97.480263 current_received_ts = 97.479584 current_seq = 1601 count = 11 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 97.313080/1591, current 97.480263/1601: elapsed=167180.0us expected=167157.5us +- 835.8us, error 0.0% (kms_flip:2594) DEBUG: name = vblank last_ts = 97.480263 last_received_ts = 97.479584 last_seq = 1601 current_ts = 97.647507 current_received_ts = 97.646942 current_seq = 1611 count = 12 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 97.480263/1601, current 97.647507/1611: elapsed=167249.0us expected=167157.5us +- 835.8us, error 0.1% (kms_flip:2594) DEBUG: name = vblank last_ts = 97.647507 last_received_ts = 97.646942 last_seq = 1611 current_ts = 97.814766 current_received_ts = 97.814133 current_seq = 1621 count = 13 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 97.647507/1611, current 97.814766/1621: elapsed=167256.0us expected=167157.5us +- 835.8us, error 0.1% (kms_flip:2594) DEBUG: name = vblank last_ts = 97.814766 last_received_ts = 97.814133 last_seq = 1621 current_ts = 97.982010 current_received_ts = 97.981422 current_seq = 1631 count = 14 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 97.814766/1621, current 97.982010/1631: elapsed=167245.0us expected=167157.5us +- 835.8us, error 0.1% (kms_flip:2594) DEBUG: name = vblank last_ts = 97.982010 last_received_ts = 97.981422 last_seq = 1631 current_ts = 98.149269 current_received_ts = 98.148628 current_seq = 1641 count = 15 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 97.982010/1631, current 98.149269/1641: elapsed=167261.0us expected=167157.5us +- 835.8us, error 0.1% (kms_flip:2594) DEBUG: name = vblank last_ts = 98.149269 last_received_ts = 98.148628 last_seq = 1641 current_ts = 98.316521 current_received_ts = 98.315872 current_seq = 1651 count = 16 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 98.149269/1641, current 98.316521/1651: elapsed=167252.0us expected=167157.5us +- 835.8us, error 0.1% (kms_flip:2594) DEBUG: name = vblank last_ts = 98.316521 last_received_ts = 98.315872 last_seq = 1651 current_ts = 98.486542 current_received_ts = 98.485886 current_seq = 1661 count = 17 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 98.316521/1651, current 98.486542/1661: elapsed=170016.0us expected=167157.5us +- 835.8us, error 1.7% (kms_flip:2594) DEBUG: inconsistent vblank ts/seq: last 98.316521/1651, current 98.486542/1661: elapsed=170016.0us expected=167157.5us (kms_flip:2594) DEBUG: name = vblank last_ts = 98.486542 last_received_ts = 98.485886 last_seq = 1661 current_ts = 98.659653 current_received_ts = 98.659096 current_seq = 1671 count = 18 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 98.486542/1661, current 98.659653/1671: elapsed=173116.0us expected=167157.5us +- 835.8us, error 3.6% (kms_flip:2594) DEBUG: inconsistent vblank ts/seq: last 98.486542/1661, current 98.659653/1671: elapsed=173116.0us expected=167157.5us (kms_flip:2594) DEBUG: name = vblank last_ts = 98.659653 last_received_ts = 98.659096 last_seq = 1671 current_ts = 98.832764 current_received_ts = 98.832062 current_seq = 1681 count = 19 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 98.659653/1671, current 98.832764/1681: elapsed=173111.0us expected=167157.5us +- 835.8us, error 3.6% (kms_flip:2594) DEBUG: inconsistent vblank ts/seq: last 98.659653/1671, current 98.832764/1681: elapsed=173111.0us expected=167157.5us (kms_flip:2594) DEBUG: name = vblank last_ts = 98.832764 last_received_ts = 98.832062 last_seq = 1681 current_ts = 99.005898 current_received_ts = 99.005165 current_seq = 1691 count = 20 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 98.832764/1681, current 99.005898/1691: elapsed=173130.0us expected=167157.5us +- 835.8us, error 3.6% (kms_flip:2594) DEBUG: inconsistent vblank ts/seq: last 98.832764/1681, current 99.005898/1691: elapsed=173130.0us expected=167157.5us (kms_flip:2594) DEBUG: name = vblank last_ts = 99.005898 last_received_ts = 99.005165 last_seq = 1691 current_ts = 99.179001 current_received_ts = 99.178429 current_seq = 1701 count = 21 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 99.005898/1691, current 99.179001/1701: elapsed=173108.0us expected=167157.5us +- 835.8us, error 3.6% (kms_flip:2594) DEBUG: inconsistent vblank ts/seq: last 99.005898/1691, current 99.179001/1701: elapsed=173108.0us expected=167157.5us (kms_flip:2594) DEBUG: name = vblank last_ts = 99.179001 last_received_ts = 99.178429 last_seq = 1701 current_ts = 99.352119 current_received_ts = 99.351517 current_seq = 1711 count = 22 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 99.179001/1701, current 99.352119/1711: elapsed=173116.0us expected=167157.5us +- 835.8us, error 3.6% (kms_flip:2594) DEBUG: inconsistent vblank ts/seq: last 99.179001/1701, current 99.352119/1711: elapsed=173116.0us expected=167157.5us (kms_flip:2594) DEBUG: name = vblank last_ts = 99.352119 last_received_ts = 99.351517 last_seq = 1711 current_ts = 99.524727 current_received_ts = 99.524071 current_seq = 1721 count = 23 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 99.352119/1711, current 99.524727/1721: elapsed=172609.0us expected=167157.5us +- 835.8us, error 3.3% (kms_flip:2594) DEBUG: inconsistent vblank ts/seq: last 99.352119/1711, current 99.524727/1721: elapsed=172609.0us expected=167157.5us (kms_flip:2594) DEBUG: name = vblank last_ts = 99.524727 last_received_ts = 99.524071 last_seq = 1721 current_ts = 99.697113 current_received_ts = 99.696480 current_seq = 1731 count = 24 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 99.524727/1721, current 99.697113/1731: elapsed=172382.0us expected=167157.5us +- 835.8us, error 3.1% (kms_flip:2594) DEBUG: inconsistent vblank ts/seq: last 99.524727/1721, current 99.697113/1731: elapsed=172382.0us expected=167157.5us (kms_flip:2594) DEBUG: name = vblank last_ts = 99.697113 last_received_ts = 99.696480 last_seq = 1731 current_ts = 99.869492 current_received_ts = 99.868889 current_seq = 1741 count = 25 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 99.697113/1731, current 99.869492/1741: elapsed=172385.0us expected=167157.5us +- 835.8us, error 3.1% (kms_flip:2594) DEBUG: inconsistent vblank ts/seq: last 99.697113/1731, current 99.869492/1741: elapsed=172385.0us expected=167157.5us (kms_flip:2594) DEBUG: name = vblank last_ts = 99.869492 last_received_ts = 99.868889 last_seq = 1741 current_ts = 100.041878 current_received_ts = 100.041237 current_seq = 1751 count = 26 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 99.869492/1741, current 100.041878/1751: elapsed=172384.0us expected=167157.5us +- 835.8us, error 3.1% (kms_flip:2594) DEBUG: inconsistent vblank ts/seq: last 99.869492/1741, current 100.041878/1751: elapsed=172384.0us expected=167157.5us (kms_flip:2594) DEBUG: name = vblank last_ts = 100.041878 last_received_ts = 100.041237 last_seq = 1751 current_ts = 100.214256 current_received_ts = 100.213654 current_seq = 1761 count = 27 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 100.041878/1751, current 100.214256/1761: elapsed=172379.0us expected=167157.5us +- 835.8us, error 3.1% (kms_flip:2594) DEBUG: inconsistent vblank ts/seq: last 100.041878/1751, current 100.214256/1761: elapsed=172379.0us expected=167157.5us (kms_flip:2594) DEBUG: name = vblank last_ts = 100.214256 last_received_ts = 100.213654 last_seq = 1761 current_ts = 100.386642 current_received_ts = 100.386063 current_seq = 1771 count = 28 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 100.214256/1761, current 100.386642/1771: elapsed=172387.0us expected=167157.5us +- 835.8us, error 3.1% (kms_flip:2594) DEBUG: inconsistent vblank ts/seq: last 100.214256/1761, current 100.386642/1771: elapsed=172387.0us expected=167157.5us (kms_flip:2594) DEBUG: name = vblank last_ts = 100.386642 last_received_ts = 100.386063 last_seq = 1771 current_ts = 100.558456 current_received_ts = 100.557846 current_seq = 1781 count = 29 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 100.386642/1771, current 100.558456/1781: elapsed=171808.0us expected=167157.5us +- 835.8us, error 2.8% (kms_flip:2594) DEBUG: inconsistent vblank ts/seq: last 100.386642/1771, current 100.558456/1781: elapsed=171808.0us expected=167157.5us (kms_flip:2594) DEBUG: name = vblank last_ts = 100.558456 last_received_ts = 100.557846 last_seq = 1781 current_ts = 100.730209 current_received_ts = 100.729614 current_seq = 1791 count = 30 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 100.558456/1781, current 100.730209/1791: elapsed=171759.0us expected=167157.5us +- 835.8us, error 2.8% (kms_flip:2594) DEBUG: inconsistent vblank ts/seq: last 100.558456/1781, current 100.730209/1791: elapsed=171759.0us expected=167157.5us (kms_flip:2594) DEBUG: name = vblank last_ts = 100.730209 last_received_ts = 100.729614 last_seq = 1791 current_ts = 100.901955 current_received_ts = 100.901329 current_seq = 1801 count = 31 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 100.730209/1791, current 100.901955/1801: elapsed=171743.0us expected=167157.5us +- 835.8us, error 2.7% (kms_flip:2594) DEBUG: inconsistent vblank ts/seq: last 100.730209/1791, current 100.901955/1801: elapsed=171743.0us expected=167157.5us (kms_flip:2594) DEBUG: name = vblank last_ts = 100.901955 last_received_ts = 100.901329 last_seq = 1801 current_ts = 101.073692 current_received_ts = 101.073082 current_seq = 1811 count = 32 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 100.901955/1801, current 101.073692/1811: elapsed=171735.0us expected=167157.5us +- 835.8us, error 2.7% (kms_flip:2594) DEBUG: inconsistent vblank ts/seq: last 100.901955/1801, current 101.073692/1811: elapsed=171735.0us expected=167157.5us (kms_flip:2594) DEBUG: name = vblank last_ts = 101.073692 last_received_ts = 101.073082 last_seq = 1811 current_ts = 101.245438 current_received_ts = 101.244743 current_seq = 1821 count = 33 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 101.073692/1811, current 101.245438/1821: elapsed=171745.0us expected=167157.5us +- 835.8us, error 2.7% (kms_flip:2594) DEBUG: inconsistent vblank ts/seq: last 101.073692/1811, current 101.245438/1821: elapsed=171745.0us expected=167157.5us (kms_flip:2594) DEBUG: name = vblank last_ts = 101.245438 last_received_ts = 101.244743 last_seq = 1821 current_ts = 101.417130 current_received_ts = 101.416519 current_seq = 1831 count = 34 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 101.245438/1821, current 101.417130/1831: elapsed=171691.0us expected=167157.5us +- 835.8us, error 2.7% (kms_flip:2594) DEBUG: inconsistent vblank ts/seq: last 101.245438/1821, current 101.417130/1831: elapsed=171691.0us expected=167157.5us (kms_flip:2594) DEBUG: name = vblank last_ts = 101.417130 last_received_ts = 101.416519 last_seq = 1831 current_ts = 101.588310 current_received_ts = 101.587662 current_seq = 1841 count = 35 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 101.417130/1831, current 101.588310/1841: elapsed=171183.0us expected=167157.5us +- 835.8us, error 2.4% (kms_flip:2594) DEBUG: inconsistent vblank ts/seq: last 101.417130/1831, current 101.588310/1841: elapsed=171183.0us expected=167157.5us (kms_flip:2594) DEBUG: name = vblank last_ts = 101.588310 last_received_ts = 101.587662 last_seq = 1841 current_ts = 101.759491 current_received_ts = 101.758858 current_seq = 1851 count = 36 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 101.588310/1841, current 101.759491/1851: elapsed=171180.0us expected=167157.5us +- 835.8us, error 2.4% (kms_flip:2594) DEBUG: inconsistent vblank ts/seq: last 101.588310/1841, current 101.759491/1851: elapsed=171180.0us expected=167157.5us (kms_flip:2594) DEBUG: name = vblank last_ts = 101.759491 last_received_ts = 101.758858 last_seq = 1851 current_ts = 101.930664 current_received_ts = 101.930084 current_seq = 1861 count = 37 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 101.759491/1851, current 101.930664/1861: elapsed=171178.0us expected=167157.5us +- 835.8us, error 2.4% (kms_flip:2594) DEBUG: inconsistent vblank ts/seq: last 101.759491/1851, current 101.930664/1861: elapsed=171178.0us expected=167157.5us (kms_flip:2594) DEBUG: name = vblank last_ts = 101.930664 last_received_ts = 101.930084 last_seq = 1861 current_ts = 102.101845 current_received_ts = 102.101212 current_seq = 1871 count = 38 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 101.930664/1861, current 102.101845/1871: elapsed=171180.0us expected=167157.5us +- 835.8us, error 2.4% (kms_flip:2594) DEBUG: inconsistent vblank ts/seq: last 101.930664/1861, current 102.101845/1871: elapsed=171180.0us expected=167157.5us (kms_flip:2594) DEBUG: name = vblank last_ts = 102.101845 last_received_ts = 102.101212 last_seq = 1871 current_ts = 102.273033 current_received_ts = 102.272392 current_seq = 1881 count = 39 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 102.101845/1871, current 102.273033/1881: elapsed=171185.0us expected=167157.5us +- 835.8us, error 2.4% (kms_flip:2594) DEBUG: inconsistent vblank ts/seq: last 102.101845/1871, current 102.273033/1881: elapsed=171185.0us expected=167157.5us (kms_flip:2594) DEBUG: name = vblank last_ts = 102.273033 last_received_ts = 102.272392 last_seq = 1881 current_ts = 102.444099 current_received_ts = 102.443497 current_seq = 1891 count = 40 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 102.273033/1881, current 102.444099/1891: elapsed=171071.0us expected=167157.5us +- 835.8us, error 2.3% (kms_flip:2594) DEBUG: inconsistent vblank ts/seq: last 102.273033/1881, current 102.444099/1891: elapsed=171071.0us expected=167157.5us (kms_flip:2594) DEBUG: name = vblank last_ts = 102.444099 last_received_ts = 102.443497 last_seq = 1891 current_ts = 102.614807 current_received_ts = 102.614143 current_seq = 1901 count = 41 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 102.444099/1891, current 102.614807/1901: elapsed=170701.0us expected=167157.5us +- 835.8us, error 2.1% (kms_flip:2594) DEBUG: inconsistent vblank ts/seq: last 102.444099/1891, current 102.614807/1901: elapsed=170701.0us expected=167157.5us (kms_flip:2594) DEBUG: name = vblank last_ts = 102.614807 last_received_ts = 102.614143 last_seq = 1901 current_ts = 102.785492 current_received_ts = 102.784821 current_seq = 1911 count = 42 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 102.614807/1901, current 102.785492/1911: elapsed=170690.0us expected=167157.5us +- 835.8us, error 2.1% (kms_flip:2594) DEBUG: inconsistent vblank ts/seq: last 102.614807/1901, current 102.785492/1911: elapsed=170690.0us expected=167157.5us (kms_flip:2594) DEBUG: name = vblank last_ts = 102.785492 last_received_ts = 102.784821 last_seq = 1911 current_ts = 102.956184 current_received_ts = 102.955559 current_seq = 1921 count = 43 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 102.785492/1911, current 102.956184/1921: elapsed=170687.0us expected=167157.5us +- 835.8us, error 2.1% (kms_flip:2594) DEBUG: inconsistent vblank ts/seq: last 102.785492/1911, current 102.956184/1921: elapsed=170687.0us expected=167157.5us (kms_flip:2594) DEBUG: name = vblank last_ts = 102.956184 last_received_ts = 102.955559 last_seq = 1921 current_ts = 103.126869 current_received_ts = 103.126167 current_seq = 1931 count = 44 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 102.956184/1921, current 103.126869/1931: elapsed=170690.0us expected=167157.5us +- 835.8us, error 2.1% (kms_flip:2594) DEBUG: inconsistent vblank ts/seq: last 102.956184/1921, current 103.126869/1931: elapsed=170690.0us expected=167157.5us (kms_flip:2594) INFO: Event vblank: expected 457, counted 450, passrate = 37.78%, encoder type 1 (kms_flip:2594) DEBUG: dropped frames, expected 457, counted 450, passrate = 37.78%, encoder type 1 (kms_flip:2594) DEBUG: Retrying without a hotplug event (kms_flip:2594) DEBUG: No stale events found (kms_flip:2594) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0 (kms_flip:2594) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0 (kms_flip:2594) INFO: Expected frametime: 16666us; measured 16938.9us +- 15.590us accuracy 0.28% (kms_flip:2594) INFO: vblank interval differs from modeline! expected 16665.6us, measured 16939us +- 15.590us, difference 273.3us (17.5 sigma) (kms_flip:2594) DEBUG: name = vblank last_ts = 0.000000 last_received_ts = 0.000000 last_seq = 0 current_ts = 106.747643 current_received_ts = 106.747093 current_seq = 1965 count = 0 seq_step = 10 (kms_flip:2594) DEBUG: name = vblank last_ts = 106.747643 last_received_ts = 106.747093 last_seq = 1965 current_ts = 106.916916 current_received_ts = 106.916168 current_seq = 1975 count = 1 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 106.747643/1965, current 106.916916/1975: elapsed=169272.0us expected=169388.7us +- 846.9us, error 0.1% (kms_flip:2594) DEBUG: name = vblank last_ts = 106.916916 last_received_ts = 106.916168 last_seq = 1975 current_ts = 107.086182 current_received_ts = 107.085503 current_seq = 1985 count = 2 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 106.916916/1975, current 107.086182/1985: elapsed=169266.0us expected=169388.7us +- 846.9us, error 0.1% (kms_flip:2594) DEBUG: name = vblank last_ts = 107.086182 last_received_ts = 107.085503 last_seq = 1985 current_ts = 107.255447 current_received_ts = 107.254852 current_seq = 1995 count = 3 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 107.086182/1985, current 107.255447/1995: elapsed=169264.0us expected=169388.7us +- 846.9us, error 0.1% (kms_flip:2594) DEBUG: name = vblank last_ts = 107.255447 last_received_ts = 107.254852 last_seq = 1995 current_ts = 107.424706 current_received_ts = 107.424072 current_seq = 2005 count = 4 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 107.255447/1995, current 107.424706/2005: elapsed=169256.0us expected=169388.7us +- 846.9us, error 0.1% (kms_flip:2594) DEBUG: name = vblank last_ts = 107.424706 last_received_ts = 107.424072 last_seq = 2005 current_ts = 107.593704 current_received_ts = 107.593079 current_seq = 2015 count = 5 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 107.424706/2005, current 107.593704/2015: elapsed=168999.0us expected=169388.7us +- 846.9us, error 0.2% (kms_flip:2594) DEBUG: name = vblank last_ts = 107.593704 last_received_ts = 107.593079 last_seq = 2015 current_ts = 107.762733 current_received_ts = 107.762032 current_seq = 2025 count = 6 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 107.593704/2015, current 107.762733/2025: elapsed=169032.0us expected=169388.7us +- 846.9us, error 0.2% (kms_flip:2594) DEBUG: name = vblank last_ts = 107.762733 last_received_ts = 107.762032 last_seq = 2025 current_ts = 107.931732 current_received_ts = 107.931145 current_seq = 2035 count = 7 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 107.762733/2025, current 107.931732/2035: elapsed=169000.0us expected=169388.7us +- 846.9us, error 0.2% (kms_flip:2594) DEBUG: name = vblank last_ts = 107.931732 last_received_ts = 107.931145 last_seq = 2035 current_ts = 108.100746 current_received_ts = 108.100166 current_seq = 2045 count = 8 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 107.931732/2035, current 108.100746/2045: elapsed=169013.0us expected=169388.7us +- 846.9us, error 0.2% (kms_flip:2594) DEBUG: name = vblank last_ts = 108.100746 last_received_ts = 108.100166 last_seq = 2045 current_ts = 108.269760 current_received_ts = 108.269119 current_seq = 2055 count = 9 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 108.100746/2045, current 108.269760/2055: elapsed=169015.0us expected=169388.7us +- 846.9us, error 0.2% (kms_flip:2594) DEBUG: name = vblank last_ts = 108.269760 last_received_ts = 108.269119 last_seq = 2055 current_ts = 108.438744 current_received_ts = 108.438095 current_seq = 2065 count = 10 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 108.269760/2055, current 108.438744/2065: elapsed=168982.0us expected=169388.7us +- 846.9us, error 0.2% (kms_flip:2594) DEBUG: name = vblank last_ts = 108.438744 last_received_ts = 108.438095 last_seq = 2065 current_ts = 108.607529 current_received_ts = 108.606941 current_seq = 2075 count = 11 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 108.438744/2065, current 108.607529/2075: elapsed=168787.0us expected=169388.7us +- 846.9us, error 0.4% (kms_flip:2594) DEBUG: name = vblank last_ts = 108.607529 last_received_ts = 108.606941 last_seq = 2075 current_ts = 108.776329 current_received_ts = 108.775742 current_seq = 2085 count = 12 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 108.607529/2075, current 108.776329/2085: elapsed=168798.0us expected=169388.7us +- 846.9us, error 0.3% (kms_flip:2594) DEBUG: name = vblank last_ts = 108.776329 last_received_ts = 108.775742 last_seq = 2085 current_ts = 108.945129 current_received_ts = 108.944519 current_seq = 2095 count = 13 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 108.776329/2085, current 108.945129/2095: elapsed=168798.0us expected=169388.7us +- 846.9us, error 0.3% (kms_flip:2594) DEBUG: name = vblank last_ts = 108.945129 last_received_ts = 108.944519 last_seq = 2095 current_ts = 109.113937 current_received_ts = 109.113274 current_seq = 2105 count = 14 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 108.945129/2095, current 109.113937/2105: elapsed=168807.0us expected=169388.7us +- 846.9us, error 0.3% (kms_flip:2594) DEBUG: name = vblank last_ts = 109.113937 last_received_ts = 109.113274 last_seq = 2105 current_ts = 109.282730 current_received_ts = 109.282097 current_seq = 2115 count = 15 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 109.113937/2105, current 109.282730/2115: elapsed=168796.0us expected=169388.7us +- 846.9us, error 0.3% (kms_flip:2594) DEBUG: name = vblank last_ts = 109.282730 last_received_ts = 109.282097 last_seq = 2115 current_ts = 109.451454 current_received_ts = 109.450798 current_seq = 2125 count = 16 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 109.282730/2115, current 109.451454/2125: elapsed=168724.0us expected=169388.7us +- 846.9us, error 0.4% (kms_flip:2594) DEBUG: name = vblank last_ts = 109.451454 last_received_ts = 109.450798 last_seq = 2125 current_ts = 109.620071 current_received_ts = 109.619507 current_seq = 2135 count = 17 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 109.451454/2125, current 109.620071/2135: elapsed=168616.0us expected=169388.7us +- 846.9us, error 0.5% (kms_flip:2594) DEBUG: name = vblank last_ts = 109.620071 last_received_ts = 109.619507 last_seq = 2135 current_ts = 109.788666 current_received_ts = 109.787979 current_seq = 2145 count = 18 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 109.620071/2135, current 109.788666/2145: elapsed=168591.0us expected=169388.7us +- 846.9us, error 0.5% (kms_flip:2594) DEBUG: name = vblank last_ts = 109.788666 last_received_ts = 109.787979 last_seq = 2145 current_ts = 109.957283 current_received_ts = 109.956627 current_seq = 2155 count = 19 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 109.788666/2145, current 109.957283/2155: elapsed=168619.0us expected=169388.7us +- 846.9us, error 0.5% (kms_flip:2594) DEBUG: name = vblank last_ts = 109.957283 last_received_ts = 109.956627 last_seq = 2155 current_ts = 110.125870 current_received_ts = 110.125259 current_seq = 2165 count = 20 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 109.957283/2155, current 110.125870/2165: elapsed=168590.0us expected=169388.7us +- 846.9us, error 0.5% (kms_flip:2594) DEBUG: name = vblank last_ts = 110.125870 last_received_ts = 110.125259 last_seq = 2165 current_ts = 110.294472 current_received_ts = 110.293816 current_seq = 2175 count = 21 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 110.125870/2165, current 110.294472/2175: elapsed=168598.0us expected=169388.7us +- 846.9us, error 0.5% (kms_flip:2594) DEBUG: name = vblank last_ts = 110.294472 last_received_ts = 110.293816 last_seq = 2175 current_ts = 110.463020 current_received_ts = 110.462311 current_seq = 2185 count = 22 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 110.294472/2175, current 110.463020/2185: elapsed=168549.0us expected=169388.7us +- 846.9us, error 0.5% (kms_flip:2594) DEBUG: name = vblank last_ts = 110.463020 last_received_ts = 110.462311 last_seq = 2185 current_ts = 110.631454 current_received_ts = 110.630775 current_seq = 2195 count = 23 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 110.463020/2185, current 110.631454/2195: elapsed=168440.0us expected=169388.7us +- 846.9us, error 0.6% (kms_flip:2594) DEBUG: inconsistent vblank ts/seq: last 110.463020/2185, current 110.631454/2195: elapsed=168440.0us expected=169388.7us (kms_flip:2594) DEBUG: name = vblank last_ts = 110.631454 last_received_ts = 110.630775 last_seq = 2195 current_ts = 110.799896 current_received_ts = 110.799248 current_seq = 2205 count = 24 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 110.631454/2195, current 110.799896/2205: elapsed=168437.0us expected=169388.7us +- 846.9us, error 0.6% (kms_flip:2594) DEBUG: inconsistent vblank ts/seq: last 110.631454/2195, current 110.799896/2205: elapsed=168437.0us expected=169388.7us (kms_flip:2594) DEBUG: name = vblank last_ts = 110.799896 last_received_ts = 110.799248 last_seq = 2205 current_ts = 110.968315 current_received_ts = 110.967583 current_seq = 2215 count = 25 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 110.799896/2205, current 110.968315/2215: elapsed=168423.0us expected=169388.7us +- 846.9us, error 0.6% (kms_flip:2594) DEBUG: inconsistent vblank ts/seq: last 110.799896/2205, current 110.968315/2215: elapsed=168423.0us expected=169388.7us (kms_flip:2594) DEBUG: name = vblank last_ts = 110.968315 last_received_ts = 110.967583 last_seq = 2215 current_ts = 111.136749 current_received_ts = 111.136070 current_seq = 2225 count = 26 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 110.968315/2215, current 111.136749/2225: elapsed=168435.0us expected=169388.7us +- 846.9us, error 0.6% (kms_flip:2594) DEBUG: inconsistent vblank ts/seq: last 110.968315/2215, current 111.136749/2225: elapsed=168435.0us expected=169388.7us (kms_flip:2594) DEBUG: name = vblank last_ts = 111.136749 last_received_ts = 111.136070 last_seq = 2225 current_ts = 111.305191 current_received_ts = 111.304535 current_seq = 2235 count = 27 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 111.136749/2225, current 111.305191/2235: elapsed=168435.0us expected=169388.7us +- 846.9us, error 0.6% (kms_flip:2594) DEBUG: inconsistent vblank ts/seq: last 111.136749/2225, current 111.305191/2235: elapsed=168435.0us expected=169388.7us (kms_flip:2594) DEBUG: name = vblank last_ts = 111.305191 last_received_ts = 111.304535 last_seq = 2235 current_ts = 111.473572 current_received_ts = 111.472916 current_seq = 2245 count = 28 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 111.305191/2235, current 111.473572/2245: elapsed=168380.0us expected=169388.7us +- 846.9us, error 0.6% (kms_flip:2594) DEBUG: inconsistent vblank ts/seq: last 111.305191/2235, current 111.473572/2245: elapsed=168380.0us expected=169388.7us (kms_flip:2594) DEBUG: name = vblank last_ts = 111.473572 last_received_ts = 111.472916 last_seq = 2245 current_ts = 111.641861 current_received_ts = 111.641212 current_seq = 2255 count = 29 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 111.473572/2245, current 111.641861/2255: elapsed=168292.0us expected=169388.7us +- 846.9us, error 0.6% (kms_flip:2594) DEBUG: inconsistent vblank ts/seq: last 111.473572/2245, current 111.641861/2255: elapsed=168292.0us expected=169388.7us (kms_flip:2594) DEBUG: name = vblank last_ts = 111.641861 last_received_ts = 111.641212 last_seq = 2255 current_ts = 111.810127 current_received_ts = 111.809525 current_seq = 2265 count = 30 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 111.641861/2255, current 111.810127/2265: elapsed=168271.0us expected=169388.7us +- 846.9us, error 0.7% (kms_flip:2594) DEBUG: inconsistent vblank ts/seq: last 111.641861/2255, current 111.810127/2265: elapsed=168271.0us expected=169388.7us (kms_flip:2594) DEBUG: name = vblank last_ts = 111.810127 last_received_ts = 111.809525 last_seq = 2265 current_ts = 111.978416 current_received_ts = 111.977829 current_seq = 2275 count = 31 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 111.810127/2265, current 111.978416/2275: elapsed=168286.0us expected=169388.7us +- 846.9us, error 0.7% (kms_flip:2594) DEBUG: inconsistent vblank ts/seq: last 111.810127/2265, current 111.978416/2275: elapsed=168286.0us expected=169388.7us (kms_flip:2594) DEBUG: name = vblank last_ts = 111.978416 last_received_ts = 111.977829 last_seq = 2275 current_ts = 112.146721 current_received_ts = 112.146118 current_seq = 2285 count = 32 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 111.978416/2275, current 112.146721/2285: elapsed=168303.0us expected=169388.7us +- 846.9us, error 0.6% (kms_flip:2594) DEBUG: inconsistent vblank ts/seq: last 111.978416/2275, current 112.146721/2285: elapsed=168303.0us expected=169388.7us (kms_flip:2594) DEBUG: name = vblank last_ts = 112.146721 last_received_ts = 112.146118 last_seq = 2285 current_ts = 112.314987 current_received_ts = 112.314438 current_seq = 2295 count = 33 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 112.146721/2285, current 112.314987/2295: elapsed=168270.0us expected=169388.7us +- 846.9us, error 0.7% (kms_flip:2594) DEBUG: inconsistent vblank ts/seq: last 112.146721/2285, current 112.314987/2295: elapsed=168270.0us expected=169388.7us (kms_flip:2594) DEBUG: name = vblank last_ts = 112.314987 last_received_ts = 112.314438 last_seq = 2295 current_ts = 112.483231 current_received_ts = 112.482544 current_seq = 2305 count = 34 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 112.314987/2295, current 112.483231/2305: elapsed=168241.0us expected=169388.7us +- 846.9us, error 0.7% (kms_flip:2594) DEBUG: inconsistent vblank ts/seq: last 112.314987/2295, current 112.483231/2305: elapsed=168241.0us expected=169388.7us (kms_flip:2594) DEBUG: name = vblank last_ts = 112.483231 last_received_ts = 112.482544 last_seq = 2305 current_ts = 112.651382 current_received_ts = 112.650749 current_seq = 2315 count = 35 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 112.483231/2305, current 112.651382/2315: elapsed=168149.0us expected=169388.7us +- 846.9us, error 0.7% (kms_flip:2594) DEBUG: inconsistent vblank ts/seq: last 112.483231/2305, current 112.651382/2315: elapsed=168149.0us expected=169388.7us (kms_flip:2594) DEBUG: name = vblank last_ts = 112.651382 last_received_ts = 112.650749 last_seq = 2315 current_ts = 112.819527 current_received_ts = 112.818924 current_seq = 2325 count = 36 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 112.651382/2315, current 112.819527/2325: elapsed=168150.0us expected=169388.7us +- 846.9us, error 0.7% (kms_flip:2594) DEBUG: inconsistent vblank ts/seq: last 112.651382/2315, current 112.819527/2325: elapsed=168150.0us expected=169388.7us (kms_flip:2594) DEBUG: name = vblank last_ts = 112.819527 last_received_ts = 112.818924 last_seq = 2325 current_ts = 112.987686 current_received_ts = 112.987083 current_seq = 2335 count = 37 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 112.819527/2325, current 112.987686/2335: elapsed=168157.0us expected=169388.7us +- 846.9us, error 0.7% (kms_flip:2594) DEBUG: inconsistent vblank ts/seq: last 112.819527/2325, current 112.987686/2335: elapsed=168157.0us expected=169388.7us (kms_flip:2594) DEBUG: name = vblank last_ts = 112.987686 last_received_ts = 112.987083 last_seq = 2335 current_ts = 113.155838 current_received_ts = 113.155228 current_seq = 2345 count = 38 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 112.987686/2335, current 113.155838/2345: elapsed=168154.0us expected=169388.7us +- 846.9us, error 0.7% (kms_flip:2594) DEBUG: inconsistent vblank ts/seq: last 112.987686/2335, current 113.155838/2345: elapsed=168154.0us expected=169388.7us (kms_flip:2594) DEBUG: name = vblank last_ts = 113.155838 last_received_ts = 113.155228 last_seq = 2345 current_ts = 113.324013 current_received_ts = 113.323265 current_seq = 2355 count = 39 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 113.155838/2345, current 113.324013/2355: elapsed=168170.0us expected=169388.7us +- 846.9us, error 0.7% (kms_flip:2594) DEBUG: inconsistent vblank ts/seq: last 113.155838/2345, current 113.324013/2355: elapsed=168170.0us expected=169388.7us (kms_flip:2594) DEBUG: name = vblank last_ts = 113.324013 last_received_ts = 113.323265 last_seq = 2355 current_ts = 113.492111 current_received_ts = 113.491440 current_seq = 2365 count = 40 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 113.324013/2355, current 113.492111/2365: elapsed=168100.0us expected=169388.7us +- 846.9us, error 0.8% (kms_flip:2594) DEBUG: inconsistent vblank ts/seq: last 113.324013/2355, current 113.492111/2365: elapsed=168100.0us expected=169388.7us (kms_flip:2594) DEBUG: name = vblank last_ts = 113.492111 last_received_ts = 113.491440 last_seq = 2365 current_ts = 113.660141 current_received_ts = 113.659431 current_seq = 2375 count = 41 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 113.492111/2365, current 113.660141/2375: elapsed=168030.0us expected=169388.7us +- 846.9us, error 0.8% (kms_flip:2594) DEBUG: inconsistent vblank ts/seq: last 113.492111/2365, current 113.660141/2375: elapsed=168030.0us expected=169388.7us (kms_flip:2594) DEBUG: name = vblank last_ts = 113.660141 last_received_ts = 113.659431 last_seq = 2375 current_ts = 113.828186 current_received_ts = 113.827591 current_seq = 2385 count = 42 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 113.660141/2375, current 113.828186/2385: elapsed=168047.0us expected=169388.7us +- 846.9us, error 0.8% (kms_flip:2594) DEBUG: inconsistent vblank ts/seq: last 113.660141/2375, current 113.828186/2385: elapsed=168047.0us expected=169388.7us (kms_flip:2594) DEBUG: name = vblank last_ts = 113.828186 last_received_ts = 113.827591 last_seq = 2385 current_ts = 113.996246 current_received_ts = 113.995605 current_seq = 2395 count = 43 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 113.828186/2385, current 113.996246/2395: elapsed=168060.0us expected=169388.7us +- 846.9us, error 0.8% (kms_flip:2594) DEBUG: inconsistent vblank ts/seq: last 113.828186/2385, current 113.996246/2395: elapsed=168060.0us expected=169388.7us (kms_flip:2594) DEBUG: name = vblank last_ts = 113.996246 last_received_ts = 113.995605 last_seq = 2395 current_ts = 114.164276 current_received_ts = 114.163689 current_seq = 2405 count = 44 seq_step = 10 (kms_flip:2594) DEBUG: vblank ts/seq: last 113.996246/2395, current 114.164276/2405: elapsed=168028.0us expected=169388.7us +- 846.9us, error 0.8% (kms_flip:2594) DEBUG: inconsistent vblank ts/seq: last 113.996246/2395, current 114.164276/2405: elapsed=168028.0us expected=169388.7us (kms_flip:2594) INFO: Event vblank: expected 447, counted 450, passrate = 51.11%, encoder type 1 (kms_flip:2594) DEBUG: dropped frames, expected 447, counted 450, passrate = 51.11%, encoder type 1 (kms_flip:2594) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1722: (kms_flip:2594) CRITICAL: Failed assertion: !retried (kms_flip:2594) CRITICAL: Last errno: 4, Interrupted system call (kms_flip:2594) igt_core-INFO: Stack trace: (kms_flip:2594) igt_core-INFO: #0 ../lib/igt_core.c:2075 __igt_fail_assert() (kms_flip:2594) igt_core-INFO: #1 ../tests/kms_flip.c:1071 run_test_on_crtc_set() (kms_flip:2594) igt_core-INFO: #2 ../tests/kms_flip.c:1920 run_test() (kms_flip:2594) igt_core-INFO: #3 ../tests/kms_flip.c:2220 __igt_unique____real_main2105() (kms_flip:2594) igt_core-INFO: #4 ../tests/kms_flip.c:2105 main() (kms_flip:2594) igt_core-INFO: #5 [__libc_init_first+0x8a] (kms_flip:2594) igt_core-INFO: #6 [__libc_start_main+0x8b] (kms_flip:2594) igt_core-INFO: #7 [_start+0x25] **** END **** Dynamic subtest A-VGA1: FAIL (19.059s) |
| Dmesg |
<6> [94.497262] Console: switching to colour dummy device 80x25
<6> [94.497356] [IGT] kms_flip: executing
<7> [94.502464] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [94.503817] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [94.505079] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [94.508862] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:84:DP-1]
<7> [94.508874] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:84:DP-1]
<7> [94.511661] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.514568] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.517445] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.520351] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.523267] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.526213] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.529134] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.532320] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.535518] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.538591] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.541724] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.544744] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.547778] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.550869] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.553897] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.556950] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.560021] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.563154] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.566283] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.569357] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.572612] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.575662] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.578716] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.581817] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.584905] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.588016] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.591186] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.594294] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.597402] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.600486] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.603627] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.606731] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.607229] i915 0000:00:02.0: [drm:drm_dp_dpcd_access [drm_display_helper]] AUX D/DP D: Too many retries, giving up. First error: -110
<7> [94.609860] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.612727] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.615635] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.618709] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.621755] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.624816] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.627911] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.631031] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.634135] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.637291] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.640448] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.643633] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.646741] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.649857] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.652967] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.656041] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.659123] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.662246] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.665409] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.668520] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.671665] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.674735] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.677837] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.680896] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.683932] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.686978] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.690108] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.693279] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.696419] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.699564] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.702653] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.705741] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.706222] i915 0000:00:02.0: [drm:drm_dp_dpcd_access [drm_display_helper]] AUX D/DP D: Too many retries, giving up. First error: -110
<7> [94.706261] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:84:DP-1] disconnected
<7> [94.706606] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:73:VGA-1]
<7> [94.706622] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] [CONNECTOR:73:VGA-1] force=1
<7> [94.707030] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] ironlake hotplug adpa=0x83f40000, result 1
<7> [94.707358] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] CRT detected via hotplug
<7> [94.707935] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [94.708354] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK on first message, retry
<7> [94.708955] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [94.709400] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [94.709412] i915 0000:00:02.0: [drm:intel_crt_get_edid [i915]] CRT GMBUS EDID read failed, retry using GPIO bit-banging
<7> [94.709697] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] enabling bit-banging on i915 gmbus vga. force bit now 1
<7> [94.710874] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [94.710881] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] disabling bit-banging on i915 gmbus vga. force bit now 0
<7> [94.711195] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:73:VGA-1] probed modes:
<7> [94.711202] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [94.711208] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "800x600": 60 40000 800 840 968 1056 600 601 605 628 0x40 0x5
<7> [94.711213] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "800x600": 56 36000 800 824 896 1024 600 601 603 625 0x40 0x5
<7> [94.711218] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "848x480": 60 33750 848 864 976 1088 480 486 494 517 0x40 0x5
<7> [94.711224] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "640x480": 60 25175 640 656 752 800 480 490 492 525 0x40 0xa
<7> [94.711246] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:76:HDMI-A-1]
<7> [94.711254] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:76:HDMI-A-1]
<7> [94.736403] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:76:HDMI-A-1] HDMI: DVI dual 0, max TMDS clock 590000 kHz
<7> [94.736411] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:76:HDMI-A-1] HDMI sink does deep color 30.
<7> [94.736415] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:76:HDMI-A-1] HDMI sink does deep color 36.
<7> [94.736419] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:76:HDMI-A-1] HDMI sink does deep color 48.
<7> [94.736422] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:76:HDMI-A-1] Assigning HDMI sink color depth as 16 bpc.
<7> [94.736426] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:76:HDMI-A-1] HDMI sink does YCRCB444 in deep color.
<7> [94.736431] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:76:HDMI-A-1] ELD monitor Mi TV
<7> [94.736436] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:76:HDMI-A-1] HDMI: latency present 0 0, video latency 0 0, audio latency 0 0
<7> [94.736440] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:76:HDMI-A-1] ELD size 36, SAD count 3
<7> [94.736628] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpd] NAK for addr: 0040 w(1)
<7> [94.736900] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpd] NAK on first message, retry
<7> [94.737297] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpd] NAK for addr: 0040 w(1)
<7> [94.737552] i915 0000:00:02.0: [drm:drm_dp_dual_mode_detect [drm_display_helper]] DP dual mode HDMI ID: (err -6)
<7> [94.738822] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "2560x1600": 60 268500 2560 2608 2640 2720 1600 1603 1609 1646 0x40 0x5 (CLOCK_HIGH)
<7> [94.738835] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "2560x1440": 60 241500 2560 2608 2640 2720 1440 1443 1449 1481 0x40 0x5 (CLOCK_HIGH)
<7> [94.738843] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "3840x2160": 30 297000 3840 4016 4104 4400 2160 2168 2178 2250 0x40 0x5 (CLOCK_HIGH)
<7> [94.738852] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "4096x2160": 30 297000 4096 4184 4272 4400 2160 2168 2178 2250 0x40 0x5 (CLOCK_HIGH)
<7> [94.738859] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "1920x1080": 120 297000 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5 (CLOCK_HIGH)
<7> [94.738867] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "3840x2160": 25 297000 3840 4896 4984 5280 2160 2168 2178 2250 0x40 0x5 (CLOCK_HIGH)
<7> [94.738875] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "3840x2160": 24 297000 3840 5116 5204 5500 2160 2168 2178 2250 0x40 0x5 (CLOCK_HIGH)
<7> [94.738882] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "4096x2160": 24 297000 4096 5116 5204 5500 2160 2168 2178 2250 0x40 0x5 (CLOCK_HIGH)
<7> [94.738890] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "3840x2160": 50 594000 3840 4896 4984 5280 2160 2168 2178 2250 0x40 0x5 (CLOCK_HIGH)
<7> [94.738898] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "3840x2160": 60 594000 3840 4016 4104 4400 2160 2168 2178 2250 0x40 0x5 (CLOCK_HIGH)
<7> [94.738905] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "4096x2160": 50 594000 4096 5064 5152 5280 2160 2168 2178 2250 0x40 0x5 (CLOCK_HIGH)
<7> [94.738913] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "4096x2160": 60 594000 4096 4184 4272 4400 2160 2168 2178 2250 0x40 0x5 (CLOCK_HIGH)
<7> [94.738921] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "3840x2160": 30 296703 3840 4016 4104 4400 2160 2168 2178 2250 0x40 0x5 (CLOCK_HIGH)
<7> [94.738928] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "4096x2160": 30 296703 4096 4184 4272 4400 2160 2168 2178 2250 0x40 0x5 (CLOCK_HIGH)
<7> [94.738936] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "1920x1080": 120 296703 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5 (CLOCK_HIGH)
<7> [94.738944] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "3840x2160": 24 296703 3840 5116 5204 5500 2160 2168 2178 2250 0x40 0x5 (CLOCK_HIGH)
<7> [94.738952] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "4096x2160": 24 296703 4096 5116 5204 5500 2160 2168 2178 2250 0x40 0x5 (CLOCK_HIGH)
<7> [94.738960] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "3840x2160": 60 593407 3840 4016 4104 4400 2160 2168 2178 2250 0x40 0x5 (CLOCK_HIGH)
<7> [94.738967] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "4096x2160": 60 593407 4096 4184 4272 4400 2160 2168 2178 2250 0x40 0x5 (CLOCK_HIGH)
<7> [94.738979] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:76:HDMI-A-1] probed modes:
<7> [94.738985] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
<7> [94.738990] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2880x1800": 30 167000 2880 2910 2920 3030 1800 1820 1828 1836 0x40 0x5
<7> [94.738995] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1200": 60 154000 1920 1968 2000 2080 1200 1203 1209 1235 0x40 0x9
<7> [94.739000] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [94.739005] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 60 148352 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [94.739011] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080i": 60 74250 1920 2008 2052 2200 1080 1084 1094 1125 0x40 0x15
<7> [94.739016] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080i": 60 74250 1920 2008 2052 2200 1080 1084 1094 1125 0x40 0x20015
<7> [94.739021] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080i": 60 74176 1920 2008 2052 2200 1080 1084 1094 1125 0x40 0x15
<7> [94.739026] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080i": 60 74176 1920 2008 2052 2200 1080 1084 1094 1125 0x40 0x20015
<7> [94.739031] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 50 148500 1920 2448 2492 2640 1080 1084 1089 1125 0x40 0x5
<7> [94.739036] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080i": 50 74250 1920 2448 2492 2640 1080 1084 1094 1125 0x40 0x15
<7> [94.739041] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080i": 50 74250 1920 2448 2492 2640 1080 1084 1094 1125 0x40 0x20015
<7> [94.739046] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 30 74250 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [94.739051] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 30 74176 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [94.739057] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 25 74250 1920 2448 2492 2640 1080 1084 1089 1125 0x40 0x5
<7> [94.739062] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 24 74250 1920 2558 2602 2750 1080 1084 1089 1125 0x40 0x5
<7> [94.739067] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 24 74250 1920 2558 2602 2750 1080 1084 1089 1125 0x40 0x1c005
<7> [94.739072] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 24 74250 1920 2558 2602 2750 1080 1084 1089 1125 0x40 0x4005
<7> [94.739077] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 24 74176 1920 2558 2602 2750 1080 1084 1089 1125 0x40 0x5
<7> [94.739082] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 24 74176 1920 2558 2602 2750 1080 1084 1089 1125 0x40 0x1c005
<7> [94.739087] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 24 74176 1920 2558 2602 2750 1080 1084 1089 1125 0x40 0x4005
<7> [94.739092] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1600x1200": 60 162000 1600 1664 1856 2160 1200 1201 1204 1250 0x40 0x5
<7> [94.739097] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1680x1050": 60 119000 1680 1728 1760 1840 1050 1053 1059 1080 0x40 0x9
<7> [94.739102] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1600x900": 60 108000 1600 1624 1704 1800 900 901 904 1000 0x40 0x5
<7> [94.739107] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x1024": 60 108000 1280 1328 1440 1688 1024 1025 1028 1066 0x40 0x5
<7> [94.739112] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1440x900": 60 88750 1440 1488 1520 1600 900 903 909 926 0x40 0x9
<7> [94.739117] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1366x768": 60 85500 1366 1436 1579 1792 768 771 774 798 0x40 0x5
<7> [94.739122] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x800": 60 71000 1280 1328 1360 1440 800 803 809 823 0x40 0x9
<7> [94.739127] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x720": 60 74250 1280 1390 1430 1650 720 725 730 750 0x40 0x5
<7> [94.739133] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x720": 60 74250 1280 1390 1430 1650 720 725 730 750 0x40 0x1c005
<7> [94.739138] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x720": 60 74250 1280 1390 1430 1650 720 725 730 750 0x40 0x4005
<7> [94.739143] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x720": 60 74176 1280 1390 1430 1650 720 725 730 750 0x40 0x5
<7> [94.739148] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x720": 60 74176 1280 1390 1430 1650 720 725 730 750 0x40 0x1c005
<7> [94.739153] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x720": 60 74176 1280 1390 1430 1650 720 725 730 750 0x40 0x4005
<7> [94.739158] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x720": 50 74250 1280 1720 1760 1980 720 725 730 750 0x40 0x5
<7> [94.739163] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x720": 50 74250 1280 1720 1760 1980 720 725 730 750 0x40 0x1c005
<7> [94.739168] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x720": 50 74250 1280 1720 1760 1980 720 725 730 750 0x40 0x4005
<7> [94.739173] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [94.739178] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "800x600": 60 40000 800 840 968 1056 600 601 605 628 0x40 0x5
<7> [94.739183] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "720x576": 50 27000 720 732 796 864 576 581 586 625 0x40 0xa
<7> [94.739188] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "720x576i": 50 13500 720 732 795 864 576 580 586 625 0x40 0x101a
<7> [94.739193] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "720x480": 60 27027 720 736 798 858 480 489 495 525 0x40 0xa
<7> [94.739198] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "720x480": 60 27000 720 736 798 858 480 489 495 525 0x40 0xa
<7> [94.739203] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "640x480": 60 25200 640 656 752 800 480 490 492 525 0x40 0xa
<7> [94.739208] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "640x480": 60 25175 640 656 752 800 480 490 492 525 0x40 0xa
<7> [94.739249] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:84:DP-1]
<7> [94.739256] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:84:DP-1]
<7> [94.742054] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.744905] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.747772] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.750641] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.753518] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.756386] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.759290] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.762154] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.765033] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.767888] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.770732] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.773588] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.776424] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.779325] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.782274] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.785170] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.788052] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.790899] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.793771] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.796650] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.799561] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.802446] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.805343] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.808412] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.811601] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.814698] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.817797] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.820875] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.823989] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.827044] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.830163] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.833289] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.833790] i915 0000:00:02.0: [drm:drm_dp_dpcd_access [drm_display_helper]] AUX D/DP D: Too many retries, giving up. First error: -110
<7> [94.836467] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.839408] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.842360] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.845543] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.848691] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.851764] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.854874] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.857928] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.861077] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.864206] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.867357] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.870516] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.873625] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.876711] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.879834] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.882955] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.886070] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.889199] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.892358] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.895439] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.898427] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.901614] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.904682] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.907738] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.910829] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.913925] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.916983] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.920054] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.923270] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.926384] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.929594] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.932711] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [94.933159] i915 0000:00:02.0: [drm:drm_dp_dpcd_access [drm_display_helper]] AUX D/DP D: Too many retries, giving up. First error: -110
<7> [94.933196] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:84:DP-1] disconnected
<6> [94.941734] [IGT] kms_flip: starting subtest wf_vblank-ts-check-interruptible
<6> [94.944065] [IGT] kms_flip: starting dynamic subtest A-VGA1
<7> [94.945397] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:90]
<7> [94.945576] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:93]
<7> [94.975705] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:53:pipe A]
<7> [94.975872] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [94.976262] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [94.976671] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [94.977037] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in has_pch_encoder (expected yes, found no)
<7> [94.977396] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in fdi_lanes (expected 1, found 0)
<7> [94.977901] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in fdi_m_n (expected tu 64 data 6039797/8388608 link 125829/524288, found tu 0, data 0/0 link 0/0)
<7> [94.978259] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in output_types (expected 0x00000002, found 0x00000000)
<7> [94.978599] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [94.978917] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1024, found 0)
<7> [94.979202] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 1344, found 0)
<7> [94.979514] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1024, found 0)
<7> [94.979756] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 1344, found 0)
<7> [94.979988] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 1048, found 0)
<7> [94.980301] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 1184, found 0)
<7> [94.980612] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 768, found 0)
<7> [94.980845] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 768, found 0)
<7> [94.981131] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 771, found 0)
<7> [94.981434] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 777, found 0)
<7> [94.981715] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 806, found 0)
<7> [94.981962] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 806, found 0)
<7> [94.982265] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1024, found 0)
<7> [94.982565] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 1344, found 0)
<7> [94.982797] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1024, found 0)
<7> [94.983084] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 1344, found 0)
<7> [94.983365] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 1048, found 0)
<7> [94.983667] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 1184, found 0)
<7> [94.983898] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 768, found 0)
<7> [94.984139] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 768, found 0)
<7> [94.984449] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 771, found 0)
<7> [94.984773] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 777, found 0)
<7> [94.985035] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 806, found 0)
<7> [94.985267] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 806, found 0)
<7> [94.985586] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [94.985872] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)
<7> [94.986111] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [94.986361] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [94.986698] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 64800, found 0)
<7> [94.986955] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 64800, found 0)
<7> [94.987186] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in port_clock (expected 64800, found 0)
<7> [94.987535] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:53:pipe A] fastset requirement not met, forcing full modeset
<7> [94.987823] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:53:pipe A] releasing PCH DPLL B
<7> [94.988082] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:53:pipe A] min cdclk: 72000 kHz -> 0 kHz
<7> [94.988366] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:53:pipe A] enable: no [modeset]
<7> [94.988728] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:primary A] fb: [NOFB], visible: no
<7> [94.989087] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [94.999548] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port B found
<7> [94.999773] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port C found
<7> [94.999988] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port D found
<7> [95.016445] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable PCH DPLL B (active 0x1, on? 1) for [CRTC:53:pipe A]
<7> [95.017001] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling PCH DPLL B
<7> [95.017720] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:CRT]
<7> [95.018232] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:75:HDMI D]
<7> [95.018965] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:83:DP D]
<7> [95.019551] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:73:VGA-1]
<7> [95.019882] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:53:pipe A]
<7> [95.020271] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:72:pipe B]
<7> [95.020380] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.enable (expected yes, found no)
<7> [95.020647] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.active (expected yes, found no)
<7> [95.020890] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in cpu_transcoder (expected 1, found -1)
<7> [95.021134] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in has_pch_encoder (expected yes, found no)
<7> [95.021397] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in fdi_lanes (expected 3, found 0)
<7> [95.021691] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in fdi_m_n (expected tu 64 data 6934582/8388608 link 288940/524288, found tu 0, data 0/0 link 0/0)
<7> [95.021937] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in lane_count (expected 4, found 0)
<7> [95.022182] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [95.022435] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [95.022723] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [95.022964] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [95.023210] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [95.023480] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [95.023745] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [95.023980] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [95.024223] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [95.024557] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [95.024837] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [95.025102] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [95.025341] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [95.025614] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [95.025906] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [95.026164] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [95.026401] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [95.026682] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [95.026960] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [95.027224] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [95.027480] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [95.027753] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [95.028017] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [95.028281] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [95.028542] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [95.028784] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [95.029035] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [95.029326] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [95.029682] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [95.029914] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [95.030165] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [95.030489] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in pipe_bpp (expected 36, found 0)
<7> [95.030793] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148800, found 0)
<7> [95.031041] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148800, found 0)
<7> [95.031352] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in port_clock (expected 223200, found 0)
<7> [95.031654] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [95.031904] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in infoframes.gcp (expected 0x00000006, found 0x00000000)
<7> [95.032181] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in avi infoframe
<7> [95.032466] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [95.032752] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [95.032755] i915 0000:00:02.0: colorspace: RGB
<7> [95.032758] i915 0000:00:02.0: scan mode: Underscan
<7> [95.032759] i915 0000:00:02.0: colorimetry: No Data
<7> [95.032761] i915 0000:00:02.0: picture aspect: 16:9
<7> [95.032763] i915 0000:00:02.0: active aspect: Same as Picture
<7> [95.032765] i915 0000:00:02.0: itc: No Data
<7> [95.032767] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [95.032769] i915 0000:00:02.0: quantization range: Default
<7> [95.032771] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [95.032773] i915 0000:00:02.0: video code: 16
<7> [95.032775] i915 0000:00:02.0: ycc quantization range: Full
<7> [95.032777] i915 0000:00:02.0: hdmi content type: Graphics
<7> [95.032779] i915 0000:00:02.0: pixel repeat: 0
<7> [95.032780] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [95.032791] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [95.033067] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in spd infoframe
<7> [95.033358] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [95.033673] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [95.033676] i915 0000:00:02.0: vendor: Intel
<7> [95.033678] i915 0000:00:02.0: product: Integrated gfx
<7> [95.033680] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [95.033683] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [95.033930] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hdmi infoframe
<7> [95.034161] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [95.034391] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [95.034393] i915 0000:00:02.0: empty frame
<7> [95.034395] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [95.034752] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:72:pipe B] fastset requirement not met, forcing full modeset
<7> [95.035033] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:72:pipe B] releasing PCH DPLL A
<7> [95.035324] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:72:pipe B] min cdclk: 165334 kHz -> 0 kHz
<7> [95.035588] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:72:pipe B] enable: no [modeset]
<7> [95.035845] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:54:primary B] fb: [NOFB], visible: no
<7> [95.036187] i915 0000:00:02.0: [drm:intel_audio_codec_disable [i915]] [CONNECTOR:76:HDMI-A-1][ENCODER:75:HDMI D] Disable audio codec on [CRTC:72:pipe B]
<7> [95.058002] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe B
<7> [95.076124] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port B found
<7> [95.076637] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port C found
<7> [95.077108] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port D found
<7> [95.086855] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable PCH DPLL A (active 0x2, on? 1) for [CRTC:72:pipe B]
<7> [95.087342] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling PCH DPLL A
<7> [95.088067] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:CRT]
<7> [95.088589] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:75:HDMI D]
<7> [95.089089] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:83:DP D]
<7> [95.089633] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:76:HDMI-A-1]
<7> [95.089959] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:72:pipe B]
<7> [95.090230] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:53:pipe A]
<7> [95.090259] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:73:VGA-1]
<7> [95.090348] i915 0000:00:02.0: [drm:compute_baseline_pipe_bpp [i915]] [CONNECTOR:73:VGA-1] Limiting target display pipe bpp to 24 (EDID bpp 0, max requested bpp 0, max platform bpp 36)
<7> [95.090622] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:53:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [95.090871] i915 0000:00:02.0: [drm:intel_fdi_atomic_check_link [i915]] checking fdi config on pipe A, lanes 1
<7> [95.091097] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [95.091329] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [95.091604] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [95.091854] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in has_pch_encoder (expected no, found yes)
<7> [95.092085] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in fdi_lanes (expected 0, found 1)
<7> [95.092315] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in fdi_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 6039797/8388608 link 125829/524288)
<7> [95.092667] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000002)
<7> [95.092909] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [95.093139] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1024)
<7> [95.093392] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 1344)
<7> [95.093685] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1024)
<7> [95.093945] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 1344)
<7> [95.094176] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 1048)
<7> [95.094406] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 1184)
<7> [95.094681] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 768)
<7> [95.094943] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 768)
<7> [95.095173] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 771)
<7> [95.095404] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 777)
<7> [95.095690] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 806)
<7> [95.095951] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 806)
<7> [95.096192] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1024)
<7> [95.096444] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 1344)
<7> [95.096813] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1024)
<7> [95.097107] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 1344)
<7> [95.097356] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 1048)
<7> [95.097708] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 1184)
<7> [95.097998] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 768)
<7> [95.098265] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 768)
<7> [95.098575] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 771)
<7> [95.098902] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 777)
<7> [95.099147] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 806)
<7> [95.099394] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 806)
<7> [95.099756] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [95.100088] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [95.100343] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [95.100707] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [95.101005] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 64800)
<7> [95.101243] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 64800)
<7> [95.101491] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in port_clock (expected 0, found 64800)
<7> [95.101772] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:53:pipe A] fastset requirement not met, forcing full modeset
<7> [95.102058] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:53:pipe A] min cdclk: 0 kHz -> 72000 kHz
<7> [95.102331] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:53:pipe A] allocated PCH DPLL A
<7> [95.102627] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:53:pipe A] reserving PCH DPLL A
<7> [95.102889] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:53:pipe A] enable: yes [modeset]
<7> [95.103125] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: ANALOG (0x2), output format: RGB, sink format: RGB
<7> [95.103372] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [95.103642] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [95.103894] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [95.104139] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [95.104390] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [95.104674] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] fdi: lanes: 1; data_m: 6039797, data_n: 8388608, link_m: 125829, link_n: 524288, tu: 64
<7> [95.105035] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [95.105362] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 1
<7> [95.105638] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [95.105904] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: no, vmin: 0, vmax: 0, flipline: 0, pipeline full: 0, guardband: 0 vsync start: 0, vsync end: 0
<7> [95.106218] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 0, vmax vblank: 0, vmin vtotal: 0, vmax vtotal: 0
<7> [95.106581] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [95.106834] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [95.107134] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [95.107481] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=64800, hd=1024 hb=1024-1344 hs=1048-1184 ht=1344, vd=768 vb=768-806 vs=771-777 vt=806, flags=0xa
<7> [95.107542] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling always-on
<7> [95.107763] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1024x768": 60 64800 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [95.108087] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=64800, hd=1024 hb=1024-1344 hs=1048-1184 ht=1344, vd=768 vb=768-806 vs=771-777 vt=806, flags=0xa
<7> [95.108406] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 64800, pixel rate 64800, min cdclk 72000, min voltage level 0
<7> [95.108717] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 0, ips linetime: 0
<7> [95.109013] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1024x768+0+0
<7> [95.109353] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [95.109644] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [95.109957] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [95.110276] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: dpll: 0x84100010, dpll_md: 0x0, fp0: 0x10c09, fp1: 0x10c09
<7> [95.110560] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x3 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [95.110902] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [95.111183] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [95.111456] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [95.111860] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [95.112173] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [95.112425] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:primary A] fb: [FB:90] 1024x768 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [95.112723] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [95.112965] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1024.000000x768.000000+0.000000+0.000000 dst: 1024x768+0+0
<7> [95.114094] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling always-on
<7> [95.114374] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:CRT]
<7> [95.114682] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:75:HDMI D]
<7> [95.115041] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:83:DP D]
<7> [95.115852] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [95.116956] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x100
<7> [95.117216] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 1 done.
<7> [95.118239] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x200
<7> [95.118623] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 2 done.
<7> [95.118875] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train done.
<7> [95.119175] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable PCH DPLL A (active 0x1, on? 0) for [CRTC:53:pipe A]
<7> [95.119494] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling PCH DPLL A
<7> [95.188213] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:73:VGA-1]
<7> [95.188862] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:53:pipe A]
<7> [105.963254] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:53:pipe A]
<7> [105.963527] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [105.964062] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [105.964629] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [105.965223] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in has_pch_encoder (expected yes, found no)
<7> [105.965803] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in fdi_lanes (expected 1, found 0)
<7> [105.966416] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in fdi_m_n (expected tu 64 data 6039797/8388608 link 125829/524288, found tu 0, data 0/0 link 0/0)
<7> [105.966722] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in output_types (expected 0x00000002, found 0x00000000)
<7> [105.966961] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [105.967197] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1024, found 0)
<7> [105.967469] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 1344, found 0)
<7> [105.967745] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1024, found 0)
<7> [105.967983] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 1344, found 0)
<7> [105.968219] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 1048, found 0)
<7> [105.968490] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 1184, found 0)
<7> [105.968773] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 768, found 0)
<7> [105.969011] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 768, found 0)
<7> [105.969268] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 771, found 0)
<7> [105.969532] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 777, found 0)
<7> [105.969829] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 806, found 0)
<7> [105.970067] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 806, found 0)
<7> [105.970320] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1024, found 0)
<7> [105.970589] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 1344, found 0)
<7> [105.970826] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1024, found 0)
<7> [105.971062] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 1344, found 0)
<7> [105.971318] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 1048, found 0)
<7> [105.971594] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 1184, found 0)
<7> [105.971831] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 768, found 0)
<7> [105.972086] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 768, found 0)
<7> [105.972355] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 771, found 0)
<7> [105.972627] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 777, found 0)
<7> [105.972865] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 806, found 0)
<7> [105.973102] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 806, found 0)
<7> [105.973376] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [105.973667] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)
<7> [105.973904] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [105.974142] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [105.974430] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 64800, found 0)
<7> [105.974737] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 64800, found 0)
<7> [105.974977] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in port_clock (expected 64800, found 0)
<7> [105.975239] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:53:pipe A] fastset requirement not met, forcing full modeset
<7> [105.975497] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:53:pipe A] releasing PCH DPLL A
<7> [105.975759] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:53:pipe A] min cdclk: 72000 kHz -> 0 kHz
<7> [105.976002] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:53:pipe A] enable: no [modeset]
<7> [105.976263] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:primary A] fb: [NOFB], visible: no
<7> [105.976681] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [105.989326] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port B found
<7> [105.989810] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port C found
<7> [105.990348] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port D found
<7> [106.004151] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable PCH DPLL A (active 0x1, on? 1) for [CRTC:53:pipe A]
<7> [106.004637] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling PCH DPLL A
<7> [106.005402] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:CRT]
<7> [106.006027] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:75:HDMI D]
<7> [106.006477] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:83:DP D]
<7> [106.007120] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:73:VGA-1]
<7> [106.007484] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:53:pipe A]
<7> [106.007864] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:72:pipe B]
<7> [106.008073] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:53:pipe A]
<7> [106.008117] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:73:VGA-1]
<7> [106.008261] i915 0000:00:02.0: [drm:compute_baseline_pipe_bpp [i915]] [CONNECTOR:73:VGA-1] Limiting target display pipe bpp to 24 (EDID bpp 0, max requested bpp 0, max platform bpp 36)
<7> [106.008526] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:53:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [106.008784] i915 0000:00:02.0: [drm:intel_fdi_atomic_check_link [i915]] checking fdi config on pipe A, lanes 1
<7> [106.009135] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [106.009390] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [106.009628] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [106.009998] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in has_pch_encoder (expected no, found yes)
<7> [106.010261] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in fdi_lanes (expected 0, found 1)
<7> [106.010500] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in fdi_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 6039797/8388608 link 125829/524288)
<7> [106.010821] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000002)
<7> [106.011142] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [106.011382] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1024)
<7> [106.011656] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 1344)
<7> [106.012046] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1024)
<7> [106.012313] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 1344)
<7> [106.012553] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 1048)
<7> [106.012821] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 1184)
<7> [106.013187] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 768)
<7> [106.013446] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 768)
<7> [106.013699] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 771)
<7> [106.014035] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 777)
<7> [106.014300] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 806)
<7> [106.014538] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 806)
<7> [106.014887] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1024)
<7> [106.015199] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 1344)
<7> [106.015437] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1024)
<7> [106.015774] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 1344)
<7> [106.016087] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 1048)
<7> [106.016339] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 1184)
<7> [106.016661] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 768)
<7> [106.017007] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 768)
<7> [106.017264] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 771)
<7> [106.017503] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 777)
<7> [106.017860] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 806)
<7> [106.018168] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 806)
<7> [106.018407] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [106.018750] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [106.019051] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [106.019305] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [106.019630] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 64800)
<7> [106.019948] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 64800)
<7> [106.020191] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in port_clock (expected 0, found 64800)
<7> [106.020456] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:53:pipe A] fastset requirement not met, forcing full modeset
<7> [106.020847] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:53:pipe A] min cdclk: 0 kHz -> 72000 kHz
<7> [106.021106] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:53:pipe A] allocated PCH DPLL A
<7> [106.021351] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:53:pipe A] reserving PCH DPLL A
<7> [106.021742] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:53:pipe A] enable: yes [modeset]
<7> [106.022010] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: ANALOG (0x2), output format: RGB, sink format: RGB
<7> [106.022247] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [106.022622] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [106.022889] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [106.023133] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [106.023399] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [106.023784] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] fdi: lanes: 1; data_m: 6039797, data_n: 8388608, link_m: 125829, link_n: 524288, tu: 64
<7> [106.024062] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [106.024300] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 1
<7> [106.024623] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [106.024951] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: no, vmin: 0, vmax: 0, flipline: 0, pipeline full: 0, guardband: 0 vsync start: 0, vsync end: 0
<7> [106.025189] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 0, vmax vblank: 0, vmin vtotal: 0, vmax vtotal: 0
<7> [106.025479] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [106.025851] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [106.025855] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling always-on
<7> [106.026098] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [106.026343] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=64800, hd=1024 hb=1024-1344 hs=1048-1184 ht=1344, vd=768 vb=768-806 vs=771-777 vt=806, flags=0xa
<7> [106.026705] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1024x768": 60 64800 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [106.027011] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=64800, hd=1024 hb=1024-1344 hs=1048-1184 ht=1344, vd=768 vb=768-806 vs=771-777 vt=806, flags=0xa
<7> [106.027250] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 64800, pixel rate 64800, min cdclk 72000, min voltage level 0
<7> [106.027594] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 0, ips linetime: 0
<7> [106.027868] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1024x768+0+0
<7> [106.028112] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [106.028440] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [106.028727] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [106.028976] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: dpll: 0x84100010, dpll_md: 0x0, fp0: 0x10c09, fp1: 0x10c09
<7> [106.029293] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x3 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [106.029692] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [106.029949] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [106.030186] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [106.030459] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [106.030802] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [106.031055] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:primary A] fb: [FB:90] 1024x768 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [106.031293] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [106.031676] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1024.000000x768.000000+0.000000+0.000000 dst: 1024x768+0+0
<7> [106.031984] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling always-on
<7> [106.032236] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:CRT]
<7> [106.032627] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:75:HDMI D]
<7> [106.032879] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:83:DP D]
<7> [106.033668] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [106.034751] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x100
<7> [106.035037] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 1 done.
<7> [106.035924] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x200
<7> [106.036198] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 2 done.
<7> [106.036481] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train done.
<7> [106.036801] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable PCH DPLL A (active 0x1, on? 0) for [CRTC:53:pipe A]
<7> [106.037038] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling PCH DPLL A
<7> [106.106206] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:73:VGA-1]
<7> [106.106813] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:53:pipe A]
<6> [114.002895] [IGT] kms_flip: finished subtest A-VGA1, FAIL
|