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

Machine description: shard-snb5

Result: Fail

git-log-oneline i915_display_info20 igt_runner20 runtimes20 results20.json results20-i915-load.json i915_display_info_post_exec20 boot20 dmesg20

DetailValue
Duration 6.17 seconds
Hostname 
shard-snb5
Igt-Version 
IGT-Version: 1.30-g61cac4a58 (x86_64) (Linux: 6.14.0-rc5-CI_DRM_16247-g23e0800ae084+ x86_64)
Out 
Using IGT_SRANDOM=1741512992 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 16999.8us +- 5.369us accuracy 0.09%
vblank interval differs from modeline! expected 16665.6us, measured 17000us +- 5.369us, difference 334.2us (62.2 sigma)
Stack trace:
  #0 ../lib/igt_core.c:2055 __igt_fail_assert()
  #1 ../tests/kms_flip.c:791 run_test_on_crtc_set.constprop.0()
  #2 ../tests/kms_flip.c:1845 run_test()
  #3 ../tests/kms_flip.c:2078 __igt_unique____real_main2001()
  #4 ../tests/kms_flip.c:2001 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest A-VGA1: FAIL (6.173s)
Err 
Starting subtest: plain-flip-fb-recreate
Starting dynamic subtest: A-VGA1
(kms_flip:2662) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1674:
(kms_flip:2662) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon)
Dynamic subtest A-VGA1 failed.
**** DEBUG ****
(kms_flip:2662) DEBUG: flip ts/seq: last 104.642433/2121, current 104.659424/2122: elapsed=16993.0us expected=16999.8us +- 85.0us, error 0.0%
(kms_flip:2662) DEBUG: name = flip
last_ts = 104.659424
last_received_ts = 104.658882
last_seq = 2122
current_ts = 104.676407
current_received_ts = 104.675789
current_seq = 2123
count = 32
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 104.659424/2122, current 104.676407/2123: elapsed=16981.0us expected=16999.8us +- 85.0us, error 0.1%
(kms_flip:2662) DEBUG: name = flip
last_ts = 104.676407
last_received_ts = 104.675789
last_seq = 2123
current_ts = 104.693382
current_received_ts = 104.692787
current_seq = 2124
count = 33
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 104.676407/2123, current 104.693382/2124: elapsed=16981.0us expected=16999.8us +- 85.0us, error 0.1%
(kms_flip:2662) DEBUG: name = flip
last_ts = 104.693382
last_received_ts = 104.692787
last_seq = 2124
current_ts = 104.710342
current_received_ts = 104.709709
current_seq = 2125
count = 34
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 104.693382/2124, current 104.710342/2125: elapsed=16959.0us expected=16999.8us +- 85.0us, error 0.2%
(kms_flip:2662) DEBUG: name = flip
last_ts = 104.710342
last_received_ts = 104.709709
last_seq = 2125
current_ts = 104.727303
current_received_ts = 104.726677
current_seq = 2126
count = 35
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 104.710342/2125, current 104.727303/2126: elapsed=16955.0us expected=16999.8us +- 85.0us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 104.727303
last_received_ts = 104.726677
last_seq = 2126
current_ts = 104.744278
current_received_ts = 104.743629
current_seq = 2127
count = 36
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 104.727303/2126, current 104.744278/2127: elapsed=16976.0us expected=16999.8us +- 85.0us, error 0.1%
(kms_flip:2662) DEBUG: name = flip
last_ts = 104.744278
last_received_ts = 104.743629
last_seq = 2127
current_ts = 104.761230
current_received_ts = 104.760635
current_seq = 2128
count = 37
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 104.744278/2127, current 104.761230/2128: elapsed=16954.0us expected=16999.8us +- 85.0us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 104.761230
last_received_ts = 104.760635
last_seq = 2128
current_ts = 104.778191
current_received_ts = 104.777580
current_seq = 2129
count = 38
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 104.761230/2128, current 104.778191/2129: elapsed=16962.0us expected=16999.8us +- 85.0us, error 0.2%
(kms_flip:2662) DEBUG: name = flip
last_ts = 104.778191
last_received_ts = 104.777580
last_seq = 2129
current_ts = 104.795158
current_received_ts = 104.794563
current_seq = 2130
count = 39
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 104.778191/2129, current 104.795158/2130: elapsed=16967.0us expected=16999.8us +- 85.0us, error 0.2%
(kms_flip:2662) DEBUG: name = flip
last_ts = 104.795158
last_received_ts = 104.794563
last_seq = 2130
current_ts = 104.812141
current_received_ts = 104.811531
current_seq = 2131
count = 40
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 104.795158/2130, current 104.812141/2131: elapsed=16983.0us expected=16999.8us +- 85.0us, error 0.1%
(kms_flip:2662) DEBUG: name = flip
last_ts = 104.812141
last_received_ts = 104.811531
last_seq = 2131
current_ts = 104.829094
current_received_ts = 104.828514
current_seq = 2132
count = 41
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 104.812141/2131, current 104.829094/2132: elapsed=16955.0us expected=16999.8us +- 85.0us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 104.829094
last_received_ts = 104.828514
last_seq = 2132
current_ts = 104.846062
current_received_ts = 104.845490
current_seq = 2133
count = 42
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 104.829094/2132, current 104.846062/2133: elapsed=16965.0us expected=16999.8us +- 85.0us, error 0.2%
(kms_flip:2662) DEBUG: name = flip
last_ts = 104.846062
last_received_ts = 104.845490
last_seq = 2133
current_ts = 104.863014
current_received_ts = 104.862396
current_seq = 2134
count = 43
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 104.846062/2133, current 104.863014/2134: elapsed=16956.0us expected=16999.8us +- 85.0us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 104.863014
last_received_ts = 104.862396
last_seq = 2134
current_ts = 104.879990
current_received_ts = 104.879402
current_seq = 2135
count = 44
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 104.863014/2134, current 104.879990/2135: elapsed=16975.0us expected=16999.8us +- 85.0us, error 0.1%
(kms_flip:2662) DEBUG: name = flip
last_ts = 104.879990
last_received_ts = 104.879402
last_seq = 2135
current_ts = 104.896965
current_received_ts = 104.896370
current_seq = 2136
count = 45
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 104.879990/2135, current 104.896965/2136: elapsed=16975.0us expected=16999.8us +- 85.0us, error 0.1%
(kms_flip:2662) DEBUG: name = flip
last_ts = 104.896965
last_received_ts = 104.896370
last_seq = 2136
current_ts = 104.913925
current_received_ts = 104.913330
current_seq = 2137
count = 46
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 104.896965/2136, current 104.913925/2137: elapsed=16955.0us expected=16999.8us +- 85.0us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 104.913925
last_received_ts = 104.913330
last_seq = 2137
current_ts = 104.930885
current_received_ts = 104.930298
current_seq = 2138
count = 47
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 104.913925/2137, current 104.930885/2138: elapsed=16965.0us expected=16999.8us +- 85.0us, error 0.2%
(kms_flip:2662) DEBUG: name = flip
last_ts = 104.930885
last_received_ts = 104.930298
last_seq = 2138
current_ts = 104.947845
current_received_ts = 104.947174
current_seq = 2139
count = 48
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 104.930885/2138, current 104.947845/2139: elapsed=16958.0us expected=16999.8us +- 85.0us, error 0.2%
(kms_flip:2662) DEBUG: name = flip
last_ts = 104.947845
last_received_ts = 104.947174
last_seq = 2139
current_ts = 104.964813
current_received_ts = 104.964180
current_seq = 2140
count = 49
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 104.947845/2139, current 104.964813/2140: elapsed=16967.0us expected=16999.8us +- 85.0us, error 0.2%
(kms_flip:2662) DEBUG: name = flip
last_ts = 104.964813
last_received_ts = 104.964180
last_seq = 2140
current_ts = 104.981773
current_received_ts = 104.981140
current_seq = 2141
count = 50
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 104.964813/2140, current 104.981773/2141: elapsed=16964.0us expected=16999.8us +- 85.0us, error 0.2%
(kms_flip:2662) DEBUG: name = flip
last_ts = 104.981773
last_received_ts = 104.981140
last_seq = 2141
current_ts = 104.998749
current_received_ts = 104.998161
current_seq = 2142
count = 51
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 104.981773/2141, current 104.998749/2142: elapsed=16972.0us expected=16999.8us +- 85.0us, error 0.2%
(kms_flip:2662) DEBUG: name = flip
last_ts = 104.998749
last_received_ts = 104.998161
last_seq = 2142
current_ts = 105.015709
current_received_ts = 105.015129
current_seq = 2143
count = 52
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 104.998749/2142, current 105.015709/2143: elapsed=16961.0us expected=16999.8us +- 85.0us, error 0.2%
(kms_flip:2662) DEBUG: name = flip
last_ts = 105.015709
last_received_ts = 105.015129
last_seq = 2143
current_ts = 105.032692
current_received_ts = 105.032188
current_seq = 2144
count = 53
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 105.015709/2143, current 105.032692/2144: elapsed=16981.0us expected=16999.8us +- 85.0us, error 0.1%
(kms_flip:2662) DEBUG: name = flip
last_ts = 105.032692
last_received_ts = 105.032188
last_seq = 2144
current_ts = 105.049637
current_received_ts = 105.049065
current_seq = 2145
count = 54
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 105.032692/2144, current 105.049637/2145: elapsed=16946.0us expected=16999.8us +- 85.0us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 105.049637
last_received_ts = 105.049065
last_seq = 2145
current_ts = 105.066605
current_received_ts = 105.066101
current_seq = 2146
count = 55
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 105.049637/2145, current 105.066605/2146: elapsed=16969.0us expected=16999.8us +- 85.0us, error 0.2%
(kms_flip:2662) DEBUG: name = flip
last_ts = 105.066605
last_received_ts = 105.066101
last_seq = 2146
current_ts = 105.083572
current_received_ts = 105.083038
current_seq = 2147
count = 56
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 105.066605/2146, current 105.083572/2147: elapsed=16967.0us expected=16999.8us +- 85.0us, error 0.2%
(kms_flip:2662) DEBUG: name = flip
last_ts = 105.083572
last_received_ts = 105.083038
last_seq = 2147
current_ts = 105.100548
current_received_ts = 105.099968
current_seq = 2148
count = 57
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 105.083572/2147, current 105.100548/2148: elapsed=16979.0us expected=16999.8us +- 85.0us, error 0.1%
(kms_flip:2662) DEBUG: name = flip
last_ts = 105.100548
last_received_ts = 105.099968
last_seq = 2148
current_ts = 105.117516
current_received_ts = 105.116943
current_seq = 2149
count = 58
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 105.100548/2148, current 105.117516/2149: elapsed=16962.0us expected=16999.8us +- 85.0us, error 0.2%
(kms_flip:2662) DEBUG: name = flip
last_ts = 105.117516
last_received_ts = 105.116943
last_seq = 2149
current_ts = 105.134468
current_received_ts = 105.133904
current_seq = 2150
count = 59
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 105.117516/2149, current 105.134468/2150: elapsed=16952.0us expected=16999.8us +- 85.0us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 105.134468
last_received_ts = 105.133904
last_seq = 2150
current_ts = 105.151428
current_received_ts = 105.150887
current_seq = 2151
count = 60
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 105.134468/2150, current 105.151428/2151: elapsed=16966.0us expected=16999.8us +- 85.0us, error 0.2%
(kms_flip:2662) DEBUG: name = flip
last_ts = 105.151428
last_received_ts = 105.150887
last_seq = 2151
current_ts = 105.168411
current_received_ts = 105.167847
current_seq = 2152
count = 61
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 105.151428/2151, current 105.168411/2152: elapsed=16980.0us expected=16999.8us +- 85.0us, error 0.1%
(kms_flip:2662) DEBUG: name = flip
last_ts = 105.168411
last_received_ts = 105.167847
last_seq = 2152
current_ts = 105.185371
current_received_ts = 105.184807
current_seq = 2153
count = 62
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 105.168411/2152, current 105.185371/2153: elapsed=16964.0us expected=16999.8us +- 85.0us, error 0.2%
(kms_flip:2662) DEBUG: name = flip
last_ts = 105.185371
last_received_ts = 105.184807
last_seq = 2153
current_ts = 105.202324
current_received_ts = 105.201706
current_seq = 2154
count = 63
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 105.185371/2153, current 105.202324/2154: elapsed=16952.0us expected=16999.8us +- 85.0us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 105.202324
last_received_ts = 105.201706
last_seq = 2154
current_ts = 105.219292
current_received_ts = 105.218689
current_seq = 2155
count = 64
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 105.202324/2154, current 105.219292/2155: elapsed=16967.0us expected=16999.8us +- 85.0us, error 0.2%
(kms_flip:2662) DEBUG: name = flip
last_ts = 105.219292
last_received_ts = 105.218689
last_seq = 2155
current_ts = 105.236267
current_received_ts = 105.235664
current_seq = 2156
count = 65
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 105.219292/2155, current 105.236267/2156: elapsed=16974.0us expected=16999.8us +- 85.0us, error 0.2%
(kms_flip:2662) DEBUG: name = flip
last_ts = 105.236267
last_received_ts = 105.235664
last_seq = 2156
current_ts = 105.253220
current_received_ts = 105.252655
current_seq = 2157
count = 66
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 105.236267/2156, current 105.253220/2157: elapsed=16951.0us expected=16999.8us +- 85.0us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 105.253220
last_received_ts = 105.252655
last_seq = 2157
current_ts = 105.270187
current_received_ts = 105.269623
current_seq = 2158
count = 67
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 105.253220/2157, current 105.270187/2158: elapsed=16969.0us expected=16999.8us +- 85.0us, error 0.2%
(kms_flip:2662) DEBUG: name = flip
last_ts = 105.270187
last_received_ts = 105.269623
last_seq = 2158
current_ts = 105.287155
current_received_ts = 105.286560
current_seq = 2159
count = 68
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 105.270187/2158, current 105.287155/2159: elapsed=16970.0us expected=16999.8us +- 85.0us, error 0.2%
(kms_flip:2662) DEBUG: name = flip
last_ts = 105.287155
last_received_ts = 105.286560
last_seq = 2159
current_ts = 105.304131
current_received_ts = 105.303513
current_seq = 2160
count = 69
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 105.287155/2159, current 105.304131/2160: elapsed=16973.0us expected=16999.8us +- 85.0us, error 0.2%
(kms_flip:2662) DEBUG: name = flip
last_ts = 105.304131
last_received_ts = 105.303513
last_seq = 2160
current_ts = 105.321098
current_received_ts = 105.320526
current_seq = 2161
count = 70
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 105.304131/2160, current 105.321098/2161: elapsed=16966.0us expected=16999.8us +- 85.0us, error 0.2%
(kms_flip:2662) DEBUG: name = flip
last_ts = 105.321098
last_received_ts = 105.320526
last_seq = 2161
current_ts = 105.338058
current_received_ts = 105.337486
current_seq = 2162
count = 71
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 105.321098/2161, current 105.338058/2162: elapsed=16959.0us expected=16999.8us +- 85.0us, error 0.2%
(kms_flip:2662) DEBUG: name = flip
last_ts = 105.338058
last_received_ts = 105.337486
last_seq = 2162
current_ts = 105.355026
current_received_ts = 105.354485
current_seq = 2163
count = 72
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 105.338058/2162, current 105.355026/2163: elapsed=16974.0us expected=16999.8us +- 85.0us, error 0.2%
(kms_flip:2662) DEBUG: name = flip
last_ts = 105.355026
last_received_ts = 105.354485
last_seq = 2163
current_ts = 105.371986
current_received_ts = 105.371422
current_seq = 2164
count = 73
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 105.355026/2163, current 105.371986/2164: elapsed=16954.0us expected=16999.8us +- 85.0us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 105.371986
last_received_ts = 105.371422
last_seq = 2164
current_ts = 105.388962
current_received_ts = 105.388390
current_seq = 2165
count = 74
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 105.371986/2164, current 105.388962/2165: elapsed=16974.0us expected=16999.8us +- 85.0us, error 0.2%
(kms_flip:2662) DEBUG: name = flip
last_ts = 105.388962
last_received_ts = 105.388390
last_seq = 2165
current_ts = 105.405914
current_received_ts = 105.405327
current_seq = 2166
count = 75
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 105.388962/2165, current 105.405914/2166: elapsed=16954.0us expected=16999.8us +- 85.0us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 105.405914
last_received_ts = 105.405327
last_seq = 2166
current_ts = 105.422890
current_received_ts = 105.422279
current_seq = 2167
count = 76
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 105.405914/2166, current 105.422890/2167: elapsed=16977.0us expected=16999.8us +- 85.0us, error 0.1%
(kms_flip:2662) DEBUG: name = flip
last_ts = 105.422890
last_received_ts = 105.422279
last_seq = 2167
current_ts = 105.439842
current_received_ts = 105.439224
current_seq = 2168
count = 77
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 105.422890/2167, current 105.439842/2168: elapsed=16953.0us expected=16999.8us +- 85.0us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 105.439842
last_received_ts = 105.439224
last_seq = 2168
current_ts = 105.456802
current_received_ts = 105.456215
current_seq = 2169
count = 78
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 105.439842/2168, current 105.456802/2169: elapsed=16960.0us expected=16999.8us +- 85.0us, error 0.2%
(kms_flip:2662) DEBUG: name = flip
last_ts = 105.456802
last_received_ts = 105.456215
last_seq = 2169
current_ts = 105.473785
current_received_ts = 105.473175
current_seq = 2170
count = 79
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 105.456802/2169, current 105.473785/2170: elapsed=16981.0us expected=16999.8us +- 85.0us, error 0.1%
(kms_flip:2662) DEBUG: name = flip
last_ts = 105.473785
last_received_ts = 105.473175
last_seq = 2170
current_ts = 105.490746
current_received_ts = 105.490181
current_seq = 2171
count = 80
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 105.473785/2170, current 105.490746/2171: elapsed=16966.0us expected=16999.8us +- 85.0us, error 0.2%
(kms_flip:2662) DEBUG: name = flip
last_ts = 105.490746
last_received_ts = 105.490181
last_seq = 2171
current_ts = 105.507706
current_received_ts = 105.507141
current_seq = 2172
count = 81
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 105.490746/2171, current 105.507706/2172: elapsed=16953.0us expected=16999.8us +- 85.0us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 105.507706
last_received_ts = 105.507141
last_seq = 2172
current_ts = 105.524666
current_received_ts = 105.524101
current_seq = 2173
count = 82
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 105.507706/2172, current 105.524666/2173: elapsed=16962.0us expected=16999.8us +- 85.0us, error 0.2%
(kms_flip:2662) DEBUG: name = flip
last_ts = 105.524666
last_received_ts = 105.524101
last_seq = 2173
current_ts = 105.541634
current_received_ts = 105.541084
current_seq = 2174
count = 83
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 105.524666/2173, current 105.541634/2174: elapsed=16968.0us expected=16999.8us +- 85.0us, error 0.2%
(kms_flip:2662) DEBUG: name = flip
last_ts = 105.541634
last_received_ts = 105.541084
last_seq = 2174
current_ts = 105.558594
current_received_ts = 105.558060
current_seq = 2175
count = 84
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 105.541634/2174, current 105.558594/2175: elapsed=16965.0us expected=16999.8us +- 85.0us, error 0.2%
(kms_flip:2662) DEBUG: name = flip
last_ts = 105.558594
last_received_ts = 105.558060
last_seq = 2175
current_ts = 105.575562
current_received_ts = 105.575027
current_seq = 2176
count = 85
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 105.558594/2175, current 105.575562/2176: elapsed=16965.0us expected=16999.8us +- 85.0us, error 0.2%
(kms_flip:2662) DEBUG: name = flip
last_ts = 105.575562
last_received_ts = 105.575027
last_seq = 2176
current_ts = 105.592522
current_received_ts = 105.591927
current_seq = 2177
count = 86
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 105.575562/2176, current 105.592522/2177: elapsed=16961.0us expected=16999.8us +- 85.0us, error 0.2%
(kms_flip:2662) DEBUG: name = flip
last_ts = 105.592522
last_received_ts = 105.591927
last_seq = 2177
current_ts = 105.609489
current_received_ts = 105.608940
current_seq = 2178
count = 87
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 105.592522/2177, current 105.609489/2178: elapsed=16970.0us expected=16999.8us +- 85.0us, error 0.2%
(kms_flip:2662) DEBUG: name = flip
last_ts = 105.609489
last_received_ts = 105.608940
last_seq = 2178
current_ts = 105.626457
current_received_ts = 105.625916
current_seq = 2179
count = 88
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 105.609489/2178, current 105.626457/2179: elapsed=16964.0us expected=16999.8us +- 85.0us, error 0.2%
(kms_flip:2662) DEBUG: name = flip
last_ts = 105.626457
last_received_ts = 105.625916
last_seq = 2179
current_ts = 105.643440
current_received_ts = 105.642868
current_seq = 2180
count = 89
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 105.626457/2179, current 105.643440/2180: elapsed=16981.0us expected=16999.8us +- 85.0us, error 0.1%
(kms_flip:2662) DEBUG: name = flip
last_ts = 105.643440
last_received_ts = 105.642868
last_seq = 2180
current_ts = 105.660400
current_received_ts = 105.659859
current_seq = 2181
count = 90
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 105.643440/2180, current 105.660400/2181: elapsed=16965.0us expected=16999.8us +- 85.0us, error 0.2%
(kms_flip:2662) DEBUG: name = flip
last_ts = 105.660400
last_received_ts = 105.659859
last_seq = 2181
current_ts = 105.677338
current_received_ts = 105.676811
current_seq = 2182
count = 91
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 105.660400/2181, current 105.677338/2182: elapsed=16932.0us expected=16999.8us +- 85.0us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 105.677338
last_received_ts = 105.676811
last_seq = 2182
current_ts = 105.694267
current_received_ts = 105.693741
current_seq = 2183
count = 92
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 105.677338/2182, current 105.694267/2183: elapsed=16936.0us expected=16999.8us +- 85.0us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 105.694267
last_received_ts = 105.693741
last_seq = 2183
current_ts = 105.711220
current_received_ts = 105.710655
current_seq = 2184
count = 93
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 105.694267/2183, current 105.711220/2184: elapsed=16949.0us expected=16999.8us +- 85.0us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 105.711220
last_received_ts = 105.710655
last_seq = 2184
current_ts = 105.728142
current_received_ts = 105.727608
current_seq = 2185
count = 94
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 105.711220/2184, current 105.728142/2185: elapsed=16920.0us expected=16999.8us +- 85.0us, error 0.5%
(kms_flip:2662) DEBUG: name = flip
last_ts = 105.728142
last_received_ts = 105.727608
last_seq = 2185
current_ts = 105.745094
current_received_ts = 105.744522
current_seq = 2186
count = 95
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 105.728142/2185, current 105.745094/2186: elapsed=16952.0us expected=16999.8us +- 85.0us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 105.745094
last_received_ts = 105.744522
last_seq = 2186
current_ts = 105.762016
current_received_ts = 105.761490
current_seq = 2187
count = 96
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 105.745094/2186, current 105.762016/2187: elapsed=16924.0us expected=16999.8us +- 85.0us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 105.762016
last_received_ts = 105.761490
last_seq = 2187
current_ts = 105.778954
current_received_ts = 105.778412
current_seq = 2188
count = 97
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 105.762016/2187, current 105.778954/2188: elapsed=16935.0us expected=16999.8us +- 85.0us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 105.778954
last_received_ts = 105.778412
last_seq = 2188
current_ts = 105.795876
current_received_ts = 105.795280
current_seq = 2189
count = 98
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 105.778954/2188, current 105.795876/2189: elapsed=16926.0us expected=16999.8us +- 85.0us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 105.795876
last_received_ts = 105.795280
last_seq = 2189
current_ts = 105.812820
current_received_ts = 105.812225
current_seq = 2190
count = 99
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 105.795876/2189, current 105.812820/2190: elapsed=16944.0us expected=16999.8us +- 85.0us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 105.812820
last_received_ts = 105.812225
last_seq = 2190
current_ts = 105.829765
current_received_ts = 105.829216
current_seq = 2191
count = 100
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 105.812820/2190, current 105.829765/2191: elapsed=16945.0us expected=16999.8us +- 85.0us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 105.829765
last_received_ts = 105.829216
last_seq = 2191
current_ts = 105.846687
current_received_ts = 105.846146
current_seq = 2192
count = 101
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 105.829765/2191, current 105.846687/2192: elapsed=16921.0us expected=16999.8us +- 85.0us, error 0.5%
(kms_flip:2662) DEBUG: name = flip
last_ts = 105.846687
last_received_ts = 105.846146
last_seq = 2192
current_ts = 105.863640
current_received_ts = 105.863060
current_seq = 2193
count = 102
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 105.846687/2192, current 105.863640/2193: elapsed=16951.0us expected=16999.8us +- 85.0us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 105.863640
last_received_ts = 105.863060
last_seq = 2193
current_ts = 105.880562
current_received_ts = 105.880051
current_seq = 2194
count = 103
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 105.863640/2193, current 105.880562/2194: elapsed=16927.0us expected=16999.8us +- 85.0us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 105.880562
last_received_ts = 105.880051
last_seq = 2194
current_ts = 105.897491
current_received_ts = 105.896942
current_seq = 2195
count = 104
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 105.880562/2194, current 105.897491/2195: elapsed=16929.0us expected=16999.8us +- 85.0us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 105.897491
last_received_ts = 105.896942
last_seq = 2195
current_ts = 105.914429
current_received_ts = 105.913887
current_seq = 2196
count = 105
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 105.897491/2195, current 105.914429/2196: elapsed=16935.0us expected=16999.8us +- 85.0us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 105.914429
last_received_ts = 105.913887
last_seq = 2196
current_ts = 105.931366
current_received_ts = 105.930824
current_seq = 2197
count = 106
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 105.914429/2196, current 105.931366/2197: elapsed=16937.0us expected=16999.8us +- 85.0us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 105.931366
last_received_ts = 105.930824
last_seq = 2197
current_ts = 105.948303
current_received_ts = 105.947762
current_seq = 2198
count = 107
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 105.931366/2197, current 105.948303/2198: elapsed=16937.0us expected=16999.8us +- 85.0us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 105.948303
last_received_ts = 105.947762
last_seq = 2198
current_ts = 105.965240
current_received_ts = 105.966400
current_seq = 2199
count = 108
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 105.948303/2198, current 105.965240/2199: elapsed=16936.0us expected=16999.8us +- 85.0us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 105.965240
last_received_ts = 105.966400
last_seq = 2199
current_ts = 105.982170
current_received_ts = 105.981552
current_seq = 2200
count = 109
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 105.965240/2199, current 105.982170/2200: elapsed=16932.0us expected=16999.8us +- 85.0us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 105.982170
last_received_ts = 105.981552
last_seq = 2200
current_ts = 105.999115
current_received_ts = 105.998482
current_seq = 2201
count = 110
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 105.982170/2200, current 105.999115/2201: elapsed=16946.0us expected=16999.8us +- 85.0us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 105.999115
last_received_ts = 105.998482
last_seq = 2201
current_ts = 106.016045
current_received_ts = 106.015404
current_seq = 2202
count = 111
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 105.999115/2201, current 106.016045/2202: elapsed=16931.0us expected=16999.8us +- 85.0us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 106.016045
last_received_ts = 106.015404
last_seq = 2202
current_ts = 106.032990
current_received_ts = 106.032341
current_seq = 2203
count = 112
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 106.016045/2202, current 106.032990/2203: elapsed=16939.0us expected=16999.8us +- 85.0us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 106.032990
last_received_ts = 106.032341
last_seq = 2203
current_ts = 106.049927
current_received_ts = 106.049286
current_seq = 2204
count = 113
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 106.032990/2203, current 106.049927/2204: elapsed=16939.0us expected=16999.8us +- 85.0us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 106.049927
last_received_ts = 106.049286
last_seq = 2204
current_ts = 106.066841
current_received_ts = 106.066185
current_seq = 2205
count = 114
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 106.049927/2204, current 106.066841/2205: elapsed=16918.0us expected=16999.8us +- 85.0us, error 0.5%
(kms_flip:2662) DEBUG: name = flip
last_ts = 106.066841
last_received_ts = 106.066185
last_seq = 2205
current_ts = 106.083794
current_received_ts = 106.083183
current_seq = 2206
count = 115
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 106.066841/2205, current 106.083794/2206: elapsed=16950.0us expected=16999.8us +- 85.0us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 106.083794
last_received_ts = 106.083183
last_seq = 2206
current_ts = 106.100716
current_received_ts = 106.100105
current_seq = 2207
count = 116
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 106.083794/2206, current 106.100716/2207: elapsed=16922.0us expected=16999.8us +- 85.0us, error 0.5%
(kms_flip:2662) DEBUG: name = flip
last_ts = 106.100716
last_received_ts = 106.100105
last_seq = 2207
current_ts = 106.117653
current_received_ts = 106.117035
current_seq = 2208
count = 117
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 106.100716/2207, current 106.117653/2208: elapsed=16936.0us expected=16999.8us +- 85.0us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 106.117653
last_received_ts = 106.117035
last_seq = 2208
current_ts = 106.134598
current_received_ts = 106.133972
current_seq = 2209
count = 118
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 106.117653/2208, current 106.134598/2209: elapsed=16947.0us expected=16999.8us +- 85.0us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 106.134598
last_received_ts = 106.133972
last_seq = 2209
current_ts = 106.151520
current_received_ts = 106.150925
current_seq = 2210
count = 119
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 106.134598/2209, current 106.151520/2210: elapsed=16923.0us expected=16999.8us +- 85.0us, error 0.5%
(kms_flip:2662) DEBUG: name = flip
last_ts = 106.151520
last_received_ts = 106.150925
last_seq = 2210
current_ts = 106.168457
current_received_ts = 106.167862
current_seq = 2211
count = 120
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 106.151520/2210, current 106.168457/2211: elapsed=16936.0us expected=16999.8us +- 85.0us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 106.168457
last_received_ts = 106.167862
last_seq = 2211
current_ts = 106.185394
current_received_ts = 106.184753
current_seq = 2212
count = 121
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 106.168457/2211, current 106.185394/2212: elapsed=16937.0us expected=16999.8us +- 85.0us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 106.185394
last_received_ts = 106.184753
last_seq = 2212
current_ts = 106.202339
current_received_ts = 106.201683
current_seq = 2213
count = 122
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 106.185394/2212, current 106.202339/2213: elapsed=16945.0us expected=16999.8us +- 85.0us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 106.202339
last_received_ts = 106.201683
last_seq = 2213
current_ts = 106.219269
current_received_ts = 106.218620
current_seq = 2214
count = 123
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 106.202339/2213, current 106.219269/2214: elapsed=16925.0us expected=16999.8us +- 85.0us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 106.219269
last_received_ts = 106.218620
last_seq = 2214
current_ts = 106.236214
current_received_ts = 106.235573
current_seq = 2215
count = 124
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 106.219269/2214, current 106.236214/2215: elapsed=16949.0us expected=16999.8us +- 85.0us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 106.236214
last_received_ts = 106.235573
last_seq = 2215
current_ts = 106.253143
current_received_ts = 106.252502
current_seq = 2216
count = 125
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 106.236214/2215, current 106.253143/2216: elapsed=16926.0us expected=16999.8us +- 85.0us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 106.253143
last_received_ts = 106.252502
last_seq = 2216
current_ts = 106.270065
current_received_ts = 106.269432
current_seq = 2217
count = 126
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 106.253143/2216, current 106.270065/2217: elapsed=16925.0us expected=16999.8us +- 85.0us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 106.270065
last_received_ts = 106.269432
last_seq = 2217
current_ts = 106.287010
current_received_ts = 106.286423
current_seq = 2218
count = 127
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 106.270065/2217, current 106.287010/2218: elapsed=16944.0us expected=16999.8us +- 85.0us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 106.287010
last_received_ts = 106.286423
last_seq = 2218
current_ts = 106.303955
current_received_ts = 106.303345
current_seq = 2219
count = 128
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 106.287010/2218, current 106.303955/2219: elapsed=16944.0us expected=16999.8us +- 85.0us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 106.303955
last_received_ts = 106.303345
last_seq = 2219
current_ts = 106.320885
current_received_ts = 106.320229
current_seq = 2220
count = 129
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 106.303955/2219, current 106.320885/2220: elapsed=16935.0us expected=16999.8us +- 85.0us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 106.320885
last_received_ts = 106.320229
last_seq = 2220
current_ts = 106.337814
current_received_ts = 106.337219
current_seq = 2221
count = 130
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 106.320885/2220, current 106.337814/2221: elapsed=16926.0us expected=16999.8us +- 85.0us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 106.337814
last_received_ts = 106.337219
last_seq = 2221
current_ts = 106.354752
current_received_ts = 106.354156
current_seq = 2222
count = 131
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 106.337814/2221, current 106.354752/2222: elapsed=16935.0us expected=16999.8us +- 85.0us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 106.354752
last_received_ts = 106.354156
last_seq = 2222
current_ts = 106.371681
current_received_ts = 106.371101
current_seq = 2223
count = 132
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 106.354752/2222, current 106.371681/2223: elapsed=16936.0us expected=16999.8us +- 85.0us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 106.371681
last_received_ts = 106.371101
last_seq = 2223
current_ts = 106.388618
current_received_ts = 106.388054
current_seq = 2224
count = 133
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 106.371681/2223, current 106.388618/2224: elapsed=16931.0us expected=16999.8us +- 85.0us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 106.388618
last_received_ts = 106.388054
last_seq = 2224
current_ts = 106.405548
current_received_ts = 106.404961
current_seq = 2225
count = 134
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 106.388618/2224, current 106.405548/2225: elapsed=16935.0us expected=16999.8us +- 85.0us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 106.405548
last_received_ts = 106.404961
last_seq = 2225
current_ts = 106.422485
current_received_ts = 106.421890
current_seq = 2226
count = 135
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 106.405548/2225, current 106.422485/2226: elapsed=16933.0us expected=16999.8us +- 85.0us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 106.422485
last_received_ts = 106.421890
last_seq = 2226
current_ts = 106.439415
current_received_ts = 106.438805
current_seq = 2227
count = 136
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 106.422485/2226, current 106.439415/2227: elapsed=16934.0us expected=16999.8us +- 85.0us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 106.439415
last_received_ts = 106.438805
last_seq = 2227
current_ts = 106.456367
current_received_ts = 106.455711
current_seq = 2228
count = 137
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 106.439415/2227, current 106.456367/2228: elapsed=16950.0us expected=16999.8us +- 85.0us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 106.456367
last_received_ts = 106.455711
last_seq = 2228
current_ts = 106.473289
current_received_ts = 106.472702
current_seq = 2229
count = 138
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 106.456367/2228, current 106.473289/2229: elapsed=16924.0us expected=16999.8us +- 85.0us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 106.473289
last_received_ts = 106.472702
last_seq = 2229
current_ts = 106.490227
current_received_ts = 106.489632
current_seq = 2230
count = 139
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 106.473289/2229, current 106.490227/2230: elapsed=16935.0us expected=16999.8us +- 85.0us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 106.490227
last_received_ts = 106.489632
last_seq = 2230
current_ts = 106.507164
current_received_ts = 106.506577
current_seq = 2231
count = 140
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 106.490227/2230, current 106.507164/2231: elapsed=16940.0us expected=16999.8us +- 85.0us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 106.507164
last_received_ts = 106.506577
last_seq = 2231
current_ts = 106.524101
current_received_ts = 106.523506
current_seq = 2232
count = 141
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 106.507164/2231, current 106.524101/2232: elapsed=16931.0us expected=16999.8us +- 85.0us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 106.524101
last_received_ts = 106.523506
last_seq = 2232
current_ts = 106.541031
current_received_ts = 106.540398
current_seq = 2233
count = 142
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 106.524101/2232, current 106.541031/2233: elapsed=16935.0us expected=16999.8us +- 85.0us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 106.541031
last_received_ts = 106.540398
last_seq = 2233
current_ts = 106.557976
current_received_ts = 106.557304
current_seq = 2234
count = 143
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 106.541031/2233, current 106.557976/2234: elapsed=16945.0us expected=16999.8us +- 85.0us, error 0.3%
(kms_flip:2662) DEBUG: name = flip
last_ts = 106.557976
last_received_ts = 106.557304
last_seq = 2234
current_ts = 106.574921
current_received_ts = 106.574249
current_seq = 2235
count = 144
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 106.557976/2234, current 106.574921/2235: elapsed=16940.0us expected=16999.8us +- 85.0us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 106.574921
last_received_ts = 106.574249
last_seq = 2235
current_ts = 106.591843
current_received_ts = 106.591156
current_seq = 2236
count = 145
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 106.574921/2235, current 106.591843/2236: elapsed=16928.0us expected=16999.8us +- 85.0us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 106.591843
last_received_ts = 106.591156
last_seq = 2236
current_ts = 106.608788
current_received_ts = 106.608192
current_seq = 2237
count = 146
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 106.591843/2236, current 106.608788/2237: elapsed=16938.0us expected=16999.8us +- 85.0us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 106.608788
last_received_ts = 106.608192
last_seq = 2237
current_ts = 106.625710
current_received_ts = 106.625198
current_seq = 2238
count = 147
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 106.608788/2237, current 106.625710/2238: elapsed=16925.0us expected=16999.8us +- 85.0us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 106.625710
last_received_ts = 106.625198
last_seq = 2238
current_ts = 106.642647
current_received_ts = 106.642105
current_seq = 2239
count = 148
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 106.625710/2238, current 106.642647/2239: elapsed=16935.0us expected=16999.8us +- 85.0us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 106.642647
last_received_ts = 106.642105
last_seq = 2239
current_ts = 106.659584
current_received_ts = 106.659065
current_seq = 2240
count = 149
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 106.642647/2239, current 106.659584/2240: elapsed=16940.0us expected=16999.8us +- 85.0us, error 0.4%
(kms_flip:2662) DEBUG: name = flip
last_ts = 106.659584
last_received_ts = 106.659065
last_seq = 2240
current_ts = 106.676506
current_received_ts = 106.675964
current_seq = 2241
count = 150
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 106.659584/2240, current 106.676506/2241: elapsed=16920.0us expected=16999.8us +- 85.0us, error 0.5%
(kms_flip:2662) DEBUG: name = flip
last_ts = 106.676506
last_received_ts = 106.675964
last_seq = 2241
current_ts = 106.693405
current_received_ts = 106.692848
current_seq = 2242
count = 151
seq_step = 1
(kms_flip:2662) DEBUG: flip ts/seq: last 106.676506/2241, current 106.693405/2242: elapsed=16904.0us expected=16999.8us +- 85.0us, error 0.6%
(kms_flip:2662) DEBUG: inconsistent flip ts/seq: last 106.676506/2241, current 106.693405/2242: elapsed=16904.0us expected=16999.8us
(kms_flip:2662) DEBUG: expected 160023, counted 151, encoder type 1
(kms_flip:2662) DEBUG: dropped frames, expected 160023, counted 151, encoder type 1
(kms_flip:2662) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1674:
(kms_flip:2662) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon)
(kms_flip:2662) igt_core-INFO: Stack trace:
(kms_flip:2662) igt_core-INFO:   #0 ../lib/igt_core.c:2055 __igt_fail_assert()
(kms_flip:2662) igt_core-INFO:   #1 ../tests/kms_flip.c:791 run_test_on_crtc_set.constprop.0()
(kms_flip:2662) igt_core-INFO:   #2 ../tests/kms_flip.c:1845 run_test()
(kms_flip:2662) igt_core-INFO:   #3 ../tests/kms_flip.c:2078 __igt_unique____real_main2001()
(kms_flip:2662) igt_core-INFO:   #4 ../tests/kms_flip.c:2001 main()
(kms_flip:2662) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:2662) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:2662) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest A-VGA1: FAIL (6.173s)
Dmesg
<6> [103.125846] Console: switching to colour dummy device 80x25
<6> [103.126049] [IGT] kms_flip: executing
<7> [103.148505] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [103.149256] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [103.149694] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [103.153108] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:64:VGA-1]
<7> [103.153135] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] [CONNECTOR:64:VGA-1] force=1
<7> [103.153416] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] ironlake hotplug adpa=0x20f40000, result 0
<7> [103.153649] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] CRT not detected via hotplug
<7> [103.154035] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [103.154268] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK on first message, retry
<7> [103.154665] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [103.154866] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [103.154873] i915 0000:00:02.0: [drm:intel_crt_get_edid [i915]] CRT GMBUS EDID read failed, retry using GPIO bit-banging
<7> [103.155073] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] enabling bit-banging on i915 gmbus vga. force bit now 1
<7> [103.156342] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [103.156349] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] disabling bit-banging on i915 gmbus vga. force bit now 0
<7> [103.156550] i915 0000:00:02.0: [drm:intel_crt_detect_ddc [i915]] CRT not detected via DDC:0x50 [no valid EDID found]
<7> [103.156752] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:64:VGA-1] disconnected
<7> [103.157363] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:75:DP-1]
<7> [103.157390] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:75:DP-1]
<7> [103.161245] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.165210] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.169089] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.173017] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.176961] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.180866] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.184792] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.188696] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.192660] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.196564] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.200431] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.204187] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.208049] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.212052] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.216251] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.220400] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.224588] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.228759] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.232978] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.237028] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.241248] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.245397] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.249316] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.253567] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.257642] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.261860] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.266015] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.270195] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.274365] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.278542] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.282684] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.286850] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.287330] 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> [103.287368] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:75:DP-1] disconnected
<7> [103.287867] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:64:VGA-1]
<7> [103.287913] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] [CONNECTOR:64:VGA-1] force=1
<7> [103.288277] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] ironlake hotplug adpa=0x20f40000, result 0
<7> [103.288721] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] CRT not detected via hotplug
<7> [103.289276] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [103.289732] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK on first message, retry
<7> [103.290224] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [103.290586] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [103.290593] i915 0000:00:02.0: [drm:intel_crt_get_edid [i915]] CRT GMBUS EDID read failed, retry using GPIO bit-banging
<7> [103.290814] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] enabling bit-banging on i915 gmbus vga. force bit now 1
<7> [103.292235] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [103.292243] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] disabling bit-banging on i915 gmbus vga. force bit now 0
<7> [103.292588] i915 0000:00:02.0: [drm:intel_crt_detect_ddc [i915]] CRT not detected via DDC:0x50 [no valid EDID found]
<7> [103.292820] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:64:VGA-1] disconnected
<7> [103.293039] i915 0000:00:02.0: [drm:status_store] [CONNECTOR:64:VGA-1] force updated from 0 to 2 or reprobing
<7> [103.293048] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:64:VGA-1]
<7> [103.293064] 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> [103.293473] i915 0000:00:02.0: [drm:drm_sysfs_hotplug_event] generating hotplug event
<7> [103.293611] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [103.293833] i915 0000:00:02.0: [drm:drm_client_hotplug] fbdev: ret=0
<7> [103.293872] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK on first message, retry
<7> [103.294300] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [103.294545] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [103.294564] i915 0000:00:02.0: [drm:intel_crt_get_edid [i915]] CRT GMBUS EDID read failed, retry using GPIO bit-banging
<7> [103.294796] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] enabling bit-banging on i915 gmbus vga. force bit now 1
<7> [103.296109] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [103.296122] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] disabling bit-banging on i915 gmbus vga. force bit now 0
<7> [103.296467] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:64:VGA-1] probed modes:
<7> [103.296494] 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> [103.296517] 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> [103.296532] 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> [103.296546] 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> [103.296560] 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> [103.296629] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:64:VGA-1]
<7> [103.296838] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [103.297042] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK on first message, retry
<7> [103.297428] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [103.297689] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [103.297701] i915 0000:00:02.0: [drm:intel_crt_get_edid [i915]] CRT GMBUS EDID read failed, retry using GPIO bit-banging
<7> [103.297936] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] enabling bit-banging on i915 gmbus vga. force bit now 1
<7> [103.299175] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [103.299188] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] disabling bit-banging on i915 gmbus vga. force bit now 0
<7> [103.299534] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:64:VGA-1] probed modes:
<7> [103.299551] 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> [103.299565] 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> [103.299580] 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> [103.299593] 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> [103.299607] 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> [103.299639] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:67:HDMI-A-1]
<7> [103.299656] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:67:HDMI-A-1]
<7> [103.325518] 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> [103.325531] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:67:HDMI-A-1] HDMI sink does deep color 30.
<7> [103.325538] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:67:HDMI-A-1] HDMI sink does deep color 36.
<7> [103.325543] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:67:HDMI-A-1] HDMI sink does deep color 48.
<7> [103.325549] 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> [103.325555] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:67:HDMI-A-1] HDMI sink does YCRCB444 in deep color.
<7> [103.325562] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:67:HDMI-A-1] ELD monitor Mi TV
<7> [103.325569] 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> [103.325576] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:67:HDMI-A-1] ELD size 36, SAD count 3
<7> [103.325790] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpd] NAK for addr: 0040 w(1)
<7> [103.326149] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpd] NAK on first message, retry
<7> [103.326649] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpd] NAK for addr: 0040 w(1)
<7> [103.327053] i915 0000:00:02.0: [drm:drm_dp_dual_mode_detect [drm_display_helper]] DP dual mode HDMI ID: (err -6)
<7> [103.328820] 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> [103.328845] 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> [103.328854] 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> [103.328862] 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> [103.328871] 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> [103.328879] 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> [103.328887] 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> [103.328895] 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> [103.328902] 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> [103.328910] 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> [103.328918] 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> [103.328925] 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> [103.328933] 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> [103.328940] 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> [103.328948] 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> [103.328956] 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> [103.328964] 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> [103.328971] 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> [103.328979] 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> [103.328992] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:67:HDMI-A-1] probed modes:
<7> [103.329009] 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> [103.329023] 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> [103.329038] 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> [103.329052] 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> [103.329065] 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> [103.329079] 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> [103.329093] 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> [103.329107] 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> [103.329121] 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> [103.329135] 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> [103.329149] 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> [103.329162] 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> [103.329176] 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> [103.329190] 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> [103.329203] 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> [103.329217] 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> [103.329230] 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> [103.329244] 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> [103.329258] 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> [103.329271] 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> [103.329285] 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> [103.329298] 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> [103.329312] 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> [103.329326] 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> [103.329339] 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> [103.329353] 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> [103.329367] 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> [103.329380] 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> [103.329394] 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> [103.329408] 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> [103.329421] 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> [103.329435] 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> [103.329449] 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> [103.329462] 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> [103.329476] 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> [103.329489] 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> [103.329503] 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> [103.329517] 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> [103.329530] 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> [103.329544] 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> [103.329558] 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> [103.329571] 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> [103.329585] 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> [103.329598] 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> [103.329612] 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> [103.329668] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:75:DP-1]
<7> [103.329684] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:75:DP-1]
<7> [103.333429] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.337434] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.341288] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.345186] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.349105] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.353008] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.356880] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.360632] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.364548] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.368368] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.372126] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.375902] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.379708] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.383621] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.387381] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.391291] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.395218] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.399172] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.402719] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.406786] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.410979] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.415132] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.419347] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.423503] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.427824] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.432276] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.436173] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.440367] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.444579] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.448790] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.452951] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.457095] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [103.457576] 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> [103.457622] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:75:DP-1] disconnected
<6> [103.458803] [IGT] kms_flip: starting subtest plain-flip-fb-recreate
<6> [103.460038] [IGT] kms_flip: starting dynamic subtest A-VGA1
<7> [103.460675] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [103.460786] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [103.508852] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:47:pipe A]
<7> [103.509059] 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> [103.509574] 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> [103.510123] 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> [103.510593] 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> [103.511128] 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> [103.511622] 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> [103.512148] 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> [103.512624] 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> [103.512986] 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> [103.513198] 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> [103.513428] 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> [103.513685] 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> [103.513896] 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> [103.514106] 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> [103.514331] 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> [103.514577] 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> [103.514789] 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> [103.514998] 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> [103.515222] 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> [103.515449] 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> [103.515746] 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> [103.516073] 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> [103.516342] 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> [103.516591] 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> [103.516903] 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> [103.517232] 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> [103.517467] 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> [103.517713] 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> [103.518021] 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> [103.518343] 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> [103.518585] 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> [103.518841] 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> [103.519184] 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> [103.519518] 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> [103.519750] 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> [103.519956] 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> [103.520219] 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> [103.520556] 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> [103.520829] 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> [103.521035] 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> [103.521274] 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> [103.521594] 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> [103.521894] 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> [103.522153] 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> [103.522384] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in avi infoframe
<7> [103.522671] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [103.522974] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [103.522978] i915 0000:00:02.0: colorspace: RGB
<7> [103.522980] i915 0000:00:02.0: scan mode: Underscan
<7> [103.522982] i915 0000:00:02.0: colorimetry: No Data
<7> [103.522984] i915 0000:00:02.0: picture aspect: 16:9
<7> [103.522986] i915 0000:00:02.0: active aspect: Same as Picture
<7> [103.522988] i915 0000:00:02.0: itc: No Data
<7> [103.522990] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [103.522993] i915 0000:00:02.0: quantization range: Default
<7> [103.522995] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [103.522997] i915 0000:00:02.0: video code: 16
<7> [103.522999] i915 0000:00:02.0: ycc quantization range: Full
<7> [103.523001] i915 0000:00:02.0: hdmi content type: Graphics
<7> [103.523003] i915 0000:00:02.0: pixel repeat: 0
<7> [103.523005] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [103.523007] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [103.523278] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in spd infoframe
<7> [103.523489] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [103.523765] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [103.523770] i915 0000:00:02.0: vendor: Intel
<7> [103.523774] i915 0000:00:02.0: product: Integrated gfx
<7> [103.523778] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [103.523782] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [103.524127] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hdmi infoframe
<7> [103.524459] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [103.524708] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [103.524711] i915 0000:00:02.0: empty frame
<7> [103.524713] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [103.524925] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:47:pipe A] fastset requirement not met, forcing full modeset
<7> [103.525169] i915 0000:00:02.0: [drm:intel_unreference_shared_dpll_crtc [i915]] [CRTC:47:pipe A] releasing PCH DPLL A
<7> [103.525439] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 400000 kHz, actual 400000 kHz
<7> [103.525660] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7> [103.525878] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:47:pipe A] enable: no [modeset]
<7> [103.526132] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:32:primary A] fb: [NOFB], visible: no
<7> [103.526411] 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> [103.550801] i915 0000:00:02.0: [drm:intel_audio_component_get_eld [i915]] Not valid for port D
<7> [103.551468] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [103.569344] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port B found
<7> [103.569792] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port C found
<7> [103.570225] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port D found
<7> [103.581779] 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> [103.582271] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disabling PCH DPLL A
<7> [103.582926] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:65:CRT]
<7> [103.583371] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:66:HDMI D]
<7> [103.583806] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:DP D]
<7> [103.584310] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:67:HDMI-A-1]
<7> [103.584930] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:47:pipe A]
<7> [103.585436] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:63:pipe B]
<7> [103.585599] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:47:pipe A]
<7> [103.585626] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:64:VGA-1]
<7> [103.585753] 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> [103.586035] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:47:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [103.586314] i915 0000:00:02.0: [drm:intel_fdi_atomic_check_link [i915]] checking fdi config on pipe A, lanes 1
<7> [103.586533] 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> [103.586746] 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> [103.587033] 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> [103.587307] 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> [103.587523] 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> [103.587739] 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> [103.588032] 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> [103.588291] 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> [103.588504] 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> [103.588715] 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> [103.588958] 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> [103.589260] 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> [103.589497] 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> [103.589717] 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> [103.589965] 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> [103.590216] 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> [103.590458] 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> [103.590696] 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> [103.590943] 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> [103.591191] 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> [103.591398] 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> [103.591642] 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> [103.591905] 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> [103.592169] 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> [103.592374] 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> [103.592580] 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> [103.592784] 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> [103.593025] 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> [103.593305] 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> [103.593520] 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> [103.593731] 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> [103.593957] 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> [103.594205] 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> [103.594415] 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> [103.594626] 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> [103.594849] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in dpll_hw_state
<7> [103.595060] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [103.595270] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: dpll: 0xc4010001, dpll_md: 0x0, fp0: 0x30f06, fp1: 0x30f06
<7> [103.595480] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [103.595690] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: dpll: 0x84100010, dpll_md: 0x0, fp0: 0x10c09, fp1: 0x10c09
<7> [103.595919] 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> [103.596131] 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> [103.596341] 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> [103.596552] 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> [103.596763] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:47:pipe A] fastset requirement not met, forcing full modeset
<7> [103.597038] 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> [103.597290] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 400000 kHz, actual 400000 kHz
<7> [103.597501] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7> [103.597713] i915 0000:00:02.0: [drm:intel_find_shared_dpll [i915]] [CRTC:47:pipe A] allocated PCH DPLL A
<7> [103.597961] i915 0000:00:02.0: [drm:intel_reference_shared_dpll_crtc [i915]] [CRTC:47:pipe A] reserving PCH DPLL A
<7> [103.598173] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:47:pipe A] enable: yes [modeset]
<7> [103.598385] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: ANALOG (0x2), output format: RGB, sink format: RGB
<7> [103.598595] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [103.598819] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [103.599029] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [103.599238] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [103.599447] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [103.599656] 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> [103.599888] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [103.600098] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 1
<7> [103.600307] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vblank delay: 0, framestart delay: 1, MSA timing delay: 0
<7> [103.600516] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, vmin: 0, vmax: 0, flipline: 0, pipeline full: 0, guardband: 0 vsync start: 0, vsync end: 0
<7> [103.600756] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: -1, vmax vblank: -2, vmin vtotal: 1, vmax vtotal: 0
<7> [103.600991] 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> [103.601237] 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> [103.601450] 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> [103.601661] 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> [103.601907] 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> [103.602118] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 64800, pipe src: 1024x768+0+0, pixel rate 64800
<7> [103.602327] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 0, ips linetime: 0
<7> [103.602536] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [103.602716] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling always-on
<7> [103.602769] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [103.602979] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: dpll: 0x84100010, dpll_md: 0x0, fp0: 0x10c09, fp1: 0x10c09
<7> [103.603189] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x3 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [103.603398] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [103.603607] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [103.603839] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [103.604048] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [103.604257] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [103.604467] 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> [103.604697] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [103.604907] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1024.000000x768.000000+0.000000+0.000000 dst: 1024x768+0+0
<7> [103.606097] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling always-on
<7> [103.606325] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:65:CRT]
<7> [103.606532] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:66:HDMI D]
<7> [103.606754] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:DP D]
<7> [103.607511] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [103.608576] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x100
<7> [103.608798] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 1 done.
<7> [103.609671] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x200
<7> [103.609885] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 2 done.
<7> [103.610085] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train done.
<7> [103.610315] 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> [103.610525] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enabling PCH DPLL A
<7> [103.662544] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:64:VGA-1]
<7> [103.663101] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:47:pipe A]
<7> [103.963564] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:81]
<7> [103.980281] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [103.996949] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [104.013728] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:81]
<7> [104.030445] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [104.047140] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [104.063839] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:81]
<7> [104.080593] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [104.097282] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [104.114034] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:81]
<7> [104.130713] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [104.147438] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [104.164118] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:81]
<7> [104.180831] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [104.197679] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [104.214406] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:81]
<7> [104.231068] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [104.247751] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [104.264540] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:81]
<7> [104.281260] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [104.297980] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [104.314707] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:81]
<7> [104.331436] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [104.348130] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [104.364785] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:81]
<7> [104.381478] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [104.398220] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [104.414888] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:81]
<7> [104.431640] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [104.448354] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [104.465155] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:81]
<7> [104.481870] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [104.498536] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [104.499313] i915 0000:00:02.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:81] from all active usage due to RMFB ioctl
<7> [104.515160] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:81]
<7> [104.531909] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [104.548583] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [104.565301] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:81]
<7> [104.582008] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [104.598761] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [104.615455] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:81]
<7> [104.632194] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [104.648912] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [104.665640] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:81]
<7> [104.682373] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [104.699031] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [104.715789] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:81]
<7> [104.732507] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [104.749222] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [104.765934] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:81]
<7> [104.782558] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [104.799336] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [104.816040] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:81]
<7> [104.832811] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [104.849525] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [104.866343] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:81]
<7> [104.882965] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [104.899766] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [104.899814] i915 0000:00:02.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:81] from all active usage due to RMFB ioctl
<7> [104.916448] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:81]
<7> [104.933156] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [104.949886] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [104.966569] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:81]
<7> [104.983303] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [105.000009] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [105.016724] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:81]
<7> [105.033380] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [105.050114] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [105.066836] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:81]
<7> [105.083581] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [105.100301] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [105.116985] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:81]
<7> [105.133697] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [105.150461] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [105.167172] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:81]
<7> [105.183916] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [105.200600] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [105.217328] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:81]
<7> [105.234028] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [105.250719] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [105.267377] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:81]
<7> [105.284157] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [105.300873] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [105.317628] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:81]
<7> [105.334347] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [105.351056] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [105.367799] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:81]
<7> [105.384511] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [105.401242] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [105.417905] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:81]
<7> [105.434671] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [105.451396] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [105.468082] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:81]
<7> [105.484862] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [105.501580] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [105.518299] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:81]
<7> [105.534993] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [105.551736] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [105.568414] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:81]
<7> [105.585170] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [105.601867] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [105.618520] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:81]
<7> [105.635265] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [105.652028] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [105.668727] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:81]
<7> [105.685446] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [105.702212] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [105.718925] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:81]
<7> [105.735645] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [105.752326] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [105.769041] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:81]
<7> [105.787403] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [105.802358] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [105.819068] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:81]
<7> [105.835773] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [105.852497] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [105.869223] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:81]
<7> [105.885902] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [105.902679] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [105.919386] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:81]
<7> [105.936093] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [105.952832] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [105.969575] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:81]
<7> [105.986268] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [106.002945] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [106.019652] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:81]
<7> [106.036371] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [106.053103] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [106.069817] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:81]
<7> [106.086530] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [106.103295] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [106.120002] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:81]
<7> [106.136669] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [106.153434] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [106.170160] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:81]
<7> [106.186884] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [106.203611] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [106.220343] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:81]
<7> [106.237021] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [106.253714] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [106.270408] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:81]
<7> [106.287177] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [106.303914] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [106.320614] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:81]
<7> [106.337327] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [106.353999] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [106.370690] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:81]
<7> [106.387419] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [106.404103] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [106.420923] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:81]
<7> [106.437708] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [106.454394] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [106.471142] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:81]
<7> [106.487871] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<6> [109.633697] [IGT] kms_flip: finished subtest A-VGA1, FAIL
Created at 2025-03-09 10:19:50