Results for igt@kms_flip@wf_vblank-ts-check-interruptible@a-vga1

Result: Fail

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

DetailValue
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
Created at 2026-05-12 03:34:38