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

Result: Fail

git-log-oneline i915_display_info4 igt_runner4 runtimes4 results4.json results4-i915-load.json guc_logs4.tar i915_display_info_post_exec4 boot4 dmesg4

DetailValue
Duration 18.89 seconds
Hostname 
shard-snb6
Igt-Version 
IGT-Version: 2.3-g1fbf77e9c (x86_64) (Linux: 7.0.0-rc2-CI_DRM_18085-gcfc20c776480+ x86_64)
Out 
Using IGT_SRANDOM=1772621941 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 17362.3us +- 9.264us accuracy 0.16%
vblank interval differs from modeline! expected 16665.6us, measured 17362us +- 9.264us, difference 696.7us (75.2 sigma)
Event vblank: expected 433, counted 440, passrate = 18.18%, encoder type 1
Expected frametime: 16666us; measured 16871.9us +- 9.632us accuracy 0.17%
vblank interval differs from modeline! expected 16665.6us, measured 16872us +- 9.632us, difference 206.3us (21.4 sigma)
Event vblank: expected 448, counted 450, passrate = 84.44%, encoder type 1
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../tests/kms_flip.c:428 run_test_on_crtc_set.constprop.0()
  #2 ../tests/kms_flip.c:1887 run_test()
  #3 ../tests/kms_flip.c:2156 __igt_unique____real_main2043()
  #4 ../tests/kms_flip.c:2043 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest A-VGA1: FAIL (18.888s)
