Results for igt@kms_flip@plain-flip-fb-recreate@a-vga1

Machine description: shard-snb7

Result: Fail

integration-manifest git-log-oneline i915_display_info9 igt_runner9 run9 runtimes9 results9.json i915_display_info_post_exec9 boot9 dmesg9

DetailValue
Duration 5.99 seconds
Hostname 
shard-snb7
Igt-Version 
IGT-Version: 1.29-NO-GIT (x86_64) (Linux: 6.12.0-rc7-CI_DRM_15700-g4018ba30ba2c+ x86_64)
Out 
Using IGT_SRANDOM=1731625498 for randomisation
Opened device: /dev/dri/card0
Using monotonic timestamps
Starting subtest: plain-flip-fb-recreate
Starting dynamic subtest: A-VGA1
  1024x768: 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa 
Expected frametime: 16666us; measured 16953.4us +- 8.808us accuracy 0.16%
vblank interval differs from modeline! expected 16665.6us, measured 16953us +- 8.808us, difference 287.8us (32.7 sigma)
Stack trace:
  #0 ../lib/igt_core.c:2051 __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.992s)
Err 
Starting subtest: plain-flip-fb-recreate
Starting dynamic subtest: A-VGA1
(kms_flip:1744) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1674:
(kms_flip:1744) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon)
Dynamic subtest A-VGA1 failed.
**** DEBUG ****
(kms_flip:1744) DEBUG: flip ts/seq: last 181.988022/4879, current 182.004944/4880: elapsed=16927.0us expected=16953.4us +- 84.8us, error 0.2%
(kms_flip:1744) DEBUG: name = flip
last_ts = 182.004944
last_received_ts = 182.004333
last_seq = 4880
current_ts = 182.021866
current_received_ts = 182.021255
current_seq = 4881
count = 29
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 182.004944/4880, current 182.021866/4881: elapsed=16927.0us expected=16953.4us +- 84.8us, error 0.2%
(kms_flip:1744) DEBUG: name = flip
last_ts = 182.021866
last_received_ts = 182.021255
last_seq = 4881
current_ts = 182.038788
current_received_ts = 182.038162
current_seq = 4882
count = 30
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 182.021866/4881, current 182.038788/4882: elapsed=16925.0us expected=16953.4us +- 84.8us, error 0.2%
(kms_flip:1744) DEBUG: name = flip
last_ts = 182.038788
last_received_ts = 182.038162
last_seq = 4882
current_ts = 182.055725
current_received_ts = 182.055099
current_seq = 4883
count = 31
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 182.038788/4882, current 182.055725/4883: elapsed=16929.0us expected=16953.4us +- 84.8us, error 0.1%
(kms_flip:1744) DEBUG: name = flip
last_ts = 182.055725
last_received_ts = 182.055099
last_seq = 4883
current_ts = 182.072647
current_received_ts = 182.072098
current_seq = 4884
count = 32
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 182.055725/4883, current 182.072647/4884: elapsed=16924.0us expected=16953.4us +- 84.8us, error 0.2%
(kms_flip:1744) DEBUG: name = flip
last_ts = 182.072647
last_received_ts = 182.072098
last_seq = 4884
current_ts = 182.089569
current_received_ts = 182.088943
current_seq = 4885
count = 33
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 182.072647/4884, current 182.089569/4885: elapsed=16926.0us expected=16953.4us +- 84.8us, error 0.2%
(kms_flip:1744) DEBUG: name = flip
last_ts = 182.089569
last_received_ts = 182.088943
last_seq = 4885
current_ts = 182.106506
current_received_ts = 182.105881
current_seq = 4886
count = 34
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 182.089569/4885, current 182.106506/4886: elapsed=16927.0us expected=16953.4us +- 84.8us, error 0.2%
(kms_flip:1744) DEBUG: name = flip
last_ts = 182.106506
last_received_ts = 182.105881
last_seq = 4886
current_ts = 182.123428
current_received_ts = 182.122818
current_seq = 4887
count = 35
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 182.106506/4886, current 182.123428/4887: elapsed=16926.0us expected=16953.4us +- 84.8us, error 0.2%
(kms_flip:1744) DEBUG: name = flip
last_ts = 182.123428
last_received_ts = 182.122818
last_seq = 4887
current_ts = 182.140350
current_received_ts = 182.139755
current_seq = 4888
count = 36
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 182.123428/4887, current 182.140350/4888: elapsed=16927.0us expected=16953.4us +- 84.8us, error 0.2%
(kms_flip:1744) DEBUG: name = flip
last_ts = 182.140350
last_received_ts = 182.139755
last_seq = 4888
current_ts = 182.157272
current_received_ts = 182.156677
current_seq = 4889
count = 37
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 182.140350/4888, current 182.157272/4889: elapsed=16925.0us expected=16953.4us +- 84.8us, error 0.2%
(kms_flip:1744) DEBUG: name = flip
last_ts = 182.157272
last_received_ts = 182.156677
last_seq = 4889
current_ts = 182.174210
current_received_ts = 182.173584
current_seq = 4890
count = 38
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 182.157272/4889, current 182.174210/4890: elapsed=16928.0us expected=16953.4us +- 84.8us, error 0.1%
(kms_flip:1744) DEBUG: name = flip
last_ts = 182.174210
last_received_ts = 182.173584
last_seq = 4890
current_ts = 182.191132
current_received_ts = 182.190521
current_seq = 4891
count = 39
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 182.174210/4890, current 182.191132/4891: elapsed=16926.0us expected=16953.4us +- 84.8us, error 0.2%
(kms_flip:1744) DEBUG: name = flip
last_ts = 182.191132
last_received_ts = 182.190521
last_seq = 4891
current_ts = 182.208054
current_received_ts = 182.207443
current_seq = 4892
count = 40
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 182.191132/4891, current 182.208054/4892: elapsed=16926.0us expected=16953.4us +- 84.8us, error 0.2%
(kms_flip:1744) DEBUG: name = flip
last_ts = 182.208054
last_received_ts = 182.207443
last_seq = 4892
current_ts = 182.224991
current_received_ts = 182.224380
current_seq = 4893
count = 41
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 182.208054/4892, current 182.224991/4893: elapsed=16926.0us expected=16953.4us +- 84.8us, error 0.2%
(kms_flip:1744) DEBUG: name = flip
last_ts = 182.224991
last_received_ts = 182.224380
last_seq = 4893
current_ts = 182.241913
current_received_ts = 182.241287
current_seq = 4894
count = 42
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 182.224991/4893, current 182.241913/4894: elapsed=16926.0us expected=16953.4us +- 84.8us, error 0.2%
(kms_flip:1744) DEBUG: name = flip
last_ts = 182.241913
last_received_ts = 182.241287
last_seq = 4894
current_ts = 182.258835
current_received_ts = 182.258240
current_seq = 4895
count = 43
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 182.241913/4894, current 182.258835/4895: elapsed=16927.0us expected=16953.4us +- 84.8us, error 0.2%
(kms_flip:1744) DEBUG: name = flip
last_ts = 182.258835
last_received_ts = 182.258240
last_seq = 4895
current_ts = 182.275757
current_received_ts = 182.275146
current_seq = 4896
count = 44
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 182.258835/4895, current 182.275757/4896: elapsed=16926.0us expected=16953.4us +- 84.8us, error 0.2%
(kms_flip:1744) DEBUG: name = flip
last_ts = 182.275757
last_received_ts = 182.275146
last_seq = 4896
current_ts = 182.292694
current_received_ts = 182.292084
current_seq = 4897
count = 45
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 182.275757/4896, current 182.292694/4897: elapsed=16927.0us expected=16953.4us +- 84.8us, error 0.2%
(kms_flip:1744) DEBUG: name = flip
last_ts = 182.292694
last_received_ts = 182.292084
last_seq = 4897
current_ts = 182.309616
current_received_ts = 182.309021
current_seq = 4898
count = 46
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 182.292694/4897, current 182.309616/4898: elapsed=16926.0us expected=16953.4us +- 84.8us, error 0.2%
(kms_flip:1744) DEBUG: name = flip
last_ts = 182.309616
last_received_ts = 182.309021
last_seq = 4898
current_ts = 182.326538
current_received_ts = 182.325928
current_seq = 4899
count = 47
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 182.309616/4898, current 182.326538/4899: elapsed=16926.0us expected=16953.4us +- 84.8us, error 0.2%
(kms_flip:1744) DEBUG: name = flip
last_ts = 182.326538
last_received_ts = 182.325928
last_seq = 4899
current_ts = 182.343475
current_received_ts = 182.342865
current_seq = 4900
count = 48
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 182.326538/4899, current 182.343475/4900: elapsed=16927.0us expected=16953.4us +- 84.8us, error 0.2%
(kms_flip:1744) DEBUG: name = flip
last_ts = 182.343475
last_received_ts = 182.342865
last_seq = 4900
current_ts = 182.360397
current_received_ts = 182.359818
current_seq = 4901
count = 49
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 182.343475/4900, current 182.360397/4901: elapsed=16927.0us expected=16953.4us +- 84.8us, error 0.2%
(kms_flip:1744) DEBUG: name = flip
last_ts = 182.360397
last_received_ts = 182.359818
last_seq = 4901
current_ts = 182.377319
current_received_ts = 182.376724
current_seq = 4902
count = 50
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 182.360397/4901, current 182.377319/4902: elapsed=16925.0us expected=16953.4us +- 84.8us, error 0.2%
(kms_flip:1744) DEBUG: name = flip
last_ts = 182.377319
last_received_ts = 182.376724
last_seq = 4902
current_ts = 182.394257
current_received_ts = 182.393631
current_seq = 4903
count = 51
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 182.377319/4902, current 182.394257/4903: elapsed=16928.0us expected=16953.4us +- 84.8us, error 0.1%
(kms_flip:1744) DEBUG: name = flip
last_ts = 182.394257
last_received_ts = 182.393631
last_seq = 4903
current_ts = 182.411179
current_received_ts = 182.410568
current_seq = 4904
count = 52
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 182.394257/4903, current 182.411179/4904: elapsed=16924.0us expected=16953.4us +- 84.8us, error 0.2%
(kms_flip:1744) DEBUG: name = flip
last_ts = 182.411179
last_received_ts = 182.410568
last_seq = 4904
current_ts = 182.428101
current_received_ts = 182.427505
current_seq = 4905
count = 53
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 182.411179/4904, current 182.428101/4905: elapsed=16927.0us expected=16953.4us +- 84.8us, error 0.2%
(kms_flip:1744) DEBUG: name = flip
last_ts = 182.428101
last_received_ts = 182.427505
last_seq = 4905
current_ts = 182.445023
current_received_ts = 182.444412
current_seq = 4906
count = 54
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 182.428101/4905, current 182.445023/4906: elapsed=16927.0us expected=16953.4us +- 84.8us, error 0.2%
(kms_flip:1744) DEBUG: name = flip
last_ts = 182.445023
last_received_ts = 182.444412
last_seq = 4906
current_ts = 182.461960
current_received_ts = 182.461319
current_seq = 4907
count = 55
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 182.445023/4906, current 182.461960/4907: elapsed=16929.0us expected=16953.4us +- 84.8us, error 0.1%
(kms_flip:1744) DEBUG: name = flip
last_ts = 182.461960
last_received_ts = 182.461319
last_seq = 4907
current_ts = 182.478882
current_received_ts = 182.478317
current_seq = 4908
count = 56
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 182.461960/4907, current 182.478882/4908: elapsed=16923.0us expected=16953.4us +- 84.8us, error 0.2%
(kms_flip:1744) DEBUG: name = flip
last_ts = 182.478882
last_received_ts = 182.478317
last_seq = 4908
current_ts = 182.495789
current_received_ts = 182.495193
current_seq = 4909
count = 57
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 182.478882/4908, current 182.495789/4909: elapsed=16905.0us expected=16953.4us +- 84.8us, error 0.3%
(kms_flip:1744) DEBUG: name = flip
last_ts = 182.495789
last_received_ts = 182.495193
last_seq = 4909
current_ts = 182.512680
current_received_ts = 182.512070
current_seq = 4910
count = 58
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 182.495789/4909, current 182.512680/4910: elapsed=16901.0us expected=16953.4us +- 84.8us, error 0.3%
(kms_flip:1744) DEBUG: name = flip
last_ts = 182.512680
last_received_ts = 182.512070
last_seq = 4910
current_ts = 182.529587
current_received_ts = 182.529022
current_seq = 4911
count = 59
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 182.512680/4910, current 182.529587/4911: elapsed=16903.0us expected=16953.4us +- 84.8us, error 0.3%
(kms_flip:1744) DEBUG: name = flip
last_ts = 182.529587
last_received_ts = 182.529022
last_seq = 4911
current_ts = 182.546494
current_received_ts = 182.545898
current_seq = 4912
count = 60
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 182.529587/4911, current 182.546494/4912: elapsed=16899.0us expected=16953.4us +- 84.8us, error 0.3%
(kms_flip:1744) DEBUG: name = flip
last_ts = 182.546494
last_received_ts = 182.545898
last_seq = 4912
current_ts = 182.563400
current_received_ts = 182.562790
current_seq = 4913
count = 61
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 182.546494/4912, current 182.563400/4913: elapsed=16910.0us expected=16953.4us +- 84.8us, error 0.3%
(kms_flip:1744) DEBUG: name = flip
last_ts = 182.563400
last_received_ts = 182.562790
last_seq = 4913
current_ts = 182.580292
current_received_ts = 182.579727
current_seq = 4914
count = 62
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 182.563400/4913, current 182.580292/4914: elapsed=16892.0us expected=16953.4us +- 84.8us, error 0.4%
(kms_flip:1744) DEBUG: name = flip
last_ts = 182.580292
last_received_ts = 182.579727
last_seq = 4914
current_ts = 182.597198
current_received_ts = 182.596664
current_seq = 4915
count = 63
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 182.580292/4914, current 182.597198/4915: elapsed=16901.0us expected=16953.4us +- 84.8us, error 0.3%
(kms_flip:1744) DEBUG: name = flip
last_ts = 182.597198
last_received_ts = 182.596664
last_seq = 4915
current_ts = 182.614090
current_received_ts = 182.613510
current_seq = 4916
count = 64
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 182.597198/4915, current 182.614090/4916: elapsed=16901.0us expected=16953.4us +- 84.8us, error 0.3%
(kms_flip:1744) DEBUG: name = flip
last_ts = 182.614090
last_received_ts = 182.613510
last_seq = 4916
current_ts = 182.630997
current_received_ts = 182.630447
current_seq = 4917
count = 65
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 182.614090/4916, current 182.630997/4917: elapsed=16904.0us expected=16953.4us +- 84.8us, error 0.3%
(kms_flip:1744) DEBUG: name = flip
last_ts = 182.630997
last_received_ts = 182.630447
last_seq = 4917
current_ts = 182.647903
current_received_ts = 182.647278
current_seq = 4918
count = 66
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 182.630997/4917, current 182.647903/4918: elapsed=16910.0us expected=16953.4us +- 84.8us, error 0.3%
(kms_flip:1744) DEBUG: name = flip
last_ts = 182.647903
last_received_ts = 182.647278
last_seq = 4918
current_ts = 182.664795
current_received_ts = 182.664230
current_seq = 4919
count = 67
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 182.647903/4918, current 182.664795/4919: elapsed=16889.0us expected=16953.4us +- 84.8us, error 0.4%
(kms_flip:1744) DEBUG: name = flip
last_ts = 182.664795
last_received_ts = 182.664230
last_seq = 4919
current_ts = 182.681702
current_received_ts = 182.681107
current_seq = 4920
count = 68
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 182.664795/4919, current 182.681702/4920: elapsed=16903.0us expected=16953.4us +- 84.8us, error 0.3%
(kms_flip:1744) DEBUG: name = flip
last_ts = 182.681702
last_received_ts = 182.681107
last_seq = 4920
current_ts = 182.698593
current_received_ts = 182.698029
current_seq = 4921
count = 69
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 182.681702/4920, current 182.698593/4921: elapsed=16900.0us expected=16953.4us +- 84.8us, error 0.3%
(kms_flip:1744) DEBUG: name = flip
last_ts = 182.698593
last_received_ts = 182.698029
last_seq = 4921
current_ts = 182.715500
current_received_ts = 182.714920
current_seq = 4922
count = 70
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 182.698593/4921, current 182.715500/4922: elapsed=16901.0us expected=16953.4us +- 84.8us, error 0.3%
(kms_flip:1744) DEBUG: name = flip
last_ts = 182.715500
last_received_ts = 182.714920
last_seq = 4922
current_ts = 182.732407
current_received_ts = 182.731842
current_seq = 4923
count = 71
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 182.715500/4922, current 182.732407/4923: elapsed=16901.0us expected=16953.4us +- 84.8us, error 0.3%
(kms_flip:1744) DEBUG: name = flip
last_ts = 182.732407
last_received_ts = 182.731842
last_seq = 4923
current_ts = 182.749298
current_received_ts = 182.748749
current_seq = 4924
count = 72
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 182.732407/4923, current 182.749298/4924: elapsed=16902.0us expected=16953.4us +- 84.8us, error 0.3%
(kms_flip:1744) DEBUG: name = flip
last_ts = 182.749298
last_received_ts = 182.748749
last_seq = 4924
current_ts = 182.766205
current_received_ts = 182.765656
current_seq = 4925
count = 73
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 182.749298/4924, current 182.766205/4925: elapsed=16902.0us expected=16953.4us +- 84.8us, error 0.3%
(kms_flip:1744) DEBUG: name = flip
last_ts = 182.766205
last_received_ts = 182.765656
last_seq = 4925
current_ts = 182.783112
current_received_ts = 182.782532
current_seq = 4926
count = 74
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 182.766205/4925, current 182.783112/4926: elapsed=16901.0us expected=16953.4us +- 84.8us, error 0.3%
(kms_flip:1744) DEBUG: name = flip
last_ts = 182.783112
last_received_ts = 182.782532
last_seq = 4926
current_ts = 182.800003
current_received_ts = 182.799454
current_seq = 4927
count = 75
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 182.783112/4926, current 182.800003/4927: elapsed=16900.0us expected=16953.4us +- 84.8us, error 0.3%
(kms_flip:1744) DEBUG: name = flip
last_ts = 182.800003
last_received_ts = 182.799454
last_seq = 4927
current_ts = 182.816910
current_received_ts = 182.816345
current_seq = 4928
count = 76
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 182.800003/4927, current 182.816910/4928: elapsed=16902.0us expected=16953.4us +- 84.8us, error 0.3%
(kms_flip:1744) DEBUG: name = flip
last_ts = 182.816910
last_received_ts = 182.816345
last_seq = 4928
current_ts = 182.833801
current_received_ts = 182.833252
current_seq = 4929
count = 77
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 182.816910/4928, current 182.833801/4929: elapsed=16901.0us expected=16953.4us +- 84.8us, error 0.3%
(kms_flip:1744) DEBUG: name = flip
last_ts = 182.833801
last_received_ts = 182.833252
last_seq = 4929
current_ts = 182.850708
current_received_ts = 182.850128
current_seq = 4930
count = 78
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 182.833801/4929, current 182.850708/4930: elapsed=16902.0us expected=16953.4us +- 84.8us, error 0.3%
(kms_flip:1744) DEBUG: name = flip
last_ts = 182.850708
last_received_ts = 182.850128
last_seq = 4930
current_ts = 182.867599
current_received_ts = 182.867081
current_seq = 4931
count = 79
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 182.850708/4930, current 182.867599/4931: elapsed=16897.0us expected=16953.4us +- 84.8us, error 0.3%
(kms_flip:1744) DEBUG: name = flip
last_ts = 182.867599
last_received_ts = 182.867081
last_seq = 4931
current_ts = 182.884506
current_received_ts = 182.883957
current_seq = 4932
count = 80
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 182.867599/4931, current 182.884506/4932: elapsed=16904.0us expected=16953.4us +- 84.8us, error 0.3%
(kms_flip:1744) DEBUG: name = flip
last_ts = 182.884506
last_received_ts = 182.883957
last_seq = 4932
current_ts = 182.901413
current_received_ts = 182.900848
current_seq = 4933
count = 81
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 182.884506/4932, current 182.901413/4933: elapsed=16899.0us expected=16953.4us +- 84.8us, error 0.3%
(kms_flip:1744) DEBUG: name = flip
last_ts = 182.901413
last_received_ts = 182.900848
last_seq = 4933
current_ts = 182.918304
current_received_ts = 182.917755
current_seq = 4934
count = 82
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 182.901413/4933, current 182.918304/4934: elapsed=16898.0us expected=16953.4us +- 84.8us, error 0.3%
(kms_flip:1744) DEBUG: name = flip
last_ts = 182.918304
last_received_ts = 182.917755
last_seq = 4934
current_ts = 182.935211
current_received_ts = 182.934601
current_seq = 4935
count = 83
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 182.918304/4934, current 182.935211/4935: elapsed=16902.0us expected=16953.4us +- 84.8us, error 0.3%
(kms_flip:1744) DEBUG: name = flip
last_ts = 182.935211
last_received_ts = 182.934601
last_seq = 4935
current_ts = 182.952118
current_received_ts = 182.951492
current_seq = 4936
count = 84
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 182.935211/4935, current 182.952118/4936: elapsed=16906.0us expected=16953.4us +- 84.8us, error 0.3%
(kms_flip:1744) DEBUG: name = flip
last_ts = 182.952118
last_received_ts = 182.951492
last_seq = 4936
current_ts = 182.969025
current_received_ts = 182.968414
current_seq = 4937
count = 85
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 182.952118/4936, current 182.969025/4937: elapsed=16901.0us expected=16953.4us +- 84.8us, error 0.3%
(kms_flip:1744) DEBUG: name = flip
last_ts = 182.969025
last_received_ts = 182.968414
last_seq = 4937
current_ts = 182.985916
current_received_ts = 182.985291
current_seq = 4938
count = 86
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 182.969025/4937, current 182.985916/4938: elapsed=16902.0us expected=16953.4us +- 84.8us, error 0.3%
(kms_flip:1744) DEBUG: name = flip
last_ts = 182.985916
last_received_ts = 182.985291
last_seq = 4938
current_ts = 183.002823
current_received_ts = 183.002228
current_seq = 4939
count = 87
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 182.985916/4938, current 183.002823/4939: elapsed=16901.0us expected=16953.4us +- 84.8us, error 0.3%
(kms_flip:1744) DEBUG: name = flip
last_ts = 183.002823
last_received_ts = 183.002228
last_seq = 4939
current_ts = 183.019714
current_received_ts = 183.019119
current_seq = 4940
count = 88
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 183.002823/4939, current 183.019714/4940: elapsed=16901.0us expected=16953.4us +- 84.8us, error 0.3%
(kms_flip:1744) DEBUG: name = flip
last_ts = 183.019714
last_received_ts = 183.019119
last_seq = 4940
current_ts = 183.036621
current_received_ts = 183.036026
current_seq = 4941
count = 89
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 183.019714/4940, current 183.036621/4941: elapsed=16902.0us expected=16953.4us +- 84.8us, error 0.3%
(kms_flip:1744) DEBUG: name = flip
last_ts = 183.036621
last_received_ts = 183.036026
last_seq = 4941
current_ts = 183.053528
current_received_ts = 183.052917
current_seq = 4942
count = 90
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 183.036621/4941, current 183.053528/4942: elapsed=16901.0us expected=16953.4us +- 84.8us, error 0.3%
(kms_flip:1744) DEBUG: name = flip
last_ts = 183.053528
last_received_ts = 183.052917
last_seq = 4942
current_ts = 183.070419
current_received_ts = 183.069839
current_seq = 4943
count = 91
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 183.053528/4942, current 183.070419/4943: elapsed=16900.0us expected=16953.4us +- 84.8us, error 0.3%
(kms_flip:1744) DEBUG: name = flip
last_ts = 183.070419
last_received_ts = 183.069839
last_seq = 4943
current_ts = 183.087326
current_received_ts = 183.086731
current_seq = 4944
count = 92
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 183.070419/4943, current 183.087326/4944: elapsed=16902.0us expected=16953.4us +- 84.8us, error 0.3%
(kms_flip:1744) DEBUG: name = flip
last_ts = 183.087326
last_received_ts = 183.086731
last_seq = 4944
current_ts = 183.104233
current_received_ts = 183.103622
current_seq = 4945
count = 93
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 183.087326/4944, current 183.104233/4945: elapsed=16903.0us expected=16953.4us +- 84.8us, error 0.3%
(kms_flip:1744) DEBUG: name = flip
last_ts = 183.104233
last_received_ts = 183.103622
last_seq = 4945
current_ts = 183.121124
current_received_ts = 183.120529
current_seq = 4946
count = 94
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 183.104233/4945, current 183.121124/4946: elapsed=16899.0us expected=16953.4us +- 84.8us, error 0.3%
(kms_flip:1744) DEBUG: name = flip
last_ts = 183.121124
last_received_ts = 183.120529
last_seq = 4946
current_ts = 183.138031
current_received_ts = 183.137421
current_seq = 4947
count = 95
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 183.121124/4946, current 183.138031/4947: elapsed=16903.0us expected=16953.4us +- 84.8us, error 0.3%
(kms_flip:1744) DEBUG: name = flip
last_ts = 183.138031
last_received_ts = 183.137421
last_seq = 4947
current_ts = 183.154938
current_received_ts = 183.154327
current_seq = 4948
count = 96
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 183.138031/4947, current 183.154938/4948: elapsed=16900.0us expected=16953.4us +- 84.8us, error 0.3%
(kms_flip:1744) DEBUG: name = flip
last_ts = 183.154938
last_received_ts = 183.154327
last_seq = 4948
current_ts = 183.171829
current_received_ts = 183.171234
current_seq = 4949
count = 97
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 183.154938/4948, current 183.171829/4949: elapsed=16901.0us expected=16953.4us +- 84.8us, error 0.3%
(kms_flip:1744) DEBUG: name = flip
last_ts = 183.171829
last_received_ts = 183.171234
last_seq = 4949
current_ts = 183.188736
current_received_ts = 183.188126
current_seq = 4950
count = 98
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 183.171829/4949, current 183.188736/4950: elapsed=16901.0us expected=16953.4us +- 84.8us, error 0.3%
(kms_flip:1744) DEBUG: name = flip
last_ts = 183.188736
last_received_ts = 183.188126
last_seq = 4950
current_ts = 183.205627
current_received_ts = 183.205048
current_seq = 4951
count = 99
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 183.188736/4950, current 183.205627/4951: elapsed=16901.0us expected=16953.4us +- 84.8us, error 0.3%
(kms_flip:1744) DEBUG: name = flip
last_ts = 183.205627
last_received_ts = 183.205048
last_seq = 4951
current_ts = 183.222549
current_received_ts = 183.221924
current_seq = 4952
count = 100
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 183.205627/4951, current 183.222549/4952: elapsed=16909.0us expected=16953.4us +- 84.8us, error 0.3%
(kms_flip:1744) DEBUG: name = flip
last_ts = 183.222549
last_received_ts = 183.221924
last_seq = 4952
current_ts = 183.239441
current_received_ts = 183.238846
current_seq = 4953
count = 101
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 183.222549/4952, current 183.239441/4953: elapsed=16895.0us expected=16953.4us +- 84.8us, error 0.3%
(kms_flip:1744) DEBUG: name = flip
last_ts = 183.239441
last_received_ts = 183.238846
last_seq = 4953
current_ts = 183.256332
current_received_ts = 183.255737
current_seq = 4954
count = 102
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 183.239441/4953, current 183.256332/4954: elapsed=16900.0us expected=16953.4us +- 84.8us, error 0.3%
(kms_flip:1744) DEBUG: name = flip
last_ts = 183.256332
last_received_ts = 183.255737
last_seq = 4954
current_ts = 183.273239
current_received_ts = 183.272629
current_seq = 4955
count = 103
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 183.256332/4954, current 183.273239/4955: elapsed=16902.0us expected=16953.4us +- 84.8us, error 0.3%
(kms_flip:1744) DEBUG: name = flip
last_ts = 183.273239
last_received_ts = 183.272629
last_seq = 4955
current_ts = 183.290146
current_received_ts = 183.289536
current_seq = 4956
count = 104
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 183.273239/4955, current 183.290146/4956: elapsed=16901.0us expected=16953.4us +- 84.8us, error 0.3%
(kms_flip:1744) DEBUG: name = flip
last_ts = 183.290146
last_received_ts = 183.289536
last_seq = 4956
current_ts = 183.307037
current_received_ts = 183.306442
current_seq = 4957
count = 105
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 183.290146/4956, current 183.307037/4957: elapsed=16901.0us expected=16953.4us +- 84.8us, error 0.3%
(kms_flip:1744) DEBUG: name = flip
last_ts = 183.307037
last_received_ts = 183.306442
last_seq = 4957
current_ts = 183.323944
current_received_ts = 183.323334
current_seq = 4958
count = 106
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 183.307037/4957, current 183.323944/4958: elapsed=16900.0us expected=16953.4us +- 84.8us, error 0.3%
(kms_flip:1744) DEBUG: name = flip
last_ts = 183.323944
last_received_ts = 183.323334
last_seq = 4958
current_ts = 183.340851
current_received_ts = 183.340240
current_seq = 4959
count = 107
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 183.323944/4958, current 183.340851/4959: elapsed=16902.0us expected=16953.4us +- 84.8us, error 0.3%
(kms_flip:1744) DEBUG: name = flip
last_ts = 183.340851
last_received_ts = 183.340240
last_seq = 4959
current_ts = 183.357742
current_received_ts = 183.357147
current_seq = 4960
count = 108
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 183.340851/4959, current 183.357742/4960: elapsed=16903.0us expected=16953.4us +- 84.8us, error 0.3%
(kms_flip:1744) DEBUG: name = flip
last_ts = 183.357742
last_received_ts = 183.357147
last_seq = 4960
current_ts = 183.374649
current_received_ts = 183.374054
current_seq = 4961
count = 109
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 183.357742/4960, current 183.374649/4961: elapsed=16899.0us expected=16953.4us +- 84.8us, error 0.3%
(kms_flip:1744) DEBUG: name = flip
last_ts = 183.374649
last_received_ts = 183.374054
last_seq = 4961
current_ts = 183.391541
current_received_ts = 183.390945
current_seq = 4962
count = 110
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 183.374649/4961, current 183.391541/4962: elapsed=16901.0us expected=16953.4us +- 84.8us, error 0.3%
(kms_flip:1744) DEBUG: name = flip
last_ts = 183.391541
last_received_ts = 183.390945
last_seq = 4962
current_ts = 183.408463
current_received_ts = 183.407822
current_seq = 4963
count = 111
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 183.391541/4962, current 183.408463/4963: elapsed=16912.0us expected=16953.4us +- 84.8us, error 0.2%
(kms_flip:1744) DEBUG: name = flip
last_ts = 183.408463
last_received_ts = 183.407822
last_seq = 4963
current_ts = 183.425354
current_received_ts = 183.424744
current_seq = 4964
count = 112
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 183.408463/4963, current 183.425354/4964: elapsed=16890.0us expected=16953.4us +- 84.8us, error 0.4%
(kms_flip:1744) DEBUG: name = flip
last_ts = 183.425354
last_received_ts = 183.424744
last_seq = 4964
current_ts = 183.442245
current_received_ts = 183.441650
current_seq = 4965
count = 113
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 183.425354/4964, current 183.442245/4965: elapsed=16902.0us expected=16953.4us +- 84.8us, error 0.3%
(kms_flip:1744) DEBUG: name = flip
last_ts = 183.442245
last_received_ts = 183.441650
last_seq = 4965
current_ts = 183.459152
current_received_ts = 183.458496
current_seq = 4966
count = 114
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 183.442245/4965, current 183.459152/4966: elapsed=16894.0us expected=16953.4us +- 84.8us, error 0.4%
(kms_flip:1744) DEBUG: name = flip
last_ts = 183.459152
last_received_ts = 183.458496
last_seq = 4966
current_ts = 183.476059
current_received_ts = 183.475449
current_seq = 4967
count = 115
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 183.459152/4966, current 183.476059/4967: elapsed=16909.0us expected=16953.4us +- 84.8us, error 0.3%
(kms_flip:1744) DEBUG: name = flip
last_ts = 183.476059
last_received_ts = 183.475449
last_seq = 4967
current_ts = 183.492950
current_received_ts = 183.492340
current_seq = 4968
count = 116
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 183.476059/4967, current 183.492950/4968: elapsed=16901.0us expected=16953.4us +- 84.8us, error 0.3%
(kms_flip:1744) DEBUG: name = flip
last_ts = 183.492950
last_received_ts = 183.492340
last_seq = 4968
current_ts = 183.509827
current_received_ts = 183.509216
current_seq = 4969
count = 117
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 183.492950/4968, current 183.509827/4969: elapsed=16879.0us expected=16953.4us +- 84.8us, error 0.4%
(kms_flip:1744) DEBUG: name = flip
last_ts = 183.509827
last_received_ts = 183.509216
last_seq = 4969
current_ts = 183.526718
current_received_ts = 183.526108
current_seq = 4970
count = 118
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 183.509827/4969, current 183.526718/4970: elapsed=16880.0us expected=16953.4us +- 84.8us, error 0.4%
(kms_flip:1744) DEBUG: name = flip
last_ts = 183.526718
last_received_ts = 183.526108
last_seq = 4970
current_ts = 183.543594
current_received_ts = 183.542969
current_seq = 4971
count = 119
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 183.526718/4970, current 183.543594/4971: elapsed=16879.0us expected=16953.4us +- 84.8us, error 0.4%
(kms_flip:1744) DEBUG: name = flip
last_ts = 183.543594
last_received_ts = 183.542969
last_seq = 4971
current_ts = 183.560471
current_received_ts = 183.559860
current_seq = 4972
count = 120
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 183.543594/4971, current 183.560471/4972: elapsed=16879.0us expected=16953.4us +- 84.8us, error 0.4%
(kms_flip:1744) DEBUG: name = flip
last_ts = 183.560471
last_received_ts = 183.559860
last_seq = 4972
current_ts = 183.577347
current_received_ts = 183.576767
current_seq = 4973
count = 121
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 183.560471/4972, current 183.577347/4973: elapsed=16878.0us expected=16953.4us +- 84.8us, error 0.4%
(kms_flip:1744) DEBUG: name = flip
last_ts = 183.577347
last_received_ts = 183.576767
last_seq = 4973
current_ts = 183.594223
current_received_ts = 183.593658
current_seq = 4974
count = 122
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 183.577347/4973, current 183.594223/4974: elapsed=16880.0us expected=16953.4us +- 84.8us, error 0.4%
(kms_flip:1744) DEBUG: name = flip
last_ts = 183.594223
last_received_ts = 183.593658
last_seq = 4974
current_ts = 183.611115
current_received_ts = 183.610535
current_seq = 4975
count = 123
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 183.594223/4974, current 183.611115/4975: elapsed=16879.0us expected=16953.4us +- 84.8us, error 0.4%
(kms_flip:1744) DEBUG: name = flip
last_ts = 183.611115
last_received_ts = 183.610535
last_seq = 4975
current_ts = 183.627991
current_received_ts = 183.627411
current_seq = 4976
count = 124
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 183.611115/4975, current 183.627991/4976: elapsed=16880.0us expected=16953.4us +- 84.8us, error 0.4%
(kms_flip:1744) DEBUG: name = flip
last_ts = 183.627991
last_received_ts = 183.627411
last_seq = 4976
current_ts = 183.644867
current_received_ts = 183.644272
current_seq = 4977
count = 125
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 183.627991/4976, current 183.644867/4977: elapsed=16880.0us expected=16953.4us +- 84.8us, error 0.4%
(kms_flip:1744) DEBUG: name = flip
last_ts = 183.644867
last_received_ts = 183.644272
last_seq = 4977
current_ts = 183.661743
current_received_ts = 183.661118
current_seq = 4978
count = 126
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 183.644867/4977, current 183.661743/4978: elapsed=16877.0us expected=16953.4us +- 84.8us, error 0.5%
(kms_flip:1744) DEBUG: name = flip
last_ts = 183.661743
last_received_ts = 183.661118
last_seq = 4978
current_ts = 183.678619
current_received_ts = 183.678085
current_seq = 4979
count = 127
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 183.661743/4978, current 183.678619/4979: elapsed=16880.0us expected=16953.4us +- 84.8us, error 0.4%
(kms_flip:1744) DEBUG: name = flip
last_ts = 183.678619
last_received_ts = 183.678085
last_seq = 4979
current_ts = 183.695511
current_received_ts = 183.694870
current_seq = 4980
count = 128
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 183.678619/4979, current 183.695511/4980: elapsed=16881.0us expected=16953.4us +- 84.8us, error 0.4%
(kms_flip:1744) DEBUG: name = flip
last_ts = 183.695511
last_received_ts = 183.694870
last_seq = 4980
current_ts = 183.712387
current_received_ts = 183.711807
current_seq = 4981
count = 129
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 183.695511/4980, current 183.712387/4981: elapsed=16878.0us expected=16953.4us +- 84.8us, error 0.4%
(kms_flip:1744) DEBUG: name = flip
last_ts = 183.712387
last_received_ts = 183.711807
last_seq = 4981
current_ts = 183.729263
current_received_ts = 183.728683
current_seq = 4982
count = 130
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 183.712387/4981, current 183.729263/4982: elapsed=16878.0us expected=16953.4us +- 84.8us, error 0.4%
(kms_flip:1744) DEBUG: name = flip
last_ts = 183.729263
last_received_ts = 183.728683
last_seq = 4982
current_ts = 183.746140
current_received_ts = 183.745544
current_seq = 4983
count = 131
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 183.729263/4982, current 183.746140/4983: elapsed=16880.0us expected=16953.4us +- 84.8us, error 0.4%
(kms_flip:1744) DEBUG: name = flip
last_ts = 183.746140
last_received_ts = 183.745544
last_seq = 4983
current_ts = 183.763016
current_received_ts = 183.762421
current_seq = 4984
count = 132
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 183.746140/4983, current 183.763016/4984: elapsed=16878.0us expected=16953.4us +- 84.8us, error 0.4%
(kms_flip:1744) DEBUG: name = flip
last_ts = 183.763016
last_received_ts = 183.762421
last_seq = 4984
current_ts = 183.779907
current_received_ts = 183.779327
current_seq = 4985
count = 133
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 183.763016/4984, current 183.779907/4985: elapsed=16880.0us expected=16953.4us +- 84.8us, error 0.4%
(kms_flip:1744) DEBUG: name = flip
last_ts = 183.779907
last_received_ts = 183.779327
last_seq = 4985
current_ts = 183.796783
current_received_ts = 183.796188
current_seq = 4986
count = 134
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 183.779907/4985, current 183.796783/4986: elapsed=16880.0us expected=16953.4us +- 84.8us, error 0.4%
(kms_flip:1744) DEBUG: name = flip
last_ts = 183.796783
last_received_ts = 183.796188
last_seq = 4986
current_ts = 183.813644
current_received_ts = 183.813080
current_seq = 4987
count = 135
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 183.796783/4986, current 183.813644/4987: elapsed=16871.0us expected=16953.4us +- 84.8us, error 0.5%
(kms_flip:1744) DEBUG: name = flip
last_ts = 183.813644
last_received_ts = 183.813080
last_seq = 4987
current_ts = 183.830536
current_received_ts = 183.829941
current_seq = 4988
count = 136
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 183.813644/4987, current 183.830536/4988: elapsed=16887.0us expected=16953.4us +- 84.8us, error 0.4%
(kms_flip:1744) DEBUG: name = flip
last_ts = 183.830536
last_received_ts = 183.829941
last_seq = 4988
current_ts = 183.847412
current_received_ts = 183.846817
current_seq = 4989
count = 137
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 183.830536/4988, current 183.847412/4989: elapsed=16879.0us expected=16953.4us +- 84.8us, error 0.4%
(kms_flip:1744) DEBUG: name = flip
last_ts = 183.847412
last_received_ts = 183.846817
last_seq = 4989
current_ts = 183.864304
current_received_ts = 183.863693
current_seq = 4990
count = 138
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 183.847412/4989, current 183.864304/4990: elapsed=16880.0us expected=16953.4us +- 84.8us, error 0.4%
(kms_flip:1744) DEBUG: name = flip
last_ts = 183.864304
last_received_ts = 183.863693
last_seq = 4990
current_ts = 183.881180
current_received_ts = 183.880569
current_seq = 4991
count = 139
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 183.864304/4990, current 183.881180/4991: elapsed=16876.0us expected=16953.4us +- 84.8us, error 0.5%
(kms_flip:1744) DEBUG: name = flip
last_ts = 183.881180
last_received_ts = 183.880569
last_seq = 4991
current_ts = 183.898056
current_received_ts = 183.897446
current_seq = 4992
count = 140
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 183.881180/4991, current 183.898056/4992: elapsed=16884.0us expected=16953.4us +- 84.8us, error 0.4%
(kms_flip:1744) DEBUG: name = flip
last_ts = 183.898056
last_received_ts = 183.897446
last_seq = 4992
current_ts = 183.914932
current_received_ts = 183.914322
current_seq = 4993
count = 141
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 183.898056/4992, current 183.914932/4993: elapsed=16877.0us expected=16953.4us +- 84.8us, error 0.5%
(kms_flip:1744) DEBUG: name = flip
last_ts = 183.914932
last_received_ts = 183.914322
last_seq = 4993
current_ts = 183.931808
current_received_ts = 183.931198
current_seq = 4994
count = 142
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 183.914932/4993, current 183.931808/4994: elapsed=16879.0us expected=16953.4us +- 84.8us, error 0.4%
(kms_flip:1744) DEBUG: name = flip
last_ts = 183.931808
last_received_ts = 183.931198
last_seq = 4994
current_ts = 183.948700
current_received_ts = 183.948090
current_seq = 4995
count = 143
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 183.931808/4994, current 183.948700/4995: elapsed=16880.0us expected=16953.4us +- 84.8us, error 0.4%
(kms_flip:1744) DEBUG: name = flip
last_ts = 183.948700
last_received_ts = 183.948090
last_seq = 4995
current_ts = 183.965576
current_received_ts = 183.964966
current_seq = 4996
count = 144
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 183.948700/4995, current 183.965576/4996: elapsed=16878.0us expected=16953.4us +- 84.8us, error 0.4%
(kms_flip:1744) DEBUG: name = flip
last_ts = 183.965576
last_received_ts = 183.964966
last_seq = 4996
current_ts = 183.982452
current_received_ts = 183.981842
current_seq = 4997
count = 145
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 183.965576/4996, current 183.982452/4997: elapsed=16879.0us expected=16953.4us +- 84.8us, error 0.4%
(kms_flip:1744) DEBUG: name = flip
last_ts = 183.982452
last_received_ts = 183.981842
last_seq = 4997
current_ts = 183.999329
current_received_ts = 183.998672
current_seq = 4998
count = 146
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 183.982452/4997, current 183.999329/4998: elapsed=16883.0us expected=16953.4us +- 84.8us, error 0.4%
(kms_flip:1744) DEBUG: name = flip
last_ts = 183.999329
last_received_ts = 183.998672
last_seq = 4998
current_ts = 184.016220
current_received_ts = 184.015564
current_seq = 4999
count = 147
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 183.999329/4998, current 184.016220/4999: elapsed=16887.0us expected=16953.4us +- 84.8us, error 0.4%
(kms_flip:1744) DEBUG: name = flip
last_ts = 184.016220
last_received_ts = 184.015564
last_seq = 4999
current_ts = 184.033081
current_received_ts = 184.032471
current_seq = 5000
count = 148
seq_step = 1
(kms_flip:1744) DEBUG: flip ts/seq: last 184.016220/4999, current 184.033081/5000: elapsed=16864.0us expected=16953.4us +- 84.8us, error 0.5%
(kms_flip:1744) DEBUG: inconsistent flip ts/seq: last 184.016220/4999, current 184.033081/5000: elapsed=16864.0us expected=16953.4us
(kms_flip:1744) DEBUG: expected 0, counted 148, encoder type 1
(kms_flip:1744) DEBUG: dropped frames, expected 0, counted 148, encoder type 1
(kms_flip:1744) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1674:
(kms_flip:1744) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon)
(kms_flip:1744) igt_core-INFO: Stack trace:
(kms_flip:1744) igt_core-INFO:   #0 ../lib/igt_core.c:2051 __igt_fail_assert()
(kms_flip:1744) igt_core-INFO:   #1 ../tests/kms_flip.c:791 run_test_on_crtc_set.constprop.0()
(kms_flip:1744) igt_core-INFO:   #2 ../tests/kms_flip.c:1845 run_test()
(kms_flip:1744) igt_core-INFO:   #3 ../tests/kms_flip.c:2078 __igt_unique____real_main2001()
(kms_flip:1744) igt_core-INFO:   #4 ../tests/kms_flip.c:2001 main()
(kms_flip:1744) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:1744) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:1744) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest A-VGA1: FAIL (5.992s)
Dmesg
<6> [180.721623] Console: switching to colour dummy device 80x25
<6> [180.721999] [IGT] kms_flip: executing
<7> [180.728155] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [180.728812] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [180.729155] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [180.732948] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:64:VGA-1]
<7> [180.732969] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] [CONNECTOR:64:VGA-1] force=1
<7> [180.733157] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] ironlake hotplug adpa=0xf40000, result 0
<7> [180.733325] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] CRT not detected via hotplug
<7> [180.733679] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [180.734153] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK on first message, retry
<7> [180.734776] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [180.735142] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [180.735158] i915 0000:00:02.0: [drm:intel_crt_get_edid [i915]] CRT GMBUS EDID read failed, retry using GPIO bit-banging
<7> [180.735611] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] enabling bit-banging on i915 gmbus vga. force bit now 1
<7> [180.737171] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [180.737177] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] disabling bit-banging on i915 gmbus vga. force bit now 0
<7> [180.737341] i915 0000:00:02.0: [drm:intel_crt_detect_ddc [i915]] CRT not detected via DDC:0x50 [no valid EDID found]
<7> [180.737507] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:64:VGA-1] disconnected
<7> [180.738057] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:75:DP-1]
<7> [180.738072] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:75:DP-1]
<7> [180.741816] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [180.745595] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [180.749434] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [180.753485] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [180.757429] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [180.761594] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [180.765607] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [180.769779] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [180.773955] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [180.778134] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [180.782318] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [180.786542] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [180.790490] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [180.794646] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [180.798864] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [180.802992] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [180.807064] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [180.811166] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [180.815130] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [180.819307] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [180.823487] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [180.827438] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [180.831654] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [180.835851] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [180.840039] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [180.844191] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [180.848365] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [180.852339] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [180.856504] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [180.860709] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [180.864900] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [180.869139] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [180.869536] 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> [180.869574] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:75:DP-1] disconnected
<7> [180.870130] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:64:VGA-1]
<7> [180.870156] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] [CONNECTOR:64:VGA-1] force=1
<7> [180.870454] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] ironlake hotplug adpa=0xf40000, result 0
<7> [180.870739] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] CRT not detected via hotplug
<7> [180.871235] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [180.871542] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK on first message, retry
<7> [180.872036] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [180.872320] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [180.872330] i915 0000:00:02.0: [drm:intel_crt_get_edid [i915]] CRT GMBUS EDID read failed, retry using GPIO bit-banging
<7> [180.872628] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] enabling bit-banging on i915 gmbus vga. force bit now 1
<7> [180.874048] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [180.874055] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] disabling bit-banging on i915 gmbus vga. force bit now 0
<7> [180.874223] i915 0000:00:02.0: [drm:intel_crt_detect_ddc [i915]] CRT not detected via DDC:0x50 [no valid EDID found]
<7> [180.874394] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:64:VGA-1] disconnected
<7> [180.874563] i915 0000:00:02.0: [drm:status_store] [CONNECTOR:64:VGA-1] force updated from 0 to 2 or reprobing
<7> [180.874570] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:64:VGA-1]
<7> [180.874584] 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> [180.874634] i915 0000:00:02.0: [drm:drm_sysfs_hotplug_event] generating hotplug event
<7> [180.874705] i915 0000:00:02.0: [drm:drm_client_dev_hotplug] intel-fbdev: ret=0
<7> [180.874827] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [180.875140] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK on first message, retry
<7> [180.875612] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [180.875896] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [180.875914] i915 0000:00:02.0: [drm:intel_crt_get_edid [i915]] CRT GMBUS EDID read failed, retry using GPIO bit-banging
<7> [180.876226] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] enabling bit-banging on i915 gmbus vga. force bit now 1
<7> [180.877973] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [180.877991] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] disabling bit-banging on i915 gmbus vga. force bit now 0
<7> [180.878340] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:64:VGA-1] probed modes:
<7> [180.878364] 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> [180.878386] 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> [180.878408] 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> [180.878430] 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> [180.878483] 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> [180.878564] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:64:VGA-1]
<7> [180.878819] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [180.879116] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK on first message, retry
<7> [180.879593] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [180.879890] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [180.879906] i915 0000:00:02.0: [drm:intel_crt_get_edid [i915]] CRT GMBUS EDID read failed, retry using GPIO bit-banging
<7> [180.880207] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] enabling bit-banging on i915 gmbus vga. force bit now 1
<7> [180.881986] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [180.882002] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] disabling bit-banging on i915 gmbus vga. force bit now 0
<7> [180.882377] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:64:VGA-1] probed modes:
<7> [180.882400] 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> [180.882422] 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> [180.882444] 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> [180.882465] 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> [180.882485] 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> [180.882562] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:67:HDMI-A-1]
<7> [180.882586] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:67:HDMI-A-1]
<7> [180.908350] 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> [180.908372] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:67:HDMI-A-1] HDMI sink does deep color 30.
<7> [180.908386] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:67:HDMI-A-1] HDMI sink does deep color 36.
<7> [180.908391] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:67:HDMI-A-1] HDMI sink does deep color 48.
<7> [180.908396] 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> [180.908401] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:67:HDMI-A-1] HDMI sink does YCRCB444 in deep color.
<7> [180.908408] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:67:HDMI-A-1] ELD monitor Mi TV
<7> [180.908413] 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> [180.908419] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:67:HDMI-A-1] ELD size 36, SAD count 3
<7> [180.908656] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpd] NAK for addr: 0040 w(1)
<7> [180.908939] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpd] NAK on first message, retry
<7> [180.909342] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpd] NAK for addr: 0040 w(1)
<7> [180.909557] i915 0000:00:02.0: [drm:drm_dp_dual_mode_detect [drm_display_helper]] DP dual mode HDMI ID: (err -6)
<7> [180.910445] 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> [180.910459] 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> [180.910468] 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> [180.910483] 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> [180.910498] 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> [180.910505] 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> [180.910519] 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> [180.910525] 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> [180.910531] 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> [180.910537] 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> [180.910544] 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> [180.910550] 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> [180.910556] 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> [180.910562] 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> [180.910568] 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> [180.910574] 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> [180.910581] 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> [180.910587] 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> [180.910593] 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> [180.910604] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:67:HDMI-A-1] probed modes:
<7> [180.910617] 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> [180.910629] 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> [180.910641] 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> [180.910652] 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> [180.910664] 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> [180.910675] 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> [180.910687] 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> [180.910699] 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> [180.910710] 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> [180.910721] 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> [180.910733] 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> [180.910744] 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> [180.910756] 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> [180.910767] 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> [180.910779] 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> [180.910790] 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> [180.910802] 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> [180.910813] 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> [180.910825] 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> [180.910836] 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> [180.910847] 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> [180.910859] 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> [180.910870] 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> [180.910882] 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> [180.910893] 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> [180.910905] 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> [180.910916] 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> [180.910928] 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> [180.910939] 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> [180.910951] 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> [180.910962] 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> [180.910974] 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> [180.910985] 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> [180.910997] 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> [180.911008] 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> [180.911020] 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> [180.911031] 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> [180.911042] 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> [180.911054] 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> [180.911065] 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> [180.911077] 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> [180.911088] 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> [180.911100] 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> [180.911111] 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> [180.911122] 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> [180.911172] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:75:DP-1]
<7> [180.911185] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:75:DP-1]
<7> [180.914898] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [180.918734] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [180.922511] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [180.926355] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [180.930142] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [180.933906] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [180.937752] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [180.941534] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [180.945426] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [180.949313] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [180.953075] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [180.956931] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [180.960749] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [180.964686] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [180.968597] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [180.972697] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [180.976838] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [180.981015] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [180.985188] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [180.989308] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [180.993362] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [180.997548] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [181.001702] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [181.005910] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [181.010134] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [181.014264] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [181.018488] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [181.022684] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [181.026870] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [181.030535] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [181.034486] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [181.038617] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [181.038983] 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> [181.039016] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:75:DP-1] disconnected
<6> [181.040221] [IGT] kms_flip: starting subtest plain-flip-fb-recreate
<6> [181.041886] [IGT] kms_flip: starting dynamic subtest A-VGA1
<7> [181.042282] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [181.042345] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [181.084351] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:47:pipe A]
<7> [181.084504] 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> [181.084968] 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> [181.085478] 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> [181.085887] 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> [181.086311] 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> [181.086688] 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> [181.087127] 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> [181.087344] 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> [181.087528] 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> [181.087724] 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> [181.087928] 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> [181.088111] 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> [181.088293] 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> [181.088474] 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> [181.088656] 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> [181.088851] 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> [181.089057] 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> [181.089239] 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> [181.089421] 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> [181.089603] 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> [181.089818] 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> [181.090023] 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> [181.090205] 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> [181.090387] 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> [181.090568] 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> [181.090766] 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> [181.090969] 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> [181.091151] 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> [181.091333] 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> [181.091514] 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> [181.091710] 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> [181.091914] 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> [181.092096] 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> [181.092277] 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> [181.092459] 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> [181.092660] 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> [181.092869] 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> [181.093051] 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> [181.093234] 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> [181.093416] 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> [181.093597] 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> [181.093804] 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> [181.094005] 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> [181.094187] 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> [181.094369] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in avi infoframe
<7> [181.094550] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [181.094762] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [181.094767] i915 0000:00:02.0: colorspace: RGB
<7> [181.094770] i915 0000:00:02.0: scan mode: Underscan
<7> [181.094774] i915 0000:00:02.0: colorimetry: No Data
<7> [181.094777] i915 0000:00:02.0: picture aspect: 16:9
<7> [181.094781] i915 0000:00:02.0: active aspect: Same as Picture
<7> [181.094784] i915 0000:00:02.0: itc: No Data
<7> [181.094787] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [181.094790] i915 0000:00:02.0: quantization range: Default
<7> [181.094794] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [181.094797] i915 0000:00:02.0: video code: 16
<7> [181.094801] i915 0000:00:02.0: ycc quantization range: Full
<7> [181.094805] i915 0000:00:02.0: hdmi content type: Graphics
<7> [181.094808] i915 0000:00:02.0: pixel repeat: 0
<7> [181.094812] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [181.094816] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [181.095029] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in spd infoframe
<7> [181.095211] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [181.095391] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [181.095394] i915 0000:00:02.0: vendor: Intel
<7> [181.095396] i915 0000:00:02.0: product: Integrated gfx
<7> [181.095398] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [181.095400] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [181.095581] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hdmi infoframe
<7> [181.095775] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [181.095980] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [181.095982] i915 0000:00:02.0: empty frame
<7> [181.095984] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [181.096166] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:47:pipe A] fastset requirement not met, forcing full modeset
<7> [181.096348] i915 0000:00:02.0: [drm:intel_unreference_shared_dpll_crtc [i915]] [CRTC:47:pipe A] releasing PCH DPLL A
<7> [181.096549] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 400000 kHz, actual 400000 kHz
<7> [181.096783] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7> [181.097000] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:47:pipe A] enable: no [modeset]
<7> [181.097183] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:32:primary A] fb: [NOFB], visible: no
<7> [181.097396] 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> [181.128815] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [181.147695] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port B found
<7> [181.148097] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port C found
<7> [181.148459] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port D found
<7> [181.157469] 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> [181.157897] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disabling PCH DPLL A
<7> [181.158490] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:65:CRT]
<7> [181.158876] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:66:HDMI D]
<7> [181.159133] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:DP D]
<7> [181.159342] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:67:HDMI-A-1]
<7> [181.159665] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:47:pipe A]
<7> [181.160217] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:63:pipe B]
<7> [181.160459] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:47:pipe A]
<7> [181.160481] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:64:VGA-1]
<7> [181.160551] 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> [181.160777] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:47:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [181.161009] i915 0000:00:02.0: [drm:intel_fdi_atomic_check_link [i915]] checking fdi config on pipe A, lanes 1
<7> [181.161186] 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> [181.161370] 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> [181.161552] 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> [181.161746] 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> [181.161962] 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> [181.162145] 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> [181.162329] 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> [181.162512] 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> [181.162722] 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> [181.162948] 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> [181.163131] 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> [181.163313] 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> [181.163495] 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> [181.163689] 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> [181.163915] 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> [181.164098] 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> [181.164280] 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> [181.164462] 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> [181.164654] 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> [181.164869] 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> [181.165051] 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> [181.165233] 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> [181.165415] 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> [181.165620] 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> [181.165832] 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> [181.166015] 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> [181.166197] 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> [181.166379] 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> [181.166560] 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> [181.166752] 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> [181.166950] 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> [181.167132] 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> [181.167313] 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> [181.167495] 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> [181.167703] 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> [181.167886] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in dpll_hw_state
<7> [181.168068] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [181.168249] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: dpll: 0xc4010001, dpll_md: 0x0, fp0: 0x30f06, fp1: 0x30f06
<7> [181.168431] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [181.168623] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: dpll: 0x84100010, dpll_md: 0x0, fp0: 0x10c09, fp1: 0x10c09
<7> [181.168809] 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> [181.168991] 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> [181.169173] 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> [181.169354] 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> [181.169545] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:47:pipe A] fastset requirement not met, forcing full modeset
<7> [181.169745] 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> [181.169932] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 400000 kHz, actual 400000 kHz
<7> [181.170116] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7> [181.170300] i915 0000:00:02.0: [drm:intel_find_shared_dpll [i915]] [CRTC:47:pipe A] allocated PCH DPLL A
<7> [181.170478] i915 0000:00:02.0: [drm:intel_reference_shared_dpll_crtc [i915]] [CRTC:47:pipe A] reserving PCH DPLL A
<7> [181.170678] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:47:pipe A] enable: yes [modeset]
<7> [181.170861] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: ANALOG (0x2), output format: RGB, sink format: RGB
<7> [181.171044] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [181.171226] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [181.171407] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [181.171598] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [181.171785] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [181.171967] 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> [181.172150] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0
<7> [181.172331] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [181.172523] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, vmin: 0, vmax: 0, pipeline full: 0, guardband: 0 flipline: 0, vmin vblank: -1, vmax vblank: -2
<7> [181.172709] 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> [181.172893] 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> [181.173075] 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> [181.173259] 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> [181.173442] 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> [181.173644] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 64800, pipe src: 1024x768+0+0, pixel rate 64800
<7> [181.173828] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 0, ips linetime: 0
<7> [181.174009] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [181.174192] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [181.174374] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: dpll: 0x84100010, dpll_md: 0x0, fp0: 0x10c09, fp1: 0x10c09
<7> [181.174564] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x3 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [181.174746] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [181.174928] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [181.175110] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [181.175291] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [181.175482] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [181.175667] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:32:primary A] fb: [FB:82] 1024x768 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [181.175851] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [181.176033] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1024.000000x768.000000+0.000000+0.000000 dst: 1024x768+0+0
<7> [181.177129] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:65:CRT]
<7> [181.177313] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:66:HDMI D]
<7> [181.177500] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:DP D]
<7> [181.178233] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [181.179265] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x100
<7> [181.179464] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 1 done.
<7> [181.180301] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x200
<7> [181.180505] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 2 done.
<7> [181.180681] i915 0000:00:02.0: [drm:ilk_pch_enable [i915]] FDI train done.
<7> [181.180858] 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> [181.181039] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enabling PCH DPLL A
<7> [181.232994] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:64:VGA-1]
<7> [181.233425] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:47:pipe A]
<7> [181.533907] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [181.550637] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [181.567355] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [181.584076] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [181.600795] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [181.617508] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [181.634255] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [181.650969] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [181.667686] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [181.684407] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [181.701131] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [181.717839] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [181.734513] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [181.751180] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [181.767969] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [181.784668] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [181.801389] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [181.818105] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [181.834854] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [181.851536] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [181.868295] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [181.884974] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [181.901723] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [181.918373] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [181.935141] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [181.951878] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [181.968555] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [181.985270] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [182.002016] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [182.018689] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [182.035409] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [182.052114] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [182.068847] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [182.085633] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [182.102266] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [182.118998] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [182.135736] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [182.152478] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [182.169194] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [182.185863] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [182.202587] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [182.219303] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [182.236061] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [182.252733] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [182.269491] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [182.286167] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [182.302897] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [182.319634] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [182.336325] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [182.353081] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [182.369815] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [182.386517] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [182.403184] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [182.419946] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [182.436666] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [182.453331] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [182.470064] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [182.486849] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [182.503514] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [182.520221] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [182.536974] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [182.553687] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [182.570411] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [182.587141] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [182.603909] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [182.620538] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [182.637322] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [182.653932] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [182.670724] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [182.687404] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [182.704147] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [182.720850] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [182.737616] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [182.754337] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [182.771042] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [182.787709] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [182.804483] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [182.821156] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [182.837881] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [182.854567] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [182.871391] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [182.888101] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [182.904775] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [182.921498] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [182.938168] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [182.954834] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [182.971608] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [182.988259] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [183.005042] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [183.021759] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [183.038464] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [183.055162] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [183.071916] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [183.088628] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [183.105312] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [183.122063] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [183.138743] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [183.155466] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [183.172210] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [183.188892] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [183.205644] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [183.222383] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [183.239080] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [183.255795] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [183.272470] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [183.289200] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [183.305920] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [183.322624] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [183.339339] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [183.356062] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [183.372791] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [183.389490] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [183.406219] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [183.422956] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [183.439650] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [183.456307] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [183.473076] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [183.489791] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [183.506508] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [183.523232] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [183.539962] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [183.556662] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [183.573473] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [183.590191] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [183.606905] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [183.623622] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [183.640284] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [183.656974] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [183.673796] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [183.690394] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [183.707206] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [183.723928] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [183.740584] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [183.757309] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [183.774060] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [183.790744] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [183.807492] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [183.824219] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [183.840900] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [183.857604] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [183.874308] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [183.891012] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [183.907729] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [183.924447] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [183.941182] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [183.957907] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [183.974598] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [183.991281] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [184.008008] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<6> [187.034527] [IGT] kms_flip: finished subtest A-VGA1, FAIL
Created at 2024-11-15 00:17:25