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

Result: Fail

git-log-oneline i915_display_info8 igt_runner8 runtimes8 results8.json results8-i915-load.json guc_logs8.tar i915_display_info_post_exec8 boot8 dmesg8

DetailValue
Duration 19.58 seconds
Hostname 
shard-snb7
Igt-Version 
IGT-Version: 2.4-g85e2fd5b5 (x86_64) (Linux: 7.0.0-rc6-CI_DRM_18256-g779129c7868f+ x86_64)
Out 
Using IGT_SRANDOM=1775028688 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 16023.4us +- 9.674us accuracy 0.18%
vblank interval differs from modeline! expected 16665.6us, measured 16023us +- 9.674us, difference 642.2us (66.4 sigma)
Event vblank: expected 478, counted 470, passrate = 2.13%, encoder type 1
Expected frametime: 16666us; measured 16552.6us +- 6.742us accuracy 0.12%
vblank interval differs from modeline! expected 16665.6us, measured 16553us +- 6.742us, difference 113.0us (16.8 sigma)
Event vblank: expected 461, counted 460, passrate = 65.22%, encoder type 1
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../tests/kms_flip.c:428 run_test_on_crtc_set.constprop.0()
  #2 ../tests/kms_flip.c:1891 run_test()
  #3 ../tests/kms_flip.c:2162 __igt_unique____real_main2047()
  #4 ../tests/kms_flip.c:2047 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest A-VGA1: FAIL (19.580s)