Err 
Starting subtest: wf_vblank-ts-check-interruptible
Starting dynamic subtest: A-VGA1
(kms_flip:2826) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1707:
(kms_flip:2826) CRITICAL: Failed assertion: !retried
(kms_flip:2826) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest A-VGA1 failed.
**** DEBUG ****
(kms_flip:2826) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=2, pitch=4096)
(kms_flip:2826) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd)
(kms_flip:2826) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2826) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2826) igt_fb-DEBUG: Test requirement passed: cairo_surface_status(fb->cairo_surface) == CAIRO_STATUS_SUCCESS
(kms_flip:2826) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2826) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2826) igt_fb-DEBUG: Test requirement passed: cairo_surface_status(fb->cairo_surface) == CAIRO_STATUS_SUCCESS
(kms_flip:2826) igt_kms-INFO:   1024x768: 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa 
(kms_flip:2826) DEBUG: No stale events found
(kms_flip:2826) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0
(kms_flip:2826) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0
(kms_flip:2826) INFO: Expected frametime: 16666us; measured 17362.3us +- 9.264us accuracy 0.16%
(kms_flip:2826) INFO: vblank interval differs from modeline! expected 16665.6us, measured 17362us +- 9.264us, difference 696.7us (75.2 sigma)
(kms_flip:2826) DEBUG: name = vblank
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 332.516632
current_received_ts = 332.515930
current_seq = 14499
count = 0
seq_step = 10
(kms_flip:2826) DEBUG: name = vblank
last_ts = 332.516632
last_received_ts = 332.515930
last_seq = 14499
current_ts = 332.690247
current_received_ts = 332.689636
current_seq = 14509
count = 1
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 332.516632/14499, current 332.690247/14509: elapsed=173621.0us expected=173623.1us +- 868.1us, error 0.0%
(kms_flip:2826) DEBUG: name = vblank
last_ts = 332.690247
last_received_ts = 332.689636
last_seq = 14509
current_ts = 332.863434
current_received_ts = 332.862762
current_seq = 14519
count = 2
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 332.690247/14509, current 332.863434/14519: elapsed=173191.0us expected=173623.1us +- 868.1us, error 0.2%
(kms_flip:2826) DEBUG: name = vblank
last_ts = 332.863434
last_received_ts = 332.862762
last_seq = 14519
current_ts = 333.036255
current_received_ts = 333.035553
current_seq = 14529
count = 3
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 332.863434/14519, current 333.036255/14529: elapsed=172826.0us expected=173623.1us +- 868.1us, error 0.5%
(kms_flip:2826) DEBUG: name = vblank
last_ts = 333.036255
last_received_ts = 333.035553
last_seq = 14529
current_ts = 333.209076
current_received_ts = 333.208527
current_seq = 14539
count = 4
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 333.036255/14529, current 333.209076/14539: elapsed=172813.0us expected=173623.1us +- 868.1us, error 0.5%
(kms_flip:2826) DEBUG: name = vblank
last_ts = 333.209076
last_received_ts = 333.208527
last_seq = 14539
current_ts = 333.381897
current_received_ts = 333.381287
current_seq = 14549
count = 5
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 333.209076/14539, current 333.381897/14549: elapsed=172840.0us expected=173623.1us +- 868.1us, error 0.5%
(kms_flip:2826) DEBUG: name = vblank
last_ts = 333.381897
last_received_ts = 333.381287
last_seq = 14549
current_ts = 333.554718
current_received_ts = 333.554077
current_seq = 14559
count = 6
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 333.381897/14549, current 333.554718/14559: elapsed=172817.0us expected=173623.1us +- 868.1us, error 0.5%
(kms_flip:2826) DEBUG: name = vblank
last_ts = 333.554718
last_received_ts = 333.554077
last_seq = 14559
current_ts = 333.727539
current_received_ts = 333.727020
current_seq = 14569
count = 7
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 333.554718/14559, current 333.727539/14569: elapsed=172824.0us expected=173623.1us +- 868.1us, error 0.5%
(kms_flip:2826) DEBUG: name = vblank
last_ts = 333.727539
last_received_ts = 333.727020
last_seq = 14569
current_ts = 333.899841
current_received_ts = 333.899139
current_seq = 14579
count = 8
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 333.727539/14569, current 333.899841/14579: elapsed=172289.0us expected=173623.1us +- 868.1us, error 0.8%
(kms_flip:2826) DEBUG: inconsistent vblank ts/seq: last 333.727539/14569, current 333.899841/14579: elapsed=172289.0us expected=173623.1us
(kms_flip:2826) DEBUG: name = vblank
last_ts = 333.899841
last_received_ts = 333.899139
last_seq = 14579
current_ts = 334.071960
current_received_ts = 334.071320
current_seq = 14589
count = 9
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 333.899841/14579, current 334.071960/14589: elapsed=172125.0us expected=173623.1us +- 868.1us, error 0.9%
(kms_flip:2826) DEBUG: inconsistent vblank ts/seq: last 333.899841/14579, current 334.071960/14589: elapsed=172125.0us expected=173623.1us
(kms_flip:2826) DEBUG: name = vblank
last_ts = 334.071960
last_received_ts = 334.071320
last_seq = 14589
current_ts = 334.244110
current_received_ts = 334.243500
current_seq = 14599
count = 10
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 334.071960/14589, current 334.244110/14599: elapsed=172135.0us expected=173623.1us +- 868.1us, error 0.9%
(kms_flip:2826) DEBUG: inconsistent vblank ts/seq: last 334.071960/14589, current 334.244110/14599: elapsed=172135.0us expected=173623.1us
(kms_flip:2826) DEBUG: name = vblank
last_ts = 334.244110
last_received_ts = 334.243500
last_seq = 14599
current_ts = 334.416229
current_received_ts = 334.415527
current_seq = 14609
count = 11
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 334.244110/14599, current 334.416229/14609: elapsed=172140.0us expected=173623.1us +- 868.1us, error 0.9%
(kms_flip:2826) DEBUG: inconsistent vblank ts/seq: last 334.244110/14599, current 334.416229/14609: elapsed=172140.0us expected=173623.1us
(kms_flip:2826) DEBUG: name = vblank
last_ts = 334.416229
last_received_ts = 334.415527
last_seq = 14609
current_ts = 334.588348
current_received_ts = 334.587738
current_seq = 14619
count = 12
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 334.416229/14609, current 334.588348/14619: elapsed=172117.0us expected=173623.1us +- 868.1us, error 0.9%
(kms_flip:2826) DEBUG: inconsistent vblank ts/seq: last 334.416229/14609, current 334.588348/14619: elapsed=172117.0us expected=173623.1us
(kms_flip:2826) DEBUG: name = vblank
last_ts = 334.588348
last_received_ts = 334.587738
last_seq = 14619
current_ts = 334.760498
current_received_ts = 334.759796
current_seq = 14629
count = 13
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 334.588348/14619, current 334.760498/14629: elapsed=172142.0us expected=173623.1us +- 868.1us, error 0.9%
(kms_flip:2826) DEBUG: inconsistent vblank ts/seq: last 334.588348/14619, current 334.760498/14629: elapsed=172142.0us expected=173623.1us
(kms_flip:2826) DEBUG: name = vblank
last_ts = 334.760498
last_received_ts = 334.759796
last_seq = 14629
current_ts = 334.932037
current_received_ts = 334.931396
current_seq = 14639
count = 14
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 334.760498/14629, current 334.932037/14639: elapsed=171544.0us expected=173623.1us +- 868.1us, error 1.2%
(kms_flip:2826) DEBUG: inconsistent vblank ts/seq: last 334.760498/14629, current 334.932037/14639: elapsed=171544.0us expected=173623.1us
(kms_flip:2826) DEBUG: name = vblank
last_ts = 334.932037
last_received_ts = 334.931396
last_seq = 14639
current_ts = 335.103577
current_received_ts = 335.102936
current_seq = 14649
count = 15
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 334.932037/14639, current 335.103577/14649: elapsed=171522.0us expected=173623.1us +- 868.1us, error 1.2%
(kms_flip:2826) DEBUG: inconsistent vblank ts/seq: last 334.932037/14639, current 335.103577/14649: elapsed=171522.0us expected=173623.1us
(kms_flip:2826) DEBUG: name = vblank
last_ts = 335.103577
last_received_ts = 335.102936
last_seq = 14649
current_ts = 335.275085
current_received_ts = 335.274445
current_seq = 14659
count = 16
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 335.103577/14649, current 335.275085/14659: elapsed=171515.0us expected=173623.1us +- 868.1us, error 1.2%
(kms_flip:2826) DEBUG: inconsistent vblank ts/seq: last 335.103577/14649, current 335.275085/14659: elapsed=171515.0us expected=173623.1us
(kms_flip:2826) DEBUG: name = vblank
last_ts = 335.275085
last_received_ts = 335.274445
last_seq = 14659
current_ts = 335.446594
current_received_ts = 335.445953
current_seq = 14669
count = 17
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 335.275085/14659, current 335.446594/14669: elapsed=171509.0us expected=173623.1us +- 868.1us, error 1.2%
(kms_flip:2826) DEBUG: inconsistent vblank ts/seq: last 335.275085/14659, current 335.446594/14669: elapsed=171509.0us expected=173623.1us
(kms_flip:2826) DEBUG: name = vblank
last_ts = 335.446594
last_received_ts = 335.445953
last_seq = 14669
current_ts = 335.618103
current_received_ts = 335.617523
current_seq = 14679
count = 18
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 335.446594/14669, current 335.618103/14679: elapsed=171521.0us expected=173623.1us +- 868.1us, error 1.2%
(kms_flip:2826) DEBUG: inconsistent vblank ts/seq: last 335.446594/14669, current 335.618103/14679: elapsed=171521.0us expected=173623.1us
(kms_flip:2826) DEBUG: name = vblank
last_ts = 335.618103
last_received_ts = 335.617523
last_seq = 14679
current_ts = 335.789551
current_received_ts = 335.788910
current_seq = 14689
count = 19
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 335.618103/14679, current 335.789551/14689: elapsed=171449.0us expected=173623.1us +- 868.1us, error 1.3%
(kms_flip:2826) DEBUG: inconsistent vblank ts/seq: last 335.618103/14679, current 335.789551/14689: elapsed=171449.0us expected=173623.1us
(kms_flip:2826) DEBUG: name = vblank
last_ts = 335.789551
last_received_ts = 335.788910
last_seq = 14689
current_ts = 335.960541
current_received_ts = 335.959961
current_seq = 14699
count = 20
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 335.789551/14689, current 335.960541/14699: elapsed=170988.0us expected=173623.1us +- 868.1us, error 1.5%
(kms_flip:2826) DEBUG: inconsistent vblank ts/seq: last 335.789551/14689, current 335.960541/14699: elapsed=170988.0us expected=173623.1us
(kms_flip:2826) DEBUG: name = vblank
last_ts = 335.960541
last_received_ts = 335.959961
last_seq = 14699
current_ts = 336.131531
current_received_ts = 336.130890
current_seq = 14709
count = 21
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 335.960541/14699, current 336.131531/14709: elapsed=170984.0us expected=173623.1us +- 868.1us, error 1.5%
(kms_flip:2826) DEBUG: inconsistent vblank ts/seq: last 335.960541/14699, current 336.131531/14709: elapsed=170984.0us expected=173623.1us
(kms_flip:2826) DEBUG: name = vblank
last_ts = 336.131531
last_received_ts = 336.130890
last_seq = 14709
current_ts = 336.302521
current_received_ts = 336.301880
current_seq = 14719
count = 22
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 336.131531/14709, current 336.302521/14719: elapsed=170987.0us expected=173623.1us +- 868.1us, error 1.5%
(kms_flip:2826) DEBUG: inconsistent vblank ts/seq: last 336.131531/14709, current 336.302521/14719: elapsed=170987.0us expected=173623.1us
(kms_flip:2826) DEBUG: name = vblank
last_ts = 336.302521
last_received_ts = 336.301880
last_seq = 14719
current_ts = 336.473511
current_received_ts = 336.472900
current_seq = 14729
count = 23
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 336.302521/14719, current 336.473511/14729: elapsed=170990.0us expected=173623.1us +- 868.1us, error 1.5%
(kms_flip:2826) DEBUG: inconsistent vblank ts/seq: last 336.302521/14719, current 336.473511/14729: elapsed=170990.0us expected=173623.1us
(kms_flip:2826) DEBUG: name = vblank
last_ts = 336.473511
last_received_ts = 336.472900
last_seq = 14729
current_ts = 336.644501
current_received_ts = 336.643921
current_seq = 14739
count = 24
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 336.473511/14729, current 336.644501/14739: elapsed=170983.0us expected=173623.1us +- 868.1us, error 1.5%
(kms_flip:2826) DEBUG: inconsistent vblank ts/seq: last 336.473511/14729, current 336.644501/14739: elapsed=170983.0us expected=173623.1us
(kms_flip:2826) DEBUG: name = vblank
last_ts = 336.644501
last_received_ts = 336.643921
last_seq = 14739
current_ts = 336.815338
current_received_ts = 336.814758
current_seq = 14749
count = 25
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 336.644501/14739, current 336.815338/14749: elapsed=170854.0us expected=173623.1us +- 868.1us, error 1.6%
(kms_flip:2826) DEBUG: inconsistent vblank ts/seq: last 336.644501/14739, current 336.815338/14749: elapsed=170854.0us expected=173623.1us
(kms_flip:2826) DEBUG: name = vblank
last_ts = 336.815338
last_received_ts = 336.814758
last_seq = 14749
current_ts = 336.985870
current_received_ts = 336.985168
current_seq = 14759
count = 26
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 336.815338/14749, current 336.985870/14759: elapsed=170530.0us expected=173623.1us +- 868.1us, error 1.8%
(kms_flip:2826) DEBUG: inconsistent vblank ts/seq: last 336.815338/14749, current 336.985870/14759: elapsed=170530.0us expected=173623.1us
(kms_flip:2826) DEBUG: name = vblank
last_ts = 336.985870
last_received_ts = 336.985168
last_seq = 14759
current_ts = 337.156372
current_received_ts = 337.155762
current_seq = 14769
count = 27
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 336.985870/14759, current 337.156372/14769: elapsed=170511.0us expected=173623.1us +- 868.1us, error 1.8%
(kms_flip:2826) DEBUG: inconsistent vblank ts/seq: last 336.985870/14759, current 337.156372/14769: elapsed=170511.0us expected=173623.1us
(kms_flip:2826) DEBUG: name = vblank
last_ts = 337.156372
last_received_ts = 337.155762
last_seq = 14769
current_ts = 337.326904
current_received_ts = 337.326263
current_seq = 14779
count = 28
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 337.156372/14769, current 337.326904/14779: elapsed=170515.0us expected=173623.1us +- 868.1us, error 1.8%
(kms_flip:2826) DEBUG: inconsistent vblank ts/seq: last 337.156372/14769, current 337.326904/14779: elapsed=170515.0us expected=173623.1us
(kms_flip:2826) DEBUG: name = vblank
last_ts = 337.326904
last_received_ts = 337.326263
last_seq = 14779
current_ts = 337.497437
current_received_ts = 337.496857
current_seq = 14789
count = 29
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 337.326904/14779, current 337.497437/14789: elapsed=170536.0us expected=173623.1us +- 868.1us, error 1.8%
(kms_flip:2826) DEBUG: inconsistent vblank ts/seq: last 337.326904/14779, current 337.497437/14789: elapsed=170536.0us expected=173623.1us
(kms_flip:2826) DEBUG: name = vblank
last_ts = 337.497437
last_received_ts = 337.496857
last_seq = 14789
current_ts = 337.667938
current_received_ts = 337.667328
current_seq = 14799
count = 30
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 337.497437/14789, current 337.667938/14799: elapsed=170507.0us expected=173623.1us +- 868.1us, error 1.8%
(kms_flip:2826) DEBUG: inconsistent vblank ts/seq: last 337.497437/14789, current 337.667938/14799: elapsed=170507.0us expected=173623.1us
(kms_flip:2826) DEBUG: name = vblank
last_ts = 337.667938
last_received_ts = 337.667328
last_seq = 14799
current_ts = 337.838287
current_received_ts = 337.837677
current_seq = 14809
count = 31
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 337.667938/14799, current 337.838287/14809: elapsed=170352.0us expected=173623.1us +- 868.1us, error 1.9%
(kms_flip:2826) DEBUG: inconsistent vblank ts/seq: last 337.667938/14799, current 337.838287/14809: elapsed=170352.0us expected=173623.1us
(kms_flip:2826) DEBUG: name = vblank
last_ts = 337.838287
last_received_ts = 337.837677
last_seq = 14809
current_ts = 338.008392
current_received_ts = 338.007812
current_seq = 14819
count = 32
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 337.838287/14809, current 338.008392/14819: elapsed=170109.0us expected=173623.1us +- 868.1us, error 2.0%
(kms_flip:2826) DEBUG: inconsistent vblank ts/seq: last 337.838287/14809, current 338.008392/14819: elapsed=170109.0us expected=173623.1us
(kms_flip:2826) DEBUG: name = vblank
last_ts = 338.008392
last_received_ts = 338.007812
last_seq = 14819
current_ts = 338.178528
current_received_ts = 338.177826
current_seq = 14829
count = 33
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 338.008392/14819, current 338.178528/14829: elapsed=170113.0us expected=173623.1us +- 868.1us, error 2.0%
(kms_flip:2826) DEBUG: inconsistent vblank ts/seq: last 338.008392/14819, current 338.178528/14829: elapsed=170113.0us expected=173623.1us
(kms_flip:2826) DEBUG: name = vblank
last_ts = 338.178528
last_received_ts = 338.177826
last_seq = 14829
current_ts = 338.348633
current_received_ts = 338.347992
current_seq = 14839
count = 34
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 338.178528/14829, current 338.348633/14839: elapsed=170112.0us expected=173623.1us +- 868.1us, error 2.0%
(kms_flip:2826) DEBUG: inconsistent vblank ts/seq: last 338.178528/14829, current 338.348633/14839: elapsed=170112.0us expected=173623.1us
(kms_flip:2826) DEBUG: name = vblank
last_ts = 338.348633
last_received_ts = 338.347992
last_seq = 14839
current_ts = 338.518738
current_received_ts = 338.518127
current_seq = 14849
count = 35
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 338.348633/14839, current 338.518738/14849: elapsed=170112.0us expected=173623.1us +- 868.1us, error 2.0%
(kms_flip:2826) DEBUG: inconsistent vblank ts/seq: last 338.348633/14839, current 338.518738/14849: elapsed=170112.0us expected=173623.1us
(kms_flip:2826) DEBUG: name = vblank
last_ts = 338.518738
last_received_ts = 338.518127
last_seq = 14849
current_ts = 338.688873
current_received_ts = 338.688110
current_seq = 14859
count = 36
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 338.518738/14849, current 338.688873/14859: elapsed=170120.0us expected=173623.1us +- 868.1us, error 2.0%
(kms_flip:2826) DEBUG: inconsistent vblank ts/seq: last 338.518738/14849, current 338.688873/14859: elapsed=170120.0us expected=173623.1us
(kms_flip:2826) DEBUG: name = vblank
last_ts = 338.688873
last_received_ts = 338.688110
last_seq = 14859
current_ts = 338.858765
current_received_ts = 338.858032
current_seq = 14869
count = 37
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 338.688873/14859, current 338.858765/14869: elapsed=169911.0us expected=173623.1us +- 868.1us, error 2.1%
(kms_flip:2826) DEBUG: inconsistent vblank ts/seq: last 338.688873/14859, current 338.858765/14869: elapsed=169911.0us expected=173623.1us
(kms_flip:2826) DEBUG: name = vblank
last_ts = 338.858765
last_received_ts = 338.858032
last_seq = 14869
current_ts = 339.028534
current_received_ts = 339.027893
current_seq = 14879
count = 38
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 338.858765/14869, current 339.028534/14879: elapsed=169751.0us expected=173623.1us +- 868.1us, error 2.2%
(kms_flip:2826) DEBUG: inconsistent vblank ts/seq: last 338.858765/14869, current 339.028534/14879: elapsed=169751.0us expected=173623.1us
(kms_flip:2826) DEBUG: name = vblank
last_ts = 339.028534
last_received_ts = 339.027893
last_seq = 14879
current_ts = 339.198273
current_received_ts = 339.197662
current_seq = 14889
count = 39
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 339.028534/14879, current 339.198273/14889: elapsed=169757.0us expected=173623.1us +- 868.1us, error 2.2%
(kms_flip:2826) DEBUG: inconsistent vblank ts/seq: last 339.028534/14879, current 339.198273/14889: elapsed=169757.0us expected=173623.1us
(kms_flip:2826) DEBUG: name = vblank
last_ts = 339.198273
last_received_ts = 339.197662
last_seq = 14889
current_ts = 339.368042
current_received_ts = 339.367432
current_seq = 14899
count = 40
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 339.198273/14889, current 339.368042/14899: elapsed=169751.0us expected=173623.1us +- 868.1us, error 2.2%
(kms_flip:2826) DEBUG: inconsistent vblank ts/seq: last 339.198273/14889, current 339.368042/14899: elapsed=169751.0us expected=173623.1us
(kms_flip:2826) DEBUG: name = vblank
last_ts = 339.368042
last_received_ts = 339.367432
last_seq = 14899
current_ts = 339.537811
current_received_ts = 339.537201
current_seq = 14909
count = 41
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 339.368042/14899, current 339.537811/14909: elapsed=169769.0us expected=173623.1us +- 868.1us, error 2.2%
(kms_flip:2826) DEBUG: inconsistent vblank ts/seq: last 339.368042/14899, current 339.537811/14909: elapsed=169769.0us expected=173623.1us
(kms_flip:2826) DEBUG: name = vblank
last_ts = 339.537811
last_received_ts = 339.537201
last_seq = 14909
current_ts = 339.707550
current_received_ts = 339.706909
current_seq = 14919
count = 42
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 339.537811/14909, current 339.707550/14919: elapsed=169737.0us expected=173623.1us +- 868.1us, error 2.2%
(kms_flip:2826) DEBUG: inconsistent vblank ts/seq: last 339.537811/14909, current 339.707550/14919: elapsed=169737.0us expected=173623.1us
(kms_flip:2826) DEBUG: name = vblank
last_ts = 339.707550
last_received_ts = 339.706909
last_seq = 14919
current_ts = 339.877106
current_received_ts = 339.876495
current_seq = 14929
count = 43
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 339.707550/14919, current 339.877106/14929: elapsed=169554.0us expected=173623.1us +- 868.1us, error 2.3%
(kms_flip:2826) DEBUG: inconsistent vblank ts/seq: last 339.707550/14919, current 339.877106/14929: elapsed=169554.0us expected=173623.1us
(kms_flip:2826) INFO: Event vblank: expected 433, counted 440, passrate = 18.18%, encoder type 1
(kms_flip:2826) DEBUG: dropped frames, expected 433, counted 440, passrate = 18.18%, encoder type 1
(kms_flip:2826) DEBUG: Retrying without a hotplug event
(kms_flip:2826) DEBUG: No stale events found
(kms_flip:2826) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0
(kms_flip:2826) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0
(kms_flip:2826) INFO: Expected frametime: 16666us; measured 16871.9us +- 9.632us accuracy 0.17%
(kms_flip:2826) INFO: vblank interval differs from modeline! expected 16665.6us, measured 16872us +- 9.632us, difference 206.3us (21.4 sigma)
(kms_flip:2826) DEBUG: name = vblank
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 343.480133
current_received_ts = 343.479492
current_seq = 14962
count = 0
seq_step = 10
(kms_flip:2826) DEBUG: name = vblank
last_ts = 343.480133
last_received_ts = 343.479492
last_seq = 14962
current_ts = 343.648834
current_received_ts = 343.648132
current_seq = 14972
count = 1
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 343.480133/14962, current 343.648834/14972: elapsed=168716.0us expected=168718.7us +- 843.6us, error 0.0%
(kms_flip:2826) DEBUG: name = vblank
last_ts = 343.648834
last_received_ts = 343.648132
last_seq = 14972
current_ts = 343.817505
current_received_ts = 343.816833
current_seq = 14982
count = 2
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 343.648834/14972, current 343.817505/14982: elapsed=168656.0us expected=168718.7us +- 843.6us, error 0.0%
(kms_flip:2826) DEBUG: name = vblank
last_ts = 343.817505
last_received_ts = 343.816833
last_seq = 14982
current_ts = 343.986053
current_received_ts = 343.985382
current_seq = 14992
count = 3
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 343.817505/14982, current 343.986053/14992: elapsed=168540.0us expected=168718.7us +- 843.6us, error 0.1%
(kms_flip:2826) DEBUG: name = vblank
last_ts = 343.986053
last_received_ts = 343.985382
last_seq = 14992
current_ts = 344.154602
current_received_ts = 344.153870
current_seq = 15002
count = 4
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 343.986053/14992, current 344.154602/15002: elapsed=168548.0us expected=168718.7us +- 843.6us, error 0.1%
(kms_flip:2826) DEBUG: name = vblank
last_ts = 344.154602
last_received_ts = 344.153870
last_seq = 15002
current_ts = 344.323120
current_received_ts = 344.322510
current_seq = 15012
count = 5
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 344.154602/15002, current 344.323120/15012: elapsed=168522.0us expected=168718.7us +- 843.6us, error 0.1%
(kms_flip:2826) DEBUG: name = vblank
last_ts = 344.323120
last_received_ts = 344.322510
last_seq = 15012
current_ts = 344.491669
current_received_ts = 344.491028
current_seq = 15022
count = 6
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 344.323120/15012, current 344.491669/15022: elapsed=168545.0us expected=168718.7us +- 843.6us, error 0.1%
(kms_flip:2826) DEBUG: name = vblank
last_ts = 344.491669
last_received_ts = 344.491028
last_seq = 15022
current_ts = 344.660187
current_received_ts = 344.659515
current_seq = 15032
count = 7
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 344.491669/15022, current 344.660187/15032: elapsed=168542.0us expected=168718.7us +- 843.6us, error 0.1%
(kms_flip:2826) DEBUG: name = vblank
last_ts = 344.660187
last_received_ts = 344.659515
last_seq = 15032
current_ts = 344.828674
current_received_ts = 344.828094
current_seq = 15042
count = 8
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 344.660187/15032, current 344.828674/15042: elapsed=168461.0us expected=168718.7us +- 843.6us, error 0.2%
(kms_flip:2826) DEBUG: name = vblank
last_ts = 344.828674
last_received_ts = 344.828094
last_seq = 15042
current_ts = 344.997040
current_received_ts = 344.996429
current_seq = 15052
count = 9
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 344.828674/15042, current 344.997040/15052: elapsed=168391.0us expected=168718.7us +- 843.6us, error 0.2%
(kms_flip:2826) DEBUG: name = vblank
last_ts = 344.997040
last_received_ts = 344.996429
last_seq = 15052
current_ts = 345.165405
current_received_ts = 345.164795
current_seq = 15062
count = 10
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 344.997040/15052, current 345.165405/15062: elapsed=168361.0us expected=168718.7us +- 843.6us, error 0.2%
(kms_flip:2826) DEBUG: name = vblank
last_ts = 345.165405
last_received_ts = 345.164795
last_seq = 15062
current_ts = 345.333801
current_received_ts = 345.333221
current_seq = 15072
count = 11
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 345.165405/15062, current 345.333801/15072: elapsed=168383.0us expected=168718.7us +- 843.6us, error 0.2%
(kms_flip:2826) DEBUG: name = vblank
last_ts = 345.333801
last_received_ts = 345.333221
last_seq = 15072
current_ts = 345.502167
current_received_ts = 345.501495
current_seq = 15082
count = 12
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 345.333801/15072, current 345.502167/15082: elapsed=168379.0us expected=168718.7us +- 843.6us, error 0.2%
(kms_flip:2826) DEBUG: name = vblank
last_ts = 345.502167
last_received_ts = 345.501495
last_seq = 15082
current_ts = 345.670532
current_received_ts = 345.669922
current_seq = 15092
count = 13
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 345.502167/15082, current 345.670532/15092: elapsed=168362.0us expected=168718.7us +- 843.6us, error 0.2%
(kms_flip:2826) DEBUG: name = vblank
last_ts = 345.670532
last_received_ts = 345.669922
last_seq = 15092
current_ts = 345.838867
current_received_ts = 345.838226
current_seq = 15102
count = 14
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 345.670532/15092, current 345.838867/15102: elapsed=168330.0us expected=168718.7us +- 843.6us, error 0.2%
(kms_flip:2826) DEBUG: name = vblank
last_ts = 345.838867
last_received_ts = 345.838226
last_seq = 15102
current_ts = 346.007111
current_received_ts = 346.006439
current_seq = 15112
count = 15
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 345.838867/15102, current 346.007111/15112: elapsed=168235.0us expected=168718.7us +- 843.6us, error 0.3%
(kms_flip:2826) DEBUG: name = vblank
last_ts = 346.007111
last_received_ts = 346.006439
last_seq = 15112
current_ts = 346.175323
current_received_ts = 346.174713
current_seq = 15122
count = 16
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 346.007111/15112, current 346.175323/15122: elapsed=168224.0us expected=168718.7us +- 843.6us, error 0.3%
(kms_flip:2826) DEBUG: name = vblank
last_ts = 346.175323
last_received_ts = 346.174713
last_seq = 15122
current_ts = 346.343567
current_received_ts = 346.342957
current_seq = 15132
count = 17
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 346.175323/15122, current 346.343567/15132: elapsed=168234.0us expected=168718.7us +- 843.6us, error 0.3%
(kms_flip:2826) DEBUG: name = vblank
last_ts = 346.343567
last_received_ts = 346.342957
last_seq = 15132
current_ts = 346.511810
current_received_ts = 346.511078
current_seq = 15142
count = 18
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 346.343567/15132, current 346.511810/15142: elapsed=168249.0us expected=168718.7us +- 843.6us, error 0.3%
(kms_flip:2826) DEBUG: name = vblank
last_ts = 346.511810
last_received_ts = 346.511078
last_seq = 15142
current_ts = 346.680023
current_received_ts = 346.679413
current_seq = 15152
count = 19
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 346.511810/15142, current 346.680023/15152: elapsed=168222.0us expected=168718.7us +- 843.6us, error 0.3%
(kms_flip:2826) DEBUG: name = vblank
last_ts = 346.680023
last_received_ts = 346.679413
last_seq = 15152
current_ts = 346.848206
current_received_ts = 346.847565
current_seq = 15162
count = 20
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 346.680023/15152, current 346.848206/15162: elapsed=168173.0us expected=168718.7us +- 843.6us, error 0.3%
(kms_flip:2826) DEBUG: name = vblank
last_ts = 346.848206
last_received_ts = 346.847565
last_seq = 15162
current_ts = 347.016327
current_received_ts = 347.015625
current_seq = 15172
count = 21
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 346.848206/15162, current 347.016327/15172: elapsed=168119.0us expected=168718.7us +- 843.6us, error 0.4%
(kms_flip:2826) DEBUG: name = vblank
last_ts = 347.016327
last_received_ts = 347.015625
last_seq = 15172
current_ts = 347.184418
current_received_ts = 347.183838
current_seq = 15182
count = 22
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 347.016327/15172, current 347.184418/15182: elapsed=168108.0us expected=168718.7us +- 843.6us, error 0.4%
(kms_flip:2826) DEBUG: name = vblank
last_ts = 347.184418
last_received_ts = 347.183838
last_seq = 15182
current_ts = 347.352539
current_received_ts = 347.351868
current_seq = 15192
count = 23
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 347.184418/15182, current 347.352539/15192: elapsed=168124.0us expected=168718.7us +- 843.6us, error 0.4%
(kms_flip:2826) DEBUG: name = vblank
last_ts = 347.352539
last_received_ts = 347.351868
last_seq = 15192
current_ts = 347.520660
current_received_ts = 347.520081
current_seq = 15202
count = 24
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 347.352539/15192, current 347.520660/15202: elapsed=168101.0us expected=168718.7us +- 843.6us, error 0.4%
(kms_flip:2826) DEBUG: name = vblank
last_ts = 347.520660
last_received_ts = 347.520081
last_seq = 15202
current_ts = 347.688782
current_received_ts = 347.688141
current_seq = 15212
count = 25
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 347.520660/15202, current 347.688782/15212: elapsed=168126.0us expected=168718.7us +- 843.6us, error 0.4%
(kms_flip:2826) DEBUG: name = vblank
last_ts = 347.688782
last_received_ts = 347.688141
last_seq = 15212
current_ts = 347.856842
current_received_ts = 347.856110
current_seq = 15222
count = 26
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 347.688782/15212, current 347.856842/15222: elapsed=168055.0us expected=168718.7us +- 843.6us, error 0.4%
(kms_flip:2826) DEBUG: name = vblank
last_ts = 347.856842
last_received_ts = 347.856110
last_seq = 15222
current_ts = 348.024841
current_received_ts = 348.024200
current_seq = 15232
count = 27
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 347.856842/15222, current 348.024841/15232: elapsed=168007.0us expected=168718.7us +- 843.6us, error 0.4%
(kms_flip:2826) DEBUG: name = vblank
last_ts = 348.024841
last_received_ts = 348.024200
last_seq = 15232
current_ts = 348.192841
current_received_ts = 348.192200
current_seq = 15242
count = 28
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 348.024841/15232, current 348.192841/15242: elapsed=167993.0us expected=168718.7us +- 843.6us, error 0.4%
(kms_flip:2826) DEBUG: name = vblank
last_ts = 348.192841
last_received_ts = 348.192200
last_seq = 15242
current_ts = 348.360840
current_received_ts = 348.360199
current_seq = 15252
count = 29
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 348.192841/15242, current 348.360840/15252: elapsed=168002.0us expected=168718.7us +- 843.6us, error 0.4%
(kms_flip:2826) DEBUG: name = vblank
last_ts = 348.360840
last_received_ts = 348.360199
last_seq = 15252
current_ts = 348.528839
current_received_ts = 348.528198
current_seq = 15262
count = 30
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 348.360840/15252, current 348.528839/15262: elapsed=168004.0us expected=168718.7us +- 843.6us, error 0.4%
(kms_flip:2826) DEBUG: name = vblank
last_ts = 348.528839
last_received_ts = 348.528198
last_seq = 15262
current_ts = 348.696869
current_received_ts = 348.696228
current_seq = 15272
count = 31
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 348.528839/15262, current 348.696869/15272: elapsed=168016.0us expected=168718.7us +- 843.6us, error 0.4%
(kms_flip:2826) DEBUG: name = vblank
last_ts = 348.696869
last_received_ts = 348.696228
last_seq = 15272
current_ts = 348.864807
current_received_ts = 348.864136
current_seq = 15282
count = 32
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 348.696869/15272, current 348.864807/15282: elapsed=167944.0us expected=168718.7us +- 843.6us, error 0.5%
(kms_flip:2826) DEBUG: name = vblank
last_ts = 348.864807
last_received_ts = 348.864136
last_seq = 15282
current_ts = 349.032715
current_received_ts = 349.032013
current_seq = 15292
count = 33
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 348.864807/15282, current 349.032715/15292: elapsed=167909.0us expected=168718.7us +- 843.6us, error 0.5%
(kms_flip:2826) DEBUG: name = vblank
last_ts = 349.032715
last_received_ts = 349.032013
last_seq = 15292
current_ts = 349.200623
current_received_ts = 349.200043
current_seq = 15302
count = 34
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 349.032715/15292, current 349.200623/15302: elapsed=167907.0us expected=168718.7us +- 843.6us, error 0.5%
(kms_flip:2826) DEBUG: name = vblank
last_ts = 349.200623
last_received_ts = 349.200043
last_seq = 15302
current_ts = 349.368530
current_received_ts = 349.367920
current_seq = 15312
count = 35
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 349.200623/15302, current 349.368530/15312: elapsed=167927.0us expected=168718.7us +- 843.6us, error 0.5%
(kms_flip:2826) DEBUG: name = vblank
last_ts = 349.368530
last_received_ts = 349.367920
last_seq = 15312
current_ts = 349.536438
current_received_ts = 349.535797
current_seq = 15322
count = 36
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 349.368530/15312, current 349.536438/15322: elapsed=167895.0us expected=168718.7us +- 843.6us, error 0.5%
(kms_flip:2826) DEBUG: name = vblank
last_ts = 349.536438
last_received_ts = 349.535797
last_seq = 15322
current_ts = 349.704376
current_received_ts = 349.703766
current_seq = 15332
count = 37
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 349.536438/15322, current 349.704376/15332: elapsed=167926.0us expected=168718.7us +- 843.6us, error 0.5%
(kms_flip:2826) DEBUG: name = vblank
last_ts = 349.704376
last_received_ts = 349.703766
last_seq = 15332
current_ts = 349.872223
current_received_ts = 349.871552
current_seq = 15342
count = 38
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 349.704376/15332, current 349.872223/15342: elapsed=167845.0us expected=168718.7us +- 843.6us, error 0.5%
(kms_flip:2826) DEBUG: inconsistent vblank ts/seq: last 349.704376/15332, current 349.872223/15342: elapsed=167845.0us expected=168718.7us
(kms_flip:2826) DEBUG: name = vblank
last_ts = 349.872223
last_received_ts = 349.871552
last_seq = 15342
current_ts = 350.040039
current_received_ts = 350.039337
current_seq = 15352
count = 39
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 349.872223/15342, current 350.040039/15352: elapsed=167834.0us expected=168718.7us +- 843.6us, error 0.5%
(kms_flip:2826) DEBUG: inconsistent vblank ts/seq: last 349.872223/15342, current 350.040039/15352: elapsed=167834.0us expected=168718.7us
(kms_flip:2826) DEBUG: name = vblank
last_ts = 350.040039
last_received_ts = 350.039337
last_seq = 15352
current_ts = 350.207886
current_received_ts = 350.207245
current_seq = 15362
count = 40
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 350.040039/15352, current 350.207886/15362: elapsed=167837.0us expected=168718.7us +- 843.6us, error 0.5%
(kms_flip:2826) DEBUG: inconsistent vblank ts/seq: last 350.040039/15352, current 350.207886/15362: elapsed=167837.0us expected=168718.7us
(kms_flip:2826) DEBUG: name = vblank
last_ts = 350.207886
last_received_ts = 350.207245
last_seq = 15362
current_ts = 350.375702
current_received_ts = 350.375153
current_seq = 15372
count = 41
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 350.207886/15362, current 350.375702/15372: elapsed=167819.0us expected=168718.7us +- 843.6us, error 0.5%
(kms_flip:2826) DEBUG: inconsistent vblank ts/seq: last 350.207886/15362, current 350.375702/15372: elapsed=167819.0us expected=168718.7us
(kms_flip:2826) DEBUG: name = vblank
last_ts = 350.375702
last_received_ts = 350.375153
last_seq = 15372
current_ts = 350.543518
current_received_ts = 350.542877
current_seq = 15382
count = 42
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 350.375702/15372, current 350.543518/15382: elapsed=167822.0us expected=168718.7us +- 843.6us, error 0.5%
(kms_flip:2826) DEBUG: inconsistent vblank ts/seq: last 350.375702/15372, current 350.543518/15382: elapsed=167822.0us expected=168718.7us
(kms_flip:2826) DEBUG: name = vblank
last_ts = 350.543518
last_received_ts = 350.542877
last_seq = 15382
current_ts = 350.711365
current_received_ts = 350.710724
current_seq = 15392
count = 43
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 350.543518/15382, current 350.711365/15392: elapsed=167831.0us expected=168718.7us +- 843.6us, error 0.5%
(kms_flip:2826) DEBUG: inconsistent vblank ts/seq: last 350.543518/15382, current 350.711365/15392: elapsed=167831.0us expected=168718.7us
(kms_flip:2826) DEBUG: name = vblank
last_ts = 350.711365
last_received_ts = 350.710724
last_seq = 15392
current_ts = 350.879120
current_received_ts = 350.878540
current_seq = 15402
count = 44
seq_step = 10
(kms_flip:2826) DEBUG: vblank ts/seq: last 350.711365/15392, current 350.879120/15402: elapsed=167781.0us expected=168718.7us +- 843.6us, error 0.6%
(kms_flip:2826) DEBUG: inconsistent vblank ts/seq: last 350.711365/15392, current 350.879120/15402: elapsed=167781.0us expected=168718.7us
(kms_flip:2826) INFO: Event vblank: expected 448, counted 450, passrate = 84.44%, encoder type 1
(kms_flip:2826) DEBUG: dropped frames, expected 448, counted 450, passrate = 84.44%, encoder type 1
(kms_flip:2826) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1707:
(kms_flip:2826) CRITICAL: Failed assertion: !retried
(kms_flip:2826) CRITICAL: Last errno: 4, Interrupted system call
(kms_flip:2826) igt_core-INFO: Stack trace:
(kms_flip:2826) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:2826) igt_core-INFO:   #1 ../tests/kms_flip.c:428 run_test_on_crtc_set.constprop.0()
(kms_flip:2826) igt_core-INFO:   #2 ../tests/kms_flip.c:1887 run_test()
(kms_flip:2826) igt_core-INFO:   #3 ../tests/kms_flip.c:2156 __igt_unique____real_main2043()
(kms_flip:2826) igt_core-INFO:   #4 ../tests/kms_flip.c:2043 main()
(kms_flip:2826) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:2826) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:2826) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest A-VGA1: FAIL (18.888s)
Dmesg
<6> [331.116381] Console: switching to colour dummy device 80x25
<6> [331.117006] [IGT] kms_flip: executing
<7> [331.122295] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [331.123277] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [331.123820] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [331.127817] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:83:DP-1]
<7> [331.127827] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:83:DP-1]
<7> [331.130680] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.133711] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.136666] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.139549] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.142451] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.145339] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.148274] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.151215] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.154184] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.157273] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.160247] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.163161] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.166040] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.168934] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.171841] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.174763] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.177667] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.180580] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.183533] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.186507] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.189525] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.192529] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.195610] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.198646] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.201555] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.204535] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.207520] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.210474] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.213464] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.216489] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.219491] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.222543] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.222912] 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> [331.225551] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.228460] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.231398] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.234389] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.237382] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.240379] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.243275] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.246148] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.249044] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.251923] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.254809] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.257700] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.260586] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.263485] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.266479] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.269543] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.272514] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.275517] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.278560] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.281662] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.284783] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.287754] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.290807] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.293853] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.296905] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.299978] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.303019] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.306082] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.309122] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.312158] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.315242] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.318349] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.318762] 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> [331.318807] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:83:DP-1] disconnected
<7> [331.319307] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:72:VGA-1]
<7> [331.319319] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] [CONNECTOR:72:VGA-1] force=1
<7> [331.319683] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] ironlake hotplug adpa=0x83f40000, result 1
<7> [331.320024] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] CRT detected via hotplug
<7> [331.320610] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [331.320954] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK on first message, retry
<7> [331.321477] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [331.321749] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [331.321758] i915 0000:00:02.0: [drm:intel_crt_get_edid [i915]] CRT GMBUS EDID read failed, retry using GPIO bit-banging
<7> [331.322059] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] enabling bit-banging on i915 gmbus vga. force bit now 1
<7> [331.323281] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [331.323287] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] disabling bit-banging on i915 gmbus vga. force bit now 0
<7> [331.323586] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:72:VGA-1] probed modes:
<7> [331.323592] 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> [331.323597] 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> [331.323601] 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> [331.323606] 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> [331.323610] 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> [331.323633] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:75:HDMI-A-1]
<7> [331.323641] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:75:HDMI-A-1]
<7> [331.348765] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:75:HDMI-A-1] HDMI: DVI dual 0, max TMDS clock 590000 kHz
<7> [331.348774] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:75:HDMI-A-1] HDMI sink does deep color 30.
<7> [331.348779] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:75:HDMI-A-1] HDMI sink does deep color 36.
<7> [331.348782] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:75:HDMI-A-1] HDMI sink does deep color 48.
<7> [331.348786] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:75:HDMI-A-1] Assigning HDMI sink color depth as 16 bpc.
<7> [331.348790] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:75:HDMI-A-1] HDMI sink does YCRCB444 in deep color.
<7> [331.348795] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:75:HDMI-A-1] ELD monitor Mi TV
<7> [331.348799] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:75:HDMI-A-1] HDMI: latency present 0 0, video latency 0 0, audio latency 0 0
<7> [331.348803] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:75:HDMI-A-1] ELD size 36, SAD count 3
<7> [331.349000] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpd] NAK for addr: 0040 w(1)
<7> [331.349231] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpd] NAK on first message, retry
<7> [331.349594] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpd] NAK for addr: 0040 w(1)
<7> [331.349830] i915 0000:00:02.0: [drm:drm_dp_dual_mode_detect [drm_display_helper]] DP dual mode HDMI ID: (err -6)
<7> [331.350984] 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> [331.350998] 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> [331.351006] 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> [331.351015] 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> [331.351023] 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> [331.351031] 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> [331.351039] 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> [331.351046] 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> [331.351054] 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> [331.351062] 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> [331.351070] 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> [331.351077] 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> [331.351085] 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> [331.351093] 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> [331.351100] 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> [331.351108] 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> [331.351116] 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> [331.351123] 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> [331.351131] 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> [331.351144] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:75:HDMI-A-1] probed modes:
<7> [331.351149] 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> [331.351153] 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> [331.351157] 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> [331.351173] 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> [331.351177] 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> [331.351181] 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> [331.351185] 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> [331.351189] 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> [331.351193] 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> [331.351197] 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> [331.351201] 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> [331.351205] 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> [331.351209] 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> [331.351213] 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> [331.351217] 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> [331.351221] 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> [331.351225] 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> [331.351229] 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> [331.351233] 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> [331.351237] 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> [331.351241] 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> [331.351245] 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> [331.351249] 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> [331.351253] 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> [331.351257] 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> [331.351261] 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> [331.351265] 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> [331.351269] 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> [331.351273] 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> [331.351276] 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> [331.351280] 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> [331.351284] 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> [331.351288] 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> [331.351292] 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> [331.351296] 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> [331.351300] 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> [331.351304] 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> [331.351307] 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> [331.351311] 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> [331.351315] 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> [331.351319] 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> [331.351323] 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> [331.351327] 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> [331.351331] 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> [331.351334] 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> [331.351420] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:83:DP-1]
<7> [331.351427] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:83:DP-1]
<7> [331.354228] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.357188] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.360144] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.363103] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.366018] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.368964] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.371942] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.374848] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.377760] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.380670] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.383606] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.386538] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.389437] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.392354] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.395266] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.398182] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.401114] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.404044] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.406986] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.409945] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.412913] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.415858] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.418810] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.421732] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.424664] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.427584] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.430548] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.433501] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.436473] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.439448] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.442447] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.445449] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.445837] 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> [331.448471] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.451492] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.454426] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.457388] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.460295] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.463328] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.466265] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.469222] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.472125] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.475037] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.478002] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.480954] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.483899] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.486848] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.489835] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.492738] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.495652] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.498590] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.501566] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.504477] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.507378] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.510297] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.513207] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.516087] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.519035] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.521946] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.524897] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.527839] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.530808] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.533723] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.536627] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.539547] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.539901] 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> [331.539983] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:83:DP-1] disconnected
<6> [331.549179] [IGT] kms_flip: starting subtest wf_vblank-ts-check-interruptible
<6> [331.550875] [IGT] kms_flip: starting dynamic subtest A-VGA1
<7> [331.551537] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:91]
<7> [331.551688] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:92]
<7> [331.581907] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:52:pipe A]
<7> [331.582172] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [331.582476] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [331.582713] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [331.583216] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in has_pch_encoder (expected yes, found no)
<7> [331.583468] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in fdi_lanes (expected 1, found 0)
<7> [331.583787] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in fdi_m_n (expected tu 64 data 6039797/8388608 link 125829/524288, found tu 0, data 0/0 link 0/0)
<7> [331.584073] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in output_types (expected 0x00000002, found 0x00000000)
<7> [331.584372] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [331.584678] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1024, found 0)
<7> [331.585031] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 1344, found 0)
<7> [331.585312] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1024, found 0)
<7> [331.585578] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 1344, found 0)
<7> [331.585999] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 1048, found 0)
<7> [331.586334] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 1184, found 0)
<7> [331.586644] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 768, found 0)
<7> [331.587095] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 768, found 0)
<7> [331.587331] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 771, found 0)
<7> [331.587572] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 777, found 0)
<7> [331.587833] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 806, found 0)
<7> [331.588079] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 806, found 0)
<7> [331.588313] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1024, found 0)
<7> [331.588547] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 1344, found 0)
<7> [331.588878] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1024, found 0)
<7> [331.589160] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 1344, found 0)
<7> [331.589395] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 1048, found 0)
<7> [331.589705] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 1184, found 0)
<7> [331.589945] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 768, found 0)
<7> [331.590201] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 768, found 0)
<7> [331.590459] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 771, found 0)
<7> [331.590716] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 777, found 0)
<7> [331.590945] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 806, found 0)
<7> [331.591172] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 806, found 0)
<7> [331.591465] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [331.591767] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)
<7> [331.592010] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [331.592263] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [331.592492] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 64800, found 0)
<7> [331.592785] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 64800, found 0)
<7> [331.593023] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in port_clock (expected 64800, found 0)
<7> [331.593253] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:52:pipe A] fastset requirement not met, forcing full modeset
<7> [331.593502] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:52:pipe A] releasing PCH DPLL A
<7> [331.593777] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:52:pipe A] min cdclk: 72000 kHz -> 0 kHz
<7> [331.594078] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:52:pipe A] enable: no [modeset]
<7> [331.594349] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:34:primary A] fb: [NOFB], visible: no
<7> [331.594754] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [331.596598] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port B found
<7> [331.596855] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port C found
<7> [331.597077] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port D found
<7> [331.613345] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable PCH DPLL A (active 0x1, on? 1) for [CRTC:52:pipe A]
<7> [331.613919] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling PCH DPLL A
<7> [331.614644] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:73:CRT]
<7> [331.615155] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:HDMI D]
<7> [331.615649] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:82:DP D]
<7> [331.616152] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:72:VGA-1]
<7> [331.616780] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:52:pipe A]
<7> [331.617415] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:71:pipe B]
<7> [331.617670] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.enable (expected yes, found no)
<7> [331.618189] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.active (expected yes, found no)
<7> [331.618489] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in cpu_transcoder (expected 1, found -1)
<7> [331.618766] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in has_pch_encoder (expected yes, found no)
<7> [331.619035] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in fdi_lanes (expected 3, found 0)
<7> [331.619271] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in fdi_m_n (expected tu 64 data 6934582/8388608 link 288940/524288, found tu 0, data 0/0 link 0/0)
<7> [331.619535] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in lane_count (expected 4, found 0)
<7> [331.619787] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [331.620022] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [331.620256] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [331.620610] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [331.620862] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [331.621097] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [331.621331] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [331.621669] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [331.621907] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [331.622143] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [331.622432] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [331.622670] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [331.622897] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [331.623134] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [331.623398] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [331.623647] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [331.623891] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [331.624141] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [331.624402] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [331.624663] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [331.624902] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [331.625129] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [331.625436] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [331.625674] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [331.625901] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [331.626128] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [331.626393] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [331.626675] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [331.626940] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [331.627175] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [331.627430] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [331.627695] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in pipe_bpp (expected 36, found 0)
<7> [331.627930] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148800, found 0)
<7> [331.628197] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148800, found 0)
<7> [331.628432] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in port_clock (expected 223200, found 0)
<7> [331.628667] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [331.628901] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in infoframes.gcp (expected 0x00000006, found 0x00000000)
<7> [331.629167] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in avi infoframe
<7> [331.629417] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [331.629651] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [331.629654] i915 0000:00:02.0: colorspace: RGB
<7> [331.629656] i915 0000:00:02.0: scan mode: Underscan
<7> [331.629658] i915 0000:00:02.0: colorimetry: No Data
<7> [331.629660] i915 0000:00:02.0: picture aspect: 16:9
<7> [331.629662] i915 0000:00:02.0: active aspect: Same as Picture
<7> [331.629664] i915 0000:00:02.0: itc: No Data
<7> [331.629666] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [331.629668] i915 0000:00:02.0: quantization range: Default
<7> [331.629670] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [331.629672] i915 0000:00:02.0: video code: 16
<7> [331.629674] i915 0000:00:02.0: ycc quantization range: Full
<7> [331.629676] i915 0000:00:02.0: hdmi content type: Graphics
<7> [331.629678] i915 0000:00:02.0: pixel repeat: 0
<7> [331.629680] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [331.629682] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [331.629916] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in spd infoframe
<7> [331.630205] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [331.630432] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [331.630435] i915 0000:00:02.0: vendor: Intel
<7> [331.630436] i915 0000:00:02.0: product: Integrated gfx
<7> [331.630438] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [331.630441] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [331.630685] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hdmi infoframe
<7> [331.630919] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [331.631184] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [331.631187] i915 0000:00:02.0: empty frame
<7> [331.631189] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [331.631427] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:71:pipe B] fastset requirement not met, forcing full modeset
<7> [331.631655] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:71:pipe B] releasing PCH DPLL B
<7> [331.631930] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:71:pipe B] min cdclk: 165334 kHz -> 0 kHz
<7> [331.632223] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:71:pipe B] enable: no [modeset]
<7> [331.632459] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:53:primary B] fb: [NOFB], visible: no
<7> [331.632714] i915 0000:00:02.0: [drm:intel_audio_codec_disable [i915]] [CONNECTOR:75:HDMI-A-1][ENCODER:74:HDMI D] Disable audio codec on [CRTC:71:pipe B]
<7> [331.661093] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe B
<7> [331.677678] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port B found
<7> [331.678159] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port C found
<7> [331.678666] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port D found
<7> [331.690156] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable PCH DPLL B (active 0x2, on? 1) for [CRTC:71:pipe B]
<7> [331.690666] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling PCH DPLL B
<7> [331.691441] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:73:CRT]
<7> [331.691992] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:HDMI D]
<7> [331.692505] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:82:DP D]
<7> [331.693104] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:75:HDMI-A-1]
<7> [331.693429] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:71:pipe B]
<7> [331.693798] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:52:pipe A]
<7> [331.693827] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:72:VGA-1]
<7> [331.693939] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:72:VGA-1] Limiting target display pipe bpp to 24 (EDID bpp 0, max requested bpp 0, max platform bpp 36)
<7> [331.694205] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:52:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [331.694461] i915 0000:00:02.0: [drm:intel_fdi_atomic_check_link [i915]] checking fdi config on pipe A, lanes 1
<7> [331.694715] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [331.694951] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [331.695186] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [331.695421] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in has_pch_encoder (expected no, found yes)
<7> [331.695699] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in fdi_lanes (expected 0, found 1)
<7> [331.695977] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in fdi_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 6039797/8388608 link 125829/524288)
<7> [331.696214] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000002)
<7> [331.696481] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [331.696736] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1024)
<7> [331.696996] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 1344)
<7> [331.697232] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1024)
<7> [331.697466] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 1344)
<7> [331.697728] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 1048)
<7> [331.697970] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 1184)
<7> [331.698279] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 768)
<7> [331.698597] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 768)
<7> [331.698826] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 771)
<7> [331.699069] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 777)
<7> [331.699309] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 806)
<7> [331.699570] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 806)
<7> [331.699837] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1024)
<7> [331.700065] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 1344)
<7> [331.700292] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1024)
<7> [331.700587] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 1344)
<7> [331.700905] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 1048)
<7> [331.701162] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 1184)
<7> [331.701430] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 768)
<7> [331.701681] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 768)
<7> [331.701910] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 771)
<7> [331.702158] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 777)
<7> [331.702439] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 806)
<7> [331.702679] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 806)
<7> [331.702907] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [331.703145] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [331.703480] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [331.703746] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [331.703975] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 64800)
<7> [331.704265] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 64800)
<7> [331.704515] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in port_clock (expected 0, found 64800)
<7> [331.704744] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:52:pipe A] fastset requirement not met, forcing full modeset
<7> [331.705022] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:52:pipe A] min cdclk: 0 kHz -> 72000 kHz
<7> [331.705327] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:52:pipe A] allocated PCH DPLL A
<7> [331.705564] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:52:pipe A] reserving PCH DPLL A
<7> [331.705820] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:52:pipe A] enable: yes [modeset]
<7> [331.706108] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: ANALOG (0x2), output format: RGB, sink format: RGB
<7> [331.706390] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [331.706640] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [331.706871] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [331.707117] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [331.707391] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [331.707618] 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> [331.707845] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [331.708111] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 1
<7> [331.708422] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [331.708702] 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> [331.708930] 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> [331.709204] 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> [331.709451] 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> [331.709679] 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> [331.709925] 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> [331.710189] 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> [331.710430] 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> [331.710658] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 64800, pipe src: 1024x768+0+0, pixel rate 64800, min cdclk 72000
<7> [331.710981] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 0, ips linetime: 0
<7> [331.711263] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [331.711490] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [331.711745] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: dpll: 0x84100010, dpll_md: 0x0, fp0: 0x10c09, fp1: 0x10c09
<7> [331.712006] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x3 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [331.712016] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling always-on
<7> [331.712252] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [331.712493] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [331.712720] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [331.712975] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [331.713212] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [331.713504] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [331.713776] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:34:primary A] fb: [FB:91] 1024x768 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [331.714067] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [331.714316] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1024.000000x768.000000+0.000000+0.000000 dst: 1024x768+0+0
<7> [331.715461] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling always-on
<7> [331.715707] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:73:CRT]
<7> [331.716049] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:HDMI D]
<7> [331.716277] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:82:DP D]
<7> [331.717058] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [331.718144] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x100
<7> [331.718425] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 1 done.
<7> [331.719351] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x200
<7> [331.719597] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 2 done.
<7> [331.719864] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train done.
<7> [331.720100] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable PCH DPLL A (active 0x1, on? 0) for [CRTC:52:pipe A]
<7> [331.720325] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling PCH DPLL A
<7> [331.772410] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:72:VGA-1]
<7> [331.773076] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:52:pipe A]
<7> [342.398379] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:52:pipe A]
<7> [342.398596] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [342.399209] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [342.400034] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [342.400837] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in has_pch_encoder (expected yes, found no)
<7> [342.401680] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in fdi_lanes (expected 1, found 0)
<7> [342.402530] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in fdi_m_n (expected tu 64 data 6039797/8388608 link 125829/524288, found tu 0, data 0/0 link 0/0)
<7> [342.402922] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in output_types (expected 0x00000002, found 0x00000000)
<7> [342.403307] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [342.403662] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1024, found 0)
<7> [342.404052] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 1344, found 0)
<7> [342.404424] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1024, found 0)
<7> [342.404779] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 1344, found 0)
<7> [342.405175] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 1048, found 0)
<7> [342.405546] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 1184, found 0)
<7> [342.405900] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 768, found 0)
<7> [342.406287] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 768, found 0)
<7> [342.406642] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 771, found 0)
<7> [342.407045] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 777, found 0)
<7> [342.407401] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 806, found 0)
<7> [342.407755] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 806, found 0)
<7> [342.408138] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1024, found 0)
<7> [342.408493] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 1344, found 0)
<7> [342.408848] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1024, found 0)
<7> [342.409239] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 1344, found 0)
<7> [342.409593] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 1048, found 0)
<7> [342.409988] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 1184, found 0)
<7> [342.410358] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 768, found 0)
<7> [342.410712] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 768, found 0)
<7> [342.411096] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 771, found 0)
<7> [342.411466] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 777, found 0)
<7> [342.411820] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 806, found 0)
<7> [342.412210] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 806, found 0)
<7> [342.412565] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [342.412959] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)
<7> [342.413313] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [342.413668] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [342.414049] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 64800, found 0)
<7> [342.414401] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 64800, found 0)
<7> [342.414755] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in port_clock (expected 64800, found 0)
<7> [342.415144] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:52:pipe A] fastset requirement not met, forcing full modeset
<7> [342.415515] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:52:pipe A] releasing PCH DPLL A
<7> [342.415963] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:52:pipe A] min cdclk: 72000 kHz -> 0 kHz
<7> [342.416322] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:52:pipe A] enable: no [modeset]
<7> [342.416673] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:34:primary A] fb: [NOFB], visible: no
<7> [342.417188] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [342.423011] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port B found
<7> [342.423357] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port C found
<7> [342.423689] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port D found
<7> [342.437962] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable PCH DPLL A (active 0x1, on? 1) for [CRTC:52:pipe A]
<7> [342.438475] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling PCH DPLL A
<7> [342.439248] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:73:CRT]
<7> [342.439606] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:HDMI D]
<7> [342.439980] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:82:DP D]
<7> [342.440319] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:72:VGA-1]
<7> [342.440961] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:52:pipe A]
<7> [342.441317] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:71:pipe B]
<7> [342.441458] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:52:pipe A]
<7> [342.441486] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:72:VGA-1]
<7> [342.441572] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:72:VGA-1] Limiting target display pipe bpp to 24 (EDID bpp 0, max requested bpp 0, max platform bpp 36)
<7> [342.441844] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:52:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [342.442134] i915 0000:00:02.0: [drm:intel_fdi_atomic_check_link [i915]] checking fdi config on pipe A, lanes 1
<7> [342.442489] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [342.442878] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [342.443230] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [342.443541] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in has_pch_encoder (expected no, found yes)
<7> [342.443815] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in fdi_lanes (expected 0, found 1)
<7> [342.444045] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in fdi_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 6039797/8388608 link 125829/524288)
<7> [342.444275] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000002)
<7> [342.444503] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [342.444763] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1024)
<7> [342.445032] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 1344)
<7> [342.445386] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1024)
<7> [342.445780] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 1344)
<7> [342.446133] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 1048)
<7> [342.446457] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 1184)
<7> [342.446744] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 768)
<7> [342.447010] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 768)
<7> [342.447239] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 771)
<7> [342.447467] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 777)
<7> [342.447726] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 806)
<7> [342.447954] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 806)
<7> [342.448239] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1024)
<7> [342.448666] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 1344)
<7> [342.449023] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1024)
<7> [342.449371] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 1344)
<7> [342.449765] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 1048)
<7> [342.450024] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 1184)
<7> [342.450253] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 768)
<7> [342.450481] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 768)
<7> [342.450763] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 771)
<7> [342.451016] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 777)
<7> [342.451244] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 806)
<7> [342.451566] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 806)
<7> [342.451922] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [342.452277] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [342.452660] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [342.452968] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [342.453198] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 64800)
<7> [342.453427] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 64800)
<7> [342.453711] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in port_clock (expected 0, found 64800)
<7> [342.453965] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:52:pipe A] fastset requirement not met, forcing full modeset
<7> [342.454222] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:52:pipe A] min cdclk: 0 kHz -> 72000 kHz
<7> [342.454556] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:52:pipe A] allocated PCH DPLL A
<7> [342.454906] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:52:pipe A] reserving PCH DPLL A
<7> [342.455260] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:52:pipe A] enable: yes [modeset]
<7> [342.455639] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: ANALOG (0x2), output format: RGB, sink format: RGB
<7> [342.455961] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [342.456198] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [342.456425] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [342.456693] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [342.456920] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [342.457147] 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> [342.457375] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [342.457705] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 1
<7> [342.458059] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [342.458413] 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> [342.458836] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling always-on
<7> [342.458813] 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> [342.459131] 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> [342.459360] 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> [342.459630] 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> [342.459859] 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> [342.460089] 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> [342.460317] 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> [342.460649] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 64800, pipe src: 1024x768+0+0, pixel rate 64800, min cdclk 72000
<7> [342.461005] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 0, ips linetime: 0
<7> [342.461353] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [342.461743] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [342.462093] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: dpll: 0x84100010, dpll_md: 0x0, fp0: 0x10c09, fp1: 0x10c09
<7> [342.462337] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x3 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [342.462608] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [342.462836] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [342.463063] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [342.463290] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [342.463546] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [342.463787] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [342.464137] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:34:primary A] fb: [FB:91] 1024x768 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [342.464528] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [342.464877] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1024.000000x768.000000+0.000000+0.000000 dst: 1024x768+0+0
<7> [342.465267] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling always-on
<7> [342.465947] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:73:CRT]
<7> [342.466186] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:HDMI D]
<7> [342.466435] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:82:DP D]
<7> [342.467247] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [342.468506] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x100
<7> [342.468738] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 1 done.
<7> [342.469624] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x200
<7> [342.469976] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 2 done.
<7> [342.470323] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train done.
<7> [342.470699] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable PCH DPLL A (active 0x1, on? 0) for [CRTC:52:pipe A]
<7> [342.470970] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling PCH DPLL A
<7> [342.522969] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:72:VGA-1]
<7> [342.523538] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:52:pipe A]
<6> [350.442025] [IGT] kms_flip: finished subtest A-VGA1, FAIL
Created at 2026-03-04 17:56:24