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

Result: Fail

integration-manifest git-log-oneline i915_display_info11 igt_runner11 runtimes11 results11.json results11-i915-load.json guc_logs11.tar i915_display_info_post_exec11 boot11 dmesg11

DetailValue
Duration 19.00 seconds
Hostname 
shard-snb7
Igt-Version 
IGT-Version: 2.4-g5666b10ca (x86_64) (Linux: 7.0.0-rc6-Patchwork_164188v3-g2ca39f35278e+ x86_64)
Out 
Using IGT_SRANDOM=1775179475 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 16725.0us +- 10.614us accuracy 0.19%
vblank interval differs from modeline! expected 16665.6us, measured 16725us +- 10.614us, difference 59.4us (5.6 sigma)
Event vblank: expected 458, counted 450, passrate = 35.56%, encoder type 1
Expected frametime: 16666us; measured 16936.2us +- 8.704us accuracy 0.15%
vblank interval differs from modeline! expected 16665.6us, measured 16936us +- 8.704us, difference 270.6us (31.1 sigma)
Event vblank: expected 447, counted 450, passrate = 48.89%, encoder type 1
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../tests/kms_flip.c:428 run_test_on_crtc_set.constprop.0()
  #2 ../tests/kms_flip.c:1891 run_test()
  #3 ../tests/kms_flip.c:2162 __igt_unique____real_main2047()
  #4 ../tests/kms_flip.c:2047 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest A-VGA1: FAIL (18.995s)