Err 
Starting subtest: wf_vblank-ts-check-interruptible
Starting dynamic subtest: A-VGA1
(kms_flip:3143) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1711:
(kms_flip:3143) CRITICAL: Failed assertion: !retried
(kms_flip:3143) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest A-VGA1 failed.
**** DEBUG ****
(kms_flip:3143) DEBUG: name = vblank
last_ts = 331.717773
last_received_ts = 331.717102
last_seq = 5048
current_ts = 331.878845
current_received_ts = 331.878204
current_seq = 5058
count = 5
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 331.717773/5048, current 331.878845/5058: elapsed=161085.0us expected=160233.8us +- 801.2us, error 0.5%
(kms_flip:3143) DEBUG: inconsistent vblank ts/seq: last 331.717773/5048, current 331.878845/5058: elapsed=161085.0us expected=160233.8us
(kms_flip:3143) DEBUG: name = vblank
last_ts = 331.878845
last_received_ts = 331.878204
last_seq = 5058
current_ts = 332.040314
current_received_ts = 332.039703
current_seq = 5068
count = 6
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 331.878845/5058, current 332.040314/5068: elapsed=161462.0us expected=160233.8us +- 801.2us, error 0.8%
(kms_flip:3143) DEBUG: inconsistent vblank ts/seq: last 331.878845/5058, current 332.040314/5068: elapsed=161462.0us expected=160233.8us
(kms_flip:3143) DEBUG: name = vblank
last_ts = 332.040314
last_received_ts = 332.039703
last_seq = 5068
current_ts = 332.202179
current_received_ts = 332.201599
current_seq = 5078
count = 7
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 332.040314/5068, current 332.202179/5078: elapsed=161843.0us expected=160233.8us +- 801.2us, error 1.0%
(kms_flip:3143) DEBUG: inconsistent vblank ts/seq: last 332.040314/5068, current 332.202179/5078: elapsed=161843.0us expected=160233.8us
(kms_flip:3143) DEBUG: name = vblank
last_ts = 332.202179
last_received_ts = 332.201599
last_seq = 5078
current_ts = 332.364014
current_received_ts = 332.363373
current_seq = 5088
count = 8
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 332.202179/5078, current 332.364014/5088: elapsed=161846.0us expected=160233.8us +- 801.2us, error 1.0%
(kms_flip:3143) DEBUG: inconsistent vblank ts/seq: last 332.202179/5078, current 332.364014/5088: elapsed=161846.0us expected=160233.8us
(kms_flip:3143) DEBUG: name = vblank
last_ts = 332.364014
last_received_ts = 332.363373
last_seq = 5088
current_ts = 332.525848
current_received_ts = 332.525238
current_seq = 5098
count = 9
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 332.364014/5088, current 332.525848/5098: elapsed=161843.0us expected=160233.8us +- 801.2us, error 1.0%
(kms_flip:3143) DEBUG: inconsistent vblank ts/seq: last 332.364014/5088, current 332.525848/5098: elapsed=161843.0us expected=160233.8us
(kms_flip:3143) DEBUG: name = vblank
last_ts = 332.525848
last_received_ts = 332.525238
last_seq = 5098
current_ts = 332.687683
current_received_ts = 332.687012
current_seq = 5108
count = 10
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 332.525848/5098, current 332.687683/5108: elapsed=161837.0us expected=160233.8us +- 801.2us, error 1.0%
(kms_flip:3143) DEBUG: inconsistent vblank ts/seq: last 332.525848/5098, current 332.687683/5108: elapsed=161837.0us expected=160233.8us
(kms_flip:3143) DEBUG: name = vblank
last_ts = 332.687683
last_received_ts = 332.687012
last_seq = 5108
current_ts = 332.849548
current_received_ts = 332.848907
current_seq = 5118
count = 11
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 332.687683/5108, current 332.849548/5118: elapsed=161855.0us expected=160233.8us +- 801.2us, error 1.0%
(kms_flip:3143) DEBUG: inconsistent vblank ts/seq: last 332.687683/5108, current 332.849548/5118: elapsed=161855.0us expected=160233.8us
(kms_flip:3143) DEBUG: name = vblank
last_ts = 332.849548
last_received_ts = 332.848907
last_seq = 5118
current_ts = 333.011566
current_received_ts = 333.010956
current_seq = 5128
count = 12
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 332.849548/5118, current 333.011566/5128: elapsed=162036.0us expected=160233.8us +- 801.2us, error 1.1%
(kms_flip:3143) DEBUG: inconsistent vblank ts/seq: last 332.849548/5118, current 333.011566/5128: elapsed=162036.0us expected=160233.8us
(kms_flip:3143) DEBUG: name = vblank
last_ts = 333.011566
last_received_ts = 333.010956
last_seq = 5128
current_ts = 333.174072
current_received_ts = 333.173492
current_seq = 5138
count = 13
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 333.011566/5128, current 333.174072/5138: elapsed=162498.0us expected=160233.8us +- 801.2us, error 1.4%
(kms_flip:3143) DEBUG: inconsistent vblank ts/seq: last 333.011566/5128, current 333.174072/5138: elapsed=162498.0us expected=160233.8us
(kms_flip:3143) DEBUG: name = vblank
last_ts = 333.174072
last_received_ts = 333.173492
last_seq = 5138
current_ts = 333.336578
current_received_ts = 333.335968
current_seq = 5148
count = 14
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 333.174072/5138, current 333.336578/5148: elapsed=162499.0us expected=160233.8us +- 801.2us, error 1.4%
(kms_flip:3143) DEBUG: inconsistent vblank ts/seq: last 333.174072/5138, current 333.336578/5148: elapsed=162499.0us expected=160233.8us
(kms_flip:3143) DEBUG: name = vblank
last_ts = 333.336578
last_received_ts = 333.335968
last_seq = 5148
current_ts = 333.499084
current_received_ts = 333.498474
current_seq = 5158
count = 15
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 333.336578/5148, current 333.499084/5158: elapsed=162495.0us expected=160233.8us +- 801.2us, error 1.4%
(kms_flip:3143) DEBUG: inconsistent vblank ts/seq: last 333.336578/5148, current 333.499084/5158: elapsed=162495.0us expected=160233.8us
(kms_flip:3143) DEBUG: name = vblank
last_ts = 333.499084
last_received_ts = 333.498474
last_seq = 5158
current_ts = 333.661591
current_received_ts = 333.660919
current_seq = 5168
count = 16
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 333.499084/5158, current 333.661591/5168: elapsed=162515.0us expected=160233.8us +- 801.2us, error 1.4%
(kms_flip:3143) DEBUG: inconsistent vblank ts/seq: last 333.499084/5158, current 333.661591/5168: elapsed=162515.0us expected=160233.8us
(kms_flip:3143) DEBUG: name = vblank
last_ts = 333.661591
last_received_ts = 333.660919
last_seq = 5168
current_ts = 333.824066
current_received_ts = 333.823425
current_seq = 5178
count = 17
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 333.661591/5168, current 333.824066/5178: elapsed=162482.0us expected=160233.8us +- 801.2us, error 1.4%
(kms_flip:3143) DEBUG: inconsistent vblank ts/seq: last 333.661591/5168, current 333.824066/5178: elapsed=162482.0us expected=160233.8us
(kms_flip:3143) DEBUG: name = vblank
last_ts = 333.824066
last_received_ts = 333.823425
last_seq = 5178
current_ts = 333.986664
current_received_ts = 333.986053
current_seq = 5188
count = 18
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 333.824066/5178, current 333.986664/5188: elapsed=162595.0us expected=160233.8us +- 801.2us, error 1.5%
(kms_flip:3143) DEBUG: inconsistent vblank ts/seq: last 333.824066/5178, current 333.986664/5188: elapsed=162595.0us expected=160233.8us
(kms_flip:3143) DEBUG: name = vblank
last_ts = 333.986664
last_received_ts = 333.986053
last_seq = 5188
current_ts = 334.149750
current_received_ts = 334.149078
current_seq = 5198
count = 19
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 333.986664/5188, current 334.149750/5198: elapsed=163081.0us expected=160233.8us +- 801.2us, error 1.8%
(kms_flip:3143) DEBUG: inconsistent vblank ts/seq: last 333.986664/5188, current 334.149750/5198: elapsed=163081.0us expected=160233.8us
(kms_flip:3143) DEBUG: name = vblank
last_ts = 334.149750
last_received_ts = 334.149078
last_seq = 5198
current_ts = 334.312805
current_received_ts = 334.312195
current_seq = 5208
count = 20
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 334.149750/5198, current 334.312805/5208: elapsed=163069.0us expected=160233.8us +- 801.2us, error 1.8%
(kms_flip:3143) DEBUG: inconsistent vblank ts/seq: last 334.149750/5198, current 334.312805/5208: elapsed=163069.0us expected=160233.8us
(kms_flip:3143) DEBUG: name = vblank
last_ts = 334.312805
last_received_ts = 334.312195
last_seq = 5208
current_ts = 334.475891
current_received_ts = 334.475220
current_seq = 5218
count = 21
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 334.312805/5208, current 334.475891/5218: elapsed=163071.0us expected=160233.8us +- 801.2us, error 1.8%
(kms_flip:3143) DEBUG: inconsistent vblank ts/seq: last 334.312805/5208, current 334.475891/5218: elapsed=163071.0us expected=160233.8us
(kms_flip:3143) DEBUG: name = vblank
last_ts = 334.475891
last_received_ts = 334.475220
last_seq = 5218
current_ts = 334.638947
current_received_ts = 334.638336
current_seq = 5228
count = 22
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 334.475891/5218, current 334.638947/5228: elapsed=163061.0us expected=160233.8us +- 801.2us, error 1.8%
(kms_flip:3143) DEBUG: inconsistent vblank ts/seq: last 334.475891/5218, current 334.638947/5228: elapsed=163061.0us expected=160233.8us
(kms_flip:3143) DEBUG: name = vblank
last_ts = 334.638947
last_received_ts = 334.638336
last_seq = 5228
current_ts = 334.802002
current_received_ts = 334.801422
current_seq = 5238
count = 23
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 334.638947/5228, current 334.802002/5238: elapsed=163069.0us expected=160233.8us +- 801.2us, error 1.8%
(kms_flip:3143) DEBUG: inconsistent vblank ts/seq: last 334.638947/5228, current 334.802002/5238: elapsed=163069.0us expected=160233.8us
(kms_flip:3143) DEBUG: name = vblank
last_ts = 334.802002
last_received_ts = 334.801422
last_seq = 5238
current_ts = 334.965118
current_received_ts = 334.964508
current_seq = 5248
count = 24
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 334.802002/5238, current 334.965118/5248: elapsed=163093.0us expected=160233.8us +- 801.2us, error 1.8%
(kms_flip:3143) DEBUG: inconsistent vblank ts/seq: last 334.802002/5238, current 334.965118/5248: elapsed=163093.0us expected=160233.8us
(kms_flip:3143) DEBUG: name = vblank
last_ts = 334.965118
last_received_ts = 334.964508
last_seq = 5248
current_ts = 335.128693
current_received_ts = 335.128082
current_seq = 5258
count = 25
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 334.965118/5248, current 335.128693/5258: elapsed=163570.0us expected=160233.8us +- 801.2us, error 2.1%
(kms_flip:3143) DEBUG: inconsistent vblank ts/seq: last 334.965118/5248, current 335.128693/5258: elapsed=163570.0us expected=160233.8us
(kms_flip:3143) DEBUG: name = vblank
last_ts = 335.128693
last_received_ts = 335.128082
last_seq = 5258
current_ts = 335.292267
current_received_ts = 335.291595
current_seq = 5268
count = 26
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 335.128693/5258, current 335.292267/5268: elapsed=163590.0us expected=160233.8us +- 801.2us, error 2.1%
(kms_flip:3143) DEBUG: inconsistent vblank ts/seq: last 335.128693/5258, current 335.292267/5268: elapsed=163590.0us expected=160233.8us
(kms_flip:3143) DEBUG: name = vblank
last_ts = 335.292267
last_received_ts = 335.291595
last_seq = 5268
current_ts = 335.455841
current_received_ts = 335.455170
current_seq = 5278
count = 27
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 335.292267/5268, current 335.455841/5278: elapsed=163571.0us expected=160233.8us +- 801.2us, error 2.1%
(kms_flip:3143) DEBUG: inconsistent vblank ts/seq: last 335.292267/5268, current 335.455841/5278: elapsed=163571.0us expected=160233.8us
(kms_flip:3143) DEBUG: name = vblank
last_ts = 335.455841
last_received_ts = 335.455170
last_seq = 5278
current_ts = 335.619415
current_received_ts = 335.618713
current_seq = 5288
count = 28
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 335.455841/5278, current 335.619415/5288: elapsed=163573.0us expected=160233.8us +- 801.2us, error 2.1%
(kms_flip:3143) DEBUG: inconsistent vblank ts/seq: last 335.455841/5278, current 335.619415/5288: elapsed=163573.0us expected=160233.8us
(kms_flip:3143) DEBUG: name = vblank
last_ts = 335.619415
last_received_ts = 335.618713
last_seq = 5288
current_ts = 335.782990
current_received_ts = 335.782349
current_seq = 5298
count = 29
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 335.619415/5288, current 335.782990/5298: elapsed=163565.0us expected=160233.8us +- 801.2us, error 2.1%
(kms_flip:3143) DEBUG: inconsistent vblank ts/seq: last 335.619415/5288, current 335.782990/5298: elapsed=163565.0us expected=160233.8us
(kms_flip:3143) DEBUG: name = vblank
last_ts = 335.782990
last_received_ts = 335.782349
last_seq = 5298
current_ts = 335.946564
current_received_ts = 335.945953
current_seq = 5308
count = 30
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 335.782990/5298, current 335.946564/5308: elapsed=163578.0us expected=160233.8us +- 801.2us, error 2.1%
(kms_flip:3143) DEBUG: inconsistent vblank ts/seq: last 335.782990/5298, current 335.946564/5308: elapsed=163578.0us expected=160233.8us
(kms_flip:3143) DEBUG: name = vblank
last_ts = 335.946564
last_received_ts = 335.945953
last_seq = 5308
current_ts = 336.110535
current_received_ts = 336.109894
current_seq = 5318
count = 31
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 335.946564/5308, current 336.110535/5318: elapsed=163964.0us expected=160233.8us +- 801.2us, error 2.3%
(kms_flip:3143) DEBUG: inconsistent vblank ts/seq: last 335.946564/5308, current 336.110535/5318: elapsed=163964.0us expected=160233.8us
(kms_flip:3143) DEBUG: name = vblank
last_ts = 336.110535
last_received_ts = 336.109894
last_seq = 5318
current_ts = 336.274536
current_received_ts = 336.273926
current_seq = 5328
count = 32
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 336.110535/5318, current 336.274536/5328: elapsed=164012.0us expected=160233.8us +- 801.2us, error 2.4%
(kms_flip:3143) DEBUG: inconsistent vblank ts/seq: last 336.110535/5318, current 336.274536/5328: elapsed=164012.0us expected=160233.8us
(kms_flip:3143) DEBUG: name = vblank
last_ts = 336.274536
last_received_ts = 336.273926
last_seq = 5328
current_ts = 336.438568
current_received_ts = 336.437866
current_seq = 5338
count = 33
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 336.274536/5328, current 336.438568/5338: elapsed=164028.0us expected=160233.8us +- 801.2us, error 2.4%
(kms_flip:3143) DEBUG: inconsistent vblank ts/seq: last 336.274536/5328, current 336.438568/5338: elapsed=164028.0us expected=160233.8us
(kms_flip:3143) DEBUG: name = vblank
last_ts = 336.438568
last_received_ts = 336.437866
last_seq = 5338
current_ts = 336.602570
current_received_ts = 336.602051
current_seq = 5348
count = 34
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 336.438568/5338, current 336.602570/5348: elapsed=163997.0us expected=160233.8us +- 801.2us, error 2.3%
(kms_flip:3143) DEBUG: inconsistent vblank ts/seq: last 336.438568/5338, current 336.602570/5348: elapsed=163997.0us expected=160233.8us
(kms_flip:3143) DEBUG: name = vblank
last_ts = 336.602570
last_received_ts = 336.602051
last_seq = 5348
current_ts = 336.766571
current_received_ts = 336.765930
current_seq = 5358
count = 35
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 336.602570/5348, current 336.766571/5358: elapsed=164017.0us expected=160233.8us +- 801.2us, error 2.4%
(kms_flip:3143) DEBUG: inconsistent vblank ts/seq: last 336.602570/5348, current 336.766571/5358: elapsed=164017.0us expected=160233.8us
(kms_flip:3143) DEBUG: name = vblank
last_ts = 336.766571
last_received_ts = 336.765930
last_seq = 5358
current_ts = 336.930573
current_received_ts = 336.930054
current_seq = 5368
count = 36
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 336.766571/5358, current 336.930573/5368: elapsed=164002.0us expected=160233.8us +- 801.2us, error 2.4%
(kms_flip:3143) DEBUG: inconsistent vblank ts/seq: last 336.766571/5358, current 336.930573/5368: elapsed=164002.0us expected=160233.8us
(kms_flip:3143) DEBUG: name = vblank
last_ts = 336.930573
last_received_ts = 336.930054
last_seq = 5368
current_ts = 337.094910
current_received_ts = 337.094269
current_seq = 5378
count = 37
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 336.930573/5368, current 337.094910/5378: elapsed=164330.0us expected=160233.8us +- 801.2us, error 2.6%
(kms_flip:3143) DEBUG: inconsistent vblank ts/seq: last 336.930573/5368, current 337.094910/5378: elapsed=164330.0us expected=160233.8us
(kms_flip:3143) DEBUG: name = vblank
last_ts = 337.094910
last_received_ts = 337.094269
last_seq = 5378
current_ts = 337.259308
current_received_ts = 337.258606
current_seq = 5388
count = 38
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 337.094910/5378, current 337.259308/5388: elapsed=164396.0us expected=160233.8us +- 801.2us, error 2.6%
(kms_flip:3143) DEBUG: inconsistent vblank ts/seq: last 337.094910/5378, current 337.259308/5388: elapsed=164396.0us expected=160233.8us
(kms_flip:3143) DEBUG: name = vblank
last_ts = 337.259308
last_received_ts = 337.258606
last_seq = 5388
current_ts = 337.423706
current_received_ts = 337.423096
current_seq = 5398
count = 39
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 337.259308/5388, current 337.423706/5398: elapsed=164398.0us expected=160233.8us +- 801.2us, error 2.6%
(kms_flip:3143) DEBUG: inconsistent vblank ts/seq: last 337.259308/5388, current 337.423706/5398: elapsed=164398.0us expected=160233.8us
(kms_flip:3143) DEBUG: name = vblank
last_ts = 337.423706
last_received_ts = 337.423096
last_seq = 5398
current_ts = 337.588104
current_received_ts = 337.587463
current_seq = 5408
count = 40
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 337.423706/5398, current 337.588104/5408: elapsed=164398.0us expected=160233.8us +- 801.2us, error 2.6%
(kms_flip:3143) DEBUG: inconsistent vblank ts/seq: last 337.423706/5398, current 337.588104/5408: elapsed=164398.0us expected=160233.8us
(kms_flip:3143) DEBUG: name = vblank
last_ts = 337.588104
last_received_ts = 337.587463
last_seq = 5408
current_ts = 337.752502
current_received_ts = 337.751770
current_seq = 5418
count = 41
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 337.588104/5408, current 337.752502/5418: elapsed=164400.0us expected=160233.8us +- 801.2us, error 2.6%
(kms_flip:3143) DEBUG: inconsistent vblank ts/seq: last 337.588104/5408, current 337.752502/5418: elapsed=164400.0us expected=160233.8us
(kms_flip:3143) DEBUG: name = vblank
last_ts = 337.752502
last_received_ts = 337.751770
last_seq = 5418
current_ts = 337.916901
current_received_ts = 337.916260
current_seq = 5428
count = 42
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 337.752502/5418, current 337.916901/5428: elapsed=164391.0us expected=160233.8us +- 801.2us, error 2.6%
(kms_flip:3143) DEBUG: inconsistent vblank ts/seq: last 337.752502/5418, current 337.916901/5428: elapsed=164391.0us expected=160233.8us
(kms_flip:3143) DEBUG: name = vblank
last_ts = 337.916901
last_received_ts = 337.916260
last_seq = 5428
current_ts = 338.081543
current_received_ts = 338.080902
current_seq = 5438
count = 43
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 337.916901/5428, current 338.081543/5438: elapsed=164646.0us expected=160233.8us +- 801.2us, error 2.8%
(kms_flip:3143) DEBUG: inconsistent vblank ts/seq: last 337.916901/5428, current 338.081543/5438: elapsed=164646.0us expected=160233.8us
(kms_flip:3143) DEBUG: name = vblank
last_ts = 338.081543
last_received_ts = 338.080902
last_seq = 5438
current_ts = 338.246277
current_received_ts = 338.245636
current_seq = 5448
count = 44
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 338.081543/5438, current 338.246277/5448: elapsed=164739.0us expected=160233.8us +- 801.2us, error 2.8%
(kms_flip:3143) DEBUG: inconsistent vblank ts/seq: last 338.081543/5438, current 338.246277/5448: elapsed=164739.0us expected=160233.8us
(kms_flip:3143) DEBUG: name = vblank
last_ts = 338.246277
last_received_ts = 338.245636
last_seq = 5448
current_ts = 338.411011
current_received_ts = 338.410370
current_seq = 5458
count = 45
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 338.246277/5448, current 338.411011/5458: elapsed=164741.0us expected=160233.8us +- 801.2us, error 2.8%
(kms_flip:3143) DEBUG: inconsistent vblank ts/seq: last 338.246277/5448, current 338.411011/5458: elapsed=164741.0us expected=160233.8us
(kms_flip:3143) DEBUG: name = vblank
last_ts = 338.411011
last_received_ts = 338.410370
last_seq = 5458
current_ts = 338.575714
current_received_ts = 338.575073
current_seq = 5468
count = 46
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 338.411011/5458, current 338.575714/5468: elapsed=164713.0us expected=160233.8us +- 801.2us, error 2.8%
(kms_flip:3143) DEBUG: inconsistent vblank ts/seq: last 338.411011/5458, current 338.575714/5468: elapsed=164713.0us expected=160233.8us
(kms_flip:3143) INFO: Event vblank: expected 478, counted 470, passrate = 2.13%, encoder type 1
(kms_flip:3143) DEBUG: dropped frames, expected 478, counted 470, passrate = 2.13%, encoder type 1
(kms_flip:3143) DEBUG: Retrying without a hotplug event
(kms_flip:3143) DEBUG: No stale events found
(kms_flip:3143) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0
(kms_flip:3143) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0
(kms_flip:3143) INFO: Expected frametime: 16666us; measured 16552.6us +- 6.742us accuracy 0.12%
(kms_flip:3143) INFO: vblank interval differs from modeline! expected 16665.6us, measured 16553us +- 6.742us, difference 113.0us (16.8 sigma)
(kms_flip:3143) DEBUG: name = vblank
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 342.166290
current_received_ts = 342.165649
current_seq = 5501
count = 0
seq_step = 10
(kms_flip:3143) DEBUG: name = vblank
last_ts = 342.166290
last_received_ts = 342.165649
last_seq = 5501
current_ts = 342.332001
current_received_ts = 342.331390
current_seq = 5511
count = 1
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 342.166290/5501, current 342.332001/5511: elapsed=165719.0us expected=165526.2us +- 827.6us, error 0.1%
(kms_flip:3143) DEBUG: name = vblank
last_ts = 342.332001
last_received_ts = 342.331390
last_seq = 5511
current_ts = 342.497711
current_received_ts = 342.497101
current_seq = 5521
count = 2
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 342.332001/5511, current 342.497711/5521: elapsed=165694.0us expected=165526.2us +- 827.6us, error 0.1%
(kms_flip:3143) DEBUG: name = vblank
last_ts = 342.497711
last_received_ts = 342.497101
last_seq = 5521
current_ts = 342.663391
current_received_ts = 342.662811
current_seq = 5531
count = 3
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 342.497711/5521, current 342.663391/5531: elapsed=165707.0us expected=165526.2us +- 827.6us, error 0.1%
(kms_flip:3143) DEBUG: name = vblank
last_ts = 342.663391
last_received_ts = 342.662811
last_seq = 5531
current_ts = 342.829102
current_received_ts = 342.828400
current_seq = 5541
count = 4
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 342.663391/5531, current 342.829102/5541: elapsed=165704.0us expected=165526.2us +- 827.6us, error 0.1%
(kms_flip:3143) DEBUG: name = vblank
last_ts = 342.829102
last_received_ts = 342.828400
last_seq = 5541
current_ts = 342.994873
current_received_ts = 342.994232
current_seq = 5551
count = 5
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 342.829102/5541, current 342.994873/5551: elapsed=165749.0us expected=165526.2us +- 827.6us, error 0.1%
(kms_flip:3143) DEBUG: name = vblank
last_ts = 342.994873
last_received_ts = 342.994232
last_seq = 5551
current_ts = 343.160736
current_received_ts = 343.160065
current_seq = 5561
count = 6
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 342.994873/5551, current 343.160736/5561: elapsed=165877.0us expected=165526.2us +- 827.6us, error 0.2%
(kms_flip:3143) DEBUG: name = vblank
last_ts = 343.160736
last_received_ts = 343.160065
last_seq = 5561
current_ts = 343.326599
current_received_ts = 343.326019
current_seq = 5571
count = 7
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 343.160736/5561, current 343.326599/5571: elapsed=165877.0us expected=165526.2us +- 827.6us, error 0.2%
(kms_flip:3143) DEBUG: name = vblank
last_ts = 343.326599
last_received_ts = 343.326019
last_seq = 5571
current_ts = 343.492493
current_received_ts = 343.491852
current_seq = 5581
count = 8
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 343.326599/5571, current 343.492493/5581: elapsed=165889.0us expected=165526.2us +- 827.6us, error 0.2%
(kms_flip:3143) DEBUG: name = vblank
last_ts = 343.492493
last_received_ts = 343.491852
last_seq = 5581
current_ts = 343.658356
current_received_ts = 343.657684
current_seq = 5591
count = 9
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 343.492493/5581, current 343.658356/5591: elapsed=165865.0us expected=165526.2us +- 827.6us, error 0.2%
(kms_flip:3143) DEBUG: name = vblank
last_ts = 343.658356
last_received_ts = 343.657684
last_seq = 5591
current_ts = 343.824249
current_received_ts = 343.823639
current_seq = 5601
count = 10
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 343.658356/5591, current 343.824249/5601: elapsed=165882.0us expected=165526.2us +- 827.6us, error 0.2%
(kms_flip:3143) DEBUG: name = vblank
last_ts = 343.824249
last_received_ts = 343.823639
last_seq = 5601
current_ts = 343.990143
current_received_ts = 343.989532
current_seq = 5611
count = 11
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 343.824249/5601, current 343.990143/5611: elapsed=165903.0us expected=165526.2us +- 827.6us, error 0.2%
(kms_flip:3143) DEBUG: name = vblank
last_ts = 343.990143
last_received_ts = 343.989532
last_seq = 5611
current_ts = 344.156189
current_received_ts = 344.155579
current_seq = 5621
count = 12
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 343.990143/5611, current 344.156189/5621: elapsed=166044.0us expected=165526.2us +- 827.6us, error 0.3%
(kms_flip:3143) DEBUG: name = vblank
last_ts = 344.156189
last_received_ts = 344.155579
last_seq = 5621
current_ts = 344.322235
current_received_ts = 344.321564
current_seq = 5631
count = 13
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 344.156189/5621, current 344.322235/5631: elapsed=166032.0us expected=165526.2us +- 827.6us, error 0.3%
(kms_flip:3143) DEBUG: name = vblank
last_ts = 344.322235
last_received_ts = 344.321564
last_seq = 5631
current_ts = 344.488251
current_received_ts = 344.487640
current_seq = 5641
count = 14
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 344.322235/5631, current 344.488251/5641: elapsed=166012.0us expected=165526.2us +- 827.6us, error 0.3%
(kms_flip:3143) DEBUG: name = vblank
last_ts = 344.488251
last_received_ts = 344.487640
last_seq = 5641
current_ts = 344.654266
current_received_ts = 344.653656
current_seq = 5651
count = 15
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 344.488251/5641, current 344.654266/5651: elapsed=166033.0us expected=165526.2us +- 827.6us, error 0.3%
(kms_flip:3143) DEBUG: name = vblank
last_ts = 344.654266
last_received_ts = 344.653656
last_seq = 5651
current_ts = 344.820312
current_received_ts = 344.819702
current_seq = 5661
count = 16
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 344.654266/5651, current 344.820312/5661: elapsed=166029.0us expected=165526.2us +- 827.6us, error 0.3%
(kms_flip:3143) DEBUG: name = vblank
last_ts = 344.820312
last_received_ts = 344.819702
last_seq = 5661
current_ts = 344.986359
current_received_ts = 344.985718
current_seq = 5671
count = 17
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 344.820312/5661, current 344.986359/5671: elapsed=166051.0us expected=165526.2us +- 827.6us, error 0.3%
(kms_flip:3143) DEBUG: name = vblank
last_ts = 344.986359
last_received_ts = 344.985718
last_seq = 5671
current_ts = 345.152527
current_received_ts = 345.151886
current_seq = 5681
count = 18
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 344.986359/5671, current 345.152527/5681: elapsed=166173.0us expected=165526.2us +- 827.6us, error 0.4%
(kms_flip:3143) DEBUG: name = vblank
last_ts = 345.152527
last_received_ts = 345.151886
last_seq = 5681
current_ts = 345.318665
current_received_ts = 345.318146
current_seq = 5691
count = 19
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 345.152527/5681, current 345.318665/5691: elapsed=166151.0us expected=165526.2us +- 827.6us, error 0.4%
(kms_flip:3143) DEBUG: name = vblank
last_ts = 345.318665
last_received_ts = 345.318146
last_seq = 5691
current_ts = 345.484863
current_received_ts = 345.484192
current_seq = 5701
count = 20
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 345.318665/5691, current 345.484863/5701: elapsed=166176.0us expected=165526.2us +- 827.6us, error 0.4%
(kms_flip:3143) DEBUG: name = vblank
last_ts = 345.484863
last_received_ts = 345.484192
last_seq = 5701
current_ts = 345.651001
current_received_ts = 345.650360
current_seq = 5711
count = 21
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 345.484863/5701, current 345.651001/5711: elapsed=166162.0us expected=165526.2us +- 827.6us, error 0.4%
(kms_flip:3143) DEBUG: name = vblank
last_ts = 345.651001
last_received_ts = 345.650360
last_seq = 5711
current_ts = 345.817169
current_received_ts = 345.816589
current_seq = 5721
count = 22
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 345.651001/5711, current 345.817169/5721: elapsed=166145.0us expected=165526.2us +- 827.6us, error 0.4%
(kms_flip:3143) DEBUG: name = vblank
last_ts = 345.817169
last_received_ts = 345.816589
last_seq = 5721
current_ts = 345.983337
current_received_ts = 345.982727
current_seq = 5731
count = 23
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 345.817169/5721, current 345.983337/5731: elapsed=166180.0us expected=165526.2us +- 827.6us, error 0.4%
(kms_flip:3143) DEBUG: name = vblank
last_ts = 345.983337
last_received_ts = 345.982727
last_seq = 5731
current_ts = 346.149628
current_received_ts = 346.149048
current_seq = 5741
count = 24
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 345.983337/5731, current 346.149628/5741: elapsed=166276.0us expected=165526.2us +- 827.6us, error 0.5%
(kms_flip:3143) DEBUG: name = vblank
last_ts = 346.149628
last_received_ts = 346.149048
last_seq = 5741
current_ts = 346.315887
current_received_ts = 346.315277
current_seq = 5751
count = 25
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 346.149628/5741, current 346.315887/5751: elapsed=166276.0us expected=165526.2us +- 827.6us, error 0.5%
(kms_flip:3143) DEBUG: name = vblank
last_ts = 346.315887
last_received_ts = 346.315277
last_seq = 5751
current_ts = 346.482178
current_received_ts = 346.481506
current_seq = 5761
count = 26
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 346.315887/5751, current 346.482178/5761: elapsed=166280.0us expected=165526.2us +- 827.6us, error 0.5%
(kms_flip:3143) DEBUG: name = vblank
last_ts = 346.482178
last_received_ts = 346.481506
last_seq = 5761
current_ts = 346.648438
current_received_ts = 346.647827
current_seq = 5771
count = 27
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 346.482178/5761, current 346.648438/5771: elapsed=166273.0us expected=165526.2us +- 827.6us, error 0.5%
(kms_flip:3143) DEBUG: name = vblank
last_ts = 346.648438
last_received_ts = 346.647827
last_seq = 5771
current_ts = 346.814728
current_received_ts = 346.814087
current_seq = 5781
count = 28
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 346.648438/5771, current 346.814728/5781: elapsed=166281.0us expected=165526.2us +- 827.6us, error 0.5%
(kms_flip:3143) DEBUG: name = vblank
last_ts = 346.814728
last_received_ts = 346.814087
last_seq = 5781
current_ts = 346.981018
current_received_ts = 346.980408
current_seq = 5791
count = 29
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 346.814728/5781, current 346.981018/5791: elapsed=166299.0us expected=165526.2us +- 827.6us, error 0.5%
(kms_flip:3143) DEBUG: name = vblank
last_ts = 346.981018
last_received_ts = 346.980408
last_seq = 5791
current_ts = 347.147400
current_received_ts = 347.146790
current_seq = 5801
count = 30
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 346.981018/5791, current 347.147400/5801: elapsed=166367.0us expected=165526.2us +- 827.6us, error 0.5%
(kms_flip:3143) DEBUG: inconsistent vblank ts/seq: last 346.981018/5791, current 347.147400/5801: elapsed=166367.0us expected=165526.2us
(kms_flip:3143) DEBUG: name = vblank
last_ts = 347.147400
last_received_ts = 347.146790
last_seq = 5801
current_ts = 347.313782
current_received_ts = 347.313049
current_seq = 5811
count = 31
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 347.147400/5801, current 347.313782/5811: elapsed=166394.0us expected=165526.2us +- 827.6us, error 0.5%
(kms_flip:3143) DEBUG: inconsistent vblank ts/seq: last 347.147400/5801, current 347.313782/5811: elapsed=166394.0us expected=165526.2us
(kms_flip:3143) DEBUG: name = vblank
last_ts = 347.313782
last_received_ts = 347.313049
last_seq = 5811
current_ts = 347.480164
current_received_ts = 347.479492
current_seq = 5821
count = 32
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 347.313782/5811, current 347.480164/5821: elapsed=166364.0us expected=165526.2us +- 827.6us, error 0.5%
(kms_flip:3143) DEBUG: inconsistent vblank ts/seq: last 347.313782/5811, current 347.480164/5821: elapsed=166364.0us expected=165526.2us
(kms_flip:3143) DEBUG: name = vblank
last_ts = 347.480164
last_received_ts = 347.479492
last_seq = 5821
current_ts = 347.646515
current_received_ts = 347.645844
current_seq = 5831
count = 33
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 347.480164/5821, current 347.646515/5831: elapsed=166379.0us expected=165526.2us +- 827.6us, error 0.5%
(kms_flip:3143) DEBUG: inconsistent vblank ts/seq: last 347.480164/5821, current 347.646515/5831: elapsed=166379.0us expected=165526.2us
(kms_flip:3143) DEBUG: name = vblank
last_ts = 347.646515
last_received_ts = 347.645844
last_seq = 5831
current_ts = 347.812897
current_received_ts = 347.812286
current_seq = 5841
count = 34
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 347.646515/5831, current 347.812897/5841: elapsed=166381.0us expected=165526.2us +- 827.6us, error 0.5%
(kms_flip:3143) DEBUG: inconsistent vblank ts/seq: last 347.646515/5831, current 347.812897/5841: elapsed=166381.0us expected=165526.2us
(kms_flip:3143) DEBUG: name = vblank
last_ts = 347.812897
last_received_ts = 347.812286
last_seq = 5841
current_ts = 347.979309
current_received_ts = 347.978729
current_seq = 5851
count = 35
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 347.812897/5841, current 347.979309/5851: elapsed=166385.0us expected=165526.2us +- 827.6us, error 0.5%
(kms_flip:3143) DEBUG: inconsistent vblank ts/seq: last 347.812897/5841, current 347.979309/5851: elapsed=166385.0us expected=165526.2us
(kms_flip:3143) DEBUG: name = vblank
last_ts = 347.979309
last_received_ts = 347.978729
last_seq = 5851
current_ts = 348.145782
current_received_ts = 348.145050
current_seq = 5861
count = 36
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 347.979309/5851, current 348.145782/5861: elapsed=166480.0us expected=165526.2us +- 827.6us, error 0.6%
(kms_flip:3143) DEBUG: inconsistent vblank ts/seq: last 347.979309/5851, current 348.145782/5861: elapsed=166480.0us expected=165526.2us
(kms_flip:3143) DEBUG: name = vblank
last_ts = 348.145782
last_received_ts = 348.145050
last_seq = 5861
current_ts = 348.312225
current_received_ts = 348.311584
current_seq = 5871
count = 37
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 348.145782/5861, current 348.312225/5871: elapsed=166452.0us expected=165526.2us +- 827.6us, error 0.6%
(kms_flip:3143) DEBUG: inconsistent vblank ts/seq: last 348.145782/5861, current 348.312225/5871: elapsed=166452.0us expected=165526.2us
(kms_flip:3143) DEBUG: name = vblank
last_ts = 348.312225
last_received_ts = 348.311584
last_seq = 5871
current_ts = 348.478699
current_received_ts = 348.478088
current_seq = 5881
count = 38
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 348.312225/5871, current 348.478699/5881: elapsed=166467.0us expected=165526.2us +- 827.6us, error 0.6%
(kms_flip:3143) DEBUG: inconsistent vblank ts/seq: last 348.312225/5871, current 348.478699/5881: elapsed=166467.0us expected=165526.2us
(kms_flip:3143) DEBUG: name = vblank
last_ts = 348.478699
last_received_ts = 348.478088
last_seq = 5881
current_ts = 348.645172
current_received_ts = 348.644501
current_seq = 5891
count = 39
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 348.478699/5881, current 348.645172/5891: elapsed=166467.0us expected=165526.2us +- 827.6us, error 0.6%
(kms_flip:3143) DEBUG: inconsistent vblank ts/seq: last 348.478699/5881, current 348.645172/5891: elapsed=166467.0us expected=165526.2us
(kms_flip:3143) DEBUG: name = vblank
last_ts = 348.645172
last_received_ts = 348.644501
last_seq = 5891
current_ts = 348.811615
current_received_ts = 348.811035
current_seq = 5901
count = 40
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 348.645172/5891, current 348.811615/5901: elapsed=166465.0us expected=165526.2us +- 827.6us, error 0.6%
(kms_flip:3143) DEBUG: inconsistent vblank ts/seq: last 348.645172/5891, current 348.811615/5901: elapsed=166465.0us expected=165526.2us
(kms_flip:3143) DEBUG: name = vblank
last_ts = 348.811615
last_received_ts = 348.811035
last_seq = 5901
current_ts = 348.978119
current_received_ts = 348.977448
current_seq = 5911
count = 41
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 348.811615/5901, current 348.978119/5911: elapsed=166486.0us expected=165526.2us +- 827.6us, error 0.6%
(kms_flip:3143) DEBUG: inconsistent vblank ts/seq: last 348.811615/5901, current 348.978119/5911: elapsed=166486.0us expected=165526.2us
(kms_flip:3143) DEBUG: name = vblank
last_ts = 348.978119
last_received_ts = 348.977448
last_seq = 5911
current_ts = 349.144653
current_received_ts = 349.144073
current_seq = 5921
count = 42
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 348.978119/5911, current 349.144653/5921: elapsed=166534.0us expected=165526.2us +- 827.6us, error 0.6%
(kms_flip:3143) DEBUG: inconsistent vblank ts/seq: last 348.978119/5911, current 349.144653/5921: elapsed=166534.0us expected=165526.2us
(kms_flip:3143) DEBUG: name = vblank
last_ts = 349.144653
last_received_ts = 349.144073
last_seq = 5921
current_ts = 349.311188
current_received_ts = 349.310547
current_seq = 5931
count = 43
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 349.144653/5921, current 349.311188/5931: elapsed=166544.0us expected=165526.2us +- 827.6us, error 0.6%
(kms_flip:3143) DEBUG: inconsistent vblank ts/seq: last 349.144653/5921, current 349.311188/5931: elapsed=166544.0us expected=165526.2us
(kms_flip:3143) DEBUG: name = vblank
last_ts = 349.311188
last_received_ts = 349.310547
last_seq = 5931
current_ts = 349.477722
current_received_ts = 349.477112
current_seq = 5941
count = 44
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 349.311188/5931, current 349.477722/5941: elapsed=166545.0us expected=165526.2us +- 827.6us, error 0.6%
(kms_flip:3143) DEBUG: inconsistent vblank ts/seq: last 349.311188/5931, current 349.477722/5941: elapsed=166545.0us expected=165526.2us
(kms_flip:3143) DEBUG: name = vblank
last_ts = 349.477722
last_received_ts = 349.477112
last_seq = 5941
current_ts = 349.644287
current_received_ts = 349.643677
current_seq = 5951
count = 45
seq_step = 10
(kms_flip:3143) DEBUG: vblank ts/seq: last 349.477722/5941, current 349.644287/5951: elapsed=166542.0us expected=165526.2us +- 827.6us, error 0.6%
(kms_flip:3143) DEBUG: inconsistent vblank ts/seq: last 349.477722/5941, current 349.644287/5951: elapsed=166542.0us expected=165526.2us
(kms_flip:3143) INFO: Event vblank: expected 461, counted 460, passrate = 65.22%, encoder type 1
(kms_flip:3143) DEBUG: dropped frames, expected 461, counted 460, passrate = 65.22%, encoder type 1
(kms_flip:3143) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1711:
(kms_flip:3143) CRITICAL: Failed assertion: !retried
(kms_flip:3143) CRITICAL: Last errno: 4, Interrupted system call
(kms_flip:3143) igt_core-INFO: Stack trace:
(kms_flip:3143) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:3143) igt_core-INFO:   #1 ../tests/kms_flip.c:428 run_test_on_crtc_set.constprop.0()
(kms_flip:3143) igt_core-INFO:   #2 ../tests/kms_flip.c:1891 run_test()
(kms_flip:3143) igt_core-INFO:   #3 ../tests/kms_flip.c:2162 __igt_unique____real_main2047()
(kms_flip:3143) igt_core-INFO:   #4 ../tests/kms_flip.c:2047 main()
(kms_flip:3143) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:3143) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:3143) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest A-VGA1: FAIL (19.580s)
Dmesg
<6> [344.592497] Console: switching to colour dummy device 80x25
<6> [344.593209] [IGT] kms_flip: executing
<7> [344.596192] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [344.597081] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [344.597602] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [344.601272] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:84:DP-1]
<7> [344.601284] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:84:DP-1]
<7> [344.604082] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.606977] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.609880] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.612958] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.616000] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.618866] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.621753] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.624649] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.627550] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.630593] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.633656] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.636563] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.639515] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.642546] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.645447] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.648407] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.651341] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.654253] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.657162] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.660042] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.662968] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.665955] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.668817] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.671691] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.674567] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.677470] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.680366] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.683254] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.686184] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.689164] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.692132] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.695183] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.695589] 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> [344.698230] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.701120] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.704121] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.707001] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.709970] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.713033] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.716021] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.719163] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.722070] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.725069] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.727973] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.730973] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.733999] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.736933] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.740001] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.743062] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.745917] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.748895] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.751806] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.754745] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.757794] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.760739] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.763699] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.766677] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.769634] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.772633] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.775618] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.778594] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.781506] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.784527] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.787436] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.790473] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.790877] 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> [344.790944] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:84:DP-1] disconnected
<7> [344.791279] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:73:VGA-1]
<7> [344.791286] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] [CONNECTOR:73:VGA-1] force=1
<7> [344.791504] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] ironlake hotplug adpa=0x83f40000, result 1
<7> [344.791761] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] CRT detected via hotplug
<7> [344.792205] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [344.792652] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK on first message, retry
<7> [344.793182] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [344.793538] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [344.793547] i915 0000:00:02.0: [drm:intel_crt_get_edid [i915]] CRT GMBUS EDID read failed, retry using GPIO bit-banging
<7> [344.793978] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] enabling bit-banging on i915 gmbus vga. force bit now 1
<7> [344.795306] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [344.795313] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] disabling bit-banging on i915 gmbus vga. force bit now 0
<7> [344.795614] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:73:VGA-1] probed modes:
<7> [344.795620] 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> [344.795626] 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> [344.795631] 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> [344.795636] 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> [344.795640] 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> [344.795664] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:76:HDMI-A-1]
<7> [344.795674] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:76:HDMI-A-1]
<7> [344.820943] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:76:HDMI-A-1] HDMI: DVI dual 0, max TMDS clock 590000 kHz
<7> [344.820952] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:76:HDMI-A-1] HDMI sink does deep color 30.
<7> [344.820957] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:76:HDMI-A-1] HDMI sink does deep color 36.
<7> [344.820961] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:76:HDMI-A-1] HDMI sink does deep color 48.
<7> [344.820965] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:76:HDMI-A-1] Assigning HDMI sink color depth as 16 bpc.
<7> [344.820969] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:76:HDMI-A-1] HDMI sink does YCRCB444 in deep color.
<7> [344.820974] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:76:HDMI-A-1] ELD monitor Mi TV
<7> [344.820979] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:76:HDMI-A-1] HDMI: latency present 0 0, video latency 0 0, audio latency 0 0
<7> [344.820984] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:76:HDMI-A-1] ELD size 36, SAD count 3
<7> [344.821174] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpd] NAK for addr: 0040 w(1)
<7> [344.821385] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpd] NAK on first message, retry
<7> [344.821740] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpd] NAK for addr: 0040 w(1)
<7> [344.821956] i915 0000:00:02.0: [drm:drm_dp_dual_mode_detect [drm_display_helper]] DP dual mode HDMI ID: (err -6)
<7> [344.823160] 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> [344.823174] 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> [344.823183] 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> [344.823192] 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> [344.823200] 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> [344.823208] 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> [344.823216] 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> [344.823224] 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> [344.823232] 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> [344.823240] 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> [344.823248] 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> [344.823256] 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> [344.823264] 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> [344.823272] 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> [344.823280] 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> [344.823288] 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> [344.823296] 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> [344.823305] 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> [344.823312] 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> [344.823325] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:76:HDMI-A-1] probed modes:
<7> [344.823330] 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> [344.823335] 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> [344.823339] 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> [344.823343] 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> [344.823348] 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> [344.823352] 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> [344.823357] 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> [344.823361] 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> [344.823366] 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> [344.823370] 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> [344.823374] 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> [344.823379] 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> [344.823383] 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> [344.823387] 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> [344.823392] 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> [344.823396] 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> [344.823400] 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> [344.823405] 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> [344.823409] 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> [344.823413] 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> [344.823418] 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> [344.823422] 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> [344.823426] 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> [344.823430] 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> [344.823435] 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> [344.823439] 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> [344.823444] 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> [344.823448] 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> [344.823452] 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> [344.823456] 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> [344.823461] 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> [344.823465] 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> [344.823469] 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> [344.823474] 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> [344.823478] 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> [344.823482] 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> [344.823486] 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> [344.823491] 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> [344.823495] 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> [344.823499] 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> [344.823503] 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> [344.823508] 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> [344.823512] 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> [344.823516] 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> [344.823520] 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> [344.823579] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:84:DP-1]
<7> [344.823585] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:84:DP-1]
<7> [344.826370] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.829316] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.832182] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.835225] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.838171] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.841050] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.843948] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.846850] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.849810] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.852759] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.855655] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.858548] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.861470] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.864401] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.867338] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.870203] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.873088] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.875967] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.878855] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.881719] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.884599] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.887471] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.890363] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.893226] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.896107] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.898993] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.901886] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.904782] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.907740] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.910623] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.913614] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.916560] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.916901] 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> [344.919559] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.922619] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.925509] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.928491] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.931474] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.934449] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.937415] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.940479] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.943413] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.946383] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.949344] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.952318] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.955315] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.958275] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.961228] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.964322] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.967246] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.970188] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.973109] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.976090] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.979056] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.981924] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.984818] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.987899] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.991099] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.994087] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [344.997116] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [345.000134] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [345.003117] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [345.006203] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [345.009205] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [345.012270] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [345.012599] 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> [345.012654] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:84:DP-1] disconnected
<6> [345.023543] [IGT] kms_flip: starting subtest wf_vblank-ts-check-interruptible
<6> [345.025197] [IGT] kms_flip: starting dynamic subtest A-VGA1
<7> [345.025815] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:91]
<7> [345.025952] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:93]
<7> [345.058286] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:53:pipe A]
<7> [345.058425] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [345.058736] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [345.058981] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [345.059222] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in has_pch_encoder (expected yes, found no)
<7> [345.059454] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in fdi_lanes (expected 1, found 0)
<7> [345.059692] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in fdi_m_n (expected tu 64 data 6039797/8388608 link 125829/524288, found tu 0, data 0/0 link 0/0)
<7> [345.059989] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in output_types (expected 0x00000002, found 0x00000000)
<7> [345.060273] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [345.060548] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1024, found 0)
<7> [345.060860] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 1344, found 0)
<7> [345.061197] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1024, found 0)
<7> [345.061472] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 1344, found 0)
<7> [345.061746] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 1048, found 0)
<7> [345.062060] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 1184, found 0)
<7> [345.062289] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 768, found 0)
<7> [345.062528] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 768, found 0)
<7> [345.062763] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 771, found 0)
<7> [345.063039] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 777, found 0)
<7> [345.063278] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 806, found 0)
<7> [345.063516] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 806, found 0)
<7> [345.063751] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1024, found 0)
<7> [345.064016] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 1344, found 0)
<7> [345.064244] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1024, found 0)
<7> [345.064472] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 1344, found 0)
<7> [345.064731] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 1048, found 0)
<7> [345.064987] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 1184, found 0)
<7> [345.065225] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 768, found 0)
<7> [345.065454] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 768, found 0)
<7> [345.065683] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 771, found 0)
<7> [345.065920] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 777, found 0)
<7> [345.066205] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 806, found 0)
<7> [345.066450] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 806, found 0)
<7> [345.066679] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [345.066924] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)
<7> [345.067198] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [345.067438] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [345.067684] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 64800, found 0)
<7> [345.067913] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 64800, found 0)
<7> [345.068206] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in port_clock (expected 64800, found 0)
<7> [345.068448] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:53:pipe A] fastset requirement not met, forcing full modeset
<7> [345.068698] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:53:pipe A] releasing PCH DPLL A
<7> [345.068979] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:53:pipe A] min cdclk: 72000 kHz -> 0 kHz
<7> [345.069242] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:53:pipe A] enable: no [modeset]
<7> [345.069478] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:primary A] fb: [NOFB], visible: no
<7> [345.069862] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [345.077964] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port B found
<7> [345.078198] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port C found
<7> [345.078418] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port D found
<7> [345.094819] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable PCH DPLL A (active 0x1, on? 1) for [CRTC:53:pipe A]
<7> [345.095364] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling PCH DPLL A
<7> [345.096098] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:CRT]
<7> [345.096607] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:75:HDMI D]
<7> [345.097098] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:83:DP D]
<7> [345.097586] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:73:VGA-1]
<7> [345.097904] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:53:pipe A]
<7> [345.098294] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:72:pipe B]
<7> [345.098457] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.enable (expected yes, found no)
<7> [345.098704] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.active (expected yes, found no)
<7> [345.098935] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in cpu_transcoder (expected 1, found -1)
<7> [345.099165] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in has_pch_encoder (expected yes, found no)
<7> [345.099417] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in fdi_lanes (expected 3, found 0)
<7> [345.099731] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in fdi_m_n (expected tu 64 data 6934582/8388608 link 288940/524288, found tu 0, data 0/0 link 0/0)
<7> [345.100003] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in lane_count (expected 4, found 0)
<7> [345.100233] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [345.100493] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [345.100723] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [345.100951] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [345.101210] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [345.101443] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [345.101699] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [345.101938] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [345.102167] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [345.102396] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [345.102675] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [345.102975] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [345.103205] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [345.103433] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [345.103682] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [345.103971] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [345.104208] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [345.104436] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [345.104686] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [345.104954] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [345.105182] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [345.105418] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [345.105709] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [345.105989] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [345.106230] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [345.106458] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [345.106709] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [345.106971] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [345.107200] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [345.107428] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [345.107675] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [345.107968] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in pipe_bpp (expected 36, found 0)
<7> [345.108283] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148800, found 0)
<7> [345.108513] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148800, found 0)
<7> [345.108746] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in port_clock (expected 223200, found 0)
<7> [345.109001] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [345.109264] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in infoframes.gcp (expected 0x00000006, found 0x00000000)
<7> [345.109492] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in avi infoframe
<7> [345.109720] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [345.109975] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [345.109978] i915 0000:00:02.0: colorspace: RGB
<7> [345.109980] i915 0000:00:02.0: scan mode: Underscan
<7> [345.109982] i915 0000:00:02.0: colorimetry: No Data
<7> [345.109985] i915 0000:00:02.0: picture aspect: 16:9
<7> [345.109987] i915 0000:00:02.0: active aspect: Same as Picture
<7> [345.109989] i915 0000:00:02.0: itc: No Data
<7> [345.109991] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [345.109993] i915 0000:00:02.0: quantization range: Default
<7> [345.109995] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [345.109997] i915 0000:00:02.0: video code: 16
<7> [345.109999] i915 0000:00:02.0: ycc quantization range: Full
<7> [345.110001] i915 0000:00:02.0: hdmi content type: Graphics
<7> [345.110004] i915 0000:00:02.0: pixel repeat: 0
<7> [345.110006] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [345.110008] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [345.110298] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in spd infoframe
<7> [345.110540] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [345.110767] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [345.110769] i915 0000:00:02.0: vendor: Intel
<7> [345.110771] i915 0000:00:02.0: product: Integrated gfx
<7> [345.110773] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [345.110776] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [345.111064] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hdmi infoframe
<7> [345.111362] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [345.111591] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [345.111593] i915 0000:00:02.0: empty frame
<7> [345.111595] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [345.111824] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:72:pipe B] fastset requirement not met, forcing full modeset
<7> [345.112094] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:72:pipe B] releasing PCH DPLL B
<7> [345.112394] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:72:pipe B] min cdclk: 165334 kHz -> 0 kHz
<7> [345.112639] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:72:pipe B] enable: no [modeset]
<7> [345.112874] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:54:primary B] fb: [NOFB], visible: no
<7> [345.113172] i915 0000:00:02.0: [drm:intel_audio_codec_disable [i915]] [CONNECTOR:76:HDMI-A-1][ENCODER:75:HDMI D] Disable audio codec on [CRTC:72:pipe B]
<7> [345.141888] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe B
<7> [345.160475] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port B found
<7> [345.160990] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port C found
<7> [345.161456] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port D found
<7> [345.170293] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable PCH DPLL B (active 0x2, on? 1) for [CRTC:72:pipe B]
<7> [345.170829] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling PCH DPLL B
<7> [345.171559] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:CRT]
<7> [345.172127] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:75:HDMI D]
<7> [345.172651] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:83:DP D]
<7> [345.173193] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:76:HDMI-A-1]
<7> [345.173794] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:72:pipe B]
<7> [345.174212] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:53:pipe A]
<7> [345.174246] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:73:VGA-1]
<7> [345.174344] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:73:VGA-1] Limiting target display pipe bpp to 24 (EDID bpp 0, max requested bpp 0, max platform bpp 36)
<7> [345.174611] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:53:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [345.174891] i915 0000:00:02.0: [drm:intel_fdi_atomic_check_link [i915]] checking fdi config on pipe A, lanes 1
<7> [345.175150] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [345.175394] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [345.175668] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [345.175948] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in has_pch_encoder (expected no, found yes)
<7> [345.176185] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in fdi_lanes (expected 0, found 1)
<7> [345.176421] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in fdi_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 6039797/8388608 link 125829/524288)
<7> [345.176688] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000002)
<7> [345.176959] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [345.177196] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1024)
<7> [345.177431] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 1344)
<7> [345.177694] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1024)
<7> [345.177964] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 1344)
<7> [345.178245] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 1048)
<7> [345.178481] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 1184)
<7> [345.178742] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 768)
<7> [345.179025] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 768)
<7> [345.179261] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 771)
<7> [345.179496] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 777)
<7> [345.179753] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 806)
<7> [345.180034] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 806)
<7> [345.180270] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1024)
<7> [345.180505] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 1344)
<7> [345.180744] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1024)
<7> [345.181044] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 1344)
<7> [345.181330] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 1048)
<7> [345.181565] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 1184)
<7> [345.181826] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 768)
<7> [345.182098] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 768)
<7> [345.182351] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 771)
<7> [345.182626] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 777)
<7> [345.182899] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 806)
<7> [345.183180] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 806)
<7> [345.183409] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [345.183638] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [345.183879] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [345.184171] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [345.184463] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 64800)
<7> [345.184699] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 64800)
<7> [345.184960] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in port_clock (expected 0, found 64800)
<7> [345.185231] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:53:pipe A] fastset requirement not met, forcing full modeset
<7> [345.185500] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:53:pipe A] min cdclk: 0 kHz -> 72000 kHz
<7> [345.185735] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:53:pipe A] allocated PCH DPLL A
<7> [345.185985] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:53:pipe A] reserving PCH DPLL A
<7> [345.186275] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:53:pipe A] enable: yes [modeset]
<7> [345.186516] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: ANALOG (0x2), output format: RGB, sink format: RGB
<7> [345.186750] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [345.186988] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [345.187269] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [345.187530] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [345.187809] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [345.188048] 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> [345.188341] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [345.188611] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 1
<7> [345.188845] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [345.189084] 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> [345.189377] 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> [345.189661] 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> [345.189903] 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> [345.190143] 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> [345.190402] 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> [345.190682] 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> [345.190918] 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> [345.191156] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 64800, pixel rate 64800, min cdclk 72000, min voltage level 0
<7> [345.191427] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 0, ips linetime: 0
<7> [345.191696] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1024x768+0+0
<7> [345.191936] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [345.192170] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [345.192449] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling always-on
<7> [345.192455] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [345.192824] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: dpll: 0x84100010, dpll_md: 0x0, fp0: 0x10c09, fp1: 0x10c09
<7> [345.193065] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x3 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [345.193334] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [345.193610] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [345.193845] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [345.194079] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [345.194335] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [345.194586] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:primary A] fb: [FB:91] 1024x768 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [345.194822] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [345.195056] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1024.000000x768.000000+0.000000+0.000000 dst: 1024x768+0+0
<7> [345.196683] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling always-on
<7> [345.196929] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:CRT]
<7> [345.197146] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:75:HDMI D]
<7> [345.197360] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:83:DP D]
<7> [345.198175] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [345.199231] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x100
<7> [345.199466] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 1 done.
<7> [345.200375] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x200
<7> [345.200645] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 2 done.
<7> [345.200867] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train done.
<7> [345.201094] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable PCH DPLL A (active 0x1, on? 0) for [CRTC:53:pipe A]
<7> [345.201320] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling PCH DPLL A
<7> [345.253315] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:73:VGA-1]
<7> [345.253920] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:53:pipe A]
<7> [356.449750] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:53:pipe A]
<7> [356.449974] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [356.450586] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [356.451098] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [356.451619] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in has_pch_encoder (expected yes, found no)
<7> [356.452124] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in fdi_lanes (expected 1, found 0)
<7> [356.452689] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in fdi_m_n (expected tu 64 data 6039797/8388608 link 125829/524288, found tu 0, data 0/0 link 0/0)
<7> [356.452993] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in output_types (expected 0x00000002, found 0x00000000)
<7> [356.453222] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [356.453486] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1024, found 0)
<7> [356.453733] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 1344, found 0)
<7> [356.453963] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1024, found 0)
<7> [356.454192] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 1344, found 0)
<7> [356.454461] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 1048, found 0)
<7> [356.454773] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 1184, found 0)
<7> [356.455128] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 768, found 0)
<7> [356.455536] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 768, found 0)
<7> [356.455889] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 771, found 0)
<7> [356.456219] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 777, found 0)
<7> [356.456491] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 806, found 0)
<7> [356.456800] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 806, found 0)
<7> [356.457158] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1024, found 0)
<7> [356.457553] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 1344, found 0)
<7> [356.457907] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1024, found 0)
<7> [356.458223] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 1344, found 0)
<7> [356.458496] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 1048, found 0)
<7> [356.458726] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 1184, found 0)
<7> [356.459011] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 768, found 0)
<7> [356.459364] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 768, found 0)
<7> [356.459771] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 771, found 0)
<7> [356.460121] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 777, found 0)
<7> [356.460422] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 806, found 0)
<7> [356.460697] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 806, found 0)
<7> [356.461048] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [356.461403] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)
<7> [356.461796] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [356.462098] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [356.462329] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 64800, found 0)
<7> [356.462709] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 64800, found 0)
<7> [356.463066] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in port_clock (expected 64800, found 0)
<7> [356.463425] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:53:pipe A] fastset requirement not met, forcing full modeset
<7> [356.463822] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:53:pipe A] releasing PCH DPLL A
<7> [356.464154] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:53:pipe A] min cdclk: 72000 kHz -> 0 kHz
<7> [356.464391] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:53:pipe A] enable: no [modeset]
<7> [356.464654] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:primary A] fb: [NOFB], visible: no
<7> [356.465147] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [356.470813] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port B found
<7> [356.471042] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port C found
<7> [356.471263] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port D found
<7> [356.487687] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable PCH DPLL A (active 0x1, on? 1) for [CRTC:53:pipe A]
<7> [356.488233] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling PCH DPLL A
<7> [356.489015] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:CRT]
<7> [356.489800] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:75:HDMI D]
<7> [356.490524] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:83:DP D]
<7> [356.491340] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:73:VGA-1]
<7> [356.491661] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:53:pipe A]
<7> [356.491969] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:72:pipe B]
<7> [356.492085] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:53:pipe A]
<7> [356.492115] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:73:VGA-1]
<7> [356.492210] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:73:VGA-1] Limiting target display pipe bpp to 24 (EDID bpp 0, max requested bpp 0, max platform bpp 36)
<7> [356.492455] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:53:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [356.492685] i915 0000:00:02.0: [drm:intel_fdi_atomic_check_link [i915]] checking fdi config on pipe A, lanes 1
<7> [356.492945] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [356.493175] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [356.493404] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [356.493633] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in has_pch_encoder (expected no, found yes)
<7> [356.494006] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in fdi_lanes (expected 0, found 1)
<7> [356.494236] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in fdi_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 6039797/8388608 link 125829/524288)
<7> [356.494466] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000002)
<7> [356.494694] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [356.494965] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1024)
<7> [356.495322] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 1344)
<7> [356.495674] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1024)
<7> [356.496066] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 1344)
<7> [356.496419] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 1048)
<7> [356.496768] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 1184)
<7> [356.497092] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 768)
<7> [356.497343] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 768)
<7> [356.497573] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 771)
<7> [356.497921] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 777)
<7> [356.498278] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 806)
<7> [356.498629] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 806)
<7> [356.499007] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1024)
<7> [356.499307] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 1344)
<7> [356.499537] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1024)
<7> [356.499896] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 1344)
<7> [356.500254] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 1048)
<7> [356.500611] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 1184)
<7> [356.501007] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 768)
<7> [356.501307] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 768)
<7> [356.501544] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 771)
<7> [356.501774] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 777)
<7> [356.502157] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 806)
<7> [356.502507] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 806)
<7> [356.502901] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [356.503261] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [356.503524] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [356.503762] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [356.504148] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 64800)
<7> [356.504507] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 64800)
<7> [356.504858] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in port_clock (expected 0, found 64800)
<7> [356.505221] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:53:pipe A] fastset requirement not met, forcing full modeset
<7> [356.505519] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:53:pipe A] min cdclk: 0 kHz -> 72000 kHz
<7> [356.505814] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:53:pipe A] allocated PCH DPLL A
<7> [356.506195] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:53:pipe A] reserving PCH DPLL A
<7> [356.506551] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:53:pipe A] enable: yes [modeset]
<7> [356.506954] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: ANALOG (0x2), output format: RGB, sink format: RGB
<7> [356.507295] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [356.507540] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [356.507768] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [356.508075] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [356.508426] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [356.508780] 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> [356.509163] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [356.509459] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 1
<7> [356.509687] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [356.510090] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling always-on
<7> [356.510070] 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> [356.510438] 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> [356.510793] 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> [356.511181] 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> [356.511439] 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> [356.511685] 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> [356.512085] 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> [356.512444] 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> [356.512800] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 64800, pixel rate 64800, min cdclk 72000, min voltage level 0
<7> [356.513186] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 0, ips linetime: 0
<7> [356.513450] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1024x768+0+0
<7> [356.513679] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [356.513922] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [356.514318] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [356.514673] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: dpll: 0x84100010, dpll_md: 0x0, fp0: 0x10c09, fp1: 0x10c09
<7> [356.515055] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x3 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [356.515396] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [356.515634] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [356.515911] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [356.516299] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [356.516650] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [356.517043] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:primary A] fb: [FB:91] 1024x768 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [356.517334] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [356.517563] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1024.000000x768.000000+0.000000+0.000000 dst: 1024x768+0+0
<7> [356.517941] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling always-on
<7> [356.518347] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:CRT]
<7> [356.518688] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:75:HDMI D]
<7> [356.518999] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:83:DP D]
<7> [356.519868] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [356.521092] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x100
<7> [356.521373] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 1 done.
<7> [356.522257] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x200
<7> [356.522611] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 2 done.
<7> [356.522959] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train done.
<7> [356.523345] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable PCH DPLL A (active 0x1, on? 0) for [CRTC:53:pipe A]
<7> [356.523607] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling PCH DPLL A
<7> [356.575618] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:73:VGA-1]
<7> [356.576182] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:53:pipe A]
<6> [364.606374] [IGT] kms_flip: finished subtest A-VGA1, FAIL
Created at 2026-04-01 08:27:56