Results for igt@kms_flip@plain-flip-ts-check@a-vga1

Machine description: shard-snb4

Result: Fail

git-log-oneline i915_display_info3 igt_runner3 runtimes3 results3.json results3-i915-load.json i915_display_info_post_exec3 boot3 dmesg3

DetailValue
Duration 5.60 seconds
Hostname 
shard-snb4
Igt-Version 
IGT-Version: 1.30-g858841cdd (x86_64) (Linux: 6.14.0-rc5-CI_DRM_16222-gff2d693d8872+ x86_64)
Out 
Using IGT_SRANDOM=1741127056 for randomisation
Opened device: /dev/dri/card0
Using monotonic timestamps
Starting subtest: plain-flip-ts-check
Starting dynamic subtest: A-VGA1
  1024x768: 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa 
Expected frametime: 16666us; measured 17063.9us +- 1.652us accuracy 0.03%
vblank interval differs from modeline! expected 16665.6us, measured 17064us +- 1.652us, difference 398.3us (241.1 sigma)
Stack trace:
  #0 ../lib/igt_core.c:2055 __igt_fail_assert()
  #1 ../tests/kms_flip.c:791 run_test_on_crtc_set.constprop.0()
  #2 ../tests/kms_flip.c:1845 run_test()
  #3 ../tests/kms_flip.c:2078 __igt_unique____real_main2001()
  #4 ../tests/kms_flip.c:2001 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest A-VGA1: FAIL (5.596s)