Err 
Starting subtest: wf_vblank-ts-check-interruptible
Starting dynamic subtest: A-VGA1
(kms_flip:2981) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1711:
(kms_flip:2981) CRITICAL: Failed assertion: !retried
(kms_flip:2981) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest A-VGA1 failed.
**** DEBUG ****
(kms_flip:2981) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0
(kms_flip:2981) INFO: Expected frametime: 16666us; measured 16725.0us +- 10.614us accuracy 0.19%
(kms_flip:2981) INFO: vblank interval differs from modeline! expected 16665.6us, measured 16725us +- 10.614us, difference 59.4us (5.6 sigma)
(kms_flip:2981) DEBUG: name = vblank
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 296.064148
current_received_ts = 296.063446
current_seq = 10642
count = 0
seq_step = 10
(kms_flip:2981) DEBUG: name = vblank
last_ts = 296.064148
last_received_ts = 296.063446
last_seq = 10642
current_ts = 296.231384
current_received_ts = 296.230652
current_seq = 10652
count = 1
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 296.064148/10642, current 296.231384/10652: elapsed=167247.0us expected=167250.0us +- 836.2us, error 0.0%
(kms_flip:2981) DEBUG: name = vblank
last_ts = 296.231384
last_received_ts = 296.230652
last_seq = 10652
current_ts = 296.398621
current_received_ts = 296.397949
current_seq = 10662
count = 2
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 296.231384/10652, current 296.398621/10662: elapsed=167249.0us expected=167250.0us +- 836.2us, error 0.0%
(kms_flip:2981) DEBUG: name = vblank
last_ts = 296.398621
last_received_ts = 296.397949
last_seq = 10662
current_ts = 296.565887
current_received_ts = 296.565155
current_seq = 10672
count = 3
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 296.398621/10662, current 296.565887/10672: elapsed=167255.0us expected=167250.0us +- 836.2us, error 0.0%
(kms_flip:2981) DEBUG: name = vblank
last_ts = 296.565887
last_received_ts = 296.565155
last_seq = 10672
current_ts = 296.733124
current_received_ts = 296.732422
current_seq = 10682
count = 4
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 296.565887/10672, current 296.733124/10682: elapsed=167253.0us expected=167250.0us +- 836.2us, error 0.0%
(kms_flip:2981) DEBUG: name = vblank
last_ts = 296.733124
last_received_ts = 296.732422
last_seq = 10682
current_ts = 296.900391
current_received_ts = 296.899658
current_seq = 10692
count = 5
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 296.733124/10682, current 296.900391/10692: elapsed=167249.0us expected=167250.0us +- 836.2us, error 0.0%
(kms_flip:2981) DEBUG: name = vblank
last_ts = 296.900391
last_received_ts = 296.899658
last_seq = 10692
current_ts = 297.067627
current_received_ts = 297.066864
current_seq = 10702
count = 6
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 296.900391/10692, current 297.067627/10702: elapsed=167252.0us expected=167250.0us +- 836.2us, error 0.0%
(kms_flip:2981) DEBUG: name = vblank
last_ts = 297.067627
last_received_ts = 297.066864
last_seq = 10702
current_ts = 297.234894
current_received_ts = 297.234161
current_seq = 10712
count = 7
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 297.067627/10702, current 297.234894/10712: elapsed=167250.0us expected=167250.0us +- 836.2us, error 0.0%
(kms_flip:2981) DEBUG: name = vblank
last_ts = 297.234894
last_received_ts = 297.234161
last_seq = 10712
current_ts = 297.402130
current_received_ts = 297.401428
current_seq = 10722
count = 8
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 297.234894/10712, current 297.402130/10722: elapsed=167253.0us expected=167250.0us +- 836.2us, error 0.0%
(kms_flip:2981) DEBUG: name = vblank
last_ts = 297.402130
last_received_ts = 297.401428
last_seq = 10722
current_ts = 297.569397
current_received_ts = 297.568665
current_seq = 10732
count = 9
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 297.402130/10722, current 297.569397/10732: elapsed=167253.0us expected=167250.0us +- 836.2us, error 0.0%
(kms_flip:2981) DEBUG: name = vblank
last_ts = 297.569397
last_received_ts = 297.568665
last_seq = 10732
current_ts = 297.736633
current_received_ts = 297.735901
current_seq = 10742
count = 10
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 297.569397/10732, current 297.736633/10742: elapsed=167251.0us expected=167250.0us +- 836.2us, error 0.0%
(kms_flip:2981) DEBUG: name = vblank
last_ts = 297.736633
last_received_ts = 297.735901
last_seq = 10742
current_ts = 297.903900
current_received_ts = 297.903168
current_seq = 10752
count = 11
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 297.736633/10742, current 297.903900/10752: elapsed=167259.0us expected=167250.0us +- 836.2us, error 0.0%
(kms_flip:2981) DEBUG: name = vblank
last_ts = 297.903900
last_received_ts = 297.903168
last_seq = 10752
current_ts = 298.071167
current_received_ts = 298.070404
current_seq = 10762
count = 12
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 297.903900/10752, current 298.071167/10762: elapsed=167253.0us expected=167250.0us +- 836.2us, error 0.0%
(kms_flip:2981) DEBUG: name = vblank
last_ts = 298.071167
last_received_ts = 298.070404
last_seq = 10762
current_ts = 298.238403
current_received_ts = 298.237671
current_seq = 10772
count = 13
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 298.071167/10762, current 298.238403/10772: elapsed=167253.0us expected=167250.0us +- 836.2us, error 0.0%
(kms_flip:2981) DEBUG: name = vblank
last_ts = 298.238403
last_received_ts = 298.237671
last_seq = 10772
current_ts = 298.405670
current_received_ts = 298.404968
current_seq = 10782
count = 14
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 298.238403/10772, current 298.405670/10782: elapsed=167257.0us expected=167250.0us +- 836.2us, error 0.0%
(kms_flip:2981) DEBUG: name = vblank
last_ts = 298.405670
last_received_ts = 298.404968
last_seq = 10782
current_ts = 298.573059
current_received_ts = 298.572357
current_seq = 10792
count = 15
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 298.405670/10782, current 298.573059/10792: elapsed=167402.0us expected=167250.0us +- 836.2us, error 0.1%
(kms_flip:2981) DEBUG: name = vblank
last_ts = 298.573059
last_received_ts = 298.572357
last_seq = 10792
current_ts = 298.746460
current_received_ts = 298.745728
current_seq = 10802
count = 16
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 298.573059/10792, current 298.746460/10802: elapsed=173383.0us expected=167250.0us +- 836.2us, error 3.7%
(kms_flip:2981) DEBUG: inconsistent vblank ts/seq: last 298.573059/10792, current 298.746460/10802: elapsed=173383.0us expected=167250.0us
(kms_flip:2981) DEBUG: name = vblank
last_ts = 298.746460
last_received_ts = 298.745728
last_seq = 10802
current_ts = 298.919861
current_received_ts = 298.919098
current_seq = 10812
count = 17
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 298.746460/10802, current 298.919861/10812: elapsed=173396.0us expected=167250.0us +- 836.2us, error 3.7%
(kms_flip:2981) DEBUG: inconsistent vblank ts/seq: last 298.746460/10802, current 298.919861/10812: elapsed=173396.0us expected=167250.0us
(kms_flip:2981) DEBUG: name = vblank
last_ts = 298.919861
last_received_ts = 298.919098
last_seq = 10812
current_ts = 299.093231
current_received_ts = 299.092529
current_seq = 10822
count = 18
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 298.919861/10812, current 299.093231/10822: elapsed=173388.0us expected=167250.0us +- 836.2us, error 3.7%
(kms_flip:2981) DEBUG: inconsistent vblank ts/seq: last 298.919861/10812, current 299.093231/10822: elapsed=173388.0us expected=167250.0us
(kms_flip:2981) DEBUG: name = vblank
last_ts = 299.093231
last_received_ts = 299.092529
last_seq = 10822
current_ts = 299.266632
current_received_ts = 299.265930
current_seq = 10832
count = 19
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 299.093231/10822, current 299.266632/10832: elapsed=173389.0us expected=167250.0us +- 836.2us, error 3.7%
(kms_flip:2981) DEBUG: inconsistent vblank ts/seq: last 299.093231/10822, current 299.266632/10832: elapsed=173389.0us expected=167250.0us
(kms_flip:2981) DEBUG: name = vblank
last_ts = 299.266632
last_received_ts = 299.265930
last_seq = 10832
current_ts = 299.440002
current_received_ts = 299.439331
current_seq = 10842
count = 20
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 299.266632/10832, current 299.440002/10842: elapsed=173390.0us expected=167250.0us +- 836.2us, error 3.7%
(kms_flip:2981) DEBUG: inconsistent vblank ts/seq: last 299.266632/10832, current 299.440002/10842: elapsed=173390.0us expected=167250.0us
(kms_flip:2981) DEBUG: name = vblank
last_ts = 299.440002
last_received_ts = 299.439331
last_seq = 10842
current_ts = 299.613190
current_received_ts = 299.612518
current_seq = 10852
count = 21
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 299.440002/10842, current 299.613190/10852: elapsed=173186.0us expected=167250.0us +- 836.2us, error 3.5%
(kms_flip:2981) DEBUG: inconsistent vblank ts/seq: last 299.440002/10842, current 299.613190/10852: elapsed=173186.0us expected=167250.0us
(kms_flip:2981) DEBUG: name = vblank
last_ts = 299.613190
last_received_ts = 299.612518
last_seq = 10852
current_ts = 299.785828
current_received_ts = 299.785095
current_seq = 10862
count = 22
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 299.613190/10852, current 299.785828/10862: elapsed=172629.0us expected=167250.0us +- 836.2us, error 3.2%
(kms_flip:2981) DEBUG: inconsistent vblank ts/seq: last 299.613190/10852, current 299.785828/10862: elapsed=172629.0us expected=167250.0us
(kms_flip:2981) DEBUG: name = vblank
last_ts = 299.785828
last_received_ts = 299.785095
last_seq = 10862
current_ts = 299.958466
current_received_ts = 299.957733
current_seq = 10872
count = 23
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 299.785828/10862, current 299.958466/10872: elapsed=172622.0us expected=167250.0us +- 836.2us, error 3.2%
(kms_flip:2981) DEBUG: inconsistent vblank ts/seq: last 299.785828/10862, current 299.958466/10872: elapsed=172622.0us expected=167250.0us
(kms_flip:2981) DEBUG: name = vblank
last_ts = 299.958466
last_received_ts = 299.957733
last_seq = 10872
current_ts = 300.131073
current_received_ts = 300.130402
current_seq = 10882
count = 24
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 299.958466/10872, current 300.131073/10882: elapsed=172628.0us expected=167250.0us +- 836.2us, error 3.2%
(kms_flip:2981) DEBUG: inconsistent vblank ts/seq: last 299.958466/10872, current 300.131073/10882: elapsed=172628.0us expected=167250.0us
(kms_flip:2981) DEBUG: name = vblank
last_ts = 300.131073
last_received_ts = 300.130402
last_seq = 10882
current_ts = 300.303711
current_received_ts = 300.303009
current_seq = 10892
count = 25
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 300.131073/10882, current 300.303711/10892: elapsed=172622.0us expected=167250.0us +- 836.2us, error 3.2%
(kms_flip:2981) DEBUG: inconsistent vblank ts/seq: last 300.131073/10882, current 300.303711/10892: elapsed=172622.0us expected=167250.0us
(kms_flip:2981) DEBUG: name = vblank
last_ts = 300.303711
last_received_ts = 300.303009
last_seq = 10892
current_ts = 300.476318
current_received_ts = 300.475616
current_seq = 10902
count = 26
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 300.303711/10892, current 300.476318/10902: elapsed=172624.0us expected=167250.0us +- 836.2us, error 3.2%
(kms_flip:2981) DEBUG: inconsistent vblank ts/seq: last 300.303711/10892, current 300.476318/10902: elapsed=172624.0us expected=167250.0us
(kms_flip:2981) DEBUG: name = vblank
last_ts = 300.476318
last_received_ts = 300.475616
last_seq = 10902
current_ts = 300.648621
current_received_ts = 300.647888
current_seq = 10912
count = 27
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 300.476318/10902, current 300.648621/10912: elapsed=172301.0us expected=167250.0us +- 836.2us, error 3.0%
(kms_flip:2981) DEBUG: inconsistent vblank ts/seq: last 300.476318/10902, current 300.648621/10912: elapsed=172301.0us expected=167250.0us
(kms_flip:2981) DEBUG: name = vblank
last_ts = 300.648621
last_received_ts = 300.647888
last_seq = 10912
current_ts = 300.820587
current_received_ts = 300.819885
current_seq = 10922
count = 28
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 300.648621/10912, current 300.820587/10922: elapsed=171954.0us expected=167250.0us +- 836.2us, error 2.8%
(kms_flip:2981) DEBUG: inconsistent vblank ts/seq: last 300.648621/10912, current 300.820587/10922: elapsed=171954.0us expected=167250.0us
(kms_flip:2981) DEBUG: name = vblank
last_ts = 300.820587
last_received_ts = 300.819885
last_seq = 10922
current_ts = 300.992523
current_received_ts = 300.991852
current_seq = 10932
count = 29
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 300.820587/10922, current 300.992523/10932: elapsed=171951.0us expected=167250.0us +- 836.2us, error 2.8%
(kms_flip:2981) DEBUG: inconsistent vblank ts/seq: last 300.820587/10922, current 300.992523/10932: elapsed=171951.0us expected=167250.0us
(kms_flip:2981) DEBUG: name = vblank
last_ts = 300.992523
last_received_ts = 300.991852
last_seq = 10932
current_ts = 301.164490
current_received_ts = 301.163757
current_seq = 10942
count = 30
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 300.992523/10932, current 301.164490/10942: elapsed=171953.0us expected=167250.0us +- 836.2us, error 2.8%
(kms_flip:2981) DEBUG: inconsistent vblank ts/seq: last 300.992523/10932, current 301.164490/10942: elapsed=171953.0us expected=167250.0us
(kms_flip:2981) DEBUG: name = vblank
last_ts = 301.164490
last_received_ts = 301.163757
last_seq = 10942
current_ts = 301.336426
current_received_ts = 301.335724
current_seq = 10952
count = 31
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 301.164490/10942, current 301.336426/10952: elapsed=171949.0us expected=167250.0us +- 836.2us, error 2.8%
(kms_flip:2981) DEBUG: inconsistent vblank ts/seq: last 301.164490/10942, current 301.336426/10952: elapsed=171949.0us expected=167250.0us
(kms_flip:2981) DEBUG: name = vblank
last_ts = 301.336426
last_received_ts = 301.335724
last_seq = 10952
current_ts = 301.508392
current_received_ts = 301.507660
current_seq = 10962
count = 32
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 301.336426/10952, current 301.508392/10962: elapsed=171953.0us expected=167250.0us +- 836.2us, error 2.8%
(kms_flip:2981) DEBUG: inconsistent vblank ts/seq: last 301.336426/10952, current 301.508392/10962: elapsed=171953.0us expected=167250.0us
(kms_flip:2981) DEBUG: name = vblank
last_ts = 301.508392
last_received_ts = 301.507660
last_seq = 10962
current_ts = 301.679962
current_received_ts = 301.679260
current_seq = 10972
count = 33
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 301.508392/10962, current 301.679962/10972: elapsed=171565.0us expected=167250.0us +- 836.2us, error 2.6%
(kms_flip:2981) DEBUG: inconsistent vblank ts/seq: last 301.508392/10962, current 301.679962/10972: elapsed=171565.0us expected=167250.0us
(kms_flip:2981) DEBUG: name = vblank
last_ts = 301.679962
last_received_ts = 301.679260
last_seq = 10972
current_ts = 301.851318
current_received_ts = 301.850586
current_seq = 10982
count = 34
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 301.679962/10972, current 301.851318/10982: elapsed=171368.0us expected=167250.0us +- 836.2us, error 2.5%
(kms_flip:2981) DEBUG: inconsistent vblank ts/seq: last 301.679962/10972, current 301.851318/10982: elapsed=171368.0us expected=167250.0us
(kms_flip:2981) DEBUG: name = vblank
last_ts = 301.851318
last_received_ts = 301.850586
last_seq = 10982
current_ts = 302.022675
current_received_ts = 302.021973
current_seq = 10992
count = 35
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 301.851318/10982, current 302.022675/10992: elapsed=171360.0us expected=167250.0us +- 836.2us, error 2.5%
(kms_flip:2981) DEBUG: inconsistent vblank ts/seq: last 301.851318/10982, current 302.022675/10992: elapsed=171360.0us expected=167250.0us
(kms_flip:2981) DEBUG: name = vblank
last_ts = 302.022675
last_received_ts = 302.021973
last_seq = 10992
current_ts = 302.194031
current_received_ts = 302.193329
current_seq = 11002
count = 36
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 302.022675/10992, current 302.194031/11002: elapsed=171361.0us expected=167250.0us +- 836.2us, error 2.5%
(kms_flip:2981) DEBUG: inconsistent vblank ts/seq: last 302.022675/10992, current 302.194031/11002: elapsed=171361.0us expected=167250.0us
(kms_flip:2981) DEBUG: name = vblank
last_ts = 302.194031
last_received_ts = 302.193329
last_seq = 11002
current_ts = 302.365417
current_received_ts = 302.364716
current_seq = 11012
count = 37
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 302.194031/11002, current 302.365417/11012: elapsed=171368.0us expected=167250.0us +- 836.2us, error 2.5%
(kms_flip:2981) DEBUG: inconsistent vblank ts/seq: last 302.194031/11002, current 302.365417/11012: elapsed=171368.0us expected=167250.0us
(kms_flip:2981) DEBUG: name = vblank
last_ts = 302.365417
last_received_ts = 302.364716
last_seq = 11012
current_ts = 302.536774
current_received_ts = 302.536041
current_seq = 11022
count = 38
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 302.365417/11012, current 302.536774/11022: elapsed=171356.0us expected=167250.0us +- 836.2us, error 2.5%
(kms_flip:2981) DEBUG: inconsistent vblank ts/seq: last 302.365417/11012, current 302.536774/11022: elapsed=171356.0us expected=167250.0us
(kms_flip:2981) DEBUG: name = vblank
last_ts = 302.536774
last_received_ts = 302.536041
last_seq = 11022
current_ts = 302.707703
current_received_ts = 302.706970
current_seq = 11032
count = 39
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 302.536774/11022, current 302.707703/11032: elapsed=170949.0us expected=167250.0us +- 836.2us, error 2.2%
(kms_flip:2981) DEBUG: inconsistent vblank ts/seq: last 302.536774/11022, current 302.707703/11032: elapsed=170949.0us expected=167250.0us
(kms_flip:2981) DEBUG: name = vblank
last_ts = 302.707703
last_received_ts = 302.706970
last_seq = 11032
current_ts = 302.878571
current_received_ts = 302.877838
current_seq = 11042
count = 40
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 302.707703/11032, current 302.878571/11042: elapsed=170856.0us expected=167250.0us +- 836.2us, error 2.2%
(kms_flip:2981) DEBUG: inconsistent vblank ts/seq: last 302.707703/11032, current 302.878571/11042: elapsed=170856.0us expected=167250.0us
(kms_flip:2981) DEBUG: name = vblank
last_ts = 302.878571
last_received_ts = 302.877838
last_seq = 11042
current_ts = 303.049408
current_received_ts = 303.048676
current_seq = 11052
count = 41
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 302.878571/11042, current 303.049408/11052: elapsed=170841.0us expected=167250.0us +- 836.2us, error 2.1%
(kms_flip:2981) DEBUG: inconsistent vblank ts/seq: last 302.878571/11042, current 303.049408/11052: elapsed=170841.0us expected=167250.0us
(kms_flip:2981) DEBUG: name = vblank
last_ts = 303.049408
last_received_ts = 303.048676
last_seq = 11052
current_ts = 303.220276
current_received_ts = 303.219543
current_seq = 11062
count = 42
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 303.049408/11052, current 303.220276/11062: elapsed=170857.0us expected=167250.0us +- 836.2us, error 2.2%
(kms_flip:2981) DEBUG: inconsistent vblank ts/seq: last 303.049408/11052, current 303.220276/11062: elapsed=170857.0us expected=167250.0us
(kms_flip:2981) DEBUG: name = vblank
last_ts = 303.220276
last_received_ts = 303.219543
last_seq = 11062
current_ts = 303.391113
current_received_ts = 303.390411
current_seq = 11072
count = 43
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 303.220276/11062, current 303.391113/11072: elapsed=170850.0us expected=167250.0us +- 836.2us, error 2.2%
(kms_flip:2981) DEBUG: inconsistent vblank ts/seq: last 303.220276/11062, current 303.391113/11072: elapsed=170850.0us expected=167250.0us
(kms_flip:2981) DEBUG: name = vblank
last_ts = 303.391113
last_received_ts = 303.390411
last_seq = 11072
current_ts = 303.561981
current_received_ts = 303.561279
current_seq = 11082
count = 44
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 303.391113/11072, current 303.561981/11082: elapsed=170849.0us expected=167250.0us +- 836.2us, error 2.2%
(kms_flip:2981) DEBUG: inconsistent vblank ts/seq: last 303.391113/11072, current 303.561981/11082: elapsed=170849.0us expected=167250.0us
(kms_flip:2981) INFO: Event vblank: expected 458, counted 450, passrate = 35.56%, encoder type 1
(kms_flip:2981) DEBUG: dropped frames, expected 458, counted 450, passrate = 35.56%, encoder type 1
(kms_flip:2981) DEBUG: Retrying without a hotplug event
(kms_flip:2981) DEBUG: No stale events found
(kms_flip:2981) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0
(kms_flip:2981) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0
(kms_flip:2981) INFO: Expected frametime: 16666us; measured 16936.2us +- 8.704us accuracy 0.15%
(kms_flip:2981) INFO: vblank interval differs from modeline! expected 16665.6us, measured 16936us +- 8.704us, difference 270.6us (31.1 sigma)
(kms_flip:2981) DEBUG: name = vblank
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 307.159607
current_received_ts = 307.158966
current_seq = 11115
count = 0
seq_step = 10
(kms_flip:2981) DEBUG: name = vblank
last_ts = 307.159607
last_received_ts = 307.158966
last_seq = 11115
current_ts = 307.328979
current_received_ts = 307.328278
current_seq = 11125
count = 1
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 307.159607/11115, current 307.328979/11125: elapsed=169372.0us expected=169361.9us +- 846.8us, error 0.0%
(kms_flip:2981) DEBUG: name = vblank
last_ts = 307.328979
last_received_ts = 307.328278
last_seq = 11125
current_ts = 307.498322
current_received_ts = 307.497650
current_seq = 11135
count = 2
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 307.328979/11125, current 307.498322/11135: elapsed=169362.0us expected=169361.9us +- 846.8us, error 0.0%
(kms_flip:2981) DEBUG: name = vblank
last_ts = 307.498322
last_received_ts = 307.497650
last_seq = 11135
current_ts = 307.667542
current_received_ts = 307.666840
current_seq = 11145
count = 3
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 307.498322/11135, current 307.667542/11145: elapsed=169207.0us expected=169361.9us +- 846.8us, error 0.1%
(kms_flip:2981) DEBUG: name = vblank
last_ts = 307.667542
last_received_ts = 307.666840
last_seq = 11145
current_ts = 307.836639
current_received_ts = 307.835968
current_seq = 11155
count = 4
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 307.667542/11145, current 307.836639/11155: elapsed=169098.0us expected=169361.9us +- 846.8us, error 0.2%
(kms_flip:2981) DEBUG: name = vblank
last_ts = 307.836639
last_received_ts = 307.835968
last_seq = 11155
current_ts = 308.005737
current_received_ts = 308.005066
current_seq = 11165
count = 5
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 307.836639/11155, current 308.005737/11165: elapsed=169097.0us expected=169361.9us +- 846.8us, error 0.2%
(kms_flip:2981) DEBUG: name = vblank
last_ts = 308.005737
last_received_ts = 308.005066
last_seq = 11165
current_ts = 308.174805
current_received_ts = 308.174133
current_seq = 11175
count = 6
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 308.005737/11165, current 308.174805/11175: elapsed=169086.0us expected=169361.9us +- 846.8us, error 0.2%
(kms_flip:2981) DEBUG: name = vblank
last_ts = 308.174805
last_received_ts = 308.174133
last_seq = 11175
current_ts = 308.343933
current_received_ts = 308.343201
current_seq = 11185
count = 7
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 308.174805/11175, current 308.343933/11185: elapsed=169112.0us expected=169361.9us +- 846.8us, error 0.1%
(kms_flip:2981) DEBUG: name = vblank
last_ts = 308.343933
last_received_ts = 308.343201
last_seq = 11185
current_ts = 308.513031
current_received_ts = 308.512299
current_seq = 11195
count = 8
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 308.343933/11185, current 308.513031/11195: elapsed=169092.0us expected=169361.9us +- 846.8us, error 0.2%
(kms_flip:2981) DEBUG: name = vblank
last_ts = 308.513031
last_received_ts = 308.512299
last_seq = 11195
current_ts = 308.681976
current_received_ts = 308.681244
current_seq = 11205
count = 9
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 308.513031/11195, current 308.681976/11205: elapsed=168951.0us expected=169361.9us +- 846.8us, error 0.2%
(kms_flip:2981) DEBUG: name = vblank
last_ts = 308.681976
last_received_ts = 308.681244
last_seq = 11205
current_ts = 308.850830
current_received_ts = 308.850098
current_seq = 11215
count = 10
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 308.681976/11205, current 308.850830/11215: elapsed=168855.0us expected=169361.9us +- 846.8us, error 0.3%
(kms_flip:2981) DEBUG: name = vblank
last_ts = 308.850830
last_received_ts = 308.850098
last_seq = 11215
current_ts = 309.019684
current_received_ts = 309.018982
current_seq = 11225
count = 11
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 308.850830/11215, current 309.019684/11225: elapsed=168872.0us expected=169361.9us +- 846.8us, error 0.3%
(kms_flip:2981) DEBUG: name = vblank
last_ts = 309.019684
last_received_ts = 309.018982
last_seq = 11225
current_ts = 309.188568
current_received_ts = 309.187866
current_seq = 11235
count = 12
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 309.019684/11225, current 309.188568/11235: elapsed=168869.0us expected=169361.9us +- 846.8us, error 0.3%
(kms_flip:2981) DEBUG: name = vblank
last_ts = 309.188568
last_received_ts = 309.187866
last_seq = 11235
current_ts = 309.357422
current_received_ts = 309.356720
current_seq = 11245
count = 13
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 309.188568/11235, current 309.357422/11245: elapsed=168869.0us expected=169361.9us +- 846.8us, error 0.3%
(kms_flip:2981) DEBUG: name = vblank
last_ts = 309.357422
last_received_ts = 309.356720
last_seq = 11245
current_ts = 309.526306
current_received_ts = 309.525574
current_seq = 11255
count = 14
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 309.357422/11245, current 309.526306/11255: elapsed=168868.0us expected=169361.9us +- 846.8us, error 0.3%
(kms_flip:2981) DEBUG: name = vblank
last_ts = 309.526306
last_received_ts = 309.525574
last_seq = 11255
current_ts = 309.695007
current_received_ts = 309.694305
current_seq = 11265
count = 15
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 309.526306/11255, current 309.695007/11265: elapsed=168710.0us expected=169361.9us +- 846.8us, error 0.4%
(kms_flip:2981) DEBUG: name = vblank
last_ts = 309.695007
last_received_ts = 309.694305
last_seq = 11265
current_ts = 309.863678
current_received_ts = 309.862946
current_seq = 11275
count = 16
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 309.695007/11265, current 309.863678/11275: elapsed=168669.0us expected=169361.9us +- 846.8us, error 0.4%
(kms_flip:2981) DEBUG: name = vblank
last_ts = 309.863678
last_received_ts = 309.862946
last_seq = 11275
current_ts = 310.032349
current_received_ts = 310.031616
current_seq = 11285
count = 17
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 309.863678/11275, current 310.032349/11285: elapsed=168665.0us expected=169361.9us +- 846.8us, error 0.4%
(kms_flip:2981) DEBUG: name = vblank
last_ts = 310.032349
last_received_ts = 310.031616
last_seq = 11285
current_ts = 310.201019
current_received_ts = 310.200256
current_seq = 11295
count = 18
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 310.032349/11285, current 310.201019/11295: elapsed=168658.0us expected=169361.9us +- 846.8us, error 0.4%
(kms_flip:2981) DEBUG: name = vblank
last_ts = 310.201019
last_received_ts = 310.200256
last_seq = 11295
current_ts = 310.369659
current_received_ts = 310.368896
current_seq = 11305
count = 19
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 310.201019/11295, current 310.369659/11305: elapsed=168664.0us expected=169361.9us +- 846.8us, error 0.4%
(kms_flip:2981) DEBUG: name = vblank
last_ts = 310.369659
last_received_ts = 310.368896
last_seq = 11305
current_ts = 310.538330
current_received_ts = 310.537659
current_seq = 11315
count = 20
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 310.369659/11305, current 310.538330/11315: elapsed=168665.0us expected=169361.9us +- 846.8us, error 0.4%
(kms_flip:2981) DEBUG: name = vblank
last_ts = 310.538330
last_received_ts = 310.537659
last_seq = 11315
current_ts = 310.706848
current_received_ts = 310.706116
current_seq = 11325
count = 21
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 310.538330/11315, current 310.706848/11325: elapsed=168526.0us expected=169361.9us +- 846.8us, error 0.5%
(kms_flip:2981) DEBUG: name = vblank
last_ts = 310.706848
last_received_ts = 310.706116
last_seq = 11325
current_ts = 310.875366
current_received_ts = 310.874664
current_seq = 11335
count = 22
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 310.706848/11325, current 310.875366/11335: elapsed=168495.0us expected=169361.9us +- 846.8us, error 0.5%
(kms_flip:2981) DEBUG: inconsistent vblank ts/seq: last 310.706848/11325, current 310.875366/11335: elapsed=168495.0us expected=169361.9us
(kms_flip:2981) DEBUG: name = vblank
last_ts = 310.875366
last_received_ts = 310.874664
last_seq = 11335
current_ts = 311.043854
current_received_ts = 311.043121
current_seq = 11345
count = 23
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 310.875366/11335, current 311.043854/11345: elapsed=168485.0us expected=169361.9us +- 846.8us, error 0.5%
(kms_flip:2981) DEBUG: inconsistent vblank ts/seq: last 310.875366/11335, current 311.043854/11345: elapsed=168485.0us expected=169361.9us
(kms_flip:2981) DEBUG: name = vblank
last_ts = 311.043854
last_received_ts = 311.043121
last_seq = 11345
current_ts = 311.212341
current_received_ts = 311.211609
current_seq = 11355
count = 24
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 311.043854/11345, current 311.212341/11355: elapsed=168490.0us expected=169361.9us +- 846.8us, error 0.5%
(kms_flip:2981) DEBUG: inconsistent vblank ts/seq: last 311.043854/11345, current 311.212341/11355: elapsed=168490.0us expected=169361.9us
(kms_flip:2981) DEBUG: name = vblank
last_ts = 311.212341
last_received_ts = 311.211609
last_seq = 11355
current_ts = 311.380829
current_received_ts = 311.380066
current_seq = 11365
count = 25
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 311.212341/11355, current 311.380829/11365: elapsed=168494.0us expected=169361.9us +- 846.8us, error 0.5%
(kms_flip:2981) DEBUG: inconsistent vblank ts/seq: last 311.212341/11355, current 311.380829/11365: elapsed=168494.0us expected=169361.9us
(kms_flip:2981) DEBUG: name = vblank
last_ts = 311.380829
last_received_ts = 311.380066
last_seq = 11365
current_ts = 311.549286
current_received_ts = 311.548553
current_seq = 11375
count = 26
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 311.380829/11365, current 311.549286/11375: elapsed=168473.0us expected=169361.9us +- 846.8us, error 0.5%
(kms_flip:2981) DEBUG: inconsistent vblank ts/seq: last 311.380829/11365, current 311.549286/11375: elapsed=168473.0us expected=169361.9us
(kms_flip:2981) DEBUG: name = vblank
last_ts = 311.549286
last_received_ts = 311.548553
last_seq = 11375
current_ts = 311.717651
current_received_ts = 311.716949
current_seq = 11385
count = 27
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 311.549286/11375, current 311.717651/11385: elapsed=168352.0us expected=169361.9us +- 846.8us, error 0.6%
(kms_flip:2981) DEBUG: inconsistent vblank ts/seq: last 311.549286/11375, current 311.717651/11385: elapsed=168352.0us expected=169361.9us
(kms_flip:2981) DEBUG: name = vblank
last_ts = 311.717651
last_received_ts = 311.716949
last_seq = 11385
current_ts = 311.885986
current_received_ts = 311.885254
current_seq = 11395
count = 28
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 311.717651/11385, current 311.885986/11395: elapsed=168338.0us expected=169361.9us +- 846.8us, error 0.6%
(kms_flip:2981) DEBUG: inconsistent vblank ts/seq: last 311.717651/11385, current 311.885986/11395: elapsed=168338.0us expected=169361.9us
(kms_flip:2981) DEBUG: name = vblank
last_ts = 311.885986
last_received_ts = 311.885254
last_seq = 11395
current_ts = 312.054321
current_received_ts = 312.053589
current_seq = 11405
count = 29
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 311.885986/11395, current 312.054321/11405: elapsed=168330.0us expected=169361.9us +- 846.8us, error 0.6%
(kms_flip:2981) DEBUG: inconsistent vblank ts/seq: last 311.885986/11395, current 312.054321/11405: elapsed=168330.0us expected=169361.9us
(kms_flip:2981) DEBUG: name = vblank
last_ts = 312.054321
last_received_ts = 312.053589
last_seq = 11405
current_ts = 312.222656
current_received_ts = 312.221954
current_seq = 11415
count = 30
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 312.054321/11405, current 312.222656/11415: elapsed=168341.0us expected=169361.9us +- 846.8us, error 0.6%
(kms_flip:2981) DEBUG: inconsistent vblank ts/seq: last 312.054321/11405, current 312.222656/11415: elapsed=168341.0us expected=169361.9us
(kms_flip:2981) DEBUG: name = vblank
last_ts = 312.222656
last_received_ts = 312.221954
last_seq = 11415
current_ts = 312.390991
current_received_ts = 312.390289
current_seq = 11425
count = 31
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 312.222656/11415, current 312.390991/11425: elapsed=168334.0us expected=169361.9us +- 846.8us, error 0.6%
(kms_flip:2981) DEBUG: inconsistent vblank ts/seq: last 312.222656/11415, current 312.390991/11425: elapsed=168334.0us expected=169361.9us
(kms_flip:2981) DEBUG: name = vblank
last_ts = 312.390991
last_received_ts = 312.390289
last_seq = 11425
current_ts = 312.559326
current_received_ts = 312.558624
current_seq = 11435
count = 32
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 312.390991/11425, current 312.559326/11435: elapsed=168334.0us expected=169361.9us +- 846.8us, error 0.6%
(kms_flip:2981) DEBUG: inconsistent vblank ts/seq: last 312.390991/11425, current 312.559326/11435: elapsed=168334.0us expected=169361.9us
(kms_flip:2981) DEBUG: name = vblank
last_ts = 312.559326
last_received_ts = 312.558624
last_seq = 11435
current_ts = 312.727539
current_received_ts = 312.726837
current_seq = 11445
count = 33
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 312.559326/11435, current 312.727539/11445: elapsed=168209.0us expected=169361.9us +- 846.8us, error 0.7%
(kms_flip:2981) DEBUG: inconsistent vblank ts/seq: last 312.559326/11435, current 312.727539/11445: elapsed=168209.0us expected=169361.9us
(kms_flip:2981) DEBUG: name = vblank
last_ts = 312.727539
last_received_ts = 312.726837
last_seq = 11445
current_ts = 312.895752
current_received_ts = 312.895050
current_seq = 11455
count = 34
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 312.727539/11445, current 312.895752/11455: elapsed=168202.0us expected=169361.9us +- 846.8us, error 0.7%
(kms_flip:2981) DEBUG: inconsistent vblank ts/seq: last 312.727539/11445, current 312.895752/11455: elapsed=168202.0us expected=169361.9us
(kms_flip:2981) DEBUG: name = vblank
last_ts = 312.895752
last_received_ts = 312.895050
last_seq = 11455
current_ts = 313.063934
current_received_ts = 313.063202
current_seq = 11465
count = 35
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 312.895752/11455, current 313.063934/11465: elapsed=168190.0us expected=169361.9us +- 846.8us, error 0.7%
(kms_flip:2981) DEBUG: inconsistent vblank ts/seq: last 312.895752/11455, current 313.063934/11465: elapsed=168190.0us expected=169361.9us
(kms_flip:2981) DEBUG: name = vblank
last_ts = 313.063934
last_received_ts = 313.063202
last_seq = 11465
current_ts = 313.232117
current_received_ts = 313.231415
current_seq = 11475
count = 36
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 313.063934/11465, current 313.232117/11475: elapsed=168203.0us expected=169361.9us +- 846.8us, error 0.7%
(kms_flip:2981) DEBUG: inconsistent vblank ts/seq: last 313.063934/11465, current 313.232117/11475: elapsed=168203.0us expected=169361.9us
(kms_flip:2981) DEBUG: name = vblank
last_ts = 313.232117
last_received_ts = 313.231415
last_seq = 11475
current_ts = 313.400330
current_received_ts = 313.399628
current_seq = 11485
count = 37
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 313.232117/11475, current 313.400330/11485: elapsed=168202.0us expected=169361.9us +- 846.8us, error 0.7%
(kms_flip:2981) DEBUG: inconsistent vblank ts/seq: last 313.232117/11475, current 313.400330/11485: elapsed=168202.0us expected=169361.9us
(kms_flip:2981) DEBUG: name = vblank
last_ts = 313.400330
last_received_ts = 313.399628
last_seq = 11485
current_ts = 313.568542
current_received_ts = 313.567841
current_seq = 11495
count = 38
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 313.400330/11485, current 313.568542/11495: elapsed=168206.0us expected=169361.9us +- 846.8us, error 0.7%
(kms_flip:2981) DEBUG: inconsistent vblank ts/seq: last 313.400330/11485, current 313.568542/11495: elapsed=168206.0us expected=169361.9us
(kms_flip:2981) DEBUG: name = vblank
last_ts = 313.568542
last_received_ts = 313.567841
last_seq = 11495
current_ts = 313.736603
current_received_ts = 313.735962
current_seq = 11505
count = 39
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 313.568542/11495, current 313.736603/11505: elapsed=168065.0us expected=169361.9us +- 846.8us, error 0.8%
(kms_flip:2981) DEBUG: inconsistent vblank ts/seq: last 313.568542/11495, current 313.736603/11505: elapsed=168065.0us expected=169361.9us
(kms_flip:2981) DEBUG: name = vblank
last_ts = 313.736603
last_received_ts = 313.735962
last_seq = 11505
current_ts = 313.904694
current_received_ts = 313.903992
current_seq = 11515
count = 40
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 313.736603/11505, current 313.904694/11515: elapsed=168092.0us expected=169361.9us +- 846.8us, error 0.7%
(kms_flip:2981) DEBUG: inconsistent vblank ts/seq: last 313.736603/11505, current 313.904694/11515: elapsed=168092.0us expected=169361.9us
(kms_flip:2981) DEBUG: name = vblank
last_ts = 313.904694
last_received_ts = 313.903992
last_seq = 11515
current_ts = 314.072784
current_received_ts = 314.072052
current_seq = 11525
count = 41
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 313.904694/11515, current 314.072784/11525: elapsed=168082.0us expected=169361.9us +- 846.8us, error 0.8%
(kms_flip:2981) DEBUG: inconsistent vblank ts/seq: last 313.904694/11515, current 314.072784/11525: elapsed=168082.0us expected=169361.9us
(kms_flip:2981) DEBUG: name = vblank
last_ts = 314.072784
last_received_ts = 314.072052
last_seq = 11525
current_ts = 314.240845
current_received_ts = 314.240143
current_seq = 11535
count = 42
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 314.072784/11525, current 314.240845/11535: elapsed=168076.0us expected=169361.9us +- 846.8us, error 0.8%
(kms_flip:2981) DEBUG: inconsistent vblank ts/seq: last 314.072784/11525, current 314.240845/11535: elapsed=168076.0us expected=169361.9us
(kms_flip:2981) DEBUG: name = vblank
last_ts = 314.240845
last_received_ts = 314.240143
last_seq = 11535
current_ts = 314.408936
current_received_ts = 314.408203
current_seq = 11545
count = 43
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 314.240845/11535, current 314.408936/11545: elapsed=168085.0us expected=169361.9us +- 846.8us, error 0.8%
(kms_flip:2981) DEBUG: inconsistent vblank ts/seq: last 314.240845/11535, current 314.408936/11545: elapsed=168085.0us expected=169361.9us
(kms_flip:2981) DEBUG: name = vblank
last_ts = 314.408936
last_received_ts = 314.408203
last_seq = 11545
current_ts = 314.576996
current_received_ts = 314.576324
current_seq = 11555
count = 44
seq_step = 10
(kms_flip:2981) DEBUG: vblank ts/seq: last 314.408936/11545, current 314.576996/11555: elapsed=168072.0us expected=169361.9us +- 846.8us, error 0.8%
(kms_flip:2981) DEBUG: inconsistent vblank ts/seq: last 314.408936/11545, current 314.576996/11555: elapsed=168072.0us expected=169361.9us
(kms_flip:2981) INFO: Event vblank: expected 447, counted 450, passrate = 48.89%, encoder type 1
(kms_flip:2981) DEBUG: dropped frames, expected 447, counted 450, passrate = 48.89%, encoder type 1
(kms_flip:2981) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1711:
(kms_flip:2981) CRITICAL: Failed assertion: !retried
(kms_flip:2981) CRITICAL: Last errno: 4, Interrupted system call
(kms_flip:2981) igt_core-INFO: Stack trace:
(kms_flip:2981) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:2981) igt_core-INFO:   #1 ../tests/kms_flip.c:428 run_test_on_crtc_set.constprop.0()
(kms_flip:2981) igt_core-INFO:   #2 ../tests/kms_flip.c:1891 run_test()
(kms_flip:2981) igt_core-INFO:   #3 ../tests/kms_flip.c:2162 __igt_unique____real_main2047()
(kms_flip:2981) igt_core-INFO:   #4 ../tests/kms_flip.c:2047 main()
(kms_flip:2981) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:2981) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:2981) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest A-VGA1: FAIL (18.995s)
Dmesg
<6> [294.543338] Console: switching to colour dummy device 80x25
<6> [294.543974] [IGT] kms_flip: executing
<7> [294.549026] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [294.549812] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [294.550372] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [294.554100] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:84:DP-1]
<7> [294.554112] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:84:DP-1]
<7> [294.556944] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.559941] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.562999] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.566044] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.568964] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.571976] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.575028] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.577915] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.581054] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.584055] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.586987] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.590020] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.592998] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.595950] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.598939] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.601978] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.605043] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.608016] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.611022] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.613997] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.616971] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.619906] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.622893] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.625805] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.628858] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.631785] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.634821] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.637796] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.640882] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.643764] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.646713] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.649643] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.650068] 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> [294.652774] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.655680] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.658616] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.661527] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.664527] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.667435] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.670360] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.673378] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.676381] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.679425] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.682377] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.685277] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.688284] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.691208] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.694161] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.697227] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.700227] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.703218] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.706168] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.709191] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.712166] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.715135] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.718123] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.721118] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.724174] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.727174] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.730122] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.733131] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.736127] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.739126] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.742112] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.745222] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.745624] 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> [294.745672] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:84:DP-1] disconnected
<7> [294.746152] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:73:VGA-1]
<7> [294.746166] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] [CONNECTOR:73:VGA-1] force=1
<7> [294.746634] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] ironlake hotplug adpa=0x83f40000, result 1
<7> [294.747187] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] CRT detected via hotplug
<7> [294.747990] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [294.748321] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK on first message, retry
<7> [294.748710] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [294.748925] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [294.748932] i915 0000:00:02.0: [drm:intel_crt_get_edid [i915]] CRT GMBUS EDID read failed, retry using GPIO bit-banging
<7> [294.749191] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] enabling bit-banging on i915 gmbus vga. force bit now 1
<7> [294.750360] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [294.750367] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] disabling bit-banging on i915 gmbus vga. force bit now 0
<7> [294.750667] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:73:VGA-1] probed modes:
<7> [294.750673] 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> [294.750679] 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> [294.750684] 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> [294.750688] 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> [294.750693] 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> [294.750731] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:76:HDMI-A-1]
<7> [294.750740] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:76:HDMI-A-1]
<7> [294.775934] 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> [294.775944] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:76:HDMI-A-1] HDMI sink does deep color 30.
<7> [294.775948] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:76:HDMI-A-1] HDMI sink does deep color 36.
<7> [294.775952] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:76:HDMI-A-1] HDMI sink does deep color 48.
<7> [294.775956] 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> [294.775960] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:76:HDMI-A-1] HDMI sink does YCRCB444 in deep color.
<7> [294.775965] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:76:HDMI-A-1] ELD monitor Mi TV
<7> [294.775970] 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> [294.775975] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:76:HDMI-A-1] ELD size 36, SAD count 3
<7> [294.776189] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpd] NAK for addr: 0040 w(1)
<7> [294.776536] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpd] NAK on first message, retry
<7> [294.777077] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpd] NAK for addr: 0040 w(1)
<7> [294.777359] i915 0000:00:02.0: [drm:drm_dp_dual_mode_detect [drm_display_helper]] DP dual mode HDMI ID: (err -6)
<7> [294.779206] 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> [294.779226] 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> [294.779242] 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> [294.779257] 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> [294.779271] 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> [294.779286] 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> [294.779300] 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> [294.779315] 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> [294.779329] 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> [294.779345] 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> [294.779360] 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> [294.779375] 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> [294.779389] 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> [294.779404] 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> [294.779418] 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> [294.779433] 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> [294.779448] 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> [294.779463] 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> [294.779477] 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> [294.779497] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:76:HDMI-A-1] probed modes:
<7> [294.779505] 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> [294.779513] 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> [294.779522] 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> [294.779530] 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> [294.779538] 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> [294.779546] 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> [294.779555] 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> [294.779563] 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> [294.779571] 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> [294.779580] 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> [294.779588] 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> [294.779596] 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> [294.779604] 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> [294.779612] 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> [294.779621] 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> [294.779629] 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> [294.779637] 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> [294.779646] 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> [294.779654] 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> [294.779662] 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> [294.779671] 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> [294.779679] 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> [294.779687] 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> [294.779696] 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> [294.779704] 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> [294.779712] 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> [294.779721] 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> [294.779729] 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> [294.779737] 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> [294.779745] 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> [294.779753] 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> [294.779761] 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> [294.779770] 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> [294.779778] 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> [294.779787] 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> [294.779795] 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> [294.779803] 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> [294.779812] 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> [294.779820] 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> [294.779828] 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> [294.779836] 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> [294.779845] 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> [294.779853] 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> [294.779861] 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> [294.779869] 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> [294.779938] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:84:DP-1]
<7> [294.779947] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:84:DP-1]
<7> [294.782921] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.786003] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.788991] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.792103] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.795091] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.798107] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.801066] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.804053] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.807057] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.810056] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.813004] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.815991] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.818952] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.821933] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.824825] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.827691] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.830561] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.833423] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.836329] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.839207] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.842114] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.845091] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.848063] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.851063] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.853980] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.856957] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.859957] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.862969] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.865899] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.869022] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.872024] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.874937] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.875349] 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> [294.877971] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.881071] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.884094] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.887093] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.890213] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.893270] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.896244] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.899279] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.902280] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.905209] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.908179] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.911202] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.914231] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.917107] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.920134] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.923171] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.926086] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.929049] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.932022] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.935027] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.938029] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.940917] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.943900] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.946938] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.949958] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.952905] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.955983] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.959027] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.962019] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.965008] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.968090] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.971036] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [294.971445] 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> [294.971491] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:84:DP-1] disconnected
<6> [294.980818] [IGT] kms_flip: starting subtest wf_vblank-ts-check-interruptible
<6> [294.982551] [IGT] kms_flip: starting dynamic subtest A-VGA1
<7> [294.983194] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:92]
<7> [294.983332] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:93]
<7> [295.012423] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:53:pipe A]
<7> [295.012595] 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> [295.012948] 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> [295.013210] 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> [295.013442] 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> [295.013673] 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> [295.013940] 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> [295.014227] 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> [295.014530] 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> [295.014783] 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> [295.015144] 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> [295.015385] 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> [295.015614] 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> [295.016066] 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> [295.016307] 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> [295.016538] 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> [295.016767] 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> [295.017339] 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> [295.017594] 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> [295.017825] 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> [295.018097] 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> [295.018355] 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> [295.018594] 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> [295.018823] 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> [295.019091] 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> [295.019337] 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> [295.019567] 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> [295.019796] 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> [295.020094] 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> [295.020325] 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> [295.020553] 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> [295.020782] 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> [295.021047] 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> [295.021293] 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> [295.021522] 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> [295.021752] 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> [295.022039] 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> [295.022296] 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> [295.022536] 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> [295.022772] 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> [295.023045] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:53:pipe A] fastset requirement not met, forcing full modeset
<7> [295.023276] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:53:pipe A] releasing PCH DPLL A
<7> [295.023546] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:53:pipe A] min cdclk: 72000 kHz -> 0 kHz
<7> [295.023788] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:53:pipe A] enable: no [modeset]
<7> [295.024056] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:primary A] fb: [NOFB], visible: no
<7> [295.024379] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [295.035181] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port B found
<7> [295.035407] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port C found
<7> [295.035622] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port D found
<7> [295.051838] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable PCH DPLL A (active 0x1, on? 1) for [CRTC:53:pipe A]
<7> [295.052392] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling PCH DPLL A
<7> [295.053143] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:CRT]
<7> [295.053628] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:75:HDMI D]
<7> [295.054134] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:83:DP D]
<7> [295.054633] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:73:VGA-1]
<7> [295.055229] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:53:pipe A]
<7> [295.055849] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:72:pipe B]
<7> [295.055979] 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> [295.056214] 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> [295.056459] 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> [295.056696] 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> [295.056945] 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> [295.057182] 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> [295.057420] 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> [295.057656] 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> [295.057912] 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> [295.058149] 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> [295.058386] 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> [295.058622] 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> [295.058858] 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> [295.059116] 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> [295.059354] 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> [295.059591] 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> [295.059827] 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> [295.060080] 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> [295.060352] 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> [295.060587] 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> [295.060822] 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> [295.061076] 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> [295.061313] 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> [295.061549] 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> [295.061785] 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> [295.062036] 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> [295.062273] 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> [295.062508] 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> [295.062743] 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> [295.062992] 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> [295.063229] 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> [295.063464] 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> [295.063700] 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> [295.063971] 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> [295.064202] 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> [295.064447] 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> [295.064685] 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> [295.064937] 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> [295.065168] 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> [295.065397] 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> [295.065636] 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> [295.065871] 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> [295.066120] 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> [295.066350] 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> [295.066579] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in avi infoframe
<7> [295.066818] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [295.067084] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [295.067087] i915 0000:00:02.0: colorspace: RGB
<7> [295.067089] i915 0000:00:02.0: scan mode: Underscan
<7> [295.067091] i915 0000:00:02.0: colorimetry: No Data
<7> [295.067093] i915 0000:00:02.0: picture aspect: 16:9
<7> [295.067095] i915 0000:00:02.0: active aspect: Same as Picture
<7> [295.067098] i915 0000:00:02.0: itc: No Data
<7> [295.067100] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [295.067102] i915 0000:00:02.0: quantization range: Default
<7> [295.067104] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [295.067106] i915 0000:00:02.0: video code: 16
<7> [295.067108] i915 0000:00:02.0: ycc quantization range: Full
<7> [295.067110] i915 0000:00:02.0: hdmi content type: Graphics
<7> [295.067112] i915 0000:00:02.0: pixel repeat: 0
<7> [295.067114] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [295.067116] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [295.067354] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in spd infoframe
<7> [295.067581] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [295.067808] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [295.067810] i915 0000:00:02.0: vendor: Intel
<7> [295.067812] i915 0000:00:02.0: product: Integrated gfx
<7> [295.067814] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [295.067817] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [295.068115] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hdmi infoframe
<7> [295.068372] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [295.068607] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [295.068610] i915 0000:00:02.0: empty frame
<7> [295.068612] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [295.068848] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:72:pipe B] fastset requirement not met, forcing full modeset
<7> [295.069101] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:72:pipe B] releasing PCH DPLL B
<7> [295.069354] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:72:pipe B] min cdclk: 165334 kHz -> 0 kHz
<7> [295.069587] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:72:pipe B] enable: no [modeset]
<7> [295.069830] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:54:primary B] fb: [NOFB], visible: no
<7> [295.070134] 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> [295.100202] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe B
<7> [295.116662] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port B found
<7> [295.117197] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port C found
<7> [295.117700] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port D found
<7> [295.129163] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable PCH DPLL B (active 0x2, on? 1) for [CRTC:72:pipe B]
<7> [295.129679] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling PCH DPLL B
<7> [295.130450] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:CRT]
<7> [295.130968] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:75:HDMI D]
<7> [295.131447] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:83:DP D]
<7> [295.131949] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:76:HDMI-A-1]
<7> [295.132457] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:72:pipe B]
<7> [295.132953] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:53:pipe A]
<7> [295.132999] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:73:VGA-1]
<7> [295.133104] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:73:VGA-1] Limiting target display pipe bpp to 24 (EDID bpp 0, max requested bpp 0, max platform bpp 36)
<7> [295.133380] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:53:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [295.133718] i915 0000:00:02.0: [drm:intel_fdi_atomic_check_link [i915]] checking fdi config on pipe A, lanes 1
<7> [295.134098] 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> [295.134350] 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> [295.134601] 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> [295.134871] 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> [295.135159] 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> [295.135410] 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> [295.135666] 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> [295.135930] 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> [295.136173] 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> [295.136409] 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> [295.136643] 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> [295.136903] 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> [295.137140] 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> [295.137374] 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> [295.137609] 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> [295.137856] 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> [295.138106] 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> [295.138335] 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> [295.138565] 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> [295.138794] 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> [295.139071] 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> [295.139311] 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> [295.139540] 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> [295.139769] 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> [295.140048] 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> [295.140287] 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> [295.140517] 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> [295.140745] 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> [295.141031] 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> [295.141270] 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> [295.141500] 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> [295.141729] 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> [295.142023] 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> [295.142263] 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> [295.142493] 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> [295.142723] 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> [295.142984] 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> [295.143232] 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> [295.143460] 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> [295.143690] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:53:pipe A] fastset requirement not met, forcing full modeset
<7> [295.143993] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:53:pipe A] min cdclk: 0 kHz -> 72000 kHz
<7> [295.144232] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:53:pipe A] allocated PCH DPLL A
<7> [295.144459] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:53:pipe A] reserving PCH DPLL A
<7> [295.144690] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:53:pipe A] enable: yes [modeset]
<7> [295.144977] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: ANALOG (0x2), output format: RGB, sink format: RGB
<7> [295.145226] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [295.145462] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [295.145689] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [295.145965] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [295.146212] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [295.146449] 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> [295.146683] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [295.146936] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 1
<7> [295.147188] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [295.147423] 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> [295.147658] 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> [295.147907] 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> [295.148141] 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> [295.148377] 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> [295.148612] 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> [295.148874] 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> [295.149129] 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> [295.149365] 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> [295.149599] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 0, ips linetime: 0
<7> [295.149832] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1024x768+0+0
<7> [295.149865] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling always-on
<7> [295.150091] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [295.150328] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [295.150567] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [295.150801] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: dpll: 0x84100010, dpll_md: 0x0, fp0: 0x10c09, fp1: 0x10c09
<7> [295.151065] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x3 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [295.151308] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [295.151535] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [295.151774] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [295.152022] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [295.152250] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [295.152489] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:primary A] fb: [FB:92] 1024x768 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [295.152725] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [295.152984] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1024.000000x768.000000+0.000000+0.000000 dst: 1024x768+0+0
<7> [295.154207] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling always-on
<7> [295.154467] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:CRT]
<7> [295.154692] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:75:HDMI D]
<7> [295.154950] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:83:DP D]
<7> [295.155788] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [295.156886] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x100
<7> [295.157117] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 1 done.
<7> [295.158008] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x200
<7> [295.158236] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 2 done.
<7> [295.158459] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train done.
<7> [295.158711] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable PCH DPLL A (active 0x1, on? 0) for [CRTC:53:pipe A]
<7> [295.159034] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling PCH DPLL A
<7> [295.211012] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:73:VGA-1]
<7> [295.211575] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:53:pipe A]
<7> [305.987179] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:53:pipe A]
<7> [305.987393] 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> [305.987964] 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> [305.988458] 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> [305.988975] 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> [305.989467] 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> [305.990001] 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> [305.990289] 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> [305.990525] 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> [305.990777] 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> [305.991015] 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> [305.991251] 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> [305.991486] 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> [305.991736] 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> [305.991973] 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> [305.992208] 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> [305.992444] 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> [305.992702] 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> [305.992939] 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> [305.993173] 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> [305.993409] 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> [305.993645] 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> [305.993892] 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> [305.994128] 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> [305.994365] 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> [305.994605] 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> [305.994880] 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> [305.995131] 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> [305.995366] 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> [305.995601] 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> [305.995846] 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> [305.996082] 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> [305.996317] 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> [305.996552] 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> [305.996799] 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> [305.997035] 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> [305.997273] 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> [305.997508] 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> [305.997759] 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> [305.997996] 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> [305.998232] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:53:pipe A] fastset requirement not met, forcing full modeset
<7> [305.998469] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:53:pipe A] releasing PCH DPLL A
<7> [305.998765] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:53:pipe A] min cdclk: 72000 kHz -> 0 kHz
<7> [305.999018] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:53:pipe A] enable: no [modeset]
<7> [305.999246] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:primary A] fb: [NOFB], visible: no
<7> [305.999594] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [306.010783] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port B found
<7> [306.011265] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port C found
<7> [306.011786] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port D found
<7> [306.027117] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable PCH DPLL A (active 0x1, on? 1) for [CRTC:53:pipe A]
<7> [306.027409] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling PCH DPLL A
<7> [306.027858] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:CRT]
<7> [306.028086] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:75:HDMI D]
<7> [306.028322] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:83:DP D]
<7> [306.028561] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:73:VGA-1]
<7> [306.028827] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:53:pipe A]
<7> [306.029209] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:72:pipe B]
<7> [306.029380] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:53:pipe A]
<7> [306.029408] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:73:VGA-1]
<7> [306.029499] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:73:VGA-1] Limiting target display pipe bpp to 24 (EDID bpp 0, max requested bpp 0, max platform bpp 36)
<7> [306.029745] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:53:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [306.029978] i915 0000:00:02.0: [drm:intel_fdi_atomic_check_link [i915]] checking fdi config on pipe A, lanes 1
<7> [306.030239] 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> [306.030487] 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> [306.030718] 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> [306.030948] 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> [306.031216] 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> [306.031463] 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> [306.031694] 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> [306.031924] 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> [306.032179] 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> [306.032435] 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> [306.032665] 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> [306.032893] 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> [306.033130] 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> [306.033392] 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> [306.033638] 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> [306.033867] 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> [306.034097] 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> [306.034365] 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> [306.034612] 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> [306.034841] 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> [306.035069] 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> [306.035325] 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> [306.035570] 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> [306.035800] 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> [306.036029] 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> [306.036319] 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> [306.036573] 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> [306.036801] 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> [306.037030] 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> [306.037293] 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> [306.037539] 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> [306.037767] 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> [306.037996] 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> [306.038248] 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> [306.038493] 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> [306.038722] 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> [306.038951] 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> [306.039208] 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> [306.039453] 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> [306.039681] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:53:pipe A] fastset requirement not met, forcing full modeset
<7> [306.039936] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:53:pipe A] min cdclk: 0 kHz -> 72000 kHz
<7> [306.040206] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:53:pipe A] allocated PCH DPLL A
<7> [306.040458] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:53:pipe A] reserving PCH DPLL A
<7> [306.040687] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:53:pipe A] enable: yes [modeset]
<7> [306.040916] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: ANALOG (0x2), output format: RGB, sink format: RGB
<7> [306.041170] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [306.041416] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [306.041643] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [306.041871] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [306.042126] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [306.042387] 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> [306.042615] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [306.042843] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 1
<7> [306.043102] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [306.043359] 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> [306.043594] 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> [306.043828] 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> [306.044070] 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> [306.044305] 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> [306.044540] 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> [306.044775] 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> [306.045021] 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> [306.045257] 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> [306.045492] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 0, ips linetime: 0
<7> [306.045725] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1024x768+0+0
<7> [306.045960] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [306.046214] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [306.046449] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [306.046690] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: dpll: 0x84100010, dpll_md: 0x0, fp0: 0x10c09, fp1: 0x10c09
<7> [306.046919] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x3 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [306.047177] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [306.047201] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling always-on
<7> [306.047578] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [306.047817] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [306.048098] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [306.048358] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [306.048587] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:primary A] fb: [FB:92] 1024x768 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [306.048818] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [306.049080] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1024.000000x768.000000+0.000000+0.000000 dst: 1024x768+0+0
<7> [306.049374] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling always-on
<7> [306.049616] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:CRT]
<7> [306.049844] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:75:HDMI D]
<7> [306.050082] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:83:DP D]
<7> [306.050850] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [306.051928] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x100
<7> [306.052160] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 1 done.
<7> [306.053047] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x200
<7> [306.053279] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 2 done.
<7> [306.053509] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train done.
<7> [306.053742] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable PCH DPLL A (active 0x1, on? 0) for [CRTC:53:pipe A]
<7> [306.053988] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling PCH DPLL A
<7> [306.105955] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:73:VGA-1]
<7> [306.106547] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:53:pipe A]
<6> [313.978786] [IGT] kms_flip: finished subtest A-VGA1, FAIL
Created at 2026-04-03 02:23:05