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

Result: Fail

integration-manifest git-log-oneline i915_display_info12 igt_runner12 runtimes12 results12.json results12-i915-load.json guc_logs12.tar i915_display_info_post_exec12 boot12 dmesg12

DetailValue
Duration 19.04 seconds
Hostname 
shard-snb5
Igt-Version 
IGT-Version: 2.3-g476cbad7c (x86_64) (Linux: 7.0.0-rc3-CI_DRM_18116-g77e176657e78+ x86_64)
Out 
Using IGT_SRANDOM=1773156627 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 16723.9us +- 7.210us accuracy 0.13%
vblank interval differs from modeline! expected 16665.6us, measured 16724us +- 7.210us, difference 58.3us (8.1 sigma)
Event vblank: expected 458, counted 450, passrate = 22.22%, encoder type 1
Expected frametime: 16666us; measured 16890.5us +- 9.121us accuracy 0.16%
vblank interval differs from modeline! expected 16665.6us, measured 16890us +- 9.121us, difference 224.9us (24.7 sigma)
Event vblank: expected 448, counted 450, passrate = 75.56%, 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:1887 run_test()
  #3 ../tests/kms_flip.c:2156 __igt_unique____real_main2043()
  #4 ../tests/kms_flip.c:2043 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest A-VGA1: FAIL (19.035s)