Err 
Starting subtest: plain-flip-ts-check
Starting dynamic subtest: A-VGA1
(kms_flip:3070) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1674:
(kms_flip:3070) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon)
Dynamic subtest A-VGA1 failed.
**** DEBUG ****
(kms_flip:3070) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd)
(kms_flip:3070) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=1024, height=768, format=XR24(0x34325258), modifier=0x0, size=0)
(kms_flip:3070) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=2, pitch=4096)
(kms_flip:3070) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd)
(kms_flip:3070) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:3070) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:3070) igt_fb-DEBUG: Test requirement passed: cairo_surface_status(fb->cairo_surface) == CAIRO_STATUS_SUCCESS
(kms_flip:3070) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:3070) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:3070) igt_fb-DEBUG: Test requirement passed: cairo_surface_status(fb->cairo_surface) == CAIRO_STATUS_SUCCESS
(kms_flip:3070) igt_kms-INFO:   1024x768: 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa 
(kms_flip:3070) DEBUG: No stale events found
(kms_flip:3070) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0
(kms_flip:3070) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0
(kms_flip:3070) INFO: Expected frametime: 16666us; measured 17063.9us +- 1.652us accuracy 0.03%
(kms_flip:3070) INFO: vblank interval differs from modeline! expected 16665.6us, measured 17064us +- 1.652us, difference 398.3us (241.1 sigma)
(kms_flip:3070) DEBUG: name = flip
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 311.960327
current_received_ts = 311.959778
current_seq = 12673
count = 0
seq_step = 1
(kms_flip:3070) DEBUG: name = flip
last_ts = 311.960327
last_received_ts = 311.959778
last_seq = 12673
current_ts = 311.977386
current_received_ts = 311.976807
current_seq = 12674
count = 1
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 311.960327/12673, current 311.977386/12674: elapsed=17055.0us expected=17063.9us +- 85.3us, error 0.1%
(kms_flip:3070) DEBUG: name = flip
last_ts = 311.977386
last_received_ts = 311.976807
last_seq = 12674
current_ts = 311.994446
current_received_ts = 311.993896
current_seq = 12675
count = 2
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 311.977386/12674, current 311.994446/12675: elapsed=17064.0us expected=17063.9us +- 85.3us, error 0.0%
(kms_flip:3070) DEBUG: name = flip
last_ts = 311.994446
last_received_ts = 311.993896
last_seq = 12675
current_ts = 312.011505
current_received_ts = 312.010956
current_seq = 12676
count = 3
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 311.994446/12675, current 312.011505/12676: elapsed=17058.0us expected=17063.9us +- 85.3us, error 0.0%
(kms_flip:3070) DEBUG: name = flip
last_ts = 312.011505
last_received_ts = 312.010956
last_seq = 12676
current_ts = 312.028564
current_received_ts = 312.028046
current_seq = 12677
count = 4
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 312.011505/12676, current 312.028564/12677: elapsed=17071.0us expected=17063.9us +- 85.3us, error 0.0%
(kms_flip:3070) DEBUG: name = flip
last_ts = 312.028564
last_received_ts = 312.028046
last_seq = 12677
current_ts = 312.045654
current_received_ts = 312.045074
current_seq = 12678
count = 5
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 312.028564/12677, current 312.045654/12678: elapsed=17072.0us expected=17063.9us +- 85.3us, error 0.0%
(kms_flip:3070) DEBUG: name = flip
last_ts = 312.045654
last_received_ts = 312.045074
last_seq = 12678
current_ts = 312.062714
current_received_ts = 312.062134
current_seq = 12679
count = 6
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 312.045654/12678, current 312.062714/12679: elapsed=17054.0us expected=17063.9us +- 85.3us, error 0.1%
(kms_flip:3070) DEBUG: name = flip
last_ts = 312.062714
last_received_ts = 312.062134
last_seq = 12679
current_ts = 312.079773
current_received_ts = 312.079163
current_seq = 12680
count = 7
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 312.062714/12679, current 312.079773/12680: elapsed=17061.0us expected=17063.9us +- 85.3us, error 0.0%
(kms_flip:3070) DEBUG: name = flip
last_ts = 312.079773
last_received_ts = 312.079163
last_seq = 12680
current_ts = 312.096832
current_received_ts = 312.096161
current_seq = 12681
count = 8
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 312.079773/12680, current 312.096832/12681: elapsed=17067.0us expected=17063.9us +- 85.3us, error 0.0%
(kms_flip:3070) DEBUG: name = flip
last_ts = 312.096832
last_received_ts = 312.096161
last_seq = 12681
current_ts = 312.113892
current_received_ts = 312.113342
current_seq = 12682
count = 9
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 312.096832/12681, current 312.113892/12682: elapsed=17063.0us expected=17063.9us +- 85.3us, error 0.0%
(kms_flip:3070) DEBUG: name = flip
last_ts = 312.113892
last_received_ts = 312.113342
last_seq = 12682
current_ts = 312.130951
current_received_ts = 312.130402
current_seq = 12683
count = 10
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 312.113892/12682, current 312.130951/12683: elapsed=17063.0us expected=17063.9us +- 85.3us, error 0.0%
(kms_flip:3070) DEBUG: name = flip
last_ts = 312.130951
last_received_ts = 312.130402
last_seq = 12683
current_ts = 312.148010
current_received_ts = 312.147461
current_seq = 12684
count = 11
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 312.130951/12683, current 312.148010/12684: elapsed=17064.0us expected=17063.9us +- 85.3us, error 0.0%
(kms_flip:3070) DEBUG: name = flip
last_ts = 312.148010
last_received_ts = 312.147461
last_seq = 12684
current_ts = 312.165070
current_received_ts = 312.164520
current_seq = 12685
count = 12
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 312.148010/12684, current 312.165070/12685: elapsed=17064.0us expected=17063.9us +- 85.3us, error 0.0%
(kms_flip:3070) DEBUG: name = flip
last_ts = 312.165070
last_received_ts = 312.164520
last_seq = 12685
current_ts = 312.182159
current_received_ts = 312.181580
current_seq = 12686
count = 13
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 312.165070/12685, current 312.182159/12686: elapsed=17064.0us expected=17063.9us +- 85.3us, error 0.0%
(kms_flip:3070) DEBUG: name = flip
last_ts = 312.182159
last_received_ts = 312.181580
last_seq = 12686
current_ts = 312.199219
current_received_ts = 312.198669
current_seq = 12687
count = 14
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 312.182159/12686, current 312.199219/12687: elapsed=17064.0us expected=17063.9us +- 85.3us, error 0.0%
(kms_flip:3070) DEBUG: name = flip
last_ts = 312.199219
last_received_ts = 312.198669
last_seq = 12687
current_ts = 312.216278
current_received_ts = 312.215698
current_seq = 12688
count = 15
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 312.199219/12687, current 312.216278/12688: elapsed=17063.0us expected=17063.9us +- 85.3us, error 0.0%
(kms_flip:3070) DEBUG: name = flip
last_ts = 312.216278
last_received_ts = 312.215698
last_seq = 12688
current_ts = 312.233337
current_received_ts = 312.232788
current_seq = 12689
count = 16
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 312.216278/12688, current 312.233337/12689: elapsed=17064.0us expected=17063.9us +- 85.3us, error 0.0%
(kms_flip:3070) DEBUG: name = flip
last_ts = 312.233337
last_received_ts = 312.232788
last_seq = 12689
current_ts = 312.250397
current_received_ts = 312.249847
current_seq = 12690
count = 17
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 312.233337/12689, current 312.250397/12690: elapsed=17064.0us expected=17063.9us +- 85.3us, error 0.0%
(kms_flip:3070) DEBUG: name = flip
last_ts = 312.250397
last_received_ts = 312.249847
last_seq = 12690
current_ts = 312.267456
current_received_ts = 312.266907
current_seq = 12691
count = 18
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 312.250397/12690, current 312.267456/12691: elapsed=17064.0us expected=17063.9us +- 85.3us, error 0.0%
(kms_flip:3070) DEBUG: name = flip
last_ts = 312.267456
last_received_ts = 312.266907
last_seq = 12691
current_ts = 312.284515
current_received_ts = 312.283997
current_seq = 12692
count = 19
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 312.267456/12691, current 312.284515/12692: elapsed=17063.0us expected=17063.9us +- 85.3us, error 0.0%
(kms_flip:3070) DEBUG: name = flip
last_ts = 312.284515
last_received_ts = 312.283997
last_seq = 12692
current_ts = 312.301605
current_received_ts = 312.301056
current_seq = 12693
count = 20
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 312.284515/12692, current 312.301605/12693: elapsed=17063.0us expected=17063.9us +- 85.3us, error 0.0%
(kms_flip:3070) DEBUG: name = flip
last_ts = 312.301605
last_received_ts = 312.301056
last_seq = 12693
current_ts = 312.318665
current_received_ts = 312.318085
current_seq = 12694
count = 21
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 312.301605/12693, current 312.318665/12694: elapsed=17060.0us expected=17063.9us +- 85.3us, error 0.0%
(kms_flip:3070) DEBUG: name = flip
last_ts = 312.318665
last_received_ts = 312.318085
last_seq = 12694
current_ts = 312.335724
current_received_ts = 312.335144
current_seq = 12695
count = 22
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 312.318665/12694, current 312.335724/12695: elapsed=17069.0us expected=17063.9us +- 85.3us, error 0.0%
(kms_flip:3070) DEBUG: name = flip
last_ts = 312.335724
last_received_ts = 312.335144
last_seq = 12695
current_ts = 312.352783
current_received_ts = 312.352203
current_seq = 12696
count = 23
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 312.335724/12695, current 312.352783/12696: elapsed=17063.0us expected=17063.9us +- 85.3us, error 0.0%
(kms_flip:3070) DEBUG: name = flip
last_ts = 312.352783
last_received_ts = 312.352203
last_seq = 12696
current_ts = 312.369843
current_received_ts = 312.369293
current_seq = 12697
count = 24
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 312.352783/12696, current 312.369843/12697: elapsed=17064.0us expected=17063.9us +- 85.3us, error 0.0%
(kms_flip:3070) DEBUG: name = flip
last_ts = 312.369843
last_received_ts = 312.369293
last_seq = 12697
current_ts = 312.386902
current_received_ts = 312.386353
current_seq = 12698
count = 25
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 312.369843/12697, current 312.386902/12698: elapsed=17061.0us expected=17063.9us +- 85.3us, error 0.0%
(kms_flip:3070) DEBUG: name = flip
last_ts = 312.386902
last_received_ts = 312.386353
last_seq = 12698
current_ts = 312.403961
current_received_ts = 312.403412
current_seq = 12699
count = 26
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 312.386902/12698, current 312.403961/12699: elapsed=17067.0us expected=17063.9us +- 85.3us, error 0.0%
(kms_flip:3070) DEBUG: name = flip
last_ts = 312.403961
last_received_ts = 312.403412
last_seq = 12699
current_ts = 312.421021
current_received_ts = 312.420471
current_seq = 12700
count = 27
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 312.403961/12699, current 312.421021/12700: elapsed=17058.0us expected=17063.9us +- 85.3us, error 0.0%
(kms_flip:3070) DEBUG: name = flip
last_ts = 312.421021
last_received_ts = 312.420471
last_seq = 12700
current_ts = 312.438110
current_received_ts = 312.437469
current_seq = 12701
count = 28
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 312.421021/12700, current 312.438110/12701: elapsed=17068.0us expected=17063.9us +- 85.3us, error 0.0%
(kms_flip:3070) DEBUG: name = flip
last_ts = 312.438110
last_received_ts = 312.437469
last_seq = 12701
current_ts = 312.455170
current_received_ts = 312.454590
current_seq = 12702
count = 29
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 312.438110/12701, current 312.455170/12702: elapsed=17065.0us expected=17063.9us +- 85.3us, error 0.0%
(kms_flip:3070) DEBUG: name = flip
last_ts = 312.455170
last_received_ts = 312.454590
last_seq = 12702
current_ts = 312.472229
current_received_ts = 312.471680
current_seq = 12703
count = 30
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 312.455170/12702, current 312.472229/12703: elapsed=17063.0us expected=17063.9us +- 85.3us, error 0.0%
(kms_flip:3070) DEBUG: name = flip
last_ts = 312.472229
last_received_ts = 312.471680
last_seq = 12703
current_ts = 312.489288
current_received_ts = 312.488739
current_seq = 12704
count = 31
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 312.472229/12703, current 312.489288/12704: elapsed=17065.0us expected=17063.9us +- 85.3us, error 0.0%
(kms_flip:3070) DEBUG: name = flip
last_ts = 312.489288
last_received_ts = 312.488739
last_seq = 12704
current_ts = 312.506348
current_received_ts = 312.505798
current_seq = 12705
count = 32
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 312.489288/12704, current 312.506348/12705: elapsed=17054.0us expected=17063.9us +- 85.3us, error 0.1%
(kms_flip:3070) DEBUG: name = flip
last_ts = 312.506348
last_received_ts = 312.505798
last_seq = 12705
current_ts = 312.523407
current_received_ts = 312.522858
current_seq = 12706
count = 33
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 312.506348/12705, current 312.523407/12706: elapsed=17073.0us expected=17063.9us +- 85.3us, error 0.1%
(kms_flip:3070) DEBUG: name = flip
last_ts = 312.523407
last_received_ts = 312.522858
last_seq = 12706
current_ts = 312.540497
current_received_ts = 312.539917
current_seq = 12707
count = 34
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 312.523407/12706, current 312.540497/12707: elapsed=17063.0us expected=17063.9us +- 85.3us, error 0.0%
(kms_flip:3070) DEBUG: name = flip
last_ts = 312.540497
last_received_ts = 312.539917
last_seq = 12707
current_ts = 312.557556
current_received_ts = 312.556976
current_seq = 12708
count = 35
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 312.540497/12707, current 312.557556/12708: elapsed=17065.0us expected=17063.9us +- 85.3us, error 0.0%
(kms_flip:3070) DEBUG: name = flip
last_ts = 312.557556
last_received_ts = 312.556976
last_seq = 12708
current_ts = 312.574615
current_received_ts = 312.574036
current_seq = 12709
count = 36
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 312.557556/12708, current 312.574615/12709: elapsed=17054.0us expected=17063.9us +- 85.3us, error 0.1%
(kms_flip:3070) DEBUG: name = flip
last_ts = 312.574615
last_received_ts = 312.574036
last_seq = 12709
current_ts = 312.591675
current_received_ts = 312.591095
current_seq = 12710
count = 37
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 312.574615/12709, current 312.591675/12710: elapsed=17072.0us expected=17063.9us +- 85.3us, error 0.0%
(kms_flip:3070) DEBUG: name = flip
last_ts = 312.591675
last_received_ts = 312.591095
last_seq = 12710
current_ts = 312.608734
current_received_ts = 312.608185
current_seq = 12711
count = 38
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 312.591675/12710, current 312.608734/12711: elapsed=17065.0us expected=17063.9us +- 85.3us, error 0.0%
(kms_flip:3070) DEBUG: name = flip
last_ts = 312.608734
last_received_ts = 312.608185
last_seq = 12711
current_ts = 312.625793
current_received_ts = 312.625183
current_seq = 12712
count = 39
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 312.608734/12711, current 312.625793/12712: elapsed=17054.0us expected=17063.9us +- 85.3us, error 0.1%
(kms_flip:3070) DEBUG: name = flip
last_ts = 312.625793
last_received_ts = 312.625183
last_seq = 12712
current_ts = 312.642853
current_received_ts = 312.642273
current_seq = 12713
count = 40
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 312.625793/12712, current 312.642853/12713: elapsed=17073.0us expected=17063.9us +- 85.3us, error 0.1%
(kms_flip:3070) DEBUG: name = flip
last_ts = 312.642853
last_received_ts = 312.642273
last_seq = 12713
current_ts = 312.659882
current_received_ts = 312.659332
current_seq = 12714
count = 41
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 312.642853/12713, current 312.659882/12714: elapsed=17022.0us expected=17063.9us +- 85.3us, error 0.2%
(kms_flip:3070) DEBUG: name = flip
last_ts = 312.659882
last_received_ts = 312.659332
last_seq = 12714
current_ts = 312.676910
current_received_ts = 312.676361
current_seq = 12715
count = 42
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 312.659882/12714, current 312.676910/12715: elapsed=17021.0us expected=17063.9us +- 85.3us, error 0.3%
(kms_flip:3070) DEBUG: name = flip
last_ts = 312.676910
last_received_ts = 312.676361
last_seq = 12715
current_ts = 312.693939
current_received_ts = 312.693359
current_seq = 12716
count = 43
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 312.676910/12715, current 312.693939/12716: elapsed=17022.0us expected=17063.9us +- 85.3us, error 0.2%
(kms_flip:3070) DEBUG: name = flip
last_ts = 312.693939
last_received_ts = 312.693359
last_seq = 12716
current_ts = 312.710938
current_received_ts = 312.710358
current_seq = 12717
count = 44
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 312.693939/12716, current 312.710938/12717: elapsed=17020.0us expected=17063.9us +- 85.3us, error 0.3%
(kms_flip:3070) DEBUG: name = flip
last_ts = 312.710938
last_received_ts = 312.710358
last_seq = 12717
current_ts = 312.727966
current_received_ts = 312.727417
current_seq = 12718
count = 45
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 312.710938/12717, current 312.727966/12718: elapsed=17022.0us expected=17063.9us +- 85.3us, error 0.2%
(kms_flip:3070) DEBUG: name = flip
last_ts = 312.727966
last_received_ts = 312.727417
last_seq = 12718
current_ts = 312.744995
current_received_ts = 312.744415
current_seq = 12719
count = 46
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 312.727966/12718, current 312.744995/12719: elapsed=17012.0us expected=17063.9us +- 85.3us, error 0.3%
(kms_flip:3070) DEBUG: name = flip
last_ts = 312.744995
last_received_ts = 312.744415
last_seq = 12719
current_ts = 312.762024
current_received_ts = 312.761444
current_seq = 12720
count = 47
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 312.744995/12719, current 312.762024/12720: elapsed=17031.0us expected=17063.9us +- 85.3us, error 0.2%
(kms_flip:3070) DEBUG: name = flip
last_ts = 312.762024
last_received_ts = 312.761444
last_seq = 12720
current_ts = 312.779053
current_received_ts = 312.778473
current_seq = 12721
count = 48
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 312.762024/12720, current 312.779053/12721: elapsed=17021.0us expected=17063.9us +- 85.3us, error 0.3%
(kms_flip:3070) DEBUG: name = flip
last_ts = 312.779053
last_received_ts = 312.778473
last_seq = 12721
current_ts = 312.796051
current_received_ts = 312.795502
current_seq = 12722
count = 49
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 312.779053/12721, current 312.796051/12722: elapsed=17022.0us expected=17063.9us +- 85.3us, error 0.2%
(kms_flip:3070) DEBUG: name = flip
last_ts = 312.796051
last_received_ts = 312.795502
last_seq = 12722
current_ts = 312.813080
current_received_ts = 312.812500
current_seq = 12723
count = 50
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 312.796051/12722, current 312.813080/12723: elapsed=17021.0us expected=17063.9us +- 85.3us, error 0.3%
(kms_flip:3070) DEBUG: name = flip
last_ts = 312.813080
last_received_ts = 312.812500
last_seq = 12723
current_ts = 312.830109
current_received_ts = 312.829559
current_seq = 12724
count = 51
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 312.813080/12723, current 312.830109/12724: elapsed=17018.0us expected=17063.9us +- 85.3us, error 0.3%
(kms_flip:3070) DEBUG: name = flip
last_ts = 312.830109
last_received_ts = 312.829559
last_seq = 12724
current_ts = 312.847137
current_received_ts = 312.846558
current_seq = 12725
count = 52
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 312.830109/12724, current 312.847137/12725: elapsed=17024.0us expected=17063.9us +- 85.3us, error 0.2%
(kms_flip:3070) DEBUG: name = flip
last_ts = 312.847137
last_received_ts = 312.846558
last_seq = 12725
current_ts = 312.864136
current_received_ts = 312.863586
current_seq = 12726
count = 53
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 312.847137/12725, current 312.864136/12726: elapsed=17022.0us expected=17063.9us +- 85.3us, error 0.2%
(kms_flip:3070) DEBUG: name = flip
last_ts = 312.864136
last_received_ts = 312.863586
last_seq = 12726
current_ts = 312.881165
current_received_ts = 312.880585
current_seq = 12727
count = 54
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 312.864136/12726, current 312.881165/12727: elapsed=17021.0us expected=17063.9us +- 85.3us, error 0.3%
(kms_flip:3070) DEBUG: name = flip
last_ts = 312.881165
last_received_ts = 312.880585
last_seq = 12727
current_ts = 312.898193
current_received_ts = 312.897644
current_seq = 12728
count = 55
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 312.881165/12727, current 312.898193/12728: elapsed=17022.0us expected=17063.9us +- 85.3us, error 0.2%
(kms_flip:3070) DEBUG: name = flip
last_ts = 312.898193
last_received_ts = 312.897644
last_seq = 12728
current_ts = 312.915222
current_received_ts = 312.914642
current_seq = 12729
count = 56
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 312.898193/12728, current 312.915222/12729: elapsed=17022.0us expected=17063.9us +- 85.3us, error 0.2%
(kms_flip:3070) DEBUG: name = flip
last_ts = 312.915222
last_received_ts = 312.914642
last_seq = 12729
current_ts = 312.932220
current_received_ts = 312.931671
current_seq = 12730
count = 57
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 312.915222/12729, current 312.932220/12730: elapsed=17021.0us expected=17063.9us +- 85.3us, error 0.3%
(kms_flip:3070) DEBUG: name = flip
last_ts = 312.932220
last_received_ts = 312.931671
last_seq = 12730
current_ts = 312.949249
current_received_ts = 312.948700
current_seq = 12731
count = 58
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 312.932220/12730, current 312.949249/12731: elapsed=17020.0us expected=17063.9us +- 85.3us, error 0.3%
(kms_flip:3070) DEBUG: name = flip
last_ts = 312.949249
last_received_ts = 312.948700
last_seq = 12731
current_ts = 312.966278
current_received_ts = 312.965698
current_seq = 12732
count = 59
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 312.949249/12731, current 312.966278/12732: elapsed=17023.0us expected=17063.9us +- 85.3us, error 0.2%
(kms_flip:3070) DEBUG: name = flip
last_ts = 312.966278
last_received_ts = 312.965698
last_seq = 12732
current_ts = 312.983307
current_received_ts = 312.982666
current_seq = 12733
count = 60
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 312.966278/12732, current 312.983307/12733: elapsed=17020.0us expected=17063.9us +- 85.3us, error 0.3%
(kms_flip:3070) DEBUG: name = flip
last_ts = 312.983307
last_received_ts = 312.982666
last_seq = 12733
current_ts = 313.000336
current_received_ts = 312.999664
current_seq = 12734
count = 61
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 312.983307/12733, current 313.000336/12734: elapsed=17028.0us expected=17063.9us +- 85.3us, error 0.2%
(kms_flip:3070) DEBUG: name = flip
last_ts = 313.000336
last_received_ts = 312.999664
last_seq = 12734
current_ts = 313.017334
current_received_ts = 313.016785
current_seq = 12735
count = 62
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 313.000336/12734, current 313.017334/12735: elapsed=17016.0us expected=17063.9us +- 85.3us, error 0.3%
(kms_flip:3070) DEBUG: name = flip
last_ts = 313.017334
last_received_ts = 313.016785
last_seq = 12735
current_ts = 313.034363
current_received_ts = 313.033813
current_seq = 12736
count = 63
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 313.017334/12735, current 313.034363/12736: elapsed=17024.0us expected=17063.9us +- 85.3us, error 0.2%
(kms_flip:3070) DEBUG: name = flip
last_ts = 313.034363
last_received_ts = 313.033813
last_seq = 12736
current_ts = 313.051392
current_received_ts = 313.050812
current_seq = 12737
count = 64
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 313.034363/12736, current 313.051392/12737: elapsed=17016.0us expected=17063.9us +- 85.3us, error 0.3%
(kms_flip:3070) DEBUG: name = flip
last_ts = 313.051392
last_received_ts = 313.050812
last_seq = 12737
current_ts = 313.068390
current_received_ts = 313.067871
current_seq = 12738
count = 65
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 313.051392/12737, current 313.068390/12738: elapsed=17024.0us expected=17063.9us +- 85.3us, error 0.2%
(kms_flip:3070) DEBUG: name = flip
last_ts = 313.068390
last_received_ts = 313.067871
last_seq = 12738
current_ts = 313.085419
current_received_ts = 313.084869
current_seq = 12739
count = 66
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 313.068390/12738, current 313.085419/12739: elapsed=17022.0us expected=17063.9us +- 85.3us, error 0.2%
(kms_flip:3070) DEBUG: name = flip
last_ts = 313.085419
last_received_ts = 313.084869
last_seq = 12739
current_ts = 313.102448
current_received_ts = 313.101898
current_seq = 12740
count = 67
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 313.085419/12739, current 313.102448/12740: elapsed=17021.0us expected=17063.9us +- 85.3us, error 0.3%
(kms_flip:3070) DEBUG: name = flip
last_ts = 313.102448
last_received_ts = 313.101898
last_seq = 12740
current_ts = 313.119476
current_received_ts = 313.118896
current_seq = 12741
count = 68
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 313.102448/12740, current 313.119476/12741: elapsed=17021.0us expected=17063.9us +- 85.3us, error 0.3%
(kms_flip:3070) DEBUG: name = flip
last_ts = 313.119476
last_received_ts = 313.118896
last_seq = 12741
current_ts = 313.136475
current_received_ts = 313.135925
current_seq = 12742
count = 69
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 313.119476/12741, current 313.136475/12742: elapsed=17022.0us expected=17063.9us +- 85.3us, error 0.2%
(kms_flip:3070) DEBUG: name = flip
last_ts = 313.136475
last_received_ts = 313.135925
last_seq = 12742
current_ts = 313.153503
current_received_ts = 313.152954
current_seq = 12743
count = 70
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 313.136475/12742, current 313.153503/12743: elapsed=17016.0us expected=17063.9us +- 85.3us, error 0.3%
(kms_flip:3070) DEBUG: name = flip
last_ts = 313.153503
last_received_ts = 313.152954
last_seq = 12743
current_ts = 313.170532
current_received_ts = 313.169983
current_seq = 12744
count = 71
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 313.153503/12743, current 313.170532/12744: elapsed=17024.0us expected=17063.9us +- 85.3us, error 0.2%
(kms_flip:3070) DEBUG: name = flip
last_ts = 313.170532
last_received_ts = 313.169983
last_seq = 12744
current_ts = 313.187561
current_received_ts = 313.186981
current_seq = 12745
count = 72
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 313.170532/12744, current 313.187561/12745: elapsed=17019.0us expected=17063.9us +- 85.3us, error 0.3%
(kms_flip:3070) DEBUG: name = flip
last_ts = 313.187561
last_received_ts = 313.186981
last_seq = 12745
current_ts = 313.204559
current_received_ts = 313.204010
current_seq = 12746
count = 73
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 313.187561/12745, current 313.204559/12746: elapsed=17027.0us expected=17063.9us +- 85.3us, error 0.2%
(kms_flip:3070) DEBUG: name = flip
last_ts = 313.204559
last_received_ts = 313.204010
last_seq = 12746
current_ts = 313.221588
current_received_ts = 313.221039
current_seq = 12747
count = 74
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 313.204559/12746, current 313.221588/12747: elapsed=17015.0us expected=17063.9us +- 85.3us, error 0.3%
(kms_flip:3070) DEBUG: name = flip
last_ts = 313.221588
last_received_ts = 313.221039
last_seq = 12747
current_ts = 313.238617
current_received_ts = 313.238037
current_seq = 12748
count = 75
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 313.221588/12747, current 313.238617/12748: elapsed=17020.0us expected=17063.9us +- 85.3us, error 0.3%
(kms_flip:3070) DEBUG: name = flip
last_ts = 313.238617
last_received_ts = 313.238037
last_seq = 12748
current_ts = 313.255646
current_received_ts = 313.255066
current_seq = 12749
count = 76
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 313.238617/12748, current 313.255646/12749: elapsed=17029.0us expected=17063.9us +- 85.3us, error 0.2%
(kms_flip:3070) DEBUG: name = flip
last_ts = 313.255646
last_received_ts = 313.255066
last_seq = 12749
current_ts = 313.272644
current_received_ts = 313.272125
current_seq = 12750
count = 77
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 313.255646/12749, current 313.272644/12750: elapsed=17021.0us expected=17063.9us +- 85.3us, error 0.3%
(kms_flip:3070) DEBUG: name = flip
last_ts = 313.272644
last_received_ts = 313.272125
last_seq = 12750
current_ts = 313.289673
current_received_ts = 313.289124
current_seq = 12751
count = 78
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 313.272644/12750, current 313.289673/12751: elapsed=17019.0us expected=17063.9us +- 85.3us, error 0.3%
(kms_flip:3070) DEBUG: name = flip
last_ts = 313.289673
last_received_ts = 313.289124
last_seq = 12751
current_ts = 313.306702
current_received_ts = 313.306152
current_seq = 12752
count = 79
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 313.289673/12751, current 313.306702/12752: elapsed=17024.0us expected=17063.9us +- 85.3us, error 0.2%
(kms_flip:3070) DEBUG: name = flip
last_ts = 313.306702
last_received_ts = 313.306152
last_seq = 12752
current_ts = 313.323730
current_received_ts = 313.323151
current_seq = 12753
count = 80
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 313.306702/12752, current 313.323730/12753: elapsed=17021.0us expected=17063.9us +- 85.3us, error 0.3%
(kms_flip:3070) DEBUG: name = flip
last_ts = 313.323730
last_received_ts = 313.323151
last_seq = 12753
current_ts = 313.340759
current_received_ts = 313.340179
current_seq = 12754
count = 81
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 313.323730/12753, current 313.340759/12754: elapsed=17022.0us expected=17063.9us +- 85.3us, error 0.2%
(kms_flip:3070) DEBUG: name = flip
last_ts = 313.340759
last_received_ts = 313.340179
last_seq = 12754
current_ts = 313.357758
current_received_ts = 313.357208
current_seq = 12755
count = 82
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 313.340759/12754, current 313.357758/12755: elapsed=17021.0us expected=17063.9us +- 85.3us, error 0.3%
(kms_flip:3070) DEBUG: name = flip
last_ts = 313.357758
last_received_ts = 313.357208
last_seq = 12755
current_ts = 313.374786
current_received_ts = 313.374237
current_seq = 12756
count = 83
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 313.357758/12755, current 313.374786/12756: elapsed=17021.0us expected=17063.9us +- 85.3us, error 0.3%
(kms_flip:3070) DEBUG: name = flip
last_ts = 313.374786
last_received_ts = 313.374237
last_seq = 12756
current_ts = 313.391815
current_received_ts = 313.391235
current_seq = 12757
count = 84
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 313.374786/12756, current 313.391815/12757: elapsed=17022.0us expected=17063.9us +- 85.3us, error 0.2%
(kms_flip:3070) DEBUG: name = flip
last_ts = 313.391815
last_received_ts = 313.391235
last_seq = 12757
current_ts = 313.408844
current_received_ts = 313.408264
current_seq = 12758
count = 85
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 313.391815/12757, current 313.408844/12758: elapsed=17029.0us expected=17063.9us +- 85.3us, error 0.2%
(kms_flip:3070) DEBUG: name = flip
last_ts = 313.408844
last_received_ts = 313.408264
last_seq = 12758
current_ts = 313.425842
current_received_ts = 313.425293
current_seq = 12759
count = 86
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 313.408844/12758, current 313.425842/12759: elapsed=17014.0us expected=17063.9us +- 85.3us, error 0.3%
(kms_flip:3070) DEBUG: name = flip
last_ts = 313.425842
last_received_ts = 313.425293
last_seq = 12759
current_ts = 313.442871
current_received_ts = 313.442322
current_seq = 12760
count = 87
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 313.425842/12759, current 313.442871/12760: elapsed=17021.0us expected=17063.9us +- 85.3us, error 0.3%
(kms_flip:3070) DEBUG: name = flip
last_ts = 313.442871
last_received_ts = 313.442322
last_seq = 12760
current_ts = 313.459900
current_received_ts = 313.459320
current_seq = 12761
count = 88
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 313.442871/12760, current 313.459900/12761: elapsed=17022.0us expected=17063.9us +- 85.3us, error 0.2%
(kms_flip:3070) DEBUG: name = flip
last_ts = 313.459900
last_received_ts = 313.459320
last_seq = 12761
current_ts = 313.476929
current_received_ts = 313.476379
current_seq = 12762
count = 89
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 313.459900/12761, current 313.476929/12762: elapsed=17021.0us expected=17063.9us +- 85.3us, error 0.3%
(kms_flip:3070) DEBUG: name = flip
last_ts = 313.476929
last_received_ts = 313.476379
last_seq = 12762
current_ts = 313.493958
current_received_ts = 313.493378
current_seq = 12763
count = 90
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 313.476929/12762, current 313.493958/12763: elapsed=17031.0us expected=17063.9us +- 85.3us, error 0.2%
(kms_flip:3070) DEBUG: name = flip
last_ts = 313.493958
last_received_ts = 313.493378
last_seq = 12763
current_ts = 313.510956
current_received_ts = 313.510406
current_seq = 12764
count = 91
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 313.493958/12763, current 313.510956/12764: elapsed=17012.0us expected=17063.9us +- 85.3us, error 0.3%
(kms_flip:3070) DEBUG: name = flip
last_ts = 313.510956
last_received_ts = 313.510406
last_seq = 12764
current_ts = 313.527985
current_received_ts = 313.527405
current_seq = 12765
count = 92
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 313.510956/12764, current 313.527985/12765: elapsed=17029.0us expected=17063.9us +- 85.3us, error 0.2%
(kms_flip:3070) DEBUG: name = flip
last_ts = 313.527985
last_received_ts = 313.527405
last_seq = 12765
current_ts = 313.545013
current_received_ts = 313.544464
current_seq = 12766
count = 93
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 313.527985/12765, current 313.545013/12766: elapsed=17014.0us expected=17063.9us +- 85.3us, error 0.3%
(kms_flip:3070) DEBUG: name = flip
last_ts = 313.545013
last_received_ts = 313.544464
last_seq = 12766
current_ts = 313.562012
current_received_ts = 313.561493
current_seq = 12767
count = 94
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 313.545013/12766, current 313.562012/12767: elapsed=17021.0us expected=17063.9us +- 85.3us, error 0.3%
(kms_flip:3070) DEBUG: name = flip
last_ts = 313.562012
last_received_ts = 313.561493
last_seq = 12767
current_ts = 313.579041
current_received_ts = 313.578491
current_seq = 12768
count = 95
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 313.562012/12767, current 313.579041/12768: elapsed=17021.0us expected=17063.9us +- 85.3us, error 0.3%
(kms_flip:3070) DEBUG: name = flip
last_ts = 313.579041
last_received_ts = 313.578491
last_seq = 12768
current_ts = 313.596069
current_received_ts = 313.595490
current_seq = 12769
count = 96
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 313.579041/12768, current 313.596069/12769: elapsed=17022.0us expected=17063.9us +- 85.3us, error 0.2%
(kms_flip:3070) DEBUG: name = flip
last_ts = 313.596069
last_received_ts = 313.595490
last_seq = 12769
current_ts = 313.613098
current_received_ts = 313.612549
current_seq = 12770
count = 97
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 313.596069/12769, current 313.613098/12770: elapsed=17022.0us expected=17063.9us +- 85.3us, error 0.2%
(kms_flip:3070) DEBUG: name = flip
last_ts = 313.613098
last_received_ts = 313.612549
last_seq = 12770
current_ts = 313.630096
current_received_ts = 313.629547
current_seq = 12771
count = 98
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 313.613098/12770, current 313.630096/12771: elapsed=17021.0us expected=17063.9us +- 85.3us, error 0.3%
(kms_flip:3070) DEBUG: name = flip
last_ts = 313.630096
last_received_ts = 313.629547
last_seq = 12771
current_ts = 313.647125
current_received_ts = 313.646515
current_seq = 12772
count = 99
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 313.630096/12771, current 313.647125/12772: elapsed=17013.0us expected=17063.9us +- 85.3us, error 0.3%
(kms_flip:3070) DEBUG: name = flip
last_ts = 313.647125
last_received_ts = 313.646515
last_seq = 12772
current_ts = 313.664124
current_received_ts = 313.663574
current_seq = 12773
count = 100
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 313.647125/12772, current 313.664124/12773: elapsed=16992.0us expected=17063.9us +- 85.3us, error 0.4%
(kms_flip:3070) DEBUG: name = flip
last_ts = 313.664124
last_received_ts = 313.663574
last_seq = 12773
current_ts = 313.681091
current_received_ts = 313.680542
current_seq = 12774
count = 101
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 313.664124/12773, current 313.681091/12774: elapsed=16985.0us expected=17063.9us +- 85.3us, error 0.5%
(kms_flip:3070) DEBUG: name = flip
last_ts = 313.681091
last_received_ts = 313.680542
last_seq = 12774
current_ts = 313.698090
current_received_ts = 313.697540
current_seq = 12775
count = 102
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 313.681091/12774, current 313.698090/12775: elapsed=16984.0us expected=17063.9us +- 85.3us, error 0.5%
(kms_flip:3070) DEBUG: name = flip
last_ts = 313.698090
last_received_ts = 313.697540
last_seq = 12775
current_ts = 313.715057
current_received_ts = 313.714508
current_seq = 12776
count = 103
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 313.698090/12775, current 313.715057/12776: elapsed=16986.0us expected=17063.9us +- 85.3us, error 0.5%
(kms_flip:3070) DEBUG: name = flip
last_ts = 313.715057
last_received_ts = 313.714508
last_seq = 12776
current_ts = 313.732056
current_received_ts = 313.731506
current_seq = 12777
count = 104
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 313.715057/12776, current 313.732056/12777: elapsed=16983.0us expected=17063.9us +- 85.3us, error 0.5%
(kms_flip:3070) DEBUG: name = flip
last_ts = 313.732056
last_received_ts = 313.731506
last_seq = 12777
current_ts = 313.749023
current_received_ts = 313.748474
current_seq = 12778
count = 105
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 313.732056/12777, current 313.749023/12778: elapsed=16985.0us expected=17063.9us +- 85.3us, error 0.5%
(kms_flip:3070) DEBUG: name = flip
last_ts = 313.749023
last_received_ts = 313.748474
last_seq = 12778
current_ts = 313.766022
current_received_ts = 313.765442
current_seq = 12779
count = 106
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 313.749023/12778, current 313.766022/12779: elapsed=16984.0us expected=17063.9us +- 85.3us, error 0.5%
(kms_flip:3070) DEBUG: name = flip
last_ts = 313.766022
last_received_ts = 313.765442
last_seq = 12779
current_ts = 313.783020
current_received_ts = 313.782440
current_seq = 12780
count = 107
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 313.766022/12779, current 313.783020/12780: elapsed=16985.0us expected=17063.9us +- 85.3us, error 0.5%
(kms_flip:3070) DEBUG: name = flip
last_ts = 313.783020
last_received_ts = 313.782440
last_seq = 12780
current_ts = 313.799988
current_received_ts = 313.799438
current_seq = 12781
count = 108
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 313.783020/12780, current 313.799988/12781: elapsed=16984.0us expected=17063.9us +- 85.3us, error 0.5%
(kms_flip:3070) DEBUG: name = flip
last_ts = 313.799988
last_received_ts = 313.799438
last_seq = 12781
current_ts = 313.816986
current_received_ts = 313.816406
current_seq = 12782
count = 109
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 313.799988/12781, current 313.816986/12782: elapsed=16984.0us expected=17063.9us +- 85.3us, error 0.5%
(kms_flip:3070) DEBUG: name = flip
last_ts = 313.816986
last_received_ts = 313.816406
last_seq = 12782
current_ts = 313.833954
current_received_ts = 313.833405
current_seq = 12783
count = 110
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 313.816986/12782, current 313.833954/12783: elapsed=16984.0us expected=17063.9us +- 85.3us, error 0.5%
(kms_flip:3070) DEBUG: name = flip
last_ts = 313.833954
last_received_ts = 313.833405
last_seq = 12783
current_ts = 313.850952
current_received_ts = 313.850342
current_seq = 12784
count = 111
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 313.833954/12783, current 313.850952/12784: elapsed=16993.0us expected=17063.9us +- 85.3us, error 0.4%
(kms_flip:3070) DEBUG: name = flip
last_ts = 313.850952
last_received_ts = 313.850342
last_seq = 12784
current_ts = 313.867920
current_received_ts = 313.867371
current_seq = 12785
count = 112
seq_step = 1
(kms_flip:3070) DEBUG: flip ts/seq: last 313.850952/12784, current 313.867920/12785: elapsed=16976.0us expected=17063.9us +- 85.3us, error 0.5%
(kms_flip:3070) DEBUG: inconsistent flip ts/seq: last 313.850952/12784, current 313.867920/12785: elapsed=16976.0us expected=17063.9us
(kms_flip:3070) DEBUG: expected 167152, counted 112, encoder type 1
(kms_flip:3070) DEBUG: dropped frames, expected 167152, counted 112, encoder type 1
(kms_flip:3070) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1674:
(kms_flip:3070) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon)
(kms_flip:3070) igt_core-INFO: Stack trace:
(kms_flip:3070) igt_core-INFO:   #0 ../lib/igt_core.c:2055 __igt_fail_assert()
(kms_flip:3070) igt_core-INFO:   #1 ../tests/kms_flip.c:791 run_test_on_crtc_set.constprop.0()
(kms_flip:3070) igt_core-INFO:   #2 ../tests/kms_flip.c:1845 run_test()
(kms_flip:3070) igt_core-INFO:   #3 ../tests/kms_flip.c:2078 __igt_unique____real_main2001()
(kms_flip:3070) igt_core-INFO:   #4 ../tests/kms_flip.c:2001 main()
(kms_flip:3070) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:3070) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:3070) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest A-VGA1: FAIL (5.596s)
Dmesg
<6> [326.021506] Console: switching to colour dummy device 80x25
<6> [326.022208] [IGT] kms_flip: executing
<7> [326.025960] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [326.026736] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [326.027159] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [326.030402] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:64:VGA-1]
<7> [326.030428] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] [CONNECTOR:64:VGA-1] force=1
<7> [326.030660] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] ironlake hotplug adpa=0xf40000, result 0
<7> [326.030895] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] CRT not detected via hotplug
<7> [326.031316] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [326.031515] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK on first message, retry
<7> [326.031970] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [326.032171] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [326.032179] i915 0000:00:02.0: [drm:intel_crt_get_edid [i915]] CRT GMBUS EDID read failed, retry using GPIO bit-banging
<7> [326.032385] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] enabling bit-banging on i915 gmbus vga. force bit now 1
<7> [326.033636] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [326.033643] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] disabling bit-banging on i915 gmbus vga. force bit now 0
<7> [326.033852] i915 0000:00:02.0: [drm:intel_crt_detect_ddc [i915]] CRT not detected via DDC:0x50 [no valid EDID found]
<7> [326.034059] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:64:VGA-1] disconnected
<7> [326.034561] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:75:DP-1]
<7> [326.034579] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:75:DP-1]
<7> [326.038464] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.042353] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.046276] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.050172] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.054048] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.057970] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.061884] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.065760] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.069524] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.073428] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.077309] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.081251] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.085176] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.089083] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.092974] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.096899] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.100806] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.104713] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.108587] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.112767] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.116676] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.120854] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.124788] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.128875] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.132816] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.136945] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.141115] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.145008] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.149191] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.153055] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.156992] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.160964] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.161315] 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> [326.161360] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:75:DP-1] disconnected
<7> [326.161756] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:64:VGA-1]
<7> [326.161783] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] [CONNECTOR:64:VGA-1] force=1
<7> [326.162098] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] ironlake hotplug adpa=0xf40000, result 0
<7> [326.162400] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] CRT not detected via hotplug
<7> [326.162886] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [326.163297] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK on first message, retry
<7> [326.163963] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [326.164323] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [326.164335] i915 0000:00:02.0: [drm:intel_crt_get_edid [i915]] CRT GMBUS EDID read failed, retry using GPIO bit-banging
<7> [326.164699] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] enabling bit-banging on i915 gmbus vga. force bit now 1
<7> [326.165947] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [326.165954] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] disabling bit-banging on i915 gmbus vga. force bit now 0
<7> [326.166162] i915 0000:00:02.0: [drm:intel_crt_detect_ddc [i915]] CRT not detected via DDC:0x50 [no valid EDID found]
<7> [326.166369] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:64:VGA-1] disconnected
<7> [326.166686] i915 0000:00:02.0: [drm:status_store] [CONNECTOR:64:VGA-1] force updated from 0 to 2 or reprobing
<7> [326.166694] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:64:VGA-1]
<7> [326.166711] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:64:VGA-1] status updated from disconnected to connected
<7> [326.166766] i915 0000:00:02.0: [drm:drm_sysfs_hotplug_event] generating hotplug event
<7> [326.166853] i915 0000:00:02.0: [drm:drm_client_dev_hotplug] intel-fbdev: ret=0
<7> [326.166901] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [326.167208] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK on first message, retry
<7> [326.167687] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [326.167915] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [326.167930] i915 0000:00:02.0: [drm:intel_crt_get_edid [i915]] CRT GMBUS EDID read failed, retry using GPIO bit-banging
<7> [326.168151] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] enabling bit-banging on i915 gmbus vga. force bit now 1
<7> [326.169460] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [326.169481] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] disabling bit-banging on i915 gmbus vga. force bit now 0
<7> [326.169777] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:64:VGA-1] probed modes:
<7> [326.169797] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [326.169813] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "800x600": 60 40000 800 840 968 1056 600 601 605 628 0x40 0x5
<7> [326.169828] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "800x600": 56 36000 800 824 896 1024 600 601 603 625 0x40 0x5
<7> [326.169843] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "848x480": 60 33750 848 864 976 1088 480 486 494 517 0x40 0x5
<7> [326.169857] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "640x480": 60 25175 640 656 752 800 480 490 492 525 0x40 0xa
<7> [326.169918] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:64:VGA-1]
<7> [326.170129] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [326.170337] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK on first message, retry
<7> [326.170753] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [326.171013] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [326.171030] i915 0000:00:02.0: [drm:intel_crt_get_edid [i915]] CRT GMBUS EDID read failed, retry using GPIO bit-banging
<7> [326.171261] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] enabling bit-banging on i915 gmbus vga. force bit now 1
<7> [326.172580] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [326.172600] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] disabling bit-banging on i915 gmbus vga. force bit now 0
<7> [326.172890] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:64:VGA-1] probed modes:
<7> [326.172910] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [326.172924] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "800x600": 60 40000 800 840 968 1056 600 601 605 628 0x40 0x5
<7> [326.172938] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "800x600": 56 36000 800 824 896 1024 600 601 603 625 0x40 0x5
<7> [326.172952] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "848x480": 60 33750 848 864 976 1088 480 486 494 517 0x40 0x5
<7> [326.172966] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "640x480": 60 25175 640 656 752 800 480 490 492 525 0x40 0xa
<7> [326.173001] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:67:HDMI-A-1]
<7> [326.173019] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:67:HDMI-A-1]
<7> [326.198799] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:67:HDMI-A-1] HDMI: DVI dual 0, max TMDS clock 590000 kHz
<7> [326.198812] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:67:HDMI-A-1] HDMI sink does deep color 30.
<7> [326.198818] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:67:HDMI-A-1] HDMI sink does deep color 36.
<7> [326.198824] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:67:HDMI-A-1] HDMI sink does deep color 48.
<7> [326.198829] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:67:HDMI-A-1] Assigning HDMI sink color depth as 16 bpc.
<7> [326.198834] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:67:HDMI-A-1] HDMI sink does YCRCB444 in deep color.
<7> [326.198842] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:67:HDMI-A-1] ELD monitor Mi TV
<7> [326.198849] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:67:HDMI-A-1] HDMI: latency present 0 0, video latency 0 0, audio latency 0 0
<7> [326.198856] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:67:HDMI-A-1] ELD size 36, SAD count 3
<7> [326.199080] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpd] NAK for addr: 0040 w(1)
<7> [326.199404] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpd] NAK on first message, retry
<7> [326.199957] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpd] NAK for addr: 0040 w(1)
<7> [326.200273] i915 0000:00:02.0: [drm:drm_dp_dual_mode_detect [drm_display_helper]] DP dual mode HDMI ID: (err -6)
<7> [326.201796] 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> [326.201814] 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> [326.201826] 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> [326.201838] 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> [326.201849] 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> [326.201861] 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> [326.201878] 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> [326.201885] 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> [326.201893] 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> [326.201901] 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> [326.201908] 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> [326.201916] 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> [326.201924] 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> [326.201932] 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> [326.201939] 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> [326.201947] 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> [326.201955] 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> [326.201962] 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> [326.201970] 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> [326.201983] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:67:HDMI-A-1] probed modes:
<7> [326.202003] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
<7> [326.202017] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "2880x1800": 30 167000 2880 2910 2920 3030 1800 1820 1828 1836 0x40 0x5
<7> [326.202031] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1920x1200": 60 154000 1920 1968 2000 2080 1200 1203 1209 1235 0x40 0x9
<7> [326.202045] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [326.202059] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1920x1080": 60 148352 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [326.202073] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1920x1080i": 60 74250 1920 2008 2052 2200 1080 1084 1094 1125 0x40 0x15
<7> [326.202087] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1920x1080i": 60 74250 1920 2008 2052 2200 1080 1084 1094 1125 0x40 0x20015
<7> [326.202101] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1920x1080i": 60 74176 1920 2008 2052 2200 1080 1084 1094 1125 0x40 0x15
<7> [326.202115] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1920x1080i": 60 74176 1920 2008 2052 2200 1080 1084 1094 1125 0x40 0x20015
<7> [326.202129] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1920x1080": 50 148500 1920 2448 2492 2640 1080 1084 1089 1125 0x40 0x5
<7> [326.202143] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1920x1080i": 50 74250 1920 2448 2492 2640 1080 1084 1094 1125 0x40 0x15
<7> [326.202157] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1920x1080i": 50 74250 1920 2448 2492 2640 1080 1084 1094 1125 0x40 0x20015
<7> [326.202171] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1920x1080": 30 74250 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [326.202184] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1920x1080": 30 74176 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [326.202198] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1920x1080": 25 74250 1920 2448 2492 2640 1080 1084 1089 1125 0x40 0x5
<7> [326.202212] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1920x1080": 24 74250 1920 2558 2602 2750 1080 1084 1089 1125 0x40 0x5
<7> [326.202226] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1920x1080": 24 74250 1920 2558 2602 2750 1080 1084 1089 1125 0x40 0x1c005
<7> [326.202240] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1920x1080": 24 74250 1920 2558 2602 2750 1080 1084 1089 1125 0x40 0x4005
<7> [326.202253] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1920x1080": 24 74176 1920 2558 2602 2750 1080 1084 1089 1125 0x40 0x5
<7> [326.202267] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1920x1080": 24 74176 1920 2558 2602 2750 1080 1084 1089 1125 0x40 0x1c005
<7> [326.202281] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1920x1080": 24 74176 1920 2558 2602 2750 1080 1084 1089 1125 0x40 0x4005
<7> [326.202295] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1600x1200": 60 162000 1600 1664 1856 2160 1200 1201 1204 1250 0x40 0x5
<7> [326.202308] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1680x1050": 60 119000 1680 1728 1760 1840 1050 1053 1059 1080 0x40 0x9
<7> [326.202322] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1600x900": 60 108000 1600 1624 1704 1800 900 901 904 1000 0x40 0x5
<7> [326.202336] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1280x1024": 60 108000 1280 1328 1440 1688 1024 1025 1028 1066 0x40 0x5
<7> [326.202350] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1440x900": 60 88750 1440 1488 1520 1600 900 903 909 926 0x40 0x9
<7> [326.202364] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1366x768": 60 85500 1366 1436 1579 1792 768 771 774 798 0x40 0x5
<7> [326.202378] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1280x800": 60 71000 1280 1328 1360 1440 800 803 809 823 0x40 0x9
<7> [326.202391] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1280x720": 60 74250 1280 1390 1430 1650 720 725 730 750 0x40 0x5
<7> [326.202405] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1280x720": 60 74250 1280 1390 1430 1650 720 725 730 750 0x40 0x1c005
<7> [326.202419] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1280x720": 60 74250 1280 1390 1430 1650 720 725 730 750 0x40 0x4005
<7> [326.202433] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1280x720": 60 74176 1280 1390 1430 1650 720 725 730 750 0x40 0x5
<7> [326.202447] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1280x720": 60 74176 1280 1390 1430 1650 720 725 730 750 0x40 0x1c005
<7> [326.202461] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1280x720": 60 74176 1280 1390 1430 1650 720 725 730 750 0x40 0x4005
<7> [326.202475] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1280x720": 50 74250 1280 1720 1760 1980 720 725 730 750 0x40 0x5
<7> [326.202489] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1280x720": 50 74250 1280 1720 1760 1980 720 725 730 750 0x40 0x1c005
<7> [326.202503] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1280x720": 50 74250 1280 1720 1760 1980 720 725 730 750 0x40 0x4005
<7> [326.202517] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [326.202531] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "800x600": 60 40000 800 840 968 1056 600 601 605 628 0x40 0x5
<7> [326.202545] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "720x576": 50 27000 720 732 796 864 576 581 586 625 0x40 0xa
<7> [326.202558] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "720x576i": 50 13500 720 732 795 864 576 580 586 625 0x40 0x101a
<7> [326.202572] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "720x480": 60 27027 720 736 798 858 480 489 495 525 0x40 0xa
<7> [326.202586] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "720x480": 60 27000 720 736 798 858 480 489 495 525 0x40 0xa
<7> [326.202600] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "640x480": 60 25200 640 656 752 800 480 490 492 525 0x40 0xa
<7> [326.202614] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "640x480": 60 25175 640 656 752 800 480 490 492 525 0x40 0xa
<7> [326.202696] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:75:DP-1]
<7> [326.202713] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:75:DP-1]
<7> [326.206445] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.210387] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.214287] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.218166] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.222079] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.225806] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.229729] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.233627] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.237511] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.241430] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.245309] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.249212] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.253077] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.256817] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.260715] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.264517] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.268264] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.271998] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.275916] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.280041] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.283987] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.288101] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.292286] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.296185] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.300327] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.304453] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.308388] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.312560] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.316691] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.320652] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.324748] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.328628] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [326.329059] 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> [326.329103] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:75:DP-1] disconnected
<6> [326.330865] [IGT] kms_flip: starting subtest plain-flip-ts-check
<6> [326.333021] [IGT] kms_flip: starting dynamic subtest A-VGA1
<7> [326.333560] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:81]
<7> [326.333673] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [326.435481] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:47:pipe A]
<7> [326.435687] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [326.436373] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [326.437060] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [326.437788] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in has_pch_encoder (expected yes, found no)
<7> [326.438429] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in fdi_lanes (expected 3, found 0)
<7> [326.438860] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] 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> [326.439306] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [326.439735] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [326.440183] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [326.440612] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [326.441051] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [326.441397] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [326.441717] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [326.442069] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [326.442405] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [326.442724] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [326.443096] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [326.443414] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [326.443735] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [326.444100] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [326.444435] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [326.444752] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [326.445109] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [326.445445] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [326.445763] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [326.446112] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [326.446449] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [326.446768] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [326.447136] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [326.447486] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [326.447804] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [326.448166] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [326.448503] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [326.448846] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [326.449168] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [326.449489] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [326.449835] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [326.450173] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [326.450493] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in pipe_bpp (expected 36, found 0)
<7> [326.450844] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148800, found 0)
<7> [326.451164] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148800, found 0)
<7> [326.451487] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in port_clock (expected 223200, found 0)
<7> [326.451854] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [326.452193] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in infoframes.gcp (expected 0x00000006, found 0x00000000)
<7> [326.452509] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in avi infoframe
<7> [326.452859] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [326.453196] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [326.453202] i915 0000:00:02.0: colorspace: RGB
<7> [326.453206] i915 0000:00:02.0: scan mode: Underscan
<7> [326.453210] i915 0000:00:02.0: colorimetry: No Data
<7> [326.453215] i915 0000:00:02.0: picture aspect: 16:9
<7> [326.453219] i915 0000:00:02.0: active aspect: Same as Picture
<7> [326.453223] i915 0000:00:02.0: itc: No Data
<7> [326.453227] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [326.453231] i915 0000:00:02.0: quantization range: Default
<7> [326.453236] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [326.453240] i915 0000:00:02.0: video code: 16
<7> [326.453244] i915 0000:00:02.0: ycc quantization range: Full
<7> [326.453248] i915 0000:00:02.0: hdmi content type: Graphics
<7> [326.453252] i915 0000:00:02.0: pixel repeat: 0
<7> [326.453256] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [326.453261] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [326.453579] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in spd infoframe
<7> [326.453924] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [326.454260] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [326.454265] i915 0000:00:02.0: vendor: Intel
<7> [326.454270] i915 0000:00:02.0: product: Integrated gfx
<7> [326.454274] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [326.454279] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [326.454597] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hdmi infoframe
<7> [326.454948] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [326.455283] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [326.455288] i915 0000:00:02.0: empty frame
<7> [326.455292] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [326.455611] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:47:pipe A] fastset requirement not met, forcing full modeset
<7> [326.455959] i915 0000:00:02.0: [drm:intel_unreference_shared_dpll_crtc [i915]] [CRTC:47:pipe A] releasing PCH DPLL A
<7> [326.456375] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 400000 kHz, actual 400000 kHz
<7> [326.456729] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7> [326.457056] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:47:pipe A] enable: no [modeset]
<7> [326.457375] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:32:primary A] fb: [NOFB], visible: no
<7> [326.457781] i915 0000:00:02.0: [drm:intel_audio_codec_disable [i915]] [CONNECTOR:67:HDMI-A-1][ENCODER:66:HDMI D] Disable audio codec on [CRTC:47:pipe A]
<7> [326.478455] i915 0000:00:02.0: [drm:intel_audio_component_get_eld [i915]] Not valid for port D
<7> [326.478919] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [326.496021] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port B found
<7> [326.496446] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port C found
<7> [326.496900] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port D found
<7> [326.508714] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disable PCH DPLL A (active 0x1, on? 1) for [CRTC:47:pipe A]
<7> [326.509177] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disabling PCH DPLL A
<7> [326.509885] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:65:CRT]
<7> [326.510390] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:66:HDMI D]
<7> [326.510932] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:DP D]
<7> [326.511182] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:67:HDMI-A-1]
<7> [326.511461] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:47:pipe A]
<7> [326.511818] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:63:pipe B]
<7> [326.511974] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:47:pipe A]
<7> [326.512002] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:64:VGA-1]
<7> [326.512090] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:64:VGA-1] Limiting display bpp to 24 (EDID bpp 0, max requested bpp 0, max platform bpp 36)
<7> [326.512336] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:47:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [326.512597] i915 0000:00:02.0: [drm:intel_fdi_atomic_check_link [i915]] checking fdi config on pipe A, lanes 1
<7> [326.512799] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [326.513021] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [326.513226] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [326.513486] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in has_pch_encoder (expected no, found yes)
<7> [326.513735] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in fdi_lanes (expected 0, found 1)
<7> [326.513940] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47: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> [326.514147] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000002)
<7> [326.514384] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [326.514635] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1024)
<7> [326.514841] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 1344)
<7> [326.515045] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1024)
<7> [326.515289] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 1344)
<7> [326.515535] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 1048)
<7> [326.515740] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 1184)
<7> [326.516000] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 768)
<7> [326.516211] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 768)
<7> [326.516446] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 771)
<7> [326.516678] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 777)
<7> [326.516882] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 806)
<7> [326.517087] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 806)
<7> [326.517331] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1024)
<7> [326.517605] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 1344)
<7> [326.517831] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1024)
<7> [326.518042] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 1344)
<7> [326.518279] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 1048)
<7> [326.518505] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 1184)
<7> [326.518709] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 768)
<7> [326.518975] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 768)
<7> [326.519241] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 771)
<7> [326.519486] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 777)
<7> [326.519691] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 806)
<7> [326.519895] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 806)
<7> [326.520100] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [326.520379] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [326.520621] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [326.520827] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in dpll_hw_state
<7> [326.521040] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [326.521309] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: dpll: 0xc4010001, dpll_md: 0x0, fp0: 0x30f06, fp1: 0x30f06
<7> [326.521532] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [326.521737] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: dpll: 0x84100010, dpll_md: 0x0, fp0: 0x10c09, fp1: 0x10c09
<7> [326.521951] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [326.522187] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 64800)
<7> [326.522414] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 64800)
<7> [326.522631] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in port_clock (expected 0, found 64800)
<7> [326.522842] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:47:pipe A] fastset requirement not met, forcing full modeset
<7> [326.523172] i915 0000:00:02.0: [drm:intel_plane_calc_min_cdclk [i915]] [PLANE:32:primary A] min cdclk (64800 kHz) > [CRTC:47:pipe A] min cdclk (0 kHz)
<7> [326.523380] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 400000 kHz, actual 400000 kHz
<7> [326.523602] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7> [326.523807] i915 0000:00:02.0: [drm:intel_find_shared_dpll [i915]] [CRTC:47:pipe A] allocated PCH DPLL A
<7> [326.524019] i915 0000:00:02.0: [drm:intel_reference_shared_dpll_crtc [i915]] [CRTC:47:pipe A] reserving PCH DPLL A
<7> [326.524270] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:47:pipe A] enable: yes [modeset]
<7> [326.524495] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: ANALOG (0x2), output format: RGB, sink format: RGB
<7> [326.524700] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [326.524915] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [326.525154] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [326.525380] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [326.525594] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [326.525804] 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> [326.526045] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [326.526273] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 1
<7> [326.526478] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vblank delay: 0, framestart delay: 1, MSA timing delay: 0
<7> [326.526691] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, vmin: 0, vmax: 0, flipline: 0, pipeline full: 0, guardband: 0 vsync start: 0, vsync end: 0
<7> [326.526901] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: -1, vmax vblank: -2, vmin vtotal: 1, vmax vtotal: 0
<7> [326.527148] 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> [326.527389] 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> [326.527600] 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> [326.527812] 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> [326.528071] 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> [326.528313] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 64800, pipe src: 1024x768+0+0, pixel rate 64800
<7> [326.528526] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 0, ips linetime: 0
<7> [326.528736] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [326.528968] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [326.528979] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling always-on
<7> [326.529201] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: dpll: 0x84100010, dpll_md: 0x0, fp0: 0x10c09, fp1: 0x10c09
<7> [326.529434] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x3 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [326.529644] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [326.529854] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [326.530088] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [326.530333] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [326.530544] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [326.530754] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:32:primary A] fb: [FB:81] 1024x768 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [326.530986] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [326.531282] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1024.000000x768.000000+0.000000+0.000000 dst: 1024x768+0+0
<7> [326.532406] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling always-on
<7> [326.532663] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:65:CRT]
<7> [326.532908] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:66:HDMI D]
<7> [326.533109] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:DP D]
<7> [326.533919] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [326.534976] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x100
<7> [326.535203] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 1 done.
<7> [326.536107] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x200
<7> [326.536310] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 2 done.
<7> [326.536510] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train done.
<7> [326.536768] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enable PCH DPLL A (active 0x1, on? 0) for [CRTC:47:pipe A]
<7> [326.537001] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enabling PCH DPLL A
<7> [326.588979] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:64:VGA-1]
<7> [326.589535] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:47:pipe A]
<6> [331.930632] [IGT] kms_flip: finished subtest A-VGA1, FAIL
Created at 2025-03-05 09:47:26