Err 
Starting subtest: wf_vblank-ts-check-interruptible
Starting dynamic subtest: A-VGA1
(kms_flip:2905) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1707:
(kms_flip:2905) CRITICAL: Failed assertion: !retried
(kms_flip:2905) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest A-VGA1 failed.
**** DEBUG ****
(kms_flip:2905) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2905) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2905) igt_fb-DEBUG: Test requirement passed: cairo_surface_status(fb->cairo_surface) == CAIRO_STATUS_SUCCESS
(kms_flip:2905) igt_kms-INFO:   1024x768: 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa 
(kms_flip:2905) DEBUG: No stale events found
(kms_flip:2905) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0
(kms_flip:2905) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0
(kms_flip:2905) INFO: Expected frametime: 16666us; measured 16723.9us +- 7.210us accuracy 0.13%
(kms_flip:2905) INFO: vblank interval differs from modeline! expected 16665.6us, measured 16724us +- 7.210us, difference 58.3us (8.1 sigma)
(kms_flip:2905) DEBUG: name = vblank
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 286.052124
current_received_ts = 286.051483
current_seq = 10175
count = 0
seq_step = 10
(kms_flip:2905) DEBUG: name = vblank
last_ts = 286.052124
last_received_ts = 286.051483
last_seq = 10175
current_ts = 286.219360
current_received_ts = 286.218689
current_seq = 10185
count = 1
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 286.052124/10175, current 286.219360/10185: elapsed=167227.0us expected=167238.8us +- 836.2us, error 0.0%
(kms_flip:2905) DEBUG: name = vblank
last_ts = 286.219360
last_received_ts = 286.218689
last_seq = 10185
current_ts = 286.386597
current_received_ts = 286.385956
current_seq = 10195
count = 2
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 286.219360/10185, current 286.386597/10195: elapsed=167247.0us expected=167238.8us +- 836.2us, error 0.0%
(kms_flip:2905) DEBUG: name = vblank
last_ts = 286.386597
last_received_ts = 286.385956
last_seq = 10195
current_ts = 286.553833
current_received_ts = 286.553192
current_seq = 10205
count = 3
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 286.386597/10195, current 286.553833/10205: elapsed=167227.0us expected=167238.8us +- 836.2us, error 0.0%
(kms_flip:2905) DEBUG: name = vblank
last_ts = 286.553833
last_received_ts = 286.553192
last_seq = 10205
current_ts = 286.721069
current_received_ts = 286.720428
current_seq = 10215
count = 4
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 286.553833/10205, current 286.721069/10215: elapsed=167238.0us expected=167238.8us +- 836.2us, error 0.0%
(kms_flip:2905) DEBUG: name = vblank
last_ts = 286.721069
last_received_ts = 286.720428
last_seq = 10215
current_ts = 286.888306
current_received_ts = 286.887695
current_seq = 10225
count = 5
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 286.721069/10215, current 286.888306/10225: elapsed=167237.0us expected=167238.8us +- 836.2us, error 0.0%
(kms_flip:2905) DEBUG: name = vblank
last_ts = 286.888306
last_received_ts = 286.887695
last_seq = 10225
current_ts = 287.055542
current_received_ts = 287.054932
current_seq = 10235
count = 6
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 286.888306/10225, current 287.055542/10235: elapsed=167240.0us expected=167238.8us +- 836.2us, error 0.0%
(kms_flip:2905) DEBUG: name = vblank
last_ts = 287.055542
last_received_ts = 287.054932
last_seq = 10235
current_ts = 287.222778
current_received_ts = 287.222168
current_seq = 10245
count = 7
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 287.055542/10235, current 287.222778/10245: elapsed=167240.0us expected=167238.8us +- 836.2us, error 0.0%
(kms_flip:2905) DEBUG: name = vblank
last_ts = 287.222778
last_received_ts = 287.222168
last_seq = 10245
current_ts = 287.390015
current_received_ts = 287.389404
current_seq = 10255
count = 8
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 287.222778/10245, current 287.390015/10255: elapsed=167234.0us expected=167238.8us +- 836.2us, error 0.0%
(kms_flip:2905) DEBUG: name = vblank
last_ts = 287.390015
last_received_ts = 287.389404
last_seq = 10255
current_ts = 287.557251
current_received_ts = 287.556641
current_seq = 10265
count = 9
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 287.390015/10255, current 287.557251/10265: elapsed=167241.0us expected=167238.8us +- 836.2us, error 0.0%
(kms_flip:2905) DEBUG: name = vblank
last_ts = 287.557251
last_received_ts = 287.556641
last_seq = 10265
current_ts = 287.725525
current_received_ts = 287.724945
current_seq = 10275
count = 10
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 287.557251/10265, current 287.725525/10275: elapsed=168283.0us expected=167238.8us +- 836.2us, error 0.6%
(kms_flip:2905) DEBUG: inconsistent vblank ts/seq: last 287.557251/10265, current 287.725525/10275: elapsed=168283.0us expected=167238.8us
(kms_flip:2905) DEBUG: name = vblank
last_ts = 287.725525
last_received_ts = 287.724945
last_seq = 10275
current_ts = 287.898254
current_received_ts = 287.897675
current_seq = 10285
count = 11
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 287.725525/10275, current 287.898254/10285: elapsed=172735.0us expected=167238.8us +- 836.2us, error 3.3%
(kms_flip:2905) DEBUG: inconsistent vblank ts/seq: last 287.725525/10275, current 287.898254/10285: elapsed=172735.0us expected=167238.8us
(kms_flip:2905) DEBUG: name = vblank
last_ts = 287.898254
last_received_ts = 287.897675
last_seq = 10285
current_ts = 288.071014
current_received_ts = 288.070343
current_seq = 10295
count = 12
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 287.898254/10285, current 288.071014/10295: elapsed=172731.0us expected=167238.8us +- 836.2us, error 3.3%
(kms_flip:2905) DEBUG: inconsistent vblank ts/seq: last 287.898254/10285, current 288.071014/10295: elapsed=172731.0us expected=167238.8us
(kms_flip:2905) DEBUG: name = vblank
last_ts = 288.071014
last_received_ts = 288.070343
last_seq = 10295
current_ts = 288.243713
current_received_ts = 288.243103
current_seq = 10305
count = 13
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 288.071014/10295, current 288.243713/10305: elapsed=172728.0us expected=167238.8us +- 836.2us, error 3.3%
(kms_flip:2905) DEBUG: inconsistent vblank ts/seq: last 288.071014/10295, current 288.243713/10305: elapsed=172728.0us expected=167238.8us
(kms_flip:2905) DEBUG: name = vblank
last_ts = 288.243713
last_received_ts = 288.243103
last_seq = 10305
current_ts = 288.416443
current_received_ts = 288.415863
current_seq = 10315
count = 14
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 288.243713/10305, current 288.416443/10315: elapsed=172729.0us expected=167238.8us +- 836.2us, error 3.3%
(kms_flip:2905) DEBUG: inconsistent vblank ts/seq: last 288.243713/10305, current 288.416443/10315: elapsed=172729.0us expected=167238.8us
(kms_flip:2905) DEBUG: name = vblank
last_ts = 288.416443
last_received_ts = 288.415863
last_seq = 10315
current_ts = 288.589203
current_received_ts = 288.588562
current_seq = 10325
count = 15
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 288.416443/10315, current 288.589203/10325: elapsed=172734.0us expected=167238.8us +- 836.2us, error 3.3%
(kms_flip:2905) DEBUG: inconsistent vblank ts/seq: last 288.416443/10315, current 288.589203/10325: elapsed=172734.0us expected=167238.8us
(kms_flip:2905) DEBUG: name = vblank
last_ts = 288.589203
last_received_ts = 288.588562
last_seq = 10325
current_ts = 288.761627
current_received_ts = 288.760986
current_seq = 10335
count = 16
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 288.589203/10325, current 288.761627/10335: elapsed=172450.0us expected=167238.8us +- 836.2us, error 3.1%
(kms_flip:2905) DEBUG: inconsistent vblank ts/seq: last 288.589203/10325, current 288.761627/10335: elapsed=172450.0us expected=167238.8us
(kms_flip:2905) DEBUG: name = vblank
last_ts = 288.761627
last_received_ts = 288.760986
last_seq = 10335
current_ts = 288.933685
current_received_ts = 288.933075
current_seq = 10345
count = 17
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 288.761627/10335, current 288.933685/10345: elapsed=172045.0us expected=167238.8us +- 836.2us, error 2.9%
(kms_flip:2905) DEBUG: inconsistent vblank ts/seq: last 288.761627/10335, current 288.933685/10345: elapsed=172045.0us expected=167238.8us
(kms_flip:2905) DEBUG: name = vblank
last_ts = 288.933685
last_received_ts = 288.933075
last_seq = 10345
current_ts = 289.105743
current_received_ts = 289.105103
current_seq = 10355
count = 18
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 288.933685/10345, current 289.105743/10355: elapsed=172051.0us expected=167238.8us +- 836.2us, error 2.9%
(kms_flip:2905) DEBUG: inconsistent vblank ts/seq: last 288.933685/10345, current 289.105743/10355: elapsed=172051.0us expected=167238.8us
(kms_flip:2905) DEBUG: name = vblank
last_ts = 289.105743
last_received_ts = 289.105103
last_seq = 10355
current_ts = 289.277771
current_received_ts = 289.277100
current_seq = 10365
count = 19
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 289.105743/10355, current 289.277771/10365: elapsed=172044.0us expected=167238.8us +- 836.2us, error 2.9%
(kms_flip:2905) DEBUG: inconsistent vblank ts/seq: last 289.105743/10355, current 289.277771/10365: elapsed=172044.0us expected=167238.8us
(kms_flip:2905) DEBUG: name = vblank
last_ts = 289.277771
last_received_ts = 289.277100
last_seq = 10365
current_ts = 289.449829
current_received_ts = 289.449158
current_seq = 10375
count = 20
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 289.277771/10365, current 289.449829/10375: elapsed=172044.0us expected=167238.8us +- 836.2us, error 2.9%
(kms_flip:2905) DEBUG: inconsistent vblank ts/seq: last 289.277771/10365, current 289.449829/10375: elapsed=172044.0us expected=167238.8us
(kms_flip:2905) DEBUG: name = vblank
last_ts = 289.449829
last_received_ts = 289.449158
last_seq = 10375
current_ts = 289.621857
current_received_ts = 289.621155
current_seq = 10385
count = 21
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 289.449829/10375, current 289.621857/10385: elapsed=172038.0us expected=167238.8us +- 836.2us, error 2.9%
(kms_flip:2905) DEBUG: inconsistent vblank ts/seq: last 289.449829/10375, current 289.621857/10385: elapsed=172038.0us expected=167238.8us
(kms_flip:2905) DEBUG: name = vblank
last_ts = 289.621857
last_received_ts = 289.621155
last_seq = 10385
current_ts = 289.793549
current_received_ts = 289.792938
current_seq = 10395
count = 22
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 289.621857/10385, current 289.793549/10395: elapsed=171690.0us expected=167238.8us +- 836.2us, error 2.7%
(kms_flip:2905) DEBUG: inconsistent vblank ts/seq: last 289.621857/10385, current 289.793549/10395: elapsed=171690.0us expected=167238.8us
(kms_flip:2905) DEBUG: name = vblank
last_ts = 289.793549
last_received_ts = 289.792938
last_seq = 10395
current_ts = 289.964996
current_received_ts = 289.964355
current_seq = 10405
count = 23
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 289.793549/10395, current 289.964996/10405: elapsed=171450.0us expected=167238.8us +- 836.2us, error 2.5%
(kms_flip:2905) DEBUG: inconsistent vblank ts/seq: last 289.793549/10395, current 289.964996/10405: elapsed=171450.0us expected=167238.8us
(kms_flip:2905) DEBUG: name = vblank
last_ts = 289.964996
last_received_ts = 289.964355
last_seq = 10405
current_ts = 290.136444
current_received_ts = 290.135834
current_seq = 10415
count = 24
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 289.964996/10405, current 290.136444/10415: elapsed=171446.0us expected=167238.8us +- 836.2us, error 2.5%
(kms_flip:2905) DEBUG: inconsistent vblank ts/seq: last 289.964996/10405, current 290.136444/10415: elapsed=171446.0us expected=167238.8us
(kms_flip:2905) DEBUG: name = vblank
last_ts = 290.136444
last_received_ts = 290.135834
last_seq = 10415
current_ts = 290.307892
current_received_ts = 290.307251
current_seq = 10425
count = 25
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 290.136444/10415, current 290.307892/10425: elapsed=171444.0us expected=167238.8us +- 836.2us, error 2.5%
(kms_flip:2905) DEBUG: inconsistent vblank ts/seq: last 290.136444/10415, current 290.307892/10425: elapsed=171444.0us expected=167238.8us
(kms_flip:2905) DEBUG: name = vblank
last_ts = 290.307892
last_received_ts = 290.307251
last_seq = 10425
current_ts = 290.479340
current_received_ts = 290.478729
current_seq = 10435
count = 26
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 290.307892/10425, current 290.479340/10435: elapsed=171448.0us expected=167238.8us +- 836.2us, error 2.5%
(kms_flip:2905) DEBUG: inconsistent vblank ts/seq: last 290.307892/10425, current 290.479340/10435: elapsed=171448.0us expected=167238.8us
(kms_flip:2905) DEBUG: name = vblank
last_ts = 290.479340
last_received_ts = 290.478729
last_seq = 10435
current_ts = 290.650787
current_received_ts = 290.650146
current_seq = 10445
count = 27
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 290.479340/10435, current 290.650787/10445: elapsed=171458.0us expected=167238.8us +- 836.2us, error 2.5%
(kms_flip:2905) DEBUG: inconsistent vblank ts/seq: last 290.479340/10435, current 290.650787/10445: elapsed=171458.0us expected=167238.8us
(kms_flip:2905) DEBUG: name = vblank
last_ts = 290.650787
last_received_ts = 290.650146
last_seq = 10445
current_ts = 290.821838
current_received_ts = 290.821198
current_seq = 10455
count = 28
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 290.650787/10445, current 290.821838/10455: elapsed=171048.0us expected=167238.8us +- 836.2us, error 2.3%
(kms_flip:2905) DEBUG: inconsistent vblank ts/seq: last 290.650787/10445, current 290.821838/10455: elapsed=171048.0us expected=167238.8us
(kms_flip:2905) DEBUG: name = vblank
last_ts = 290.821838
last_received_ts = 290.821198
last_seq = 10455
current_ts = 290.992767
current_received_ts = 290.992126
current_seq = 10465
count = 29
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 290.821838/10455, current 290.992767/10465: elapsed=170911.0us expected=167238.8us +- 836.2us, error 2.2%
(kms_flip:2905) DEBUG: inconsistent vblank ts/seq: last 290.821838/10455, current 290.992767/10465: elapsed=170911.0us expected=167238.8us
(kms_flip:2905) DEBUG: name = vblank
last_ts = 290.992767
last_received_ts = 290.992126
last_seq = 10465
current_ts = 291.163666
current_received_ts = 291.163086
current_seq = 10475
count = 30
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 290.992767/10465, current 291.163666/10475: elapsed=170923.0us expected=167238.8us +- 836.2us, error 2.2%
(kms_flip:2905) DEBUG: inconsistent vblank ts/seq: last 290.992767/10465, current 291.163666/10475: elapsed=170923.0us expected=167238.8us
(kms_flip:2905) DEBUG: name = vblank
last_ts = 291.163666
last_received_ts = 291.163086
last_seq = 10475
current_ts = 291.334595
current_received_ts = 291.333984
current_seq = 10485
count = 31
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 291.163666/10475, current 291.334595/10485: elapsed=170921.0us expected=167238.8us +- 836.2us, error 2.2%
(kms_flip:2905) DEBUG: inconsistent vblank ts/seq: last 291.163666/10475, current 291.334595/10485: elapsed=170921.0us expected=167238.8us
(kms_flip:2905) DEBUG: name = vblank
last_ts = 291.334595
last_received_ts = 291.333984
last_seq = 10485
current_ts = 291.505524
current_received_ts = 291.504944
current_seq = 10495
count = 32
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 291.334595/10485, current 291.505524/10495: elapsed=170922.0us expected=167238.8us +- 836.2us, error 2.2%
(kms_flip:2905) DEBUG: inconsistent vblank ts/seq: last 291.334595/10485, current 291.505524/10495: elapsed=170922.0us expected=167238.8us
(kms_flip:2905) DEBUG: name = vblank
last_ts = 291.505524
last_received_ts = 291.504944
last_seq = 10495
current_ts = 291.676453
current_received_ts = 291.675842
current_seq = 10505
count = 33
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 291.505524/10495, current 291.676453/10505: elapsed=170918.0us expected=167238.8us +- 836.2us, error 2.2%
(kms_flip:2905) DEBUG: inconsistent vblank ts/seq: last 291.505524/10495, current 291.676453/10505: elapsed=170918.0us expected=167238.8us
(kms_flip:2905) DEBUG: name = vblank
last_ts = 291.676453
last_received_ts = 291.675842
last_seq = 10505
current_ts = 291.846954
current_received_ts = 291.846405
current_seq = 10515
count = 34
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 291.676453/10505, current 291.846954/10515: elapsed=170510.0us expected=167238.8us +- 836.2us, error 2.0%
(kms_flip:2905) DEBUG: inconsistent vblank ts/seq: last 291.676453/10505, current 291.846954/10515: elapsed=170510.0us expected=167238.8us
(kms_flip:2905) DEBUG: name = vblank
last_ts = 291.846954
last_received_ts = 291.846405
last_seq = 10515
current_ts = 292.017426
current_received_ts = 292.016815
current_seq = 10525
count = 35
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 291.846954/10515, current 292.017426/10525: elapsed=170462.0us expected=167238.8us +- 836.2us, error 1.9%
(kms_flip:2905) DEBUG: inconsistent vblank ts/seq: last 291.846954/10515, current 292.017426/10525: elapsed=170462.0us expected=167238.8us
(kms_flip:2905) DEBUG: name = vblank
last_ts = 292.017426
last_received_ts = 292.016815
last_seq = 10525
current_ts = 292.187866
current_received_ts = 292.187225
current_seq = 10535
count = 36
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 292.017426/10525, current 292.187866/10535: elapsed=170463.0us expected=167238.8us +- 836.2us, error 1.9%
(kms_flip:2905) DEBUG: inconsistent vblank ts/seq: last 292.017426/10525, current 292.187866/10535: elapsed=170463.0us expected=167238.8us
(kms_flip:2905) DEBUG: name = vblank
last_ts = 292.187866
last_received_ts = 292.187225
last_seq = 10535
current_ts = 292.358337
current_received_ts = 292.357635
current_seq = 10545
count = 37
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 292.187866/10535, current 292.358337/10545: elapsed=170463.0us expected=167238.8us +- 836.2us, error 1.9%
(kms_flip:2905) DEBUG: inconsistent vblank ts/seq: last 292.187866/10535, current 292.358337/10545: elapsed=170463.0us expected=167238.8us
(kms_flip:2905) DEBUG: name = vblank
last_ts = 292.358337
last_received_ts = 292.357635
last_seq = 10545
current_ts = 292.528809
current_received_ts = 292.528168
current_seq = 10555
count = 38
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 292.358337/10545, current 292.528809/10555: elapsed=170474.0us expected=167238.8us +- 836.2us, error 1.9%
(kms_flip:2905) DEBUG: inconsistent vblank ts/seq: last 292.358337/10545, current 292.528809/10555: elapsed=170474.0us expected=167238.8us
(kms_flip:2905) DEBUG: name = vblank
last_ts = 292.528809
last_received_ts = 292.528168
last_seq = 10555
current_ts = 292.699280
current_received_ts = 292.698639
current_seq = 10565
count = 39
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 292.528809/10555, current 292.699280/10565: elapsed=170451.0us expected=167238.8us +- 836.2us, error 1.9%
(kms_flip:2905) DEBUG: inconsistent vblank ts/seq: last 292.528809/10555, current 292.699280/10565: elapsed=170451.0us expected=167238.8us
(kms_flip:2905) DEBUG: name = vblank
last_ts = 292.699280
last_received_ts = 292.698639
last_seq = 10565
current_ts = 292.869324
current_received_ts = 292.868744
current_seq = 10575
count = 40
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 292.699280/10565, current 292.869324/10575: elapsed=170049.0us expected=167238.8us +- 836.2us, error 1.7%
(kms_flip:2905) DEBUG: inconsistent vblank ts/seq: last 292.699280/10565, current 292.869324/10575: elapsed=170049.0us expected=167238.8us
(kms_flip:2905) DEBUG: name = vblank
last_ts = 292.869324
last_received_ts = 292.868744
last_seq = 10575
current_ts = 293.039368
current_received_ts = 293.038788
current_seq = 10585
count = 41
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 292.869324/10575, current 293.039368/10585: elapsed=170062.0us expected=167238.8us +- 836.2us, error 1.7%
(kms_flip:2905) DEBUG: inconsistent vblank ts/seq: last 292.869324/10575, current 293.039368/10585: elapsed=170062.0us expected=167238.8us
(kms_flip:2905) DEBUG: name = vblank
last_ts = 293.039368
last_received_ts = 293.038788
last_seq = 10585
current_ts = 293.209442
current_received_ts = 293.208832
current_seq = 10595
count = 42
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 293.039368/10585, current 293.209442/10595: elapsed=170061.0us expected=167238.8us +- 836.2us, error 1.7%
(kms_flip:2905) DEBUG: inconsistent vblank ts/seq: last 293.039368/10585, current 293.209442/10595: elapsed=170061.0us expected=167238.8us
(kms_flip:2905) DEBUG: name = vblank
last_ts = 293.209442
last_received_ts = 293.208832
last_seq = 10595
current_ts = 293.379486
current_received_ts = 293.378845
current_seq = 10605
count = 43
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 293.209442/10595, current 293.379486/10605: elapsed=170064.0us expected=167238.8us +- 836.2us, error 1.7%
(kms_flip:2905) DEBUG: inconsistent vblank ts/seq: last 293.209442/10595, current 293.379486/10605: elapsed=170064.0us expected=167238.8us
(kms_flip:2905) DEBUG: name = vblank
last_ts = 293.379486
last_received_ts = 293.378845
last_seq = 10605
current_ts = 293.549561
current_received_ts = 293.548981
current_seq = 10615
count = 44
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 293.379486/10605, current 293.549561/10615: elapsed=170073.0us expected=167238.8us +- 836.2us, error 1.7%
(kms_flip:2905) DEBUG: inconsistent vblank ts/seq: last 293.379486/10605, current 293.549561/10615: elapsed=170073.0us expected=167238.8us
(kms_flip:2905) INFO: Event vblank: expected 458, counted 450, passrate = 22.22%, encoder type 1
(kms_flip:2905) DEBUG: dropped frames, expected 458, counted 450, passrate = 22.22%, encoder type 1
(kms_flip:2905) DEBUG: Retrying without a hotplug event
(kms_flip:2905) DEBUG: No stale events found
(kms_flip:2905) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0
(kms_flip:2905) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0
(kms_flip:2905) INFO: Expected frametime: 16666us; measured 16890.5us +- 9.121us accuracy 0.16%
(kms_flip:2905) INFO: vblank interval differs from modeline! expected 16665.6us, measured 16890us +- 9.121us, difference 224.9us (24.7 sigma)
(kms_flip:2905) DEBUG: name = vblank
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 297.156647
current_received_ts = 297.156067
current_seq = 10648
count = 0
seq_step = 10
(kms_flip:2905) DEBUG: name = vblank
last_ts = 297.156647
last_received_ts = 297.156067
last_seq = 10648
current_ts = 297.325531
current_received_ts = 297.324951
current_seq = 10658
count = 1
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 297.156647/10648, current 297.325531/10658: elapsed=168898.0us expected=168905.0us +- 844.5us, error 0.0%
(kms_flip:2905) DEBUG: name = vblank
last_ts = 297.325531
last_received_ts = 297.324951
last_seq = 10658
current_ts = 297.494446
current_received_ts = 297.493866
current_seq = 10668
count = 2
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 297.325531/10658, current 297.494446/10668: elapsed=168902.0us expected=168905.0us +- 844.5us, error 0.0%
(kms_flip:2905) DEBUG: name = vblank
last_ts = 297.494446
last_received_ts = 297.493866
last_seq = 10668
current_ts = 297.663361
current_received_ts = 297.662689
current_seq = 10678
count = 3
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 297.494446/10668, current 297.663361/10678: elapsed=168914.0us expected=168905.0us +- 844.5us, error 0.0%
(kms_flip:2905) DEBUG: name = vblank
last_ts = 297.663361
last_received_ts = 297.662689
last_seq = 10678
current_ts = 297.832092
current_received_ts = 297.831482
current_seq = 10688
count = 4
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 297.663361/10678, current 297.832092/10688: elapsed=168725.0us expected=168905.0us +- 844.5us, error 0.1%
(kms_flip:2905) DEBUG: name = vblank
last_ts = 297.832092
last_received_ts = 297.831482
last_seq = 10688
current_ts = 298.000793
current_received_ts = 298.000153
current_seq = 10698
count = 5
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 297.832092/10688, current 298.000793/10698: elapsed=168693.0us expected=168905.0us +- 844.5us, error 0.1%
(kms_flip:2905) DEBUG: name = vblank
last_ts = 298.000793
last_received_ts = 298.000153
last_seq = 10698
current_ts = 298.169464
current_received_ts = 298.168793
current_seq = 10708
count = 6
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 298.000793/10698, current 298.169464/10708: elapsed=168692.0us expected=168905.0us +- 844.5us, error 0.1%
(kms_flip:2905) DEBUG: name = vblank
last_ts = 298.169464
last_received_ts = 298.168793
last_seq = 10708
current_ts = 298.338165
current_received_ts = 298.337494
current_seq = 10718
count = 7
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 298.169464/10708, current 298.338165/10718: elapsed=168683.0us expected=168905.0us +- 844.5us, error 0.1%
(kms_flip:2905) DEBUG: name = vblank
last_ts = 298.338165
last_received_ts = 298.337494
last_seq = 10718
current_ts = 298.506836
current_received_ts = 298.506195
current_seq = 10728
count = 8
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 298.338165/10718, current 298.506836/10728: elapsed=168697.0us expected=168905.0us +- 844.5us, error 0.1%
(kms_flip:2905) DEBUG: name = vblank
last_ts = 298.506836
last_received_ts = 298.506195
last_seq = 10728
current_ts = 298.675537
current_received_ts = 298.674927
current_seq = 10738
count = 9
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 298.506836/10728, current 298.675537/10738: elapsed=168690.0us expected=168905.0us +- 844.5us, error 0.1%
(kms_flip:2905) DEBUG: name = vblank
last_ts = 298.675537
last_received_ts = 298.674927
last_seq = 10738
current_ts = 298.844086
current_received_ts = 298.843445
current_seq = 10748
count = 10
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 298.675537/10738, current 298.844086/10748: elapsed=168544.0us expected=168905.0us +- 844.5us, error 0.2%
(kms_flip:2905) DEBUG: name = vblank
last_ts = 298.844086
last_received_ts = 298.843445
last_seq = 10748
current_ts = 299.012604
current_received_ts = 299.011871
current_seq = 10758
count = 11
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 298.844086/10748, current 299.012604/10758: elapsed=168517.0us expected=168905.0us +- 844.5us, error 0.2%
(kms_flip:2905) DEBUG: name = vblank
last_ts = 299.012604
last_received_ts = 299.011871
last_seq = 10758
current_ts = 299.181091
current_received_ts = 299.180481
current_seq = 10768
count = 12
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 299.012604/10758, current 299.181091/10768: elapsed=168500.0us expected=168905.0us +- 844.5us, error 0.2%
(kms_flip:2905) DEBUG: name = vblank
last_ts = 299.181091
last_received_ts = 299.180481
last_seq = 10768
current_ts = 299.349609
current_received_ts = 299.348999
current_seq = 10778
count = 13
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 299.181091/10768, current 299.349609/10778: elapsed=168511.0us expected=168905.0us +- 844.5us, error 0.2%
(kms_flip:2905) DEBUG: name = vblank
last_ts = 299.349609
last_received_ts = 299.348999
last_seq = 10778
current_ts = 299.518127
current_received_ts = 299.517517
current_seq = 10788
count = 14
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 299.349609/10778, current 299.518127/10788: elapsed=168516.0us expected=168905.0us +- 844.5us, error 0.2%
(kms_flip:2905) DEBUG: name = vblank
last_ts = 299.518127
last_received_ts = 299.517517
last_seq = 10788
current_ts = 299.686646
current_received_ts = 299.686066
current_seq = 10798
count = 15
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 299.518127/10788, current 299.686646/10798: elapsed=168512.0us expected=168905.0us +- 844.5us, error 0.2%
(kms_flip:2905) DEBUG: name = vblank
last_ts = 299.686646
last_received_ts = 299.686066
last_seq = 10798
current_ts = 299.855011
current_received_ts = 299.854370
current_seq = 10808
count = 16
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 299.686646/10798, current 299.855011/10808: elapsed=168366.0us expected=168905.0us +- 844.5us, error 0.3%
(kms_flip:2905) DEBUG: name = vblank
last_ts = 299.855011
last_received_ts = 299.854370
last_seq = 10808
current_ts = 300.023346
current_received_ts = 300.022675
current_seq = 10818
count = 17
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 299.855011/10808, current 300.023346/10818: elapsed=168354.0us expected=168905.0us +- 844.5us, error 0.3%
(kms_flip:2905) DEBUG: name = vblank
last_ts = 300.023346
last_received_ts = 300.022675
last_seq = 10818
current_ts = 300.191711
current_received_ts = 300.191071
current_seq = 10828
count = 18
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 300.023346/10818, current 300.191711/10828: elapsed=168351.0us expected=168905.0us +- 844.5us, error 0.3%
(kms_flip:2905) DEBUG: name = vblank
last_ts = 300.191711
last_received_ts = 300.191071
last_seq = 10828
current_ts = 300.360077
current_received_ts = 300.359436
current_seq = 10838
count = 19
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 300.191711/10828, current 300.360077/10838: elapsed=168357.0us expected=168905.0us +- 844.5us, error 0.3%
(kms_flip:2905) DEBUG: name = vblank
last_ts = 300.360077
last_received_ts = 300.359436
last_seq = 10838
current_ts = 300.528412
current_received_ts = 300.527832
current_seq = 10848
count = 20
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 300.360077/10838, current 300.528412/10848: elapsed=168358.0us expected=168905.0us +- 844.5us, error 0.3%
(kms_flip:2905) DEBUG: name = vblank
last_ts = 300.528412
last_received_ts = 300.527832
last_seq = 10848
current_ts = 300.696777
current_received_ts = 300.696075
current_seq = 10858
count = 21
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 300.528412/10848, current 300.696777/10858: elapsed=168361.0us expected=168905.0us +- 844.5us, error 0.3%
(kms_flip:2905) DEBUG: name = vblank
last_ts = 300.696777
last_received_ts = 300.696075
last_seq = 10858
current_ts = 300.864990
current_received_ts = 300.864380
current_seq = 10868
count = 22
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 300.696777/10858, current 300.864990/10868: elapsed=168208.0us expected=168905.0us +- 844.5us, error 0.4%
(kms_flip:2905) DEBUG: name = vblank
last_ts = 300.864990
last_received_ts = 300.864380
last_seq = 10868
current_ts = 301.033203
current_received_ts = 301.032623
current_seq = 10878
count = 23
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 300.864990/10868, current 301.033203/10878: elapsed=168220.0us expected=168905.0us +- 844.5us, error 0.4%
(kms_flip:2905) DEBUG: name = vblank
last_ts = 301.033203
last_received_ts = 301.032623
last_seq = 10878
current_ts = 301.201447
current_received_ts = 301.200806
current_seq = 10888
count = 24
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 301.033203/10878, current 301.201447/10888: elapsed=168232.0us expected=168905.0us +- 844.5us, error 0.4%
(kms_flip:2905) DEBUG: name = vblank
last_ts = 301.201447
last_received_ts = 301.200806
last_seq = 10888
current_ts = 301.369659
current_received_ts = 301.369019
current_seq = 10898
count = 25
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 301.201447/10888, current 301.369659/10898: elapsed=168205.0us expected=168905.0us +- 844.5us, error 0.4%
(kms_flip:2905) DEBUG: name = vblank
last_ts = 301.369659
last_received_ts = 301.369019
last_seq = 10898
current_ts = 301.537872
current_received_ts = 301.537231
current_seq = 10908
count = 26
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 301.369659/10898, current 301.537872/10908: elapsed=168216.0us expected=168905.0us +- 844.5us, error 0.4%
(kms_flip:2905) DEBUG: name = vblank
last_ts = 301.537872
last_received_ts = 301.537231
last_seq = 10908
current_ts = 301.706085
current_received_ts = 301.705444
current_seq = 10918
count = 27
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 301.537872/10908, current 301.706085/10918: elapsed=168205.0us expected=168905.0us +- 844.5us, error 0.4%
(kms_flip:2905) DEBUG: name = vblank
last_ts = 301.706085
last_received_ts = 301.705444
last_seq = 10918
current_ts = 301.874176
current_received_ts = 301.873566
current_seq = 10928
count = 28
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 301.706085/10918, current 301.874176/10928: elapsed=168115.0us expected=168905.0us +- 844.5us, error 0.5%
(kms_flip:2905) DEBUG: name = vblank
last_ts = 301.874176
last_received_ts = 301.873566
last_seq = 10928
current_ts = 302.042267
current_received_ts = 302.041626
current_seq = 10938
count = 29
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 301.874176/10928, current 302.042267/10938: elapsed=168091.0us expected=168905.0us +- 844.5us, error 0.5%
(kms_flip:2905) DEBUG: name = vblank
last_ts = 302.042267
last_received_ts = 302.041626
last_seq = 10938
current_ts = 302.210358
current_received_ts = 302.209747
current_seq = 10948
count = 30
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 302.042267/10938, current 302.210358/10948: elapsed=168092.0us expected=168905.0us +- 844.5us, error 0.5%
(kms_flip:2905) DEBUG: name = vblank
last_ts = 302.210358
last_received_ts = 302.209747
last_seq = 10948
current_ts = 302.378479
current_received_ts = 302.377869
current_seq = 10958
count = 31
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 302.210358/10948, current 302.378479/10958: elapsed=168094.0us expected=168905.0us +- 844.5us, error 0.5%
(kms_flip:2905) DEBUG: name = vblank
last_ts = 302.378479
last_received_ts = 302.377869
last_seq = 10958
current_ts = 302.546570
current_received_ts = 302.545868
current_seq = 10968
count = 32
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 302.378479/10958, current 302.546570/10968: elapsed=168099.0us expected=168905.0us +- 844.5us, error 0.5%
(kms_flip:2905) DEBUG: name = vblank
last_ts = 302.546570
last_received_ts = 302.545868
last_seq = 10968
current_ts = 302.714661
current_received_ts = 302.714050
current_seq = 10978
count = 33
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 302.546570/10968, current 302.714661/10978: elapsed=168081.0us expected=168905.0us +- 844.5us, error 0.5%
(kms_flip:2905) DEBUG: name = vblank
last_ts = 302.714661
last_received_ts = 302.714050
last_seq = 10978
current_ts = 302.882629
current_received_ts = 302.882050
current_seq = 10988
count = 34
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 302.714661/10978, current 302.882629/10988: elapsed=167992.0us expected=168905.0us +- 844.5us, error 0.5%
(kms_flip:2905) DEBUG: inconsistent vblank ts/seq: last 302.714661/10978, current 302.882629/10988: elapsed=167992.0us expected=168905.0us
(kms_flip:2905) DEBUG: name = vblank
last_ts = 302.882629
last_received_ts = 302.882050
last_seq = 10988
current_ts = 303.050629
current_received_ts = 303.050049
current_seq = 10998
count = 35
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 302.882629/10988, current 303.050629/10998: elapsed=167998.0us expected=168905.0us +- 844.5us, error 0.5%
(kms_flip:2905) DEBUG: inconsistent vblank ts/seq: last 302.882629/10988, current 303.050629/10998: elapsed=167998.0us expected=168905.0us
(kms_flip:2905) DEBUG: name = vblank
last_ts = 303.050629
last_received_ts = 303.050049
last_seq = 10998
current_ts = 303.218628
current_received_ts = 303.217987
current_seq = 11008
count = 36
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 303.050629/10998, current 303.218628/11008: elapsed=167988.0us expected=168905.0us +- 844.5us, error 0.5%
(kms_flip:2905) DEBUG: inconsistent vblank ts/seq: last 303.050629/10998, current 303.218628/11008: elapsed=167988.0us expected=168905.0us
(kms_flip:2905) DEBUG: name = vblank
last_ts = 303.218628
last_received_ts = 303.217987
last_seq = 11008
current_ts = 303.386627
current_received_ts = 303.386047
current_seq = 11018
count = 37
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 303.218628/11008, current 303.386627/11018: elapsed=167989.0us expected=168905.0us +- 844.5us, error 0.5%
(kms_flip:2905) DEBUG: inconsistent vblank ts/seq: last 303.218628/11008, current 303.386627/11018: elapsed=167989.0us expected=168905.0us
(kms_flip:2905) DEBUG: name = vblank
last_ts = 303.386627
last_received_ts = 303.386047
last_seq = 11018
current_ts = 303.554596
current_received_ts = 303.553925
current_seq = 11028
count = 38
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 303.386627/11018, current 303.554596/11028: elapsed=167997.0us expected=168905.0us +- 844.5us, error 0.5%
(kms_flip:2905) DEBUG: inconsistent vblank ts/seq: last 303.386627/11018, current 303.554596/11028: elapsed=167997.0us expected=168905.0us
(kms_flip:2905) DEBUG: name = vblank
last_ts = 303.554596
last_received_ts = 303.553925
last_seq = 11028
current_ts = 303.722595
current_received_ts = 303.721985
current_seq = 11038
count = 39
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 303.554596/11028, current 303.722595/11038: elapsed=167970.0us expected=168905.0us +- 844.5us, error 0.6%
(kms_flip:2905) DEBUG: inconsistent vblank ts/seq: last 303.554596/11028, current 303.722595/11038: elapsed=167970.0us expected=168905.0us
(kms_flip:2905) DEBUG: name = vblank
last_ts = 303.722595
last_received_ts = 303.721985
last_seq = 11038
current_ts = 303.890472
current_received_ts = 303.889862
current_seq = 11048
count = 40
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 303.722595/11038, current 303.890472/11048: elapsed=167900.0us expected=168905.0us +- 844.5us, error 0.6%
(kms_flip:2905) DEBUG: inconsistent vblank ts/seq: last 303.722595/11038, current 303.890472/11048: elapsed=167900.0us expected=168905.0us
(kms_flip:2905) DEBUG: name = vblank
last_ts = 303.890472
last_received_ts = 303.889862
last_seq = 11048
current_ts = 304.058380
current_received_ts = 304.057739
current_seq = 11058
count = 41
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 303.890472/11048, current 304.058380/11058: elapsed=167901.0us expected=168905.0us +- 844.5us, error 0.6%
(kms_flip:2905) DEBUG: inconsistent vblank ts/seq: last 303.890472/11048, current 304.058380/11058: elapsed=167901.0us expected=168905.0us
(kms_flip:2905) DEBUG: name = vblank
last_ts = 304.058380
last_received_ts = 304.057739
last_seq = 11058
current_ts = 304.226288
current_received_ts = 304.226318
current_seq = 11068
count = 42
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 304.058380/11058, current 304.226288/11068: elapsed=167912.0us expected=168905.0us +- 844.5us, error 0.6%
(kms_flip:2905) DEBUG: inconsistent vblank ts/seq: last 304.058380/11058, current 304.226288/11068: elapsed=167912.0us expected=168905.0us
(kms_flip:2905) DEBUG: name = vblank
last_ts = 304.226288
last_received_ts = 304.226318
last_seq = 11068
current_ts = 304.394165
current_received_ts = 304.393585
current_seq = 11078
count = 43
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 304.226288/11068, current 304.394165/11078: elapsed=167887.0us expected=168905.0us +- 844.5us, error 0.6%
(kms_flip:2905) DEBUG: inconsistent vblank ts/seq: last 304.226288/11068, current 304.394165/11078: elapsed=167887.0us expected=168905.0us
(kms_flip:2905) DEBUG: name = vblank
last_ts = 304.394165
last_received_ts = 304.393585
last_seq = 11078
current_ts = 304.562103
current_received_ts = 304.561432
current_seq = 11088
count = 44
seq_step = 10
(kms_flip:2905) DEBUG: vblank ts/seq: last 304.394165/11078, current 304.562103/11088: elapsed=167910.0us expected=168905.0us +- 844.5us, error 0.6%
(kms_flip:2905) DEBUG: inconsistent vblank ts/seq: last 304.394165/11078, current 304.562103/11088: elapsed=167910.0us expected=168905.0us
(kms_flip:2905) INFO: Event vblank: expected 448, counted 450, passrate = 75.56%, encoder type 1
(kms_flip:2905) DEBUG: dropped frames, expected 448, counted 450, passrate = 75.56%, encoder type 1
(kms_flip:2905) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1707:
(kms_flip:2905) CRITICAL: Failed assertion: !retried
(kms_flip:2905) CRITICAL: Last errno: 4, Interrupted system call
(kms_flip:2905) igt_core-INFO: Stack trace:
(kms_flip:2905) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:2905) igt_core-INFO:   #1 ../tests/kms_flip.c:428 run_test_on_crtc_set.constprop.0()
(kms_flip:2905) igt_core-INFO:   #2 ../tests/kms_flip.c:1887 run_test()
(kms_flip:2905) igt_core-INFO:   #3 ../tests/kms_flip.c:2156 __igt_unique____real_main2043()
(kms_flip:2905) igt_core-INFO:   #4 ../tests/kms_flip.c:2043 main()
(kms_flip:2905) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:2905) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:2905) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest A-VGA1: FAIL (19.035s)
Dmesg
<6> [284.662315] Console: switching to colour dummy device 80x25
<6> [284.663128] [IGT] kms_flip: executing
<7> [284.667757] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [284.668751] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [284.669270] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [284.673040] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:83:DP-1]
<7> [284.673050] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:83:DP-1]
<7> [284.675852] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.678747] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.681630] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.684519] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.687431] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.690322] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.693240] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.696218] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.699099] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.701960] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.704816] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.707713] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.710578] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.713528] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.716503] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.719409] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.722369] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.725470] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.728481] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.731392] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.734411] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.737396] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.740344] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.743337] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.746301] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.749272] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.752393] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.755332] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.758296] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.761227] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.764264] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.767356] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.767697] 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> [284.770344] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.773528] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.776445] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.779493] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.782397] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.785358] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.788396] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.791501] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.794387] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.797447] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.800451] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.803427] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.806523] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.809549] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.812492] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.815476] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.818497] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.821450] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.824332] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.827220] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.830168] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.833304] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.836252] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.839249] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.842290] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.845352] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.848353] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.851367] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.854548] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.857519] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.860527] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.863502] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.863898] 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> [284.863949] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:83:DP-1] disconnected
<7> [284.864315] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:72:VGA-1]
<7> [284.864326] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] [CONNECTOR:72:VGA-1] force=1
<7> [284.864744] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] ironlake hotplug adpa=0x83f40000, result 1
<7> [284.865096] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] CRT detected via hotplug
<7> [284.865660] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [284.866239] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK on first message, retry
<7> [284.866835] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [284.867239] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [284.867248] i915 0000:00:02.0: [drm:intel_crt_get_edid [i915]] CRT GMBUS EDID read failed, retry using GPIO bit-banging
<7> [284.867666] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] enabling bit-banging on i915 gmbus vga. force bit now 1
<7> [284.869191] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [284.869198] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] disabling bit-banging on i915 gmbus vga. force bit now 0
<7> [284.869533] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:72:VGA-1] probed modes:
<7> [284.869539] 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> [284.869544] 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> [284.869549] 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> [284.869553] 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> [284.869557] 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> [284.869580] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:75:HDMI-A-1]
<7> [284.869588] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:75:HDMI-A-1]
<7> [284.894775] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:75:HDMI-A-1] HDMI: DVI dual 0, max TMDS clock 590000 kHz
<7> [284.894785] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:75:HDMI-A-1] HDMI sink does deep color 30.
<7> [284.894789] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:75:HDMI-A-1] HDMI sink does deep color 36.
<7> [284.894793] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:75:HDMI-A-1] HDMI sink does deep color 48.
<7> [284.894796] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:75:HDMI-A-1] Assigning HDMI sink color depth as 16 bpc.
<7> [284.894800] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:75:HDMI-A-1] HDMI sink does YCRCB444 in deep color.
<7> [284.894805] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:75:HDMI-A-1] ELD monitor Mi TV
<7> [284.894809] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:75:HDMI-A-1] HDMI: latency present 0 0, video latency 0 0, audio latency 0 0
<7> [284.894814] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:75:HDMI-A-1] ELD size 36, SAD count 3
<7> [284.894989] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpd] NAK for addr: 0040 w(1)
<7> [284.895201] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpd] NAK on first message, retry
<7> [284.895597] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpd] NAK for addr: 0040 w(1)
<7> [284.895820] i915 0000:00:02.0: [drm:drm_dp_dual_mode_detect [drm_display_helper]] DP dual mode HDMI ID: (err -6)
<7> [284.897061] 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> [284.897075] 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> [284.897084] 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> [284.897093] 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> [284.897101] 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> [284.897109] 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> [284.897117] 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> [284.897125] 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> [284.897133] 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> [284.897142] 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> [284.897150] 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> [284.897158] 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> [284.897166] 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> [284.897174] 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> [284.897182] 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> [284.897190] 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> [284.897198] 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> [284.897206] 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> [284.897214] 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> [284.897227] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:75:HDMI-A-1] probed modes:
<7> [284.897232] 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> [284.897236] 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> [284.897240] 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> [284.897245] 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> [284.897249] 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> [284.897253] 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> [284.897257] 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> [284.897261] 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> [284.897266] 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> [284.897270] 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> [284.897274] 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> [284.897278] 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> [284.897282] 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> [284.897286] 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> [284.897291] 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> [284.897295] 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> [284.897299] 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> [284.897303] 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> [284.897307] 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> [284.897312] 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> [284.897316] 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> [284.897320] 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> [284.897324] 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> [284.897328] 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> [284.897332] 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> [284.897344] 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> [284.897349] 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> [284.897353] 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> [284.897357] 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> [284.897392] 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> [284.897398] 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> [284.897402] 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> [284.897406] 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> [284.897411] 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> [284.897415] 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> [284.897419] 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> [284.897424] 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> [284.897428] 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> [284.897432] 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> [284.897437] 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> [284.897441] 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> [284.897445] 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> [284.897450] 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> [284.897454] 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> [284.897458] 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> [284.897508] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:83:DP-1]
<7> [284.897514] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:83:DP-1]
<7> [284.900309] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.903187] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.906069] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.909004] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.911879] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.914745] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.917627] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.920492] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.923404] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.926286] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.929169] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.932045] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.934956] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.937818] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.940681] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.943554] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.946412] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.949268] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.952141] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.955054] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.957945] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.960812] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.963671] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.966540] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.969439] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.972370] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.975355] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.978384] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.981403] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.984404] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.987510] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.990423] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.990823] 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> [284.993460] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.996541] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [284.999501] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [285.002406] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [285.005366] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [285.008363] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [285.011422] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [285.014483] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [285.017438] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [285.020402] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [285.023340] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [285.026380] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [285.029430] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [285.032363] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [285.035388] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [285.038445] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [285.041382] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [285.044296] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [285.047246] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [285.050246] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [285.053218] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [285.056251] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [285.059170] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [285.062121] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [285.065123] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [285.068069] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [285.071048] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [285.074107] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [285.077025] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [285.080075] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [285.083074] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [285.086059] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [285.086571] 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> [285.086616] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:83:DP-1] disconnected
<6> [285.095454] [IGT] kms_flip: starting subtest wf_vblank-ts-check-interruptible
<6> [285.097114] [IGT] kms_flip: starting dynamic subtest A-VGA1
<7> [285.097736] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:90]
<7> [285.097884] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:92]
<7> [285.126962] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:52:pipe A]
<7> [285.127082] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [285.127461] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [285.127699] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [285.127936] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in has_pch_encoder (expected yes, found no)
<7> [285.128171] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in fdi_lanes (expected 1, found 0)
<7> [285.128510] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52: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> [285.128747] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in output_types (expected 0x00000002, found 0x00000000)
<7> [285.128981] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [285.129215] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1024, found 0)
<7> [285.129544] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 1344, found 0)
<7> [285.129778] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1024, found 0)
<7> [285.130011] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 1344, found 0)
<7> [285.130243] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 1048, found 0)
<7> [285.130489] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 1184, found 0)
<7> [285.130723] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 768, found 0)
<7> [285.130956] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 768, found 0)
<7> [285.131188] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 771, found 0)
<7> [285.131473] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 777, found 0)
<7> [285.131752] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 806, found 0)
<7> [285.131985] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 806, found 0)
<7> [285.132218] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1024, found 0)
<7> [285.132477] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 1344, found 0)
<7> [285.132711] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1024, found 0)
<7> [285.132943] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 1344, found 0)
<7> [285.133175] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 1048, found 0)
<7> [285.133510] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 1184, found 0)
<7> [285.133849] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 768, found 0)
<7> [285.134081] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 768, found 0)
<7> [285.134327] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 771, found 0)
<7> [285.134561] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 777, found 0)
<7> [285.134793] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 806, found 0)
<7> [285.135025] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 806, found 0)
<7> [285.135257] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [285.135518] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)
<7> [285.135752] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [285.135986] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [285.136220] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 64800, found 0)
<7> [285.136481] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 64800, found 0)
<7> [285.136715] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in port_clock (expected 64800, found 0)
<7> [285.136949] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:52:pipe A] fastset requirement not met, forcing full modeset
<7> [285.137183] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:52:pipe A] releasing PCH DPLL A
<7> [285.137487] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:52:pipe A] min cdclk: 72000 kHz -> 0 kHz
<7> [285.137730] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:52:pipe A] enable: no [modeset]
<7> [285.137956] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:34:primary A] fb: [NOFB], visible: no
<7> [285.138344] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [285.147535] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port B found
<7> [285.147781] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port C found
<7> [285.148007] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port D found
<7> [285.163738] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable PCH DPLL A (active 0x1, on? 1) for [CRTC:52:pipe A]
<7> [285.164248] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling PCH DPLL A
<7> [285.165002] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:73:CRT]
<7> [285.165542] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:HDMI D]
<7> [285.166087] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:82:DP D]
<7> [285.166612] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:72:VGA-1]
<7> [285.166994] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:52:pipe A]
<7> [285.167443] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:71:pipe B]
<7> [285.167543] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.enable (expected yes, found no)
<7> [285.167774] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.active (expected yes, found no)
<7> [285.168013] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in cpu_transcoder (expected 1, found -1)
<7> [285.168246] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in has_pch_encoder (expected yes, found no)
<7> [285.168501] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in fdi_lanes (expected 3, found 0)
<7> [285.168771] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71: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> [285.169007] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in lane_count (expected 4, found 0)
<7> [285.169240] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [285.169502] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [285.169777] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [285.170009] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [285.170242] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [285.170495] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [285.170770] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [285.171003] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [285.171235] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [285.171495] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [285.171769] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [285.172002] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [285.172234] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [285.172493] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [285.172769] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [285.173033] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [285.173295] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [285.173564] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [285.173796] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [285.174029] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [285.174358] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [285.174638] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [285.174872] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [285.175105] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [285.175368] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [285.175670] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [285.175948] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [285.176196] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [285.176469] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [285.176739] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [285.176966] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [285.177244] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in pipe_bpp (expected 36, found 0)
<7> [285.177589] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148800, found 0)
<7> [285.177863] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148800, found 0)
<7> [285.178090] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in port_clock (expected 223200, found 0)
<7> [285.178356] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [285.178646] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in infoframes.gcp (expected 0x00000006, found 0x00000000)
<7> [285.178882] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in avi infoframe
<7> [285.179114] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [285.179386] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [285.179389] i915 0000:00:02.0: colorspace: RGB
<7> [285.179392] i915 0000:00:02.0: scan mode: Underscan
<7> [285.179394] i915 0000:00:02.0: colorimetry: No Data
<7> [285.179396] i915 0000:00:02.0: picture aspect: 16:9
<7> [285.179398] i915 0000:00:02.0: active aspect: Same as Picture
<7> [285.179400] i915 0000:00:02.0: itc: No Data
<7> [285.179402] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [285.179405] i915 0000:00:02.0: quantization range: Default
<7> [285.179407] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [285.179409] i915 0000:00:02.0: video code: 16
<7> [285.179411] i915 0000:00:02.0: ycc quantization range: Full
<7> [285.179413] i915 0000:00:02.0: hdmi content type: Graphics
<7> [285.179415] i915 0000:00:02.0: pixel repeat: 0
<7> [285.179417] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [285.179420] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [285.179710] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in spd infoframe
<7> [285.179936] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [285.180162] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [285.180164] i915 0000:00:02.0: vendor: Intel
<7> [285.180166] i915 0000:00:02.0: product: Integrated gfx
<7> [285.180168] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [285.180170] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [285.180450] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hdmi infoframe
<7> [285.180740] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [285.180978] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [285.180981] i915 0000:00:02.0: empty frame
<7> [285.180983] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [285.181215] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:71:pipe B] fastset requirement not met, forcing full modeset
<7> [285.181483] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:71:pipe B] releasing PCH DPLL B
<7> [285.181783] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:71:pipe B] min cdclk: 165334 kHz -> 0 kHz
<7> [285.182028] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:71:pipe B] enable: no [modeset]
<7> [285.182297] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:53:primary B] fb: [NOFB], visible: no
<7> [285.182597] i915 0000:00:02.0: [drm:intel_audio_codec_disable [i915]] [CONNECTOR:75:HDMI-A-1][ENCODER:74:HDMI D] Disable audio codec on [CRTC:71:pipe B]
<7> [285.208373] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe B
<7> [285.227605] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port B found
<7> [285.228083] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port C found
<7> [285.228571] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port D found
<7> [285.239522] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable PCH DPLL B (active 0x2, on? 1) for [CRTC:71:pipe B]
<7> [285.240030] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling PCH DPLL B
<7> [285.240790] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:73:CRT]
<7> [285.241337] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:HDMI D]
<7> [285.241880] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:82:DP D]
<7> [285.242376] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:75:HDMI-A-1]
<7> [285.243032] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:71:pipe B]
<7> [285.243645] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:52:pipe A]
<7> [285.243677] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:72:VGA-1]
<7> [285.243768] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:72:VGA-1] Limiting target display pipe bpp to 24 (EDID bpp 0, max requested bpp 0, max platform bpp 36)
<7> [285.244011] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:52:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [285.244267] i915 0000:00:02.0: [drm:intel_fdi_atomic_check_link [i915]] checking fdi config on pipe A, lanes 1
<7> [285.244530] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [285.244764] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [285.244997] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [285.245259] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in has_pch_encoder (expected no, found yes)
<7> [285.245544] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in fdi_lanes (expected 0, found 1)
<7> [285.245777] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52: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> [285.246012] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000002)
<7> [285.246290] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [285.246524] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1024)
<7> [285.246757] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 1344)
<7> [285.246988] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1024)
<7> [285.247243] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 1344)
<7> [285.247586] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 1048)
<7> [285.247873] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 1184)
<7> [285.248104] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 768)
<7> [285.248359] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 768)
<7> [285.248592] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 771)
<7> [285.248824] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 777)
<7> [285.249055] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 806)
<7> [285.249309] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 806)
<7> [285.249542] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1024)
<7> [285.249780] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 1344)
<7> [285.250005] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1024)
<7> [285.250269] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 1344)
<7> [285.250502] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 1048)
<7> [285.250734] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 1184)
<7> [285.250966] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 768)
<7> [285.251198] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 768)
<7> [285.251458] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 771)
<7> [285.251691] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 777)
<7> [285.251923] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 806)
<7> [285.252155] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 806)
<7> [285.252423] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [285.252657] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [285.252889] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [285.253122] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [285.253368] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 64800)
<7> [285.253601] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 64800)
<7> [285.253844] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in port_clock (expected 0, found 64800)
<7> [285.254084] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:52:pipe A] fastset requirement not met, forcing full modeset
<7> [285.254367] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:52:pipe A] min cdclk: 0 kHz -> 72000 kHz
<7> [285.254601] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:52:pipe A] allocated PCH DPLL A
<7> [285.254834] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:52:pipe A] reserving PCH DPLL A
<7> [285.255068] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:52:pipe A] enable: yes [modeset]
<7> [285.255336] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: ANALOG (0x2), output format: RGB, sink format: RGB
<7> [285.255570] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [285.255801] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [285.256051] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [285.256305] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [285.256588] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [285.256859] 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> [285.257107] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [285.257363] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 1
<7> [285.257595] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [285.257826] 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> [285.258109] 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> [285.258397] 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> [285.258651] 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> [285.258878] 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> [285.259104] 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> [285.259402] 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> [285.259690] 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> [285.259929] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 64800, pipe src: 1024x768+0+0, pixel rate 64800, min cdclk 72000
<7> [285.260167] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 0, ips linetime: 0
<7> [285.260433] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [285.260685] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [285.260964] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: dpll: 0x84100010, dpll_md: 0x0, fp0: 0x10c09, fp1: 0x10c09
<7> [285.261232] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x3 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [285.261484] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [285.261708] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [285.261933] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [285.262166] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [285.262235] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling always-on
<7> [285.262489] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [285.262769] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [285.263013] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:34:primary A] fb: [FB:90] 1024x768 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [285.263292] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [285.263529] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1024.000000x768.000000+0.000000+0.000000 dst: 1024x768+0+0
<7> [285.264701] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling always-on
<7> [285.264954] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:73:CRT]
<7> [285.265175] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:HDMI D]
<7> [285.265438] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:82:DP D]
<7> [285.266256] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [285.267331] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x100
<7> [285.267586] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 1 done.
<7> [285.268469] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x200
<7> [285.268734] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 2 done.
<7> [285.268964] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train done.
<7> [285.269189] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable PCH DPLL A (active 0x1, on? 0) for [CRTC:52:pipe A]
<7> [285.269461] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling PCH DPLL A
<7> [285.321427] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:72:VGA-1]
<7> [285.321962] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:52:pipe A]
<7> [296.111506] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:52:pipe A]
<7> [296.111715] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [296.112276] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [296.112777] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [296.113280] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in has_pch_encoder (expected yes, found no)
<7> [296.113763] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in fdi_lanes (expected 1, found 0)
<7> [296.114293] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52: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> [296.114845] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in output_types (expected 0x00000002, found 0x00000000)
<7> [296.115439] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [296.115782] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1024, found 0)
<7> [296.116034] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 1344, found 0)
<7> [296.116297] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1024, found 0)
<7> [296.116534] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 1344, found 0)
<7> [296.116769] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 1048, found 0)
<7> [296.117008] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 1184, found 0)
<7> [296.117320] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 768, found 0)
<7> [296.117568] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 768, found 0)
<7> [296.117802] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 771, found 0)
<7> [296.118034] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 777, found 0)
<7> [296.118291] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 806, found 0)
<7> [296.118566] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 806, found 0)
<7> [296.118826] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1024, found 0)
<7> [296.119071] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 1344, found 0)
<7> [296.119328] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1024, found 0)
<7> [296.119562] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 1344, found 0)
<7> [296.119795] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 1048, found 0)
<7> [296.120065] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 1184, found 0)
<7> [296.120396] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 768, found 0)
<7> [296.120667] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 768, found 0)
<7> [296.120901] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 771, found 0)
<7> [296.121150] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 777, found 0)
<7> [296.121383] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 806, found 0)
<7> [296.121615] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 806, found 0)
<7> [296.121887] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [296.122172] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)
<7> [296.122431] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [296.122659] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [296.122885] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 64800, found 0)
<7> [296.123177] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 64800, found 0)
<7> [296.123430] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in port_clock (expected 64800, found 0)
<7> [296.123665] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:52:pipe A] fastset requirement not met, forcing full modeset
<7> [296.123899] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:52:pipe A] releasing PCH DPLL A
<7> [296.124201] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:52:pipe A] min cdclk: 72000 kHz -> 0 kHz
<7> [296.124438] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:52:pipe A] enable: no [modeset]
<7> [296.124674] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:34:primary A] fb: [NOFB], visible: no
<7> [296.125014] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [296.139518] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port B found
<7> [296.140032] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port C found
<7> [296.140545] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port D found
<7> [296.156693] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable PCH DPLL A (active 0x1, on? 1) for [CRTC:52:pipe A]
<7> [296.157208] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling PCH DPLL A
<7> [296.157954] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:73:CRT]
<7> [296.158429] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:HDMI D]
<7> [296.158932] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:82:DP D]
<7> [296.159435] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:72:VGA-1]
<7> [296.160068] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:52:pipe A]
<7> [296.160442] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:71:pipe B]
<7> [296.160582] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:52:pipe A]
<7> [296.160609] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:72:VGA-1]
<7> [296.160734] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:72:VGA-1] Limiting target display pipe bpp to 24 (EDID bpp 0, max requested bpp 0, max platform bpp 36)
<7> [296.160992] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:52:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [296.161227] i915 0000:00:02.0: [drm:intel_fdi_atomic_check_link [i915]] checking fdi config on pipe A, lanes 1
<7> [296.161455] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [296.161708] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [296.161948] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [296.162182] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in has_pch_encoder (expected no, found yes)
<7> [296.162414] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in fdi_lanes (expected 0, found 1)
<7> [296.162669] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52: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> [296.162904] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000002)
<7> [296.163136] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [296.163368] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1024)
<7> [296.163615] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 1344)
<7> [296.163862] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1024)
<7> [296.164095] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 1344)
<7> [296.164328] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 1048)
<7> [296.164560] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 1184)
<7> [296.164816] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 768)
<7> [296.165054] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 768)
<7> [296.165287] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 771)
<7> [296.165519] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 777)
<7> [296.165779] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 806)
<7> [296.166015] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 806)
<7> [296.166247] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1024)
<7> [296.166479] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 1344)
<7> [296.166723] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1024)
<7> [296.166969] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 1344)
<7> [296.167202] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 1048)
<7> [296.167434] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 1184)
<7> [296.167679] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 768)
<7> [296.167913] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 768)
<7> [296.168145] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 771)
<7> [296.168378] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 777)
<7> [296.168651] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 806)
<7> [296.168901] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 806)
<7> [296.169145] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [296.169377] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [296.169637] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [296.169886] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [296.170120] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 64800)
<7> [296.170352] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 64800)
<7> [296.170630] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in port_clock (expected 0, found 64800)
<7> [296.170881] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:52:pipe A] fastset requirement not met, forcing full modeset
<7> [296.171146] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:52:pipe A] min cdclk: 0 kHz -> 72000 kHz
<7> [296.171379] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:52:pipe A] allocated PCH DPLL A
<7> [296.171636] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:52:pipe A] reserving PCH DPLL A
<7> [296.171871] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:52:pipe A] enable: yes [modeset]
<7> [296.172103] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: ANALOG (0x2), output format: RGB, sink format: RGB
<7> [296.172335] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [296.172619] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [296.172852] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [296.173083] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [296.173313] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [296.173566] 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> [296.173799] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [296.174031] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 1
<7> [296.174261] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [296.174511] 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> [296.174747] 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> [296.174986] 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> [296.175217] 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> [296.175460] 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> [296.175695] 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> [296.175948] 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> [296.176180] 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> [296.176412] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 64800, pipe src: 1024x768+0+0, pixel rate 64800, min cdclk 72000
<7> [296.176672] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 0, ips linetime: 0
<7> [296.176918] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [296.177151] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [296.177382] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: dpll: 0x84100010, dpll_md: 0x0, fp0: 0x10c09, fp1: 0x10c09
<7> [296.177668] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x3 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [296.177668] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling always-on
<7> [296.177901] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [296.178132] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [296.178362] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [296.178631] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [296.178873] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [296.179104] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [296.179335] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:34:primary A] fb: [FB:90] 1024x768 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [296.179598] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [296.179842] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1024.000000x768.000000+0.000000+0.000000 dst: 1024x768+0+0
<7> [296.180113] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling always-on
<7> [296.180359] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:73:CRT]
<7> [296.180649] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:HDMI D]
<7> [296.180880] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:82:DP D]
<7> [296.181656] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [296.182734] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x100
<7> [296.182966] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 1 done.
<7> [296.183859] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x200
<7> [296.184081] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 2 done.
<7> [296.184313] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train done.
<7> [296.184574] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable PCH DPLL A (active 0x1, on? 0) for [CRTC:52:pipe A]
<7> [296.184826] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling PCH DPLL A
<7> [296.236793] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:72:VGA-1]
<7> [296.237313] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:52:pipe A]
<6> [304.135974] [IGT] kms_flip: finished subtest A-VGA1, FAIL
Created at 2026-03-10 16:21:31